builder: larch_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0306 starttime: 1477796744.07 results: success (0) buildid: 20161029175923 builduid: c7ffed62b55e4609b66271641a4e2631 revision: d4bf6c41f00dbc87e25914319094fe9e11756006 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-29 20:05:44.069349) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-29 20:05:44.070191) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-29 20:05:44.070505) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-29 20:05:44.113764) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-29 20:05:44.114335) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.hHFnurEEs6/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.6eju0Pengj/Listeners TMPDIR=/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-10-29 20:05:44-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 19.2M=0.001s 2016-10-29 20:05:44 (19.2 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.643091 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-29 20:05:44.780484) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-29 20:05:44.780795) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.hHFnurEEs6/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.6eju0Pengj/Listeners TMPDIR=/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.025347 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-29 20:05:44.829291) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-10-29 20:05:44.829652) ========= bash -c 'python archiver_client.py mozharness --repo projects/larch --rev d4bf6c41f00dbc87e25914319094fe9e11756006 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/larch --rev d4bf6c41f00dbc87e25914319094fe9e11756006 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.hHFnurEEs6/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.6eju0Pengj/Listeners TMPDIR=/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-10-29 20:05:44,914 truncating revision to first 12 chars 2016-10-29 20:05:44,914 Setting DEBUG logging. 2016-10-29 20:05:44,914 attempt 1/10 2016-10-29 20:05:44,914 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/larch/d4bf6c41f00d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-29 20:05:46,269 unpacking tar archive at: larch-d4bf6c41f00d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.623787 ========= master_lag: 1.22 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-10-29 20:05:47.671399) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-29 20:05:47.671669) ========= script_repo_revision: d4bf6c41f00dbc87e25914319094fe9e11756006 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-29 20:05:47.672013) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-29 20:05:47.672274) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-29 20:05:47.709394) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-10-29 20:05:47.709670) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Larch', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Larch'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.hHFnurEEs6/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.6eju0Pengj/Listeners TMPDIR=/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 20:05:47 INFO - MultiFileLogger online at 20161029 20:05:47 in /builds/slave/test 20:05:47 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 20:05:47 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:05:47 INFO - {'append_to_log': False, 20:05:47 INFO - 'base_work_dir': '/builds/slave/test', 20:05:47 INFO - 'blob_upload_branch': 'Larch', 20:05:47 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:05:47 INFO - 'branch': 'Larch', 20:05:47 INFO - 'buildbot_json_path': 'buildprops.json', 20:05:47 INFO - 'config_files': ('talos/mac_config.py',), 20:05:47 INFO - 'default_actions': ('clobber', 20:05:47 INFO - 'read-buildbot-config', 20:05:47 INFO - 'download-and-extract', 20:05:47 INFO - 'populate-webroot', 20:05:47 INFO - 'create-virtualenv', 20:05:47 INFO - 'install', 20:05:47 INFO - 'run-tests'), 20:05:47 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:05:47 INFO - 'download_minidump_stackwalk': True, 20:05:47 INFO - 'download_symbols': 'ondemand', 20:05:47 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:05:47 INFO - 'tooltool.py': '/tools/tooltool.py', 20:05:47 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:05:47 INFO - '/tools/misc-python/virtualenv.py')}, 20:05:47 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:05:47 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:05:47 INFO - 'installer_path': 'installer.exe', 20:05:47 INFO - 'log_level': 'info', 20:05:47 INFO - 'log_name': 'talos', 20:05:47 INFO - 'log_to_console': True, 20:05:47 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 20:05:47 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 20:05:47 INFO - 'opt_config_files': (), 20:05:47 INFO - 'pip_index': False, 20:05:47 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:05:47 INFO - 'cmd': ('bash', 20:05:47 INFO - '-c', 20:05:47 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:05:47 INFO - 'enabled': True, 20:05:47 INFO - 'halt_on_failure': False, 20:05:47 INFO - 'name': 'check_screen_resolution'},), 20:05:47 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:05:47 INFO - 'cmd': ('bash', 20:05:47 INFO - '-c', 20:05:47 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:05:47 INFO - 'enabled': True, 20:05:47 INFO - 'halt_on_failure': False, 20:05:47 INFO - 'name': 'check_screen_resolution'},), 20:05:47 INFO - 'run_cmd_checks_enabled': True, 20:05:47 INFO - 'sps_profile': False, 20:05:47 INFO - 'sps_profile_interval': 0, 20:05:47 INFO - 'suite': 'dromaeojs-e10s', 20:05:47 INFO - 'system_bits': '32', 20:05:47 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:05:47 INFO - 'title': 't-yosemite-r7-0306', 20:05:47 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:05:47 INFO - 'use_talos_json': True, 20:05:47 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:05:47 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:05:47 INFO - 'work_dir': 'build'} 20:05:47 INFO - [mozharness: 2016-10-30 03:05:47.917418Z] Running clobber step. 20:05:47 INFO - Running pre-action listener: _resource_record_pre_action 20:05:47 INFO - Running main action method: clobber 20:05:47 INFO - rmtree: /builds/slave/test/build 20:05:47 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:05:50 INFO - Running post-action listener: _resource_record_post_action 20:05:50 INFO - [mozharness: 2016-10-30 03:05:50.126722Z] Finished clobber step (success) 20:05:50 INFO - [mozharness: 2016-10-30 03:05:50.126848Z] Running read-buildbot-config step. 20:05:50 INFO - Running pre-action listener: _resource_record_pre_action 20:05:50 INFO - Running main action method: read_buildbot_config 20:05:50 INFO - Using buildbot properties: 20:05:50 INFO - { 20:05:50 INFO - "project": "", 20:05:50 INFO - "product": "firefox", 20:05:50 INFO - "script_repo_revision": "production", 20:05:50 INFO - "builddir": "larch_yosemite_r7_test-dromaeojs-e10s", 20:05:50 INFO - "repository": "", 20:05:50 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 larch talos dromaeojs-e10s", 20:05:50 INFO - "buildid": "20161029175923", 20:05:50 INFO - "pgo_build": "False", 20:05:50 INFO - "basedir": "/builds/slave/test", 20:05:50 INFO - "buildnumber": 3, 20:05:50 INFO - "slavename": "t-yosemite-r7-0306", 20:05:50 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 20:05:50 INFO - "platform": "yosemite_r7", 20:05:50 INFO - "scheduler": "tests-larch-macosx64-talos", 20:05:50 INFO - "branch": "larch", 20:05:50 INFO - "repo_path": "projects/larch", 20:05:50 INFO - "revision": "d4bf6c41f00dbc87e25914319094fe9e11756006", 20:05:50 INFO - "stage_platform": "macosx64", 20:05:50 INFO - "builduid": "c7ffed62b55e4609b66271641a4e2631", 20:05:50 INFO - "slavebuilddir": "test" 20:05:50 INFO - } 20:05:50 INFO - Found installer url https://queue.taskcluster.net/v1/task/SBKPH6Y5Qrar0ULLW1T99g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 20:05:50 INFO - Running post-action listener: _resource_record_post_action 20:05:50 INFO - [mozharness: 2016-10-30 03:05:50.130144Z] Finished read-buildbot-config step (success) 20:05:50 INFO - [mozharness: 2016-10-30 03:05:50.130283Z] Running download-and-extract step. 20:05:50 INFO - Running pre-action listener: _resource_record_pre_action 20:05:50 INFO - Running main action method: download_and_extract 20:05:50 INFO - mkdir: /builds/slave/test/build/tests 20:05:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:05:50 INFO - trying https://queue.taskcluster.net/v1/task/SBKPH6Y5Qrar0ULLW1T99g/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 20:05:50 INFO - Downloading https://queue.taskcluster.net/v1/task/SBKPH6Y5Qrar0ULLW1T99g/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 20:05:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SBKPH6Y5Qrar0ULLW1T99g/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 20:05:51 INFO - Downloaded 1199 bytes. 20:05:51 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 20:05:51 INFO - Using the following test package requirements: 20:05:51 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 20:05:51 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 20:05:51 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 20:05:51 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 20:05:51 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 20:05:51 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 20:05:51 INFO - u'jsshell-mac.zip'], 20:05:51 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 20:05:51 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 20:05:51 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 20:05:51 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 20:05:51 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 20:05:51 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 20:05:51 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 20:05:51 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 20:05:51 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 20:05:51 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 20:05:51 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 20:05:51 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 20:05:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SBKPH6Y5Qrar0ULLW1T99g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 20:05:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SBKPH6Y5Qrar0ULLW1T99g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 20:05:51 INFO - Fetch https://queue.taskcluster.net/v1/task/SBKPH6Y5Qrar0ULLW1T99g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 20:05:52 INFO - Http code: 200 20:05:52 INFO - via: 1.1 0cf1d7257c633ae75623c5e75bf3805e.cloudfront.net (CloudFront) 20:05:52 INFO - x-amz-cf-id: QB3v8-RtYfgb6JuLW-Je1I1x7y0fO5K4i0fqJjQjIV-ZwUrd1K3xFg== 20:05:52 INFO - x-amz-version-id: wfu2u0chqcbbkguHYZI0MQdFR9g4eBak 20:05:52 INFO - Expected file size: 16391592 20:05:52 INFO - Obtained file size: 16391592 20:05:58 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 20:05:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SBKPH6Y5Qrar0ULLW1T99g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 20:05:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SBKPH6Y5Qrar0ULLW1T99g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 20:05:58 INFO - Fetch https://queue.taskcluster.net/v1/task/SBKPH6Y5Qrar0ULLW1T99g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 20:05:59 INFO - Http code: 200 20:05:59 INFO - via: 1.1 73fa5a1ee49cf827e596b502b5927eab.cloudfront.net (CloudFront) 20:05:59 INFO - x-amz-cf-id: 1NG3TWJSjlt76NqjDJNeh5H6Di-1ijkQAz4AvZngqU51HPUxBur9XQ== 20:05:59 INFO - x-amz-version-id: wfu2u0chqcbbkguHYZI0MQdFR9g4eBak 20:05:59 INFO - Expected file size: 16391592 20:05:59 INFO - Obtained file size: 16391592 20:06:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SBKPH6Y5Qrar0ULLW1T99g/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 20:06:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SBKPH6Y5Qrar0ULLW1T99g/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 20:06:05 INFO - Fetch https://queue.taskcluster.net/v1/task/SBKPH6Y5Qrar0ULLW1T99g/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 20:06:06 INFO - Http code: 200 20:06:06 INFO - via: 1.1 b3bccc0e4c2cc0a33e7e8308d88cacdd.cloudfront.net (CloudFront) 20:06:06 INFO - x-amz-cf-id: rmT-wLF-oRobWh7nWqj-HVdKG84WBxXXTAkkDn9ALLccoa0RYUch1g== 20:06:06 INFO - x-amz-version-id: 2n2Y254ap.DAoJdTxntYbpw6cWrdAzqx 20:06:06 INFO - Expected file size: 14409038 20:06:06 INFO - Obtained file size: 14409038 20:06:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:06:07 INFO - trying https://queue.taskcluster.net/v1/task/SBKPH6Y5Qrar0ULLW1T99g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 20:06:07 INFO - Downloading https://queue.taskcluster.net/v1/task/SBKPH6Y5Qrar0ULLW1T99g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 20:06:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SBKPH6Y5Qrar0ULLW1T99g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 20:06:10 INFO - Downloaded 112083992 bytes. 20:06:10 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SBKPH6Y5Qrar0ULLW1T99g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 20:06:10 INFO - mkdir: /builds/slave/test/properties 20:06:10 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:06:10 INFO - Writing to file /builds/slave/test/properties/build_url 20:06:10 INFO - Contents: 20:06:10 INFO - build_url:https://queue.taskcluster.net/v1/task/SBKPH6Y5Qrar0ULLW1T99g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 20:06:10 INFO - Running post-action listener: _resource_record_post_action 20:06:10 INFO - Running post-action listener: set_extra_try_arguments 20:06:10 INFO - [mozharness: 2016-10-30 03:06:10.810172Z] Finished download-and-extract step (success) 20:06:10 INFO - [mozharness: 2016-10-30 03:06:10.810306Z] Running populate-webroot step. 20:06:10 INFO - Running pre-action listener: _resource_record_pre_action 20:06:10 INFO - Running main action method: populate_webroot 20:06:10 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:06:10 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:06:10 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:06:10 INFO - '--tppagecycles', 20:06:10 INFO - '7'], 20:06:10 INFO - 'tests': ['tsvgm']}}, 20:06:10 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:06:10 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:06:10 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:06:10 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:06:10 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:06:10 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:06:10 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:06:10 INFO - 'talos_options': ['--disable-e10s'], 20:06:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:06:10 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:06:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:06:10 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:06:10 INFO - 'talos_options': ['--disable-e10s'], 20:06:10 INFO - 'tests': ['damp', 'tps']}, 20:06:10 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:06:10 INFO - 'tests': ['damp', 'tps']}, 20:06:10 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:06:10 INFO - 'tests': ['dromaeo_dom']}, 20:06:10 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:06:10 INFO - 'g4': {'talos_options': ['--disable-e10s'], 20:06:10 INFO - 'tests': ['basic_compositor_video']}, 20:06:10 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 20:06:10 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:06:10 INFO - 'tests': ['a11yr', 20:06:10 INFO - 'ts_paint', 20:06:10 INFO - 'tpaint', 20:06:10 INFO - 'sessionrestore', 20:06:10 INFO - 'sessionrestore_no_auto_restore', 20:06:10 INFO - 'tabpaint']}, 20:06:10 INFO - 'other-e10s': {'tests': ['a11yr', 20:06:10 INFO - 'ts_paint', 20:06:10 INFO - 'tpaint', 20:06:10 INFO - 'sessionrestore', 20:06:10 INFO - 'sessionrestore_no_auto_restore', 20:06:10 INFO - 'tabpaint']}, 20:06:10 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:06:10 INFO - 'ts_paint', 20:06:10 INFO - 'tpaint', 20:06:10 INFO - 'sessionrestore', 20:06:10 INFO - 'sessionrestore_no_auto_restore', 20:06:10 INFO - 'tabpaint']}, 20:06:10 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:06:10 INFO - 'ts_paint', 20:06:10 INFO - 'tpaint', 20:06:10 INFO - 'sessionrestore', 20:06:10 INFO - 'sessionrestore_no_auto_restore', 20:06:10 INFO - 'tabpaint']}, 20:06:10 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:06:10 INFO - 'tests': ['a11yr', 20:06:10 INFO - 'ts_paint', 20:06:10 INFO - 'tpaint', 20:06:10 INFO - 'sessionrestore', 20:06:10 INFO - 'sessionrestore_no_auto_restore', 20:06:10 INFO - 'tabpaint']}, 20:06:10 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:06:10 INFO - 'tests': ['a11yr', 20:06:10 INFO - 'ts_paint', 20:06:10 INFO - 'tpaint', 20:06:10 INFO - 'sessionrestore', 20:06:10 INFO - 'sessionrestore_no_auto_restore', 20:06:10 INFO - 'tabpaint']}, 20:06:10 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:06:10 INFO - 'tests': ['tsvgx', 20:06:10 INFO - 'tsvgr_opacity', 20:06:10 INFO - 'tart', 20:06:10 INFO - 'tscrollx', 20:06:10 INFO - 'cart']}, 20:06:10 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:06:10 INFO - 'tsvgr_opacity', 20:06:10 INFO - 'tart', 20:06:10 INFO - 'tscrollx', 20:06:10 INFO - 'cart']}, 20:06:10 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:06:10 INFO - 'talos_options': ['--disable-e10s'], 20:06:10 INFO - 'tests': ['tp5o']}, 20:06:10 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:06:10 INFO - 'tests': ['tp5o']}, 20:06:10 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:06:10 INFO - 'talos_options': ['--disable-e10s', 20:06:10 INFO - '--xperf_path', 20:06:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:06:10 INFO - 'tests': ['tp5n']}, 20:06:10 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:06:10 INFO - 'talos_options': ['--xperf_path', 20:06:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:06:10 INFO - 'tests': ['tp5n']}}, 20:06:10 INFO - 'talos.zip': {'path': '', 20:06:10 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:06:10 INFO - Running post-action listener: _resource_record_post_action 20:06:10 INFO - [mozharness: 2016-10-30 03:06:10.829305Z] Finished populate-webroot step (success) 20:06:10 INFO - [mozharness: 2016-10-30 03:06:10.829442Z] Running create-virtualenv step. 20:06:10 INFO - Running pre-action listener: _resource_record_pre_action 20:06:10 INFO - Running main action method: create_virtualenv 20:06:10 INFO - Creating virtualenv /builds/slave/test/build/venv 20:06:10 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 20:06:10 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:06:10 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:06:10 INFO - Using real prefix '/tools/python27' 20:06:10 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:06:11 INFO - Installing distribute.............................................................................................................................................................................................done. 20:06:14 INFO - Installing pip.................done. 20:06:14 INFO - Return code: 0 20:06:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 20:06:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 20:06:14 INFO - Reading from file tmpfile_stdout 20:06:14 INFO - Output received: 20:06:14 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 20:06:14 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:06:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:06:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:06:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:06:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:06:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:06:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:06:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d03b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cfb1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3934d58e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce4fd30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc75db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ce47b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hHFnurEEs6/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6eju0Pengj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 20:06:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 20:06:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 20:06:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hHFnurEEs6/Render', 20:06:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:06:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:06:14 INFO - 'HOME': '/Users/cltbld', 20:06:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:06:14 INFO - 'LOGNAME': 'cltbld', 20:06:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:06:14 INFO - 'MOZ_NO_REMOTE': '1', 20:06:14 INFO - 'NO_EM_RESTART': '1', 20:06:14 INFO - 'PAGER': '/bin/cat', 20:06:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:06:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:06:14 INFO - 'PWD': '/builds/slave/test', 20:06:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:06:14 INFO - 'SHELL': '/bin/bash', 20:06:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6eju0Pengj/Listeners', 20:06:14 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 20:06:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:06:14 INFO - 'USER': 'cltbld', 20:06:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:06:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:06:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:06:14 INFO - 'XPC_FLAGS': '0x0', 20:06:14 INFO - 'XPC_SERVICE_NAME': '0', 20:06:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:06:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:06:15 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 20:06:15 INFO - Cleaning up... 20:06:15 INFO - Return code: 0 20:06:15 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:06:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:06:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:06:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:06:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:06:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:06:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:06:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d03b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cfb1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3934d58e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce4fd30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc75db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ce47b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hHFnurEEs6/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6eju0Pengj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 20:06:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 20:06:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 20:06:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hHFnurEEs6/Render', 20:06:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:06:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:06:15 INFO - 'HOME': '/Users/cltbld', 20:06:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:06:15 INFO - 'LOGNAME': 'cltbld', 20:06:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:06:15 INFO - 'MOZ_NO_REMOTE': '1', 20:06:15 INFO - 'NO_EM_RESTART': '1', 20:06:15 INFO - 'PAGER': '/bin/cat', 20:06:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:06:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:06:15 INFO - 'PWD': '/builds/slave/test', 20:06:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:06:15 INFO - 'SHELL': '/bin/bash', 20:06:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6eju0Pengj/Listeners', 20:06:15 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 20:06:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:06:15 INFO - 'USER': 'cltbld', 20:06:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:06:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:06:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:06:15 INFO - 'XPC_FLAGS': '0x0', 20:06:15 INFO - 'XPC_SERVICE_NAME': '0', 20:06:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:06:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:06:15 INFO - Downloading/unpacking psutil>=3.1.1 20:06:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:06:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:06:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:06:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:06:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:06:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:06:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:06:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:06:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:06:18 INFO - Installing collected packages: psutil 20:06:18 INFO - Running setup.py install for psutil 20:06:19 INFO - building 'psutil._psutil_osx' extension 20:06:19 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 20:06:19 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 20:06:19 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 20:06:19 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 20:06:19 INFO - building 'psutil._psutil_posix' extension 20:06:19 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 20:06:19 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 20:06:19 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 20:06:19 INFO - ^ 20:06:19 INFO - 1 warning generated. 20:06:19 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 20:06:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:06:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:06:20 INFO - Successfully installed psutil 20:06:20 INFO - Cleaning up... 20:06:20 INFO - Return code: 0 20:06:20 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:06:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:06:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:06:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:06:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:06:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:06:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:06:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d03b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cfb1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3934d58e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce4fd30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc75db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ce47b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hHFnurEEs6/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6eju0Pengj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 20:06:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 20:06:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 20:06:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hHFnurEEs6/Render', 20:06:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:06:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:06:20 INFO - 'HOME': '/Users/cltbld', 20:06:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:06:20 INFO - 'LOGNAME': 'cltbld', 20:06:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:06:20 INFO - 'MOZ_NO_REMOTE': '1', 20:06:20 INFO - 'NO_EM_RESTART': '1', 20:06:20 INFO - 'PAGER': '/bin/cat', 20:06:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:06:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:06:20 INFO - 'PWD': '/builds/slave/test', 20:06:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:06:20 INFO - 'SHELL': '/bin/bash', 20:06:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6eju0Pengj/Listeners', 20:06:20 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 20:06:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:06:20 INFO - 'USER': 'cltbld', 20:06:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:06:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:06:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:06:20 INFO - 'XPC_FLAGS': '0x0', 20:06:20 INFO - 'XPC_SERVICE_NAME': '0', 20:06:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:06:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:06:20 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:06:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:06:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:06:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:06:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:06:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:06:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:06:22 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:06:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:06:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:06:23 INFO - Installing collected packages: mozsystemmonitor 20:06:23 INFO - Running setup.py install for mozsystemmonitor 20:06:23 INFO - Successfully installed mozsystemmonitor 20:06:23 INFO - Cleaning up... 20:06:23 INFO - Return code: 0 20:06:23 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:06:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:06:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:06:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:06:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:06:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:06:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:06:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d03b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cfb1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3934d58e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce4fd30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc75db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ce47b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hHFnurEEs6/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6eju0Pengj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 20:06:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 20:06:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 20:06:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hHFnurEEs6/Render', 20:06:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:06:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:06:23 INFO - 'HOME': '/Users/cltbld', 20:06:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:06:23 INFO - 'LOGNAME': 'cltbld', 20:06:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:06:23 INFO - 'MOZ_NO_REMOTE': '1', 20:06:23 INFO - 'NO_EM_RESTART': '1', 20:06:23 INFO - 'PAGER': '/bin/cat', 20:06:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:06:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:06:23 INFO - 'PWD': '/builds/slave/test', 20:06:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:06:23 INFO - 'SHELL': '/bin/bash', 20:06:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6eju0Pengj/Listeners', 20:06:23 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 20:06:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:06:23 INFO - 'USER': 'cltbld', 20:06:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:06:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:06:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:06:23 INFO - 'XPC_FLAGS': '0x0', 20:06:23 INFO - 'XPC_SERVICE_NAME': '0', 20:06:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:06:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:06:23 INFO - Downloading/unpacking jsonschema==2.5.1 20:06:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:06:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:06:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:06:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:06:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:06:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:06:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:06:25 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:06:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:06:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:06:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:06:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:06:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:06:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:06:26 INFO - Downloading functools32-3.2.3-2.tar.gz 20:06:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:06:26 INFO - warning: no files found matching '*.txt' 20:06:26 INFO - no previously-included directories found matching 'build' 20:06:26 INFO - no previously-included directories found matching 'dist' 20:06:26 INFO - no previously-included directories found matching '.git*' 20:06:26 INFO - Installing collected packages: jsonschema, functools32 20:06:26 INFO - Running setup.py install for functools32 20:06:26 INFO - warning: no files found matching '*.txt' 20:06:26 INFO - no previously-included directories found matching 'build' 20:06:26 INFO - no previously-included directories found matching 'dist' 20:06:26 INFO - no previously-included directories found matching '.git*' 20:06:26 INFO - Successfully installed jsonschema functools32 20:06:26 INFO - Cleaning up... 20:06:26 INFO - Return code: 0 20:06:26 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 20:06:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:06:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:06:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:06:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:06:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:06:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:06:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d03b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cfb1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3934d58e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce4fd30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc75db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ce47b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hHFnurEEs6/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6eju0Pengj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 20:06:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 20:06:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 20:06:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hHFnurEEs6/Render', 20:06:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:06:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:06:26 INFO - 'HOME': '/Users/cltbld', 20:06:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:06:26 INFO - 'LOGNAME': 'cltbld', 20:06:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:06:26 INFO - 'MOZ_NO_REMOTE': '1', 20:06:26 INFO - 'NO_EM_RESTART': '1', 20:06:26 INFO - 'PAGER': '/bin/cat', 20:06:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:06:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:06:26 INFO - 'PWD': '/builds/slave/test', 20:06:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:06:26 INFO - 'SHELL': '/bin/bash', 20:06:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6eju0Pengj/Listeners', 20:06:26 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 20:06:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:06:26 INFO - 'USER': 'cltbld', 20:06:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:06:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:06:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:06:26 INFO - 'XPC_FLAGS': '0x0', 20:06:26 INFO - 'XPC_SERVICE_NAME': '0', 20:06:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:06:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:06:26 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 20:06:26 INFO - Cleaning up... 20:06:27 INFO - Return code: 0 20:06:27 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:06:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:06:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:06:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:06:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:06:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:06:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:06:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d03b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cfb1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3934d58e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce4fd30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc75db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ce47b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hHFnurEEs6/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6eju0Pengj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 20:06:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 20:06:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 20:06:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hHFnurEEs6/Render', 20:06:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:06:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:06:27 INFO - 'HOME': '/Users/cltbld', 20:06:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:06:27 INFO - 'LOGNAME': 'cltbld', 20:06:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:06:27 INFO - 'MOZ_NO_REMOTE': '1', 20:06:27 INFO - 'NO_EM_RESTART': '1', 20:06:27 INFO - 'PAGER': '/bin/cat', 20:06:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:06:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:06:27 INFO - 'PWD': '/builds/slave/test', 20:06:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:06:27 INFO - 'SHELL': '/bin/bash', 20:06:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6eju0Pengj/Listeners', 20:06:27 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 20:06:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:06:27 INFO - 'USER': 'cltbld', 20:06:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:06:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:06:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:06:27 INFO - 'XPC_FLAGS': '0x0', 20:06:27 INFO - 'XPC_SERVICE_NAME': '0', 20:06:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:06:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:06:27 INFO - Downloading/unpacking blobuploader==1.2.4 20:06:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:06:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:06:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:06:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:06:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:06:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:06:29 INFO - Downloading blobuploader-1.2.4.tar.gz 20:06:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:06:29 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:06:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:06:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:06:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:06:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:06:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:06:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:06:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:06:30 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:06:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:06:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:06:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:06:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:06:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:06:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:06:30 INFO - Downloading docopt-0.6.1.tar.gz 20:06:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:06:31 INFO - Installing collected packages: blobuploader, requests, docopt 20:06:31 INFO - Running setup.py install for blobuploader 20:06:31 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:06:31 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:06:31 INFO - Running setup.py install for requests 20:06:31 INFO - Running setup.py install for docopt 20:06:31 INFO - Successfully installed blobuploader requests docopt 20:06:31 INFO - Cleaning up... 20:06:31 INFO - Return code: 0 20:06:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:06:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:06:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:06:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:06:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:06:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:06:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:06:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d03b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cfb1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3934d58e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce4fd30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc75db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ce47b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hHFnurEEs6/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6eju0Pengj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 20:06:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 20:06:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 20:06:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hHFnurEEs6/Render', 20:06:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:06:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:06:31 INFO - 'HOME': '/Users/cltbld', 20:06:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:06:31 INFO - 'LOGNAME': 'cltbld', 20:06:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:06:31 INFO - 'MOZ_NO_REMOTE': '1', 20:06:31 INFO - 'NO_EM_RESTART': '1', 20:06:31 INFO - 'PAGER': '/bin/cat', 20:06:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:06:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:06:31 INFO - 'PWD': '/builds/slave/test', 20:06:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:06:31 INFO - 'SHELL': '/bin/bash', 20:06:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6eju0Pengj/Listeners', 20:06:31 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 20:06:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:06:31 INFO - 'USER': 'cltbld', 20:06:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:06:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:06:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:06:31 INFO - 'XPC_FLAGS': '0x0', 20:06:31 INFO - 'XPC_SERVICE_NAME': '0', 20:06:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:06:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:06:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:06:32 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-0in5W8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:06:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:06:32 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-crqFaz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:06:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:06:32 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-mFrAYu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:06:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:06:32 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-WcsFWs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:06:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:06:32 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-hlN23X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:06:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:06:32 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-1VOolK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:06:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:06:32 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-M138N7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:06:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:06:32 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-Lx2_va-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:06:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:06:33 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-JQK5LW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:06:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:06:33 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-w9rpDg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:06:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:06:33 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-VorlKA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:06:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:06:33 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-TSz1dd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:06:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:06:33 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-J886om-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:06:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:06:33 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-R2oVaq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:06:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:06:33 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-8vtOt3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:06:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:06:33 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-34A6et-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:06:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:06:33 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-6orbj_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:06:33 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:06:33 INFO - Running setup.py install for manifestparser 20:06:34 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:06:34 INFO - Running setup.py install for mozcrash 20:06:34 INFO - Running setup.py install for mozdebug 20:06:34 INFO - Running setup.py install for mozdevice 20:06:34 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:06:34 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:06:34 INFO - Running setup.py install for mozfile 20:06:34 INFO - Running setup.py install for mozhttpd 20:06:34 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:06:34 INFO - Running setup.py install for mozinfo 20:06:34 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:06:34 INFO - Running setup.py install for mozInstall 20:06:35 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:06:35 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:06:35 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:06:35 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:06:35 INFO - Running setup.py install for mozleak 20:06:35 INFO - Running setup.py install for mozlog 20:06:35 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:06:35 INFO - Running setup.py install for moznetwork 20:06:35 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:06:35 INFO - Running setup.py install for mozprocess 20:06:35 INFO - Running setup.py install for mozprofile 20:06:35 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:06:35 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:06:35 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:06:35 INFO - Running setup.py install for mozrunner 20:06:36 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:06:36 INFO - Running setup.py install for mozscreenshot 20:06:36 INFO - Running setup.py install for moztest 20:06:36 INFO - Running setup.py install for mozversion 20:06:36 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:06:36 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:06:36 INFO - Cleaning up... 20:06:36 INFO - Return code: 0 20:06:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:06:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:06:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:06:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:06:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:06:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:06:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:06:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d03b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cfb1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3934d58e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce4fd30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc75db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ce47b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hHFnurEEs6/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6eju0Pengj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 20:06:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 20:06:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 20:06:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hHFnurEEs6/Render', 20:06:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:06:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:06:36 INFO - 'HOME': '/Users/cltbld', 20:06:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:06:36 INFO - 'LOGNAME': 'cltbld', 20:06:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:06:36 INFO - 'MOZ_NO_REMOTE': '1', 20:06:36 INFO - 'NO_EM_RESTART': '1', 20:06:36 INFO - 'PAGER': '/bin/cat', 20:06:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:06:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:06:36 INFO - 'PWD': '/builds/slave/test', 20:06:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:06:36 INFO - 'SHELL': '/bin/bash', 20:06:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6eju0Pengj/Listeners', 20:06:36 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 20:06:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:06:36 INFO - 'USER': 'cltbld', 20:06:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:06:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:06:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:06:36 INFO - 'XPC_FLAGS': '0x0', 20:06:36 INFO - 'XPC_SERVICE_NAME': '0', 20:06:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:06:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:06:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:06:36 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-E_Kl6U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:06:36 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 20:06:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:06:36 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-h9bXvM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:06:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:06:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:06:37 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-0S56P0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:06:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 20:06:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:06:37 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-uAQN9Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:06:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:06:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:06:37 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-q_oW26-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:06:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 20:06:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:06:37 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-mEpbmB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:06:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 20:06:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:06:37 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-KwJy9f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:06:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 20:06:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:06:37 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-sEIYIe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:06:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 20:06:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:06:37 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-XIJPft-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:06:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 20:06:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:06:37 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-y4R4Ej-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:06:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:06:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:06:37 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-H1O7P6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:06:37 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 20:06:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:06:37 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-gd2no9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:06:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 20:06:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:06:38 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-6bSvLN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:06:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 20:06:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:06:38 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-tq8cqD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:06:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 20:06:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:06:38 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-DrMkcF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:06:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 20:06:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:06:38 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-TNTdta-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:06:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 20:06:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:06:38 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-aNuyNN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:06:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 20:06:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:06:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:06:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 20:06:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:06:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:06:38 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:06:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:06:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:06:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:06:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:06:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:06:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:06:41 INFO - Downloading blessings-1.6.tar.gz 20:06:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:06:41 INFO - Installing collected packages: blessings 20:06:41 INFO - Running setup.py install for blessings 20:06:41 INFO - Successfully installed blessings 20:06:41 INFO - Cleaning up... 20:06:41 INFO - Return code: 0 20:06:41 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:06:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:06:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:06:41 INFO - Reading from file tmpfile_stdout 20:06:41 INFO - Current package versions: 20:06:41 INFO - blessings == 1.6 20:06:41 INFO - blobuploader == 1.2.4 20:06:41 INFO - docopt == 0.6.1 20:06:41 INFO - functools32 == 3.2.3-2 20:06:41 INFO - jsonschema == 2.5.1 20:06:41 INFO - manifestparser == 1.1 20:06:41 INFO - mozInstall == 1.12 20:06:41 INFO - mozcrash == 0.17 20:06:41 INFO - mozdebug == 0.1 20:06:41 INFO - mozdevice == 0.48 20:06:41 INFO - mozfile == 1.2 20:06:41 INFO - mozhttpd == 0.7 20:06:41 INFO - mozinfo == 0.9 20:06:41 INFO - mozleak == 0.1 20:06:41 INFO - mozlog == 3.4 20:06:41 INFO - moznetwork == 0.27 20:06:41 INFO - mozprocess == 0.23 20:06:41 INFO - mozprofile == 0.28 20:06:41 INFO - mozrunner == 6.12 20:06:41 INFO - mozscreenshot == 0.1 20:06:41 INFO - mozsystemmonitor == 0.3 20:06:41 INFO - moztest == 0.7 20:06:41 INFO - mozversion == 1.4 20:06:41 INFO - psutil == 3.1.1 20:06:41 INFO - requests == 1.2.3 20:06:41 INFO - wsgiref == 0.1.2 20:06:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:06:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:06:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:06:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:06:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:06:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:06:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:06:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d03b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cfb1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3934d58e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce4fd30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc75db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ce47b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hHFnurEEs6/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6eju0Pengj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 20:06:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 20:06:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 20:06:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hHFnurEEs6/Render', 20:06:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:06:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:06:41 INFO - 'HOME': '/Users/cltbld', 20:06:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:06:41 INFO - 'LOGNAME': 'cltbld', 20:06:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:06:41 INFO - 'MOZ_NO_REMOTE': '1', 20:06:41 INFO - 'NO_EM_RESTART': '1', 20:06:41 INFO - 'PAGER': '/bin/cat', 20:06:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:06:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:06:41 INFO - 'PWD': '/builds/slave/test', 20:06:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:06:41 INFO - 'SHELL': '/bin/bash', 20:06:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6eju0Pengj/Listeners', 20:06:41 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 20:06:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:06:41 INFO - 'USER': 'cltbld', 20:06:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:06:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:06:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:06:41 INFO - 'XPC_FLAGS': '0x0', 20:06:41 INFO - 'XPC_SERVICE_NAME': '0', 20:06:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:06:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:06:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 20:06:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 20:06:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 20:06:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 20:06:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 20:06:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 20:06:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 20:06:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 20:06:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 20:06:42 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 20:06:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 20:06:42 INFO - Cleaning up... 20:06:42 INFO - Return code: 0 20:06:42 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:06:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:06:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:06:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:06:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:06:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:06:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:06:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d03b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cfb1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3934d58e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce4fd30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc75db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ce47b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hHFnurEEs6/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6eju0Pengj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 20:06:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 20:06:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 20:06:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hHFnurEEs6/Render', 20:06:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:06:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:06:42 INFO - 'HOME': '/Users/cltbld', 20:06:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:06:42 INFO - 'LOGNAME': 'cltbld', 20:06:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:06:42 INFO - 'MOZ_NO_REMOTE': '1', 20:06:42 INFO - 'NO_EM_RESTART': '1', 20:06:42 INFO - 'PAGER': '/bin/cat', 20:06:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:06:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:06:42 INFO - 'PWD': '/builds/slave/test', 20:06:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:06:42 INFO - 'SHELL': '/bin/bash', 20:06:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6eju0Pengj/Listeners', 20:06:42 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 20:06:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:06:42 INFO - 'USER': 'cltbld', 20:06:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:06:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:06:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:06:42 INFO - 'XPC_FLAGS': '0x0', 20:06:42 INFO - 'XPC_SERVICE_NAME': '0', 20:06:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:06:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:06:42 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:06:42 INFO - Cleaning up... 20:06:42 INFO - Return code: 0 20:06:42 INFO - Running post-action listener: _resource_record_post_action 20:06:42 INFO - Running post-action listener: _start_resource_monitoring 20:06:42 INFO - Starting resource monitoring. 20:06:42 INFO - [mozharness: 2016-10-30 03:06:42.329804Z] Finished create-virtualenv step (success) 20:06:42 INFO - [mozharness: 2016-10-30 03:06:42.330772Z] Running install step. 20:06:42 INFO - Running pre-action listener: _resource_record_pre_action 20:06:42 INFO - Running main action method: install 20:06:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:06:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:06:42 INFO - Reading from file tmpfile_stdout 20:06:42 INFO - Detecting whether we're running mozinstall >=1.0... 20:06:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:06:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:06:42 INFO - Reading from file tmpfile_stdout 20:06:42 INFO - Output received: 20:06:42 INFO - Usage: mozinstall [options] installer 20:06:42 INFO - Options: 20:06:42 INFO - -h, --help show this help message and exit 20:06:42 INFO - -d DEST, --destination=DEST 20:06:42 INFO - Directory to install application into. [default: 20:06:42 INFO - "/builds/slave/test"] 20:06:42 INFO - --app=APP Application being installed. [default: firefox] 20:06:42 INFO - mkdir: /builds/slave/test/build/application 20:06:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 20:06:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 20:07:17 INFO - Reading from file tmpfile_stdout 20:07:17 INFO - Output received: 20:07:17 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 20:07:17 INFO - Running post-action listener: _resource_record_post_action 20:07:17 INFO - [mozharness: 2016-10-30 03:07:17.128416Z] Finished install step (success) 20:07:17 INFO - [mozharness: 2016-10-30 03:07:17.128571Z] Running run-tests step. 20:07:17 INFO - Running pre-action listener: _resource_record_pre_action 20:07:17 INFO - Running main action method: run_tests 20:07:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:07:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:07:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:07:17 INFO - 2016-10-29 20:07:17.143 screenresolution[1644:8300] starting screenresolution argv=screenresolution get 20:07:17 INFO - 2016-10-29 20:07:17.145 screenresolution[1644:8300] Display 0: 1600x1200x32@60 20:07:17 INFO - 2016-10-29 20:07:17.152 screenresolution[1645:8302] starting screenresolution argv=screenresolution list 20:07:17 INFO - Available Modes on Display 0 20:07:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:07:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:07:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:07:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:07:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:07:17 INFO - Intel Iris: 20:07:17 INFO - Chipset Model: Intel Iris 20:07:17 INFO - Type: GPU 20:07:17 INFO - Bus: Built-In 20:07:17 INFO - VRAM (Dynamic, Max): 1536 MB 20:07:17 INFO - Vendor: Intel (0x8086) 20:07:17 INFO - Device ID: 0x0a2e 20:07:17 INFO - Revision ID: 0x0009 20:07:17 INFO - Displays: 20:07:17 INFO - Display: 20:07:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:07:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:07:17 INFO - Display Serial Number: 1600x1200 60 20:07:17 INFO - Main Display: Yes 20:07:17 INFO - Mirror: Off 20:07:17 INFO - Online: Yes 20:07:17 INFO - Rotation: Supported 20:07:17 INFO - Return code: 0 20:07:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:07:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:07:17 INFO - Python 2.7.3 20:07:17 INFO - Return code: 0 20:07:17 INFO - grabbing minidump binary from tooltool 20:07:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:07:17 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce4fd30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc75db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ce47b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:07:17 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 20:07:17 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 20:07:17 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 20:07:17 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:07:17 INFO - Return code: 0 20:07:17 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 20:07:17 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:07:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:07:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 20:07:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:07:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SBKPH6Y5Qrar0ULLW1T99g/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0306', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 20:07:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Larch --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/SBKPH6Y5Qrar0ULLW1T99g/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0306 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 20:07:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hHFnurEEs6/Render', 20:07:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:07:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:07:17 INFO - 'HOME': '/Users/cltbld', 20:07:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:07:17 INFO - 'LOGNAME': 'cltbld', 20:07:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:07:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 20:07:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:07:17 INFO - 'MOZ_NO_REMOTE': '1', 20:07:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:07:17 INFO - 'NO_EM_RESTART': '1', 20:07:17 INFO - 'PAGER': '/bin/cat', 20:07:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:07:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:07:17 INFO - 'PWD': '/builds/slave/test', 20:07:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:07:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:07:17 INFO - 'SHELL': '/bin/bash', 20:07:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6eju0Pengj/Listeners', 20:07:17 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 20:07:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:07:17 INFO - 'USER': 'cltbld', 20:07:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:07:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:07:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:07:17 INFO - 'XPC_FLAGS': '0x0', 20:07:17 INFO - 'XPC_SERVICE_NAME': '0', 20:07:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:07:17 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SBKPH6Y5Qrar0ULLW1T99g/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0306', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 20:07:17 INFO - mozversion application_buildid: 20161029175923 20:07:17 INFO - mozversion application_changeset: d4bf6c41f00dbc87e25914319094fe9e11756006 20:07:17 INFO - mozversion application_display_name: Nightly 20:07:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:07:17 INFO - mozversion application_name: Firefox 20:07:17 INFO - mozversion application_remotingname: firefox 20:07:17 INFO - mozversion application_repository: https://hg.mozilla.org/projects/larch 20:07:17 INFO - mozversion application_vendor: Mozilla 20:07:17 INFO - mozversion application_version: 52.0a1 20:07:17 INFO - mozversion platform_buildid: 20161029175923 20:07:17 INFO - mozversion platform_changeset: d4bf6c41f00dbc87e25914319094fe9e11756006 20:07:17 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/larch 20:07:17 INFO - mozversion platform_version: 52.0a1 20:07:17 INFO - using testdate: 1477796837 20:07:17 INFO - actual date: 1477796837 20:07:17 INFO - starting webserver on 'localhost:49272' 20:07:17 INFO - SUITE-START | Running 2 tests 20:07:17 INFO - TEST-START | dromaeo_css 20:07:17 INFO - operating with platform_type : mac_ 20:07:17 INFO - Initialising browser for dromaeo_css test... 20:07:17 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/tmpKPa8I2/profile http://localhost:49272/getInfo.html) 20:07:20 INFO - PROCESS | 1655 | 2016-10-29 20:07:20.973 plugin-container[1657:8481] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d3f, name = 'com.apple.tsm.portname' 20:07:20 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:07:20 INFO - PROCESS | 1655 | 2016-10-29 20:07:20.976 plugin-container[1657:8481] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 20:07:20 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:07:20 INFO - PROCESS | 1655 | 2016-10-29 20:07:20.976 plugin-container[1657:8481] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:07:21 INFO - PROCESS | 1655 | __metrics Screen width/height:1600/1200 20:07:21 INFO - PROCESS | 1655 | colorDepth:24 20:07:21 INFO - PROCESS | 1655 | Browser inner width/height: 1024/689 20:07:21 INFO - PROCESS | 1655 | __metrics 20:07:21 ERROR - PROCESS | 1655 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3131: TypeError: this.tabs is undefined 20:07:21 INFO - TEST-INFO | 1655: exit 0 20:07:21 INFO - Browser initialized. 20:07:21 INFO - Running cycle 1/1 for dromaeo_css test... 20:07:21 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/tmpKPa8I2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:07:24 INFO - PROCESS | 1659 | 2016-10-29 20:07:24.472 plugin-container[1660:8613] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d4b, name = 'com.apple.tsm.portname' 20:07:24 INFO - PROCESS | 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:07:24 INFO - PROCESS | 1659 | 2016-10-29 20:07:24.473 plugin-container[1660:8613] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 20:07:24 INFO - PROCESS | 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:07:24 INFO - PROCESS | 1659 | 2016-10-29 20:07:24.473 plugin-container[1660:8613] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:07:33 INFO - PROCESS | 1659 | RSS: Main: 242749440 20:07:33 INFO - PROCESS | 1659 | 20:08:23 INFO - PROCESS | 1659 | 1477796903642 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 20:10:37 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49272/tests/dromaeo/cssquery-ext.html) 20:10:39 INFO - PROCESS | 1659 | RSS: Main: 255205376 20:10:39 INFO - PROCESS | 1659 | 20:13:41 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-ext.html (next: http://localhost:49272/tests/dromaeo/cssquery-jquery.html) 20:13:42 INFO - PROCESS | 1659 | RSS: Main: 266063872 20:13:42 INFO - PROCESS | 1659 | 20:16:44 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49272/tests/dromaeo/cssquery-mootools.html) 20:16:45 INFO - PROCESS | 1659 | RSS: Main: 271101952 20:16:45 INFO - PROCESS | 1659 | 20:19:47 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49272/tests/dromaeo/cssquery-prototype.html) 20:19:47 INFO - PROCESS | 1659 | RSS: Main: 269688832 20:19:47 INFO - PROCESS | 1659 | 20:22:50 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49272/tests/dromaeo/cssquery-yui.html) 20:22:51 INFO - PROCESS | 1659 | RSS: Main: 274137088 20:22:51 INFO - PROCESS | 1659 | 20:25:53 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-yui.html (next: http://localhost:49272/tests/dromaeo/cssquery-dojo.html) 20:25:54 INFO - PROCESS | 1659 | RSS: Main: 275951616 20:25:54 INFO - PROCESS | 1659 | 20:25:54 INFO - PROCESS | 1659 | __start_tp_report 20:25:54 INFO - PROCESS | 1659 | _x_x_mozilla_page_load 20:25:54 INFO - PROCESS | 1659 | _x_x_mozilla_page_load_details 20:25:54 INFO - PROCESS | 1659 | |i|pagename|runs| 20:25:54 INFO - PROCESS | 1659 | |0;dojo.html;3341.7159541438978;3355.909329467923;3382.188274814045;3404.6254911959663;3411.266577685797;5657.9717101414535;5670.659760414378;5671.546276297897;5756.107803290486;5796.681182534959;5510.586705997046;5631.746571404286;5697.715114244282;5744.195812586241;5795.449432303935;4247.426597409357;5120.667156634825;5216.321878155834;5217.973910130463;5262.210668399752;4148.128892932487;5068.366454193226;5267.209918512234;5326.094563924142;5397.109476936321;5005.824796132154;5252.579793616521;5274.287971123926;5302.814401495948;5311.415744268127;3631.3282042821897;3993.94009089861;4589.586937175637;5068.290439338495;5161.793528258865;4141.275276826544;5119.948800511985;5167.534921857051;5239.947600523984;5282.841514754564;4891.828785992473;5623.297087864017;5651.830445086613;5749.0514065179195;5778.451047150514;5014.89970200594;6143.358355906958;6200.720967556471;6248.750049997994;6275.560710750249;6184.165137706397;6225.813225603194;6238.189035425411;6258.780942666985;6287.276963149251;5756.798512052058;5798.159266906275;5919.49684276837;5967.7911273105665;5997.610155339889;5655.462731040545;5941.22764040672;6194.81415557534;6214.378562143749;6215.285242197205;6182.6599537025895;6194;6201.224846894138;6205.5345849060595;6212.596181248205;6128.142060111588;6217.098520714471;6257.374262573797;6260.405261500241;6277.466415354746;5402.270693456333;6041.214642096499;6060.666663333559;6113.510919126459;6234.407731265523;6115.1744514490865;6160.568760186834;6184.8763024739255;6190.535709821781;6195.101710251988;4845.515448455197;4933.851984440473;4947.282644016597;4959.652824302264;4966.627502937295;4393.209222339946;4415.66882483815;4422.800973956179;4423.955760442354;4490.57339552742;5503.284573005563;5933.3473317934995;5974.731137098841;5993.910091348634;6020.969895150496;6219.098230756515;6224.284207316216;6227.19046523949;6259.311475737665;6261.5616906816995;5605.075162598126;5704.229928959538;5869.325027621878;5885;5974.3726908674835;4830.734309612934;5211.931554031415;5429.538489228381;5484.725763711798;5495.945040549624;6261.24865016201;6281.528885333618;6299.464545513591;6321.241451025906;6335;6143.665407145256;6166.260048794174;6185.41238582334;6255.46828519581;6258.280297765633;6166.105914642262;6277.1525844010475;6303.558750887394;6303.802277567248;6330.620162790247;6063.605865618721;6097.5246788036175;6157.476614487639;6200.627962322275;6227.283862355705;4918.9670169264855;5747.827565173052;6113.141862489121;6274.1843560336865;6294;5672.432756724294;5889.116632504987;6009.849753756191;6065.5754097212775;6154.5384096192065;5155.639105262596;6108.969455152874;6110.236220472441;6144.126041557368;6148.50811935053;4112.444819949388;5975.133605627247;6069.9089513656445;6082.239720034996;6108.6945652716295;5999.91000135007;6079.635221886701;6098.481629061402;6116.449519543263;6148.200733904742;5965.254343207099;5972.880542389041;6032.819015429544;6064.969675151596;6077.544184186116 20:25:54 INFO - PROCESS | 1659 | |1;ext.html;472.11242863416794;498.0835263115867;510.8186593759213;527.3645257464756;529.7457220534144;33795.83102084489;41574.16851662969;41704.165916681646;44193.11613767723;44289.33565996504;42569.14861702772;43070.569294307046;43097.56902430975;43128.568714312845;43170.35244471327;9566.904330956688;9685.661001864935;9756.317057805956;9761.951190244074;9766.804663906718;8427.662893484254;9572.234221262283;9690.370125941827;9691.563879625433;9710.66012689556;9566.186874115709;9709.854352184722;9710.805783884318;9711.320207585472;9711.805763884719;9631.903680963205;9712.077352651522;9732.172765314957;9737.367071140354;9739.561719722558;9566.426014439156;9621.422714637145;9645.37305075168;9649.276304277137;9649.421034737938;39851.80444586667;40114.39828402576;40177.598224017675;40213.798931005156;40241;3577.409727394977;3695.4456831475227;3782.678472329855;4059.6346328830546;4184.811683474251;25542.616860747105;29660.851695741385;30645.387092258254;30723.38553228923;31312.21719457009;2637.353848307158;3276.2464633134273;3316.137804170909;3345.6654334566592;3348.5814273215847;437289;438924;439841;443820;444062;57061.14408283879;57586.42413575894;57607;57620.71189644025;57717.13424298639;58854;59353.40646593565;59683.40316596865;59792.70103649454;60886;15454.690906181813;15702.9214853925;15716.764248536281;15758.921205394128;15764.527064188092;12218.511259549696;12264;12320;12337.198082124813;12365.690857728629;12327.6918077047;15074.54776356711;15082.849171508364;15421.537353879401;15429.845701543065;3203.4874420092674;6704.530682852153;6908.792736217922;6914.481413893978;6968.860622787516;910.8815853939078;987.1244635193048;989.4162444157837;1011.8836333821557;1028.3675539543126;27926.441471170463;29858.954936577116;29900.85049574738;30384.84807575948;30842.84578577093;957.3203025851724;995.5520015992832;1121.9439028048564;1125.4485302202027;1128.2793739547092;14295.714085718435;15756.369745210293;15829.525114246591;15833.287502062434;16105.355785768437;631394;631475;631890;639197;639938.4040037383;49285;49728.52443280122;58224;58869.52826179294;59545.702271488364;1351.7161396106928;1406.8171137752026;1421.0549984260351;1422.9857701423061;1428.8714015738633;27829.721702782714;28500.714992849807;29932.857050009436;30382.544261835646;30897.536536951513;50441.74779126081;50719;51033.72415689489;51080;51167.488325116276;573578;575870;577871;578038.1098094482;578077;26201.73798261993;26391.472170556866;26639.067632632767;27212.91148354123;27945.021924232555;47510.52489475061;47691.284630729875;47884.28173577329;48009.7997550064;48976.26535601897;38863.41704874486;38938.02654933649;39589.208215834944;39839;40504;38762.41856372213;38971;38974.220515590096;40261.59738402579;40400.39399408952;38152;38780.80609596934;38792.22415551617;39026.02434939149;39183;28160.43679126365;31232.531512026882;34305.59860809719;34371.14072148116;34791.4781278276;18087.909560452114;21680.67478987785;24144.758552414252;25743.871280643478;26136.477270454103 20:25:54 INFO - PROCESS | 1659 | |2;jquery.html;312.4797212640953;364.1678764024205;364.6645086520402;376.6929952089049;396.17397725741824;16730.498085057454;19338.709919351204;19792.802071979277;20227.089780959835;20501.589968200627;20240.89879550604;20540.794592054073;20913.16347346108;20959.580808383824;20983;5456.317960254968;5614.848955964038;5784.68184249866;5793.913091303634;6098.39016098391;5910.261217347832;6001.66990815505;6037.426444487766;6074.757009719606;6099.115628233903;5606.383297837255;5833.8833223335505;5966.522678185756;5997.760089596411;6019.849503762419;4660.580547750702;5027.974860125694;5280.894382112356;5925.40745925404;5979.192808970777;4360.171567402183;5103.132467480537;5155.046316431435;5171.715555644438;5177.456367081478;25307;25397.746022539723;25550.87224563884;25631.359216019562;25809.74190258111;2178.6187417202086;2312.7571604981404;2344.3787396339985;2393.6050551658955;2438.682971213731;14698.33857476416;15259.61850953724;15285.923570382078;15560.144192069432;16092.83907160925;1875.9155837987187;1891.1300801631266;1905.6284024615206;1946.707993800927;1956.7358406615067;118196;122569;122647;122816.38591807074;125868.99999999908;19404.611907761766;20773.584528309348;20828;21283.680744788842;21513.56972860534;34985.1253718654;35236.47145292823;35317.47023794645;35332.64667353345;35381.46927796085;10165.4917254137;10335.328203666737;10411.895881041093;10497.9475102624;10615.84076238857;7324.6337683115635;8288.336933045342;8366.958165209135;8549.401541892008;8750.249950010024;9712.368696034735;9781.657641982496;9831.655892043744;9955.153811925922;9970.102690757867;3805.3911374179997;3885.3977633466857;3896.103896103912;3914.5693973662874;3966.2662407454714;851.9872201916976;936.0265324063034;955.2214944819949;960.8894977077725;963.3834346018554;15942.68114637724;21481.67777483339;21666.674999875013;21770.89114554417;21794.455138621342;879.872418499314;893.25413279912;992.5682328187262;994.1251698505414;1034.1468288661883;11163.888361116447;11418.257813242113;11514.424278786195;11566.595169169039;11608.76782464346;561053;564906;575059;589368;589424;127999.36000320125;135310.3234483821;140421.4464088507;140880;144121;963.3401120232663;998.4708104704519;1017.3539802225492;1037.3775734559204;1040.308195050283;15188.696226075195;18968.905155474135;19076.950767707352;19108.140133693738;19252.8074719251;36732;41056.58943410528;43655.56344436515;49076.01847962949;53633.19550206828;66956;81143.5942820282;84421;85844;87049.56475217584;6247.406496382836;6882.4149947253045;7316.0489136411925;7380.335769780745;7532.623368831427;8200.835983280182;10561.260711750103;12912.548060817826;13293.867061329263;13356.599302020955;8774.868376974478;9036.412633178823;11267.549298028314;11397.316161030365;11415.258008229439;10159;10285.691429257135;10775.245732798629;11214.402389741615;11231.55073797039;9526.90473095288;9599.952000239955;11645.88354116448;11824.23142495735;11853.288802671867;6911.5853048817235;7121.46589005837;7205.099362579677;7389.593572353572;7484.663190156347;9781.902180978099;11202.047825934887;11437.771244575228;11458.4270786462;11485.770284594428 20:25:54 INFO - PROCESS | 1659 | |3;mootools.html;1405.45187376923;1526.0462211118054;1730.2992288123307;1859.832615064644;1876.8592355573335;7520.661570229339;7740.109887362941;8072.475289106204;8416.116307787686;8451.070382257954;7934.404919631022;8165.755027349189;8194.426390152677;8310;8336.16638336166;2020.9898950505226;2250.9437264068365;2296.1504243429954;2303.8272129590264;2311.468362276669;2282.109977108929;2307.157887371106;2318.3972167236557;2337.7194736631627;2348.1194552042984;2273.9886300568555;2285.531466049456;2301.4361481436986;2305.907763689459;2311.4683622766775;2223.4552534628956;2245.9326220213256;2254.785795349439;2261.106862789189;2263.841531092824;2244.3603572981783;2305.976940230593;2312.8034117099896;2313.97686023141;2314.525522267931;8299.377546684023;8331.583420828934;8336.83326333476;8381.03618083922;8382.87425688615;5004.499550045003;5173.198154286091;5176.55999240065;5178.792848286064;5195.870103247411;3271.8854840080485;3373.241020770307;3612;3629.818509074562;3634.7637403568683;1007.7883644434678;1017.959281628734;1048.5176818663347;1049.1292227451197;1062.8777690565607;10721.678349649523;10736.677899663022;10748.946255268673;10811.405372704497;10849.132069434505;4412.117576484716;4486.147631949919;4661.114388266286;4678;4680.485146633868;9236.953815230881;9312.068793120634;9313.860292095624;9321.207697345802;9330.440173589606;5147.40804807452;5168.819091331786;5179.04187725272;5182.922256166161;5195.766190521435;4088.529819070845;4645.953540464552;4693.741844198602;4738.47876733559;4755.738434386142;3686.6866316363407;3703.4815125882396;3767.4348847672522;3806.2768074066394;3821.923561528754;1240.621610408834;1245.9937700311623;1250.7060840702427;1262;1270.003047607642;757.4054367321626;813.6826637611337;923.1829830599834;953.7043516509765;957.5690939077303;5022.347094877642;5418.428655689884;5526.668399896019;5656.830295091154;5796.304443466726;420.573118284941;427.88233235859764;447.42729306487746;451.7447642082213;452.3260342090262;3333.749968752353;3421.9391988483153;3745.6890088469363;3747.981260093682;3866.7679939203736;8251.752447426587;9878.851817222747;10012.299139060342;10015.148712359385;10034.899651003543;9921.40471571708;9970.601175953027;9973.10242078202;10003.699889003341;10013.29906906524;6874.621895795779;7681;7791.10402303731;7828.295453409107;7893;4409.4488188976375;4513.277875539898;5254.474552544715;5445.183222516495;5453.209284653623;8296.668133274601;8984.595693193953;9077.818443630958;9224.723258302261;9263.980962094029;9237.907620923705;9273.25813934897;9304.95347523258;9311.534423278674;9382.48396338208;6517.967410162919;6744.156980377453;6891.517593768388;7012.929870701228;7109.360157585842;7790.2209779023915;7981.161977992228;7997.560134192442;8009.559474228973;8023.358131349595;7000.7549735759;7095.432365410651;7105.609191494517;7149.213586505584;7235.167955684953;5684.971575142098;6754.257031726408;6942.340477654615;6990.685419156252;6998.895016574654;6358.427741503287;6528.738850446119;6628.138342015507;6632;6644.302348253557;4795.928061079157;4818.469968303554;4839.249916262985;4887.413510378635;4914.92627610579;4023.7384570002855;4764.809407623657;4857.368542089647;4927.975360123177;5047.167217409086 20:25:54 INFO - PROCESS | 1659 | |4;prototype.html;298.4851131797648;302.31073153210673;309.5156080733651;312.99217519562023;320.30813442963205;5071.79712811488;5248.895022099556;5249.4750524947585;5473.534749546288;5519.6412233204865;4513.842015529457;4920.606351491881;5298.947010529893;5357.223202635625;5359.356877174735;3921.450996860442;3962.286788378091;4085.6507508182767;4143.544210136897;4147.854825081123;3667.8166091695443;3940.7241493095494;4022.8879838084945;4187.623113919746;4194.412782210493;4101.5488296287385;4120.629143377096;4128.442660240859;4138.730982486129;4139.3997870308785;3884.882025053424;4041.2725709372303;4067.4712287402594;4158.6465150462245;4161.771102589357;2755.476459472694;3323.933521329584;3625.546806649169;3641.927161456756;3649.927001459956;4990.176620857555;5210.296609957645;5219.765110569995;5238.423773384925;5274.472552744734;4232.492100947906;4749;4752.904941901177;4776.761161941889;4809.5430934061205;5605.887882242332;5708.974243650871;5728.79949201776;5745.913811292834;5770.653760774367;3587.7980876406436;3731.3470142724914;3817.4273858921106;3919.6668283195963;4008.198360327946;5046.6719663221775;5449.891002179934;5794.478496935254;5980.043193089128;6217.782377616762;3388.542546756206;3400.523926650271;3674.9816250918575;3972.9868883434538;4111.691623128278;3451.758376913642;3791.7345785795283;3968.66266367362;4120.134771697977;4152.771597562102;3954.6440820326306;4383.60547550722;4737.597304229179;4748.525147485224;4786.832460863922;5077.136886729265;5116.032701033391;5314.70769107695;5747.568932330092;5756.539476841843;1316.3220941215282;1360.0615575253044;1381.7720076187518;1397.475946520055;1397.8392484864166;920.0155833258483;942.1755963531909;950.8193443245762;964.2237998411206;964.7925695975399;4485.147821913826;5295.484644793769;5297.708626025528;5300.725777850986;5384.772953231694;5784.975258659436;5789.565782566325;5798.536117110621;5845.444682755132;5852.882942341214;4983.975080124576;5262.657927234717;5266.78932842682;5278.551323137576;5314.97342513285;2787.1220565521794;2822.7600653944232;3076.10819346105;3105.223694076481;3112.5331200320134;5383.515483606494;5763.567732420085;6093.308152722468;6102.481289090477;6136.23297087864;5449.313590312328;5714.571407144481;6030.969845150747;6049.969750151309;6063.575549711478;6057.60625559346;6072.392760723892;6089.908651370233;6106.450419462359;6112.572119951561;5409.756560954688;5415.160650099241;5604.458773837065;5838.970805145947;5906.544671367298;4641.651876109373;5616.66300022;5817.272840894888;5863.824085277449;5906.232189815297;6132.41742034506;6134.607503073716;6135.53983451234;6148.938510614837;6181.505479561714;3605.8197090144863;3884.4367566703236;3896.6103389660807;3909.139989202371;3920.294347017564;5601.971990140023;5881.9705901470215;5896.380880007709;5901.675407852437;5908.759160576155;5431.972840135774;5654.971725141348;5662.716864156858;5665.178549110438;5668.149777533403;5685.744141513559;5688.862227554423;5688.943110568841;5703.20155178267;5708.885822283614;4807.471178170328;5264.368275806928;5325.813596524103;5504.256925315191;5579.888402231852;4988.8752781180765;5036.093503169464;5523.585731070106;5676;5715.371309155907;4203.747775133379;4402.578581779944;5321.829197576527;5580.972095139498;5590.217369568345 20:25:54 INFO - PROCESS | 1659 | |5;yui.html;728.0644371982004;777.0287141073658;796.2594786848229;799.9800004999876;834.4534330013839;15015.849841501595;15564.922175389107;15595.922020389911;15639.06165630063;15664.765028524582;12713.23720576766;12732.236065836056;12743.936280318585;12745.808812867814;12755.553555625555;868.5049521772593;890.9420887642283;892.7054072156172;893.4148132972913;893.5889490834224;876.6011464783509;892.9107089291051;893.2942975049702;894.7807787092172;898.716904175186;940.92472602192;942.3309450290302;946.6024269806663;948.340903072365;956.7321150077954;932.9626814927394;937.6577549194495;938.0338251600862;938.31503002808;939.6006697153716;601.5909181756404;690.2717632897292;697.0102454514602;705.7318219076769;706.5266271598042;12584.307863067528;12887.548935787296;12913.289769062698;12930.224186548838;13565.796513052312;11783.234089784137;11786.410679465993;11793.174477786559;11862.406879655982;11907.345096019715;10315.84526232107;10404.479776011245;10407.843882341771;10432.426216558086;10768.084712799422;3549.396602577568;3586.4799604057266;3592.964070359289;3660.6705396514176;3756.7370284079852;92591.07408925892;94393.05606943912;96329.51835240779;97029;97975;8931.374803763803;9005.324600654978;9060.81878362429;9087.591058402388;9228.169464748205;18896.43310700654;18996;19037.048147592566;19052.04693272545;19054.61890762177;7641.808954776175;7642.656080476392;7752.255742457902;7784.844303113906;7909;3926.6662333701383;4215.515215750228;4244.957550424456;4367.803448844809;4385.320275357325;3273.688999545087;3384.8984530464127;3386.0180547641403;3394.5077963695126;3401.523786669868;602.9125776762345;618.6937465954381;635.1267007864187;642.3640595810115;646.5829539946708;537.6048604275854;627.7520379450092;646.5280345347828;673.5756473421811;677.1298880937971;6504.5446818723185;8418.86345343383;8668.43655162425;8823.294136469196;9073.81852362949;500.8873003574167;612.8682333298273;620.6965372717258;630.9274433440213;686.6498085976089;4082.6733861291027;4218.8523401680795;4489.9551004490195;4546.204414227497;4553.043860789205;69504;80659.79010314733;97697.51151244198;105440.47279763705;113484;61238.69380653157;81148;87759;87978.40161987877;91929.62105568421;71824;81722;84355.5782221085;85398.57300713456;85447;9607.759806004906;9628.807423851344;9716.562754675915;9732.221422286058;9734.36726612768;40956.79521602373;44625.33062004008;50682.74658626683;52499.73750131225;52570.73714631402;52791.944161115796;55404;69922.65038674774;81235.59382203051;87886;13150.342482876009;13198.313582043467;13374.264415457243;13502.392392342172;13884.444622214998;14379.92810035985;14440.711185776016;14463.927680361952;14835.851641483447;15453.227338633249;10658.520366583367;11779.941100294787;11883.989860861579;12939.353032348534;13469.865301347254;13372.93313533426;13454.730905382034;13692.65768355799;14016.088954217943;14044.508442204473;11294.830577541179;13035.869641303845;13099.738005240033;13173;13334.666633334244;9868.667729856377;10466.529006194893;10578.312409693346;10763.623273185402;11301.660950171508;11206.207919662267;11313.151513636345;11468.483918223534;11472.88527114718;11528.827067593826 20:25:54 INFO - PROCESS | 1659 | __end_tp_report 20:25:54 INFO - PROCESS | 1659 | __start_cc_report 20:25:54 INFO - PROCESS | 1659 | _x_x_mozilla_cycle_collect,372 20:25:54 INFO - PROCESS | 1659 | __end_cc_report 20:25:54 INFO - PROCESS | 1659 | __startTimestamp1477797954013__endTimestamp 20:25:54 INFO - PROCESS | 1659 | 20:25:54 INFO - PROCESS | 1659 | ------- Summary: start ------- 20:25:54 INFO - PROCESS | 1659 | Number of tests: 6 20:25:54 INFO - PROCESS | 1659 | 20:25:54 INFO - PROCESS | 1659 | [#0] dojo.html Cycles:165 Average:5661.61 Median:5974.37 stddev:712.88 (11.9%) stddev-sans-first:691.44 20:25:54 INFO - PROCESS | 1659 | Values: 3341.7 3355.9 3382.2 3404.6 3411.3 5658.0 5670.7 5671.5 5756.1 5796.7 5510.6 5631.7 5697.7 5744.2 5795.4 4247.4 5120.7 5216.3 5218.0 5262.2 4148.1 5068.4 5267.2 5326.1 5397.1 5005.8 5252.6 5274.3 5302.8 5311.4 3631.3 3993.9 4589.6 5068.3 5161.8 4141.3 5119.9 5167.5 5239.9 5282.8 4891.8 5623.3 5651.8 5749.1 5778.5 5014.9 6143.4 6200.7 6248.8 6275.6 6184.2 6225.8 6238.2 6258.8 6287.3 5756.8 5798.2 5919.5 5967.8 5997.6 5655.5 5941.2 6194.8 6214.4 6215.3 6182.7 6194.0 6201.2 6205.5 6212.6 6128.1 6217.1 6257.4 6260.4 6277.5 5402.3 6041.2 6060.7 6113.5 6234.4 6115.2 6160.6 6184.9 6190.5 6195.1 4845.5 4933.9 4947.3 4959.7 4966.6 4393.2 4415.7 4422.8 4424.0 4490.6 5503.3 5933.3 5974.7 5993.9 6021.0 6219.1 6224.3 6227.2 6259.3 6261.6 5605.1 5704.2 5869.3 5885.0 5974.4 4830.7 5211.9 5429.5 5484.7 5495.9 6261.2 6281.5 6299.5 6321.2 6335.0 6143.7 6166.3 6185.4 6255.5 6258.3 6166.1 6277.2 6303.6 6303.8 6330.6 6063.6 6097.5 6157.5 6200.6 6227.3 4919.0 5747.8 6113.1 6274.2 6294.0 5672.4 5889.1 6009.8 6065.6 6154.5 5155.6 6109.0 6110.2 6144.1 6148.5 4112.4 5975.1 6069.9 6082.2 6108.7 5999.9 6079.6 6098.5 6116.4 6148.2 5965.3 5972.9 6032.8 6065.0 6077.5 20:25:54 INFO - PROCESS | 1659 | 20:25:54 INFO - PROCESS | 1659 | [#1] ext.html Cycles:180 Average:68348.58 Median:22816.90 stddev:148798.05 (652.1%) stddev-sans-first:149128.19 20:25:54 INFO - PROCESS | 1659 | Values: 472.1 498.1 510.8 527.4 529.7 33795.8 41574.2 41704.2 44193.1 44289.3 42569.1 43070.6 43097.6 43128.6 43170.4 9566.9 9685.7 9756.3 9762.0 9766.8 8427.7 9572.2 9690.4 9691.6 9710.7 9566.2 9709.9 9710.8 9711.3 9711.8 9631.9 9712.1 9732.2 9737.4 9739.6 9566.4 9621.4 9645.4 9649.3 9649.4 39851.8 40114.4 40177.6 40213.8 40241.0 3577.4 3695.4 3782.7 4059.6 4184.8 25542.6 29660.9 30645.4 30723.4 31312.2 2637.4 3276.2 3316.1 3345.7 3348.6 437289.0 438924.0 439841.0 443820.0 444062.0 57061.1 57586.4 57607.0 57620.7 57717.1 58854.0 59353.4 59683.4 59792.7 60886.0 15454.7 15702.9 15716.8 15758.9 15764.5 12218.5 12264.0 12320.0 12337.2 12365.7 12327.7 15074.5 15082.8 15421.5 15429.8 3203.5 6704.5 6908.8 6914.5 6968.9 910.9 987.1 989.4 1011.9 1028.4 27926.4 29859.0 29900.9 30384.8 30842.8 957.3 995.6 1121.9 1125.4 1128.3 14295.7 15756.4 15829.5 15833.3 16105.4 631394.0 631475.0 631890.0 639197.0 639938.4 49285.0 49728.5 58224.0 58869.5 59545.7 1351.7 1406.8 1421.1 1423.0 1428.9 27829.7 28500.7 29932.9 30382.5 30897.5 50441.7 50719.0 51033.7 51080.0 51167.5 573578.0 575870.0 577871.0 578038.1 578077.0 26201.7 26391.5 26639.1 27212.9 27945.0 47510.5 47691.3 47884.3 48009.8 48976.3 38863.4 38938.0 39589.2 39839.0 40504.0 38762.4 38971.0 38974.2 40261.6 40400.4 38152.0 38780.8 38792.2 39026.0 39183.0 28160.4 31232.5 34305.6 34371.1 34791.5 18087.9 21680.7 24144.8 25743.9 26136.5 20:25:54 INFO - PROCESS | 1659 | 20:25:54 INFO - PROCESS | 1659 | [#2] jquery.html Cycles:180 Average:35730.65 Median:35276.97 stddev:96592.48 (273.8%) stddev-sans-first:96826.84 20:25:54 INFO - PROCESS | 1659 | Values: 312.5 364.2 364.7 376.7 396.2 16730.5 19338.7 19792.8 20227.1 20501.6 20240.9 20540.8 20913.2 20959.6 20983.0 5456.3 5614.8 5784.7 5793.9 6098.4 5910.3 6001.7 6037.4 6074.8 6099.1 5606.4 5833.9 5966.5 5997.8 6019.8 4660.6 5028.0 5280.9 5925.4 5979.2 4360.2 5103.1 5155.0 5171.7 5177.5 25307.0 25397.7 25550.9 25631.4 25809.7 2178.6 2312.8 2344.4 2393.6 2438.7 14698.3 15259.6 15285.9 15560.1 16092.8 1875.9 1891.1 1905.6 1946.7 1956.7 118196.0 122569.0 122647.0 122816.4 125869.0 19404.6 20773.6 20828.0 21283.7 21513.6 34985.1 35236.5 35317.5 35332.6 35381.5 10165.5 10335.3 10411.9 10497.9 10615.8 7324.6 8288.3 8367.0 8549.4 8750.2 9712.4 9781.7 9831.7 9955.2 9970.1 3805.4 3885.4 3896.1 3914.6 3966.3 852.0 936.0 955.2 960.9 963.4 15942.7 21481.7 21666.7 21770.9 21794.5 879.9 893.3 992.6 994.1 1034.1 11163.9 11418.3 11514.4 11566.6 11608.8 561053.0 564906.0 575059.0 589368.0 589424.0 127999.4 135310.3 140421.4 140880.0 144121.0 963.3 998.5 1017.4 1037.4 1040.3 15188.7 18968.9 19077.0 19108.1 19252.8 36732.0 41056.6 43655.6 49076.0 53633.2 66956.0 81143.6 84421.0 85844.0 87049.6 6247.4 6882.4 7316.0 7380.3 7532.6 8200.8 10561.3 12912.5 13293.9 13356.6 8774.9 9036.4 11267.5 11397.3 11415.3 10159.0 10285.7 10775.2 11214.4 11231.6 9526.9 9600.0 11645.9 11824.2 11853.3 6911.6 7121.5 7205.1 7389.6 7484.7 9781.9 11202.0 11437.8 11458.4 11485.8 20:25:54 INFO - PROCESS | 1659 | 20:25:54 INFO - PROCESS | 1659 | [#3] mootools.html Cycles:180 Average:5345.99 Median:4966.24 stddev:2948.44 (59.4%) stddev-sans-first:2941.84 20:25:54 INFO - PROCESS | 1659 | Values: 1405.5 1526.0 1730.3 1859.8 1876.9 7520.7 7740.1 8072.5 8416.1 8451.1 7934.4 8165.8 8194.4 8310.0 8336.2 2021.0 2250.9 2296.2 2303.8 2311.5 2282.1 2307.2 2318.4 2337.7 2348.1 2274.0 2285.5 2301.4 2305.9 2311.5 2223.5 2245.9 2254.8 2261.1 2263.8 2244.4 2306.0 2312.8 2314.0 2314.5 8299.4 8331.6 8336.8 8381.0 8382.9 5004.5 5173.2 5176.6 5178.8 5195.9 3271.9 3373.2 3612.0 3629.8 3634.8 1007.8 1018.0 1048.5 1049.1 1062.9 10721.7 10736.7 10748.9 10811.4 10849.1 4412.1 4486.1 4661.1 4678.0 4680.5 9237.0 9312.1 9313.9 9321.2 9330.4 5147.4 5168.8 5179.0 5182.9 5195.8 4088.5 4646.0 4693.7 4738.5 4755.7 3686.7 3703.5 3767.4 3806.3 3821.9 1240.6 1246.0 1250.7 1262.0 1270.0 757.4 813.7 923.2 953.7 957.6 5022.3 5418.4 5526.7 5656.8 5796.3 420.6 427.9 447.4 451.7 452.3 3333.7 3421.9 3745.7 3748.0 3866.8 8251.8 9878.9 10012.3 10015.1 10034.9 9921.4 9970.6 9973.1 10003.7 10013.3 6874.6 7681.0 7791.1 7828.3 7893.0 4409.4 4513.3 5254.5 5445.2 5453.2 8296.7 8984.6 9077.8 9224.7 9264.0 9237.9 9273.3 9305.0 9311.5 9382.5 6518.0 6744.2 6891.5 7012.9 7109.4 7790.2 7981.2 7997.6 8009.6 8023.4 7000.8 7095.4 7105.6 7149.2 7235.2 5685.0 6754.3 6942.3 6990.7 6998.9 6358.4 6528.7 6628.1 6632.0 6644.3 4795.9 4818.5 4839.2 4887.4 4914.9 4023.7 4764.8 4857.4 4928.0 5047.2 20:25:54 INFO - PROCESS | 1659 | 20:25:54 INFO - PROCESS | 1659 | [#4] prototype.html Cycles:180 Average:4620.57 Median:5263.51 stddev:1429.87 (27.2%) stddev-sans-first:1396.60 20:25:54 INFO - PROCESS | 1659 | Values: 298.5 302.3 309.5 313.0 320.3 5071.8 5248.9 5249.5 5473.5 5519.6 4513.8 4920.6 5298.9 5357.2 5359.4 3921.5 3962.3 4085.7 4143.5 4147.9 3667.8 3940.7 4022.9 4187.6 4194.4 4101.5 4120.6 4128.4 4138.7 4139.4 3884.9 4041.3 4067.5 4158.6 4161.8 2755.5 3323.9 3625.5 3641.9 3649.9 4990.2 5210.3 5219.8 5238.4 5274.5 4232.5 4749.0 4752.9 4776.8 4809.5 5605.9 5709.0 5728.8 5745.9 5770.7 3587.8 3731.3 3817.4 3919.7 4008.2 5046.7 5449.9 5794.5 5980.0 6217.8 3388.5 3400.5 3675.0 3973.0 4111.7 3451.8 3791.7 3968.7 4120.1 4152.8 3954.6 4383.6 4737.6 4748.5 4786.8 5077.1 5116.0 5314.7 5747.6 5756.5 1316.3 1360.1 1381.8 1397.5 1397.8 920.0 942.2 950.8 964.2 964.8 4485.1 5295.5 5297.7 5300.7 5384.8 5785.0 5789.6 5798.5 5845.4 5852.9 4984.0 5262.7 5266.8 5278.6 5315.0 2787.1 2822.8 3076.1 3105.2 3112.5 5383.5 5763.6 6093.3 6102.5 6136.2 5449.3 5714.6 6031.0 6050.0 6063.6 6057.6 6072.4 6089.9 6106.5 6112.6 5409.8 5415.2 5604.5 5839.0 5906.5 4641.7 5616.7 5817.3 5863.8 5906.2 6132.4 6134.6 6135.5 6148.9 6181.5 3605.8 3884.4 3896.6 3909.1 3920.3 5602.0 5882.0 5896.4 5901.7 5908.8 5432.0 5655.0 5662.7 5665.2 5668.1 5685.7 5688.9 5688.9 5703.2 5708.9 4807.5 5264.4 5325.8 5504.3 5579.9 4988.9 5036.1 5523.6 5676.0 5715.4 4203.7 4402.6 5321.8 5581.0 5590.2 20:25:54 INFO - PROCESS | 1659 | 20:25:54 INFO - PROCESS | 1659 | [#5] yui.html Cycles:180 Average:19421.76 Median:52535.24 stddev:28049.46 (53.4%) stddev-sans-first:28093.02 20:25:54 INFO - PROCESS | 1659 | Values: 728.1 777.0 796.3 800.0 834.5 15015.8 15564.9 15595.9 15639.1 15664.8 12713.2 12732.2 12743.9 12745.8 12755.6 868.5 890.9 892.7 893.4 893.6 876.6 892.9 893.3 894.8 898.7 940.9 942.3 946.6 948.3 956.7 933.0 937.7 938.0 938.3 939.6 601.6 690.3 697.0 705.7 706.5 12584.3 12887.5 12913.3 12930.2 13565.8 11783.2 11786.4 11793.2 11862.4 11907.3 10315.8 10404.5 10407.8 10432.4 10768.1 3549.4 3586.5 3593.0 3660.7 3756.7 92591.1 94393.1 96329.5 97029.0 97975.0 8931.4 9005.3 9060.8 9087.6 9228.2 18896.4 18996.0 19037.0 19052.0 19054.6 7641.8 7642.7 7752.3 7784.8 7909.0 3926.7 4215.5 4245.0 4367.8 4385.3 3273.7 3384.9 3386.0 3394.5 3401.5 602.9 618.7 635.1 642.4 646.6 537.6 627.8 646.5 673.6 677.1 6504.5 8418.9 8668.4 8823.3 9073.8 500.9 612.9 620.7 630.9 686.6 4082.7 4218.9 4490.0 4546.2 4553.0 69504.0 80659.8 97697.5 105440.5 113484.0 61238.7 81148.0 87759.0 87978.4 91929.6 71824.0 81722.0 84355.6 85398.6 85447.0 9607.8 9628.8 9716.6 9732.2 9734.4 40956.8 44625.3 50682.7 52499.7 52570.7 52791.9 55404.0 69922.7 81235.6 87886.0 13150.3 13198.3 13374.3 13502.4 13884.4 14379.9 14440.7 14463.9 14835.9 15453.2 10658.5 11779.9 11884.0 12939.4 13469.9 13372.9 13454.7 13692.7 14016.1 14044.5 11294.8 13035.9 13099.7 13173.0 13334.7 9868.7 10466.5 10578.3 10763.6 11301.7 11206.2 11313.2 11468.5 11472.9 11528.8 20:25:54 INFO - PROCESS | 1659 | -------- Summary: end -------- 20:25:54 INFO - PROCESS | 1659 | 20:25:54 INFO - TEST-INFO | 1659: exit 0 20:25:54 INFO - TEST-OK | dromaeo_css | took 1116928ms 20:25:54 INFO - TEST-START | kraken 20:25:54 INFO - operating with platform_type : mac_ 20:25:54 INFO - Initialising browser for kraken test... 20:25:54 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/tmpsZobrD/profile http://localhost:49272/getInfo.html) 20:25:57 INFO - PROCESS | 1667 | 2016-10-29 20:25:57.385 plugin-container[1668:10813] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883b, name = 'com.apple.tsm.portname' 20:25:57 INFO - PROCESS | 1667 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:25:57 INFO - PROCESS | 1667 | 2016-10-29 20:25:57.388 plugin-container[1668:10813] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0b, name = 'com.apple.CFPasteboardClient' 20:25:57 INFO - PROCESS | 1667 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:25:57 INFO - PROCESS | 1667 | 2016-10-29 20:25:57.388 plugin-container[1668:10813] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:25:57 INFO - PROCESS | 1667 | __metrics Screen width/height:1600/1200 20:25:57 INFO - PROCESS | 1667 | colorDepth:24 20:25:57 INFO - PROCESS | 1667 | Browser inner width/height: 1024/689 20:25:57 INFO - PROCESS | 1667 | __metrics 20:25:57 ERROR - PROCESS | 1667 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3131: TypeError: this.tabs is undefined 20:25:58 INFO - TEST-INFO | 1667: exit 0 20:25:58 INFO - Browser initialized. 20:25:58 INFO - Running cycle 1/1 for kraken test... 20:25:58 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/tmpsZobrD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:26:00 INFO - PROCESS | 1669 | 2016-10-29 20:26:00.727 plugin-container[1670:10930] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 20:26:00 INFO - PROCESS | 1669 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:26:00 INFO - PROCESS | 1669 | 2016-10-29 20:26:00.728 plugin-container[1670:10930] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0b, name = 'com.apple.CFPasteboardClient' 20:26:00 INFO - PROCESS | 1669 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:26:00 INFO - PROCESS | 1669 | 2016-10-29 20:26:00.728 plugin-container[1670:10930] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:26:10 INFO - PROCESS | 1669 | RSS: Main: 243130368 20:26:10 INFO - PROCESS | 1669 | 20:26:13 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection) 20:26:14 INFO - PROCESS | 1669 | RSS: Main: 253898752 20:26:14 INFO - PROCESS | 1669 | 20:26:17 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-dft) 20:26:17 INFO - PROCESS | 1669 | RSS: Main: 261541888 20:26:17 INFO - PROCESS | 1669 | 20:26:21 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-fft) 20:26:21 INFO - PROCESS | 1669 | RSS: Main: 262537216 20:26:21 INFO - PROCESS | 1669 | 20:26:23 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator) 20:26:24 INFO - PROCESS | 1669 | RSS: Main: 263282688 20:26:24 INFO - PROCESS | 1669 | 20:26:26 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:26:27 INFO - PROCESS | 1669 | RSS: Main: 263548928 20:26:27 INFO - PROCESS | 1669 | 20:26:30 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom) 20:26:30 INFO - PROCESS | 1669 | RSS: Main: 264916992 20:26:30 INFO - PROCESS | 1669 | 20:26:33 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate) 20:26:34 INFO - PROCESS | 1669 | RSS: Main: 265113600 20:26:34 INFO - PROCESS | 1669 | 20:26:37 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial) 20:26:37 INFO - PROCESS | 1669 | RSS: Main: 265166848 20:26:37 INFO - PROCESS | 1669 | 20:26:39 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:26:39 INFO - PROCESS | 1669 | RSS: Main: 264982528 20:26:39 INFO - PROCESS | 1669 | 20:26:41 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:26:42 INFO - PROCESS | 1669 | RSS: Main: 265072640 20:26:42 INFO - PROCESS | 1669 | 20:26:44 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:26:45 INFO - PROCESS | 1669 | RSS: Main: 265101312 20:26:45 INFO - PROCESS | 1669 | 20:26:48 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:26:49 INFO - PROCESS | 1669 | RSS: Main: 265695232 20:26:49 INFO - PROCESS | 1669 | 20:26:52 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:26:52 INFO - PROCESS | 1669 | RSS: Main: 265494528 20:26:52 INFO - PROCESS | 1669 | 20:26:54 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49272/tests/kraken/driver.html?testName=ai-astar) 20:26:54 INFO - PROCESS | 1669 | RSS: Main: 266936320 20:26:54 INFO - PROCESS | 1669 | 20:26:54 INFO - PROCESS | 1669 | __start_tp_report 20:26:54 INFO - PROCESS | 1669 | _x_x_mozilla_page_load 20:26:54 INFO - PROCESS | 1669 | _x_x_mozilla_page_load_details 20:26:54 INFO - PROCESS | 1669 | |i|pagename|runs| 20:26:54 INFO - PROCESS | 1669 | |0;ai-astar;107;122;169;173;123;116;117;119;118;117 20:26:54 INFO - PROCESS | 1669 | |1;audio-beat-detection;148;116;106;186;160;139;117;118;142;114 20:26:54 INFO - PROCESS | 1669 | |2;audio-dft;212;252;234;285;195;194;197;200;198;195 20:26:54 INFO - PROCESS | 1669 | |3;audio-fft;83;59;63;77;66;75;84;82;79;73 20:26:54 INFO - PROCESS | 1669 | |4;audio-oscillator;93;99;125;76;69;73;88;71;71;90 20:26:54 INFO - PROCESS | 1669 | |5;imaging-gaussian-blur;89;87;116;131;90;86;91;87;76;131 20:26:54 INFO - PROCESS | 1669 | |6;imaging-darkroom;95;107;96;101;154;119;95;97;108;97 20:26:54 INFO - PROCESS | 1669 | |7;imaging-desaturate;88;91;87;87;89;87;96;89;88;89 20:26:54 INFO - PROCESS | 1669 | |8;json-parse-financial;56;58;62;61;61;92;55;60;59;57 20:26:54 INFO - PROCESS | 1669 | |9;json-stringify-tinderbox;69;74;65;78;77;71;71;68;68;71 20:26:54 INFO - PROCESS | 1669 | |10;stanford-crypto-aes;92;91;94;111;111;88;90;90;91;90 20:26:54 INFO - PROCESS | 1669 | |11;stanford-crypto-ccm;151;167;165;161;140;149;150;152;201;147 20:26:54 INFO - PROCESS | 1669 | |12;stanford-crypto-pbkdf2;173;178;174;176;179;177;177;177;175;179 20:26:54 INFO - PROCESS | 1669 | |13;stanford-crypto-sha256-iterative;66;85;70;93;60;67;76;94;84;89 20:26:54 INFO - PROCESS | 1669 | __end_tp_report 20:26:54 INFO - PROCESS | 1669 | __start_cc_report 20:26:54 INFO - PROCESS | 1669 | _x_x_mozilla_cycle_collect,997 20:26:54 INFO - PROCESS | 1669 | __end_cc_report 20:26:54 INFO - PROCESS | 1669 | __startTimestamp1477798014890__endTimestamp 20:26:54 INFO - PROCESS | 1669 | 20:26:54 INFO - PROCESS | 1669 | ------- Summary: start ------- 20:26:54 INFO - PROCESS | 1669 | Number of tests: 14 20:26:54 INFO - PROCESS | 1669 | 20:26:54 INFO - PROCESS | 1669 | [#0] ai-astar Cycles:10 Average:128.10 Median:120.50 stddev:23.03 (19.1%) stddev-sans-first:23.13 20:26:54 INFO - PROCESS | 1669 | Values: 107.0 122.0 169.0 173.0 123.0 116.0 117.0 119.0 118.0 117.0 20:26:54 INFO - PROCESS | 1669 | 20:26:54 INFO - PROCESS | 1669 | [#1] audio-beat-detection Cycles:10 Average:134.60 Median:140.50 stddev:25.20 (17.9%) stddev-sans-first:26.26 20:26:54 INFO - PROCESS | 1669 | Values: 148.0 116.0 106.0 186.0 160.0 139.0 117.0 118.0 142.0 114.0 20:26:54 INFO - PROCESS | 1669 | 20:26:54 INFO - PROCESS | 1669 | [#2] audio-dft Cycles:10 Average:216.20 Median:206.00 stddev:31.10 (15.1%) stddev-sans-first:32.95 20:26:54 INFO - PROCESS | 1669 | Values: 212.0 252.0 234.0 285.0 195.0 194.0 197.0 200.0 198.0 195.0 20:26:54 INFO - PROCESS | 1669 | 20:26:54 INFO - PROCESS | 1669 | [#3] audio-fft Cycles:10 Average:74.10 Median:78.00 stddev:8.76 (11.2%) stddev-sans-first:8.68 20:26:54 INFO - PROCESS | 1669 | Values: 83.0 59.0 63.0 77.0 66.0 75.0 84.0 82.0 79.0 73.0 20:26:54 INFO - PROCESS | 1669 | 20:26:54 INFO - PROCESS | 1669 | [#4] audio-oscillator Cycles:10 Average:85.50 Median:82.00 stddev:17.53 (21.4%) stddev-sans-first:18.38 20:26:54 INFO - PROCESS | 1669 | Values: 93.0 99.0 125.0 76.0 69.0 73.0 88.0 71.0 71.0 90.0 20:26:54 INFO - PROCESS | 1669 | 20:26:54 INFO - PROCESS | 1669 | [#5] imaging-gaussian-blur Cycles:10 Average:98.40 Median:87.00 stddev:19.90 (22.9%) stddev-sans-first:20.82 20:26:54 INFO - PROCESS | 1669 | Values: 89.0 87.0 116.0 131.0 90.0 86.0 91.0 87.0 76.0 131.0 20:26:54 INFO - PROCESS | 1669 | 20:26:54 INFO - PROCESS | 1669 | [#6] imaging-darkroom Cycles:10 Average:106.90 Median:95.00 stddev:18.25 (19.2%) stddev-sans-first:18.85 20:26:54 INFO - PROCESS | 1669 | Values: 95.0 107.0 96.0 101.0 154.0 119.0 95.0 97.0 108.0 97.0 20:26:54 INFO - PROCESS | 1669 | 20:26:54 INFO - PROCESS | 1669 | [#7] imaging-desaturate Cycles:10 Average:89.10 Median:89.00 stddev:2.73 (3.1%) stddev-sans-first:2.86 20:26:54 INFO - PROCESS | 1669 | Values: 88.0 91.0 87.0 87.0 89.0 87.0 96.0 89.0 88.0 89.0 20:26:54 INFO - PROCESS | 1669 | 20:26:54 INFO - PROCESS | 1669 | [#8] json-parse-financial Cycles:10 Average:62.10 Median:60.50 stddev:10.75 (17.8%) stddev-sans-first:11.18 20:26:54 INFO - PROCESS | 1669 | Values: 56.0 58.0 62.0 61.0 61.0 92.0 55.0 60.0 59.0 57.0 20:26:54 INFO - PROCESS | 1669 | 20:26:54 INFO - PROCESS | 1669 | [#9] json-stringify-tinderbox Cycles:10 Average:71.20 Median:71.00 stddev:4.10 (5.8%) stddev-sans-first:4.28 20:26:54 INFO - PROCESS | 1669 | Values: 69.0 74.0 65.0 78.0 77.0 71.0 71.0 68.0 68.0 71.0 20:26:54 INFO - PROCESS | 1669 | 20:26:54 INFO - PROCESS | 1669 | [#10] stanford-crypto-aes Cycles:10 Average:94.80 Median:90.50 stddev:8.68 (9.6%) stddev-sans-first:9.14 20:26:54 INFO - PROCESS | 1669 | Values: 92.0 91.0 94.0 111.0 111.0 88.0 90.0 90.0 91.0 90.0 20:26:54 INFO - PROCESS | 1669 | 20:26:54 INFO - PROCESS | 1669 | [#11] stanford-crypto-ccm Cycles:10 Average:158.30 Median:156.50 stddev:17.20 (11.0%) stddev-sans-first:18.04 20:26:54 INFO - PROCESS | 1669 | Values: 151.0 167.0 165.0 161.0 140.0 149.0 150.0 152.0 201.0 147.0 20:26:54 INFO - PROCESS | 1669 | 20:26:54 INFO - PROCESS | 1669 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.50 Median:177.00 stddev:2.01 (1.1%) stddev-sans-first:1.69 20:26:54 INFO - PROCESS | 1669 | Values: 173.0 178.0 174.0 176.0 179.0 177.0 177.0 177.0 175.0 179.0 20:26:54 INFO - PROCESS | 1669 | 20:26:54 INFO - PROCESS | 1669 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:78.40 Median:84.50 stddev:12.21 (14.5%) stddev-sans-first:12.10 20:26:54 INFO - PROCESS | 1669 | Values: 66.0 85.0 70.0 93.0 60.0 67.0 76.0 94.0 84.0 89.0 20:26:54 INFO - PROCESS | 1669 | -------- Summary: end -------- 20:26:54 INFO - PROCESS | 1669 | 20:26:55 INFO - TEST-INFO | 1669: exit 0 20:26:55 INFO - TEST-OK | kraken | took 61044ms 20:26:55 INFO - SUITE-END | took 1177s 20:26:56 INFO - Completed test suite (00:19:38) 20:26:56 INFO - javascript benchmark 20:26:56 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7261.362464080199, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3341.7159541438978, 3355.909329467923, 3382.188274814045, 3404.6254911959663, 3411.266577685797, 5657.9717101414535, 5670.659760414378, 5671.546276297897, 5756.107803290486, 5796.681182534959, 5510.586705997046, 5631.746571404286, 5697.715114244282, 5744.195812586241, 5795.449432303935, 4247.426597409357, 5120.667156634825, 5216.321878155834, 5217.973910130463, 5262.210668399752, 4148.128892932487, 5068.366454193226, 5267.209918512234, 5326.094563924142, 5397.109476936321, 5005.824796132154, 5252.579793616521, 5274.287971123926, 5302.814401495948, 5311.415744268127, 3631.3282042821897, 3993.94009089861, 4589.586937175637, 5068.290439338495, 5161.793528258865, 4141.275276826544, 5119.948800511985, 5167.534921857051, 5239.947600523984, 5282.841514754564, 4891.828785992473, 5623.297087864017, 5651.830445086613, 5749.0514065179195, 5778.451047150514, 5014.89970200594, 6143.358355906958, 6200.720967556471, 6248.750049997994, 6275.560710750249, 6184.165137706397, 6225.813225603194, 6238.189035425411, 6258.780942666985, 6287.276963149251, 5756.798512052058, 5798.159266906275, 5919.49684276837, 5967.7911273105665, 5997.610155339889, 5655.462731040545, 5941.22764040672, 6194.81415557534, 6214.378562143749, 6215.285242197205, 6182.6599537025895, 6194.0, 6201.224846894138, 6205.5345849060595, 6212.596181248205, 6128.142060111588, 6217.098520714471, 6257.374262573797, 6260.405261500241, 6277.466415354746, 5402.270693456333, 6041.214642096499, 6060.666663333559, 6113.510919126459, 6234.407731265523, 6115.1744514490865, 6160.568760186834, 6184.8763024739255, 6190.535709821781, 6195.101710251988, 4845.515448455197, 4933.851984440473, 4947.282644016597, 4959.652824302264, 4966.627502937295, 4393.209222339946, 4415.66882483815, 4422.800973956179, 4423.955760442354, 4490.57339552742, 5503.284573005563, 5933.3473317934995, 5974.731137098841, 5993.910091348634, 6020.969895150496, 6219.098230756515, 6224.284207316216, 6227.19046523949, 6259.311475737665, 6261.5616906816995, 5605.075162598126, 5704.229928959538, 5869.325027621878, 5885.0, 5974.3726908674835, 4830.734309612934, 5211.931554031415, 5429.538489228381, 5484.725763711798, 5495.945040549624, 6261.24865016201, 6281.528885333618, 6299.464545513591, 6321.241451025906, 6335.0, 6143.665407145256, 6166.260048794174, 6185.41238582334, 6255.46828519581, 6258.280297765633, 6166.105914642262, 6277.1525844010475, 6303.558750887394, 6303.802277567248, 6330.620162790247, 6063.605865618721, 6097.5246788036175, 6157.476614487639, 6200.627962322275, 6227.283862355705, 4918.9670169264855, 5747.827565173052, 6113.141862489121, 6274.1843560336865, 6294.0, 5672.432756724294, 5889.116632504987, 6009.849753756191, 6065.5754097212775, 6154.5384096192065, 5155.639105262596, 6108.969455152874, 6110.236220472441, 6144.126041557368, 6148.50811935053, 4112.444819949388, 5975.133605627247, 6069.9089513656445, 6082.239720034996, 6108.6945652716295, 5999.91000135007, 6079.635221886701, 6098.481629061402, 6116.449519543263, 6148.200733904742, 5965.254343207099, 5972.880542389041, 6032.819015429544, 6064.969675151596, 6077.544184186116], "value": 5617.51704062549, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [472.11242863416794, 498.0835263115867, 510.8186593759213, 527.3645257464756, 529.7457220534144, 33795.83102084489, 41574.16851662969, 41704.165916681646, 44193.11613767723, 44289.33565996504, 42569.14861702772, 43070.569294307046, 43097.56902430975, 43128.568714312845, 43170.35244471327, 9566.904330956688, 9685.661001864935, 9756.317057805956, 9761.951190244074, 9766.804663906718, 8427.662893484254, 9572.234221262283, 9690.370125941827, 9691.563879625433, 9710.66012689556, 9566.186874115709, 9709.854352184722, 9710.805783884318, 9711.320207585472, 9711.805763884719, 9631.903680963205, 9712.077352651522, 9732.172765314957, 9737.367071140354, 9739.561719722558, 9566.426014439156, 9621.422714637145, 9645.37305075168, 9649.276304277137, 9649.421034737938, 39851.80444586667, 40114.39828402576, 40177.598224017675, 40213.798931005156, 40241.0, 3577.409727394977, 3695.4456831475227, 3782.678472329855, 4059.6346328830546, 4184.811683474251, 25542.616860747105, 29660.851695741385, 30645.387092258254, 30723.38553228923, 31312.21719457009, 2637.353848307158, 3276.2464633134273, 3316.137804170909, 3345.6654334566592, 3348.5814273215847, 437289.0, 438924.0, 439841.0, 443820.0, 444062.0, 57061.14408283879, 57586.42413575894, 57607.0, 57620.71189644025, 57717.13424298639, 58854.0, 59353.40646593565, 59683.40316596865, 59792.70103649454, 60886.0, 15454.690906181813, 15702.9214853925, 15716.764248536281, 15758.921205394128, 15764.527064188092, 12218.511259549696, 12264.0, 12320.0, 12337.198082124813, 12365.690857728629, 12327.6918077047, 15074.54776356711, 15082.849171508364, 15421.537353879401, 15429.845701543065, 3203.4874420092674, 6704.530682852153, 6908.792736217922, 6914.481413893978, 6968.860622787516, 910.8815853939078, 987.1244635193048, 989.4162444157837, 1011.8836333821557, 1028.3675539543126, 27926.441471170463, 29858.954936577116, 29900.85049574738, 30384.84807575948, 30842.84578577093, 957.3203025851724, 995.5520015992832, 1121.9439028048564, 1125.4485302202027, 1128.2793739547092, 14295.714085718435, 15756.369745210293, 15829.525114246591, 15833.287502062434, 16105.355785768437, 631394.0, 631475.0, 631890.0, 639197.0, 639938.4040037383, 49285.0, 49728.52443280122, 58224.0, 58869.52826179294, 59545.702271488364, 1351.7161396106928, 1406.8171137752026, 1421.0549984260351, 1422.9857701423061, 1428.8714015738633, 27829.721702782714, 28500.714992849807, 29932.857050009436, 30382.544261835646, 30897.536536951513, 50441.74779126081, 50719.0, 51033.72415689489, 51080.0, 51167.488325116276, 573578.0, 575870.0, 577871.0, 578038.1098094482, 578077.0, 26201.73798261993, 26391.472170556866, 26639.067632632767, 27212.91148354123, 27945.021924232555, 47510.52489475061, 47691.284630729875, 47884.28173577329, 48009.7997550064, 48976.26535601897, 38863.41704874486, 38938.02654933649, 39589.208215834944, 39839.0, 40504.0, 38762.41856372213, 38971.0, 38974.220515590096, 40261.59738402579, 40400.39399408952, 38152.0, 38780.80609596934, 38792.22415551617, 39026.02434939149, 39183.0, 28160.43679126365, 31232.531512026882, 34305.59860809719, 34371.14072148116, 34791.4781278276, 18087.909560452114, 21680.67478987785, 24144.758552414252, 25743.871280643478, 26136.477270454103], "value": 19628.847629386844, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [312.4797212640953, 364.1678764024205, 364.6645086520402, 376.6929952089049, 396.17397725741824, 16730.498085057454, 19338.709919351204, 19792.802071979277, 20227.089780959835, 20501.589968200627, 20240.89879550604, 20540.794592054073, 20913.16347346108, 20959.580808383824, 20983.0, 5456.317960254968, 5614.848955964038, 5784.68184249866, 5793.913091303634, 6098.39016098391, 5910.261217347832, 6001.66990815505, 6037.426444487766, 6074.757009719606, 6099.115628233903, 5606.383297837255, 5833.8833223335505, 5966.522678185756, 5997.760089596411, 6019.849503762419, 4660.580547750702, 5027.974860125694, 5280.894382112356, 5925.40745925404, 5979.192808970777, 4360.171567402183, 5103.132467480537, 5155.046316431435, 5171.715555644438, 5177.456367081478, 25307.0, 25397.746022539723, 25550.87224563884, 25631.359216019562, 25809.74190258111, 2178.6187417202086, 2312.7571604981404, 2344.3787396339985, 2393.6050551658955, 2438.682971213731, 14698.33857476416, 15259.61850953724, 15285.923570382078, 15560.144192069432, 16092.83907160925, 1875.9155837987187, 1891.1300801631266, 1905.6284024615206, 1946.707993800927, 1956.7358406615067, 118196.0, 122569.0, 122647.0, 122816.38591807074, 125868.99999999908, 19404.611907761766, 20773.584528309348, 20828.0, 21283.680744788842, 21513.56972860534, 34985.1253718654, 35236.47145292823, 35317.47023794645, 35332.64667353345, 35381.46927796085, 10165.4917254137, 10335.328203666737, 10411.895881041093, 10497.9475102624, 10615.84076238857, 7324.6337683115635, 8288.336933045342, 8366.958165209135, 8549.401541892008, 8750.249950010024, 9712.368696034735, 9781.657641982496, 9831.655892043744, 9955.153811925922, 9970.102690757867, 3805.3911374179997, 3885.3977633466857, 3896.103896103912, 3914.5693973662874, 3966.2662407454714, 851.9872201916976, 936.0265324063034, 955.2214944819949, 960.8894977077725, 963.3834346018554, 15942.68114637724, 21481.67777483339, 21666.674999875013, 21770.89114554417, 21794.455138621342, 879.872418499314, 893.25413279912, 992.5682328187262, 994.1251698505414, 1034.1468288661883, 11163.888361116447, 11418.257813242113, 11514.424278786195, 11566.595169169039, 11608.76782464346, 561053.0, 564906.0, 575059.0, 589368.0, 589424.0, 127999.36000320125, 135310.3234483821, 140421.4464088507, 140880.0, 144121.0, 963.3401120232663, 998.4708104704519, 1017.3539802225492, 1037.3775734559204, 1040.308195050283, 15188.696226075195, 18968.905155474135, 19076.950767707352, 19108.140133693738, 19252.8074719251, 36732.0, 41056.58943410528, 43655.56344436515, 49076.01847962949, 53633.19550206828, 66956.0, 81143.5942820282, 84421.0, 85844.0, 87049.56475217584, 6247.406496382836, 6882.4149947253045, 7316.0489136411925, 7380.335769780745, 7532.623368831427, 8200.835983280182, 10561.260711750103, 12912.548060817826, 13293.867061329263, 13356.599302020955, 8774.868376974478, 9036.412633178823, 11267.549298028314, 11397.316161030365, 11415.258008229439, 10159.0, 10285.691429257135, 10775.245732798629, 11214.402389741615, 11231.55073797039, 9526.90473095288, 9599.952000239955, 11645.88354116448, 11824.23142495735, 11853.288802671867, 6911.5853048817235, 7121.46589005837, 7205.099362579677, 7389.593572353572, 7484.663190156347, 9781.902180978099, 11202.047825934887, 11437.771244575228, 11458.4270786462, 11485.770284594428], "value": 10372.414601862107, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1405.45187376923, 1526.0462211118054, 1730.2992288123307, 1859.832615064644, 1876.8592355573335, 7520.661570229339, 7740.109887362941, 8072.475289106204, 8416.116307787686, 8451.070382257954, 7934.404919631022, 8165.755027349189, 8194.426390152677, 8310.0, 8336.16638336166, 2020.9898950505226, 2250.9437264068365, 2296.1504243429954, 2303.8272129590264, 2311.468362276669, 2282.109977108929, 2307.157887371106, 2318.3972167236557, 2337.7194736631627, 2348.1194552042984, 2273.9886300568555, 2285.531466049456, 2301.4361481436986, 2305.907763689459, 2311.4683622766775, 2223.4552534628956, 2245.9326220213256, 2254.785795349439, 2261.106862789189, 2263.841531092824, 2244.3603572981783, 2305.976940230593, 2312.8034117099896, 2313.97686023141, 2314.525522267931, 8299.377546684023, 8331.583420828934, 8336.83326333476, 8381.03618083922, 8382.87425688615, 5004.499550045003, 5173.198154286091, 5176.55999240065, 5178.792848286064, 5195.870103247411, 3271.8854840080485, 3373.241020770307, 3612.0, 3629.818509074562, 3634.7637403568683, 1007.7883644434678, 1017.959281628734, 1048.5176818663347, 1049.1292227451197, 1062.8777690565607, 10721.678349649523, 10736.677899663022, 10748.946255268673, 10811.405372704497, 10849.132069434505, 4412.117576484716, 4486.147631949919, 4661.114388266286, 4678.0, 4680.485146633868, 9236.953815230881, 9312.068793120634, 9313.860292095624, 9321.207697345802, 9330.440173589606, 5147.40804807452, 5168.819091331786, 5179.04187725272, 5182.922256166161, 5195.766190521435, 4088.529819070845, 4645.953540464552, 4693.741844198602, 4738.47876733559, 4755.738434386142, 3686.6866316363407, 3703.4815125882396, 3767.4348847672522, 3806.2768074066394, 3821.923561528754, 1240.621610408834, 1245.9937700311623, 1250.7060840702427, 1262.0, 1270.003047607642, 757.4054367321626, 813.6826637611337, 923.1829830599834, 953.7043516509765, 957.5690939077303, 5022.347094877642, 5418.428655689884, 5526.668399896019, 5656.830295091154, 5796.304443466726, 420.573118284941, 427.88233235859764, 447.42729306487746, 451.7447642082213, 452.3260342090262, 3333.749968752353, 3421.9391988483153, 3745.6890088469363, 3747.981260093682, 3866.7679939203736, 8251.752447426587, 9878.851817222747, 10012.299139060342, 10015.148712359385, 10034.899651003543, 9921.40471571708, 9970.601175953027, 9973.10242078202, 10003.699889003341, 10013.29906906524, 6874.621895795779, 7681.0, 7791.10402303731, 7828.295453409107, 7893.0, 4409.4488188976375, 4513.277875539898, 5254.474552544715, 5445.183222516495, 5453.209284653623, 8296.668133274601, 8984.595693193953, 9077.818443630958, 9224.723258302261, 9263.980962094029, 9237.907620923705, 9273.25813934897, 9304.95347523258, 9311.534423278674, 9382.48396338208, 6517.967410162919, 6744.156980377453, 6891.517593768388, 7012.929870701228, 7109.360157585842, 7790.2209779023915, 7981.161977992228, 7997.560134192442, 8009.559474228973, 8023.358131349595, 7000.7549735759, 7095.432365410651, 7105.609191494517, 7149.213586505584, 7235.167955684953, 5684.971575142098, 6754.257031726408, 6942.340477654615, 6990.685419156252, 6998.895016574654, 6358.427741503287, 6528.738850446119, 6628.138342015507, 6632.0, 6644.302348253557, 4795.928061079157, 4818.469968303554, 4839.249916262985, 4887.413510378635, 4914.92627610579, 4023.7384570002855, 4764.809407623657, 4857.368542089647, 4927.975360123177, 5047.167217409086], "value": 4271.82567018903, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [298.4851131797648, 302.31073153210673, 309.5156080733651, 312.99217519562023, 320.30813442963205, 5071.79712811488, 5248.895022099556, 5249.4750524947585, 5473.534749546288, 5519.6412233204865, 4513.842015529457, 4920.606351491881, 5298.947010529893, 5357.223202635625, 5359.356877174735, 3921.450996860442, 3962.286788378091, 4085.6507508182767, 4143.544210136897, 4147.854825081123, 3667.8166091695443, 3940.7241493095494, 4022.8879838084945, 4187.623113919746, 4194.412782210493, 4101.5488296287385, 4120.629143377096, 4128.442660240859, 4138.730982486129, 4139.3997870308785, 3884.882025053424, 4041.2725709372303, 4067.4712287402594, 4158.6465150462245, 4161.771102589357, 2755.476459472694, 3323.933521329584, 3625.546806649169, 3641.927161456756, 3649.927001459956, 4990.176620857555, 5210.296609957645, 5219.765110569995, 5238.423773384925, 5274.472552744734, 4232.492100947906, 4749.0, 4752.904941901177, 4776.761161941889, 4809.5430934061205, 5605.887882242332, 5708.974243650871, 5728.79949201776, 5745.913811292834, 5770.653760774367, 3587.7980876406436, 3731.3470142724914, 3817.4273858921106, 3919.6668283195963, 4008.198360327946, 5046.6719663221775, 5449.891002179934, 5794.478496935254, 5980.043193089128, 6217.782377616762, 3388.542546756206, 3400.523926650271, 3674.9816250918575, 3972.9868883434538, 4111.691623128278, 3451.758376913642, 3791.7345785795283, 3968.66266367362, 4120.134771697977, 4152.771597562102, 3954.6440820326306, 4383.60547550722, 4737.597304229179, 4748.525147485224, 4786.832460863922, 5077.136886729265, 5116.032701033391, 5314.70769107695, 5747.568932330092, 5756.539476841843, 1316.3220941215282, 1360.0615575253044, 1381.7720076187518, 1397.475946520055, 1397.8392484864166, 920.0155833258483, 942.1755963531909, 950.8193443245762, 964.2237998411206, 964.7925695975399, 4485.147821913826, 5295.484644793769, 5297.708626025528, 5300.725777850986, 5384.772953231694, 5784.975258659436, 5789.565782566325, 5798.536117110621, 5845.444682755132, 5852.882942341214, 4983.975080124576, 5262.657927234717, 5266.78932842682, 5278.551323137576, 5314.97342513285, 2787.1220565521794, 2822.7600653944232, 3076.10819346105, 3105.223694076481, 3112.5331200320134, 5383.515483606494, 5763.567732420085, 6093.308152722468, 6102.481289090477, 6136.23297087864, 5449.313590312328, 5714.571407144481, 6030.969845150747, 6049.969750151309, 6063.575549711478, 6057.60625559346, 6072.392760723892, 6089.908651370233, 6106.450419462359, 6112.572119951561, 5409.756560954688, 5415.160650099241, 5604.458773837065, 5838.970805145947, 5906.544671367298, 4641.651876109373, 5616.66300022, 5817.272840894888, 5863.824085277449, 5906.232189815297, 6132.41742034506, 6134.607503073716, 6135.53983451234, 6148.938510614837, 6181.505479561714, 3605.8197090144863, 3884.4367566703236, 3896.6103389660807, 3909.139989202371, 3920.294347017564, 5601.971990140023, 5881.9705901470215, 5896.380880007709, 5901.675407852437, 5908.759160576155, 5431.972840135774, 5654.971725141348, 5662.716864156858, 5665.178549110438, 5668.149777533403, 5685.744141513559, 5688.862227554423, 5688.943110568841, 5703.20155178267, 5708.885822283614, 4807.471178170328, 5264.368275806928, 5325.813596524103, 5504.256925315191, 5579.888402231852, 4988.8752781180765, 5036.093503169464, 5523.585731070106, 5676.0, 5715.371309155907, 4203.747775133379, 4402.578581779944, 5321.829197576527, 5580.972095139498, 5590.217369568345], "value": 4167.941875425708, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [728.0644371982004, 777.0287141073658, 796.2594786848229, 799.9800004999876, 834.4534330013839, 15015.849841501595, 15564.922175389107, 15595.922020389911, 15639.06165630063, 15664.765028524582, 12713.23720576766, 12732.236065836056, 12743.936280318585, 12745.808812867814, 12755.553555625555, 868.5049521772593, 890.9420887642283, 892.7054072156172, 893.4148132972913, 893.5889490834224, 876.6011464783509, 892.9107089291051, 893.2942975049702, 894.7807787092172, 898.716904175186, 940.92472602192, 942.3309450290302, 946.6024269806663, 948.340903072365, 956.7321150077954, 932.9626814927394, 937.6577549194495, 938.0338251600862, 938.31503002808, 939.6006697153716, 601.5909181756404, 690.2717632897292, 697.0102454514602, 705.7318219076769, 706.5266271598042, 12584.307863067528, 12887.548935787296, 12913.289769062698, 12930.224186548838, 13565.796513052312, 11783.234089784137, 11786.410679465993, 11793.174477786559, 11862.406879655982, 11907.345096019715, 10315.84526232107, 10404.479776011245, 10407.843882341771, 10432.426216558086, 10768.084712799422, 3549.396602577568, 3586.4799604057266, 3592.964070359289, 3660.6705396514176, 3756.7370284079852, 92591.07408925892, 94393.05606943912, 96329.51835240779, 97029.0, 97975.0, 8931.374803763803, 9005.324600654978, 9060.81878362429, 9087.591058402388, 9228.169464748205, 18896.43310700654, 18996.0, 19037.048147592566, 19052.04693272545, 19054.61890762177, 7641.808954776175, 7642.656080476392, 7752.255742457902, 7784.844303113906, 7909.0, 3926.6662333701383, 4215.515215750228, 4244.957550424456, 4367.803448844809, 4385.320275357325, 3273.688999545087, 3384.8984530464127, 3386.0180547641403, 3394.5077963695126, 3401.523786669868, 602.9125776762345, 618.6937465954381, 635.1267007864187, 642.3640595810115, 646.5829539946708, 537.6048604275854, 627.7520379450092, 646.5280345347828, 673.5756473421811, 677.1298880937971, 6504.5446818723185, 8418.86345343383, 8668.43655162425, 8823.294136469196, 9073.81852362949, 500.8873003574167, 612.8682333298273, 620.6965372717258, 630.9274433440213, 686.6498085976089, 4082.6733861291027, 4218.8523401680795, 4489.9551004490195, 4546.204414227497, 4553.043860789205, 69504.0, 80659.79010314733, 97697.51151244198, 105440.47279763705, 113484.0, 61238.69380653157, 81148.0, 87759.0, 87978.40161987877, 91929.62105568421, 71824.0, 81722.0, 84355.5782221085, 85398.57300713456, 85447.0, 9607.759806004906, 9628.807423851344, 9716.562754675915, 9732.221422286058, 9734.36726612768, 40956.79521602373, 44625.33062004008, 50682.74658626683, 52499.73750131225, 52570.73714631402, 52791.944161115796, 55404.0, 69922.65038674774, 81235.59382203051, 87886.0, 13150.342482876009, 13198.313582043467, 13374.264415457243, 13502.392392342172, 13884.444622214998, 14379.92810035985, 14440.711185776016, 14463.927680361952, 14835.851641483447, 15453.227338633249, 10658.520366583367, 11779.941100294787, 11883.989860861579, 12939.353032348534, 13469.865301347254, 13372.93313533426, 13454.730905382034, 13692.65768355799, 14016.088954217943, 14044.508442204473, 11294.830577541179, 13035.869641303845, 13099.738005240033, 13173.0, 13334.666633334244, 9868.667729856377, 10466.529006194893, 10578.312409693346, 10763.623273185402, 11301.660950171508, 11206.207919662267, 11313.151513636345, 11468.483918223534, 11472.88527114718, 11528.827067593826], "value": 7197.900039790606, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1574.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 122.0, 169.0, 173.0, 123.0, 116.0, 117.0, 119.0, 118.0, 117.0], "value": 128.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 116.0, 106.0, 186.0, 160.0, 139.0, 117.0, 118.0, 142.0, 114.0], "value": 134.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [212.0, 252.0, 234.0, 285.0, 195.0, 194.0, 197.0, 200.0, 198.0, 195.0], "value": 216.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 59.0, 63.0, 77.0, 66.0, 75.0, 84.0, 82.0, 79.0, 73.0], "value": 74.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 99.0, 125.0, 76.0, 69.0, 73.0, 88.0, 71.0, 71.0, 90.0], "value": 85.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 116.0, 131.0, 90.0, 86.0, 91.0, 87.0, 76.0, 131.0], "value": 98.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 107.0, 96.0, 101.0, 154.0, 119.0, 95.0, 97.0, 108.0, 97.0], "value": 106.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 91.0, 87.0, 87.0, 89.0, 87.0, 96.0, 89.0, 88.0, 89.0], "value": 89.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 58.0, 62.0, 61.0, 61.0, 92.0, 55.0, 60.0, 59.0, 57.0], "value": 62.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 74.0, 65.0, 78.0, 77.0, 71.0, 71.0, 68.0, 68.0, 71.0], "value": 71.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 91.0, 94.0, 111.0, 111.0, 88.0, 90.0, 90.0, 91.0, 90.0], "value": 94.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 167.0, 165.0, 161.0, 140.0, 149.0, 150.0, 152.0, 201.0, 147.0], "value": 158.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 178.0, 174.0, 176.0, 179.0, 177.0, 177.0, 177.0, 175.0, 179.0], "value": 176.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 85.0, 70.0, 93.0, 60.0, 67.0, 76.0, 94.0, 84.0, 89.0], "value": 78.4, "unit": "score"}]}]} 20:26:56 INFO - Return code: 0 20:26:56 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:26:56 ERROR - # TBPL SUCCESS # 20:26:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:26:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:26:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:26:56 INFO - 2016-10-29 20:26:56.209 screenresolution[1674:11316] starting screenresolution argv=screenresolution get 20:26:56 INFO - 2016-10-29 20:26:56.211 screenresolution[1674:11316] Display 0: 1600x1200x32@60 20:26:56 INFO - 2016-10-29 20:26:56.217 screenresolution[1675:11319] starting screenresolution argv=screenresolution list 20:26:56 INFO - Available Modes on Display 0 20:26:56 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:26:56 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:26:56 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:26:56 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:26:56 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:26:56 INFO - Intel Iris: 20:26:56 INFO - Chipset Model: Intel Iris 20:26:56 INFO - Type: GPU 20:26:56 INFO - Bus: Built-In 20:26:56 INFO - VRAM (Dynamic, Max): 1536 MB 20:26:56 INFO - Vendor: Intel (0x8086) 20:26:56 INFO - Device ID: 0x0a2e 20:26:56 INFO - Revision ID: 0x0009 20:26:56 INFO - Displays: 20:26:56 INFO - Display: 20:26:56 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:26:56 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:26:56 INFO - Display Serial Number: 1600x1200 60 20:26:56 INFO - Main Display: Yes 20:26:56 INFO - Mirror: Off 20:26:56 INFO - Online: Yes 20:26:56 INFO - Rotation: Supported 20:26:56 INFO - Return code: 0 20:26:56 INFO - Running post-action listener: _resource_record_post_action 20:26:56 INFO - [mozharness: 2016-10-30 03:26:56.459558Z] Finished run-tests step (success) 20:26:56 INFO - Running post-run listener: _resource_record_post_run 20:26:56 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 33255936; Write bytes: 713571840; Read time: 204; Write time: 2065 20:26:56 INFO - TinderboxPrint: CPU usage
54.4% 20:26:56 INFO - TinderboxPrint: I/O read bytes / time
33,255,936 / 204 20:26:56 INFO - TinderboxPrint: I/O write bytes / time
713,571,840 / 2,065 20:26:56 INFO - TinderboxPrint: CPU idle
2,211.7 (45.6%) 20:26:56 INFO - TinderboxPrint: CPU system
980.0 (20.2%) 20:26:56 INFO - TinderboxPrint: CPU user
1,662.2 (34.2%) 20:26:56 INFO - TinderboxPrint: Swap in / out
436,817,920 / 0 20:26:56 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 391492096; Write bytes: 387972608; Read time: 29654; Write time: 845 20:26:56 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 32330240; Write bytes: 302698496; Read time: 192; Write time: 1163 20:26:56 INFO - Running post-run listener: _upload_blobber_files 20:26:56 INFO - Blob upload gear active. 20:26:56 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:26:56 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 20:26:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Larch', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 20:26:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Larch -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 20:26:57 INFO - (blobuploader) - INFO - Open directory for files ... 20:26:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 20:26:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:26:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:26:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 20:26:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:26:58 INFO - (blobuploader) - INFO - Done attempting. 20:26:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 20:26:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:26:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:26:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 20:26:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:26:59 INFO - (blobuploader) - INFO - Done attempting. 20:26:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:26:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:26:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:27:01 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:27:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:27:01 INFO - (blobuploader) - INFO - Done attempting. 20:27:01 INFO - (blobuploader) - INFO - Iteration through files over. 20:27:01 INFO - Return code: 0 20:27:01 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:27:01 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:27:01 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/1d0bdd0c4a9dc33219fd9b902fe783dcd069febf6581d8efcf17e04238b59ec6ba5cd56f1ebb5ae0a98f1ffc1b8df5a170f9f6f4a327da22a009b1fb9cd2240b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/6d189d1b2afe15fdbdc9fc1d00a4571f58368440e0e520ea699ae2c4d5182a3ed99d2e1348d9bcfd9dd9d09e3881ff95bf2296a25ac941aeebb009471dc67e60", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/29a2dd3e1597d4da4d6e58d34ec1153872b9efbeb629757d41360d0f1ee3e4ec2156b52c8ccc27a0752d70b1ac3a111888bd6876bdc3f61aaac060569bb37377"} 20:27:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:27:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:27:01 INFO - Contents: 20:27:01 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/1d0bdd0c4a9dc33219fd9b902fe783dcd069febf6581d8efcf17e04238b59ec6ba5cd56f1ebb5ae0a98f1ffc1b8df5a170f9f6f4a327da22a009b1fb9cd2240b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/6d189d1b2afe15fdbdc9fc1d00a4571f58368440e0e520ea699ae2c4d5182a3ed99d2e1348d9bcfd9dd9d09e3881ff95bf2296a25ac941aeebb009471dc67e60", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/29a2dd3e1597d4da4d6e58d34ec1153872b9efbeb629757d41360d0f1ee3e4ec2156b52c8ccc27a0752d70b1ac3a111888bd6876bdc3f61aaac060569bb37377"} 20:27:01 INFO - Running post-run listener: copy_logs_to_upload_dir 20:27:01 INFO - Copying logs to upload dir... 20:27:01 INFO - mkdir: /builds/slave/test/build/upload/logs 20:27:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.835162 ========= master_lag: 2.38 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-10-29 20:27:03.925494) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-29 20:27:03.928755) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.hHFnurEEs6/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test/properties RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.6eju0Pengj/Listeners TMPDIR=/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/1d0bdd0c4a9dc33219fd9b902fe783dcd069febf6581d8efcf17e04238b59ec6ba5cd56f1ebb5ae0a98f1ffc1b8df5a170f9f6f4a327da22a009b1fb9cd2240b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/6d189d1b2afe15fdbdc9fc1d00a4571f58368440e0e520ea699ae2c4d5182a3ed99d2e1348d9bcfd9dd9d09e3881ff95bf2296a25ac941aeebb009471dc67e60", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/29a2dd3e1597d4da4d6e58d34ec1153872b9efbeb629757d41360d0f1ee3e4ec2156b52c8ccc27a0752d70b1ac3a111888bd6876bdc3f61aaac060569bb37377"} build_url:https://queue.taskcluster.net/v1/task/SBKPH6Y5Qrar0ULLW1T99g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011118 build_url: 'https://queue.taskcluster.net/v1/task/SBKPH6Y5Qrar0ULLW1T99g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/1d0bdd0c4a9dc33219fd9b902fe783dcd069febf6581d8efcf17e04238b59ec6ba5cd56f1ebb5ae0a98f1ffc1b8df5a170f9f6f4a327da22a009b1fb9cd2240b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/6d189d1b2afe15fdbdc9fc1d00a4571f58368440e0e520ea699ae2c4d5182a3ed99d2e1348d9bcfd9dd9d09e3881ff95bf2296a25ac941aeebb009471dc67e60", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/29a2dd3e1597d4da4d6e58d34ec1153872b9efbeb629757d41360d0f1ee3e4ec2156b52c8ccc27a0752d70b1ac3a111888bd6876bdc3f61aaac060569bb37377"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-29 20:27:03.966951) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 3 secs) (at 2016-10-29 20:27:03.967238) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.hHFnurEEs6/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.6eju0Pengj/Listeners TMPDIR=/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005295 ========= master_lag: 3.66 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 3 secs) (at 2016-10-29 20:27:07.629477) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-29 20:27:07.630194) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-29 20:27:08.021852) ========= ========= Total master_lag: 7.33 =========