builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0010 starttime: 1464928271.69 results: success (0) buildid: 20160602200611 builduid: b7e4ae978d8f46b6be0fe6e372628047 revision: 0c48702e8ff4cf427e443ed91630465c39f6c4ee ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-02 21:31:11.694669) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-02 21:31:11.695603) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-02 21:31:11.695897) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-02 21:31:11.719295) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 21:31:11.719611) ========= 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.BxCGCiMDAu/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.kpQuZJgF3N/Listeners TMPDIR=/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/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-06-02 21:31:11-- 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% 143M=0s 2016-06-02 21:31:12 (143 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.502171 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 21:31:12.243435) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 21:31:12.243767) ========= 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.BxCGCiMDAu/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.kpQuZJgF3N/Listeners TMPDIR=/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/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.027085 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 21:31:12.292576) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-02 21:31:12.292932) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 0c48702e8ff4cf427e443ed91630465c39f6c4ee --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 0c48702e8ff4cf427e443ed91630465c39f6c4ee --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.BxCGCiMDAu/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.kpQuZJgF3N/Listeners TMPDIR=/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/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-06-02 21:31:12,369 truncating revision to first 12 chars 2016-06-02 21:31:12,370 Setting DEBUG logging. 2016-06-02 21:31:12,370 attempt 1/10 2016-06-02 21:31:12,370 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/0c48702e8ff4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-02 21:31:13,397 unpacking tar archive at: fx-team-0c48702e8ff4/testing/mozharness/ program finished with exit code 0 elapsedTime=1.290089 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-02 21:31:13.602641) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-02 21:31:13.602970) ========= script_repo_revision: 0c48702e8ff4cf427e443ed91630465c39f6c4ee ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-02 21:31:13.603352) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-02 21:31:13.603612) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-02 21:31:13.619547) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-06-02 21:31:13.619818) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --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', '--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.BxCGCiMDAu/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.kpQuZJgF3N/Listeners TMPDIR=/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/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 21:31:13 INFO - MultiFileLogger online at 20160602 21:31:13 in /builds/slave/test 21:31:13 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 21:31:13 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:31:13 INFO - {'append_to_log': False, 21:31:13 INFO - 'base_work_dir': '/builds/slave/test', 21:31:13 INFO - 'blob_upload_branch': 'Fx-Team', 21:31:13 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:31:13 INFO - 'branch': 'Fx-Team', 21:31:13 INFO - 'buildbot_json_path': 'buildprops.json', 21:31:13 INFO - 'config_files': ('talos/mac_config.py',), 21:31:13 INFO - 'default_actions': ('clobber', 21:31:13 INFO - 'read-buildbot-config', 21:31:13 INFO - 'download-and-extract', 21:31:13 INFO - 'populate-webroot', 21:31:13 INFO - 'create-virtualenv', 21:31:13 INFO - 'install', 21:31:13 INFO - 'run-tests'), 21:31:13 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:31:13 INFO - 'download_minidump_stackwalk': True, 21:31:13 INFO - 'download_symbols': 'ondemand', 21:31:13 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:31:13 INFO - 'tooltool.py': '/tools/tooltool.py', 21:31:13 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:31:13 INFO - '/tools/misc-python/virtualenv.py')}, 21:31:13 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:31:13 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:31:13 INFO - 'installer_path': 'installer.exe', 21:31:13 INFO - 'log_level': 'info', 21:31:13 INFO - 'log_name': 'talos', 21:31:13 INFO - 'log_to_console': True, 21:31:13 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 21:31:13 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 21:31:13 INFO - 'opt_config_files': (), 21:31:13 INFO - 'pip_index': False, 21:31:13 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:31:13 INFO - 'cmd': ('bash', 21:31:13 INFO - '-c', 21:31:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:31:13 INFO - 'enabled': True, 21:31:13 INFO - 'halt_on_failure': False, 21:31:13 INFO - 'name': 'check_screen_resolution'},), 21:31:13 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:31:13 INFO - 'cmd': ('bash', 21:31:13 INFO - '-c', 21:31:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:31:13 INFO - 'enabled': True, 21:31:13 INFO - 'halt_on_failure': False, 21:31:13 INFO - 'name': 'check_screen_resolution'},), 21:31:13 INFO - 'run_cmd_checks_enabled': True, 21:31:13 INFO - 'sps_profile': False, 21:31:13 INFO - 'sps_profile_interval': 0, 21:31:13 INFO - 'suite': 'dromaeojs', 21:31:13 INFO - 'system_bits': '32', 21:31:13 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:31:13 INFO - 'title': 't-yosemite-r7-0010', 21:31:13 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:31:13 INFO - 'use_talos_json': True, 21:31:13 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:31:13 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:31:13 INFO - 'work_dir': 'build'} 21:31:13 INFO - ##### 21:31:13 INFO - ##### Running clobber step. 21:31:13 INFO - ##### 21:31:13 INFO - Running pre-action listener: _resource_record_pre_action 21:31:13 INFO - Running main action method: clobber 21:31:13 INFO - rmtree: /builds/slave/test/build 21:31:13 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:31:15 INFO - Running post-action listener: _resource_record_post_action 21:31:15 INFO - ##### 21:31:15 INFO - ##### Finished clobber step (success) 21:31:15 INFO - ##### 21:31:15 INFO - ##### 21:31:15 INFO - ##### Running read-buildbot-config step. 21:31:15 INFO - ##### 21:31:15 INFO - Running pre-action listener: _resource_record_pre_action 21:31:15 INFO - Running main action method: read_buildbot_config 21:31:15 INFO - Using buildbot properties: 21:31:15 INFO - { 21:31:15 INFO - "project": "", 21:31:15 INFO - "product": "firefox", 21:31:15 INFO - "script_repo_revision": "production", 21:31:15 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 21:31:15 INFO - "repository": "", 21:31:15 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 21:31:15 INFO - "buildid": "20160602200611", 21:31:15 INFO - "pgo_build": "False", 21:31:15 INFO - "basedir": "/builds/slave/test", 21:31:15 INFO - "buildnumber": 745, 21:31:15 INFO - "slavename": "t-yosemite-r7-0010", 21:31:15 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 21:31:15 INFO - "platform": "yosemite_r7", 21:31:15 INFO - "scheduler": "tests-fx-team-macosx64-talos", 21:31:15 INFO - "branch": "fx-team", 21:31:15 INFO - "repo_path": "integration/fx-team", 21:31:15 INFO - "revision": "0c48702e8ff4cf427e443ed91630465c39f6c4ee", 21:31:15 INFO - "stage_platform": "macosx64", 21:31:15 INFO - "builduid": "b7e4ae978d8f46b6be0fe6e372628047", 21:31:15 INFO - "slavebuilddir": "test" 21:31:15 INFO - } 21:31:15 INFO - Found installer url https://queue.taskcluster.net/v1/task/I1fDyzk8SVu2DQy863LLng/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 21:31:15 INFO - Running post-action listener: _resource_record_post_action 21:31:15 INFO - ##### 21:31:15 INFO - ##### Finished read-buildbot-config step (success) 21:31:15 INFO - ##### 21:31:15 INFO - ##### 21:31:15 INFO - ##### Running download-and-extract step. 21:31:15 INFO - ##### 21:31:15 INFO - Running pre-action listener: _resource_record_pre_action 21:31:15 INFO - Running main action method: download_and_extract 21:31:15 INFO - mkdir: /builds/slave/test/build/tests 21:31:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:31:15 INFO - https://queue.taskcluster.net/v1/task/I1fDyzk8SVu2DQy863LLng/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 21:31:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I1fDyzk8SVu2DQy863LLng/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 21:31:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I1fDyzk8SVu2DQy863LLng/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 21:31:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I1fDyzk8SVu2DQy863LLng/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 21:31:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I1fDyzk8SVu2DQy863LLng/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 21:31:17 INFO - Downloaded 1199 bytes. 21:31:17 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 21:31:17 INFO - Using the following test package requirements: 21:31:17 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 21:31:17 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 21:31:17 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 21:31:17 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 21:31:17 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 21:31:17 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 21:31:17 INFO - u'jsshell-mac.zip'], 21:31:17 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 21:31:17 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 21:31:17 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 21:31:17 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 21:31:17 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 21:31:17 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 21:31:17 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 21:31:17 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 21:31:17 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 21:31:17 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 21:31:17 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 21:31:17 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 21:31:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:31:17 INFO - https://queue.taskcluster.net/v1/task/I1fDyzk8SVu2DQy863LLng/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 21:31:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I1fDyzk8SVu2DQy863LLng/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 21:31:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I1fDyzk8SVu2DQy863LLng/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 21:31:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I1fDyzk8SVu2DQy863LLng/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 21:31:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I1fDyzk8SVu2DQy863LLng/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 21:31:18 INFO - Downloaded 18713639 bytes. 21:31:18 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 21:31:18 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 21:31:21 INFO - Return code: 0 21:31:21 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 21:31:21 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:31:21 INFO - https://queue.taskcluster.net/v1/task/I1fDyzk8SVu2DQy863LLng/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 21:31:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I1fDyzk8SVu2DQy863LLng/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 21:31:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I1fDyzk8SVu2DQy863LLng/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 21:31:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I1fDyzk8SVu2DQy863LLng/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 21:31:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I1fDyzk8SVu2DQy863LLng/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 21:31:26 INFO - Downloaded 18713639 bytes. 21:31:26 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 21:31:26 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 21:31:30 INFO - Return code: 0 21:31:30 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:31:30 INFO - https://queue.taskcluster.net/v1/task/I1fDyzk8SVu2DQy863LLng/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 21:31:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I1fDyzk8SVu2DQy863LLng/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 21:31:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I1fDyzk8SVu2DQy863LLng/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 21:31:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I1fDyzk8SVu2DQy863LLng/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 21:31:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I1fDyzk8SVu2DQy863LLng/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 21:31:32 INFO - Downloaded 10834055 bytes. 21:31:32 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 21:31:32 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 21:31:33 INFO - Return code: 0 21:31: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:31:33 INFO - https://queue.taskcluster.net/v1/task/I1fDyzk8SVu2DQy863LLng/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 21:31:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I1fDyzk8SVu2DQy863LLng/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 21:31:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I1fDyzk8SVu2DQy863LLng/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 21:31:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I1fDyzk8SVu2DQy863LLng/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 21:31:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I1fDyzk8SVu2DQy863LLng/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 21:31:36 INFO - Downloaded 111079320 bytes. 21:31:36 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/I1fDyzk8SVu2DQy863LLng/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 21:31:36 INFO - mkdir: /builds/slave/test/properties 21:31:36 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:31:36 INFO - Writing to file /builds/slave/test/properties/build_url 21:31:36 INFO - Contents: 21:31:36 INFO - build_url:https://queue.taskcluster.net/v1/task/I1fDyzk8SVu2DQy863LLng/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 21:31:37 INFO - Running post-action listener: _resource_record_post_action 21:31:37 INFO - Running post-action listener: set_extra_try_arguments 21:31:37 INFO - ##### 21:31:37 INFO - ##### Finished download-and-extract step (success) 21:31:37 INFO - ##### 21:31:37 INFO - ##### 21:31:37 INFO - ##### Running populate-webroot step. 21:31:37 INFO - ##### 21:31:37 INFO - Running pre-action listener: _resource_record_pre_action 21:31:37 INFO - Running main action method: populate_webroot 21:31:37 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:31:37 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:31:37 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:31:37 INFO - '--tppagecycles', 21:31:37 INFO - '7'], 21:31:37 INFO - 'tests': ['tsvgm']}}, 21:31:37 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:31:37 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:31:37 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:31:37 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:31:37 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:31:37 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:31:37 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:31:37 INFO - 'talos_options': ['--disable-e10s'], 21:31:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:31:37 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:31:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:31:37 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:31:37 INFO - 'talos_options': ['--disable-e10s'], 21:31:37 INFO - 'tests': ['damp', 'tps']}, 21:31:37 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:31:37 INFO - 'tests': ['damp', 'tps']}, 21:31:37 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:31:37 INFO - 'tests': ['dromaeo_dom']}, 21:31:37 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:31:37 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:31:37 INFO - 'tests': ['a11yr', 21:31:37 INFO - 'ts_paint', 21:31:37 INFO - 'tpaint', 21:31:37 INFO - 'sessionrestore', 21:31:37 INFO - 'sessionrestore_no_auto_restore', 21:31:37 INFO - 'tabpaint']}, 21:31:37 INFO - 'other-e10s': {'tests': ['a11yr', 21:31:37 INFO - 'ts_paint', 21:31:37 INFO - 'tpaint', 21:31:37 INFO - 'sessionrestore', 21:31:37 INFO - 'sessionrestore_no_auto_restore', 21:31:37 INFO - 'tabpaint']}, 21:31:37 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:31:37 INFO - 'ts_paint', 21:31:37 INFO - 'tpaint', 21:31:37 INFO - 'sessionrestore', 21:31:37 INFO - 'sessionrestore_no_auto_restore', 21:31:37 INFO - 'tabpaint']}, 21:31:37 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:31:37 INFO - 'ts_paint', 21:31:37 INFO - 'tpaint', 21:31:37 INFO - 'sessionrestore', 21:31:37 INFO - 'sessionrestore_no_auto_restore', 21:31:37 INFO - 'tabpaint']}, 21:31:37 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:31:37 INFO - 'tests': ['a11yr', 21:31:37 INFO - 'ts_paint', 21:31:37 INFO - 'tpaint', 21:31:37 INFO - 'sessionrestore', 21:31:37 INFO - 'sessionrestore_no_auto_restore', 21:31:37 INFO - 'tabpaint']}, 21:31:37 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:31:37 INFO - 'tests': ['a11yr', 21:31:37 INFO - 'ts_paint', 21:31:37 INFO - 'tpaint', 21:31:37 INFO - 'sessionrestore', 21:31:37 INFO - 'sessionrestore_no_auto_restore', 21:31:37 INFO - 'tabpaint']}, 21:31:37 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:31:37 INFO - 'tests': ['tsvgx', 21:31:37 INFO - 'tsvgr_opacity', 21:31:37 INFO - 'tart', 21:31:37 INFO - 'tscrollx', 21:31:37 INFO - 'cart']}, 21:31:37 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:31:37 INFO - 'tsvgr_opacity', 21:31:37 INFO - 'tart', 21:31:37 INFO - 'tscrollx', 21:31:37 INFO - 'cart']}, 21:31:37 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:31:37 INFO - 'talos_options': ['--disable-e10s'], 21:31:37 INFO - 'tests': ['tp5o']}, 21:31:37 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:31:37 INFO - 'tests': ['tp5o']}, 21:31:37 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:31:37 INFO - 'talos_options': ['--disable-e10s', 21:31:37 INFO - '--xperf_path', 21:31:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:31:37 INFO - 'tests': ['tp5n']}, 21:31:37 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:31:37 INFO - 'talos_options': ['--xperf_path', 21:31:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:31:37 INFO - 'tests': ['tp5n']}}, 21:31:37 INFO - 'talos.zip': {'path': '', 21:31:37 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:31:37 INFO - Running post-action listener: _resource_record_post_action 21:31:37 INFO - ##### 21:31:37 INFO - ##### Finished populate-webroot step (success) 21:31:37 INFO - ##### 21:31:37 INFO - ##### 21:31:37 INFO - ##### Running create-virtualenv step. 21:31:37 INFO - ##### 21:31:37 INFO - Running pre-action listener: _resource_record_pre_action 21:31:37 INFO - Running main action method: create_virtualenv 21:31:37 INFO - Creating virtualenv /builds/slave/test/build/venv 21:31:37 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 21:31:37 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:31:37 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:31:37 INFO - Using real prefix '/tools/python27' 21:31:37 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:31:38 INFO - Installing distribute.............................................................................................................................................................................................done. 21:31:41 INFO - Installing pip.................done. 21:31:41 INFO - Return code: 0 21:31:41 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:31:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:31:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:31:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:31:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:31:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:31:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:31:41 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 0x1046e1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1046e7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2285686b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104585d30>, '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 0x1043a9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10457cb20>, '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.BxCGCiMDAu/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.kpQuZJgF3N/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/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 21:31:41 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 21:31:41 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 21:31:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BxCGCiMDAu/Render', 21:31:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:31:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:31:41 INFO - 'HOME': '/Users/cltbld', 21:31:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:31:41 INFO - 'LOGNAME': 'cltbld', 21:31:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:31:41 INFO - 'MOZ_NO_REMOTE': '1', 21:31:41 INFO - 'NO_EM_RESTART': '1', 21:31:41 INFO - 'PAGER': '/bin/cat', 21:31:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:31:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:31:41 INFO - 'PWD': '/builds/slave/test', 21:31:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:31:41 INFO - 'SHELL': '/bin/bash', 21:31:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kpQuZJgF3N/Listeners', 21:31:41 INFO - 'TMPDIR': '/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/', 21:31:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:31:41 INFO - 'USER': 'cltbld', 21:31:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:31:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:31:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:31:41 INFO - 'XPC_FLAGS': '0x0', 21:31:41 INFO - 'XPC_SERVICE_NAME': '0', 21:31:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:31:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:31:41 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 21:31:41 INFO - Cleaning up... 21:31:41 INFO - Return code: 0 21:31:41 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:31:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:31:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:31:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:31:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:31:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:31:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:31:41 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 0x1046e1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1046e7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2285686b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104585d30>, '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 0x1043a9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10457cb20>, '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.BxCGCiMDAu/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.kpQuZJgF3N/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/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 21:31:41 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 21:31:41 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 21:31:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BxCGCiMDAu/Render', 21:31:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:31:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:31:41 INFO - 'HOME': '/Users/cltbld', 21:31:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:31:41 INFO - 'LOGNAME': 'cltbld', 21:31:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:31:41 INFO - 'MOZ_NO_REMOTE': '1', 21:31:41 INFO - 'NO_EM_RESTART': '1', 21:31:41 INFO - 'PAGER': '/bin/cat', 21:31:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:31:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:31:41 INFO - 'PWD': '/builds/slave/test', 21:31:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:31:41 INFO - 'SHELL': '/bin/bash', 21:31:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kpQuZJgF3N/Listeners', 21:31:41 INFO - 'TMPDIR': '/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/', 21:31:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:31:41 INFO - 'USER': 'cltbld', 21:31:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:31:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:31:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:31:41 INFO - 'XPC_FLAGS': '0x0', 21:31:41 INFO - 'XPC_SERVICE_NAME': '0', 21:31:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:31:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:31:41 INFO - Downloading/unpacking psutil>=3.1.1 21:31:41 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 21:31:41 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 21:31:41 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 21:31:41 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 21:31:41 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 21:31:41 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 21:31:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:31:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:31:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:31:44 INFO - Installing collected packages: psutil 21:31:44 INFO - Running setup.py install for psutil 21:31:45 INFO - building 'psutil._psutil_osx' extension 21:31:45 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 21:31:45 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 21:31:45 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 21:31:45 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 21:31:45 INFO - building 'psutil._psutil_posix' extension 21:31:45 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 21:31:45 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 21:31:45 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 21:31:45 INFO - ^ 21:31:45 INFO - 1 warning generated. 21:31:45 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 21:31:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:31:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:31:46 INFO - Successfully installed psutil 21:31:46 INFO - Cleaning up... 21:31:46 INFO - Return code: 0 21:31:46 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:31:46 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:31:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:31:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:31:46 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:31:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:31:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:31:46 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 0x1046e1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1046e7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2285686b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104585d30>, '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 0x1043a9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10457cb20>, '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.BxCGCiMDAu/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.kpQuZJgF3N/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/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 21:31:46 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 21:31:46 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 21:31:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BxCGCiMDAu/Render', 21:31:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:31:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:31:46 INFO - 'HOME': '/Users/cltbld', 21:31:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:31:46 INFO - 'LOGNAME': 'cltbld', 21:31:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:31:46 INFO - 'MOZ_NO_REMOTE': '1', 21:31:46 INFO - 'NO_EM_RESTART': '1', 21:31:46 INFO - 'PAGER': '/bin/cat', 21:31:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:31:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:31:46 INFO - 'PWD': '/builds/slave/test', 21:31:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:31:46 INFO - 'SHELL': '/bin/bash', 21:31:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kpQuZJgF3N/Listeners', 21:31:46 INFO - 'TMPDIR': '/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/', 21:31:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:31:46 INFO - 'USER': 'cltbld', 21:31:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:31:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:31:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:31:46 INFO - 'XPC_FLAGS': '0x0', 21:31:46 INFO - 'XPC_SERVICE_NAME': '0', 21:31:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:31:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:31:46 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:31: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 21:31: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 21:31: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 21:31: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 21:31: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 21:31: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 21:31:48 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:31:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:31:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:31:48 INFO - Installing collected packages: mozsystemmonitor 21:31:48 INFO - Running setup.py install for mozsystemmonitor 21:31:49 INFO - Successfully installed mozsystemmonitor 21:31:49 INFO - Cleaning up... 21:31:49 INFO - Return code: 0 21:31:49 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:31:49 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:31:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:31:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:31:49 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:31:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:31:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:31:49 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 0x1046e1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1046e7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2285686b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104585d30>, '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 0x1043a9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10457cb20>, '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.BxCGCiMDAu/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.kpQuZJgF3N/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/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 21:31:49 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 21:31:49 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 21:31:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BxCGCiMDAu/Render', 21:31:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:31:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:31:49 INFO - 'HOME': '/Users/cltbld', 21:31:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:31:49 INFO - 'LOGNAME': 'cltbld', 21:31:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:31:49 INFO - 'MOZ_NO_REMOTE': '1', 21:31:49 INFO - 'NO_EM_RESTART': '1', 21:31:49 INFO - 'PAGER': '/bin/cat', 21:31:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:31:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:31:49 INFO - 'PWD': '/builds/slave/test', 21:31:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:31:49 INFO - 'SHELL': '/bin/bash', 21:31:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kpQuZJgF3N/Listeners', 21:31:49 INFO - 'TMPDIR': '/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/', 21:31:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:31:49 INFO - 'USER': 'cltbld', 21:31:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:31:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:31:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:31:49 INFO - 'XPC_FLAGS': '0x0', 21:31:49 INFO - 'XPC_SERVICE_NAME': '0', 21:31:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:31:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:31:49 INFO - Downloading/unpacking blobuploader==1.2.4 21:31: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 21:31: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 21:31: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 21:31: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 21:31: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 21:31: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 21:31:51 INFO - Downloading blobuploader-1.2.4.tar.gz 21:31:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:31:51 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:31:51 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 21:31:51 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 21:31:51 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 21:31:51 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 21:31:51 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 21:31:51 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 21:31:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:31:52 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:31:52 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 21:31:52 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 21:31:52 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 21:31:52 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 21:31:52 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 21:31:52 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 21:31:53 INFO - Downloading docopt-0.6.1.tar.gz 21:31:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:31:53 INFO - Installing collected packages: blobuploader, requests, docopt 21:31:53 INFO - Running setup.py install for blobuploader 21:31:53 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:31:53 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:31:53 INFO - Running setup.py install for requests 21:31:53 INFO - Running setup.py install for docopt 21:31:53 INFO - Successfully installed blobuploader requests docopt 21:31:53 INFO - Cleaning up... 21:31:54 INFO - Return code: 0 21:31:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:31:54 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:31:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:31:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:31:54 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:31:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:31:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:31:54 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 0x1046e1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1046e7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2285686b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104585d30>, '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 0x1043a9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10457cb20>, '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.BxCGCiMDAu/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.kpQuZJgF3N/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/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 21:31:54 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 21:31:54 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 21:31:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BxCGCiMDAu/Render', 21:31:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:31:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:31:54 INFO - 'HOME': '/Users/cltbld', 21:31:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:31:54 INFO - 'LOGNAME': 'cltbld', 21:31:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:31:54 INFO - 'MOZ_NO_REMOTE': '1', 21:31:54 INFO - 'NO_EM_RESTART': '1', 21:31:54 INFO - 'PAGER': '/bin/cat', 21:31:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:31:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:31:54 INFO - 'PWD': '/builds/slave/test', 21:31:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:31:54 INFO - 'SHELL': '/bin/bash', 21:31:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kpQuZJgF3N/Listeners', 21:31:54 INFO - 'TMPDIR': '/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/', 21:31:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:31:54 INFO - 'USER': 'cltbld', 21:31:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:31:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:31:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:31:54 INFO - 'XPC_FLAGS': '0x0', 21:31:54 INFO - 'XPC_SERVICE_NAME': '0', 21:31:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:31:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:31:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:31:54 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-IevpZ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:31:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:31:54 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-RT1FTZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:31:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:31:54 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-Cyu4uS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:31:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:31:54 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-6Qi4XN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:31:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:31:54 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-Bam2Ft-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:31:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:31:54 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-fjt0AU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:31:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:31:54 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-nFhYii-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:31:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:31:55 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-U9t8_x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:31:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:31:55 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-_83TpW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:31:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:31:55 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-lgR7VB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:31:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:31:55 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-T8FrYE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:31:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:31:55 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-ehPsdS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:31:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:31:55 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-OkRwoF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:31:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:31:55 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-rYwEAA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:31:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:31:55 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-bGY7je-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:31:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:31:55 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-tnreJ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:31:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:31:55 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-5pNQ8g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:31:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:31:56 INFO - Running setup.py install for manifestparser 21:31:56 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:31:56 INFO - Running setup.py install for mozcrash 21:31:56 INFO - Running setup.py install for mozdebug 21:31:56 INFO - Running setup.py install for mozdevice 21:31:56 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:31:56 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:31:56 INFO - Running setup.py install for mozfile 21:31:56 INFO - Running setup.py install for mozhttpd 21:31:56 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:31:56 INFO - Running setup.py install for mozinfo 21:31:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:31:57 INFO - Running setup.py install for mozInstall 21:31:57 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:31:57 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:31:57 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:31:57 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:31:57 INFO - Running setup.py install for mozleak 21:31:57 INFO - Running setup.py install for mozlog 21:31:57 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:31:57 INFO - Running setup.py install for moznetwork 21:31:57 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:31:57 INFO - Running setup.py install for mozprocess 21:31:57 INFO - Running setup.py install for mozprofile 21:31:57 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:31:57 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:31:57 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:31:57 INFO - Running setup.py install for mozrunner 21:31:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:31:58 INFO - Running setup.py install for mozscreenshot 21:31:58 INFO - Running setup.py install for moztest 21:31:58 INFO - Running setup.py install for mozversion 21:31:58 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:31:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:31:58 INFO - Cleaning up... 21:31:58 INFO - Return code: 0 21:31:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:31:58 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:31:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:31:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:31:58 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:31:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:31:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:31:58 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 0x1046e1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1046e7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2285686b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104585d30>, '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 0x1043a9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10457cb20>, '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.BxCGCiMDAu/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.kpQuZJgF3N/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/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 21:31:58 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 21:31:58 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 21:31:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BxCGCiMDAu/Render', 21:31:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:31:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:31:58 INFO - 'HOME': '/Users/cltbld', 21:31:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:31:58 INFO - 'LOGNAME': 'cltbld', 21:31:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:31:58 INFO - 'MOZ_NO_REMOTE': '1', 21:31:58 INFO - 'NO_EM_RESTART': '1', 21:31:58 INFO - 'PAGER': '/bin/cat', 21:31:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:31:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:31:58 INFO - 'PWD': '/builds/slave/test', 21:31:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:31:58 INFO - 'SHELL': '/bin/bash', 21:31:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kpQuZJgF3N/Listeners', 21:31:58 INFO - 'TMPDIR': '/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/', 21:31:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:31:58 INFO - 'USER': 'cltbld', 21:31:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:31:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:31:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:31:58 INFO - 'XPC_FLAGS': '0x0', 21:31:58 INFO - 'XPC_SERVICE_NAME': '0', 21:31:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:31:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:31:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:31:58 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-gMT8aX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:31:58 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)) 21:31:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:31:58 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-24i95i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:31:59 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)) 21:31:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:31:59 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-D8_DDU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:31:59 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)) 21:31:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:31:59 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-yUHjyb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:31:59 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)) 21:31:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:31:59 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-2zgajL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:31:59 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)) 21:31:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:31:59 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-dLsVSC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:31:59 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)) 21:31:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:31:59 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-lBE5hw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:31:59 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)) 21:31:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:31:59 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-VbggBG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:31:59 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)) 21:31:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:31:59 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-Lk6xJa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:31:59 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)) 21:31:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:31:59 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-LfSaHo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:31:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 21:31:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:31:59 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-iyM8VJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:32:00 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)) 21:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:32:00 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-8UxleI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:32:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 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)) 21:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:32:00 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-hK3sIq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:32:00 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)) 21:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:32:00 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-CLsmMo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:32:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 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)) 21:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:32:00 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-ybP4Js-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:32:00 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)) 21:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:32:00 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-Gv1YRe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:32:00 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)) 21:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:32:00 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-Mwu_mx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:32:00 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)) 21:32:00 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)) 21:32:00 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)) 21:32:00 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)) 21:32:00 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)) 21:32:00 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)) 21:32:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:32:00 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 21:32:00 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 21:32:00 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 21:32:00 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 21:32:00 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 21:32:00 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 21:32:03 INFO - Downloading blessings-1.6.tar.gz 21:32:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:32:03 INFO - Installing collected packages: blessings 21:32:03 INFO - Running setup.py install for blessings 21:32:03 INFO - Successfully installed blessings 21:32:03 INFO - Cleaning up... 21:32:03 INFO - Return code: 0 21:32:03 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:32:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:32:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:32:03 INFO - Reading from file tmpfile_stdout 21:32:03 INFO - Current package versions: 21:32:03 INFO - blessings == 1.6 21:32:03 INFO - blobuploader == 1.2.4 21:32:03 INFO - docopt == 0.6.1 21:32:03 INFO - manifestparser == 1.1 21:32:03 INFO - mozInstall == 1.12 21:32:03 INFO - mozcrash == 0.17 21:32:03 INFO - mozdebug == 0.1 21:32:03 INFO - mozdevice == 0.48 21:32:03 INFO - mozfile == 1.2 21:32:03 INFO - mozhttpd == 0.7 21:32:03 INFO - mozinfo == 0.9 21:32:03 INFO - mozleak == 0.1 21:32:03 INFO - mozlog == 3.2 21:32:03 INFO - moznetwork == 0.27 21:32:03 INFO - mozprocess == 0.22 21:32:03 INFO - mozprofile == 0.28 21:32:03 INFO - mozrunner == 6.11 21:32:03 INFO - mozscreenshot == 0.1 21:32:03 INFO - mozsystemmonitor == 0.3 21:32:03 INFO - moztest == 0.7 21:32:03 INFO - mozversion == 1.4 21:32:03 INFO - psutil == 3.1.1 21:32:03 INFO - requests == 1.2.3 21:32:03 INFO - wsgiref == 0.1.2 21:32:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:32:03 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:32:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:32:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:32:03 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:32:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:32:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:32:03 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 0x1046e1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1046e7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2285686b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104585d30>, '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 0x1043a9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10457cb20>, '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.BxCGCiMDAu/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.kpQuZJgF3N/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/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 21:32:03 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 21:32:03 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 21:32:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BxCGCiMDAu/Render', 21:32:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:32:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:32:03 INFO - 'HOME': '/Users/cltbld', 21:32:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:32:03 INFO - 'LOGNAME': 'cltbld', 21:32:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:32:03 INFO - 'MOZ_NO_REMOTE': '1', 21:32:03 INFO - 'NO_EM_RESTART': '1', 21:32:03 INFO - 'PAGER': '/bin/cat', 21:32:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:32:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:32:03 INFO - 'PWD': '/builds/slave/test', 21:32:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:32:03 INFO - 'SHELL': '/bin/bash', 21:32:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kpQuZJgF3N/Listeners', 21:32:03 INFO - 'TMPDIR': '/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/', 21:32:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:32:03 INFO - 'USER': 'cltbld', 21:32:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:32:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:32:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:32:03 INFO - 'XPC_FLAGS': '0x0', 21:32:03 INFO - 'XPC_SERVICE_NAME': '0', 21:32:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:32:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:32:04 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)) 21:32:04 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)) 21:32:04 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)) 21:32:04 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)) 21:32:04 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)) 21:32:04 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)) 21:32:04 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)) 21:32:04 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)) 21:32:04 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)) 21:32:04 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)) 21:32:04 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)) 21:32:04 INFO - Cleaning up... 21:32:04 INFO - Return code: 0 21:32:04 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:32:04 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:32:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:32:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:32:04 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:32:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:32:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:32:04 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 0x1046e1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1046e7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2285686b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104585d30>, '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 0x1043a9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10457cb20>, '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.BxCGCiMDAu/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.kpQuZJgF3N/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/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 21:32:04 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 21:32:04 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 21:32:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BxCGCiMDAu/Render', 21:32:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:32:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:32:04 INFO - 'HOME': '/Users/cltbld', 21:32:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:32:04 INFO - 'LOGNAME': 'cltbld', 21:32:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:32:04 INFO - 'MOZ_NO_REMOTE': '1', 21:32:04 INFO - 'NO_EM_RESTART': '1', 21:32:04 INFO - 'PAGER': '/bin/cat', 21:32:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:32:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:32:04 INFO - 'PWD': '/builds/slave/test', 21:32:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:32:04 INFO - 'SHELL': '/bin/bash', 21:32:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kpQuZJgF3N/Listeners', 21:32:04 INFO - 'TMPDIR': '/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/', 21:32:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:32:04 INFO - 'USER': 'cltbld', 21:32:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:32:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:32:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:32:04 INFO - 'XPC_FLAGS': '0x0', 21:32:04 INFO - 'XPC_SERVICE_NAME': '0', 21:32:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:32:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:32:04 INFO - Downloading/unpacking jsonschema 21:32:04 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 21:32:04 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 21:32:04 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 21:32:04 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 21:32:04 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 21:32:04 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 21:32:06 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:32:06 INFO - Downloading/unpacking functools32 (from jsonschema) 21:32:06 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 21:32:06 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 21:32:06 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 21:32:06 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 21:32:06 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 21:32:06 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 21:32:07 INFO - Downloading functools32-3.2.3-2.tar.gz 21:32:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:32:07 INFO - warning: no files found matching '*.txt' 21:32:07 INFO - no previously-included directories found matching 'build' 21:32:07 INFO - no previously-included directories found matching 'dist' 21:32:07 INFO - no previously-included directories found matching '.git*' 21:32:07 INFO - Installing collected packages: jsonschema, functools32 21:32:07 INFO - Running setup.py install for functools32 21:32:07 INFO - warning: no files found matching '*.txt' 21:32:07 INFO - no previously-included directories found matching 'build' 21:32:07 INFO - no previously-included directories found matching 'dist' 21:32:07 INFO - no previously-included directories found matching '.git*' 21:32:07 INFO - Successfully installed jsonschema functools32 21:32:07 INFO - Cleaning up... 21:32:07 INFO - Return code: 0 21:32:07 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 21:32:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:32:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:32:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:32:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:32:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:32:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:32:07 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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1046e1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1046e7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa2285686b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104585d30>, '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 0x1043a9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10457cb20>, '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.BxCGCiMDAu/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.kpQuZJgF3N/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/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 21:32:07 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', 'flake8'] in /builds/slave/test/build 21:32:07 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 flake8 21:32:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BxCGCiMDAu/Render', 21:32:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:32:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:32:07 INFO - 'HOME': '/Users/cltbld', 21:32:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:32:07 INFO - 'LOGNAME': 'cltbld', 21:32:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:32:07 INFO - 'MOZ_NO_REMOTE': '1', 21:32:07 INFO - 'NO_EM_RESTART': '1', 21:32:07 INFO - 'PAGER': '/bin/cat', 21:32:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:32:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:32:07 INFO - 'PWD': '/builds/slave/test', 21:32:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:32:07 INFO - 'SHELL': '/bin/bash', 21:32:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kpQuZJgF3N/Listeners', 21:32:07 INFO - 'TMPDIR': '/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/', 21:32:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:32:07 INFO - 'USER': 'cltbld', 21:32:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:32:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:32:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:32:07 INFO - 'XPC_FLAGS': '0x0', 21:32:07 INFO - 'XPC_SERVICE_NAME': '0', 21:32:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:32:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:32:07 INFO - Downloading/unpacking flake8 21:32:07 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 21:32:07 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 21:32:07 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 21:32:07 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 21:32:07 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 21:32:07 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 21:32:10 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 21:32:10 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 21:32:10 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 21:32:10 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 21:32:10 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 21:32:10 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 21:32:10 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 21:32:10 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 21:32:10 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 21:32:10 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 21:32:10 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 21:32:10 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 21:32:10 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 21:32:10 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 21:32:10 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 21:32:10 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 21:32:10 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 21:32:10 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 21:32:10 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 21:32:10 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 21:32:10 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 21:32:10 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 21:32:10 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 21:32:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 21:32:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 21:32:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 21:32:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 21:32:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 21:32:11 INFO - no previously-included directories found matching 'docs/_build' 21:32:11 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 21:32:11 INFO - Running setup.py install for pep8 21:32:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 21:32:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 21:32:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 21:32:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 21:32:11 INFO - no previously-included directories found matching 'docs/_build' 21:32:11 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 21:32:11 INFO - Successfully installed flake8 mccabe pyflakes pep8 21:32:11 INFO - Cleaning up... 21:32:11 INFO - Return code: 0 21:32:11 INFO - Running post-action listener: _resource_record_post_action 21:32:11 INFO - Running post-action listener: _start_resource_monitoring 21:32:11 INFO - Starting resource monitoring. 21:32:11 INFO - ##### 21:32:11 INFO - ##### Finished create-virtualenv step (success) 21:32:11 INFO - ##### 21:32:11 INFO - ##### 21:32:11 INFO - ##### Running install step. 21:32:11 INFO - ##### 21:32:11 INFO - Running pre-action listener: _resource_record_pre_action 21:32:11 INFO - Running main action method: install 21:32:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:32:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:32:12 INFO - Reading from file tmpfile_stdout 21:32:12 INFO - Detecting whether we're running mozinstall >=1.0... 21:32:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:32:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:32:12 INFO - Reading from file tmpfile_stdout 21:32:12 INFO - Output received: 21:32:12 INFO - Usage: mozinstall [options] installer 21:32:12 INFO - Options: 21:32:12 INFO - -h, --help show this help message and exit 21:32:12 INFO - -d DEST, --destination=DEST 21:32:12 INFO - Directory to install application into. [default: 21:32:12 INFO - "/builds/slave/test"] 21:32:12 INFO - --app=APP Application being installed. [default: firefox] 21:32:12 INFO - mkdir: /builds/slave/test/build/application 21:32:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 21:32:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 21:32:46 INFO - Reading from file tmpfile_stdout 21:32:46 INFO - Output received: 21:32:46 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 21:32:46 INFO - Running post-action listener: _resource_record_post_action 21:32:46 INFO - ##### 21:32:46 INFO - ##### Finished install step (success) 21:32:46 INFO - ##### 21:32:46 INFO - ##### 21:32:46 INFO - ##### Running run-tests step. 21:32:46 INFO - ##### 21:32:46 INFO - Running pre-action listener: _resource_record_pre_action 21:32:46 INFO - Running main action method: run_tests 21:32:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:32:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:32:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:32:46 INFO - 2016-06-02 21:32:46.238 screenresolution[1640:8101] starting screenresolution argv=screenresolution get 21:32:46 INFO - 2016-06-02 21:32:46.241 screenresolution[1640:8101] Display 0: 1600x1200x32@60 21:32:46 INFO - 2016-06-02 21:32:46.246 screenresolution[1641:8104] starting screenresolution argv=screenresolution list 21:32:46 INFO - Available Modes on Display 0 21:32:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:32:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:32:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:32:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:32:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:32:46 INFO - Intel Iris: 21:32:46 INFO - Chipset Model: Intel Iris 21:32:46 INFO - Type: GPU 21:32:46 INFO - Bus: Built-In 21:32:46 INFO - VRAM (Dynamic, Max): 1536 MB 21:32:46 INFO - Vendor: Intel (0x8086) 21:32:46 INFO - Device ID: 0x0a2e 21:32:46 INFO - Revision ID: 0x0009 21:32:46 INFO - Displays: 21:32:46 INFO - Display: 21:32:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:32:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:32:46 INFO - Display Serial Number: 1600x1200 60 21:32:46 INFO - Main Display: Yes 21:32:46 INFO - Mirror: Off 21:32:46 INFO - Online: Yes 21:32:46 INFO - Rotation: Supported 21:32:46 INFO - Return code: 0 21:32:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:32:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:32:46 INFO - Python 2.7.3 21:32:46 INFO - Return code: 0 21:32:46 INFO - grabbing minidump binary from tooltool 21:32:46 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:32:46 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 0x104585d30>, '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 0x1043a9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10457cb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:32:46 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 21:32:46 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 21:32:46 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 21:32:46 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:32:46 INFO - Return code: 0 21:32:46 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 21:32:46 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:32:46 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:32:46 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 21:32:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:32:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 21:32:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 21:32:47 INFO - Return code: 0 21:32:47 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', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/I1fDyzk8SVu2DQy863LLng/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0010', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 21:32:47 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 --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/I1fDyzk8SVu2DQy863LLng/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0010 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 21:32:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BxCGCiMDAu/Render', 21:32:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:32:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:32:47 INFO - 'HOME': '/Users/cltbld', 21:32:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:32:47 INFO - 'LOGNAME': 'cltbld', 21:32:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:32:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 21:32:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:32:47 INFO - 'MOZ_NO_REMOTE': '1', 21:32:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:32:47 INFO - 'NO_EM_RESTART': '1', 21:32:47 INFO - 'PAGER': '/bin/cat', 21:32:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:32:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:32:47 INFO - 'PWD': '/builds/slave/test', 21:32:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:32:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:32:47 INFO - 'SHELL': '/bin/bash', 21:32:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kpQuZJgF3N/Listeners', 21:32:47 INFO - 'TMPDIR': '/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/', 21:32:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:32:47 INFO - 'USER': 'cltbld', 21:32:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:32:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:32:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:32:47 INFO - 'XPC_FLAGS': '0x0', 21:32:47 INFO - 'XPC_SERVICE_NAME': '0', 21:32:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:32:47 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/I1fDyzk8SVu2DQy863LLng/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0010', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 21:32:47 INFO - mozversion application_buildid: 20160602200611 21:32:47 INFO - mozversion application_changeset: 0c48702e8ff4cf427e443ed91630465c39f6c4ee 21:32:47 INFO - mozversion application_display_name: Nightly 21:32:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:32:47 INFO - mozversion application_name: Firefox 21:32:47 INFO - mozversion application_remotingname: firefox 21:32:47 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 21:32:47 INFO - mozversion application_vendor: Mozilla 21:32:47 INFO - mozversion application_version: 49.0a1 21:32:47 INFO - mozversion platform_buildid: 20160602200611 21:32:47 INFO - mozversion platform_changeset: 0c48702e8ff4cf427e443ed91630465c39f6c4ee 21:32:47 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 21:32:47 INFO - mozversion platform_version: 49.0a1 21:32:47 INFO - using testdate: 1464928367 21:32:47 INFO - actual date: 1464928367 21:32:47 INFO - starting webserver on 'localhost:49275' 21:32:47 INFO - SUITE-START | Running 2 tests 21:32:47 INFO - TEST-START | dromaeo_css 21:32:47 INFO - operating with platform_type : mac_ 21:32:47 INFO - Initialising browser for dromaeo_css test... 21:32:47 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/tmpwDB5tj/profile http://localhost:49275/getInfo.html) 21:32:50 INFO - PROCESS | 1659 | __metrics Screen width/height:1600/1200 21:32:50 INFO - PROCESS | 1659 | colorDepth:24 21:32:50 INFO - PROCESS | 1659 | Browser inner width/height: 1024/689 21:32:50 INFO - PROCESS | 1659 | __metrics 21:32:50 INFO - PROCESS | 1659 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 21:32:50 ERROR - PROCESS | 1659 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 21:32:51 INFO - TEST-INFO | 1659: exit 0 21:32:51 INFO - Browser initialized. 21:32:51 INFO - Running cycle 1/1 for dromaeo_css test... 21:32:51 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/tmpwDB5tj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:33:03 INFO - PROCESS | 1662 | RSS: Main: 242147328 21:33:03 INFO - PROCESS | 1662 | 21:33:54 INFO - PROCESS | 1662 | 1464928434239 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] 21:36:05 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 21:36:06 INFO - PROCESS | 1662 | RSS: Main: 358604800 21:36:06 INFO - PROCESS | 1662 | 21:39:09 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 21:39:10 INFO - PROCESS | 1662 | RSS: Main: 381169664 21:39:10 INFO - PROCESS | 1662 | 21:42:12 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 21:42:13 INFO - PROCESS | 1662 | RSS: Main: 372056064 21:42:13 INFO - PROCESS | 1662 | 21:45:15 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 21:45:16 INFO - PROCESS | 1662 | RSS: Main: 351297536 21:45:16 INFO - PROCESS | 1662 | 21:48:19 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 21:48:20 INFO - PROCESS | 1662 | RSS: Main: 340205568 21:48:20 INFO - PROCESS | 1662 | 21:51:23 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 21:51:23 INFO - PROCESS | 1662 | RSS: Main: 376389632 21:51:23 INFO - PROCESS | 1662 | 21:51:23 INFO - PROCESS | 1662 | __start_tp_report 21:51:23 INFO - PROCESS | 1662 | _x_x_mozilla_page_load 21:51:23 INFO - PROCESS | 1662 | _x_x_mozilla_page_load_details 21:51:23 INFO - PROCESS | 1662 | |i|pagename|runs| 21:51:23 INFO - PROCESS | 1662 | |0;dojo.html;3428.5714285714284;3437.982810085949;3463.4112200925833;3479.61724210337;3515.876944306948;5503.9174412383745;5618.719064046797;5661.801836935708;5673.063944449161;5701.2018317435595;5589.944100558993;5610.990021796075;5710.486056254937;5711.05767548354;5773.5381169506445;5069.594432445414;5071.036503064406;5134;5154.458781827911;5248.8425347239645;5247.501487358695;5265.710385928773;5269.604779641542;5276.419593844674;5322.068637988336;5067.873303167414;5125.359330083739;5129.615278854101;5141;5195.142801437758;5168.741562921877;5181.404138524081;5185.507376799198;5188.870278243036;5199.896002079937;5086.287919691246;5113.795448182068;5141.074606570815;5152.639315247934;5162.74186290684;4617.515160908123;5588.133839254922;5639.661620302754;5645.717714114277;5646.463585959327;6133.3559976202305;6141.907871381933;6155.938440615626;6227.719752611098;6236.532260080512;6246.750129994794;6272.65500397478;6316.08416779569;6342.270638876496;6343.809685709436;5648.971755141198;5690.061139911951;5719.857003574861;5739.196512488255;5765.481106700333;6286.622802631857;6318.684065796691;6323.778667746698;6330.113784070234;6354.682265886687;5943.257092863392;6085.548441742803;6092.8288561481595;6094.235209809691;6100.542459315569;6110.541709371815;6115.879294474783;6179.16581261533;6181.289151747606;6277.2781130170615;6160.10678451631;6178.949578571654;6181.84545386369;6196.659183744847;6200.689965501707;5950.642961422329;6203.720832562545;6225.906611400833;6234.625922444578;6237.1891654084675;4854.466008739036;4892.3884514435695;4896.755162241945;4923.113839508851;4923.163062279421;4387.254166791589;4406.317020861771;4422.690411671152;4437.667174961891;4487.6634252431195;5644.943550564524;5649.68926709035;5660.405657405913;5675.2054712340305;5696.515796157373;6303.180586523723;6303.936960630427;6305.779297724557;6334.461570766444;6338.714757835908;5333.626646134733;5372.328458942632;5571.331440227118;5629;5636.210930469655;5355.571554275649;5382.1119515280325;5388.676679399248;5409.7295135243085;5417.972910135424;6245.96877015612;6310.495160387158;6311.747530098836;6331.366863313724;6347.301796802256;6095.8313083325365;6186.566940314135;6266.310705822447;6268.592541484789;6347.39699728525;6276.560640755286;6305.936940630719;6315.5894866833505;6316;6331.620102793847;5983.611065280743;6167.5682702210415;6185.134081228722;6194.225721784777;6205.968970155121;6141.854248382235;6148.3236843948025;6225.62646241227;6287.339829317856;6304.936950630618;6050.818475445743;6076.301225358963;6080.635161890301;6082.543809214415;6108.389161083856;5873.2658417697785;5967.1049342597225;6070.484008859296;6079.379093280667;6139.6009259398;5839.883202336015;5952.375000625046;5976.19321391624;5977.6114552553945;5993.850153746017;6044.123602077762;6073.149759033827;6088.78358442481;6120.908186377119;6124.829284339988;5715.657060576379;5798.449147330997;5828.067509198508;5942.58401911862;5942.64344139353 21:51:23 INFO - PROCESS | 1662 | |1;ext.html;489.9485554016828;498.4840689885967;498.8977259661767;503.01660254203034;505.2900674552253;30116.698833011662;40393.39409908854;41538.37692434612;41549.58450415495;41777.58222417775;41983.58016419842;42357;42438.363424548654;42450.7877460613;42529.57470425295;8468.44955077921;9457.19613832825;9683.12851843334;9685.90314096857;9689.854652180224;8529.232369086782;11507;11782.705432364173;11813.291202527833;12068.57759978401;11825.940870295637;11829.467673954692;11938.820917686242;11955.880441195564;11959.162858599902;8586.613602387908;8772.605232764541;8784.868226976601;11715.941420292887;11842.585509507126;8647.65409383624;11346.659600212008;11667.533298668042;11713.648590542298;11976.341301228429;40297;40774.388384174265;40799.59200408017;40815.38776918319;41046.79476602628;3568.4825700273313;3682.576503702055;3745.419459983707;3782.527184101987;3915.490986171808;30853;32150.71397144135;32278.677213227802;32301.86943456968;32595.51106733401;3295.2585668224697;3306.487494438366;3307.355065762177;3362.7982321060813;3404.370191514553;361847;362977;363282.9999999974;363791;364585;33588.664113359046;59125.817483650084;59328.703356483806;59928;60049;32329.191770205463;35688.75089371859;35695.82152089223;35742.64257357393;35753;14700.705985880222;14839.109653420614;14847.70304593902;14847.777283340758;14877.925610372095;11257.71855703614;11355.318680879174;12426.005919526417;12489.313087780078;12650.430730617145;7831.6475758591005;7837.608119593998;7870.960645196852;7910.288074073361;7914.44598878073;3238.141892398516;3246.962798941506;3250.512423136501;3261.119497735598;3269.9509507357407;1013.2603199664139;1021.2238698589126;1037.8547003419528;1046.858674079005;1161.6747310753003;24964.375890602518;26814.463710726068;30187.943421980126;30550.08349749511;31067.84466077655;880.8282384935004;1030.386919782728;1098.2367254757867;1100.2573263047411;1108.2740804772886;14643;15638.374465021272;15696.372145114296;15878.492592224367;16129.677406451805;643265;649315;649440;649836;650344;58951.410485894594;59878.802423951274;60276;60321.79356412847;60337.69831150904;1447.3414596358632;1448.4061534770904;1453.2515754386288;1454.469118771662;1468.0677769616157;29497.557536636537;29676;29704.55443168311;30228;30316.54525182168;50739.49260507348;51186;51238.975220494634;51279.974400512525;51281.743591281804;514627;514640;514655;515029.4248528733;515523;21931.342059738232;23308.184213553122;23426.06295748219;25951.870240648677;27166;47391;47391.28913066238;48680.5131948676;48932.02135957332;49343.50656493533;38907;39745.60254397419;39767.602323977546;40287;40362;38405.80797095997;39193;39331.410028849015;39832;40036;37373.62626373702;38176.04559885914;38316.61683383242;38360.80819595884;38439.03902402351;32037.67962320347;33974;34971.30057398888;34997.300053999286;35133.121671958404;22857.65713514332;25352;25538.616920745833;25596;26133.869330653226 21:51:23 INFO - PROCESS | 1662 | |2;jquery.html;372.7614326830829;372.95338082739653;376.8059449383568;386.59793814432953;396.74410005546423;19320.84074955502;19398.515037124045;21738.782612173873;21749.45626359342;21759.12963481461;11950.64148075557;12279.508819647204;12281.324527151026;12295.20081194724;19628.9018554907;5164.070467315891;5342.706151161685;5389.353277606692;5391.73041347931;5394.487523685263;3846.5961074087236;4043.009462681647;4281.100968796541;6585.143931288926;6600.570962887421;6457.12828768115;6548.738050477999;6595.472362211011;6597.2083349998065;6625.569337993015;5680.971595141998;5875.529957603424;5989.610675306113;5993.700314984233;6290.937090629104;3749.1564398010228;3973.304671682473;3975.6819454443576;3976.980115099406;5655.4910058094965;12020.45907934145;13240.668983275398;13243.271620060988;14461.638459038502;24986.375340616447;2203.0723117272387;2228.6099932511747;2241.4172315198;2401.586405662316;2424.0303878448763;9994.650187243411;10114.595416183343;10151.847722284172;10513.52689128991;10530.262881598292;1848.7319338695813;1898.9525261868425;1916.0802814648907;1938.6704260275787;1955.4915721912264;81253.59373203156;101652;117513.41243293814;126361;128121;8441.535715535581;9257.074292570689;9861.71797666313;9949.651762188434;10097.141742951932;12287.754244915051;12340.629781106582;12408.689782755504;12426.254424734365;12426.254424734545;7968.840623187504;8117.553534555538;8136.34909207262;8232.34141268697;8250.298724608356;6859.382655561023;8453.323734101257;8474.406791524534;8594.398392112493;8598.441101328519;3932.5870783567884;3944.9408258876138;3960.9009774755286;3992.940105898414;4004.1591265833918;1638.336473728142;1923.6922092465418;1944.893030883315;1947.065408603878;2000.369883486697;821.5358322547742;954.9809003819885;958.1233171648004;972.6255391674115;1048.418127938986;19937.401877943685;20474.873881936637;20579.794202057787;20670.276540321018;20697.379078627666;951.990480095204;966.5167416291854;986.3539381704851;1017.3336464615688;1039.542601255445;10670.093042091354;10926.562937482571;10929.453527323602;10969.506372213269;10971.396573188551;505925;509092;510590;601200;615486;111385;113000.43499782613;113138;133486;139565;938.5401153434768;992.9255305851946;1022.5296363672794;1035.5598870480017;1039.9168066554657;17030.06334651606;17864.91067544654;18279.17743701563;18335.266589336276;18474.422208645465;50427.74786126192;52369.47630523646;52801.735991319794;53041.469585303654;54331.45668543422;76952.23047769675;79881;84074;86527;88296;7982.720604778805;7994.40041996841;8286.337093032422;8286.70996515119;8306.252437280673;11031.39327336972;11726.70683232926;12900.741985160432;12973.508046574765;13133.802992955305;10866.67399978002;11113.388763618079;11135.3875536843;11255.66233013011;11356.148288878201;5117.02776472469;8798.560071996502;8918.955405222932;10828.566857325619;11369.772604547697;9167;11551.191416600757;11640.126990475579;11705.88294117048;11741.47163377633;8247.711330103417;8256.958715206385;8343.874841877498;8582.570871456526;8592.226699597066;9338.393004454689;11203.551857925591;11445.084393248353;11554.248973816675;11655.41722913834 21:51:23 INFO - PROCESS | 1662 | |3;mootools.html;1369.9315034248284;1545.1347245542493;1580.6522565035689;1792.121860288459;1943.7084437334406;7712.6914923403065;8485.830283394329;8664.826703465942;8672.783180420492;8781.736547903573;8409;8648.956755216232;8655.307575393968;8666.73999780006;8690.478571285727;2079.7712251652306;2141.7751136130714;2141.796529329711;2312.710911136108;2347.95304093918;2151.0535364439597;2172.5220451500645;2300.0799680127916;2376.536575367804;2377.560151371993;2144.2280778919576;2174.1629472653062;2355.222776483765;2362.6574146748712;2364.657124716915;2275.1354485295647;2334.941626459335;2336.5794157051723;2343.203310874294;2348.6594443805725;1601.5275493729378;2061.4846288427893;2154.289084602093;2374.5725769361507;2377.583922813518;8620.525871077089;8707.434016788888;8710.954685437724;8713.738587842312;8731.825363492695;5117;5282.128448805943;5290.629655924087;5294.232336311252;5294.814681486129;3372.629010808809;3531.099569609733;3533.9116522086897;3557.1285035166293;3629.9274014519824;1048.5596049659162;1082.442542090824;1094.261373572835;1104.8729396119388;1107.7009207513927;11466;11535.307881527051;11554.960053595218;11561.01731352836;11583.47874345648;3971.821268042945;4514.232580461264;4630.791614377361;4713.81144754206;4728.361671174416;9669.17811985975;9747;9750.804983900423;9754.853677194847;9756.170725488413;5256.553192978563;5266.078436273637;5273.2617433559335;5273.446288139767;5275.393329767126;4645.790939407735;4690.226112691368;4835.323054772335;4836.7339796310835;4849.151398505247;3903.4925459690626;3916.412538119305;3964.6431821136234;3992.6606238469476;4014.6788256939376;1076.8653918260218;1256.1520973342956;1263.5009171377255;1265.702559898423;1280.1934781087855;820.3191351178508;884.4869975414244;926.8100039491889;953.2516974175239;965.4303960663242;5763.682997435098;5773.971130144322;5853.239078919713;5883.17635531026;5996.850078748066;429.7708553536882;437.6892406391498;446.88827793051735;447.2798793941751;451.04603763040933;3788.2234142000825;3931.6068393160454;3977.0455090778005;3979.92040159201;3996.780177090288;10354.275200735876;10653.094486968539;10673.626423075155;10677.199210059276;10695.679129626124;10094.697159085092;10519.684409467574;10521.789564208673;10627.468626568641;10640.148788096934;8029.799255018671;8118.14759450261;8230.753077407688;8233.629486673113;8247.587620618946;5868.559858010581;6019.127226552213;6102.786402475892;6116.3883611638485;6203.193584833943;9674.951625241829;10091.243156763125;10092.84860727075;10101.292909496266;10116.949415252877;9188.862167067633;9859.901400985898;10066.798664026532;10182.694519164437;10217.13154381886;6937.340952609494;7303.196648368569;7490.925090749023;7496.400287977058;7575.393968482618;8083.312918401766;8125.79685507867;8203.753887383387;8257.339412847077;8268.503889766633;7382.815429614303;7399.556026638419;7518.398528117847;7534.811629709301;7540.3213710766295;7255.637218138966;7361.153467351323;7370.262973702587;7409.222031686595;7454.440916931143;6893;6911.343422375067;6912.305094845406;6991.685374158277;7025.227225005143;4907.018596280686;4932.2601609757385;4944.456109827988;4993.975030124826;5004.724740139327;4522.547745225583;4958.504149585013;4999.700017998932;5037.697738135724;5073.16292811682 21:51:23 INFO - PROCESS | 1662 | |4;prototype.html;295.25890016059685;300.4561743244727;310.14400255295385;312.8029341514845;316.85583059707835;4659.976700116503;5152.613553983452;5360.311501876906;5379.811706590261;5388.353397592285;4335.566443355663;4963.131451995904;5097.617678674105;5133.716570857286;5281.336379040599;3378.9493157602656;3408.5568876046077;3592.9820350898276;3912.9021774455723;3931.017245688578;3253.8210398428077;3871.612838716134;4009.1380353224195;4026.979865100685;4053.412255222991;3221.661725518822;3518.9120271993147;3697.4453831925293;3917.9020524486973;3945.842166313344;3736.7757934523743;3945.5857135000697;3960.742551734128;3988.162485877969;3992.6805855531634;3049.7407720343576;3283.917902052468;3412.5904891412943;3430.6569343065744;3476.774009689387;4825.252085926687;4923.48303428142;5102.209157580581;5125.82059627915;5182.689038657655;3712.5916149223563;4211.389348544474;4314.935275970832;4546.931796023062;4621.907561848778;5238.266642670029;5524.613277070606;5565.582581306418;5569.164625306196;5590.881823635248;3129.998400511837;3714.9783809452615;3835.17543728097;3852.57621661617;3894.2795582816984;4817.590504807095;5520.475554822286;5871.677057761822;5963.403659634046;5986.28164620244;3312.7018568328967;3488.89533314001;3669.5596528416227;3911.5892831252877;3924.4898163238595;3510.350585141757;3886.6008237034644;3919.451276821247;3948.2893079245437;3990.7605543667473;4021.8592349267633;4588.6099681526775;4589.311603259538;4598.540145985442;4693.7653117344;4819.542143496362;4869.537393947569;5458.536024437888;5651.406602306781;5702.857428564319;1131.4781618722805;1309.9585829265757;1320.735852829438;1326.8606796286444;1344.3211178354868;845.5053793530731;868.8045189832237;879.520661239626;898.9955050224838;903.7514683461969;4687.429711046838;4805.37530121082;5093.694378337312;5099.617528685363;5135.6918584885025;5161.81933632321;5300.780820411327;5514.448555144534;5729.770809167587;5759.95521589838;4176.31090870003;5027.698338099725;5029.0947629427055;5056.443791182967;5085.389753229636;2542.758437948392;2558.6204829508733;2645.8677066146615;2882.12095310928;3018.501947178735;5462.8361149165585;5850.64896106235;5936.050231962865;5955.67243801595;6010.819675409744;5160.716160611202;5173.611979101583;5576.442355764472;5802.245708057925;5824.446677565625;5435.809746658848;5856.970715146397;5920.526357891445;5951.315598706117;5967.641941483438;4750.904981900412;5027.522385373383;5513.117901135799;5645.463680950304;5722.998475266729;5004;5544.7782088716;5556.222128901876;5592.3848376677715;5610.326760788732;5450.536704380013;5810.767569297181;5880.530487012683;5881.823635272877;5898.705064746659;3434.9484757729156;3828.9234215316096;3836.4628951946193;3838.4242363645676;3851.7111216659423;5228.764705588182;5606.383297837317;5659.236003139524;5669.943300566942;5708.65748055118;4709.411323584552;5109.901371205208;5251.448597897319;5497.697626630574;5519.227308176778;4969.577585905238;5089.720065396291;5483.75323110453;5493.7802487900035;5525.977694126599;4894.706317620954;5042.672226305279;5122.513361230677;5333.893322133613;5357.437469065692;5121.8463446096675;5347.085838218102;5496.0107180707855;5529.4840847413525;5565;4314.856563010742;4901.411830580353;4971.260058979328;5351.525636992771;5486.917696234639 21:51:23 INFO - PROCESS | 1662 | |5;yui.html;715.2775696546488;772.1814876231192;800.9679612815492;826.1985873702506;865.1824026295152;15469.226538673078;15487.148206848618;15789.28948197331;15818.050916944963;15826.999999999985;15705;16418.097004664767;16430.17849107543;16438.589035274123;16481.34074637013;706.8303607134277;726.4370113162315;883.3507372081515;886.1802832380039;893.1559676106083;873.9388242823005;904.2178515584038;908.9727308180766;909.0727457992843;910.9590068446903;952.7951490429556;952.9285303602257;953.0659953245788;954.9570269337862;959.2469911119772;737.6201256352982;761.4022991951347;764.3503022430889;951.1439704266181;951.7192428233653;552.5662355051265;557.1086261980839;678.4335080208032;696.9442444604471;699.6781480518968;15464.226788660635;15493.302801373966;15515.689686206326;15535.844641553553;15589.688206235925;13490.190588564717;13713.45146194151;13777.862221377858;13852.584422467342;14067.648308792259;12974.286414247212;13198.14212076212;13228.933855330759;13336.933315333457;13537.43195325374;3845;4585.151746926829;4712.246040633517;4778.996410753711;4791.928121078186;102833.99999999926;104735;106169;107732;109026;10861.185411094042;10896.673099807018;10959.50682219302;11027.889721102847;11085.390303533382;21154.471138221666;21317;21346.359609211748;21365.14539418406;21633.56732865334;7788.415868809749;7897.24976127267;7906.090799558009;7938.126806051329;8033.35733141359;3851.1322008825696;3950.071733142709;3951.9604803952166;3955.3275943089743;4067.6949228807957;3428.3828910796296;3545.007397928584;3547.130952916552;3549.0949807798847;3555.8933232003083;644.7421031587403;665.766981556461;667.4726965697045;680.8195828105556;683.0948992584845;610.5725991805754;627.2660986645635;628.4273902862243;640.6636515829134;646.6281887914467;9040.778406025816;9190;9202.81594368109;9205.585748641328;9391.342606017513;545.724409173365;620.6989610039127;630.3444417805431;635.796545105571;639.6130341143635;3425.400554902882;4309.28896732042;4314.590113939171;4320.287152619782;4450.710703804242;136145.319273403;141795;145352;148048;149748;82054.58972705099;83695;100123;101625.34559216863;114187;80672.59663701644;88263.67604485939;88554;89637;95798.52100739452;8460.153984601491;9328.533573321443;9490.193333566724;9766.46284454362;9956.900430995887;46069.76965115153;48302.75848620734;52206.95586088333;57903;58752.70623646854;67009.66495167493;85895.57052214698;86381;92844;93382.53308733413;11650.242734222249;11829;12723.473183217702;13224.933875330562;13303.600891973256;14942.701145977237;15061.849381506483;15066.472673456376;15093;15124.243787810785;10033.49832508357;13775.655608609864;13974.518287844681;14014.439422422989;14284.500042498463;13999.300034998412;14096.295185240902;14117.152970821784;14570.34433450476;14774.926125369304;10807.783844322912;13520.93239533796;13771.449142034206;13776.586702398945;13908.582742517741;11051.455697147308;11230.101591872468;11290.379029153475;11479.885201147881;11743.823842642196;11112.333260004425;11345.432728363714;11371.20401571882;11445.25605835618;11802.409879505818 21:51:23 INFO - PROCESS | 1662 | __end_tp_report 21:51:23 INFO - PROCESS | 1662 | __start_cc_report 21:51:23 INFO - PROCESS | 1662 | _x_x_mozilla_cycle_collect,1087 21:51:23 INFO - PROCESS | 1662 | __end_cc_report 21:51:23 INFO - PROCESS | 1662 | __startTimestamp1464929483649__endTimestamp 21:51:23 INFO - PROCESS | 1662 | 21:51:23 INFO - PROCESS | 1662 | ------- Summary: start ------- 21:51:23 INFO - PROCESS | 1662 | Number of tests: 6 21:51:23 INFO - PROCESS | 1662 | 21:51:23 INFO - PROCESS | 1662 | [#0] dojo.html Cycles:165 Average:5714.86 Median:5943.26 stddev:636.72 (10.7%) stddev-sans-first:612.89 21:51:23 INFO - PROCESS | 1662 | Values: 3428.6 3438.0 3463.4 3479.6 3515.9 5503.9 5618.7 5661.8 5673.1 5701.2 5589.9 5611.0 5710.5 5711.1 5773.5 5069.6 5071.0 5134.0 5154.5 5248.8 5247.5 5265.7 5269.6 5276.4 5322.1 5067.9 5125.4 5129.6 5141.0 5195.1 5168.7 5181.4 5185.5 5188.9 5199.9 5086.3 5113.8 5141.1 5152.6 5162.7 4617.5 5588.1 5639.7 5645.7 5646.5 6133.4 6141.9 6155.9 6227.7 6236.5 6246.8 6272.7 6316.1 6342.3 6343.8 5649.0 5690.1 5719.9 5739.2 5765.5 6286.6 6318.7 6323.8 6330.1 6354.7 5943.3 6085.5 6092.8 6094.2 6100.5 6110.5 6115.9 6179.2 6181.3 6277.3 6160.1 6178.9 6181.8 6196.7 6200.7 5950.6 6203.7 6225.9 6234.6 6237.2 4854.5 4892.4 4896.8 4923.1 4923.2 4387.3 4406.3 4422.7 4437.7 4487.7 5644.9 5649.7 5660.4 5675.2 5696.5 6303.2 6303.9 6305.8 6334.5 6338.7 5333.6 5372.3 5571.3 5629.0 5636.2 5355.6 5382.1 5388.7 5409.7 5418.0 6246.0 6310.5 6311.7 6331.4 6347.3 6095.8 6186.6 6266.3 6268.6 6347.4 6276.6 6305.9 6315.6 6316.0 6331.6 5983.6 6167.6 6185.1 6194.2 6206.0 6141.9 6148.3 6225.6 6287.3 6304.9 6050.8 6076.3 6080.6 6082.5 6108.4 5873.3 5967.1 6070.5 6079.4 6139.6 5839.9 5952.4 5976.2 5977.6 5993.9 6044.1 6073.1 6088.8 6120.9 6124.8 5715.7 5798.4 5828.1 5942.6 5942.6 21:51:23 INFO - PROCESS | 1662 | 21:51:23 INFO - PROCESS | 1662 | [#1] ext.html Cycles:180 Average:64096.54 Median:35747.82 stddev:139688.61 (390.8%) stddev-sans-first:139998.84 21:51:23 INFO - PROCESS | 1662 | Values: 489.9 498.5 498.9 503.0 505.3 30116.7 40393.4 41538.4 41549.6 41777.6 41983.6 42357.0 42438.4 42450.8 42529.6 8468.4 9457.2 9683.1 9685.9 9689.9 8529.2 11507.0 11782.7 11813.3 12068.6 11825.9 11829.5 11938.8 11955.9 11959.2 8586.6 8772.6 8784.9 11715.9 11842.6 8647.7 11346.7 11667.5 11713.6 11976.3 40297.0 40774.4 40799.6 40815.4 41046.8 3568.5 3682.6 3745.4 3782.5 3915.5 30853.0 32150.7 32278.7 32301.9 32595.5 3295.3 3306.5 3307.4 3362.8 3404.4 361847.0 362977.0 363283.0 363791.0 364585.0 33588.7 59125.8 59328.7 59928.0 60049.0 32329.2 35688.8 35695.8 35742.6 35753.0 14700.7 14839.1 14847.7 14847.8 14877.9 11257.7 11355.3 12426.0 12489.3 12650.4 7831.6 7837.6 7871.0 7910.3 7914.4 3238.1 3247.0 3250.5 3261.1 3270.0 1013.3 1021.2 1037.9 1046.9 1161.7 24964.4 26814.5 30187.9 30550.1 31067.8 880.8 1030.4 1098.2 1100.3 1108.3 14643.0 15638.4 15696.4 15878.5 16129.7 643265.0 649315.0 649440.0 649836.0 650344.0 58951.4 59878.8 60276.0 60321.8 60337.7 1447.3 1448.4 1453.3 1454.5 1468.1 29497.6 29676.0 29704.6 30228.0 30316.5 50739.5 51186.0 51239.0 51280.0 51281.7 514627.0 514640.0 514655.0 515029.4 515523.0 21931.3 23308.2 23426.1 25951.9 27166.0 47391.0 47391.3 48680.5 48932.0 49343.5 38907.0 39745.6 39767.6 40287.0 40362.0 38405.8 39193.0 39331.4 39832.0 40036.0 37373.6 38176.0 38316.6 38360.8 38439.0 32037.7 33974.0 34971.3 34997.3 35133.1 22857.7 25352.0 25538.6 25596.0 26133.9 21:51:23 INFO - PROCESS | 1662 | 21:51:23 INFO - PROCESS | 1662 | [#2] jquery.html Cycles:180 Average:32697.53 Median:372.86 stddev:92344.54 (24766.7%) stddev-sans-first:92571.69 21:51:23 INFO - PROCESS | 1662 | Values: 372.8 373.0 376.8 386.6 396.7 19320.8 19398.5 21738.8 21749.5 21759.1 11950.6 12279.5 12281.3 12295.2 19628.9 5164.1 5342.7 5389.4 5391.7 5394.5 3846.6 4043.0 4281.1 6585.1 6600.6 6457.1 6548.7 6595.5 6597.2 6625.6 5681.0 5875.5 5989.6 5993.7 6290.9 3749.2 3973.3 3975.7 3977.0 5655.5 12020.5 13240.7 13243.3 14461.6 24986.4 2203.1 2228.6 2241.4 2401.6 2424.0 9994.7 10114.6 10151.8 10513.5 10530.3 1848.7 1899.0 1916.1 1938.7 1955.5 81253.6 101652.0 117513.4 126361.0 128121.0 8441.5 9257.1 9861.7 9949.7 10097.1 12287.8 12340.6 12408.7 12426.3 12426.3 7968.8 8117.6 8136.3 8232.3 8250.3 6859.4 8453.3 8474.4 8594.4 8598.4 3932.6 3944.9 3960.9 3992.9 4004.2 1638.3 1923.7 1944.9 1947.1 2000.4 821.5 955.0 958.1 972.6 1048.4 19937.4 20474.9 20579.8 20670.3 20697.4 952.0 966.5 986.4 1017.3 1039.5 10670.1 10926.6 10929.5 10969.5 10971.4 505925.0 509092.0 510590.0 601200.0 615486.0 111385.0 113000.4 113138.0 133486.0 139565.0 938.5 992.9 1022.5 1035.6 1039.9 17030.1 17864.9 18279.2 18335.3 18474.4 50427.7 52369.5 52801.7 53041.5 54331.5 76952.2 79881.0 84074.0 86527.0 88296.0 7982.7 7994.4 8286.3 8286.7 8306.3 11031.4 11726.7 12900.7 12973.5 13133.8 10866.7 11113.4 11135.4 11255.7 11356.1 5117.0 8798.6 8919.0 10828.6 11369.8 9167.0 11551.2 11640.1 11705.9 11741.5 8247.7 8257.0 8343.9 8582.6 8592.2 9338.4 11203.6 11445.1 11554.2 11655.4 21:51:23 INFO - PROCESS | 1662 | 21:51:23 INFO - PROCESS | 1662 | [#3] mootools.html Cycles:180 Average:5641.86 Median:4836.03 stddev:3193.08 (66.0%) stddev-sans-first:3185.90 21:51:23 INFO - PROCESS | 1662 | Values: 1369.9 1545.1 1580.7 1792.1 1943.7 7712.7 8485.8 8664.8 8672.8 8781.7 8409.0 8649.0 8655.3 8666.7 8690.5 2079.8 2141.8 2141.8 2312.7 2348.0 2151.1 2172.5 2300.1 2376.5 2377.6 2144.2 2174.2 2355.2 2362.7 2364.7 2275.1 2334.9 2336.6 2343.2 2348.7 1601.5 2061.5 2154.3 2374.6 2377.6 8620.5 8707.4 8711.0 8713.7 8731.8 5117.0 5282.1 5290.6 5294.2 5294.8 3372.6 3531.1 3533.9 3557.1 3629.9 1048.6 1082.4 1094.3 1104.9 1107.7 11466.0 11535.3 11555.0 11561.0 11583.5 3971.8 4514.2 4630.8 4713.8 4728.4 9669.2 9747.0 9750.8 9754.9 9756.2 5256.6 5266.1 5273.3 5273.4 5275.4 4645.8 4690.2 4835.3 4836.7 4849.2 3903.5 3916.4 3964.6 3992.7 4014.7 1076.9 1256.2 1263.5 1265.7 1280.2 820.3 884.5 926.8 953.3 965.4 5763.7 5774.0 5853.2 5883.2 5996.9 429.8 437.7 446.9 447.3 451.0 3788.2 3931.6 3977.0 3979.9 3996.8 10354.3 10653.1 10673.6 10677.2 10695.7 10094.7 10519.7 10521.8 10627.5 10640.1 8029.8 8118.1 8230.8 8233.6 8247.6 5868.6 6019.1 6102.8 6116.4 6203.2 9675.0 10091.2 10092.8 10101.3 10116.9 9188.9 9859.9 10066.8 10182.7 10217.1 6937.3 7303.2 7490.9 7496.4 7575.4 8083.3 8125.8 8203.8 8257.3 8268.5 7382.8 7399.6 7518.4 7534.8 7540.3 7255.6 7361.2 7370.3 7409.2 7454.4 6893.0 6911.3 6912.3 6991.7 7025.2 4907.0 4932.3 4944.5 4994.0 5004.7 4522.5 4958.5 4999.7 5037.7 5073.2 21:51:23 INFO - PROCESS | 1662 | 21:51:23 INFO - PROCESS | 1662 | [#4] prototype.html Cycles:180 Average:4436.52 Median:5028.40 stddev:1389.10 (27.6%) stddev-sans-first:1357.78 21:51:23 INFO - PROCESS | 1662 | Values: 295.3 300.5 310.1 312.8 316.9 4660.0 5152.6 5360.3 5379.8 5388.4 4335.6 4963.1 5097.6 5133.7 5281.3 3378.9 3408.6 3593.0 3912.9 3931.0 3253.8 3871.6 4009.1 4027.0 4053.4 3221.7 3518.9 3697.4 3917.9 3945.8 3736.8 3945.6 3960.7 3988.2 3992.7 3049.7 3283.9 3412.6 3430.7 3476.8 4825.3 4923.5 5102.2 5125.8 5182.7 3712.6 4211.4 4314.9 4546.9 4621.9 5238.3 5524.6 5565.6 5569.2 5590.9 3130.0 3715.0 3835.2 3852.6 3894.3 4817.6 5520.5 5871.7 5963.4 5986.3 3312.7 3488.9 3669.6 3911.6 3924.5 3510.4 3886.6 3919.5 3948.3 3990.8 4021.9 4588.6 4589.3 4598.5 4693.8 4819.5 4869.5 5458.5 5651.4 5702.9 1131.5 1310.0 1320.7 1326.9 1344.3 845.5 868.8 879.5 899.0 903.8 4687.4 4805.4 5093.7 5099.6 5135.7 5161.8 5300.8 5514.4 5729.8 5760.0 4176.3 5027.7 5029.1 5056.4 5085.4 2542.8 2558.6 2645.9 2882.1 3018.5 5462.8 5850.6 5936.1 5955.7 6010.8 5160.7 5173.6 5576.4 5802.2 5824.4 5435.8 5857.0 5920.5 5951.3 5967.6 4750.9 5027.5 5513.1 5645.5 5723.0 5004.0 5544.8 5556.2 5592.4 5610.3 5450.5 5810.8 5880.5 5881.8 5898.7 3434.9 3828.9 3836.5 3838.4 3851.7 5228.8 5606.4 5659.2 5669.9 5708.7 4709.4 5109.9 5251.4 5497.7 5519.2 4969.6 5089.7 5483.8 5493.8 5526.0 4894.7 5042.7 5122.5 5333.9 5357.4 5121.8 5347.1 5496.0 5529.5 5565.0 4314.9 4901.4 4971.3 5351.5 5486.9 21:51:23 INFO - PROCESS | 1662 | 21:51:23 INFO - PROCESS | 1662 | [#5] yui.html Cycles:180 Average:22790.86 Median:3900.60 stddev:35063.94 (898.9%) stddev-sans-first:35123.12 21:51:23 INFO - PROCESS | 1662 | Values: 715.3 772.2 801.0 826.2 865.2 15469.2 15487.1 15789.3 15818.1 15827.0 15705.0 16418.1 16430.2 16438.6 16481.3 706.8 726.4 883.4 886.2 893.2 873.9 904.2 909.0 909.1 911.0 952.8 952.9 953.1 955.0 959.2 737.6 761.4 764.4 951.1 951.7 552.6 557.1 678.4 696.9 699.7 15464.2 15493.3 15515.7 15535.8 15589.7 13490.2 13713.5 13777.9 13852.6 14067.6 12974.3 13198.1 13228.9 13336.9 13537.4 3845.0 4585.2 4712.2 4779.0 4791.9 102834.0 104735.0 106169.0 107732.0 109026.0 10861.2 10896.7 10959.5 11027.9 11085.4 21154.5 21317.0 21346.4 21365.1 21633.6 7788.4 7897.2 7906.1 7938.1 8033.4 3851.1 3950.1 3952.0 3955.3 4067.7 3428.4 3545.0 3547.1 3549.1 3555.9 644.7 665.8 667.5 680.8 683.1 610.6 627.3 628.4 640.7 646.6 9040.8 9190.0 9202.8 9205.6 9391.3 545.7 620.7 630.3 635.8 639.6 3425.4 4309.3 4314.6 4320.3 4450.7 136145.3 141795.0 145352.0 148048.0 149748.0 82054.6 83695.0 100123.0 101625.3 114187.0 80672.6 88263.7 88554.0 89637.0 95798.5 8460.2 9328.5 9490.2 9766.5 9956.9 46069.8 48302.8 52207.0 57903.0 58752.7 67009.7 85895.6 86381.0 92844.0 93382.5 11650.2 11829.0 12723.5 13224.9 13303.6 14942.7 15061.8 15066.5 15093.0 15124.2 10033.5 13775.7 13974.5 14014.4 14284.5 13999.3 14096.3 14117.2 14570.3 14774.9 10807.8 13520.9 13771.4 13776.6 13908.6 11051.5 11230.1 11290.4 11479.9 11743.8 11112.3 11345.4 11371.2 11445.3 11802.4 21:51:23 INFO - PROCESS | 1662 | -------- Summary: end -------- 21:51:23 INFO - PROCESS | 1662 | 21:51:24 INFO - TEST-INFO | 1662: exit 0 21:51:24 INFO - TEST-OK | dromaeo_css | took 1116265ms 21:51:24 INFO - TEST-START | kraken 21:51:24 INFO - operating with platform_type : mac_ 21:51:24 INFO - Initialising browser for kraken test... 21:51:24 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/tmpr9egYu/profile http://localhost:49275/getInfo.html) 21:51:26 INFO - PROCESS | 1667 | __metrics Screen width/height:1600/1200 21:51:26 INFO - PROCESS | 1667 | colorDepth:24 21:51:26 INFO - PROCESS | 1667 | Browser inner width/height: 1024/689 21:51:26 INFO - PROCESS | 1667 | __metrics 21:51:26 INFO - PROCESS | 1667 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 21:51:26 ERROR - PROCESS | 1667 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 21:51:27 INFO - TEST-INFO | 1667: exit 0 21:51:27 INFO - Browser initialized. 21:51:27 INFO - Running cycle 1/1 for kraken test... 21:51:27 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/tmpr9egYu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:51:39 INFO - PROCESS | 1668 | RSS: Main: 242167808 21:51:39 INFO - PROCESS | 1668 | 21:51:42 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 21:51:43 INFO - PROCESS | 1668 | RSS: Main: 291766272 21:51:43 INFO - PROCESS | 1668 | 21:51:45 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 21:51:46 INFO - PROCESS | 1668 | RSS: Main: 301727744 21:51:46 INFO - PROCESS | 1668 | 21:51:49 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 21:51:50 INFO - PROCESS | 1668 | RSS: Main: 386793472 21:51:50 INFO - PROCESS | 1668 | 21:51:52 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 21:51:53 INFO - PROCESS | 1668 | RSS: Main: 288710656 21:51:53 INFO - PROCESS | 1668 | 21:51:55 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:51:55 INFO - PROCESS | 1668 | RSS: Main: 292327424 21:51:55 INFO - PROCESS | 1668 | 21:51:58 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 21:51:59 INFO - PROCESS | 1668 | RSS: Main: 329818112 21:51:59 INFO - PROCESS | 1668 | 21:52:02 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 21:52:02 INFO - PROCESS | 1668 | RSS: Main: 330473472 21:52:02 INFO - PROCESS | 1668 | 21:52:05 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 21:52:06 INFO - PROCESS | 1668 | RSS: Main: 329969664 21:52:06 INFO - PROCESS | 1668 | 21:52:07 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:52:08 INFO - PROCESS | 1668 | RSS: Main: 309460992 21:52:08 INFO - PROCESS | 1668 | 21:52:10 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:52:10 INFO - PROCESS | 1668 | RSS: Main: 317206528 21:52:10 INFO - PROCESS | 1668 | 21:52:13 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:52:13 INFO - PROCESS | 1668 | RSS: Main: 320692224 21:52:13 INFO - PROCESS | 1668 | 21:52:16 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:52:17 INFO - PROCESS | 1668 | RSS: Main: 323514368 21:52:17 INFO - PROCESS | 1668 | 21:52:20 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:52:20 INFO - PROCESS | 1668 | RSS: Main: 325865472 21:52:20 INFO - PROCESS | 1668 | 21:52:22 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 21:52:22 INFO - PROCESS | 1668 | RSS: Main: 369156096 21:52:22 INFO - PROCESS | 1668 | 21:52:22 INFO - PROCESS | 1668 | __start_tp_report 21:52:22 INFO - PROCESS | 1668 | _x_x_mozilla_page_load 21:52:22 INFO - PROCESS | 1668 | _x_x_mozilla_page_load_details 21:52:22 INFO - PROCESS | 1668 | |i|pagename|runs| 21:52:22 INFO - PROCESS | 1668 | |0;ai-astar;112;107;113;115;157;167;112;112;113;112 21:52:22 INFO - PROCESS | 1668 | |1;audio-beat-detection;159;137;140;167;117;142;136;116;168;120 21:52:22 INFO - PROCESS | 1668 | |2;audio-dft;182;184;187;184;184;184;183;184;179;182 21:52:22 INFO - PROCESS | 1668 | |3;audio-fft;79;73;57;107;56;56;62;67;84;57 21:52:22 INFO - PROCESS | 1668 | |4;audio-oscillator;83;87;94;70;71;69;95;71;68;100 21:52:22 INFO - PROCESS | 1668 | |5;imaging-gaussian-blur;88;86;89;86;89;85;90;86;87;74 21:52:22 INFO - PROCESS | 1668 | |6;imaging-darkroom;94;94;95;95;93;94;95;95;94;94 21:52:22 INFO - PROCESS | 1668 | |7;imaging-desaturate;84;88;84;86;86;84;85;86;86;86 21:52:22 INFO - PROCESS | 1668 | |8;json-parse-financial;55;53;54;56;56;55;54;55;55;55 21:52:22 INFO - PROCESS | 1668 | |9;json-stringify-tinderbox;68;76;79;73;73;70;70;72;72;72 21:52:22 INFO - PROCESS | 1668 | |10;stanford-crypto-aes;86;87;86;85;75;83;100;110;162;97 21:52:22 INFO - PROCESS | 1668 | |11;stanford-crypto-ccm;140;142;140;126;158;132;138;146;161;140 21:52:23 INFO - PROCESS | 1668 | |12;stanford-crypto-pbkdf2;204;222;170;165;170;168;163;168;172;164 21:52:23 INFO - PROCESS | 1668 | |13;stanford-crypto-sha256-iterative;68;68;67;66;72;78;82;85;74;65 21:52:23 INFO - PROCESS | 1668 | __end_tp_report 21:52:23 INFO - PROCESS | 1668 | __start_cc_report 21:52:23 INFO - PROCESS | 1668 | _x_x_mozilla_cycle_collect,2023 21:52:23 INFO - PROCESS | 1668 | __end_cc_report 21:52:23 INFO - PROCESS | 1668 | __startTimestamp1464929542995__endTimestamp 21:52:23 INFO - PROCESS | 1668 | 21:52:23 INFO - PROCESS | 1668 | ------- Summary: start ------- 21:52:23 INFO - PROCESS | 1668 | Number of tests: 14 21:52:23 INFO - PROCESS | 1668 | 21:52:23 INFO - PROCESS | 1668 | [#0] ai-astar Cycles:10 Average:122.00 Median:113.00 stddev:21.31 (18.9%) stddev-sans-first:22.29 21:52:23 INFO - PROCESS | 1668 | Values: 112.0 107.0 113.0 115.0 157.0 167.0 112.0 112.0 113.0 112.0 21:52:23 INFO - PROCESS | 1668 | 21:52:23 INFO - PROCESS | 1668 | [#1] audio-beat-detection Cycles:10 Average:140.20 Median:141.00 stddev:19.46 (13.8%) stddev-sans-first:19.41 21:52:23 INFO - PROCESS | 1668 | Values: 159.0 137.0 140.0 167.0 117.0 142.0 136.0 116.0 168.0 120.0 21:52:23 INFO - PROCESS | 1668 | 21:52:23 INFO - PROCESS | 1668 | [#2] audio-dft Cycles:10 Average:183.30 Median:184.00 stddev:2.06 (1.1%) stddev-sans-first:2.13 21:52:23 INFO - PROCESS | 1668 | Values: 182.0 184.0 187.0 184.0 184.0 184.0 183.0 184.0 179.0 182.0 21:52:23 INFO - PROCESS | 1668 | 21:52:23 INFO - PROCESS | 1668 | [#3] audio-fft Cycles:10 Average:69.80 Median:64.50 stddev:16.52 (25.6%) stddev-sans-first:17.19 21:52:23 INFO - PROCESS | 1668 | Values: 79.0 73.0 57.0 107.0 56.0 56.0 62.0 67.0 84.0 57.0 21:52:23 INFO - PROCESS | 1668 | 21:52:23 INFO - PROCESS | 1668 | [#4] audio-oscillator Cycles:10 Average:80.80 Median:77.00 stddev:12.47 (16.2%) stddev-sans-first:13.20 21:52:23 INFO - PROCESS | 1668 | Values: 83.0 87.0 94.0 70.0 71.0 69.0 95.0 71.0 68.0 100.0 21:52:23 INFO - PROCESS | 1668 | 21:52:23 INFO - PROCESS | 1668 | [#5] imaging-gaussian-blur Cycles:10 Average:86.00 Median:87.50 stddev:4.52 (5.2%) stddev-sans-first:4.74 21:52:23 INFO - PROCESS | 1668 | Values: 88.0 86.0 89.0 86.0 89.0 85.0 90.0 86.0 87.0 74.0 21:52:23 INFO - PROCESS | 1668 | 21:52:23 INFO - PROCESS | 1668 | [#6] imaging-darkroom Cycles:10 Average:94.30 Median:94.50 stddev:0.67 (0.7%) stddev-sans-first:0.71 21:52:23 INFO - PROCESS | 1668 | Values: 94.0 94.0 95.0 95.0 93.0 94.0 95.0 95.0 94.0 94.0 21:52:23 INFO - PROCESS | 1668 | 21:52:23 INFO - PROCESS | 1668 | [#7] imaging-desaturate Cycles:10 Average:85.50 Median:86.00 stddev:1.27 (1.5%) stddev-sans-first:1.22 21:52:23 INFO - PROCESS | 1668 | Values: 84.0 88.0 84.0 86.0 86.0 84.0 85.0 86.0 86.0 86.0 21:52:23 INFO - PROCESS | 1668 | 21:52:23 INFO - PROCESS | 1668 | [#8] json-parse-financial Cycles:10 Average:54.80 Median:55.00 stddev:0.92 (1.7%) stddev-sans-first:0.97 21:52:23 INFO - PROCESS | 1668 | Values: 55.0 53.0 54.0 56.0 56.0 55.0 54.0 55.0 55.0 55.0 21:52:23 INFO - PROCESS | 1668 | 21:52:23 INFO - PROCESS | 1668 | [#9] json-stringify-tinderbox Cycles:10 Average:72.50 Median:72.50 stddev:3.14 (4.3%) stddev-sans-first:2.87 21:52:23 INFO - PROCESS | 1668 | Values: 68.0 76.0 79.0 73.0 73.0 70.0 70.0 72.0 72.0 72.0 21:52:23 INFO - PROCESS | 1668 | 21:52:23 INFO - PROCESS | 1668 | [#10] stanford-crypto-aes Cycles:10 Average:97.10 Median:85.50 stddev:24.88 (29.1%) stddev-sans-first:26.06 21:52:23 INFO - PROCESS | 1668 | Values: 86.0 87.0 86.0 85.0 75.0 83.0 100.0 110.0 162.0 97.0 21:52:23 INFO - PROCESS | 1668 | 21:52:23 INFO - PROCESS | 1668 | [#11] stanford-crypto-ccm Cycles:10 Average:142.30 Median:141.00 stddev:10.63 (7.5%) stddev-sans-first:11.24 21:52:23 INFO - PROCESS | 1668 | Values: 140.0 142.0 140.0 126.0 158.0 132.0 138.0 146.0 161.0 140.0 21:52:23 INFO - PROCESS | 1668 | 21:52:23 INFO - PROCESS | 1668 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.60 Median:170.00 stddev:19.85 (11.7%) stddev-sans-first:18.41 21:52:23 INFO - PROCESS | 1668 | Values: 204.0 222.0 170.0 165.0 170.0 168.0 163.0 168.0 172.0 164.0 21:52:23 INFO - PROCESS | 1668 | 21:52:23 INFO - PROCESS | 1668 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.50 Median:73.00 stddev:7.06 (9.7%) stddev-sans-first:7.30 21:52:23 INFO - PROCESS | 1668 | Values: 68.0 68.0 67.0 66.0 72.0 78.0 82.0 85.0 74.0 65.0 21:52:23 INFO - PROCESS | 1668 | -------- Summary: end -------- 21:52:23 INFO - PROCESS | 1668 | 21:52:23 INFO - TEST-INFO | 1668: exit 0 21:52:23 INFO - TEST-OK | kraken | took 59602ms 21:52:23 INFO - SUITE-END | took 1175s 21:52:24 INFO - Completed test suite (00:19:36) 21:52:24 INFO - javascript benchmark 21:52:24 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3428.5714285714284, 3437.982810085949, 3463.4112200925833, 3479.61724210337, 3515.876944306948, 5503.9174412383745, 5618.719064046797, 5661.801836935708, 5673.063944449161, 5701.2018317435595, 5589.944100558993, 5610.990021796075, 5710.486056254937, 5711.05767548354, 5773.5381169506445, 5069.594432445414, 5071.036503064406, 5134.0, 5154.458781827911, 5248.8425347239645, 5247.501487358695, 5265.710385928773, 5269.604779641542, 5276.419593844674, 5322.068637988336, 5067.873303167414, 5125.359330083739, 5129.615278854101, 5141.0, 5195.142801437758, 5168.741562921877, 5181.404138524081, 5185.507376799198, 5188.870278243036, 5199.896002079937, 5086.287919691246, 5113.795448182068, 5141.074606570815, 5152.639315247934, 5162.74186290684, 4617.515160908123, 5588.133839254922, 5639.661620302754, 5645.717714114277, 5646.463585959327, 6133.3559976202305, 6141.907871381933, 6155.938440615626, 6227.719752611098, 6236.532260080512, 6246.750129994794, 6272.65500397478, 6316.08416779569, 6342.270638876496, 6343.809685709436, 5648.971755141198, 5690.061139911951, 5719.857003574861, 5739.196512488255, 5765.481106700333, 6286.622802631857, 6318.684065796691, 6323.778667746698, 6330.113784070234, 6354.682265886687, 5943.257092863392, 6085.548441742803, 6092.8288561481595, 6094.235209809691, 6100.542459315569, 6110.541709371815, 6115.879294474783, 6179.16581261533, 6181.289151747606, 6277.2781130170615, 6160.10678451631, 6178.949578571654, 6181.84545386369, 6196.659183744847, 6200.689965501707, 5950.642961422329, 6203.720832562545, 6225.906611400833, 6234.625922444578, 6237.1891654084675, 4854.466008739036, 4892.3884514435695, 4896.755162241945, 4923.113839508851, 4923.163062279421, 4387.254166791589, 4406.317020861771, 4422.690411671152, 4437.667174961891, 4487.6634252431195, 5644.943550564524, 5649.68926709035, 5660.405657405913, 5675.2054712340305, 5696.515796157373, 6303.180586523723, 6303.936960630427, 6305.779297724557, 6334.461570766444, 6338.714757835908, 5333.626646134733, 5372.328458942632, 5571.331440227118, 5629.0, 5636.210930469655, 5355.571554275649, 5382.1119515280325, 5388.676679399248, 5409.7295135243085, 5417.972910135424, 6245.96877015612, 6310.495160387158, 6311.747530098836, 6331.366863313724, 6347.301796802256, 6095.8313083325365, 6186.566940314135, 6266.310705822447, 6268.592541484789, 6347.39699728525, 6276.560640755286, 6305.936940630719, 6315.5894866833505, 6316.0, 6331.620102793847, 5983.611065280743, 6167.5682702210415, 6185.134081228722, 6194.225721784777, 6205.968970155121, 6141.854248382235, 6148.3236843948025, 6225.62646241227, 6287.339829317856, 6304.936950630618, 6050.818475445743, 6076.301225358963, 6080.635161890301, 6082.543809214415, 6108.389161083856, 5873.2658417697785, 5967.1049342597225, 6070.484008859296, 6079.379093280667, 6139.6009259398, 5839.883202336015, 5952.375000625046, 5976.19321391624, 5977.6114552553945, 5993.850153746017, 6044.123602077762, 6073.149759033827, 6088.78358442481, 6120.908186377119, 6124.829284339988, 5715.657060576379, 5798.449147330997, 5828.067509198508, 5942.58401911862, 5942.64344139353], "value": 5674.717613597411, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [489.9485554016828, 498.4840689885967, 498.8977259661767, 503.01660254203034, 505.2900674552253, 30116.698833011662, 40393.39409908854, 41538.37692434612, 41549.58450415495, 41777.58222417775, 41983.58016419842, 42357.0, 42438.363424548654, 42450.7877460613, 42529.57470425295, 8468.44955077921, 9457.19613832825, 9683.12851843334, 9685.90314096857, 9689.854652180224, 8529.232369086782, 11507.0, 11782.705432364173, 11813.291202527833, 12068.57759978401, 11825.940870295637, 11829.467673954692, 11938.820917686242, 11955.880441195564, 11959.162858599902, 8586.613602387908, 8772.605232764541, 8784.868226976601, 11715.941420292887, 11842.585509507126, 8647.65409383624, 11346.659600212008, 11667.533298668042, 11713.648590542298, 11976.341301228429, 40297.0, 40774.388384174265, 40799.59200408017, 40815.38776918319, 41046.79476602628, 3568.4825700273313, 3682.576503702055, 3745.419459983707, 3782.527184101987, 3915.490986171808, 30853.0, 32150.71397144135, 32278.677213227802, 32301.86943456968, 32595.51106733401, 3295.2585668224697, 3306.487494438366, 3307.355065762177, 3362.7982321060813, 3404.370191514553, 361847.0, 362977.0, 363282.9999999974, 363791.0, 364585.0, 33588.664113359046, 59125.817483650084, 59328.703356483806, 59928.0, 60049.0, 32329.191770205463, 35688.75089371859, 35695.82152089223, 35742.64257357393, 35753.0, 14700.705985880222, 14839.109653420614, 14847.70304593902, 14847.777283340758, 14877.925610372095, 11257.71855703614, 11355.318680879174, 12426.005919526417, 12489.313087780078, 12650.430730617145, 7831.6475758591005, 7837.608119593998, 7870.960645196852, 7910.288074073361, 7914.44598878073, 3238.141892398516, 3246.962798941506, 3250.512423136501, 3261.119497735598, 3269.9509507357407, 1013.2603199664139, 1021.2238698589126, 1037.8547003419528, 1046.858674079005, 1161.6747310753003, 24964.375890602518, 26814.463710726068, 30187.943421980126, 30550.08349749511, 31067.84466077655, 880.8282384935004, 1030.386919782728, 1098.2367254757867, 1100.2573263047411, 1108.2740804772886, 14643.0, 15638.374465021272, 15696.372145114296, 15878.492592224367, 16129.677406451805, 643265.0, 649315.0, 649440.0, 649836.0, 650344.0, 58951.410485894594, 59878.802423951274, 60276.0, 60321.79356412847, 60337.69831150904, 1447.3414596358632, 1448.4061534770904, 1453.2515754386288, 1454.469118771662, 1468.0677769616157, 29497.557536636537, 29676.0, 29704.55443168311, 30228.0, 30316.54525182168, 50739.49260507348, 51186.0, 51238.975220494634, 51279.974400512525, 51281.743591281804, 514627.0, 514640.0, 514655.0, 515029.4248528733, 515523.0, 21931.342059738232, 23308.184213553122, 23426.06295748219, 25951.870240648677, 27166.0, 47391.0, 47391.28913066238, 48680.5131948676, 48932.02135957332, 49343.50656493533, 38907.0, 39745.60254397419, 39767.602323977546, 40287.0, 40362.0, 38405.80797095997, 39193.0, 39331.410028849015, 39832.0, 40036.0, 37373.62626373702, 38176.04559885914, 38316.61683383242, 38360.80819595884, 38439.03902402351, 32037.67962320347, 33974.0, 34971.30057398888, 34997.300053999286, 35133.121671958404, 22857.65713514332, 25352.0, 25538.616920745833, 25596.0, 26133.869330653226], "value": 18825.8353975279, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [372.7614326830829, 372.95338082739653, 376.8059449383568, 386.59793814432953, 396.74410005546423, 19320.84074955502, 19398.515037124045, 21738.782612173873, 21749.45626359342, 21759.12963481461, 11950.64148075557, 12279.508819647204, 12281.324527151026, 12295.20081194724, 19628.9018554907, 5164.070467315891, 5342.706151161685, 5389.353277606692, 5391.73041347931, 5394.487523685263, 3846.5961074087236, 4043.009462681647, 4281.100968796541, 6585.143931288926, 6600.570962887421, 6457.12828768115, 6548.738050477999, 6595.472362211011, 6597.2083349998065, 6625.569337993015, 5680.971595141998, 5875.529957603424, 5989.610675306113, 5993.700314984233, 6290.937090629104, 3749.1564398010228, 3973.304671682473, 3975.6819454443576, 3976.980115099406, 5655.4910058094965, 12020.45907934145, 13240.668983275398, 13243.271620060988, 14461.638459038502, 24986.375340616447, 2203.0723117272387, 2228.6099932511747, 2241.4172315198, 2401.586405662316, 2424.0303878448763, 9994.650187243411, 10114.595416183343, 10151.847722284172, 10513.52689128991, 10530.262881598292, 1848.7319338695813, 1898.9525261868425, 1916.0802814648907, 1938.6704260275787, 1955.4915721912264, 81253.59373203156, 101652.0, 117513.41243293814, 126361.0, 128121.0, 8441.535715535581, 9257.074292570689, 9861.71797666313, 9949.651762188434, 10097.141742951932, 12287.754244915051, 12340.629781106582, 12408.689782755504, 12426.254424734365, 12426.254424734545, 7968.840623187504, 8117.553534555538, 8136.34909207262, 8232.34141268697, 8250.298724608356, 6859.382655561023, 8453.323734101257, 8474.406791524534, 8594.398392112493, 8598.441101328519, 3932.5870783567884, 3944.9408258876138, 3960.9009774755286, 3992.940105898414, 4004.1591265833918, 1638.336473728142, 1923.6922092465418, 1944.893030883315, 1947.065408603878, 2000.369883486697, 821.5358322547742, 954.9809003819885, 958.1233171648004, 972.6255391674115, 1048.418127938986, 19937.401877943685, 20474.873881936637, 20579.794202057787, 20670.276540321018, 20697.379078627666, 951.990480095204, 966.5167416291854, 986.3539381704851, 1017.3336464615688, 1039.542601255445, 10670.093042091354, 10926.562937482571, 10929.453527323602, 10969.506372213269, 10971.396573188551, 505925.0, 509092.0, 510590.0, 601200.0, 615486.0, 111385.0, 113000.43499782613, 113138.0, 133486.0, 139565.0, 938.5401153434768, 992.9255305851946, 1022.5296363672794, 1035.5598870480017, 1039.9168066554657, 17030.06334651606, 17864.91067544654, 18279.17743701563, 18335.266589336276, 18474.422208645465, 50427.74786126192, 52369.47630523646, 52801.735991319794, 53041.469585303654, 54331.45668543422, 76952.23047769675, 79881.0, 84074.0, 86527.0, 88296.0, 7982.720604778805, 7994.40041996841, 8286.337093032422, 8286.70996515119, 8306.252437280673, 11031.39327336972, 11726.70683232926, 12900.741985160432, 12973.508046574765, 13133.802992955305, 10866.67399978002, 11113.388763618079, 11135.3875536843, 11255.66233013011, 11356.148288878201, 5117.02776472469, 8798.560071996502, 8918.955405222932, 10828.566857325619, 11369.772604547697, 9167.0, 11551.191416600757, 11640.126990475579, 11705.88294117048, 11741.47163377633, 8247.711330103417, 8256.958715206385, 8343.874841877498, 8582.570871456526, 8592.226699597066, 9338.393004454689, 11203.551857925591, 11445.084393248353, 11554.248973816675, 11655.41722913834], "value": 9041.700850899218, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1369.9315034248284, 1545.1347245542493, 1580.6522565035689, 1792.121860288459, 1943.7084437334406, 7712.6914923403065, 8485.830283394329, 8664.826703465942, 8672.783180420492, 8781.736547903573, 8409.0, 8648.956755216232, 8655.307575393968, 8666.73999780006, 8690.478571285727, 2079.7712251652306, 2141.7751136130714, 2141.796529329711, 2312.710911136108, 2347.95304093918, 2151.0535364439597, 2172.5220451500645, 2300.0799680127916, 2376.536575367804, 2377.560151371993, 2144.2280778919576, 2174.1629472653062, 2355.222776483765, 2362.6574146748712, 2364.657124716915, 2275.1354485295647, 2334.941626459335, 2336.5794157051723, 2343.203310874294, 2348.6594443805725, 1601.5275493729378, 2061.4846288427893, 2154.289084602093, 2374.5725769361507, 2377.583922813518, 8620.525871077089, 8707.434016788888, 8710.954685437724, 8713.738587842312, 8731.825363492695, 5117.0, 5282.128448805943, 5290.629655924087, 5294.232336311252, 5294.814681486129, 3372.629010808809, 3531.099569609733, 3533.9116522086897, 3557.1285035166293, 3629.9274014519824, 1048.5596049659162, 1082.442542090824, 1094.261373572835, 1104.8729396119388, 1107.7009207513927, 11466.0, 11535.307881527051, 11554.960053595218, 11561.01731352836, 11583.47874345648, 3971.821268042945, 4514.232580461264, 4630.791614377361, 4713.81144754206, 4728.361671174416, 9669.17811985975, 9747.0, 9750.804983900423, 9754.853677194847, 9756.170725488413, 5256.553192978563, 5266.078436273637, 5273.2617433559335, 5273.446288139767, 5275.393329767126, 4645.790939407735, 4690.226112691368, 4835.323054772335, 4836.7339796310835, 4849.151398505247, 3903.4925459690626, 3916.412538119305, 3964.6431821136234, 3992.6606238469476, 4014.6788256939376, 1076.8653918260218, 1256.1520973342956, 1263.5009171377255, 1265.702559898423, 1280.1934781087855, 820.3191351178508, 884.4869975414244, 926.8100039491889, 953.2516974175239, 965.4303960663242, 5763.682997435098, 5773.971130144322, 5853.239078919713, 5883.17635531026, 5996.850078748066, 429.7708553536882, 437.6892406391498, 446.88827793051735, 447.2798793941751, 451.04603763040933, 3788.2234142000825, 3931.6068393160454, 3977.0455090778005, 3979.92040159201, 3996.780177090288, 10354.275200735876, 10653.094486968539, 10673.626423075155, 10677.199210059276, 10695.679129626124, 10094.697159085092, 10519.684409467574, 10521.789564208673, 10627.468626568641, 10640.148788096934, 8029.799255018671, 8118.14759450261, 8230.753077407688, 8233.629486673113, 8247.587620618946, 5868.559858010581, 6019.127226552213, 6102.786402475892, 6116.3883611638485, 6203.193584833943, 9674.951625241829, 10091.243156763125, 10092.84860727075, 10101.292909496266, 10116.949415252877, 9188.862167067633, 9859.901400985898, 10066.798664026532, 10182.694519164437, 10217.13154381886, 6937.340952609494, 7303.196648368569, 7490.925090749023, 7496.400287977058, 7575.393968482618, 8083.312918401766, 8125.79685507867, 8203.753887383387, 8257.339412847077, 8268.503889766633, 7382.815429614303, 7399.556026638419, 7518.398528117847, 7534.811629709301, 7540.3213710766295, 7255.637218138966, 7361.153467351323, 7370.262973702587, 7409.222031686595, 7454.440916931143, 6893.0, 6911.343422375067, 6912.305094845406, 6991.685374158277, 7025.227225005143, 4907.018596280686, 4932.2601609757385, 4944.456109827988, 4993.975030124826, 5004.724740139327, 4522.547745225583, 4958.504149585013, 4999.700017998932, 5037.697738135724, 5073.16292811682], "value": 4444.62541722601, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [295.25890016059685, 300.4561743244727, 310.14400255295385, 312.8029341514845, 316.85583059707835, 4659.976700116503, 5152.613553983452, 5360.311501876906, 5379.811706590261, 5388.353397592285, 4335.566443355663, 4963.131451995904, 5097.617678674105, 5133.716570857286, 5281.336379040599, 3378.9493157602656, 3408.5568876046077, 3592.9820350898276, 3912.9021774455723, 3931.017245688578, 3253.8210398428077, 3871.612838716134, 4009.1380353224195, 4026.979865100685, 4053.412255222991, 3221.661725518822, 3518.9120271993147, 3697.4453831925293, 3917.9020524486973, 3945.842166313344, 3736.7757934523743, 3945.5857135000697, 3960.742551734128, 3988.162485877969, 3992.6805855531634, 3049.7407720343576, 3283.917902052468, 3412.5904891412943, 3430.6569343065744, 3476.774009689387, 4825.252085926687, 4923.48303428142, 5102.209157580581, 5125.82059627915, 5182.689038657655, 3712.5916149223563, 4211.389348544474, 4314.935275970832, 4546.931796023062, 4621.907561848778, 5238.266642670029, 5524.613277070606, 5565.582581306418, 5569.164625306196, 5590.881823635248, 3129.998400511837, 3714.9783809452615, 3835.17543728097, 3852.57621661617, 3894.2795582816984, 4817.590504807095, 5520.475554822286, 5871.677057761822, 5963.403659634046, 5986.28164620244, 3312.7018568328967, 3488.89533314001, 3669.5596528416227, 3911.5892831252877, 3924.4898163238595, 3510.350585141757, 3886.6008237034644, 3919.451276821247, 3948.2893079245437, 3990.7605543667473, 4021.8592349267633, 4588.6099681526775, 4589.311603259538, 4598.540145985442, 4693.7653117344, 4819.542143496362, 4869.537393947569, 5458.536024437888, 5651.406602306781, 5702.857428564319, 1131.4781618722805, 1309.9585829265757, 1320.735852829438, 1326.8606796286444, 1344.3211178354868, 845.5053793530731, 868.8045189832237, 879.520661239626, 898.9955050224838, 903.7514683461969, 4687.429711046838, 4805.37530121082, 5093.694378337312, 5099.617528685363, 5135.6918584885025, 5161.81933632321, 5300.780820411327, 5514.448555144534, 5729.770809167587, 5759.95521589838, 4176.31090870003, 5027.698338099725, 5029.0947629427055, 5056.443791182967, 5085.389753229636, 2542.758437948392, 2558.6204829508733, 2645.8677066146615, 2882.12095310928, 3018.501947178735, 5462.8361149165585, 5850.64896106235, 5936.050231962865, 5955.67243801595, 6010.819675409744, 5160.716160611202, 5173.611979101583, 5576.442355764472, 5802.245708057925, 5824.446677565625, 5435.809746658848, 5856.970715146397, 5920.526357891445, 5951.315598706117, 5967.641941483438, 4750.904981900412, 5027.522385373383, 5513.117901135799, 5645.463680950304, 5722.998475266729, 5004.0, 5544.7782088716, 5556.222128901876, 5592.3848376677715, 5610.326760788732, 5450.536704380013, 5810.767569297181, 5880.530487012683, 5881.823635272877, 5898.705064746659, 3434.9484757729156, 3828.9234215316096, 3836.4628951946193, 3838.4242363645676, 3851.7111216659423, 5228.764705588182, 5606.383297837317, 5659.236003139524, 5669.943300566942, 5708.65748055118, 4709.411323584552, 5109.901371205208, 5251.448597897319, 5497.697626630574, 5519.227308176778, 4969.577585905238, 5089.720065396291, 5483.75323110453, 5493.7802487900035, 5525.977694126599, 4894.706317620954, 5042.672226305279, 5122.513361230677, 5333.893322133613, 5357.437469065692, 5121.8463446096675, 5347.085838218102, 5496.0107180707855, 5529.4840847413525, 5565.0, 4314.856563010742, 4901.411830580353, 4971.260058979328, 5351.525636992771, 5486.917696234639], "value": 3997.4870090059085, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [715.2775696546488, 772.1814876231192, 800.9679612815492, 826.1985873702506, 865.1824026295152, 15469.226538673078, 15487.148206848618, 15789.28948197331, 15818.050916944963, 15826.999999999985, 15705.0, 16418.097004664767, 16430.17849107543, 16438.589035274123, 16481.34074637013, 706.8303607134277, 726.4370113162315, 883.3507372081515, 886.1802832380039, 893.1559676106083, 873.9388242823005, 904.2178515584038, 908.9727308180766, 909.0727457992843, 910.9590068446903, 952.7951490429556, 952.9285303602257, 953.0659953245788, 954.9570269337862, 959.2469911119772, 737.6201256352982, 761.4022991951347, 764.3503022430889, 951.1439704266181, 951.7192428233653, 552.5662355051265, 557.1086261980839, 678.4335080208032, 696.9442444604471, 699.6781480518968, 15464.226788660635, 15493.302801373966, 15515.689686206326, 15535.844641553553, 15589.688206235925, 13490.190588564717, 13713.45146194151, 13777.862221377858, 13852.584422467342, 14067.648308792259, 12974.286414247212, 13198.14212076212, 13228.933855330759, 13336.933315333457, 13537.43195325374, 3845.0, 4585.151746926829, 4712.246040633517, 4778.996410753711, 4791.928121078186, 102833.99999999926, 104735.0, 106169.0, 107732.0, 109026.0, 10861.185411094042, 10896.673099807018, 10959.50682219302, 11027.889721102847, 11085.390303533382, 21154.471138221666, 21317.0, 21346.359609211748, 21365.14539418406, 21633.56732865334, 7788.415868809749, 7897.24976127267, 7906.090799558009, 7938.126806051329, 8033.35733141359, 3851.1322008825696, 3950.071733142709, 3951.9604803952166, 3955.3275943089743, 4067.6949228807957, 3428.3828910796296, 3545.007397928584, 3547.130952916552, 3549.0949807798847, 3555.8933232003083, 644.7421031587403, 665.766981556461, 667.4726965697045, 680.8195828105556, 683.0948992584845, 610.5725991805754, 627.2660986645635, 628.4273902862243, 640.6636515829134, 646.6281887914467, 9040.778406025816, 9190.0, 9202.81594368109, 9205.585748641328, 9391.342606017513, 545.724409173365, 620.6989610039127, 630.3444417805431, 635.796545105571, 639.6130341143635, 3425.400554902882, 4309.28896732042, 4314.590113939171, 4320.287152619782, 4450.710703804242, 136145.319273403, 141795.0, 145352.0, 148048.0, 149748.0, 82054.58972705099, 83695.0, 100123.0, 101625.34559216863, 114187.0, 80672.59663701644, 88263.67604485939, 88554.0, 89637.0, 95798.52100739452, 8460.153984601491, 9328.533573321443, 9490.193333566724, 9766.46284454362, 9956.900430995887, 46069.76965115153, 48302.75848620734, 52206.95586088333, 57903.0, 58752.70623646854, 67009.66495167493, 85895.57052214698, 86381.0, 92844.0, 93382.53308733413, 11650.242734222249, 11829.0, 12723.473183217702, 13224.933875330562, 13303.600891973256, 14942.701145977237, 15061.849381506483, 15066.472673456376, 15093.0, 15124.243787810785, 10033.49832508357, 13775.655608609864, 13974.518287844681, 14014.439422422989, 14284.500042498463, 13999.300034998412, 14096.295185240902, 14117.152970821784, 14570.34433450476, 14774.926125369304, 10807.783844322912, 13520.93239533796, 13771.449142034206, 13776.586702398945, 13908.582742517741, 11051.455697147308, 11230.101591872468, 11290.379029153475, 11479.885201147881, 11743.823842642196, 11112.333260004425, 11345.432728363714, 11371.20401571882, 11445.25605835618, 11802.409879505818], "value": 7708.097368298923, "unit": "score"}], "name": "dromaeo_css", "value": 7138.285110359692, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 107.0, 113.0, 115.0, 157.0, 167.0, 112.0, 112.0, 113.0, 112.0], "value": 122.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 137.0, 140.0, 167.0, 117.0, 142.0, 136.0, 116.0, 168.0, 120.0], "value": 140.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 184.0, 187.0, 184.0, 184.0, 184.0, 183.0, 184.0, 179.0, 182.0], "value": 183.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 73.0, 57.0, 107.0, 56.0, 56.0, 62.0, 67.0, 84.0, 57.0], "value": 69.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 87.0, 94.0, 70.0, 71.0, 69.0, 95.0, 71.0, 68.0, 100.0], "value": 80.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 89.0, 86.0, 89.0, 85.0, 90.0, 86.0, 87.0, 74.0], "value": 86.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 95.0, 93.0, 94.0, 95.0, 95.0, 94.0, 94.0], "value": 94.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 88.0, 84.0, 86.0, 86.0, 84.0, 85.0, 86.0, 86.0, 86.0], "value": 85.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 53.0, 54.0, 56.0, 56.0, 55.0, 54.0, 55.0, 55.0, 55.0], "value": 54.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 76.0, 79.0, 73.0, 73.0, 70.0, 70.0, 72.0, 72.0, 72.0], "value": 72.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 86.0, 85.0, 75.0, 83.0, 100.0, 110.0, 162.0, 97.0], "value": 97.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 142.0, 140.0, 126.0, 158.0, 132.0, 138.0, 146.0, 161.0, 140.0], "value": 142.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [204.0, 222.0, 170.0, 165.0, 170.0, 168.0, 163.0, 168.0, 172.0, 164.0], "value": 176.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 67.0, 66.0, 72.0, 78.0, 82.0, 85.0, 74.0, 65.0], "value": 72.5, "unit": "score"}], "name": "kraken", "value": 1477.6999999999996, "alertThreshold": 2.0}]} 21:52:24 INFO - Return code: 0 21:52:24 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 21:52:24 ERROR - # TBPL SUCCESS # 21:52:24 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:52:24 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:52:24 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:52:24 INFO - 2016-06-02 21:52:24.315 screenresolution[1672:10070] starting screenresolution argv=screenresolution get 21:52:24 INFO - 2016-06-02 21:52:24.318 screenresolution[1672:10070] Display 0: 1600x1200x32@60 21:52:24 INFO - 2016-06-02 21:52:24.324 screenresolution[1673:10073] starting screenresolution argv=screenresolution list 21:52:24 INFO - Available Modes on Display 0 21:52:24 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:52:24 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:52:24 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:52:24 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:52:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:52:24 INFO - Intel Iris: 21:52:24 INFO - Chipset Model: Intel Iris 21:52:24 INFO - Type: GPU 21:52:24 INFO - Bus: Built-In 21:52:24 INFO - VRAM (Dynamic, Max): 1536 MB 21:52:24 INFO - Vendor: Intel (0x8086) 21:52:24 INFO - Device ID: 0x0a2e 21:52:24 INFO - Revision ID: 0x0009 21:52:24 INFO - Displays: 21:52:24 INFO - Display: 21:52:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:52:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:52:24 INFO - Display Serial Number: 1600x1200 60 21:52:24 INFO - Main Display: Yes 21:52:24 INFO - Mirror: Off 21:52:24 INFO - Online: Yes 21:52:24 INFO - Rotation: Supported 21:52:24 INFO - Return code: 0 21:52:24 INFO - Running post-action listener: _resource_record_post_action 21:52:24 INFO - ##### 21:52:24 INFO - ##### Finished run-tests step (success) 21:52:24 INFO - ##### 21:52:24 INFO - Running post-run listener: _resource_record_post_run 21:52:24 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 32677376; Write bytes: 698609152; Read time: 322; Write time: 2100 21:52:24 INFO - TinderboxPrint: CPU usage
53.9% 21:52:24 INFO - TinderboxPrint: I/O read bytes / time
32,677,376 / 322 21:52:24 INFO - TinderboxPrint: I/O write bytes / time
698,609,152 / 2,100 21:52:24 INFO - TinderboxPrint: CPU idle
2,237.3 (46.1%) 21:52:24 INFO - TinderboxPrint: CPU system
969.5 (20.0%) 21:52:24 INFO - TinderboxPrint: CPU user
1,642.8 (33.9%) 21:52:24 INFO - TinderboxPrint: Swap in / out
428,142,592 / 0 21:52:24 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 393587200; Write bytes: 386844160; Read time: 29564; Write time: 678 21:52:24 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 30879232; Write bytes: 294322176; Read time: 303; Write time: 1314 21:52:25 INFO - Running post-run listener: _upload_blobber_files 21:52:25 INFO - Blob upload gear active. 21:52:25 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:52:25 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 21:52:25 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'] 21:52:25 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 21:52:25 INFO - (blobuploader) - INFO - Open directory for files ... 21:52:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 21:52:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:52:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:52:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 21:52:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:52:26 INFO - (blobuploader) - INFO - Done attempting. 21:52:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 21:52:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:52:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:52:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 21:52:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:52:27 INFO - (blobuploader) - INFO - Done attempting. 21:52:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:52:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:52:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:52:30 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:52:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:52:30 INFO - (blobuploader) - INFO - Done attempting. 21:52:30 INFO - (blobuploader) - INFO - Iteration through files over. 21:52:30 INFO - Return code: 0 21:52:30 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:52:30 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:52:30 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/00ca7dc1e7d69c4934058f018f410a451c02cbcd6be7591cc3af4e7e9fad5b45bd507daf59d24b24511f6c628d557b4a8ccf2fd1f7239d60faf5a884aa12f8ed", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/120b9a26fc79d2a4c695712a4b371b474fa0d4da390455baa1c937205de3758e5e3fe7970956060cf7870322bd3db3e9f7471c21f380c45cca261c05dde935ad", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b65b110f954dac336e48e7826304da75d1b08528c545394d3bbc461fdb439c51d70f3f579633b06c60afcd02c0e79c43db1fbbb60d01efb21e0bf842e6353cff"} 21:52:30 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:52:30 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:52:30 INFO - Contents: 21:52:30 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/00ca7dc1e7d69c4934058f018f410a451c02cbcd6be7591cc3af4e7e9fad5b45bd507daf59d24b24511f6c628d557b4a8ccf2fd1f7239d60faf5a884aa12f8ed", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/120b9a26fc79d2a4c695712a4b371b474fa0d4da390455baa1c937205de3758e5e3fe7970956060cf7870322bd3db3e9f7471c21f380c45cca261c05dde935ad", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b65b110f954dac336e48e7826304da75d1b08528c545394d3bbc461fdb439c51d70f3f579633b06c60afcd02c0e79c43db1fbbb60d01efb21e0bf842e6353cff"} 21:52:30 INFO - Running post-run listener: copy_logs_to_upload_dir 21:52:30 INFO - Copying logs to upload dir... 21:52:30 INFO - mkdir: /builds/slave/test/build/upload/logs 21:52:30 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1277.299434 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-06-02 21:52:30.954791) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-02 21:52:30.960265) ========= 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.BxCGCiMDAu/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.kpQuZJgF3N/Listeners TMPDIR=/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/00ca7dc1e7d69c4934058f018f410a451c02cbcd6be7591cc3af4e7e9fad5b45bd507daf59d24b24511f6c628d557b4a8ccf2fd1f7239d60faf5a884aa12f8ed", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/120b9a26fc79d2a4c695712a4b371b474fa0d4da390455baa1c937205de3758e5e3fe7970956060cf7870322bd3db3e9f7471c21f380c45cca261c05dde935ad", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b65b110f954dac336e48e7826304da75d1b08528c545394d3bbc461fdb439c51d70f3f579633b06c60afcd02c0e79c43db1fbbb60d01efb21e0bf842e6353cff"} build_url:https://queue.taskcluster.net/v1/task/I1fDyzk8SVu2DQy863LLng/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011259 build_url: 'https://queue.taskcluster.net/v1/task/I1fDyzk8SVu2DQy863LLng/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/00ca7dc1e7d69c4934058f018f410a451c02cbcd6be7591cc3af4e7e9fad5b45bd507daf59d24b24511f6c628d557b4a8ccf2fd1f7239d60faf5a884aa12f8ed", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/120b9a26fc79d2a4c695712a4b371b474fa0d4da390455baa1c937205de3758e5e3fe7970956060cf7870322bd3db3e9f7471c21f380c45cca261c05dde935ad", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b65b110f954dac336e48e7826304da75d1b08528c545394d3bbc461fdb439c51d70f3f579633b06c60afcd02c0e79c43db1fbbb60d01efb21e0bf842e6353cff"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-02 21:52:30.992189) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 21:52:30.992548) ========= 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.BxCGCiMDAu/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.kpQuZJgF3N/Listeners TMPDIR=/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/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.005056 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 21:52:31.042038) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-02 21:52:31.042344) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-02 21:52:31.042691) ========= ========= Total master_lag: 0.16 =========