builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0258 starttime: 1473243604.59 results: success (0) buildid: 20160907012216 builduid: 80ac442189174fe1b9ec5235165b7ffa revision: 541c9086c0f27fba60beecc9bc94543103895c86 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-07 03:20:04.591733) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-07 03:20:04.592141) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-07 03:20:04.592424) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-07 03:20:04.654272) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 03:20:04.654675) ========= 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.Hv4QVEOsYm/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.MIfKZSQ6J7/Listeners TMPDIR=/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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-09-07 03:20:04-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.9M=0.001s 2016-09-07 03:20:05 (18.9 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.500186 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 03:20:05.180117) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 03:20:05.180409) ========= 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.Hv4QVEOsYm/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.MIfKZSQ6J7/Listeners TMPDIR=/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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.025234 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 03:20:05.229614) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 03:20:05.233093) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 541c9086c0f27fba60beecc9bc94543103895c86 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 541c9086c0f27fba60beecc9bc94543103895c86 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Hv4QVEOsYm/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.MIfKZSQ6J7/Listeners TMPDIR=/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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-09-07 03:20:05,316 truncating revision to first 12 chars 2016-09-07 03:20:05,317 Setting DEBUG logging. 2016-09-07 03:20:05,317 attempt 1/10 2016-09-07 03:20:05,317 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/541c9086c0f2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-07 03:20:05,993 unpacking tar archive at: fx-team-541c9086c0f2/testing/mozharness/ program finished with exit code 0 elapsedTime=0.946964 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 03:20:06.211523) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-07 03:20:06.211966) ========= script_repo_revision: 541c9086c0f27fba60beecc9bc94543103895c86 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-07 03:20:06.212470) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-07 03:20:06.212882) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-07 03:20:06.266005) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-09-07 03:20:06.266406) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Hv4QVEOsYm/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.MIfKZSQ6J7/Listeners TMPDIR=/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 03:20:06 INFO - MultiFileLogger online at 20160907 03:20:06 in /builds/slave/test 03:20:06 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 03:20:06 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:20:06 INFO - {'append_to_log': False, 03:20:06 INFO - 'base_work_dir': '/builds/slave/test', 03:20:06 INFO - 'blob_upload_branch': 'Fx-Team', 03:20:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:20:06 INFO - 'branch': 'Fx-Team', 03:20:06 INFO - 'buildbot_json_path': 'buildprops.json', 03:20:06 INFO - 'config_files': ('talos/mac_config.py',), 03:20:06 INFO - 'default_actions': ('clobber', 03:20:06 INFO - 'read-buildbot-config', 03:20:06 INFO - 'download-and-extract', 03:20:06 INFO - 'populate-webroot', 03:20:06 INFO - 'create-virtualenv', 03:20:06 INFO - 'install', 03:20:06 INFO - 'run-tests'), 03:20:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:20:06 INFO - 'download_minidump_stackwalk': True, 03:20:06 INFO - 'download_symbols': 'ondemand', 03:20:06 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:20:06 INFO - 'tooltool.py': '/tools/tooltool.py', 03:20:06 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:20:06 INFO - '/tools/misc-python/virtualenv.py')}, 03:20:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:20:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:20:06 INFO - 'installer_path': 'installer.exe', 03:20:06 INFO - 'log_level': 'info', 03:20:06 INFO - 'log_name': 'talos', 03:20:06 INFO - 'log_to_console': True, 03:20:06 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:20:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:20:06 INFO - 'opt_config_files': (), 03:20:06 INFO - 'pip_index': False, 03:20:06 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:20:06 INFO - 'cmd': ('bash', 03:20:06 INFO - '-c', 03:20:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:20:06 INFO - 'enabled': True, 03:20:06 INFO - 'halt_on_failure': False, 03:20:06 INFO - 'name': 'check_screen_resolution'},), 03:20:06 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:20:06 INFO - 'cmd': ('bash', 03:20:06 INFO - '-c', 03:20:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:20:06 INFO - 'enabled': True, 03:20:06 INFO - 'halt_on_failure': False, 03:20:06 INFO - 'name': 'check_screen_resolution'},), 03:20:06 INFO - 'run_cmd_checks_enabled': True, 03:20:06 INFO - 'sps_profile': False, 03:20:06 INFO - 'sps_profile_interval': 0, 03:20:06 INFO - 'suite': 'dromaeojs-e10s', 03:20:06 INFO - 'system_bits': '32', 03:20:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:20:06 INFO - 'title': 't-yosemite-r7-0258', 03:20:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:20:06 INFO - 'use_talos_json': True, 03:20:06 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:20:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:20:06 INFO - 'work_dir': 'build'} 03:20:06 INFO - [mozharness: 2016-09-07 10:20:06.469877Z] Running clobber step. 03:20:06 INFO - Running pre-action listener: _resource_record_pre_action 03:20:06 INFO - Running main action method: clobber 03:20:06 INFO - rmtree: /builds/slave/test/build 03:20:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:20:08 INFO - Running post-action listener: _resource_record_post_action 03:20:08 INFO - [mozharness: 2016-09-07 10:20:08.628053Z] Finished clobber step (success) 03:20:08 INFO - [mozharness: 2016-09-07 10:20:08.628232Z] Running read-buildbot-config step. 03:20:08 INFO - Running pre-action listener: _resource_record_pre_action 03:20:08 INFO - Running main action method: read_buildbot_config 03:20:08 INFO - Using buildbot properties: 03:20:08 INFO - { 03:20:08 INFO - "project": "", 03:20:08 INFO - "product": "firefox", 03:20:08 INFO - "script_repo_revision": "production", 03:20:08 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 03:20:08 INFO - "repository": "", 03:20:08 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 03:20:08 INFO - "buildid": "20160907012216", 03:20:08 INFO - "pgo_build": "False", 03:20:08 INFO - "basedir": "/builds/slave/test", 03:20:08 INFO - "buildnumber": 18, 03:20:08 INFO - "slavename": "t-yosemite-r7-0258", 03:20:08 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 03:20:08 INFO - "platform": "yosemite_r7", 03:20:08 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 03:20:08 INFO - "branch": "fx-team", 03:20:08 INFO - "repo_path": "integration/fx-team", 03:20:08 INFO - "revision": "541c9086c0f27fba60beecc9bc94543103895c86", 03:20:08 INFO - "stage_platform": "macosx64", 03:20:08 INFO - "builduid": "80ac442189174fe1b9ec5235165b7ffa", 03:20:08 INFO - "slavebuilddir": "test" 03:20:08 INFO - } 03:20:08 INFO - Found installer url https://queue.taskcluster.net/v1/task/asi2zzvQTCmeOzFSSitltQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 03:20:08 INFO - Running post-action listener: _resource_record_post_action 03:20:08 INFO - [mozharness: 2016-09-07 10:20:08.631636Z] Finished read-buildbot-config step (success) 03:20:08 INFO - [mozharness: 2016-09-07 10:20:08.631756Z] Running download-and-extract step. 03:20:08 INFO - Running pre-action listener: _resource_record_pre_action 03:20:08 INFO - Running main action method: download_and_extract 03:20:08 INFO - mkdir: /builds/slave/test/build/tests 03:20:08 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')]} 03:20:08 INFO - trying https://queue.taskcluster.net/v1/task/asi2zzvQTCmeOzFSSitltQ/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 03:20:08 INFO - Downloading https://queue.taskcluster.net/v1/task/asi2zzvQTCmeOzFSSitltQ/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 03:20:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/asi2zzvQTCmeOzFSSitltQ/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 03:20:10 INFO - Downloaded 1199 bytes. 03:20:10 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 03:20:10 INFO - Using the following test package requirements: 03:20:10 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 03:20:10 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 03:20:10 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 03:20:10 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 03:20:10 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 03:20:10 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 03:20:10 INFO - u'jsshell-mac.zip'], 03:20:10 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 03:20:10 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 03:20:10 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 03:20:10 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 03:20:10 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 03:20:10 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 03:20:10 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 03:20:10 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 03:20:10 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 03:20:10 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 03:20:10 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 03:20:10 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 03:20:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/asi2zzvQTCmeOzFSSitltQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 03:20:10 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 03:20:17 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 03:20:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/asi2zzvQTCmeOzFSSitltQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 03:20:18 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 03:20:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/asi2zzvQTCmeOzFSSitltQ/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 03:20:25 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 03:20: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')]} 03:20:26 INFO - trying https://queue.taskcluster.net/v1/task/asi2zzvQTCmeOzFSSitltQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 03:20:26 INFO - Downloading https://queue.taskcluster.net/v1/task/asi2zzvQTCmeOzFSSitltQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 03:20:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/asi2zzvQTCmeOzFSSitltQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 03:20:29 INFO - Downloaded 111493922 bytes. 03:20:29 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/asi2zzvQTCmeOzFSSitltQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 03:20:29 INFO - mkdir: /builds/slave/test/properties 03:20:29 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:20:29 INFO - Writing to file /builds/slave/test/properties/build_url 03:20:29 INFO - Contents: 03:20:29 INFO - build_url:https://queue.taskcluster.net/v1/task/asi2zzvQTCmeOzFSSitltQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 03:20:30 INFO - Running post-action listener: _resource_record_post_action 03:20:30 INFO - Running post-action listener: set_extra_try_arguments 03:20:30 INFO - [mozharness: 2016-09-07 10:20:30.009328Z] Finished download-and-extract step (success) 03:20:30 INFO - [mozharness: 2016-09-07 10:20:30.009466Z] Running populate-webroot step. 03:20:30 INFO - Running pre-action listener: _resource_record_pre_action 03:20:30 INFO - Running main action method: populate_webroot 03:20:30 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:20:30 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:20:30 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:20:30 INFO - '--tppagecycles', 03:20:30 INFO - '7'], 03:20:30 INFO - 'tests': ['tsvgm']}}, 03:20:30 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:20:30 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:20:30 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:20:30 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:20:30 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:20:30 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:20:30 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:20:30 INFO - 'talos_options': ['--disable-e10s'], 03:20:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:20:30 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:20:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:20:30 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:20:30 INFO - 'talos_options': ['--disable-e10s'], 03:20:30 INFO - 'tests': ['damp', 'tps']}, 03:20:30 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:20:30 INFO - 'tests': ['damp', 'tps']}, 03:20:30 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:20:30 INFO - 'tests': ['dromaeo_dom']}, 03:20:30 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:20:30 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:20:30 INFO - 'tests': ['basic_compositor_video']}, 03:20:30 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:20:30 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:20:30 INFO - 'tests': ['a11yr', 03:20:30 INFO - 'ts_paint', 03:20:30 INFO - 'tpaint', 03:20:30 INFO - 'sessionrestore', 03:20:30 INFO - 'sessionrestore_no_auto_restore', 03:20:30 INFO - 'tabpaint']}, 03:20:30 INFO - 'other-e10s': {'tests': ['a11yr', 03:20:30 INFO - 'ts_paint', 03:20:30 INFO - 'tpaint', 03:20:30 INFO - 'sessionrestore', 03:20:30 INFO - 'sessionrestore_no_auto_restore', 03:20:30 INFO - 'tabpaint']}, 03:20:30 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:20:30 INFO - 'ts_paint', 03:20:30 INFO - 'tpaint', 03:20:30 INFO - 'sessionrestore', 03:20:30 INFO - 'sessionrestore_no_auto_restore', 03:20:30 INFO - 'tabpaint']}, 03:20:30 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:20:30 INFO - 'ts_paint', 03:20:30 INFO - 'tpaint', 03:20:30 INFO - 'sessionrestore', 03:20:30 INFO - 'sessionrestore_no_auto_restore', 03:20:30 INFO - 'tabpaint']}, 03:20:30 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:20:30 INFO - 'tests': ['a11yr', 03:20:30 INFO - 'ts_paint', 03:20:30 INFO - 'tpaint', 03:20:30 INFO - 'sessionrestore', 03:20:30 INFO - 'sessionrestore_no_auto_restore', 03:20:30 INFO - 'tabpaint']}, 03:20:30 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:20:30 INFO - 'tests': ['a11yr', 03:20:30 INFO - 'ts_paint', 03:20:30 INFO - 'tpaint', 03:20:30 INFO - 'sessionrestore', 03:20:30 INFO - 'sessionrestore_no_auto_restore', 03:20:30 INFO - 'tabpaint']}, 03:20:30 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:20:30 INFO - 'tests': ['tsvgx', 03:20:30 INFO - 'tsvgr_opacity', 03:20:30 INFO - 'tart', 03:20:30 INFO - 'tscrollx', 03:20:30 INFO - 'cart']}, 03:20:30 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:20:30 INFO - 'tsvgr_opacity', 03:20:30 INFO - 'tart', 03:20:30 INFO - 'tscrollx', 03:20:30 INFO - 'cart']}, 03:20:30 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:20:30 INFO - 'talos_options': ['--disable-e10s'], 03:20:30 INFO - 'tests': ['tp5o']}, 03:20:30 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:20:30 INFO - 'tests': ['tp5o']}, 03:20:30 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:20:30 INFO - 'talos_options': ['--disable-e10s', 03:20:30 INFO - '--xperf_path', 03:20:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:20:30 INFO - 'tests': ['tp5n']}, 03:20:30 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:20:30 INFO - 'talos_options': ['--xperf_path', 03:20:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:20:30 INFO - 'tests': ['tp5n']}}, 03:20:30 INFO - 'talos.zip': {'path': '', 03:20:30 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:20:30 INFO - Running post-action listener: _resource_record_post_action 03:20:30 INFO - [mozharness: 2016-09-07 10:20:30.028200Z] Finished populate-webroot step (success) 03:20:30 INFO - [mozharness: 2016-09-07 10:20:30.028331Z] Running create-virtualenv step. 03:20:30 INFO - Running pre-action listener: _resource_record_pre_action 03:20:30 INFO - Running main action method: create_virtualenv 03:20:30 INFO - Creating virtualenv /builds/slave/test/build/venv 03:20:30 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 03:20:30 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:20:30 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:20:30 INFO - Using real prefix '/tools/python27' 03:20:30 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:20:30 INFO - Installing distribute.............................................................................................................................................................................................done. 03:20:33 INFO - Installing pip.................done. 03:20:33 INFO - Return code: 0 03:20:33 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:20:33 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')]} 03:20:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:20:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:20:33 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')]} 03:20:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:20:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:20:33 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 0x108709a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10879e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97224dcd60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10866dd30>, '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 0x1083d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108665b20>, '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.Hv4QVEOsYm/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.MIfKZSQ6J7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 03:20:33 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 03:20:33 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 03:20:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Hv4QVEOsYm/Render', 03:20:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:20:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:20:33 INFO - 'HOME': '/Users/cltbld', 03:20:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:20:33 INFO - 'LOGNAME': 'cltbld', 03:20:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:20:33 INFO - 'MOZ_NO_REMOTE': '1', 03:20:33 INFO - 'NO_EM_RESTART': '1', 03:20:33 INFO - 'PAGER': '/bin/cat', 03:20:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:20:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:20:33 INFO - 'PWD': '/builds/slave/test', 03:20:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:20:33 INFO - 'SHELL': '/bin/bash', 03:20:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MIfKZSQ6J7/Listeners', 03:20:33 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 03:20:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:20:33 INFO - 'USER': 'cltbld', 03:20:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:20:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:20:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:20:33 INFO - 'XPC_FLAGS': '0x0', 03:20:33 INFO - 'XPC_SERVICE_NAME': '0', 03:20:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:20:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:20:33 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 03:20:33 INFO - Cleaning up... 03:20:34 INFO - Return code: 0 03:20:34 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:20:34 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')]} 03:20:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:20:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:20:34 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')]} 03:20:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:20:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:20:34 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 0x108709a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10879e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97224dcd60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10866dd30>, '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 0x1083d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108665b20>, '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.Hv4QVEOsYm/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.MIfKZSQ6J7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 03:20:34 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 03:20:34 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 03:20:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Hv4QVEOsYm/Render', 03:20:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:20:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:20:34 INFO - 'HOME': '/Users/cltbld', 03:20:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:20:34 INFO - 'LOGNAME': 'cltbld', 03:20:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:20:34 INFO - 'MOZ_NO_REMOTE': '1', 03:20:34 INFO - 'NO_EM_RESTART': '1', 03:20:34 INFO - 'PAGER': '/bin/cat', 03:20:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:20:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:20:34 INFO - 'PWD': '/builds/slave/test', 03:20:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:20:34 INFO - 'SHELL': '/bin/bash', 03:20:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MIfKZSQ6J7/Listeners', 03:20:34 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 03:20:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:20:34 INFO - 'USER': 'cltbld', 03:20:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:20:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:20:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:20:34 INFO - 'XPC_FLAGS': '0x0', 03:20:34 INFO - 'XPC_SERVICE_NAME': '0', 03:20:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:20:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:20:34 INFO - Downloading/unpacking psutil>=3.1.1 03:20:34 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 03:20:34 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 03:20:34 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 03:20:34 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 03:20:34 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 03:20:34 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 03:20:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:20:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:20:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:20:37 INFO - Installing collected packages: psutil 03:20:37 INFO - Running setup.py install for psutil 03:20:37 INFO - building 'psutil._psutil_osx' extension 03:20:37 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 03:20:38 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 03:20:38 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 03:20:38 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 03:20:38 INFO - building 'psutil._psutil_posix' extension 03:20:38 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 03:20:38 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:20:38 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:20:38 INFO - ^ 03:20:38 INFO - 1 warning generated. 03:20:38 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 03:20:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:20:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:20:38 INFO - Successfully installed psutil 03:20:38 INFO - Cleaning up... 03:20:39 INFO - Return code: 0 03:20:39 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:20:39 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')]} 03:20:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:20:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:20:39 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')]} 03:20:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:20:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:20:39 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 0x108709a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10879e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97224dcd60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10866dd30>, '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 0x1083d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108665b20>, '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.Hv4QVEOsYm/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.MIfKZSQ6J7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 03:20:39 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 03:20:39 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 03:20:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Hv4QVEOsYm/Render', 03:20:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:20:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:20:39 INFO - 'HOME': '/Users/cltbld', 03:20:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:20:39 INFO - 'LOGNAME': 'cltbld', 03:20:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:20:39 INFO - 'MOZ_NO_REMOTE': '1', 03:20:39 INFO - 'NO_EM_RESTART': '1', 03:20:39 INFO - 'PAGER': '/bin/cat', 03:20:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:20:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:20:39 INFO - 'PWD': '/builds/slave/test', 03:20:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:20:39 INFO - 'SHELL': '/bin/bash', 03:20:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MIfKZSQ6J7/Listeners', 03:20:39 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 03:20:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:20:39 INFO - 'USER': 'cltbld', 03:20:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:20:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:20:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:20:39 INFO - 'XPC_FLAGS': '0x0', 03:20:39 INFO - 'XPC_SERVICE_NAME': '0', 03:20:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:20:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:20:39 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:20:39 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 03:20:39 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 03:20:39 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 03:20:39 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 03:20:39 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 03:20:39 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 03:20:41 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:20:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:20:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:20:41 INFO - Installing collected packages: mozsystemmonitor 03:20:41 INFO - Running setup.py install for mozsystemmonitor 03:20:41 INFO - Successfully installed mozsystemmonitor 03:20:41 INFO - Cleaning up... 03:20:42 INFO - Return code: 0 03:20:42 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:20: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')]} 03:20:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:20:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:20: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')]} 03:20:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:20:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:20: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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108709a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10879e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97224dcd60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10866dd30>, '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 0x1083d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108665b20>, '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.Hv4QVEOsYm/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.MIfKZSQ6J7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 03:20: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==2.5.1'] in /builds/slave/test/build 03:20: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==2.5.1 03:20:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Hv4QVEOsYm/Render', 03:20:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:20:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:20:42 INFO - 'HOME': '/Users/cltbld', 03:20:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:20:42 INFO - 'LOGNAME': 'cltbld', 03:20:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:20:42 INFO - 'MOZ_NO_REMOTE': '1', 03:20:42 INFO - 'NO_EM_RESTART': '1', 03:20:42 INFO - 'PAGER': '/bin/cat', 03:20:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:20:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:20:42 INFO - 'PWD': '/builds/slave/test', 03:20:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:20:42 INFO - 'SHELL': '/bin/bash', 03:20:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MIfKZSQ6J7/Listeners', 03:20:42 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 03:20:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:20:42 INFO - 'USER': 'cltbld', 03:20:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:20:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:20:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:20:42 INFO - 'XPC_FLAGS': '0x0', 03:20:42 INFO - 'XPC_SERVICE_NAME': '0', 03:20:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:20:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:20:42 INFO - Downloading/unpacking jsonschema==2.5.1 03:20:42 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 03:20:42 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 03:20:42 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 03:20:42 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 03:20:42 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 03:20:42 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 03:20:44 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:20:44 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:20:44 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 03:20:44 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 03:20:44 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 03:20:44 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 03:20:44 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 03:20:44 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 03:20:45 INFO - Downloading functools32-3.2.3-2.tar.gz 03:20:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:20:45 INFO - warning: no files found matching '*.txt' 03:20:45 INFO - no previously-included directories found matching 'build' 03:20:45 INFO - no previously-included directories found matching 'dist' 03:20:45 INFO - no previously-included directories found matching '.git*' 03:20:45 INFO - Installing collected packages: jsonschema, functools32 03:20:45 INFO - Running setup.py install for functools32 03:20:45 INFO - warning: no files found matching '*.txt' 03:20:45 INFO - no previously-included directories found matching 'build' 03:20:45 INFO - no previously-included directories found matching 'dist' 03:20:45 INFO - no previously-included directories found matching '.git*' 03:20:45 INFO - Successfully installed jsonschema functools32 03:20:45 INFO - Cleaning up... 03:20:45 INFO - Return code: 0 03:20:45 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:20:45 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')]} 03:20:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:20:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:20:45 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')]} 03:20:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:20:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:20:45 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 0x108709a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10879e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97224dcd60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10866dd30>, '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 0x1083d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108665b20>, '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.Hv4QVEOsYm/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.MIfKZSQ6J7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 03:20:45 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 03:20:45 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 03:20:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Hv4QVEOsYm/Render', 03:20:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:20:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:20:45 INFO - 'HOME': '/Users/cltbld', 03:20:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:20:45 INFO - 'LOGNAME': 'cltbld', 03:20:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:20:45 INFO - 'MOZ_NO_REMOTE': '1', 03:20:45 INFO - 'NO_EM_RESTART': '1', 03:20:45 INFO - 'PAGER': '/bin/cat', 03:20:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:20:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:20:45 INFO - 'PWD': '/builds/slave/test', 03:20:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:20:45 INFO - 'SHELL': '/bin/bash', 03:20:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MIfKZSQ6J7/Listeners', 03:20:45 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 03:20:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:20:45 INFO - 'USER': 'cltbld', 03:20:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:20:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:20:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:20:45 INFO - 'XPC_FLAGS': '0x0', 03:20:45 INFO - 'XPC_SERVICE_NAME': '0', 03:20:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:20:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:20:45 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:20:45 INFO - Cleaning up... 03:20:45 INFO - Return code: 0 03:20:45 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:20:45 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')]} 03:20:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:20:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:20:45 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')]} 03:20:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:20:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:20:45 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 0x108709a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10879e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97224dcd60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10866dd30>, '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 0x1083d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108665b20>, '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.Hv4QVEOsYm/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.MIfKZSQ6J7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 03:20:45 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 03:20:45 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 03:20:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Hv4QVEOsYm/Render', 03:20:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:20:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:20:45 INFO - 'HOME': '/Users/cltbld', 03:20:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:20:45 INFO - 'LOGNAME': 'cltbld', 03:20:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:20:45 INFO - 'MOZ_NO_REMOTE': '1', 03:20:45 INFO - 'NO_EM_RESTART': '1', 03:20:45 INFO - 'PAGER': '/bin/cat', 03:20:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:20:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:20:45 INFO - 'PWD': '/builds/slave/test', 03:20:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:20:45 INFO - 'SHELL': '/bin/bash', 03:20:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MIfKZSQ6J7/Listeners', 03:20:45 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 03:20:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:20:45 INFO - 'USER': 'cltbld', 03:20:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:20:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:20:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:20:45 INFO - 'XPC_FLAGS': '0x0', 03:20:45 INFO - 'XPC_SERVICE_NAME': '0', 03:20:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:20:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:20:46 INFO - Downloading/unpacking blobuploader==1.2.4 03:20:46 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 03:20:46 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 03:20:46 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 03:20:46 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 03:20:46 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 03:20:46 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 03:20:48 INFO - Downloading blobuploader-1.2.4.tar.gz 03:20:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:20:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:20:48 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 03:20:48 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 03:20:48 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 03:20:48 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 03:20:48 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 03:20:48 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 03:20:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:20:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:20:49 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 03:20:49 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 03:20:49 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 03:20:49 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 03:20:49 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 03:20:49 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 03:20:49 INFO - Downloading docopt-0.6.1.tar.gz 03:20:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:20:49 INFO - Installing collected packages: blobuploader, requests, docopt 03:20:49 INFO - Running setup.py install for blobuploader 03:20:49 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:20:50 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:20:50 INFO - Running setup.py install for requests 03:20:50 INFO - Running setup.py install for docopt 03:20:50 INFO - Successfully installed blobuploader requests docopt 03:20:50 INFO - Cleaning up... 03:20:50 INFO - Return code: 0 03:20:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:20: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')]} 03:20:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:20:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:20: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')]} 03:20:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:20:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:20:50 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 0x108709a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10879e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97224dcd60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10866dd30>, '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 0x1083d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108665b20>, '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.Hv4QVEOsYm/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.MIfKZSQ6J7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 03:20:50 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 03:20:50 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 03:20:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Hv4QVEOsYm/Render', 03:20:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:20:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:20:50 INFO - 'HOME': '/Users/cltbld', 03:20:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:20:50 INFO - 'LOGNAME': 'cltbld', 03:20:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:20:50 INFO - 'MOZ_NO_REMOTE': '1', 03:20:50 INFO - 'NO_EM_RESTART': '1', 03:20:50 INFO - 'PAGER': '/bin/cat', 03:20:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:20:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:20:50 INFO - 'PWD': '/builds/slave/test', 03:20:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:20:50 INFO - 'SHELL': '/bin/bash', 03:20:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MIfKZSQ6J7/Listeners', 03:20:50 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 03:20:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:20:50 INFO - 'USER': 'cltbld', 03:20:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:20:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:20:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:20:50 INFO - 'XPC_FLAGS': '0x0', 03:20:50 INFO - 'XPC_SERVICE_NAME': '0', 03:20:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:20:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:20:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:20:51 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-PkRq9c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:20:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:20:51 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-VMmaYn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:20:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:20:51 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-DlNT6K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:20:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:20:51 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-YGREx6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:20:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:20:51 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-5c852o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:20:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:20:51 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-YSjF5S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:20:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:20:51 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-mVHny4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:20:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:20:51 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-403yOF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:20:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:20:51 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-SffuML-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:20:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:20:52 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-Bqfp_6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:20:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:20:52 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-Dc5K5N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:20:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:20:52 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-6sjSj0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:20:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:20:52 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-ceofOO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:20:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:20:52 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-koKEoV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:20:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:20:52 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-G8pCZG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:20:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:20:52 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-WtQx3V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:20:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:20:52 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-uHw5bV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:20:52 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:20:52 INFO - Running setup.py install for manifestparser 03:20:52 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:20:53 INFO - Running setup.py install for mozcrash 03:20:53 INFO - Running setup.py install for mozdebug 03:20:53 INFO - Running setup.py install for mozdevice 03:20:53 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:20:53 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:20:53 INFO - Running setup.py install for mozfile 03:20:53 INFO - Running setup.py install for mozhttpd 03:20:53 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:20:53 INFO - Running setup.py install for mozinfo 03:20:53 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:20:53 INFO - Running setup.py install for mozInstall 03:20:54 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:20:54 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:20:54 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:20:54 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:20:54 INFO - Running setup.py install for mozleak 03:20:54 INFO - Running setup.py install for mozlog 03:20:54 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:20:54 INFO - Running setup.py install for moznetwork 03:20:54 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:20:54 INFO - Running setup.py install for mozprocess 03:20:54 INFO - Running setup.py install for mozprofile 03:20:54 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:20:54 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:20:54 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:20:54 INFO - Running setup.py install for mozrunner 03:20:54 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:20:54 INFO - Running setup.py install for mozscreenshot 03:20:55 INFO - Running setup.py install for moztest 03:20:55 INFO - Running setup.py install for mozversion 03:20:55 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:20:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:20:55 INFO - Cleaning up... 03:20:55 INFO - Return code: 0 03:20:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:20:55 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')]} 03:20:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:20:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:20:55 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')]} 03:20:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:20:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:20:55 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 0x108709a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10879e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97224dcd60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10866dd30>, '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 0x1083d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108665b20>, '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.Hv4QVEOsYm/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.MIfKZSQ6J7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 03:20:55 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 03:20:55 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 03:20:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Hv4QVEOsYm/Render', 03:20:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:20:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:20:55 INFO - 'HOME': '/Users/cltbld', 03:20:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:20:55 INFO - 'LOGNAME': 'cltbld', 03:20:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:20:55 INFO - 'MOZ_NO_REMOTE': '1', 03:20:55 INFO - 'NO_EM_RESTART': '1', 03:20:55 INFO - 'PAGER': '/bin/cat', 03:20:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:20:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:20:55 INFO - 'PWD': '/builds/slave/test', 03:20:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:20:55 INFO - 'SHELL': '/bin/bash', 03:20:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MIfKZSQ6J7/Listeners', 03:20:55 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 03:20:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:20:55 INFO - 'USER': 'cltbld', 03:20:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:20:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:20:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:20:55 INFO - 'XPC_FLAGS': '0x0', 03:20:55 INFO - 'XPC_SERVICE_NAME': '0', 03:20:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:20:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:20:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:20:55 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-qaA5ok-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:20:55 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)) 03:20:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:20:55 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-aIV5UQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:20:55 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)) 03:20:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:20:55 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-JN3Czn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:20:56 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)) 03:20:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:20:56 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-wBz_qS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:20:56 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)) 03:20:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:20:56 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-4XJewp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:20:56 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)) 03:20:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:20:56 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-xOu4f9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:20:56 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)) 03:20:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:20:56 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-P5CwMZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:20:56 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)) 03:20:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:20:56 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-7JoxlD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:20:56 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)) 03:20:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:20:56 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-McWeCR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:20:56 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)) 03:20:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:20:56 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-4ASLPj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:20:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 03:20:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:20:56 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-ilKemy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:20:56 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)) 03:20:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:20:56 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-uFXi8k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:20:56 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)) 03:20:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:20:56 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-oKqwXf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:20:57 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)) 03:20:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:20:57 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-Kea97_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:20:57 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)) 03:20:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:20:57 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-jqu8au-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:20:57 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)) 03:20:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:20:57 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-riihnC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:20:57 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)) 03:20:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:20:57 INFO - Running setup.py (path:/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/pip-37dl2y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:20:57 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)) 03:20:57 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)) 03:20:57 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)) 03:20:57 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)) 03:20:57 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)) 03:20:57 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)) 03:20:57 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:20:57 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 03:20:57 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 03:20:57 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 03:20:57 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 03:20:57 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 03:20:57 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 03:20:59 INFO - Downloading blessings-1.6.tar.gz 03:20:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:21:00 INFO - Installing collected packages: blessings 03:21:00 INFO - Running setup.py install for blessings 03:21:00 INFO - Successfully installed blessings 03:21:00 INFO - Cleaning up... 03:21:00 INFO - Return code: 0 03:21:00 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:21:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:21:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:21:00 INFO - Reading from file tmpfile_stdout 03:21:00 INFO - Current package versions: 03:21:00 INFO - blessings == 1.6 03:21:00 INFO - blobuploader == 1.2.4 03:21:00 INFO - docopt == 0.6.1 03:21:00 INFO - functools32 == 3.2.3-2 03:21:00 INFO - jsonschema == 2.5.1 03:21:00 INFO - manifestparser == 1.1 03:21:00 INFO - mozInstall == 1.12 03:21:00 INFO - mozcrash == 0.17 03:21:00 INFO - mozdebug == 0.1 03:21:00 INFO - mozdevice == 0.48 03:21:00 INFO - mozfile == 1.2 03:21:00 INFO - mozhttpd == 0.7 03:21:00 INFO - mozinfo == 0.9 03:21:00 INFO - mozleak == 0.1 03:21:00 INFO - mozlog == 3.3 03:21:00 INFO - moznetwork == 0.27 03:21:00 INFO - mozprocess == 0.23 03:21:00 INFO - mozprofile == 0.28 03:21:00 INFO - mozrunner == 6.12 03:21:00 INFO - mozscreenshot == 0.1 03:21:00 INFO - mozsystemmonitor == 0.3 03:21:00 INFO - moztest == 0.7 03:21:00 INFO - mozversion == 1.4 03:21:00 INFO - psutil == 3.1.1 03:21:00 INFO - requests == 1.2.3 03:21:00 INFO - wsgiref == 0.1.2 03:21:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:21:00 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')]} 03:21:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:00 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')]} 03:21:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:00 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 0x108709a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10879e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97224dcd60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10866dd30>, '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 0x1083d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108665b20>, '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.Hv4QVEOsYm/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.MIfKZSQ6J7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 03:21:00 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 03:21:00 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 03:21:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Hv4QVEOsYm/Render', 03:21:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:21:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:21:00 INFO - 'HOME': '/Users/cltbld', 03:21:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:21:00 INFO - 'LOGNAME': 'cltbld', 03:21:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:00 INFO - 'MOZ_NO_REMOTE': '1', 03:21:00 INFO - 'NO_EM_RESTART': '1', 03:21:00 INFO - 'PAGER': '/bin/cat', 03:21:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:21:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:00 INFO - 'PWD': '/builds/slave/test', 03:21:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:21:00 INFO - 'SHELL': '/bin/bash', 03:21:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MIfKZSQ6J7/Listeners', 03:21:00 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 03:21:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:21:00 INFO - 'USER': 'cltbld', 03:21:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:21:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:21:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:00 INFO - 'XPC_FLAGS': '0x0', 03:21:00 INFO - 'XPC_SERVICE_NAME': '0', 03:21:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:21:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:00 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)) 03:21:00 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)) 03:21:00 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)) 03:21:00 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)) 03:21:00 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)) 03:21:00 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)) 03:21:00 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)) 03:21:00 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)) 03:21:00 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)) 03:21:00 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)) 03:21:00 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)) 03:21:00 INFO - Cleaning up... 03:21:00 INFO - Return code: 0 03:21:00 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:21:00 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')]} 03:21:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:00 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')]} 03:21:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:00 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 0x108709a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10879e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97224dcd60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10866dd30>, '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 0x1083d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108665b20>, '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.Hv4QVEOsYm/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.MIfKZSQ6J7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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 03:21:00 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 03:21:00 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 03:21:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Hv4QVEOsYm/Render', 03:21:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:21:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:21:00 INFO - 'HOME': '/Users/cltbld', 03:21:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:21:00 INFO - 'LOGNAME': 'cltbld', 03:21:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:00 INFO - 'MOZ_NO_REMOTE': '1', 03:21:00 INFO - 'NO_EM_RESTART': '1', 03:21:00 INFO - 'PAGER': '/bin/cat', 03:21:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:21:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:00 INFO - 'PWD': '/builds/slave/test', 03:21:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:21:00 INFO - 'SHELL': '/bin/bash', 03:21:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MIfKZSQ6J7/Listeners', 03:21:00 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 03:21:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:21:00 INFO - 'USER': 'cltbld', 03:21:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:21:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:21:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:00 INFO - 'XPC_FLAGS': '0x0', 03:21:00 INFO - 'XPC_SERVICE_NAME': '0', 03:21:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:21:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:01 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:21:01 INFO - Cleaning up... 03:21:01 INFO - Return code: 0 03:21:01 INFO - Running post-action listener: _resource_record_post_action 03:21:01 INFO - Running post-action listener: _start_resource_monitoring 03:21:01 INFO - Starting resource monitoring. 03:21:01 INFO - [mozharness: 2016-09-07 10:21:01.119131Z] Finished create-virtualenv step (success) 03:21:01 INFO - [mozharness: 2016-09-07 10:21:01.120111Z] Running install step. 03:21:01 INFO - Running pre-action listener: _resource_record_pre_action 03:21:01 INFO - Running main action method: install 03:21:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:21:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:21:01 INFO - Reading from file tmpfile_stdout 03:21:01 INFO - Detecting whether we're running mozinstall >=1.0... 03:21:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:21:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:21:01 INFO - Reading from file tmpfile_stdout 03:21:01 INFO - Output received: 03:21:01 INFO - Usage: mozinstall [options] installer 03:21:01 INFO - Options: 03:21:01 INFO - -h, --help show this help message and exit 03:21:01 INFO - -d DEST, --destination=DEST 03:21:01 INFO - Directory to install application into. [default: 03:21:01 INFO - "/builds/slave/test"] 03:21:01 INFO - --app=APP Application being installed. [default: firefox] 03:21:01 INFO - mkdir: /builds/slave/test/build/application 03:21:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 03:21:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 03:21:35 INFO - Reading from file tmpfile_stdout 03:21:35 INFO - Output received: 03:21:35 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 03:21:35 INFO - Running post-action listener: _resource_record_post_action 03:21:35 INFO - [mozharness: 2016-09-07 10:21:35.477047Z] Finished install step (success) 03:21:35 INFO - [mozharness: 2016-09-07 10:21:35.477187Z] Running run-tests step. 03:21:35 INFO - Running pre-action listener: _resource_record_pre_action 03:21:35 INFO - Running main action method: run_tests 03:21:35 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:21:35 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:21:35 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:21:35 INFO - 2016-09-07 03:21:35.491 screenresolution[1706:8081] starting screenresolution argv=screenresolution get 03:21:35 INFO - 2016-09-07 03:21:35.494 screenresolution[1706:8081] Display 0: 1600x1200x32@60 03:21:35 INFO - 2016-09-07 03:21:35.499 screenresolution[1707:8083] starting screenresolution argv=screenresolution list 03:21:35 INFO - Available Modes on Display 0 03:21:35 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:21:35 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:21:35 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:21:35 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:21:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:21:35 INFO - Intel Iris: 03:21:35 INFO - Chipset Model: Intel Iris 03:21:35 INFO - Type: GPU 03:21:35 INFO - Bus: Built-In 03:21:35 INFO - VRAM (Dynamic, Max): 1536 MB 03:21:35 INFO - Vendor: Intel (0x8086) 03:21:35 INFO - Device ID: 0x0a2e 03:21:35 INFO - Revision ID: 0x0009 03:21:35 INFO - Displays: 03:21:35 INFO - Display: 03:21:35 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:21:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:21:35 INFO - Display Serial Number: 1600x1200 60 03:21:35 INFO - Main Display: Yes 03:21:35 INFO - Mirror: Off 03:21:35 INFO - Online: Yes 03:21:35 INFO - Rotation: Supported 03:21:35 INFO - Return code: 0 03:21:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:21:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:21:35 INFO - Python 2.7.3 03:21:35 INFO - Return code: 0 03:21:35 INFO - grabbing minidump binary from tooltool 03:21:35 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')]} 03:21:35 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 0x10866dd30>, '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 0x1083d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108665b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:21:35 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 03:21:35 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 03:21:35 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 03:21:35 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:21:35 INFO - Return code: 0 03:21:35 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:21:35 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:21:35 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:21:35 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:21:35 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:21:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/asi2zzvQTCmeOzFSSitltQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0258', '--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 03:21:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/asi2zzvQTCmeOzFSSitltQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0258 --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 03:21:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Hv4QVEOsYm/Render', 03:21:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:21:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:21:35 INFO - 'HOME': '/Users/cltbld', 03:21:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:21:35 INFO - 'LOGNAME': 'cltbld', 03:21:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:21:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:21:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:35 INFO - 'MOZ_NO_REMOTE': '1', 03:21:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:21:35 INFO - 'NO_EM_RESTART': '1', 03:21:35 INFO - 'PAGER': '/bin/cat', 03:21:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:21:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:35 INFO - 'PWD': '/builds/slave/test', 03:21:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:21:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:21:35 INFO - 'SHELL': '/bin/bash', 03:21:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MIfKZSQ6J7/Listeners', 03:21:35 INFO - 'TMPDIR': '/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/', 03:21:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:21:35 INFO - 'USER': 'cltbld', 03:21:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:21:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:21:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:35 INFO - 'XPC_FLAGS': '0x0', 03:21:35 INFO - 'XPC_SERVICE_NAME': '0', 03:21:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:21:35 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/asi2zzvQTCmeOzFSSitltQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0258', '--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 03:21:36 INFO - mozversion application_buildid: 20160907012216 03:21:36 INFO - mozversion application_changeset: 541c9086c0f27fba60beecc9bc94543103895c86 03:21:36 INFO - mozversion application_display_name: Nightly 03:21:36 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:21:36 INFO - mozversion application_name: Firefox 03:21:36 INFO - mozversion application_remotingname: firefox 03:21:36 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 03:21:36 INFO - mozversion application_vendor: Mozilla 03:21:36 INFO - mozversion application_version: 51.0a1 03:21:36 INFO - mozversion platform_buildid: 20160907012216 03:21:36 INFO - mozversion platform_changeset: 541c9086c0f27fba60beecc9bc94543103895c86 03:21:36 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 03:21:36 INFO - mozversion platform_version: 51.0a1 03:21:36 INFO - using testdate: 1473243696 03:21:36 INFO - actual date: 1473243696 03:21:36 INFO - starting webserver on 'localhost:49267' 03:21:36 INFO - SUITE-START | Running 2 tests 03:21:36 INFO - TEST-START | dromaeo_css 03:21:36 INFO - operating with platform_type : mac_ 03:21:36 INFO - Initialising browser for dromaeo_css test... 03:21:36 INFO - TEST-INFO | started process 1717 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/tmphH_Kts/profile http://localhost:49267/getInfo.html) 03:21:38 INFO - PROCESS | 1717 | __metrics Screen width/height:1600/1200 03:21:38 INFO - PROCESS | 1717 | colorDepth:24 03:21:38 INFO - PROCESS | 1717 | Browser inner width/height: 1024/689 03:21:38 INFO - PROCESS | 1717 | __metrics 03:21:38 ERROR - PROCESS | 1717 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2998: TypeError: this.tabs is undefined 03:21:39 INFO - TEST-INFO | 1717: exit 0 03:21:39 INFO - Browser initialized. 03:21:39 INFO - Running cycle 1/1 for dromaeo_css test... 03:21:39 INFO - TEST-INFO | started process 1722 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/tmphH_Kts/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:21:51 INFO - PROCESS | 1722 | RSS: Main: 243896320 03:21:51 INFO - PROCESS | 1722 | 03:22:41 INFO - PROCESS | 1722 | 1473243761247 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] 03:24:53 INFO - PROCESS | 1722 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49267/tests/dromaeo/cssquery-ext.html) 03:24:54 INFO - PROCESS | 1722 | RSS: Main: 262410240 03:24:54 INFO - PROCESS | 1722 | 03:27:56 INFO - PROCESS | 1722 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-ext.html (next: http://localhost:49267/tests/dromaeo/cssquery-jquery.html) 03:27:57 INFO - PROCESS | 1722 | RSS: Main: 267526144 03:27:57 INFO - PROCESS | 1722 | 03:30:59 INFO - PROCESS | 1722 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49267/tests/dromaeo/cssquery-mootools.html) 03:30:59 INFO - PROCESS | 1722 | RSS: Main: 272224256 03:30:59 INFO - PROCESS | 1722 | 03:34:01 INFO - PROCESS | 1722 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49267/tests/dromaeo/cssquery-prototype.html) 03:34:02 INFO - PROCESS | 1722 | RSS: Main: 268734464 03:34:02 INFO - PROCESS | 1722 | 03:37:05 INFO - PROCESS | 1722 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49267/tests/dromaeo/cssquery-yui.html) 03:37:05 INFO - PROCESS | 1722 | RSS: Main: 273887232 03:37:05 INFO - PROCESS | 1722 | 03:40:08 INFO - PROCESS | 1722 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-yui.html (next: http://localhost:49267/tests/dromaeo/cssquery-dojo.html) 03:40:08 INFO - PROCESS | 1722 | RSS: Main: 276840448 03:40:08 INFO - PROCESS | 1722 | 03:40:08 INFO - PROCESS | 1722 | __start_tp_report 03:40:08 INFO - PROCESS | 1722 | _x_x_mozilla_page_load 03:40:08 INFO - PROCESS | 1722 | _x_x_mozilla_page_load_details 03:40:08 INFO - PROCESS | 1722 | |i|pagename|runs| 03:40:08 INFO - PROCESS | 1722 | |0;dojo.html;3287.1617737476954;3353.564036675232;3353.5640366752323;3358.4458564336887;3380.290139070795;4519.367288579598;5560.221568980341;5565.666060036396;5627.324721033471;5664.886702265952;5469.54593579576;5612.915806262899;5628.212050312949;5700.401457846918;5726;4293.592108749664;5055.823046193384;5057.988402319533;5223.242629818693;5284.339457567804;5006.3241462402475;5289.418164001957;5305.628605997582;5317.202419637047;5323.547498462635;5087.821926232564;5127.871803204913;5172.19830926207;5206.349206349207;5208;5180.870478238036;5192.974035129838;5193.324867767204;5206.947930520684;5223.268742376071;5111.105556527593;5129.897402051975;5140.691558506502;5166.354205724285;5220.660657057279;4430.955690443118;5553.4450353900975;5562;5571.944280557183;5645.8023969161295;6137.969310153465;6175.166352542395;6178.84552886177;6201.255849298113;6206.193194884636;6256.96871515644;6275.309715931244;6276.623402595813;6278.497720182374;6305.5901366411035;4313.892152696177;4713.85858424241;5095.210242412432;5622.353429355636;5695.943040569541;5405.108157154104;6260.530460215502;6263.874722505524;6278.183836101278;6311.558190926591;6083.878322433527;6087.756489740449;6135.417135372132;6136.2636483622255;6136.631802091889;5499.972500137474;6058.0610005449225;6146.416090471398;6196.977498712662;6240.876642204446;5577.633479797394;5638.718064096779;5951.256092988377;6106.94607981953;6146.279041868284;6038.33578306386;6177.2587289524645;6184.165137706352;6187.938120618736;6214.40963108504;4861.708297502161;4950.653454258239;4957.157283261854;4974.104661161025;4985.551300382983;4350.499692535399;4385.473743150842;4389.275769497997;4402.603765661106;4429.933550996738;5611.523020543218;5714.5999780015;5724.112762521816;5730;5738.885222295531;6072.332043475214;6164.722587483574;6164.815055548341;6169.691515424211;6176.598521096204;5619.4099619540175;5665;5678.829635110953;5730.885382292331;5754.510866576305;5343.839684809462;5360;5368.704721240291;5378.408375078739;5391.649542779707;5537.861553461195;5805.912911306334;5920.437558431942;6084.300305464928;6281.466075383552;6130.184124957687;6133.570650054453;6135.1776484982;6136.059583474799;6167.537434692326;6231.470325022424;6245.125682404558;6252.843678908063;6253.687315634109;6291.685415729104;6074.1799857020505;6140;6149.907751383822;6166.321704612579;6174.475169610633;5194.4805519447755;5680.00599895025;6071.453569178795;6123.479504242011;6148.231471066117;6055.485283750931;6066.545009124422;6098.438422643064;6129.9387006131155;6142.201513803177;5870.412958704095;5957.582969192288;6093.421124993119;6093.421124993119;6109.816705498843;4820.39745031871;5843.2403787507355;5846.210761547137;5923.851903702442;5928.229330187048;6083.391660834058;6098.634081955097;6104.145419641165;6104.908426373519;6131.019036954066;5745.74144163522;5916.526677865674;5946.643201407929;5952.464278214981;5964.880702385841 03:40:08 INFO - PROCESS | 1722 | |1;ext.html;474.9833755818547;512.9384473863137;513.5634710496078;525.2803658987189;539.9417142400894;37849.81075094624;39707.40438893412;39958.2008359833;40008.99977500557;40071;40474;41168;41252.96867578312;41296.793516032456;41358;9497.810043799138;9694.709158725214;9707.854382184272;9731.610735570568;9743.464109473976;9559.044095590454;9680;9696.51517424126;9714.514274286294;9723.027697230256;8369.748907532783;9685.467299298536;9711.12599866012;9717.757056073619;9741.512924353789;9699.030096990315;9700.563474643623;9734.123928846368;9737.56180971858;9763.316567840255;9580.329376943617;9599.60805683179;9659.71020869375;9662.3719458235;9678.370905891095;38941;39122.02194945149;39303.80348098241;39306.21387572261;39445.80277098625;3256.348730253935;3294.8187849668257;3322.7009569138895;3340.0981734931675;3386.762926595139;30035.94874179393;30363;30768.384632307454;30816.537751933738;30819.845900770575;3200.7599430042605;3221.838908054588;3231.7737758356925;3238.8218647974354;3349.363620912043;487107;491933;493767;494258;494795;57045;57399.42600574024;57455;57479.712601436724;57602.135967960516;30634.23414414613;33932.32135357279;34046.48930266048;35506;60088.39911600828;16089.356425743073;16235.431759888348;16236.64568637541;16241.59396015109;16242.187890605423;10464.319819211727;12468.75062498745;12481.251124932533;12514.998800095971;12527.624171274876;18608.604354673455;18644.16101275446;18650.067496625386;18691.532711682317;18775.530611734815;6125.571210015345;6881.724731010803;8009.799755006172;8037.8392432152195;8072.273495385443;849.9405041647026;946.668665966906;948.4445509364648;1017.8676772019738;1105.723569107723;28119.297017574725;29694.554581681292;29760.33448644899;29777.10668679943;29858.552121718192;998.201438848918;1055.5355643516828;1137.726945533066;1163.3310846263432;1182.6215611004395;14163.716725665636;14845.812335013174;15027.323770430357;15100.471483498024;15269.83858026378;586260;679991;700129;700149;703484;55421;55642;56001;56045;56166.719166403906;1344.683999260173;1348.9932550337185;1386.7503849307222;1389.861013898602;1391.2348208485294;27865.721342787125;28655;28964.13107606775;29047.419051618428;29568.408631826813;47526.52473475221;47970;47971;47986.760066199444;48014.759926200146;580319;582122;583854;584096.0795195997;585071;25643;25656.61515077238;25926.481470370865;26030.349241268363;26129.73870261273;45268.320975186056;45503.772481137385;46016;47094.82262943317;47139.29291060568;37837.62162378451;38027.2394552113;38129;38892;40121.79939100286;33609.99170024903;37734.056648584;37870;38116.618833811306;39315.21369572535;37496;37619;37847.2430551393;38374.616253837106;38538.80730596329;28404.57393139146;31493.89771358083;34189.48715769216;34265;34315;25161.87419062893;25588;25645.3588660285;25709.485810283317;25944.61083083793 03:40:08 INFO - PROCESS | 1722 | |2;jquery.html;367.24164600100795;371.6153780836834;372.3688348249716;381.14433097695644;397.98806035818916;19360.709589356153;19422.320218792345;19627.761419365805;20708.068136933838;20839.166433342685;19914.502137446572;20204;20964.580708385824;21015.369538913856;21241.68137477939;5299.443558426367;5934.139549765281;6098;6179.073139029137;6193.442590166884;6039.637621742688;6047;6164.784232551861;6168.907466388007;6182.69086545671;5855.209546711182;6061.727222275008;6092.11664308675;6130.233720784902;6136.079588061782;5813.709314534256;5924.963131451978;5927.081302398114;5942.732577034044;6009.399060094;5224.320838290998;5264.078786212397;5268.157094864803;5277.973610131963;5289.814856480005;22404.32787016392;23044;23172;25670.614940775904;26113.869430652914;2108.652072408066;2344.4490544721975;2476.839005464639;2502.349389158832;2823.251838262862;9727.65953191635;9969.950150249275;10205.540750666238;12273.877261227452;13831.446742130303;1728.8271172882612;1775.6448710258;1898.4399602117408;1906.2851430713483;1936.6998115292151;123572;124042;124332.75667243302;125721.37139314336;126841;10924;11332.830007549728;11367.261128026652;11399.829002564968;11550.306981581132;14082;14114.576562703136;14138.151710897379;14138.293085345691;14140;8093.595320233965;8306.958465207636;8348.373871959628;8431.241188293083;8488.066312705598;8199.754007379788;8257.256846883809;8292.00495940491;8395.370347223861;8424.53665048416;4333.761643109659;4469.351943968107;4471.888202794956;4474.664400170001;4562.361269422284;1575.6297270141508;1629.4541328654796;1873.3162395725735;1938.5347516596198;1939.6023815117867;805.3355981315439;920.1120918313859;922.7324076018014;976.2824324121765;977.3012296208245;20235.696464553042;21147.25984590532;21273.468163296042;21297.78702212958;21318.360449186548;898.7393655839865;926.6979893249994;937.9062093790703;940.6284517615503;959.3572306554626;7573.2805383488485;8123.9593802031795;10111.494425278706;11226.494807733508;11757.412129393497;559286;561865;571244;573538;578508;116219;119062.40468797775;119414.8058519421;132978;135848;766.8696321625337;975.6000039983572;1017.3488967061087;1030.9742256443799;1035.0787798859021;17091.05999170008;18771.624567508847;19032;19285.4214373569;19346.32287869918;57046.7147664259;57844.71077644585;58308.70845645745;58546.707266463396;60158.09762853648;68492;85623.5718821402;86090.56954715187;86667;87992;7625.885611715939;7806.1803510632835;7999.200079992187;8021.558814265271;8100.635471403683;9707.6116955321;10776.67669969902;12010.579629712918;12885.162464439782;13020.544280950122;11217.383043932336;11224;11256.83114753263;11507;11570.247933884271;10956;11064.612738554433;11379.772404552028;11469.713257168638;11758.176927614984;9795.412275263507;11033.227674062739;11781.057515398576;11821.76356472883;11830.763384732429;6869.828254293682;6890.724371025103;7208.7116515340895;7362.9631851840395;7840.411969102226;9591.90408095938;11114.944425277821;11451.484683189443;11572.94213528927;11611.187216894736 03:40:08 INFO - PROCESS | 1722 | |3;mootools.html;1505.4655597262972;1531.831498535161;1731.714267145921;1751.7810273715777;1869.7849747279067;7771.766846994585;8345.37409694273;8504.404691671585;8893.421927574704;8914.50970196639;8554.95722521388;8631;8637.568121593911;8645.438046526971;8660.956695216515;2340.9531809363802;2387.152560840894;2390.40239940015;2391.820613453989;2394.7605239476;2337.94155146121;2380.0360853854218;2381.3927448500694;2388.4148383645943;2394.820388470863;2337.4857531343077;2352.3648614873973;2363.6927199464044;2367.088670861722;2375.0737212487143;2140.4755834820567;2147.259195577531;2354.0348457132495;2357.0925193800426;2363.988180059106;2052.2509284111343;2339.1345202275265;2339.146211632759;2347.6830627865193;2351.776581224781;8719.912800872036;8793.296536277083;8797.47215167092;8802.427842190302;8810.207081362643;4560.498345182027;5168.9224661630105;5186.118359878792;5281.815136470205;5333.919991200135;3462.6364231755806;3531.611522732497;3577.5528058992627;3616.3671357512594;3626.746127771057;1038.4132964874898;1072.6996440996534;1074.049466222391;1080.556971641631;1093.2565855218447;11402.942985285103;11478.483468243949;11513.366764827932;11527.711807204803;11561.479733411932;4548.408706868152;4629;4812.8796780080775;4825.276208568673;4827.806887724522;9707.854382184272;9734.659286925065;9818.459984700767;9830.655927042519;9839.55721992512;5306.575473962073;5322.494363035506;5327.627066105335;5337.839864804062;5342.2253773202665;4363.2800587903275;4705.505921878222;4851.539103785135;4870.82952096682;4893.437254715754;3234.2884565395575;3928.685705143582;3988.3020471417385;4001.7198796084576;4030.1133750574827;1278.7826069568196;1287.0604458745167;1288.664947113757;1289.9871001290055;1303.9413226404833;794.4637369775379;864.7449002544264;893.9106089391009;912;921.9032001639865;5702.657840529581;5713.771449142071;5729.427057294237;5829.329627092854;6045.183900173508;837.4389159263308;896.789254525186;936.1481052242426;938.4556956964944;943.0663642993525;3745.793981331053;3807.4479200516325;3813.961860381416;3859.421086836997;3931.4692516509904;10350.171986241234;10576.048155666027;10580.57677692899;10669.78660426787;10703.83944240837;10563.841542376871;10566.841497377545;10660.25378223517;10666.36001839892;10667.83998240027;7730.342920851791;8009.439339246197;8125.268725814587;8182.304504117216;8193.713220037389;5802.7098645066735;5921.111833225051;5951.613145145551;6035.818925432244;6053.424924632153;9415.717528474157;9824.607015719292;9840.458774767456;9851.753706157117;9875.40747555149;9945.353552019096;9945.900540994497;9998.350107243006;10143.7464063399;10222.744431389276;6819.147606549182;7217.025701530226;7321.707131714885;7370.5946172961;7454.515456495311;8218.958905205436;8229.506229626242;8255.339572834278;8266.793330166794;8271.669133234604;6246.812595622138;7390;7419.517731347445;7467.850642987219;7469.962650186714;6035.094735789667;7237.3486386225495;7315.780526584211;7326.120865496175;7367.115946086289;6668.799936001734;6730.360615741497;6801.625910574768;6806.897896531457;6821.727130914906;4086.3257562501853;4790.089882922233;4902.607791376753;4951.5048495150195;4977.427595826526;4917.754112294442;4967.875803104951;5010.173321401928;5011.624128190328;5017.899642007213 03:40:08 INFO - PROCESS | 1722 | |4;prototype.html;245.7137434888354;303.8571871220526;307.9553464747611;309.9535069739539;312.39863263218297;4435.512093669702;5355.544778693816;5498.835034948948;5556;5638.802641907534;4483.641308695304;5221.702450921592;5226.163813789785;5400.83797486076;5528.650323612459;3536.469529570559;4080.6939479539005;4210.705250632449;4260.488741351027;4287.871363859073;3854.672352850011;4129.855455059073;4274.636655884254;4300.741955482681;4302.579449337784;3391.186115332327;4083.8570650027254;4103.199876024177;4106.979465102685;4113.403556484308;3979.542352629441;4005.679545636342;4073.7963101845085;4134.193832202722;4140.3168477201225;3478.3565040467593;3543.85824567017;3633.582138054131;3651.7808931463933;3673.485712000322;5352.384475785257;5360.624756267063;5399.460053994609;5402.486763757437;5405.405405405402;4141.937870931938;4842.658565589767;4851.248056551205;4896.657233993622;4907.9263811042865;5273.288106105703;5554.666719996813;5816.854578635525;5826.737796799154;5900.527957763412;3775.452559378875;4025.2553277643437;4029.3150164471817;4035.656969157625;4044.472204871955;5099.005693889693;5137.666051706665;5349.331333583302;5457.789595213944;6203.410675985774;3931.5478719947573;3997.380406037069;4080.367543030776;4121.9587804121575;4136.586341365839;4194.3918131870705;4212.789360531961;4215.241256573846;4217.768022758716;4219.670803696826;4071.633552980246;4876.341693871353;4895.653695233839;4928.28539861718;4929.457759646436;4913.48408417111;5652.491275785199;5901.468867801918;5906.291245050621;5992.970035149883;1329.5014369611395;1383.1700979412474;1423.3737155651688;1434.9426022959174;1436.5833908166514;941.773974246176;954.8615450759601;968.3657204531044;978.6868202175235;981.5239608789731;4209.137126889042;4317.525072242051;5364.892702146013;5384.0847056000575;5386.952957875982;5193.584513239008;5944.4352786485215;5975.731092100779;5976.641401515836;5996.640201587918;4379.430674012422;4546.476256125111;4900.019996000814;5366.543843773323;5529.529989950819;2725.8364498130177;2756.3109222694325;3010.1722026442467;3088.336007758343;3146.7797254192446;4939.629527785431;5415.675059496364;5955.285365756137;5963.19496867926;6239.656818875005;5721.885562288814;5864.618799778001;6079.9632123238325;6128.966161749962;6151.415615516519;4886.853394398174;5576.410722943941;5631.887362252732;5725.713714314267;6279.372062793684;5822.706561512302;5953.315368732651;6003.309619393651;6008.339082700812;6034.306054803754;4933.6299777516115;5568.749406276646;5935.821925342247;5977.491913187428;5986.904583395777;5026.949730502794;5397.8110766123;5806.883862322646;5947.123358845705;6351.237851457855;3477.1133360992785;3737.8504859805303;3834.9808250958567;3913.569507354132;3919.4708714324343;5094.847154585368;5663.405342438985;5976.372480889444;6014.143281941043;6036.374177232918;4853.95146048535;5437.374701909331;5658.434156584309;5878.29460464747;5894.646321220569;4738.431388233434;5563.720344320748;5848.707564621667;5870.735816888165;5898.94101058984;4920.269250565971;5403.405625381283;5623.581859800325;5819.701218018943;5844.4155584442915;4364.738115713068;5201.687898726089;5230.137027390438;5757.3954734753925;5967.880642387215;5405.405405405481;5493.752781124779;5790.971045144747;5840.737166827588;5874.706264686663 03:40:08 INFO - PROCESS | 1722 | |5;yui.html;592.7298446948188;627.7394881124334;661.6989269882204;664.143255200791;760.1979911193696;14885.702285954289;15626.76559851603;15670.92164539177;15674.373025078983;15755.921220393897;13809.861901380984;13971.510997115103;14014.019018668698;14238;14242.145471271731;839.7186942374296;864.6627815152095;866.8266346730647;868.3964644572025;868.535333596526;687.1925487552131;827.9420440569163;844.2444012608743;851.940364174508;859.70769938221;900.8018235988072;906.537665790445;909.6952520905471;915.5376534849892;919.9862002069974;885.0441523155009;899.4198741811527;899.9325050621229;901.1124042817819;902.5757893789942;666.5434177588319;667.6194569095618;668.5654324688943;669.64174166821;674.1337381464843;14591.78112328316;14847.183404912725;14877.851221487756;14888.925555372154;14943.1034137951;14245;14279.928600357036;14394;14424.639384015483;14449.78325325121;11744.865986788194;11815.88184118165;11828.290302581787;11869.52521899123;11914.804301763035;3539.752217344787;3616.89149325518;3624.5650521937537;3724.050367156358;3735.4957080794015;101709.491452543;105916.47041764742;106720;109857.45071274672;116115.41942290234;10512.1590272778;10778.73053173677;10962.890371096346;10970.405232929113;11212.439378031066;21018.2643607473;21160.259390921245;21296.148154073973;21317.294616430958;22092.889535552542;7881.684732610746;7956.920430795733;7974.800629984296;7982.321502672222;8115.3913456491;4274.401583778274;4336.869893903125;4338.067315527177;4393.824247030147;4398.274284742982;3566;3710.777353358807;3721.14413684854;3721.497597824259;3728.3289007978824;706.4066184405021;723.4393345157545;726.614894106114;726.6366816591704;741.2402287655088;595.8748662780778;641.1920979565781;669.1602039440471;677.8779819632514;681.5842336174929;8944.597493112695;9361.485118318422;9463.38487998278;9480.478573678514;9544.570494327772;576.7202906590301;594.3580932592789;594.560025581067;609.8889130908274;640.3020707428832;3605.7295702822394;4358.716683415568;4514.819407223675;4526.140033393644;4650.232711602615;113957;115774.42112789382;140746.59253407334;143857;169767;125435.37282313712;129255.35372323079;134664.32667836794;137941;141476;80787.19212807914;81577.59211203906;97763;112023.19942001515;118651.8134818658;8552.657893684183;10037.19702423819;10046.346987445793;10067;10152.187824973837;45009.09981800411;46639.76680116577;53831.73084134554;64059.3594064072;68800.96798547926;86215;99496.50251748695;99639;103877;115338.42330788293;11513.53945842157;14023.088499247517;14108.858911410754;14227.720858302922;14639.560813175196;13693.452261909411;14169.574912752634;14349.928250358682;14639.560813175622;15050;12206;13511.391987360788;13923.2056748898;14020.439182432588;14590.416383344547;13562.93218533901;13815.240161791198;14047.876169906178;14608.196549189897;14609.561713148623;13407.865921341052;13438.126521776054;13541.121397484314;13685.931570342484;13936.303184840921;10902.236843420884;11094.278871873303;11236.752649469976;11481.138914581607;11549.076073913899;10663.4135122569;10979.341239525655;11402.258853174517;11741.76516469683;11833.585824496446 03:40:08 INFO - PROCESS | 1722 | __end_tp_report 03:40:08 INFO - PROCESS | 1722 | __start_cc_report 03:40:08 INFO - PROCESS | 1722 | _x_x_mozilla_cycle_collect,371 03:40:08 INFO - PROCESS | 1722 | __end_cc_report 03:40:08 INFO - PROCESS | 1722 | __startTimestamp1473244808766__endTimestamp 03:40:08 INFO - PROCESS | 1722 | 03:40:08 INFO - PROCESS | 1722 | ------- Summary: start ------- 03:40:08 INFO - PROCESS | 1722 | Number of tests: 6 03:40:08 INFO - PROCESS | 1722 | 03:40:08 INFO - PROCESS | 1722 | [#0] dojo.html Cycles:165 Average:5626.05 Median:5745.74 stddev:655.70 (11.4%) stddev-sans-first:631.52 03:40:08 INFO - PROCESS | 1722 | Values: 3287.2 3353.6 3353.6 3358.4 3380.3 4519.4 5560.2 5565.7 5627.3 5664.9 5469.5 5612.9 5628.2 5700.4 5726.0 4293.6 5055.8 5058.0 5223.2 5284.3 5006.3 5289.4 5305.6 5317.2 5323.5 5087.8 5127.9 5172.2 5206.3 5208.0 5180.9 5193.0 5193.3 5206.9 5223.3 5111.1 5129.9 5140.7 5166.4 5220.7 4431.0 5553.4 5562.0 5571.9 5645.8 6138.0 6175.2 6178.8 6201.3 6206.2 6257.0 6275.3 6276.6 6278.5 6305.6 4313.9 4713.9 5095.2 5622.4 5695.9 5405.1 6260.5 6263.9 6278.2 6311.6 6083.9 6087.8 6135.4 6136.3 6136.6 5500.0 6058.1 6146.4 6197.0 6240.9 5577.6 5638.7 5951.3 6106.9 6146.3 6038.3 6177.3 6184.2 6187.9 6214.4 4861.7 4950.7 4957.2 4974.1 4985.6 4350.5 4385.5 4389.3 4402.6 4429.9 5611.5 5714.6 5724.1 5730.0 5738.9 6072.3 6164.7 6164.8 6169.7 6176.6 5619.4 5665.0 5678.8 5730.9 5754.5 5343.8 5360.0 5368.7 5378.4 5391.6 5537.9 5805.9 5920.4 6084.3 6281.5 6130.2 6133.6 6135.2 6136.1 6167.5 6231.5 6245.1 6252.8 6253.7 6291.7 6074.2 6140.0 6149.9 6166.3 6174.5 5194.5 5680.0 6071.5 6123.5 6148.2 6055.5 6066.5 6098.4 6129.9 6142.2 5870.4 5957.6 6093.4 6093.4 6109.8 4820.4 5843.2 5846.2 5923.9 5928.2 6083.4 6098.6 6104.1 6104.9 6131.0 5745.7 5916.5 5946.6 5952.5 5964.9 03:40:08 INFO - PROCESS | 1722 | 03:40:08 INFO - PROCESS | 1722 | [#1] ext.html Cycles:180 Average:70284.30 Median:39212.91 stddev:157584.05 (401.9%) stddev-sans-first:157938.95 03:40:08 INFO - PROCESS | 1722 | Values: 475.0 512.9 513.6 525.3 539.9 37849.8 39707.4 39958.2 40009.0 40071.0 40474.0 41168.0 41253.0 41296.8 41358.0 9497.8 9694.7 9707.9 9731.6 9743.5 9559.0 9680.0 9696.5 9714.5 9723.0 8369.7 9685.5 9711.1 9717.8 9741.5 9699.0 9700.6 9734.1 9737.6 9763.3 9580.3 9599.6 9659.7 9662.4 9678.4 38941.0 39122.0 39303.8 39306.2 39445.8 3256.3 3294.8 3322.7 3340.1 3386.8 30035.9 30363.0 30768.4 30816.5 30819.8 3200.8 3221.8 3231.8 3238.8 3349.4 487107.0 491933.0 493767.0 494258.0 494795.0 57045.0 57399.4 57455.0 57479.7 57602.1 30634.2 33932.3 34046.5 35506.0 60088.4 16089.4 16235.4 16236.6 16241.6 16242.2 10464.3 12468.8 12481.3 12515.0 12527.6 18608.6 18644.2 18650.1 18691.5 18775.5 6125.6 6881.7 8009.8 8037.8 8072.3 849.9 946.7 948.4 1017.9 1105.7 28119.3 29694.6 29760.3 29777.1 29858.6 998.2 1055.5 1137.7 1163.3 1182.6 14163.7 14845.8 15027.3 15100.5 15269.8 586260.0 679991.0 700129.0 700149.0 703484.0 55421.0 55642.0 56001.0 56045.0 56166.7 1344.7 1349.0 1386.8 1389.9 1391.2 27865.7 28655.0 28964.1 29047.4 29568.4 47526.5 47970.0 47971.0 47986.8 48014.8 580319.0 582122.0 583854.0 584096.1 585071.0 25643.0 25656.6 25926.5 26030.3 26129.7 45268.3 45503.8 46016.0 47094.8 47139.3 37837.6 38027.2 38129.0 38892.0 40121.8 33610.0 37734.1 37870.0 38116.6 39315.2 37496.0 37619.0 37847.2 38374.6 38538.8 28404.6 31493.9 34189.5 34265.0 34315.0 25161.9 25588.0 25645.4 25709.5 25944.6 03:40:08 INFO - PROCESS | 1722 | 03:40:08 INFO - PROCESS | 1722 | [#2] jquery.html Cycles:180 Average:34507.67 Median:1595.25 stddev:95462.32 (5984.2%) stddev-sans-first:95695.70 03:40:08 INFO - PROCESS | 1722 | Values: 367.2 371.6 372.4 381.1 398.0 19360.7 19422.3 19627.8 20708.1 20839.2 19914.5 20204.0 20964.6 21015.4 21241.7 5299.4 5934.1 6098.0 6179.1 6193.4 6039.6 6047.0 6164.8 6168.9 6182.7 5855.2 6061.7 6092.1 6130.2 6136.1 5813.7 5925.0 5927.1 5942.7 6009.4 5224.3 5264.1 5268.2 5278.0 5289.8 22404.3 23044.0 23172.0 25670.6 26113.9 2108.7 2344.4 2476.8 2502.3 2823.3 9727.7 9970.0 10205.5 12273.9 13831.4 1728.8 1775.6 1898.4 1906.3 1936.7 123572.0 124042.0 124332.8 125721.4 126841.0 10924.0 11332.8 11367.3 11399.8 11550.3 14082.0 14114.6 14138.2 14138.3 14140.0 8093.6 8307.0 8348.4 8431.2 8488.1 8199.8 8257.3 8292.0 8395.4 8424.5 4333.8 4469.4 4471.9 4474.7 4562.4 1575.6 1629.5 1873.3 1938.5 1939.6 805.3 920.1 922.7 976.3 977.3 20235.7 21147.3 21273.5 21297.8 21318.4 898.7 926.7 937.9 940.6 959.4 7573.3 8124.0 10111.5 11226.5 11757.4 559286.0 561865.0 571244.0 573538.0 578508.0 116219.0 119062.4 119414.8 132978.0 135848.0 766.9 975.6 1017.3 1031.0 1035.1 17091.1 18771.6 19032.0 19285.4 19346.3 57046.7 57844.7 58308.7 58546.7 60158.1 68492.0 85623.6 86090.6 86667.0 87992.0 7625.9 7806.2 7999.2 8021.6 8100.6 9707.6 10776.7 12010.6 12885.2 13020.5 11217.4 11224.0 11256.8 11507.0 11570.2 10956.0 11064.6 11379.8 11469.7 11758.2 9795.4 11033.2 11781.1 11821.8 11830.8 6869.8 6890.7 7208.7 7363.0 7840.4 9591.9 11114.9 11451.5 11572.9 11611.2 03:40:08 INFO - PROCESS | 1722 | 03:40:08 INFO - PROCESS | 1722 | [#3] mootools.html Cycles:180 Average:5635.12 Median:5010.90 stddev:3151.40 (62.9%) stddev-sans-first:3144.96 03:40:08 INFO - PROCESS | 1722 | Values: 1505.5 1531.8 1731.7 1751.8 1869.8 7771.8 8345.4 8504.4 8893.4 8914.5 8555.0 8631.0 8637.6 8645.4 8661.0 2341.0 2387.2 2390.4 2391.8 2394.8 2337.9 2380.0 2381.4 2388.4 2394.8 2337.5 2352.4 2363.7 2367.1 2375.1 2140.5 2147.3 2354.0 2357.1 2364.0 2052.3 2339.1 2339.1 2347.7 2351.8 8719.9 8793.3 8797.5 8802.4 8810.2 4560.5 5168.9 5186.1 5281.8 5333.9 3462.6 3531.6 3577.6 3616.4 3626.7 1038.4 1072.7 1074.0 1080.6 1093.3 11402.9 11478.5 11513.4 11527.7 11561.5 4548.4 4629.0 4812.9 4825.3 4827.8 9707.9 9734.7 9818.5 9830.7 9839.6 5306.6 5322.5 5327.6 5337.8 5342.2 4363.3 4705.5 4851.5 4870.8 4893.4 3234.3 3928.7 3988.3 4001.7 4030.1 1278.8 1287.1 1288.7 1290.0 1303.9 794.5 864.7 893.9 912.0 921.9 5702.7 5713.8 5729.4 5829.3 6045.2 837.4 896.8 936.1 938.5 943.1 3745.8 3807.4 3814.0 3859.4 3931.5 10350.2 10576.0 10580.6 10669.8 10703.8 10563.8 10566.8 10660.3 10666.4 10667.8 7730.3 8009.4 8125.3 8182.3 8193.7 5802.7 5921.1 5951.6 6035.8 6053.4 9415.7 9824.6 9840.5 9851.8 9875.4 9945.4 9945.9 9998.4 10143.7 10222.7 6819.1 7217.0 7321.7 7370.6 7454.5 8219.0 8229.5 8255.3 8266.8 8271.7 6246.8 7390.0 7419.5 7467.9 7470.0 6035.1 7237.3 7315.8 7326.1 7367.1 6668.8 6730.4 6801.6 6806.9 6821.7 4086.3 4790.1 4902.6 4951.5 4977.4 4917.8 4967.9 5010.2 5011.6 5017.9 03:40:08 INFO - PROCESS | 1722 | 03:40:08 INFO - PROCESS | 1722 | [#4] prototype.html Cycles:180 Average:4649.23 Median:5211.70 stddev:1419.74 (27.2%) stddev-sans-first:1384.72 03:40:08 INFO - PROCESS | 1722 | Values: 245.7 303.9 308.0 310.0 312.4 4435.5 5355.5 5498.8 5556.0 5638.8 4483.6 5221.7 5226.2 5400.8 5528.7 3536.5 4080.7 4210.7 4260.5 4287.9 3854.7 4129.9 4274.6 4300.7 4302.6 3391.2 4083.9 4103.2 4107.0 4113.4 3979.5 4005.7 4073.8 4134.2 4140.3 3478.4 3543.9 3633.6 3651.8 3673.5 5352.4 5360.6 5399.5 5402.5 5405.4 4141.9 4842.7 4851.2 4896.7 4907.9 5273.3 5554.7 5816.9 5826.7 5900.5 3775.5 4025.3 4029.3 4035.7 4044.5 5099.0 5137.7 5349.3 5457.8 6203.4 3931.5 3997.4 4080.4 4122.0 4136.6 4194.4 4212.8 4215.2 4217.8 4219.7 4071.6 4876.3 4895.7 4928.3 4929.5 4913.5 5652.5 5901.5 5906.3 5993.0 1329.5 1383.2 1423.4 1434.9 1436.6 941.8 954.9 968.4 978.7 981.5 4209.1 4317.5 5364.9 5384.1 5387.0 5193.6 5944.4 5975.7 5976.6 5996.6 4379.4 4546.5 4900.0 5366.5 5529.5 2725.8 2756.3 3010.2 3088.3 3146.8 4939.6 5415.7 5955.3 5963.2 6239.7 5721.9 5864.6 6080.0 6129.0 6151.4 4886.9 5576.4 5631.9 5725.7 6279.4 5822.7 5953.3 6003.3 6008.3 6034.3 4933.6 5568.7 5935.8 5977.5 5986.9 5026.9 5397.8 5806.9 5947.1 6351.2 3477.1 3737.9 3835.0 3913.6 3919.5 5094.8 5663.4 5976.4 6014.1 6036.4 4854.0 5437.4 5658.4 5878.3 5894.6 4738.4 5563.7 5848.7 5870.7 5898.9 4920.3 5403.4 5623.6 5819.7 5844.4 4364.7 5201.7 5230.1 5757.4 5967.9 5405.4 5493.8 5791.0 5840.7 5874.7 03:40:08 INFO - PROCESS | 1722 | 03:40:08 INFO - PROCESS | 1722 | [#5] yui.html Cycles:180 Average:24394.77 Median:21306.72 stddev:38764.33 (181.9%) stddev-sans-first:38831.88 03:40:08 INFO - PROCESS | 1722 | Values: 592.7 627.7 661.7 664.1 760.2 14885.7 15626.8 15670.9 15674.4 15755.9 13809.9 13971.5 14014.0 14238.0 14242.1 839.7 864.7 866.8 868.4 868.5 687.2 827.9 844.2 851.9 859.7 900.8 906.5 909.7 915.5 920.0 885.0 899.4 899.9 901.1 902.6 666.5 667.6 668.6 669.6 674.1 14591.8 14847.2 14877.9 14888.9 14943.1 14245.0 14279.9 14394.0 14424.6 14449.8 11744.9 11815.9 11828.3 11869.5 11914.8 3539.8 3616.9 3624.6 3724.1 3735.5 101709.5 105916.5 106720.0 109857.5 116115.4 10512.2 10778.7 10962.9 10970.4 11212.4 21018.3 21160.3 21296.1 21317.3 22092.9 7881.7 7956.9 7974.8 7982.3 8115.4 4274.4 4336.9 4338.1 4393.8 4398.3 3566.0 3710.8 3721.1 3721.5 3728.3 706.4 723.4 726.6 726.6 741.2 595.9 641.2 669.2 677.9 681.6 8944.6 9361.5 9463.4 9480.5 9544.6 576.7 594.4 594.6 609.9 640.3 3605.7 4358.7 4514.8 4526.1 4650.2 113957.0 115774.4 140746.6 143857.0 169767.0 125435.4 129255.4 134664.3 137941.0 141476.0 80787.2 81577.6 97763.0 112023.2 118651.8 8552.7 10037.2 10046.3 10067.0 10152.2 45009.1 46639.8 53831.7 64059.4 68801.0 86215.0 99496.5 99639.0 103877.0 115338.4 11513.5 14023.1 14108.9 14227.7 14639.6 13693.5 14169.6 14349.9 14639.6 15050.0 12206.0 13511.4 13923.2 14020.4 14590.4 13562.9 13815.2 14047.9 14608.2 14609.6 13407.9 13438.1 13541.1 13685.9 13936.3 10902.2 11094.3 11236.8 11481.1 11549.1 10663.4 10979.3 11402.3 11741.8 11833.6 03:40:08 INFO - PROCESS | 1722 | -------- Summary: end -------- 03:40:08 INFO - PROCESS | 1722 | 03:40:09 INFO - TEST-INFO | 1722: exit 0 03:40:09 INFO - TEST-OK | dromaeo_css | took 1113397ms 03:40:09 INFO - TEST-START | kraken 03:40:09 INFO - operating with platform_type : mac_ 03:40:09 INFO - Initialising browser for kraken test... 03:40:09 INFO - TEST-INFO | started process 1862 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/tmp6JIijV/profile http://localhost:49267/getInfo.html) 03:40:12 INFO - PROCESS | 1862 | __metrics Screen width/height:1600/1200 03:40:12 INFO - PROCESS | 1862 | colorDepth:24 03:40:12 INFO - PROCESS | 1862 | Browser inner width/height: 1024/689 03:40:12 INFO - PROCESS | 1862 | __metrics 03:40:12 ERROR - PROCESS | 1862 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2998: TypeError: this.tabs is undefined 03:40:12 INFO - TEST-INFO | 1862: exit 0 03:40:12 INFO - Browser initialized. 03:40:12 INFO - Running cycle 1/1 for kraken test... 03:40:12 INFO - TEST-INFO | started process 1865 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/T/tmp6JIijV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:40:24 INFO - PROCESS | 1865 | RSS: Main: 243875840 03:40:24 INFO - PROCESS | 1865 | 03:40:27 INFO - PROCESS | 1865 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection) 03:40:28 INFO - PROCESS | 1865 | RSS: Main: 256679936 03:40:28 INFO - PROCESS | 1865 | 03:40:31 INFO - PROCESS | 1865 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-dft) 03:40:31 INFO - PROCESS | 1865 | RSS: Main: 265441280 03:40:31 INFO - PROCESS | 1865 | 03:40:35 INFO - PROCESS | 1865 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-fft) 03:40:35 INFO - PROCESS | 1865 | RSS: Main: 263196672 03:40:35 INFO - PROCESS | 1865 | 03:40:38 INFO - PROCESS | 1865 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator) 03:40:38 INFO - PROCESS | 1865 | RSS: Main: 264409088 03:40:38 INFO - PROCESS | 1865 | 03:40:40 INFO - PROCESS | 1865 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:40:41 INFO - PROCESS | 1865 | RSS: Main: 264835072 03:40:41 INFO - PROCESS | 1865 | 03:40:44 INFO - PROCESS | 1865 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom) 03:40:44 INFO - PROCESS | 1865 | RSS: Main: 265400320 03:40:44 INFO - PROCESS | 1865 | 03:40:47 INFO - PROCESS | 1865 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate) 03:40:48 INFO - PROCESS | 1865 | RSS: Main: 265658368 03:40:48 INFO - PROCESS | 1865 | 03:40:51 INFO - PROCESS | 1865 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial) 03:40:52 INFO - PROCESS | 1865 | RSS: Main: 267485184 03:40:52 INFO - PROCESS | 1865 | 03:40:53 INFO - PROCESS | 1865 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:40:54 INFO - PROCESS | 1865 | RSS: Main: 266588160 03:40:54 INFO - PROCESS | 1865 | 03:40:56 INFO - PROCESS | 1865 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:40:57 INFO - PROCESS | 1865 | RSS: Main: 266375168 03:40:57 INFO - PROCESS | 1865 | 03:40:59 INFO - PROCESS | 1865 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:41:00 INFO - PROCESS | 1865 | RSS: Main: 266735616 03:41:00 INFO - PROCESS | 1865 | 03:41:02 INFO - PROCESS | 1865 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:41:03 INFO - PROCESS | 1865 | RSS: Main: 266985472 03:41:03 INFO - PROCESS | 1865 | 03:41:06 INFO - PROCESS | 1865 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:41:07 INFO - PROCESS | 1865 | RSS: Main: 267112448 03:41:07 INFO - PROCESS | 1865 | 03:41:08 INFO - PROCESS | 1865 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49267/tests/kraken/driver.html?testName=ai-astar) 03:41:09 INFO - PROCESS | 1865 | RSS: Main: 268820480 03:41:09 INFO - PROCESS | 1865 | 03:41:09 INFO - PROCESS | 1865 | __start_tp_report 03:41:09 INFO - PROCESS | 1865 | _x_x_mozilla_page_load 03:41:09 INFO - PROCESS | 1865 | _x_x_mozilla_page_load_details 03:41:09 INFO - PROCESS | 1865 | |i|pagename|runs| 03:41:09 INFO - PROCESS | 1865 | |0;ai-astar;114;155;148;116;116;101;116;117;116;115 03:41:09 INFO - PROCESS | 1865 | |1;audio-beat-detection;144;107;132;155;145;162;130;114;160;112 03:41:09 INFO - PROCESS | 1865 | |2;audio-dft;195;204;206;221;213;205;232;199;216;254 03:41:09 INFO - PROCESS | 1865 | |3;audio-fft;65;96;103;169;83;51;63;80;160;86 03:41:09 INFO - PROCESS | 1865 | |4;audio-oscillator;70;81;113;74;100;109;142;55;85;137 03:41:09 INFO - PROCESS | 1865 | |5;imaging-gaussian-blur;89;87;89;86;90;86;129;87;137;130 03:41:09 INFO - PROCESS | 1865 | |6;imaging-darkroom;95;97;96;94;95;95;101;151;95;97 03:41:09 INFO - PROCESS | 1865 | |7;imaging-desaturate;102;86;126;144;133;145;133;97;92;140 03:41:09 INFO - PROCESS | 1865 | |8;json-parse-financial;56;60;57;58;58;56;58;59;58;56 03:41:09 INFO - PROCESS | 1865 | |9;json-stringify-tinderbox;68;69;71;73;73;71;71;68;72;70 03:41:09 INFO - PROCESS | 1865 | |10;stanford-crypto-aes;112;89;93;88;87;90;106;90;80;106 03:41:09 INFO - PROCESS | 1865 | |11;stanford-crypto-ccm;156;131;149;159;136;138;137;141;183;163 03:41:09 INFO - PROCESS | 1865 | |12;stanford-crypto-pbkdf2;174;175;175;170;177;175;173;174;174;175 03:41:09 INFO - PROCESS | 1865 | |13;stanford-crypto-sha256-iterative;72;71;68;67;71;68;68;72;75;68 03:41:09 INFO - PROCESS | 1865 | __end_tp_report 03:41:09 INFO - PROCESS | 1865 | __start_cc_report 03:41:09 INFO - PROCESS | 1865 | _x_x_mozilla_cycle_collect,985 03:41:09 INFO - PROCESS | 1865 | __end_cc_report 03:41:09 INFO - PROCESS | 1865 | __startTimestamp1473244869185__endTimestamp 03:41:09 INFO - PROCESS | 1865 | 03:41:09 INFO - PROCESS | 1865 | ------- Summary: start ------- 03:41:09 INFO - PROCESS | 1865 | Number of tests: 14 03:41:09 INFO - PROCESS | 1865 | 03:41:09 INFO - PROCESS | 1865 | [#0] ai-astar Cycles:10 Average:121.40 Median:116.00 stddev:16.61 (14.3%) stddev-sans-first:17.41 03:41:09 INFO - PROCESS | 1865 | Values: 114.0 155.0 148.0 116.0 116.0 101.0 116.0 117.0 116.0 115.0 03:41:09 INFO - PROCESS | 1865 | 03:41:09 INFO - PROCESS | 1865 | [#1] audio-beat-detection Cycles:10 Average:136.10 Median:144.50 stddev:20.31 (14.1%) stddev-sans-first:21.34 03:41:09 INFO - PROCESS | 1865 | Values: 144.0 107.0 132.0 155.0 145.0 162.0 130.0 114.0 160.0 112.0 03:41:09 INFO - PROCESS | 1865 | 03:41:09 INFO - PROCESS | 1865 | [#2] audio-dft Cycles:10 Average:214.50 Median:214.50 stddev:17.66 (8.2%) stddev-sans-first:17.26 03:41:09 INFO - PROCESS | 1865 | Values: 195.0 204.0 206.0 221.0 213.0 205.0 232.0 199.0 216.0 254.0 03:41:09 INFO - PROCESS | 1865 | 03:41:09 INFO - PROCESS | 1865 | [#3] audio-fft Cycles:10 Average:95.60 Median:72.50 stddev:39.54 (54.5%) stddev-sans-first:40.36 03:41:09 INFO - PROCESS | 1865 | Values: 65.0 96.0 103.0 169.0 83.0 51.0 63.0 80.0 160.0 86.0 03:41:09 INFO - PROCESS | 1865 | 03:41:09 INFO - PROCESS | 1865 | [#4] audio-oscillator Cycles:10 Average:96.60 Median:62.50 stddev:28.78 (46.0%) stddev-sans-first:28.87 03:41:09 INFO - PROCESS | 1865 | Values: 70.0 81.0 113.0 74.0 100.0 109.0 142.0 55.0 85.0 137.0 03:41:09 INFO - PROCESS | 1865 | 03:41:09 INFO - PROCESS | 1865 | [#5] imaging-gaussian-blur Cycles:10 Average:101.00 Median:87.00 stddev:21.53 (24.7%) stddev-sans-first:22.39 03:41:09 INFO - PROCESS | 1865 | Values: 89.0 87.0 89.0 86.0 90.0 86.0 129.0 87.0 137.0 130.0 03:41:09 INFO - PROCESS | 1865 | 03:41:09 INFO - PROCESS | 1865 | [#6] imaging-darkroom Cycles:10 Average:101.60 Median:95.00 stddev:17.47 (18.4%) stddev-sans-first:18.36 03:41:09 INFO - PROCESS | 1865 | Values: 95.0 97.0 96.0 94.0 95.0 95.0 101.0 151.0 95.0 97.0 03:41:09 INFO - PROCESS | 1865 | 03:41:09 INFO - PROCESS | 1865 | [#7] imaging-desaturate Cycles:10 Average:119.80 Median:144.50 stddev:23.02 (15.9%) stddev-sans-first:23.49 03:41:09 INFO - PROCESS | 1865 | Values: 102.0 86.0 126.0 144.0 133.0 145.0 133.0 97.0 92.0 140.0 03:41:09 INFO - PROCESS | 1865 | 03:41:09 INFO - PROCESS | 1865 | [#8] json-parse-financial Cycles:10 Average:57.60 Median:58.00 stddev:1.35 (2.3%) stddev-sans-first:1.30 03:41:09 INFO - PROCESS | 1865 | Values: 56.0 60.0 57.0 58.0 58.0 56.0 58.0 59.0 58.0 56.0 03:41:09 INFO - PROCESS | 1865 | 03:41:09 INFO - PROCESS | 1865 | [#9] json-stringify-tinderbox Cycles:10 Average:70.60 Median:71.00 stddev:1.84 (2.6%) stddev-sans-first:1.69 03:41:09 INFO - PROCESS | 1865 | Values: 68.0 69.0 71.0 73.0 73.0 71.0 71.0 68.0 72.0 70.0 03:41:09 INFO - PROCESS | 1865 | 03:41:09 INFO - PROCESS | 1865 | [#10] stanford-crypto-aes Cycles:10 Average:94.10 Median:88.50 stddev:10.28 (11.6%) stddev-sans-first:8.62 03:41:09 INFO - PROCESS | 1865 | Values: 112.0 89.0 93.0 88.0 87.0 90.0 106.0 90.0 80.0 106.0 03:41:09 INFO - PROCESS | 1865 | 03:41:09 INFO - PROCESS | 1865 | [#11] stanford-crypto-ccm Cycles:10 Average:149.30 Median:152.50 stddev:16.06 (10.5%) stddev-sans-first:16.85 03:41:09 INFO - PROCESS | 1865 | Values: 156.0 131.0 149.0 159.0 136.0 138.0 137.0 141.0 183.0 163.0 03:41:09 INFO - PROCESS | 1865 | 03:41:09 INFO - PROCESS | 1865 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.20 Median:175.00 stddev:1.81 (1.0%) stddev-sans-first:1.92 03:41:09 INFO - PROCESS | 1865 | Values: 174.0 175.0 175.0 170.0 177.0 175.0 173.0 174.0 174.0 175.0 03:41:09 INFO - PROCESS | 1865 | 03:41:09 INFO - PROCESS | 1865 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.00 Median:71.00 stddev:2.58 (3.6%) stddev-sans-first:2.64 03:41:09 INFO - PROCESS | 1865 | Values: 72.0 71.0 68.0 67.0 71.0 68.0 68.0 72.0 75.0 68.0 03:41:09 INFO - PROCESS | 1865 | -------- Summary: end -------- 03:41:09 INFO - PROCESS | 1865 | 03:41:09 INFO - TEST-INFO | 1865: exit 0 03:41:10 INFO - TEST-OK | kraken | took 60532ms 03:41:10 INFO - SUITE-END | took 1173s 03:41:10 INFO - Completed test suite (00:19:34) 03:41:10 INFO - javascript benchmark 03:41:10 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7322.151458034253, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3287.1617737476954, 3353.564036675232, 3353.5640366752323, 3358.4458564336887, 3380.290139070795, 4519.367288579598, 5560.221568980341, 5565.666060036396, 5627.324721033471, 5664.886702265952, 5469.54593579576, 5612.915806262899, 5628.212050312949, 5700.401457846918, 5726.0, 4293.592108749664, 5055.823046193384, 5057.988402319533, 5223.242629818693, 5284.339457567804, 5006.3241462402475, 5289.418164001957, 5305.628605997582, 5317.202419637047, 5323.547498462635, 5087.821926232564, 5127.871803204913, 5172.19830926207, 5206.349206349207, 5208.0, 5180.870478238036, 5192.974035129838, 5193.324867767204, 5206.947930520684, 5223.268742376071, 5111.105556527593, 5129.897402051975, 5140.691558506502, 5166.354205724285, 5220.660657057279, 4430.955690443118, 5553.4450353900975, 5562.0, 5571.944280557183, 5645.8023969161295, 6137.969310153465, 6175.166352542395, 6178.84552886177, 6201.255849298113, 6206.193194884636, 6256.96871515644, 6275.309715931244, 6276.623402595813, 6278.497720182374, 6305.5901366411035, 4313.892152696177, 4713.85858424241, 5095.210242412432, 5622.353429355636, 5695.943040569541, 5405.108157154104, 6260.530460215502, 6263.874722505524, 6278.183836101278, 6311.558190926591, 6083.878322433527, 6087.756489740449, 6135.417135372132, 6136.2636483622255, 6136.631802091889, 5499.972500137474, 6058.0610005449225, 6146.416090471398, 6196.977498712662, 6240.876642204446, 5577.633479797394, 5638.718064096779, 5951.256092988377, 6106.94607981953, 6146.279041868284, 6038.33578306386, 6177.2587289524645, 6184.165137706352, 6187.938120618736, 6214.40963108504, 4861.708297502161, 4950.653454258239, 4957.157283261854, 4974.104661161025, 4985.551300382983, 4350.499692535399, 4385.473743150842, 4389.275769497997, 4402.603765661106, 4429.933550996738, 5611.523020543218, 5714.5999780015, 5724.112762521816, 5730.0, 5738.885222295531, 6072.332043475214, 6164.722587483574, 6164.815055548341, 6169.691515424211, 6176.598521096204, 5619.4099619540175, 5665.0, 5678.829635110953, 5730.885382292331, 5754.510866576305, 5343.839684809462, 5360.0, 5368.704721240291, 5378.408375078739, 5391.649542779707, 5537.861553461195, 5805.912911306334, 5920.437558431942, 6084.300305464928, 6281.466075383552, 6130.184124957687, 6133.570650054453, 6135.1776484982, 6136.059583474799, 6167.537434692326, 6231.470325022424, 6245.125682404558, 6252.843678908063, 6253.687315634109, 6291.685415729104, 6074.1799857020505, 6140.0, 6149.907751383822, 6166.321704612579, 6174.475169610633, 5194.4805519447755, 5680.00599895025, 6071.453569178795, 6123.479504242011, 6148.231471066117, 6055.485283750931, 6066.545009124422, 6098.438422643064, 6129.9387006131155, 6142.201513803177, 5870.412958704095, 5957.582969192288, 6093.421124993119, 6093.421124993119, 6109.816705498843, 4820.39745031871, 5843.2403787507355, 5846.210761547137, 5923.851903702442, 5928.229330187048, 6083.391660834058, 6098.634081955097, 6104.145419641165, 6104.908426373519, 6131.019036954066, 5745.74144163522, 5916.526677865674, 5946.643201407929, 5952.464278214981, 5964.880702385841], "value": 5586.232312907078, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [474.9833755818547, 512.9384473863137, 513.5634710496078, 525.2803658987189, 539.9417142400894, 37849.81075094624, 39707.40438893412, 39958.2008359833, 40008.99977500557, 40071.0, 40474.0, 41168.0, 41252.96867578312, 41296.793516032456, 41358.0, 9497.810043799138, 9694.709158725214, 9707.854382184272, 9731.610735570568, 9743.464109473976, 9559.044095590454, 9680.0, 9696.51517424126, 9714.514274286294, 9723.027697230256, 8369.748907532783, 9685.467299298536, 9711.12599866012, 9717.757056073619, 9741.512924353789, 9699.030096990315, 9700.563474643623, 9734.123928846368, 9737.56180971858, 9763.316567840255, 9580.329376943617, 9599.60805683179, 9659.71020869375, 9662.3719458235, 9678.370905891095, 38941.0, 39122.02194945149, 39303.80348098241, 39306.21387572261, 39445.80277098625, 3256.348730253935, 3294.8187849668257, 3322.7009569138895, 3340.0981734931675, 3386.762926595139, 30035.94874179393, 30363.0, 30768.384632307454, 30816.537751933738, 30819.845900770575, 3200.7599430042605, 3221.838908054588, 3231.7737758356925, 3238.8218647974354, 3349.363620912043, 487107.0, 491933.0, 493767.0, 494258.0, 494795.0, 57045.0, 57399.42600574024, 57455.0, 57479.712601436724, 57602.135967960516, 30634.23414414613, 33932.32135357279, 34046.48930266048, 35506.0, 60088.39911600828, 16089.356425743073, 16235.431759888348, 16236.64568637541, 16241.59396015109, 16242.187890605423, 10464.319819211727, 12468.75062498745, 12481.251124932533, 12514.998800095971, 12527.624171274876, 18608.604354673455, 18644.16101275446, 18650.067496625386, 18691.532711682317, 18775.530611734815, 6125.571210015345, 6881.724731010803, 8009.799755006172, 8037.8392432152195, 8072.273495385443, 849.9405041647026, 946.668665966906, 948.4445509364648, 1017.8676772019738, 1105.723569107723, 28119.297017574725, 29694.554581681292, 29760.33448644899, 29777.10668679943, 29858.552121718192, 998.201438848918, 1055.5355643516828, 1137.726945533066, 1163.3310846263432, 1182.6215611004395, 14163.716725665636, 14845.812335013174, 15027.323770430357, 15100.471483498024, 15269.83858026378, 586260.0, 679991.0, 700129.0, 700149.0, 703484.0, 55421.0, 55642.0, 56001.0, 56045.0, 56166.719166403906, 1344.683999260173, 1348.9932550337185, 1386.7503849307222, 1389.861013898602, 1391.2348208485294, 27865.721342787125, 28655.0, 28964.13107606775, 29047.419051618428, 29568.408631826813, 47526.52473475221, 47970.0, 47971.0, 47986.760066199444, 48014.759926200146, 580319.0, 582122.0, 583854.0, 584096.0795195997, 585071.0, 25643.0, 25656.61515077238, 25926.481470370865, 26030.349241268363, 26129.73870261273, 45268.320975186056, 45503.772481137385, 46016.0, 47094.82262943317, 47139.29291060568, 37837.62162378451, 38027.2394552113, 38129.0, 38892.0, 40121.79939100286, 33609.99170024903, 37734.056648584, 37870.0, 38116.618833811306, 39315.21369572535, 37496.0, 37619.0, 37847.2430551393, 38374.616253837106, 38538.80730596329, 28404.57393139146, 31493.89771358083, 34189.48715769216, 34265.0, 34315.0, 25161.87419062893, 25588.0, 25645.3588660285, 25709.485810283317, 25944.61083083793], "value": 19520.39305770999, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [367.24164600100795, 371.6153780836834, 372.3688348249716, 381.14433097695644, 397.98806035818916, 19360.709589356153, 19422.320218792345, 19627.761419365805, 20708.068136933838, 20839.166433342685, 19914.502137446572, 20204.0, 20964.580708385824, 21015.369538913856, 21241.68137477939, 5299.443558426367, 5934.139549765281, 6098.0, 6179.073139029137, 6193.442590166884, 6039.637621742688, 6047.0, 6164.784232551861, 6168.907466388007, 6182.69086545671, 5855.209546711182, 6061.727222275008, 6092.11664308675, 6130.233720784902, 6136.079588061782, 5813.709314534256, 5924.963131451978, 5927.081302398114, 5942.732577034044, 6009.399060094, 5224.320838290998, 5264.078786212397, 5268.157094864803, 5277.973610131963, 5289.814856480005, 22404.32787016392, 23044.0, 23172.0, 25670.614940775904, 26113.869430652914, 2108.652072408066, 2344.4490544721975, 2476.839005464639, 2502.349389158832, 2823.251838262862, 9727.65953191635, 9969.950150249275, 10205.540750666238, 12273.877261227452, 13831.446742130303, 1728.8271172882612, 1775.6448710258, 1898.4399602117408, 1906.2851430713483, 1936.6998115292151, 123572.0, 124042.0, 124332.75667243302, 125721.37139314336, 126841.0, 10924.0, 11332.830007549728, 11367.261128026652, 11399.829002564968, 11550.306981581132, 14082.0, 14114.576562703136, 14138.151710897379, 14138.293085345691, 14140.0, 8093.595320233965, 8306.958465207636, 8348.373871959628, 8431.241188293083, 8488.066312705598, 8199.754007379788, 8257.256846883809, 8292.00495940491, 8395.370347223861, 8424.53665048416, 4333.761643109659, 4469.351943968107, 4471.888202794956, 4474.664400170001, 4562.361269422284, 1575.6297270141508, 1629.4541328654796, 1873.3162395725735, 1938.5347516596198, 1939.6023815117867, 805.3355981315439, 920.1120918313859, 922.7324076018014, 976.2824324121765, 977.3012296208245, 20235.696464553042, 21147.25984590532, 21273.468163296042, 21297.78702212958, 21318.360449186548, 898.7393655839865, 926.6979893249994, 937.9062093790703, 940.6284517615503, 959.3572306554626, 7573.2805383488485, 8123.9593802031795, 10111.494425278706, 11226.494807733508, 11757.412129393497, 559286.0, 561865.0, 571244.0, 573538.0, 578508.0, 116219.0, 119062.40468797775, 119414.8058519421, 132978.0, 135848.0, 766.8696321625337, 975.6000039983572, 1017.3488967061087, 1030.9742256443799, 1035.0787798859021, 17091.05999170008, 18771.624567508847, 19032.0, 19285.4214373569, 19346.32287869918, 57046.7147664259, 57844.71077644585, 58308.70845645745, 58546.707266463396, 60158.09762853648, 68492.0, 85623.5718821402, 86090.56954715187, 86667.0, 87992.0, 7625.885611715939, 7806.1803510632835, 7999.200079992187, 8021.558814265271, 8100.635471403683, 9707.6116955321, 10776.67669969902, 12010.579629712918, 12885.162464439782, 13020.544280950122, 11217.383043932336, 11224.0, 11256.83114753263, 11507.0, 11570.247933884271, 10956.0, 11064.612738554433, 11379.772404552028, 11469.713257168638, 11758.176927614984, 9795.412275263507, 11033.227674062739, 11781.057515398576, 11821.76356472883, 11830.763384732429, 6869.828254293682, 6890.724371025103, 7208.7116515340895, 7362.9631851840395, 7840.411969102226, 9591.90408095938, 11114.944425277821, 11451.484683189443, 11572.94213528927, 11611.187216894736], "value": 9521.08910448384, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1505.4655597262972, 1531.831498535161, 1731.714267145921, 1751.7810273715777, 1869.7849747279067, 7771.766846994585, 8345.37409694273, 8504.404691671585, 8893.421927574704, 8914.50970196639, 8554.95722521388, 8631.0, 8637.568121593911, 8645.438046526971, 8660.956695216515, 2340.9531809363802, 2387.152560840894, 2390.40239940015, 2391.820613453989, 2394.7605239476, 2337.94155146121, 2380.0360853854218, 2381.3927448500694, 2388.4148383645943, 2394.820388470863, 2337.4857531343077, 2352.3648614873973, 2363.6927199464044, 2367.088670861722, 2375.0737212487143, 2140.4755834820567, 2147.259195577531, 2354.0348457132495, 2357.0925193800426, 2363.988180059106, 2052.2509284111343, 2339.1345202275265, 2339.146211632759, 2347.6830627865193, 2351.776581224781, 8719.912800872036, 8793.296536277083, 8797.47215167092, 8802.427842190302, 8810.207081362643, 4560.498345182027, 5168.9224661630105, 5186.118359878792, 5281.815136470205, 5333.919991200135, 3462.6364231755806, 3531.611522732497, 3577.5528058992627, 3616.3671357512594, 3626.746127771057, 1038.4132964874898, 1072.6996440996534, 1074.049466222391, 1080.556971641631, 1093.2565855218447, 11402.942985285103, 11478.483468243949, 11513.366764827932, 11527.711807204803, 11561.479733411932, 4548.408706868152, 4629.0, 4812.8796780080775, 4825.276208568673, 4827.806887724522, 9707.854382184272, 9734.659286925065, 9818.459984700767, 9830.655927042519, 9839.55721992512, 5306.575473962073, 5322.494363035506, 5327.627066105335, 5337.839864804062, 5342.2253773202665, 4363.2800587903275, 4705.505921878222, 4851.539103785135, 4870.82952096682, 4893.437254715754, 3234.2884565395575, 3928.685705143582, 3988.3020471417385, 4001.7198796084576, 4030.1133750574827, 1278.7826069568196, 1287.0604458745167, 1288.664947113757, 1289.9871001290055, 1303.9413226404833, 794.4637369775379, 864.7449002544264, 893.9106089391009, 912.0, 921.9032001639865, 5702.657840529581, 5713.771449142071, 5729.427057294237, 5829.329627092854, 6045.183900173508, 837.4389159263308, 896.789254525186, 936.1481052242426, 938.4556956964944, 943.0663642993525, 3745.793981331053, 3807.4479200516325, 3813.961860381416, 3859.421086836997, 3931.4692516509904, 10350.171986241234, 10576.048155666027, 10580.57677692899, 10669.78660426787, 10703.83944240837, 10563.841542376871, 10566.841497377545, 10660.25378223517, 10666.36001839892, 10667.83998240027, 7730.342920851791, 8009.439339246197, 8125.268725814587, 8182.304504117216, 8193.713220037389, 5802.7098645066735, 5921.111833225051, 5951.613145145551, 6035.818925432244, 6053.424924632153, 9415.717528474157, 9824.607015719292, 9840.458774767456, 9851.753706157117, 9875.40747555149, 9945.353552019096, 9945.900540994497, 9998.350107243006, 10143.7464063399, 10222.744431389276, 6819.147606549182, 7217.025701530226, 7321.707131714885, 7370.5946172961, 7454.515456495311, 8218.958905205436, 8229.506229626242, 8255.339572834278, 8266.793330166794, 8271.669133234604, 6246.812595622138, 7390.0, 7419.517731347445, 7467.850642987219, 7469.962650186714, 6035.094735789667, 7237.3486386225495, 7315.780526584211, 7326.120865496175, 7367.115946086289, 6668.799936001734, 6730.360615741497, 6801.625910574768, 6806.897896531457, 6821.727130914906, 4086.3257562501853, 4790.089882922233, 4902.607791376753, 4951.5048495150195, 4977.427595826526, 4917.754112294442, 4967.875803104951, 5010.173321401928, 5011.624128190328, 5017.899642007213], "value": 4537.607654913806, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [245.7137434888354, 303.8571871220526, 307.9553464747611, 309.9535069739539, 312.39863263218297, 4435.512093669702, 5355.544778693816, 5498.835034948948, 5556.0, 5638.802641907534, 4483.641308695304, 5221.702450921592, 5226.163813789785, 5400.83797486076, 5528.650323612459, 3536.469529570559, 4080.6939479539005, 4210.705250632449, 4260.488741351027, 4287.871363859073, 3854.672352850011, 4129.855455059073, 4274.636655884254, 4300.741955482681, 4302.579449337784, 3391.186115332327, 4083.8570650027254, 4103.199876024177, 4106.979465102685, 4113.403556484308, 3979.542352629441, 4005.679545636342, 4073.7963101845085, 4134.193832202722, 4140.3168477201225, 3478.3565040467593, 3543.85824567017, 3633.582138054131, 3651.7808931463933, 3673.485712000322, 5352.384475785257, 5360.624756267063, 5399.460053994609, 5402.486763757437, 5405.405405405402, 4141.937870931938, 4842.658565589767, 4851.248056551205, 4896.657233993622, 4907.9263811042865, 5273.288106105703, 5554.666719996813, 5816.854578635525, 5826.737796799154, 5900.527957763412, 3775.452559378875, 4025.2553277643437, 4029.3150164471817, 4035.656969157625, 4044.472204871955, 5099.005693889693, 5137.666051706665, 5349.331333583302, 5457.789595213944, 6203.410675985774, 3931.5478719947573, 3997.380406037069, 4080.367543030776, 4121.9587804121575, 4136.586341365839, 4194.3918131870705, 4212.789360531961, 4215.241256573846, 4217.768022758716, 4219.670803696826, 4071.633552980246, 4876.341693871353, 4895.653695233839, 4928.28539861718, 4929.457759646436, 4913.48408417111, 5652.491275785199, 5901.468867801918, 5906.291245050621, 5992.970035149883, 1329.5014369611395, 1383.1700979412474, 1423.3737155651688, 1434.9426022959174, 1436.5833908166514, 941.773974246176, 954.8615450759601, 968.3657204531044, 978.6868202175235, 981.5239608789731, 4209.137126889042, 4317.525072242051, 5364.892702146013, 5384.0847056000575, 5386.952957875982, 5193.584513239008, 5944.4352786485215, 5975.731092100779, 5976.641401515836, 5996.640201587918, 4379.430674012422, 4546.476256125111, 4900.019996000814, 5366.543843773323, 5529.529989950819, 2725.8364498130177, 2756.3109222694325, 3010.1722026442467, 3088.336007758343, 3146.7797254192446, 4939.629527785431, 5415.675059496364, 5955.285365756137, 5963.19496867926, 6239.656818875005, 5721.885562288814, 5864.618799778001, 6079.9632123238325, 6128.966161749962, 6151.415615516519, 4886.853394398174, 5576.410722943941, 5631.887362252732, 5725.713714314267, 6279.372062793684, 5822.706561512302, 5953.315368732651, 6003.309619393651, 6008.339082700812, 6034.306054803754, 4933.6299777516115, 5568.749406276646, 5935.821925342247, 5977.491913187428, 5986.904583395777, 5026.949730502794, 5397.8110766123, 5806.883862322646, 5947.123358845705, 6351.237851457855, 3477.1133360992785, 3737.8504859805303, 3834.9808250958567, 3913.569507354132, 3919.4708714324343, 5094.847154585368, 5663.405342438985, 5976.372480889444, 6014.143281941043, 6036.374177232918, 4853.95146048535, 5437.374701909331, 5658.434156584309, 5878.29460464747, 5894.646321220569, 4738.431388233434, 5563.720344320748, 5848.707564621667, 5870.735816888165, 5898.94101058984, 4920.269250565971, 5403.405625381283, 5623.581859800325, 5819.701218018943, 5844.4155584442915, 4364.738115713068, 5201.687898726089, 5230.137027390438, 5757.3954734753925, 5967.880642387215, 5405.405405405481, 5493.752781124779, 5790.971045144747, 5840.737166827588, 5874.706264686663], "value": 4198.896397822147, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [592.7298446948188, 627.7394881124334, 661.6989269882204, 664.143255200791, 760.1979911193696, 14885.702285954289, 15626.76559851603, 15670.92164539177, 15674.373025078983, 15755.921220393897, 13809.861901380984, 13971.510997115103, 14014.019018668698, 14238.0, 14242.145471271731, 839.7186942374296, 864.6627815152095, 866.8266346730647, 868.3964644572025, 868.535333596526, 687.1925487552131, 827.9420440569163, 844.2444012608743, 851.940364174508, 859.70769938221, 900.8018235988072, 906.537665790445, 909.6952520905471, 915.5376534849892, 919.9862002069974, 885.0441523155009, 899.4198741811527, 899.9325050621229, 901.1124042817819, 902.5757893789942, 666.5434177588319, 667.6194569095618, 668.5654324688943, 669.64174166821, 674.1337381464843, 14591.78112328316, 14847.183404912725, 14877.851221487756, 14888.925555372154, 14943.1034137951, 14245.0, 14279.928600357036, 14394.0, 14424.639384015483, 14449.78325325121, 11744.865986788194, 11815.88184118165, 11828.290302581787, 11869.52521899123, 11914.804301763035, 3539.752217344787, 3616.89149325518, 3624.5650521937537, 3724.050367156358, 3735.4957080794015, 101709.491452543, 105916.47041764742, 106720.0, 109857.45071274672, 116115.41942290234, 10512.1590272778, 10778.73053173677, 10962.890371096346, 10970.405232929113, 11212.439378031066, 21018.2643607473, 21160.259390921245, 21296.148154073973, 21317.294616430958, 22092.889535552542, 7881.684732610746, 7956.920430795733, 7974.800629984296, 7982.321502672222, 8115.3913456491, 4274.401583778274, 4336.869893903125, 4338.067315527177, 4393.824247030147, 4398.274284742982, 3566.0, 3710.777353358807, 3721.14413684854, 3721.497597824259, 3728.3289007978824, 706.4066184405021, 723.4393345157545, 726.614894106114, 726.6366816591704, 741.2402287655088, 595.8748662780778, 641.1920979565781, 669.1602039440471, 677.8779819632514, 681.5842336174929, 8944.597493112695, 9361.485118318422, 9463.38487998278, 9480.478573678514, 9544.570494327772, 576.7202906590301, 594.3580932592789, 594.560025581067, 609.8889130908274, 640.3020707428832, 3605.7295702822394, 4358.716683415568, 4514.819407223675, 4526.140033393644, 4650.232711602615, 113957.0, 115774.42112789382, 140746.59253407334, 143857.0, 169767.0, 125435.37282313712, 129255.35372323079, 134664.32667836794, 137941.0, 141476.0, 80787.19212807914, 81577.59211203906, 97763.0, 112023.19942001515, 118651.8134818658, 8552.657893684183, 10037.19702423819, 10046.346987445793, 10067.0, 10152.187824973837, 45009.09981800411, 46639.76680116577, 53831.73084134554, 64059.3594064072, 68800.96798547926, 86215.0, 99496.50251748695, 99639.0, 103877.0, 115338.42330788293, 11513.53945842157, 14023.088499247517, 14108.858911410754, 14227.720858302922, 14639.560813175196, 13693.452261909411, 14169.574912752634, 14349.928250358682, 14639.560813175622, 15050.0, 12206.0, 13511.391987360788, 13923.2056748898, 14020.439182432588, 14590.416383344547, 13562.93218533901, 13815.240161791198, 14047.876169906178, 14608.196549189897, 14609.561713148623, 13407.865921341052, 13438.126521776054, 13541.121397484314, 13685.931570342484, 13936.303184840921, 10902.236843420884, 11094.278871873303, 11236.752649469976, 11481.138914581607, 11549.076073913899, 10663.4135122569, 10979.341239525655, 11402.258853174517, 11741.76516469683, 11833.585824496446], "value": 7789.8905440097615, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1602.3999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 155.0, 148.0, 116.0, 116.0, 101.0, 116.0, 117.0, 116.0, 115.0], "value": 121.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 107.0, 132.0, 155.0, 145.0, 162.0, 130.0, 114.0, 160.0, 112.0], "value": 136.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 204.0, 206.0, 221.0, 213.0, 205.0, 232.0, 199.0, 216.0, 254.0], "value": 214.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 96.0, 103.0, 169.0, 83.0, 51.0, 63.0, 80.0, 160.0, 86.0], "value": 95.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 81.0, 113.0, 74.0, 100.0, 109.0, 142.0, 55.0, 85.0, 137.0], "value": 96.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 89.0, 86.0, 90.0, 86.0, 129.0, 87.0, 137.0, 130.0], "value": 101.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 97.0, 96.0, 94.0, 95.0, 95.0, 101.0, 151.0, 95.0, 97.0], "value": 101.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 86.0, 126.0, 144.0, 133.0, 145.0, 133.0, 97.0, 92.0, 140.0], "value": 119.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 60.0, 57.0, 58.0, 58.0, 56.0, 58.0, 59.0, 58.0, 56.0], "value": 57.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 71.0, 73.0, 73.0, 71.0, 71.0, 68.0, 72.0, 70.0], "value": 70.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 89.0, 93.0, 88.0, 87.0, 90.0, 106.0, 90.0, 80.0, 106.0], "value": 94.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 131.0, 149.0, 159.0, 136.0, 138.0, 137.0, 141.0, 183.0, 163.0], "value": 149.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 175.0, 175.0, 170.0, 177.0, 175.0, 173.0, 174.0, 174.0, 175.0], "value": 174.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 71.0, 68.0, 67.0, 71.0, 68.0, 68.0, 72.0, 75.0, 68.0], "value": 70.0, "unit": "score"}]}]} 03:41:10 INFO - Return code: 0 03:41:10 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:41:10 ERROR - # TBPL SUCCESS # 03:41:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:41:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:41:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:41:10 INFO - 2016-09-07 03:41:10.494 screenresolution[1879:11112] starting screenresolution argv=screenresolution get 03:41:10 INFO - 2016-09-07 03:41:10.496 screenresolution[1879:11112] Display 0: 1600x1200x32@60 03:41:10 INFO - 2016-09-07 03:41:10.502 screenresolution[1880:11114] starting screenresolution argv=screenresolution list 03:41:10 INFO - Available Modes on Display 0 03:41:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:41:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:41:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:41:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:41:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:41:10 INFO - Intel Iris: 03:41:10 INFO - Chipset Model: Intel Iris 03:41:10 INFO - Type: GPU 03:41:10 INFO - Bus: Built-In 03:41:10 INFO - VRAM (Dynamic, Max): 1536 MB 03:41:10 INFO - Vendor: Intel (0x8086) 03:41:10 INFO - Device ID: 0x0a2e 03:41:10 INFO - Revision ID: 0x0009 03:41:10 INFO - Displays: 03:41:10 INFO - Display: 03:41:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:41:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:41:10 INFO - Display Serial Number: 1600x1200 60 03:41:10 INFO - Main Display: Yes 03:41:10 INFO - Mirror: Off 03:41:10 INFO - Online: Yes 03:41:10 INFO - Rotation: Supported 03:41:10 INFO - Return code: 0 03:41:10 INFO - Running post-action listener: _resource_record_post_action 03:41:10 INFO - [mozharness: 2016-09-07 10:41:10.736884Z] Finished run-tests step (success) 03:41:10 INFO - Running post-run listener: _resource_record_post_run 03:41:10 INFO - Total resource usage - Wall time: 1209s; CPU: 54.0%; Read bytes: 32115712; Write bytes: 713274368; Read time: 195; Write time: 2089 03:41:10 INFO - TinderboxPrint: CPU usage
54.4% 03:41:10 INFO - TinderboxPrint: I/O read bytes / time
32,115,712 / 195 03:41:10 INFO - TinderboxPrint: I/O write bytes / time
713,274,368 / 2,089 03:41:10 INFO - TinderboxPrint: CPU idle
2,205.4 (45.6%) 03:41:10 INFO - TinderboxPrint: CPU system
974.3 (20.1%) 03:41:10 INFO - TinderboxPrint: CPU user
1,657.9 (34.3%) 03:41:10 INFO - TinderboxPrint: Swap in / out
434,614,272 / 0 03:41:10 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 400390656; Write bytes: 395947520; Read time: 29412; Write time: 801 03:41:10 INFO - run-tests - Wall time: 1175s; CPU: 54.0%; Read bytes: 30047232; Write bytes: 302519808; Read time: 177; Write time: 1247 03:41:11 INFO - Running post-run listener: _upload_blobber_files 03:41:11 INFO - Blob upload gear active. 03:41:11 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:41:11 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 03:41:11 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:41:11 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:41:11 INFO - (blobuploader) - INFO - Open directory for files ... 03:41:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:41:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:41:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:41:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:41:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:41:12 INFO - (blobuploader) - INFO - Done attempting. 03:41:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:41:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:41:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:41:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:41:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:41:13 INFO - (blobuploader) - INFO - Done attempting. 03:41:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:41:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:41:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:41:15 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:41:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:41:15 INFO - (blobuploader) - INFO - Done attempting. 03:41:15 INFO - (blobuploader) - INFO - Iteration through files over. 03:41:15 INFO - Return code: 0 03:41:15 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:41:15 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:41:15 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3a26f8125541a1f565bc52f6c8991f996c14896f0076609738947d5a7f2c0965c5273a79bc6e01b7aa38f01f0d31349f9bcb7c4c7bcef9c88bb9fde110946e67", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/00d2cd070755a3e6c7bebc8d91083f2a11fb58eebd46171a8b5627e5ff6a55379c34dd2a810b0d075429d0dcd4eede342c51fca3b42896b538849c466b9a4f54", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4c52eeb97c163aea8550d57552648e098bf70437ab2f0b5ee633ce131f80f4f56251a144d340db323437119387a2dc3a39d545e6f258012bdfe743cc8a2da5af"} 03:41:15 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:41:15 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:41:15 INFO - Contents: 03:41:15 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3a26f8125541a1f565bc52f6c8991f996c14896f0076609738947d5a7f2c0965c5273a79bc6e01b7aa38f01f0d31349f9bcb7c4c7bcef9c88bb9fde110946e67", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/00d2cd070755a3e6c7bebc8d91083f2a11fb58eebd46171a8b5627e5ff6a55379c34dd2a810b0d075429d0dcd4eede342c51fca3b42896b538849c466b9a4f54", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4c52eeb97c163aea8550d57552648e098bf70437ab2f0b5ee633ce131f80f4f56251a144d340db323437119387a2dc3a39d545e6f258012bdfe743cc8a2da5af"} 03:41:15 INFO - Running post-run listener: copy_logs_to_upload_dir 03:41:15 INFO - Copying logs to upload dir... 03:41:15 INFO - mkdir: /builds/slave/test/build/upload/logs 03:41:15 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.722694 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-09-07 03:41:16.032917) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-07 03:41:16.036199) ========= 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.Hv4QVEOsYm/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.MIfKZSQ6J7/Listeners TMPDIR=/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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/Fx-Team/sha512/3a26f8125541a1f565bc52f6c8991f996c14896f0076609738947d5a7f2c0965c5273a79bc6e01b7aa38f01f0d31349f9bcb7c4c7bcef9c88bb9fde110946e67", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/00d2cd070755a3e6c7bebc8d91083f2a11fb58eebd46171a8b5627e5ff6a55379c34dd2a810b0d075429d0dcd4eede342c51fca3b42896b538849c466b9a4f54", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4c52eeb97c163aea8550d57552648e098bf70437ab2f0b5ee633ce131f80f4f56251a144d340db323437119387a2dc3a39d545e6f258012bdfe743cc8a2da5af"} build_url:https://queue.taskcluster.net/v1/task/asi2zzvQTCmeOzFSSitltQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010675 build_url: 'https://queue.taskcluster.net/v1/task/asi2zzvQTCmeOzFSSitltQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3a26f8125541a1f565bc52f6c8991f996c14896f0076609738947d5a7f2c0965c5273a79bc6e01b7aa38f01f0d31349f9bcb7c4c7bcef9c88bb9fde110946e67", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/00d2cd070755a3e6c7bebc8d91083f2a11fb58eebd46171a8b5627e5ff6a55379c34dd2a810b0d075429d0dcd4eede342c51fca3b42896b538849c466b9a4f54", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4c52eeb97c163aea8550d57552648e098bf70437ab2f0b5ee633ce131f80f4f56251a144d340db323437119387a2dc3a39d545e6f258012bdfe743cc8a2da5af"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-07 03:41:16.069780) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 03:41:16.070141) ========= 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.Hv4QVEOsYm/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.MIfKZSQ6J7/Listeners TMPDIR=/var/folders/sg/rcqyw_k121lg6wmzvvr3_7fm00000w/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.005092 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 03:41:16.118966) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-07 03:41:16.119332) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-07 03:41:16.119722) ========= ========= Total master_lag: 0.19 =========