builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0179 starttime: 1463413689.34 results: success (0) buildid: 20160516074433 builduid: c2f13e2278c2440aa4c221e7e2eb802a revision: e9533997fcef31fa5d2db464c6ceec0ca6ea560f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-16 08:48:09.337621) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-16 08:48:09.338028) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-16 08:48:09.338322) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-16 08:48:09.387428) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 08:48:09.388255) ========= 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.pxMWQjMwDO/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.yKsvP79hmN/Listeners TMPDIR=/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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-05-16 08:48:09-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 9.79M=0.001s 2016-05-16 08:48:09 (9.79 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.454883 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 08:48:09.863841) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 08:48:09.864173) ========= 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.pxMWQjMwDO/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.yKsvP79hmN/Listeners TMPDIR=/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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.027435 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 08:48:09.914713) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-16 08:48:09.915021) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev e9533997fcef31fa5d2db464c6ceec0ca6ea560f --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 e9533997fcef31fa5d2db464c6ceec0ca6ea560f --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.pxMWQjMwDO/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.yKsvP79hmN/Listeners TMPDIR=/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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-05-16 08:48:09,992 truncating revision to first 12 chars 2016-05-16 08:48:09,992 Setting DEBUG logging. 2016-05-16 08:48:09,993 attempt 1/10 2016-05-16 08:48:09,993 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/e9533997fcef?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-16 08:48:11,139 unpacking tar archive at: fx-team-e9533997fcef/testing/mozharness/ program finished with exit code 0 elapsedTime=1.396266 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-16 08:48:11.330677) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-16 08:48:11.330970) ========= script_repo_revision: e9533997fcef31fa5d2db464c6ceec0ca6ea560f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-16 08:48:11.331343) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-16 08:48:11.331608) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-16 08:48:11.345192) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2016-05-16 08:48:11.345475) ========= /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.pxMWQjMwDO/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.yKsvP79hmN/Listeners TMPDIR=/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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 08:48:11 INFO - MultiFileLogger online at 20160516 08:48:11 in /builds/slave/test 08:48:11 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 08:48:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:48:11 INFO - {'append_to_log': False, 08:48:11 INFO - 'base_work_dir': '/builds/slave/test', 08:48:11 INFO - 'blob_upload_branch': 'Fx-Team', 08:48:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:48:11 INFO - 'branch': 'Fx-Team', 08:48:11 INFO - 'buildbot_json_path': 'buildprops.json', 08:48:11 INFO - 'config_files': ('talos/mac_config.py',), 08:48:11 INFO - 'default_actions': ('clobber', 08:48:11 INFO - 'read-buildbot-config', 08:48:11 INFO - 'download-and-extract', 08:48:11 INFO - 'populate-webroot', 08:48:11 INFO - 'create-virtualenv', 08:48:11 INFO - 'install', 08:48:11 INFO - 'run-tests'), 08:48:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:48:11 INFO - 'download_minidump_stackwalk': True, 08:48:11 INFO - 'download_symbols': 'ondemand', 08:48:11 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:48:11 INFO - 'tooltool.py': '/tools/tooltool.py', 08:48:11 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:48:11 INFO - '/tools/misc-python/virtualenv.py')}, 08:48:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:48:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:48:11 INFO - 'installer_path': 'installer.exe', 08:48:11 INFO - 'log_level': 'info', 08:48:11 INFO - 'log_name': 'talos', 08:48:11 INFO - 'log_to_console': True, 08:48:11 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:48:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:48:11 INFO - 'opt_config_files': (), 08:48:11 INFO - 'pip_index': False, 08:48:11 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:48:11 INFO - 'cmd': ('bash', 08:48:11 INFO - '-c', 08:48:11 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:48:11 INFO - 'enabled': True, 08:48:11 INFO - 'halt_on_failure': False, 08:48:11 INFO - 'name': 'check_screen_resolution'},), 08:48:11 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:48:11 INFO - 'cmd': ('bash', 08:48:11 INFO - '-c', 08:48:11 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:48:11 INFO - 'enabled': True, 08:48:11 INFO - 'halt_on_failure': False, 08:48:11 INFO - 'name': 'check_screen_resolution'},), 08:48:11 INFO - 'run_cmd_checks_enabled': True, 08:48:11 INFO - 'sps_profile': False, 08:48:11 INFO - 'sps_profile_interval': 0, 08:48:11 INFO - 'suite': 'dromaeojs', 08:48:11 INFO - 'system_bits': '32', 08:48:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:48:11 INFO - 'title': 't-yosemite-r7-0179', 08:48:11 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:48:11 INFO - 'use_talos_json': True, 08:48:11 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:48:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:48:11 INFO - 'work_dir': 'build'} 08:48:11 INFO - ##### 08:48:11 INFO - ##### Running clobber step. 08:48:11 INFO - ##### 08:48:11 INFO - Running pre-action listener: _resource_record_pre_action 08:48:11 INFO - Running main action method: clobber 08:48:11 INFO - rmtree: /builds/slave/test/build 08:48:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:48:13 INFO - Running post-action listener: _resource_record_post_action 08:48:13 INFO - ##### 08:48:13 INFO - ##### Finished clobber step (success) 08:48:13 INFO - ##### 08:48:13 INFO - ##### 08:48:13 INFO - ##### Running read-buildbot-config step. 08:48:13 INFO - ##### 08:48:13 INFO - Running pre-action listener: _resource_record_pre_action 08:48:13 INFO - Running main action method: read_buildbot_config 08:48:13 INFO - Using buildbot properties: 08:48:13 INFO - { 08:48:13 INFO - "project": "", 08:48:13 INFO - "product": "firefox", 08:48:13 INFO - "script_repo_revision": "production", 08:48:13 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 08:48:13 INFO - "repository": "", 08:48:13 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 08:48:13 INFO - "buildid": "20160516074433", 08:48:13 INFO - "pgo_build": "False", 08:48:13 INFO - "basedir": "/builds/slave/test", 08:48:13 INFO - "buildnumber": 10, 08:48:13 INFO - "slavename": "t-yosemite-r7-0179", 08:48:13 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 08:48:13 INFO - "platform": "yosemite_r7", 08:48:13 INFO - "scheduler": "tests-fx-team-macosx64-talos", 08:48:13 INFO - "branch": "fx-team", 08:48:13 INFO - "repo_path": "integration/fx-team", 08:48:13 INFO - "revision": "e9533997fcef31fa5d2db464c6ceec0ca6ea560f", 08:48:13 INFO - "stage_platform": "macosx64", 08:48:13 INFO - "builduid": "c2f13e2278c2440aa4c221e7e2eb802a", 08:48:13 INFO - "slavebuilddir": "test" 08:48:13 INFO - } 08:48:13 INFO - Found installer url https://queue.taskcluster.net/v1/task/bsJth3rIRo-exmrUl9e9gQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 08:48:13 INFO - Running post-action listener: _resource_record_post_action 08:48:13 INFO - ##### 08:48:13 INFO - ##### Finished read-buildbot-config step (success) 08:48:13 INFO - ##### 08:48:13 INFO - ##### 08:48:13 INFO - ##### Running download-and-extract step. 08:48:13 INFO - ##### 08:48:13 INFO - Running pre-action listener: _resource_record_pre_action 08:48:13 INFO - Running main action method: download_and_extract 08:48:13 INFO - mkdir: /builds/slave/test/build/tests 08:48:13 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')]} 08:48:13 INFO - https://queue.taskcluster.net/v1/task/bsJth3rIRo-exmrUl9e9gQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 08:48:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bsJth3rIRo-exmrUl9e9gQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 08:48:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bsJth3rIRo-exmrUl9e9gQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 08:48:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bsJth3rIRo-exmrUl9e9gQ/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 08:48:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bsJth3rIRo-exmrUl9e9gQ/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 08:48:19 INFO - Downloaded 1199 bytes. 08:48:19 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 08:48:19 INFO - Using the following test package requirements: 08:48:19 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 08:48:19 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:48:19 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 08:48:19 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:48:19 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 08:48:19 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:48:19 INFO - u'jsshell-mac.zip'], 08:48:19 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:48:19 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 08:48:19 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 08:48:19 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:48:19 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 08:48:19 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:48:19 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 08:48:19 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:48:19 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 08:48:19 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:48:19 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 08:48:19 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:48:19 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')]} 08:48:19 INFO - https://queue.taskcluster.net/v1/task/bsJth3rIRo-exmrUl9e9gQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 08:48:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bsJth3rIRo-exmrUl9e9gQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 08:48:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bsJth3rIRo-exmrUl9e9gQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 08:48:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bsJth3rIRo-exmrUl9e9gQ/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 08:48:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bsJth3rIRo-exmrUl9e9gQ/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 08:48:21 INFO - Downloaded 18267661 bytes. 08:48:21 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'] 08:48:21 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 08:48:24 INFO - Return code: 0 08:48:24 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 08:48:24 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')]} 08:48:24 INFO - https://queue.taskcluster.net/v1/task/bsJth3rIRo-exmrUl9e9gQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 08:48:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bsJth3rIRo-exmrUl9e9gQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 08:48:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bsJth3rIRo-exmrUl9e9gQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 08:48:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bsJth3rIRo-exmrUl9e9gQ/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 08:48:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bsJth3rIRo-exmrUl9e9gQ/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 08:48:27 INFO - Downloaded 18267661 bytes. 08:48:27 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'] 08:48:27 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 08:48:30 INFO - Return code: 0 08:48: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')]} 08:48:30 INFO - https://queue.taskcluster.net/v1/task/bsJth3rIRo-exmrUl9e9gQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 08:48:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bsJth3rIRo-exmrUl9e9gQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 08:48:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bsJth3rIRo-exmrUl9e9gQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 08:48:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bsJth3rIRo-exmrUl9e9gQ/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 08:48:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bsJth3rIRo-exmrUl9e9gQ/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 08:48:32 INFO - Downloaded 10840194 bytes. 08:48: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'] 08:48: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 08:48:32 INFO - Return code: 0 08:48:32 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')]} 08:48:32 INFO - https://queue.taskcluster.net/v1/task/bsJth3rIRo-exmrUl9e9gQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 08:48:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bsJth3rIRo-exmrUl9e9gQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 08:48:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bsJth3rIRo-exmrUl9e9gQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 08:48:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bsJth3rIRo-exmrUl9e9gQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 08:48:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bsJth3rIRo-exmrUl9e9gQ/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 08:48:37 INFO - Downloaded 110052757 bytes. 08:48:37 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/bsJth3rIRo-exmrUl9e9gQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 08:48:37 INFO - mkdir: /builds/slave/test/properties 08:48:37 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:48:37 INFO - Writing to file /builds/slave/test/properties/build_url 08:48:37 INFO - Contents: 08:48:37 INFO - build_url:https://queue.taskcluster.net/v1/task/bsJth3rIRo-exmrUl9e9gQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 08:48:38 INFO - Running post-action listener: _resource_record_post_action 08:48:38 INFO - Running post-action listener: set_extra_try_arguments 08:48:38 INFO - ##### 08:48:38 INFO - ##### Finished download-and-extract step (success) 08:48:38 INFO - ##### 08:48:38 INFO - ##### 08:48:38 INFO - ##### Running populate-webroot step. 08:48:38 INFO - ##### 08:48:38 INFO - Running pre-action listener: _resource_record_pre_action 08:48:38 INFO - Running main action method: populate_webroot 08:48:38 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:48:38 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:48:38 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:48:38 INFO - '--tppagecycles', 08:48:38 INFO - '7'], 08:48:38 INFO - 'tests': ['tsvgm']}}, 08:48:38 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:48:38 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:48:38 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:48:38 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:48:38 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:48:38 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:48:38 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:48:38 INFO - 'talos_options': ['--disable-e10s'], 08:48:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:48:38 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:48:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:48:38 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:48:38 INFO - 'talos_options': ['--disable-e10s'], 08:48:38 INFO - 'tests': ['damp', 'tps']}, 08:48:38 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:48:38 INFO - 'tests': ['damp', 'tps']}, 08:48:38 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:48:38 INFO - 'tests': ['dromaeo_dom']}, 08:48:38 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:48:38 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:48:38 INFO - 'tests': ['a11yr', 08:48:38 INFO - 'ts_paint', 08:48:38 INFO - 'tpaint', 08:48:38 INFO - 'sessionrestore', 08:48:38 INFO - 'sessionrestore_no_auto_restore', 08:48:38 INFO - 'tabpaint']}, 08:48:38 INFO - 'other-e10s': {'tests': ['a11yr', 08:48:38 INFO - 'ts_paint', 08:48:38 INFO - 'tpaint', 08:48:38 INFO - 'sessionrestore', 08:48:38 INFO - 'sessionrestore_no_auto_restore', 08:48:38 INFO - 'tabpaint']}, 08:48:38 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:48:38 INFO - 'ts_paint', 08:48:38 INFO - 'tpaint', 08:48:38 INFO - 'sessionrestore', 08:48:38 INFO - 'sessionrestore_no_auto_restore', 08:48:38 INFO - 'tabpaint']}, 08:48:38 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:48:38 INFO - 'ts_paint', 08:48:38 INFO - 'tpaint', 08:48:38 INFO - 'sessionrestore', 08:48:38 INFO - 'sessionrestore_no_auto_restore', 08:48:38 INFO - 'tabpaint']}, 08:48:38 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:48:38 INFO - 'tests': ['a11yr', 08:48:38 INFO - 'ts_paint', 08:48:38 INFO - 'tpaint', 08:48:38 INFO - 'sessionrestore', 08:48:38 INFO - 'sessionrestore_no_auto_restore', 08:48:38 INFO - 'tabpaint']}, 08:48:38 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:48:38 INFO - 'tests': ['a11yr', 08:48:38 INFO - 'ts_paint', 08:48:38 INFO - 'tpaint', 08:48:38 INFO - 'sessionrestore', 08:48:38 INFO - 'sessionrestore_no_auto_restore', 08:48:38 INFO - 'tabpaint']}, 08:48:38 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:48:38 INFO - 'tests': ['tsvgx', 08:48:38 INFO - 'tsvgr_opacity', 08:48:38 INFO - 'tart', 08:48:38 INFO - 'tscrollx', 08:48:38 INFO - 'cart']}, 08:48:38 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:48:38 INFO - 'tsvgr_opacity', 08:48:38 INFO - 'tart', 08:48:38 INFO - 'tscrollx', 08:48:38 INFO - 'cart']}, 08:48:38 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:48:38 INFO - 'talos_options': ['--disable-e10s'], 08:48:38 INFO - 'tests': ['tp5o']}, 08:48:38 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:48:38 INFO - 'tests': ['tp5o']}, 08:48:38 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:48:38 INFO - 'talos_options': ['--disable-e10s', 08:48:38 INFO - '--xperf_path', 08:48:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:48:38 INFO - 'tests': ['tp5n']}, 08:48:38 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:48:38 INFO - 'talos_options': ['--xperf_path', 08:48:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:48:38 INFO - 'tests': ['tp5n']}}, 08:48:38 INFO - 'talos.zip': {'path': '', 08:48:38 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:48:38 INFO - Running post-action listener: _resource_record_post_action 08:48:38 INFO - ##### 08:48:38 INFO - ##### Finished populate-webroot step (success) 08:48:38 INFO - ##### 08:48:38 INFO - ##### 08:48:38 INFO - ##### Running create-virtualenv step. 08:48:38 INFO - ##### 08:48:38 INFO - Running pre-action listener: _resource_record_pre_action 08:48:38 INFO - Running main action method: create_virtualenv 08:48:38 INFO - Creating virtualenv /builds/slave/test/build/venv 08:48:38 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 08:48:38 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:48:38 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:48:38 INFO - Using real prefix '/tools/python27' 08:48:38 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:48:39 INFO - Installing distribute.............................................................................................................................................................................................done. 08:48:42 INFO - Installing pip.................done. 08:48:42 INFO - Return code: 0 08:48:42 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:48:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 08:48:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:48:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 08:48:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:48:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109750a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109756570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7facc84a3960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1095f4d30>, '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 0x109418db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1095ebb20>, '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.pxMWQjMwDO/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.yKsvP79hmN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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 08:48:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:48:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:48:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pxMWQjMwDO/Render', 08:48:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:48:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:48:42 INFO - 'HOME': '/Users/cltbld', 08:48:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:48:42 INFO - 'LOGNAME': 'cltbld', 08:48:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:48:42 INFO - 'MOZ_NO_REMOTE': '1', 08:48:42 INFO - 'NO_EM_RESTART': '1', 08:48:42 INFO - 'PAGER': '/bin/cat', 08:48:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:48:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:48:42 INFO - 'PWD': '/builds/slave/test', 08:48:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:48:42 INFO - 'SHELL': '/bin/bash', 08:48:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yKsvP79hmN/Listeners', 08:48:42 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 08:48:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:48:42 INFO - 'USER': 'cltbld', 08:48:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:48:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:48:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:48:42 INFO - 'XPC_FLAGS': '0x0', 08:48:42 INFO - 'XPC_SERVICE_NAME': '0', 08:48:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:48:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:48:42 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 08:48:42 INFO - Cleaning up... 08:48:42 INFO - Return code: 0 08:48:42 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:48:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 08:48:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:48:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 08:48:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:48:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x109750a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109756570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7facc84a3960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1095f4d30>, '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 0x109418db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1095ebb20>, '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.pxMWQjMwDO/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.yKsvP79hmN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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 08:48:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:48:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:48:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pxMWQjMwDO/Render', 08:48:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:48:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:48:42 INFO - 'HOME': '/Users/cltbld', 08:48:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:48:42 INFO - 'LOGNAME': 'cltbld', 08:48:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:48:42 INFO - 'MOZ_NO_REMOTE': '1', 08:48:42 INFO - 'NO_EM_RESTART': '1', 08:48:42 INFO - 'PAGER': '/bin/cat', 08:48:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:48:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:48:42 INFO - 'PWD': '/builds/slave/test', 08:48:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:48:42 INFO - 'SHELL': '/bin/bash', 08:48:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yKsvP79hmN/Listeners', 08:48:42 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 08:48:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:48:42 INFO - 'USER': 'cltbld', 08:48:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:48:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:48:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:48:42 INFO - 'XPC_FLAGS': '0x0', 08:48:42 INFO - 'XPC_SERVICE_NAME': '0', 08:48:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:48:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:48:42 INFO - Downloading/unpacking psutil>=3.1.1 08:48:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:48:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:48:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:48:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:48:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:48:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:48:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:48:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:48:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:48:45 INFO - Installing collected packages: psutil 08:48:45 INFO - Running setup.py install for psutil 08:48:45 INFO - building 'psutil._psutil_osx' extension 08:48: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 08:48:46 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 08:48:46 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 08:48:46 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 08:48:46 INFO - building 'psutil._psutil_posix' extension 08:48:46 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 08:48:46 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:48:46 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:48:46 INFO - ^ 08:48:46 INFO - 1 warning generated. 08:48:46 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 08:48:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:48:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:48:46 INFO - Successfully installed psutil 08:48:46 INFO - Cleaning up... 08:48:47 INFO - Return code: 0 08:48:47 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:48:47 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')]} 08:48:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:48:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48:47 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')]} 08:48:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:48:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48:47 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 0x109750a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109756570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7facc84a3960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1095f4d30>, '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 0x109418db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1095ebb20>, '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.pxMWQjMwDO/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.yKsvP79hmN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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 08:48:47 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 08:48:47 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 08:48:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pxMWQjMwDO/Render', 08:48:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:48:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:48:47 INFO - 'HOME': '/Users/cltbld', 08:48:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:48:47 INFO - 'LOGNAME': 'cltbld', 08:48:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:48:47 INFO - 'MOZ_NO_REMOTE': '1', 08:48:47 INFO - 'NO_EM_RESTART': '1', 08:48:47 INFO - 'PAGER': '/bin/cat', 08:48:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:48:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:48:47 INFO - 'PWD': '/builds/slave/test', 08:48:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:48:47 INFO - 'SHELL': '/bin/bash', 08:48:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yKsvP79hmN/Listeners', 08:48:47 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 08:48:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:48:47 INFO - 'USER': 'cltbld', 08:48:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:48:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:48:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:48:47 INFO - 'XPC_FLAGS': '0x0', 08:48:47 INFO - 'XPC_SERVICE_NAME': '0', 08:48:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:48:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:48:47 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:48:47 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 08:48:47 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 08:48:47 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 08:48:47 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 08:48:47 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 08:48:47 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 08:48:49 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:48:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:48:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:48:49 INFO - Installing collected packages: mozsystemmonitor 08:48:49 INFO - Running setup.py install for mozsystemmonitor 08:48:50 INFO - Successfully installed mozsystemmonitor 08:48:50 INFO - Cleaning up... 08:48:50 INFO - Return code: 0 08:48:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:48:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 08:48:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:48:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 08:48:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:48:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48:50 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 0x109750a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109756570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7facc84a3960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1095f4d30>, '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 0x109418db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1095ebb20>, '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.pxMWQjMwDO/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.yKsvP79hmN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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 08:48:50 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 08:48:50 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 08:48:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pxMWQjMwDO/Render', 08:48:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:48:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:48:50 INFO - 'HOME': '/Users/cltbld', 08:48:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:48:50 INFO - 'LOGNAME': 'cltbld', 08:48:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:48:50 INFO - 'MOZ_NO_REMOTE': '1', 08:48:50 INFO - 'NO_EM_RESTART': '1', 08:48:50 INFO - 'PAGER': '/bin/cat', 08:48:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:48:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:48:50 INFO - 'PWD': '/builds/slave/test', 08:48:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:48:50 INFO - 'SHELL': '/bin/bash', 08:48:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yKsvP79hmN/Listeners', 08:48:50 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 08:48:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:48:50 INFO - 'USER': 'cltbld', 08:48:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:48:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:48:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:48:50 INFO - 'XPC_FLAGS': '0x0', 08:48:50 INFO - 'XPC_SERVICE_NAME': '0', 08:48:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:48:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:48:50 INFO - Downloading/unpacking blobuploader==1.2.4 08:48:50 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 08:48:50 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 08:48:50 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 08:48:50 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 08:48:50 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 08:48:50 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 08:48:52 INFO - Downloading blobuploader-1.2.4.tar.gz 08:48:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:48:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:48: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 08:48: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 08:48: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 08:48: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 08:48: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 08:48: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 08:48:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:48:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:48:53 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 08:48:53 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 08:48:53 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 08:48:53 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 08:48:53 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 08:48:53 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 08:48:53 INFO - Downloading docopt-0.6.1.tar.gz 08:48:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:48:54 INFO - Installing collected packages: blobuploader, requests, docopt 08:48:54 INFO - Running setup.py install for blobuploader 08:48:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:48:54 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:48:54 INFO - Running setup.py install for requests 08:48:54 INFO - Running setup.py install for docopt 08:48:54 INFO - Successfully installed blobuploader requests docopt 08:48:54 INFO - Cleaning up... 08:48:54 INFO - Return code: 0 08:48:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:48: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')]} 08:48:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:48:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48: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')]} 08:48:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:48:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48: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 0x109750a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109756570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7facc84a3960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1095f4d30>, '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 0x109418db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1095ebb20>, '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.pxMWQjMwDO/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.yKsvP79hmN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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 08:48: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 08:48: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 08:48:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pxMWQjMwDO/Render', 08:48:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:48:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:48:54 INFO - 'HOME': '/Users/cltbld', 08:48:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:48:54 INFO - 'LOGNAME': 'cltbld', 08:48:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:48:54 INFO - 'MOZ_NO_REMOTE': '1', 08:48:54 INFO - 'NO_EM_RESTART': '1', 08:48:54 INFO - 'PAGER': '/bin/cat', 08:48:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:48:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:48:54 INFO - 'PWD': '/builds/slave/test', 08:48:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:48:54 INFO - 'SHELL': '/bin/bash', 08:48:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yKsvP79hmN/Listeners', 08:48:54 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 08:48:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:48:54 INFO - 'USER': 'cltbld', 08:48:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:48:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:48:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:48:54 INFO - 'XPC_FLAGS': '0x0', 08:48:54 INFO - 'XPC_SERVICE_NAME': '0', 08:48:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:48:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:48:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:48:55 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-1D8ELK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:48:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:48:55 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-bNbkrl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:48:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:48:55 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-dfWDOL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:48:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:48:55 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-UjRTdN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:48:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:48:55 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-ycOYQ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:48:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:48:55 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-n19AKE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:48:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:48:55 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-dOAzFx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:48:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:48:55 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-6Wnbyq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:48:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:48:56 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-VuMoHw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:48:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:48:56 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-43tv1v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:48:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:48:56 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-oH9UIJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:48:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:48:56 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-xpiQXO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:48:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:48:56 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-8aAlpd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:48:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:48:56 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-hKHFKR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:48:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:48:56 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-9cm2uH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:48:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:48:56 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-92buDn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:48:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:48:56 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-NdbQSN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:48:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:48:56 INFO - Running setup.py install for manifestparser 08:48:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:48:57 INFO - Running setup.py install for mozcrash 08:48:57 INFO - Running setup.py install for mozdebug 08:48:57 INFO - Running setup.py install for mozdevice 08:48:57 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:48:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:48:57 INFO - Running setup.py install for mozfile 08:48:57 INFO - Running setup.py install for mozhttpd 08:48:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:48:57 INFO - Running setup.py install for mozinfo 08:48:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:48:58 INFO - Running setup.py install for mozInstall 08:48:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:48:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:48:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:48:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:48:58 INFO - Running setup.py install for mozleak 08:48:58 INFO - Running setup.py install for mozlog 08:48:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:48:58 INFO - Running setup.py install for moznetwork 08:48:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:48:58 INFO - Running setup.py install for mozprocess 08:48:58 INFO - Running setup.py install for mozprofile 08:48:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:48:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:48:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:48:58 INFO - Running setup.py install for mozrunner 08:48:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:48:59 INFO - Running setup.py install for mozscreenshot 08:48:59 INFO - Running setup.py install for moztest 08:48:59 INFO - Running setup.py install for mozversion 08:48:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:48:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:48:59 INFO - Cleaning up... 08:48:59 INFO - Return code: 0 08:48:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:48:59 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')]} 08:48:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:48:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48:59 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')]} 08:48:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:48:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48:59 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 0x109750a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109756570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7facc84a3960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1095f4d30>, '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 0x109418db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1095ebb20>, '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.pxMWQjMwDO/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.yKsvP79hmN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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 08:48:59 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 08:48:59 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 08:48:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pxMWQjMwDO/Render', 08:48:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:48:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:48:59 INFO - 'HOME': '/Users/cltbld', 08:48:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:48:59 INFO - 'LOGNAME': 'cltbld', 08:48:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:48:59 INFO - 'MOZ_NO_REMOTE': '1', 08:48:59 INFO - 'NO_EM_RESTART': '1', 08:48:59 INFO - 'PAGER': '/bin/cat', 08:48:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:48:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:48:59 INFO - 'PWD': '/builds/slave/test', 08:48:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:48:59 INFO - 'SHELL': '/bin/bash', 08:48:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yKsvP79hmN/Listeners', 08:48:59 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 08:48:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:48:59 INFO - 'USER': 'cltbld', 08:48:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:48:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:48:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:48:59 INFO - 'XPC_FLAGS': '0x0', 08:48:59 INFO - 'XPC_SERVICE_NAME': '0', 08:48:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:48:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:48:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:48:59 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-nk8fNE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:48:59 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)) 08:48:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:48:59 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-AkHOyJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:49:00 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)) 08:49:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:49:00 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-OSod0Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:49:00 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)) 08:49:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:49:00 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-IGl9Jp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:49:00 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)) 08:49:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:49:00 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-PIFPB4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:49:00 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)) 08:49:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:49:00 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-8ZyY3o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:49:00 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)) 08:49:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:49:00 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-z6OCVm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:49:00 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)) 08:49:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:49:00 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-KZ0H_e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:49:00 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)) 08:49:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:49:00 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-upH4W3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:49:00 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)) 08:49:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:49:00 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-FZ7Q8h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:49:00 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)) 08:49:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:49:00 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-4LyUKM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:49:01 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)) 08:49:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:49:01 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-boY1QJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:49:01 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)) 08:49:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:49:01 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-QZL7UD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:49:01 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)) 08:49:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:49:01 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-tl1eVp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:49:01 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)) 08:49:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:49:01 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-NZCcU1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:49:01 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)) 08:49:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:49:01 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-dcdOOh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:49:01 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)) 08:49:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:49:01 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-G1C2ao-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:49:01 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)) 08:49:01 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)) 08:49:01 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)) 08:49:01 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)) 08:49:01 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)) 08:49:01 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)) 08:49:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:49:01 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 08:49:01 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 08:49:01 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 08:49:01 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 08:49:01 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 08:49:01 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 08:49:04 INFO - Downloading blessings-1.6.tar.gz 08:49:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:49:04 INFO - Installing collected packages: blessings 08:49:04 INFO - Running setup.py install for blessings 08:49:04 INFO - Successfully installed blessings 08:49:04 INFO - Cleaning up... 08:49:04 INFO - Return code: 0 08:49:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:49:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:49:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:49:04 INFO - Reading from file tmpfile_stdout 08:49:04 INFO - Current package versions: 08:49:04 INFO - blessings == 1.6 08:49:04 INFO - blobuploader == 1.2.4 08:49:04 INFO - docopt == 0.6.1 08:49:04 INFO - manifestparser == 1.1 08:49:04 INFO - mozInstall == 1.12 08:49:04 INFO - mozcrash == 0.17 08:49:04 INFO - mozdebug == 0.1 08:49:04 INFO - mozdevice == 0.48 08:49:04 INFO - mozfile == 1.2 08:49:04 INFO - mozhttpd == 0.7 08:49:04 INFO - mozinfo == 0.9 08:49:04 INFO - mozleak == 0.1 08:49:04 INFO - mozlog == 3.2 08:49:04 INFO - moznetwork == 0.27 08:49:04 INFO - mozprocess == 0.22 08:49:04 INFO - mozprofile == 0.28 08:49:04 INFO - mozrunner == 6.11 08:49:04 INFO - mozscreenshot == 0.1 08:49:04 INFO - mozsystemmonitor == 0.3 08:49:04 INFO - moztest == 0.7 08:49:04 INFO - mozversion == 1.4 08:49:04 INFO - psutil == 3.1.1 08:49:04 INFO - requests == 1.2.3 08:49:04 INFO - wsgiref == 0.1.2 08:49:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:49: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')]} 08:49:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49: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')]} 08:49:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:04 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 0x109750a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109756570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7facc84a3960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1095f4d30>, '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 0x109418db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1095ebb20>, '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.pxMWQjMwDO/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.yKsvP79hmN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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 08:49:04 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 08:49:04 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 08:49:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pxMWQjMwDO/Render', 08:49:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:49:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:49:04 INFO - 'HOME': '/Users/cltbld', 08:49:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:49:04 INFO - 'LOGNAME': 'cltbld', 08:49:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:04 INFO - 'MOZ_NO_REMOTE': '1', 08:49:04 INFO - 'NO_EM_RESTART': '1', 08:49:04 INFO - 'PAGER': '/bin/cat', 08:49:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:49:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:04 INFO - 'PWD': '/builds/slave/test', 08:49:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:49:04 INFO - 'SHELL': '/bin/bash', 08:49:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yKsvP79hmN/Listeners', 08:49:04 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 08:49:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:49:04 INFO - 'USER': 'cltbld', 08:49:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:49:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:49:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:04 INFO - 'XPC_FLAGS': '0x0', 08:49:04 INFO - 'XPC_SERVICE_NAME': '0', 08:49:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:49:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:05 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)) 08:49:05 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)) 08:49:05 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)) 08:49:05 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)) 08:49:05 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)) 08:49:05 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)) 08:49:05 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)) 08:49:05 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)) 08:49:05 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)) 08:49:05 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)) 08:49:05 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)) 08:49:05 INFO - Cleaning up... 08:49:05 INFO - Return code: 0 08:49:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:49:05 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')]} 08:49:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:05 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')]} 08:49:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:05 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 0x109750a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109756570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7facc84a3960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1095f4d30>, '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 0x109418db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1095ebb20>, '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.pxMWQjMwDO/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.yKsvP79hmN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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 08:49:05 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 08:49:05 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 08:49:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pxMWQjMwDO/Render', 08:49:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:49:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:49:05 INFO - 'HOME': '/Users/cltbld', 08:49:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:49:05 INFO - 'LOGNAME': 'cltbld', 08:49:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:05 INFO - 'MOZ_NO_REMOTE': '1', 08:49:05 INFO - 'NO_EM_RESTART': '1', 08:49:05 INFO - 'PAGER': '/bin/cat', 08:49:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:49:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:05 INFO - 'PWD': '/builds/slave/test', 08:49:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:49:05 INFO - 'SHELL': '/bin/bash', 08:49:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yKsvP79hmN/Listeners', 08:49:05 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 08:49:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:49:05 INFO - 'USER': 'cltbld', 08:49:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:49:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:49:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:05 INFO - 'XPC_FLAGS': '0x0', 08:49:05 INFO - 'XPC_SERVICE_NAME': '0', 08:49:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:49:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:05 INFO - Downloading/unpacking jsonschema 08:49:05 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 08:49:05 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 08:49:05 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 08:49:05 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 08:49:05 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 08:49:05 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 08:49:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:49:07 INFO - Downloading/unpacking functools32 (from jsonschema) 08:49: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 08:49: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 08:49: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 08:49: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 08:49: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 08:49: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 08:49:08 INFO - Downloading functools32-3.2.3-2.tar.gz 08:49:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:49:08 INFO - warning: no files found matching '*.txt' 08:49:08 INFO - no previously-included directories found matching 'build' 08:49:08 INFO - no previously-included directories found matching 'dist' 08:49:08 INFO - no previously-included directories found matching '.git*' 08:49:08 INFO - Installing collected packages: jsonschema, functools32 08:49:08 INFO - Running setup.py install for functools32 08:49:08 INFO - warning: no files found matching '*.txt' 08:49:08 INFO - no previously-included directories found matching 'build' 08:49:08 INFO - no previously-included directories found matching 'dist' 08:49:08 INFO - no previously-included directories found matching '.git*' 08:49:08 INFO - Successfully installed jsonschema functools32 08:49:08 INFO - Cleaning up... 08:49:08 INFO - Return code: 0 08:49:08 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 08:49:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 08:49:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 08:49:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:08 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 0x109750a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109756570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7facc84a3960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1095f4d30>, '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 0x109418db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1095ebb20>, '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.pxMWQjMwDO/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.yKsvP79hmN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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 08:49:08 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 08:49:08 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 08:49:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pxMWQjMwDO/Render', 08:49:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:49:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:49:08 INFO - 'HOME': '/Users/cltbld', 08:49:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:49:08 INFO - 'LOGNAME': 'cltbld', 08:49:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:08 INFO - 'MOZ_NO_REMOTE': '1', 08:49:08 INFO - 'NO_EM_RESTART': '1', 08:49:08 INFO - 'PAGER': '/bin/cat', 08:49:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:49:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:08 INFO - 'PWD': '/builds/slave/test', 08:49:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:49:08 INFO - 'SHELL': '/bin/bash', 08:49:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yKsvP79hmN/Listeners', 08:49:08 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 08:49:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:49:08 INFO - 'USER': 'cltbld', 08:49:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:49:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:49:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:08 INFO - 'XPC_FLAGS': '0x0', 08:49:08 INFO - 'XPC_SERVICE_NAME': '0', 08:49:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:49:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:08 INFO - Downloading/unpacking flake8 08:49:08 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 08:49:08 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 08:49:08 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 08:49:08 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 08:49:08 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 08:49:08 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 08:49:11 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 08:49:11 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 08:49:11 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 08:49:11 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 08:49:11 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 08:49:11 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 08:49:11 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 08:49:11 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 08:49:11 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 08:49:11 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 08:49:11 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 08:49:11 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 08:49:11 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 08:49:11 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 08:49:11 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 08:49:11 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 08:49:11 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 08:49:11 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 08:49:11 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 08:49:11 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 08:49:11 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 08:49:11 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 08:49:11 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 08:49:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 08:49:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:49:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:49:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:49:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:49:12 INFO - no previously-included directories found matching 'docs/_build' 08:49:12 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 08:49:12 INFO - Running setup.py install for pep8 08:49:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:49:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:49:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:49:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:49:12 INFO - no previously-included directories found matching 'docs/_build' 08:49:12 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 08:49:12 INFO - Successfully installed flake8 mccabe pyflakes pep8 08:49:12 INFO - Cleaning up... 08:49:12 INFO - Return code: 0 08:49:12 INFO - Running post-action listener: _resource_record_post_action 08:49:12 INFO - Running post-action listener: _start_resource_monitoring 08:49:12 INFO - Starting resource monitoring. 08:49:12 INFO - ##### 08:49:12 INFO - ##### Finished create-virtualenv step (success) 08:49:12 INFO - ##### 08:49:12 INFO - ##### 08:49:12 INFO - ##### Running install step. 08:49:12 INFO - ##### 08:49:12 INFO - Running pre-action listener: _resource_record_pre_action 08:49:12 INFO - Running main action method: install 08:49:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:49:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:49:13 INFO - Reading from file tmpfile_stdout 08:49:13 INFO - Detecting whether we're running mozinstall >=1.0... 08:49:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:49:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:49:13 INFO - Reading from file tmpfile_stdout 08:49:13 INFO - Output received: 08:49:13 INFO - Usage: mozinstall [options] installer 08:49:13 INFO - Options: 08:49:13 INFO - -h, --help show this help message and exit 08:49:13 INFO - -d DEST, --destination=DEST 08:49:13 INFO - Directory to install application into. [default: 08:49:13 INFO - "/builds/slave/test"] 08:49:13 INFO - --app=APP Application being installed. [default: firefox] 08:49:13 INFO - mkdir: /builds/slave/test/build/application 08:49:13 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'] 08:49:13 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 08:49:46 INFO - Reading from file tmpfile_stdout 08:49:46 INFO - Output received: 08:49:46 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:49:46 INFO - Running post-action listener: _resource_record_post_action 08:49:46 INFO - ##### 08:49:46 INFO - ##### Finished install step (success) 08:49:46 INFO - ##### 08:49:46 INFO - ##### 08:49:46 INFO - ##### Running run-tests step. 08:49:46 INFO - ##### 08:49:46 INFO - Running pre-action listener: _resource_record_pre_action 08:49:46 INFO - Running main action method: run_tests 08:49:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:49:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:49:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:49:46 INFO - 2016-05-16 08:49:46.746 screenresolution[1649:8156] starting screenresolution argv=screenresolution get 08:49:46 INFO - 2016-05-16 08:49:46.749 screenresolution[1649:8156] Display 0: 1600x1200x32@60 08:49:46 INFO - 2016-05-16 08:49:46.755 screenresolution[1650:8158] starting screenresolution argv=screenresolution list 08:49:46 INFO - Available Modes on Display 0 08:49:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:49:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:49:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:49:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:49:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:49:46 INFO - Intel Iris: 08:49:46 INFO - Chipset Model: Intel Iris 08:49:46 INFO - Type: GPU 08:49:46 INFO - Bus: Built-In 08:49:46 INFO - VRAM (Dynamic, Max): 1536 MB 08:49:46 INFO - Vendor: Intel (0x8086) 08:49:46 INFO - Device ID: 0x0a2e 08:49:46 INFO - Revision ID: 0x0009 08:49:46 INFO - Displays: 08:49:46 INFO - Display: 08:49:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:49:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:49:46 INFO - Display Serial Number: 1600x1200 60 08:49:46 INFO - Main Display: Yes 08:49:46 INFO - Mirror: Off 08:49:46 INFO - Online: Yes 08:49:46 INFO - Rotation: Supported 08:49:46 INFO - Return code: 0 08:49:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:49:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:49:46 INFO - Python 2.7.3 08:49:46 INFO - Return code: 0 08:49:46 INFO - grabbing minidump binary from tooltool 08:49: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')]} 08:49: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 0x1095f4d30>, '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 0x109418db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1095ebb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:49: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 08:49: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 08:49: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 08:49:47 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:49:47 INFO - Return code: 0 08:49:47 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:49:47 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:49:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:49:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:49:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:49:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 08:49:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 08:49:48 INFO - Return code: 0 08:49:48 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/bsJth3rIRo-exmrUl9e9gQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0179', '--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 08:49:48 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/bsJth3rIRo-exmrUl9e9gQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0179 --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 08:49:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pxMWQjMwDO/Render', 08:49:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:49:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:49:48 INFO - 'HOME': '/Users/cltbld', 08:49:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:49:48 INFO - 'LOGNAME': 'cltbld', 08:49:48 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:49:48 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:49:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:48 INFO - 'MOZ_NO_REMOTE': '1', 08:49:48 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:49:48 INFO - 'NO_EM_RESTART': '1', 08:49:48 INFO - 'PAGER': '/bin/cat', 08:49:48 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:49:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:48 INFO - 'PWD': '/builds/slave/test', 08:49:48 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:49:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:49:48 INFO - 'SHELL': '/bin/bash', 08:49:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yKsvP79hmN/Listeners', 08:49:48 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 08:49:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:49:48 INFO - 'USER': 'cltbld', 08:49:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:49:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:49:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:48 INFO - 'XPC_FLAGS': '0x0', 08:49:48 INFO - 'XPC_SERVICE_NAME': '0', 08:49:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:49:48 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/bsJth3rIRo-exmrUl9e9gQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0179', '--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 08:49:48 INFO - mozversion application_buildid: 20160516074433 08:49:48 INFO - mozversion application_changeset: e9533997fcef31fa5d2db464c6ceec0ca6ea560f 08:49:48 INFO - mozversion application_display_name: Nightly 08:49:48 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:49:48 INFO - mozversion application_name: Firefox 08:49:48 INFO - mozversion application_remotingname: firefox 08:49:48 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 08:49:48 INFO - mozversion application_vendor: Mozilla 08:49:48 INFO - mozversion application_version: 49.0a1 08:49:48 INFO - mozversion platform_buildid: 20160516074433 08:49:48 INFO - mozversion platform_changeset: e9533997fcef31fa5d2db464c6ceec0ca6ea560f 08:49:48 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 08:49:48 INFO - mozversion platform_version: 49.0a1 08:49:48 INFO - using testdate: 1463413788 08:49:48 INFO - actual date: 1463413788 08:49:48 INFO - starting webserver on 'localhost:49276' 08:49:48 INFO - SUITE-START | Running 2 tests 08:49:48 INFO - TEST-START | dromaeo_css 08:49:48 INFO - operating with platform_type : mac_ 08:49:48 INFO - Initialising browser for dromaeo_css test... 08:49:48 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/tmpgSA_pt/profile http://localhost:49276/getInfo.html) 08:49:50 INFO - PROCESS | 1668 | __metrics Screen width/height:1600/1200 08:49:50 INFO - PROCESS | 1668 | colorDepth:24 08:49:50 INFO - PROCESS | 1668 | Browser inner width/height: 1024/689 08:49:50 INFO - PROCESS | 1668 | __metrics 08:49:50 INFO - PROCESS | 1668 | 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" 08:49:50 ERROR - PROCESS | 1668 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 08:49:51 INFO - TEST-INFO | 1668: exit 0 08:49:51 INFO - Browser initialized. 08:49:51 INFO - Running cycle 1/1 for dromaeo_css test... 08:49:51 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/tmpgSA_pt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:50:03 INFO - PROCESS | 1671 | RSS: Main: 239095808 08:50:03 INFO - PROCESS | 1671 | 08:50:54 INFO - PROCESS | 1671 | 1463413854929 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] 08:53:05 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49276/tests/dromaeo/cssquery-ext.html) 08:53:06 INFO - PROCESS | 1671 | RSS: Main: 371372032 08:53:06 INFO - PROCESS | 1671 | 08:56:09 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-ext.html (next: http://localhost:49276/tests/dromaeo/cssquery-jquery.html) 08:56:10 INFO - PROCESS | 1671 | RSS: Main: 376258560 08:56:10 INFO - PROCESS | 1671 | 08:59:12 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49276/tests/dromaeo/cssquery-mootools.html) 08:59:13 INFO - PROCESS | 1671 | RSS: Main: 366624768 08:59:13 INFO - PROCESS | 1671 | 09:02:15 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49276/tests/dromaeo/cssquery-prototype.html) 09:02:16 INFO - PROCESS | 1671 | RSS: Main: 365953024 09:02:16 INFO - PROCESS | 1671 | 09:05:19 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49276/tests/dromaeo/cssquery-yui.html) 09:05:19 INFO - PROCESS | 1671 | RSS: Main: 331833344 09:05:19 INFO - PROCESS | 1671 | 09:08:23 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-yui.html (next: http://localhost:49276/tests/dromaeo/cssquery-dojo.html) 09:08:23 INFO - PROCESS | 1671 | RSS: Main: 422768640 09:08:23 INFO - PROCESS | 1671 | 09:08:23 INFO - PROCESS | 1671 | __start_tp_report 09:08:23 INFO - PROCESS | 1671 | _x_x_mozilla_page_load 09:08:23 INFO - PROCESS | 1671 | _x_x_mozilla_page_load_details 09:08:23 INFO - PROCESS | 1671 | |i|pagename|runs| 09:08:23 INFO - PROCESS | 1671 | |0;dojo.html;2849.98825416977;3221.726153276971;3260.646831564901;3398.980305908227;3423.0415483664583;5526.336839579251;5538.307711536058;5572.86067848303;5612.186232996218;5642.069058605331;5586.217929489864;5604.131359639263;5687.17535957286;5697.373288938214;5720.771169153229;5000.549950504454;5046.142155833517;5094.006668699605;5102.897942041157;5235.240890070937;5154.561862241714;5161.509656582619;5177.559907407875;5207.791688332462;5209.531142197202;5021.84934451967;5038.748062596892;5086.18621020635;5087.287779710843;5101.668391554537;5023;5028.572571331441;5045.899082018339;5074.949250507485;5146.536811686966;5095.235714642796;5099.974500127476;5112.233165025239;5122.538971492546;5128.102582048164;5388.326459192601;5483.424240454735;5532.889342213133;5611.775528978836;5613.550915926716;6106.931008400048;6128;6136.294326152505;6141.946315431838;6153.446189842935;6185.381461853824;6237.251529816451;6260.686965651745;6266.185395898549;6268.49852011838;5593.496585307342;5638.915416268677;5640.661560306395;5737.139429085629;5797.217375654317;6270.435660790459;6336.936630633634;6340.302566717657;6347.682615869188;6347.746090156435;5993.280806303184;6004.039353703474;6034.125051867542;6057.394260573908;6072.787452439144;6036.879262414815;6056.818295451144;6103.489197737777;6121.707990350846;6128.3565225651555;6029.457348838626;6066.059760737093;6072.453479186806;6074.271087469532;6090.299615544269;5222.582193424517;5987.940120598738;6071.180390647206;6102.176206212193;6139.584134579375;4830.637702172351;4831.396075490564;4842.443119041355;4869.196582563908;4872.658913875995;4311.288637374861;4319.352097185448;4328.026194106301;4333.761643109596;4345.239583072949;5467.398586155519;5486.286782718301;5504.4770746778995;5567.248421463131;5576.581756368289;6005.189299444519;6063.757449701962;6094.177286066413;6106.2061931948565;6110.938890611126;5495.148252020943;5536.252605898233;5554.333479982347;5555.638883472642;5582.106862901916;5220.686758794484;5228.816991405282;5237.9476205238225;5242.318498595235;5288.788448462018;6197.5351848611535;6243.656598887014;6254.405831446005;6299.118123462719;6303.432691057827;6217.937820621736;6221.160143380585;6224.782132625336;6242.781502647385;6254.437100660963;6272.090546870587;6307.779227727191;6320.1151838743535;6323.9683801580695;6332.651704156316;5876.764929402776;6054.969725151523;6077.4834139098675;6102.176206212104;6128;6123.118851766045;6193.535484838565;6213.5339849512375;6218.00511918091;6224.128621993015;5786.566007549366;5808.680522571299;5992.910106348496;6029.728662210124;6054.424829641177;5845.240118784531;5875.3243377010485;5916.526677865846;5984.31180414258;6005.129256257906;5820.039693450533;5825.009748342707;5831.679257640871;5839.240898683144;5992;5837.153612726046;5837.532997360286;6026.879462410815;6053.727582258897;6066.1204125402455;5804.709764511673;5841.883162336814;5848.444397782204;5862.267216597926;5866.706664666664 09:08:23 INFO - PROCESS | 1671 | |1;ext.html;516.7622893469004;534.6952237224783;540.667489493961;553.4520824383859;559.6306437751085;25296.87351563242;25868.83590238439;32573.67426325736;32583.348333033326;32610.836945815237;43943.340849887274;45264;45321;47731.28403073957;47824.80437989052;9254.398464099846;9674.709758707233;9842.163416109639;9855.408675479459;9858.802823943517;9079.273658107368;9825.65610203643;9826.901730982707;9831.164351030171;9841.36031157976;9795.216382689403;9850.704478865646;9851.95074024629;9854.162396196296;9855.457949812793;9752.317337786359;9803;9806.16647584956;9813.558389872473;9830.557624906825;9731.221502279801;9787;9799.16707079899;9806.656767013192;9827.213822894222;41244;41600.1679966402;41616.79191604022;41651.1669766606;41668.37497437541;3182.8567714452906;3344.3311337732553;3504.56192975878;3826.598207188233;3848.692104631623;28646.856765716246;29298.70701292981;30365.392692146255;30604.693953060407;30662.386752265054;2786.122371452986;2812.9578056329174;2847.786416018807;2878.913632591026;2893.56596510523;510128;511750;515631;516425;517641.9999999962;30099.398012039637;35058;36065.81967090148;47438.76280618575;61531;37547.4367884482;37718.24563508714;37757.81121094432;37829;37936.051598710255;15237.6190595236;15395.769063464057;15399.846001540065;15406.614834629225;15407.075575465507;11458.739538650741;12362.258264503978;12924.353782310847;12981.870181298254;13020.218786872818;8204.261616454547;8222.17778222173;8224.506529608243;8297.668093276323;8313.45962512447;2984.731374176291;3388.373150967079;3399.85808940366;3415.4023045966856;3426.0920855973186;956.5791051937265;990.321629683669;1011.4487604255551;1096.4607911172827;1125.7410795517078;24982.625955572617;28255.858720706266;29680.257993550335;30087.398252035273;30535.541966870514;964.7925695975399;1118.6923596011;1138.7267055906689;1143.1026644084354;1286.948522059126;13143.737125257441;14688.19214943167;15475.690486190213;16008.359665613478;16090.436834710728;709255;720648;725882.741172592;728799;732346;64098.35901640924;64665.67667161634;64704.35295647077;64709;64735;1325.4896864707157;1414.2504472629523;1432.1478720161488;1462.0058360314874;1483.384395475887;26139.607905881807;29321.7067829319;30546.930857419884;30558.541621875243;31195.376092478476;53073;53331;53765;53959.920801582964;53969.19046214232;550093;552123;552518;553390;556252;21966.670499942833;23006;26921.596176057767;26964.191074267805;26969.460610788068;45482.772586138184;46432.7678361606;48010.75994620004;48044.27933581069;48056.5194348052;36333.38961335641;37764;38360.42459363056;38918.6108138915;39174.41238381484;36933.26133477369;37241.62758372381;37428;37872;38203;37216.255674886896;37423;38348.6165138345;38377.232455351295;38404.23191536209;27116.1865144046;31794.84102579472;33099;33201.667983320825;33317.16707082342;24423.023079076636;24576.6313505294;24731.13441029555;24911.750882490942;24931 09:08:23 INFO - PROCESS | 1671 | |2;jquery.html;394.68819632490334;399.99200015999696;401.3438028822875;407.1429640606525;425.4047322540473;12053.950828359179;17475.912620436913;20014.49963750907;20071.397858064243;20974.790252097475;16425.260863261152;17153.82846171538;20225.595488090228;20963.16147354104;21041.684374734352;5604.327480702311;5845.240118784552;5876.265466816648;5903.11453282009;5927.407259274081;4991.500849915015;5505.944940550603;5774.6535207887455;5820.185174075633;5873.412658734135;3783.6594706476412;4010.0977280112033;7052.541584796997;7409.814754631124;7412.295831895961;7390.076240469942;7398.445116616248;7481.877718342238;7487.288707572772;7494.587797671126;6689.397954184147;7012.859742805166;7201.639918004079;7233.31283528064;7242.963785181093;23501.88249058761;26969.86515067432;27805.443891122268;27880.442391152064;27990.72009279922;2247.358905010249;2262.1856131792542;2294.9311520654405;2307.699999000136;2406.6991626046743;15676.980996235323;15708.764368534481;16050.75923861143;16159.272832722556;16408.507744767558;1517.9089254644646;1869.3644160985195;1871.5414723392855;1883.5950270691867;1991.163711241282;140804.59195408018;144587;146052;146619;147541.26229368785;20440.795592044185;20844.853533055826;20883.68674469884;21101.261455848973;21232;35402.114947126014;35436.29127417437;35543.28913421769;35555.82222088873;35596.46605300922;10824.891751082547;10971.83542246867;10982.670519884416;11033.779324413626;11215.551377944954;9040.593173307218;9051.321150913707;9068.047854975264;9151.95424022889;9213.683074093322;7749.961250193713;9304.95347523258;9430.056994300516;9581.808363832684;9594.737789151583;2975;3128.562001319817;3742.868999585056;3771.2268984858038;3785.470034195215;915.5285028210477;942.5664194470484;968.1093394077466;976.9267304952157;1004.42747633848;19184.71222931657;20096.497587560134;20130.29543965954;20161.39515814528;20278.44045570187;930.6510058727977;954.379653225409;983.6999715086824;1019.6227395863579;1029.145808978546;10414.739631509121;10624.893751062546;10659.987301206373;10660.62687805923;10673.83989240162;602529;605197;619680;619974;629281;131014.34492827475;132838;137113;139184.86521078288;140647;1024.882138554076;1025.2771795883884;1047.1099565369375;1049.5539395756773;1052.4737631184407;17604.29582816673;17801;17883.37408190707;17991.820081799015;18156.45530634083;40222.79888600538;41560.58439415688;44682.87219894576;45754.77122614365;51975;73226;73706.89224185229;74703;74825.50348992881;75756;6682.23154337238;7826.413019023482;7992.600369981594;8091.8381632365845;8251.968503937007;8660.437071590326;11078.390688512209;12064.577739779066;12244.571439999558;12255.080868934687;9973.252006099427;10347.327423717434;10399.376037437778;10605.840912386475;10669.626563070255;5141.562967147835;8848.867266990872;9683.757906052406;10137.239707021856;10608.840867386842;8734.519601421984;10787.67636970892;10821.891781082088;11047.72380690489;11127.221094523306;7874.881876771968;7900.604969751605;7901.920980790119;8149.307308878811;8167.591620419074;7991.84016319682;9642.951785241028;10575.735606609896;10906.61826836057;10972.177086718688 09:08:23 INFO - PROCESS | 1671 | |3;mootools.html;1426.7075249573838;1575.4013474879544;1624.8618867396272;1836.1186630417399;1979.7030445433174;7214.386777123944;8128.4310098293145;9040.14118658728;9050.456972581642;9156.221721153694;8841.690540831072;8920.884889388826;9007.144321289466;9010.54947252638;9066.546672666356;2140.283005193262;2216.5345277491747;2429.854208747472;2467.568175569277;2469.074097213545;2237.1387015998794;2266.931992040233;2267.9886600567056;2398.07674045492;2470.8270421070533;2426.4055306449854;2434.9756502434925;2436.5735996200683;2437.792787613055;2442.6213936839818;2392.1986134644917;2408.963865542018;2428.344347026293;2428.659987601737;2430.769076937688;2113.5244569971787;2346.6831977682964;2403.2910291463886;2420.1166574200465;2421.5278020785954;7679.923200767977;8998.775030624287;9007.45955242681;9042.547872606343;9049.049849765746;3606.368885445063;4655.674102812804;4658.557437043475;5476.835694929158;5479.123340265578;3428.4171690812373;3550.0592343029116;3608.386574282378;3629.6188900165366;3632.8183590820354;1035.1615191694752;1037.7339645632317;1038.0812980512226;1039.2880876599554;1042.2860340666668;11872.228305160223;12025.819612705816;12028.63914082579;12073.577424790177;12120.090993175547;4623.653226007996;4639.791209395586;4759.881002974884;4779.665423420327;4782.617390608743;9465.621375144918;9466.148046675833;9474;9477.28920330978;9485.952570237105;3979.3633018716864;5119;5314.973425132927;5394.838154855361;5410.242566040757;4790.401199850019;4985.750712464363;5035.395752509649;5042.42012168598;5043.117454445531;3387;3480.8085555294197;3649.61679023704;3773.867914622975;3920.5687374388795;954.4321128928416;980.5783513089298;1172.5661505243115;1187.2223693480785;1188.102982248397;901.7114523352465;928.6842473559022;933.1275257634134;939.1406862720671;954.9522523873917;5766.394528574523;5916.171735956969;5968.731407086691;6056.152138700585;6226.346233645492;949.7910459698855;961.5384615384654;1059.6873922192972;1075.876274228458;1085.039739830256;3900.531936167678;3965.980170099131;3991.720579559403;4037.7779222142476;4063.1061166543313;10275.332103413255;10655.946720266349;10945.01494865466;11306.378149201708;11331.376774277494;11175.44122793857;11180.776384472427;11288.830667539993;11296.20926535151;11306.547738090385;8370.707025254087;8411.663533458594;8496.405251632326;8518.318534517348;8527.360447966303;5398.244245805512;5850.882982340244;5861.472467477777;6029.75880964774;6133.233345831771;10035.648752293635;10038.949805250926;10353.63762268317;10427.061564459234;10455.79088418212;10440.42577658236;10597.152227821602;10598.788024239318;10640.042396184072;10716.303440276357;6648.734050638114;7078.5752854826815;7191.892121618075;7289.052423184952;7333.486655934247;8011.479253848481;8334.08325084228;8650.913490865263;8662.610182541926;8741.606627701642;7631.38948884099;7762.961185194038;7782.922170778446;7882.251186137308;7909.762707118795;7537.886931696139;7571.356434702891;7598.658060387406;7624.733134340271;7780.649870755943;7141.64291785419;7195.640217989185;7224.277572242734;7238.457115716447;7243.311885370881;4894.486078961801;5017.222330538772;5044.974775126101;5046.293518907282;5150.989309079298;4630.953690463187;4966.22708266315;4992.775325110451;5035.748212589283;5217.660852044605 09:08:23 INFO - PROCESS | 1671 | |4;prototype.html;294.41117764471056;307.3376872839031;323.1243330574144;327.17388593800666;330.17456359102243;4429.778511074442;5086.771095300711;5199.402068762093;5285.550728188104;5292.115796890695;4963.776630051647;5150.948490515094;5167.870803229912;5194.246834209038;5233.267342572043;3306;3504.844520619813;3745.8127093645344;3827.598102199257;3958.6239307265837;3582.7492075554724;3754.9436758448646;3934.881953541398;3965.345717956534;3967.960320396802;3960.524737031546;3966.6628336591425;3972.801359932006;3990.5410877748996;3999.560048394674;3926.7054970877157;3942.3692209246383;3954.6638535724496;3972.7616343019513;3980.4029395590605;3469.906979301512;3473.5484387029524;3485.7734247274097;3507.8772242971627;3510.7717998330027;4937.629677774181;4951.92572111415;4985.1774457214515;5008.7996480140755;5018.974905125451;4552.203364411214;4552.863414097583;4561.813928378646;4562.247229207177;4588.288815233611;5243.659162154486;5256.001359741636;5429.728513574306;5448;5530.5299049580835;3778.6221377861993;3780.5822816443833;3785.432185172246;3789.4694742736037;3790.2040571479747;4802.831900883452;4977.576905962997;5195.038827868117;5711.857203569903;5776.59563830532;3263.983680081632;3822.3502004658717;3832.1186127190904;3846.788426636506;3857.1128640412862;3577.4097273949506;3863.826127824255;3908.335582950905;3910.550286717007;3910.608939106067;3738.3831667775057;4504.054148628759;4520.660950428731;4522.683412161117;4531.56950089741;5297.284866543045;5500.477454641803;5507.614466987273;5543.703629687025;5585.553155747531;1213.4478812140455;1259.9685007874693;1280.6542233596876;1300.4018151650157;1304.5108084468325;763.1719584251039;772.8454309138195;804.7143264141263;811.9797005074802;827.3257295304307;3590.964090359063;4263.59495847894;4320.611144996902;4345.521992580813;4363.890902727458;4007.8797636070963;4550.726956382628;4593.093866045554;4655.344655344656;4800.927986080212;3799.6200379962333;3804.31522325984;4017.7187596868525;4283.593058659422;4301.483821941324;2235.5520704828186;2361.976380236176;2697.608846717215;2967.8516074196637;3019.531972544259;4979;5890.617109887844;5917.437843404869;5918.881622367615;5921.3190483094995;5533.972330138324;5675.659460432387;5914.378990206053;5919.378465261172;5958.769273815553;5213.556847667915;5217.843464696065;5376.973115134399;5858.004139296329;5945.375735547792;4843;5655.688937108499;5685.459881311269;5694.743736531783;5711.600187986967;4718.197906355997;5095.649652842074;5328.3605967284175;5617.831465056055;5707.429257074426;5831.562632802472;5835.416458354301;5847.152162936435;5850.912236316373;5856.531477481876;3430.7598468106994;3801.4867992821723;3839.500864887547;3845.115623406688;3850.3261929161727;4763.4522029967;5210.8957820844125;5437.341610808741;5613.326400831763;5679.35298763354;4495.932561011653;5211.895762084661;5395;5485.461877089335;5514.558835293247;4646.838290427393;5074.122574647404;5248.821738978281;5361.705106219196;5364.792921592611;5085;5380.730963451733;5392.218128371443;5410.78356865721;5439;4946.604271658331;5514;5537.975474537224;5566.159350048453;5568.192612071146;5435.84720861907;5465.6447330923365;5483.670979741229;5493.560515158858;5497.587680924026 09:08:23 INFO - PROCESS | 1671 | |5;yui.html;668.5353679192962;741.7329761285936;754.5774366354839;755.6637296403101;767.5855038279329;14259.572212833618;14858.108513489185;15367.923160384196;17408.738868916975;17415.73876391855;16420.17899105046;16494.917525412387;16508.669826603462;16517.752233716503;16536.917315413408;944.079522465594;960.0879164793474;961.1685891703696;965.1988849255137;972.4748635736694;765.3265126688507;796.6176235407008;918.5499105438354;959.0026372572551;961.0773657289011;994.2443742755486;1000.3347773730496;1001.8847832499247;1004.3772860826298;1005.0903931941568;794.7695168401157;990.9990909181706;995.2237254941158;998.1715176403568;999.8900120986685;613.9969300153515;616.300498933709;617.1976430640194;715.8711431942248;744.8435828476026;15274.083554986737;15329.233538323038;15344.923275383664;15346.002509836937;15350.155741434217;12148.514059437612;12202.267863928104;12214.450349734283;12466.501339946391;12564.560240391635;10868.945655271753;10904.781904361947;11362.147838912031;11438.428078596036;11720.765584688344;3524.2775231077567;4035.3139966205817;4184.748915065105;4189.937150942738;4264.466941632296;79017;81570;81920.9999999994;82096.17903820945;82134.58932705298;9239.445633262027;9280.350375473652;9432.434053956647;9540.189083927806;9740.366876153028;20089.095990680453;20190;20215.595688086156;20225.19099236014;20230.393088207376;6697.866042679119;7315.524490908074;7365.48441609093;7405.7778266652085;7587.962060189664;4008.599140086026;4056.756594604274;4064.552899181088;4066.2477441673386;4068.3083875741195;3393.8642454301553;3491.371553120412;3495.790252584802;3497.108237399447;3498.772579782306;605.7243954000919;626.6960524145786;628.0453710360227;635.9554831161867;638.4477427025589;495.7001014386244;542.1325878594297;542.9484199001089;571.331542095749;578.5429510686595;7579.772606821804;7787.688492460352;7863.488873223222;7979.72070977513;8038.927416999624;499.9825006124768;548.4953842464942;551.9862003449946;559.6725915339572;568.4741614007027;3671.7613355132366;3720.144366795598;3732.6267373263;3739.8504059837846;3935.980320098381;68285;87999;90714.54642726744;93466;94991.52504237434;77602.44795104179;78457;78618.21381786223;82313.76529352064;82627.58686206651;70796;72952.54094918178;73398.5320293591;73702.26297737061;73960.89058664232;7340.339369456775;7347.632618369167;7384.261573842573;7408.296211859864;7480.887786683313;40220.597794022855;46034.769826152;46489.767551162025;46517;46586;57472.425275746704;59208;60465.69767151136;60669;61459;11019.614313498989;11113.206985217585;11124.944375278072;11340.376279304717;11488.36813975206;12135.939320303341;12226.510939562319;12354.505819767108;12403.503340557218;12423.130380873252;11559;11723.35521546323;11924.403779811148;11937.701557461132;12503;11435.256708313933;11465.942670286595;11478.024367928485;11572.826407603725;12556;11035.83446248323;11308.943455282671;11319.603813866805;11618.360990145624;11746.412679366169;8687.087855775053;9667.323287370098;9908.919126983414;9920.454375009444;10037.096661300518;8801.647934082564;9642.903570964481;9667.129958303787;9845.606175753177;9895.65365212214 09:08:23 INFO - PROCESS | 1671 | __end_tp_report 09:08:23 INFO - PROCESS | 1671 | __start_cc_report 09:08:23 INFO - PROCESS | 1671 | _x_x_mozilla_cycle_collect,1264 09:08:23 INFO - PROCESS | 1671 | __end_cc_report 09:08:23 INFO - PROCESS | 1671 | __startTimestamp1463414903252__endTimestamp 09:08:23 INFO - PROCESS | 1671 | 09:08:23 INFO - PROCESS | 1671 | ------- Summary: start ------- 09:08:23 INFO - PROCESS | 1671 | Number of tests: 6 09:08:23 INFO - PROCESS | 1671 | 09:08:23 INFO - PROCESS | 1671 | [#0] dojo.html Cycles:165 Average:5640.38 Median:5837.15 stddev:651.96 (11.2%) stddev-sans-first:616.12 09:08:23 INFO - PROCESS | 1671 | Values: 2850.0 3221.7 3260.6 3399.0 3423.0 5526.3 5538.3 5572.9 5612.2 5642.1 5586.2 5604.1 5687.2 5697.4 5720.8 5000.5 5046.1 5094.0 5102.9 5235.2 5154.6 5161.5 5177.6 5207.8 5209.5 5021.8 5038.7 5086.2 5087.3 5101.7 5023.0 5028.6 5045.9 5074.9 5146.5 5095.2 5100.0 5112.2 5122.5 5128.1 5388.3 5483.4 5532.9 5611.8 5613.6 6106.9 6128.0 6136.3 6141.9 6153.4 6185.4 6237.3 6260.7 6266.2 6268.5 5593.5 5638.9 5640.7 5737.1 5797.2 6270.4 6336.9 6340.3 6347.7 6347.7 5993.3 6004.0 6034.1 6057.4 6072.8 6036.9 6056.8 6103.5 6121.7 6128.4 6029.5 6066.1 6072.5 6074.3 6090.3 5222.6 5987.9 6071.2 6102.2 6139.6 4830.6 4831.4 4842.4 4869.2 4872.7 4311.3 4319.4 4328.0 4333.8 4345.2 5467.4 5486.3 5504.5 5567.2 5576.6 6005.2 6063.8 6094.2 6106.2 6110.9 5495.1 5536.3 5554.3 5555.6 5582.1 5220.7 5228.8 5237.9 5242.3 5288.8 6197.5 6243.7 6254.4 6299.1 6303.4 6217.9 6221.2 6224.8 6242.8 6254.4 6272.1 6307.8 6320.1 6324.0 6332.7 5876.8 6055.0 6077.5 6102.2 6128.0 6123.1 6193.5 6213.5 6218.0 6224.1 5786.6 5808.7 5992.9 6029.7 6054.4 5845.2 5875.3 5916.5 5984.3 6005.1 5820.0 5825.0 5831.7 5839.2 5992.0 5837.2 5837.5 6026.9 6053.7 6066.1 5804.7 5841.9 5848.4 5862.3 5866.7 09:08:23 INFO - PROCESS | 1671 | 09:08:23 INFO - PROCESS | 1671 | [#1] ext.html Cycles:180 Average:70844.58 Median:38069.53 stddev:162090.25 (425.8%) stddev-sans-first:162458.95 09:08:23 INFO - PROCESS | 1671 | Values: 516.8 534.7 540.7 553.5 559.6 25296.9 25868.8 32573.7 32583.3 32610.8 43943.3 45264.0 45321.0 47731.3 47824.8 9254.4 9674.7 9842.2 9855.4 9858.8 9079.3 9825.7 9826.9 9831.2 9841.4 9795.2 9850.7 9852.0 9854.2 9855.5 9752.3 9803.0 9806.2 9813.6 9830.6 9731.2 9787.0 9799.2 9806.7 9827.2 41244.0 41600.2 41616.8 41651.2 41668.4 3182.9 3344.3 3504.6 3826.6 3848.7 28646.9 29298.7 30365.4 30604.7 30662.4 2786.1 2813.0 2847.8 2878.9 2893.6 510128.0 511750.0 515631.0 516425.0 517642.0 30099.4 35058.0 36065.8 47438.8 61531.0 37547.4 37718.2 37757.8 37829.0 37936.1 15237.6 15395.8 15399.8 15406.6 15407.1 11458.7 12362.3 12924.4 12981.9 13020.2 8204.3 8222.2 8224.5 8297.7 8313.5 2984.7 3388.4 3399.9 3415.4 3426.1 956.6 990.3 1011.4 1096.5 1125.7 24982.6 28255.9 29680.3 30087.4 30535.5 964.8 1118.7 1138.7 1143.1 1286.9 13143.7 14688.2 15475.7 16008.4 16090.4 709255.0 720648.0 725882.7 728799.0 732346.0 64098.4 64665.7 64704.4 64709.0 64735.0 1325.5 1414.3 1432.1 1462.0 1483.4 26139.6 29321.7 30546.9 30558.5 31195.4 53073.0 53331.0 53765.0 53959.9 53969.2 550093.0 552123.0 552518.0 553390.0 556252.0 21966.7 23006.0 26921.6 26964.2 26969.5 45482.8 46432.8 48010.8 48044.3 48056.5 36333.4 37764.0 38360.4 38918.6 39174.4 36933.3 37241.6 37428.0 37872.0 38203.0 37216.3 37423.0 38348.6 38377.2 38404.2 27116.2 31794.8 33099.0 33201.7 33317.2 24423.0 24576.6 24731.1 24911.8 24931.0 09:08:23 INFO - PROCESS | 1671 | 09:08:23 INFO - PROCESS | 1671 | [#2] jquery.html Cycles:180 Average:37217.78 Median:3051.78 stddev:103223.54 (3382.4%) stddev-sans-first:103476.08 09:08:23 INFO - PROCESS | 1671 | Values: 394.7 400.0 401.3 407.1 425.4 12054.0 17475.9 20014.5 20071.4 20974.8 16425.3 17153.8 20225.6 20963.2 21041.7 5604.3 5845.2 5876.3 5903.1 5927.4 4991.5 5505.9 5774.7 5820.2 5873.4 3783.7 4010.1 7052.5 7409.8 7412.3 7390.1 7398.4 7481.9 7487.3 7494.6 6689.4 7012.9 7201.6 7233.3 7243.0 23501.9 26969.9 27805.4 27880.4 27990.7 2247.4 2262.2 2294.9 2307.7 2406.7 15677.0 15708.8 16050.8 16159.3 16408.5 1517.9 1869.4 1871.5 1883.6 1991.2 140804.6 144587.0 146052.0 146619.0 147541.3 20440.8 20844.9 20883.7 21101.3 21232.0 35402.1 35436.3 35543.3 35555.8 35596.5 10824.9 10971.8 10982.7 11033.8 11215.6 9040.6 9051.3 9068.0 9152.0 9213.7 7750.0 9305.0 9430.1 9581.8 9594.7 2975.0 3128.6 3742.9 3771.2 3785.5 915.5 942.6 968.1 976.9 1004.4 19184.7 20096.5 20130.3 20161.4 20278.4 930.7 954.4 983.7 1019.6 1029.1 10414.7 10624.9 10660.0 10660.6 10673.8 602529.0 605197.0 619680.0 619974.0 629281.0 131014.3 132838.0 137113.0 139184.9 140647.0 1024.9 1025.3 1047.1 1049.6 1052.5 17604.3 17801.0 17883.4 17991.8 18156.5 40222.8 41560.6 44682.9 45754.8 51975.0 73226.0 73706.9 74703.0 74825.5 75756.0 6682.2 7826.4 7992.6 8091.8 8252.0 8660.4 11078.4 12064.6 12244.6 12255.1 9973.3 10347.3 10399.4 10605.8 10669.6 5141.6 8848.9 9683.8 10137.2 10608.8 8734.5 10787.7 10821.9 11047.7 11127.2 7874.9 7900.6 7901.9 8149.3 8167.6 7991.8 9643.0 10575.7 10906.6 10972.2 09:08:23 INFO - PROCESS | 1671 | 09:08:23 INFO - PROCESS | 1671 | [#3] mootools.html Cycles:180 Average:5767.83 Median:4786.51 stddev:3297.17 (68.9%) stddev-sans-first:3290.28 09:08:23 INFO - PROCESS | 1671 | Values: 1426.7 1575.4 1624.9 1836.1 1979.7 7214.4 8128.4 9040.1 9050.5 9156.2 8841.7 8920.9 9007.1 9010.5 9066.5 2140.3 2216.5 2429.9 2467.6 2469.1 2237.1 2266.9 2268.0 2398.1 2470.8 2426.4 2435.0 2436.6 2437.8 2442.6 2392.2 2409.0 2428.3 2428.7 2430.8 2113.5 2346.7 2403.3 2420.1 2421.5 7679.9 8998.8 9007.5 9042.5 9049.0 3606.4 4655.7 4658.6 5476.8 5479.1 3428.4 3550.1 3608.4 3629.6 3632.8 1035.2 1037.7 1038.1 1039.3 1042.3 11872.2 12025.8 12028.6 12073.6 12120.1 4623.7 4639.8 4759.9 4779.7 4782.6 9465.6 9466.1 9474.0 9477.3 9486.0 3979.4 5119.0 5315.0 5394.8 5410.2 4790.4 4985.8 5035.4 5042.4 5043.1 3387.0 3480.8 3649.6 3773.9 3920.6 954.4 980.6 1172.6 1187.2 1188.1 901.7 928.7 933.1 939.1 955.0 5766.4 5916.2 5968.7 6056.2 6226.3 949.8 961.5 1059.7 1075.9 1085.0 3900.5 3966.0 3991.7 4037.8 4063.1 10275.3 10655.9 10945.0 11306.4 11331.4 11175.4 11180.8 11288.8 11296.2 11306.5 8370.7 8411.7 8496.4 8518.3 8527.4 5398.2 5850.9 5861.5 6029.8 6133.2 10035.6 10038.9 10353.6 10427.1 10455.8 10440.4 10597.2 10598.8 10640.0 10716.3 6648.7 7078.6 7191.9 7289.1 7333.5 8011.5 8334.1 8650.9 8662.6 8741.6 7631.4 7763.0 7782.9 7882.3 7909.8 7537.9 7571.4 7598.7 7624.7 7780.6 7141.6 7195.6 7224.3 7238.5 7243.3 4894.5 5017.2 5045.0 5046.3 5151.0 4631.0 4966.2 4992.8 5035.7 5217.7 09:08:23 INFO - PROCESS | 1671 | 09:08:23 INFO - PROCESS | 1671 | [#4] prototype.html Cycles:180 Average:4381.33 Median:4822.92 stddev:1364.78 (28.3%) stddev-sans-first:1333.68 09:08:23 INFO - PROCESS | 1671 | Values: 294.4 307.3 323.1 327.2 330.2 4429.8 5086.8 5199.4 5285.6 5292.1 4963.8 5150.9 5167.9 5194.2 5233.3 3306.0 3504.8 3745.8 3827.6 3958.6 3582.7 3754.9 3934.9 3965.3 3968.0 3960.5 3966.7 3972.8 3990.5 3999.6 3926.7 3942.4 3954.7 3972.8 3980.4 3469.9 3473.5 3485.8 3507.9 3510.8 4937.6 4951.9 4985.2 5008.8 5019.0 4552.2 4552.9 4561.8 4562.2 4588.3 5243.7 5256.0 5429.7 5448.0 5530.5 3778.6 3780.6 3785.4 3789.5 3790.2 4802.8 4977.6 5195.0 5711.9 5776.6 3264.0 3822.4 3832.1 3846.8 3857.1 3577.4 3863.8 3908.3 3910.6 3910.6 3738.4 4504.1 4520.7 4522.7 4531.6 5297.3 5500.5 5507.6 5543.7 5585.6 1213.4 1260.0 1280.7 1300.4 1304.5 763.2 772.8 804.7 812.0 827.3 3591.0 4263.6 4320.6 4345.5 4363.9 4007.9 4550.7 4593.1 4655.3 4800.9 3799.6 3804.3 4017.7 4283.6 4301.5 2235.6 2362.0 2697.6 2967.9 3019.5 4979.0 5890.6 5917.4 5918.9 5921.3 5534.0 5675.7 5914.4 5919.4 5958.8 5213.6 5217.8 5377.0 5858.0 5945.4 4843.0 5655.7 5685.5 5694.7 5711.6 4718.2 5095.6 5328.4 5617.8 5707.4 5831.6 5835.4 5847.2 5850.9 5856.5 3430.8 3801.5 3839.5 3845.1 3850.3 4763.5 5210.9 5437.3 5613.3 5679.4 4495.9 5211.9 5395.0 5485.5 5514.6 4646.8 5074.1 5248.8 5361.7 5364.8 5085.0 5380.7 5392.2 5410.8 5439.0 4946.6 5514.0 5538.0 5566.2 5568.2 5435.8 5465.6 5483.7 5493.6 5497.6 09:08:23 INFO - PROCESS | 1671 | 09:08:23 INFO - PROCESS | 1671 | [#5] yui.html Cycles:180 Average:18049.70 Median:555.83 stddev:25102.80 (4516.3%) stddev-sans-first:25139.29 09:08:23 INFO - PROCESS | 1671 | Values: 668.5 741.7 754.6 755.7 767.6 14259.6 14858.1 15367.9 17408.7 17415.7 16420.2 16494.9 16508.7 16517.8 16536.9 944.1 960.1 961.2 965.2 972.5 765.3 796.6 918.5 959.0 961.1 994.2 1000.3 1001.9 1004.4 1005.1 794.8 991.0 995.2 998.2 999.9 614.0 616.3 617.2 715.9 744.8 15274.1 15329.2 15344.9 15346.0 15350.2 12148.5 12202.3 12214.5 12466.5 12564.6 10868.9 10904.8 11362.1 11438.4 11720.8 3524.3 4035.3 4184.7 4189.9 4264.5 79017.0 81570.0 81921.0 82096.2 82134.6 9239.4 9280.4 9432.4 9540.2 9740.4 20089.1 20190.0 20215.6 20225.2 20230.4 6697.9 7315.5 7365.5 7405.8 7588.0 4008.6 4056.8 4064.6 4066.2 4068.3 3393.9 3491.4 3495.8 3497.1 3498.8 605.7 626.7 628.0 636.0 638.4 495.7 542.1 542.9 571.3 578.5 7579.8 7787.7 7863.5 7979.7 8038.9 500.0 548.5 552.0 559.7 568.5 3671.8 3720.1 3732.6 3739.9 3936.0 68285.0 87999.0 90714.5 93466.0 94991.5 77602.4 78457.0 78618.2 82313.8 82627.6 70796.0 72952.5 73398.5 73702.3 73960.9 7340.3 7347.6 7384.3 7408.3 7480.9 40220.6 46034.8 46489.8 46517.0 46586.0 57472.4 59208.0 60465.7 60669.0 61459.0 11019.6 11113.2 11124.9 11340.4 11488.4 12135.9 12226.5 12354.5 12403.5 12423.1 11559.0 11723.4 11924.4 11937.7 12503.0 11435.3 11465.9 11478.0 11572.8 12556.0 11035.8 11308.9 11319.6 11618.4 11746.4 8687.1 9667.3 9908.9 9920.5 10037.1 8801.6 9642.9 9667.1 9845.6 9895.7 09:08:23 INFO - PROCESS | 1671 | -------- Summary: end -------- 09:08:23 INFO - PROCESS | 1671 | 09:08:23 INFO - TEST-INFO | 1671: exit 0 09:08:23 INFO - TEST-OK | dromaeo_css | took 1115376ms 09:08:23 INFO - TEST-START | kraken 09:08:23 INFO - operating with platform_type : mac_ 09:08:23 INFO - Initialising browser for kraken test... 09:08:23 INFO - TEST-INFO | started process 1677 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/tmpWaewIU/profile http://localhost:49276/getInfo.html) 09:08:26 INFO - PROCESS | 1677 | __metrics Screen width/height:1600/1200 09:08:26 INFO - PROCESS | 1677 | colorDepth:24 09:08:26 INFO - PROCESS | 1677 | Browser inner width/height: 1024/689 09:08:26 INFO - PROCESS | 1677 | __metrics 09:08:26 INFO - PROCESS | 1677 | 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" 09:08:26 ERROR - PROCESS | 1677 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:08:26 INFO - TEST-INFO | 1677: exit 0 09:08:26 INFO - Browser initialized. 09:08:26 INFO - Running cycle 1/1 for kraken test... 09:08:26 INFO - TEST-INFO | started process 1679 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/tmpWaewIU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:08:38 INFO - PROCESS | 1679 | RSS: Main: 239271936 09:08:38 INFO - PROCESS | 1679 | 09:08:41 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection) 09:08:42 INFO - PROCESS | 1679 | RSS: Main: 287977472 09:08:42 INFO - PROCESS | 1679 | 09:08:45 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-dft) 09:08:45 INFO - PROCESS | 1679 | RSS: Main: 298496000 09:08:45 INFO - PROCESS | 1679 | 09:08:49 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-fft) 09:08:50 INFO - PROCESS | 1679 | RSS: Main: 384610304 09:08:50 INFO - PROCESS | 1679 | 09:08:52 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator) 09:08:52 INFO - PROCESS | 1679 | RSS: Main: 283746304 09:08:52 INFO - PROCESS | 1679 | 09:08:54 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:08:55 INFO - PROCESS | 1679 | RSS: Main: 286994432 09:08:55 INFO - PROCESS | 1679 | 09:08:58 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom) 09:08:59 INFO - PROCESS | 1679 | RSS: Main: 324251648 09:08:59 INFO - PROCESS | 1679 | 09:09:02 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate) 09:09:02 INFO - PROCESS | 1679 | RSS: Main: 326283264 09:09:02 INFO - PROCESS | 1679 | 09:09:05 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial) 09:09:06 INFO - PROCESS | 1679 | RSS: Main: 324595712 09:09:06 INFO - PROCESS | 1679 | 09:09:08 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:09:08 INFO - PROCESS | 1679 | RSS: Main: 316710912 09:09:08 INFO - PROCESS | 1679 | 09:09:10 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:09:11 INFO - PROCESS | 1679 | RSS: Main: 322826240 09:09:11 INFO - PROCESS | 1679 | 09:09:13 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:09:14 INFO - PROCESS | 1679 | RSS: Main: 325738496 09:09:14 INFO - PROCESS | 1679 | 09:09:17 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:09:17 INFO - PROCESS | 1679 | RSS: Main: 330072064 09:09:17 INFO - PROCESS | 1679 | 09:09:20 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:09:21 INFO - PROCESS | 1679 | RSS: Main: 327843840 09:09:21 INFO - PROCESS | 1679 | 09:09:23 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49276/tests/kraken/driver.html?testName=ai-astar) 09:09:23 INFO - PROCESS | 1679 | RSS: Main: 374501376 09:09:23 INFO - PROCESS | 1679 | 09:09:23 INFO - PROCESS | 1679 | __start_tp_report 09:09:23 INFO - PROCESS | 1679 | _x_x_mozilla_page_load 09:09:23 INFO - PROCESS | 1679 | _x_x_mozilla_page_load_details 09:09:23 INFO - PROCESS | 1679 | |i|pagename|runs| 09:09:23 INFO - PROCESS | 1679 | |0;ai-astar;111;105;107;91;133;151;165;113;111;112 09:09:23 INFO - PROCESS | 1679 | |1;audio-beat-detection;156;120;145;235;146;139;133;120;166;120 09:09:23 INFO - PROCESS | 1679 | |2;audio-dft;185;187;187;185;186;185;186;183;180;186 09:09:23 INFO - PROCESS | 1679 | |3;audio-fft;78;74;57;125;57;61;56;62;112;55 09:09:23 INFO - PROCESS | 1679 | |4;audio-oscillator;92;99;133;79;77;76;100;79;78;101 09:09:23 INFO - PROCESS | 1679 | |5;imaging-gaussian-blur;86;84;86;84;86;83;87;84;86;83 09:09:23 INFO - PROCESS | 1679 | |6;imaging-darkroom;93;91;93;92;93;92;92;92;96;91 09:09:23 INFO - PROCESS | 1679 | |7;imaging-desaturate;82;85;84;85;86;84;87;82;88;85 09:09:23 INFO - PROCESS | 1679 | |8;json-parse-financial;57;56;56;55;56;58;57;116;54;56 09:09:23 INFO - PROCESS | 1679 | |9;json-stringify-tinderbox;63;74;64;74;69;66;67;72;67;67 09:09:23 INFO - PROCESS | 1679 | |10;stanford-crypto-aes;84;83;93;105;103;79;84;109;102;86 09:09:23 INFO - PROCESS | 1679 | |11;stanford-crypto-ccm;136;139;135;134;137;139;140;146;216;139 09:09:23 INFO - PROCESS | 1679 | |12;stanford-crypto-pbkdf2;164;162;163;164;162;168;217;222;166;163 09:09:23 INFO - PROCESS | 1679 | |13;stanford-crypto-sha256-iterative;59;64;84;67;65;66;66;66;64;67 09:09:23 INFO - PROCESS | 1679 | __end_tp_report 09:09:23 INFO - PROCESS | 1679 | __start_cc_report 09:09:23 INFO - PROCESS | 1679 | _x_x_mozilla_cycle_collect,2228 09:09:23 INFO - PROCESS | 1679 | __end_cc_report 09:09:23 INFO - PROCESS | 1679 | __startTimestamp1463414963597__endTimestamp 09:09:23 INFO - PROCESS | 1679 | 09:09:23 INFO - PROCESS | 1679 | ------- Summary: start ------- 09:09:23 INFO - PROCESS | 1679 | Number of tests: 14 09:09:23 INFO - PROCESS | 1679 | 09:09:23 INFO - PROCESS | 1679 | [#0] ai-astar Cycles:10 Average:119.90 Median:123.00 stddev:22.77 (18.5%) stddev-sans-first:23.92 09:09:23 INFO - PROCESS | 1679 | Values: 111.0 105.0 107.0 91.0 133.0 151.0 165.0 113.0 111.0 112.0 09:09:23 INFO - PROCESS | 1679 | 09:09:23 INFO - PROCESS | 1679 | [#1] audio-beat-detection Cycles:10 Average:148.00 Median:145.50 stddev:34.36 (23.6%) stddev-sans-first:36.33 09:09:23 INFO - PROCESS | 1679 | Values: 156.0 120.0 145.0 235.0 146.0 139.0 133.0 120.0 166.0 120.0 09:09:23 INFO - PROCESS | 1679 | 09:09:23 INFO - PROCESS | 1679 | [#2] audio-dft Cycles:10 Average:185.00 Median:186.00 stddev:2.11 (1.1%) stddev-sans-first:2.24 09:09:23 INFO - PROCESS | 1679 | Values: 185.0 187.0 187.0 185.0 186.0 185.0 186.0 183.0 180.0 186.0 09:09:23 INFO - PROCESS | 1679 | 09:09:23 INFO - PROCESS | 1679 | [#3] audio-fft Cycles:10 Average:73.70 Median:59.00 stddev:25.02 (42.4%) stddev-sans-first:26.49 09:09:23 INFO - PROCESS | 1679 | Values: 78.0 74.0 57.0 125.0 57.0 61.0 56.0 62.0 112.0 55.0 09:09:23 INFO - PROCESS | 1679 | 09:09:23 INFO - PROCESS | 1679 | [#4] audio-oscillator Cycles:10 Average:91.40 Median:78.50 stddev:17.91 (22.8%) stddev-sans-first:18.99 09:09:23 INFO - PROCESS | 1679 | Values: 92.0 99.0 133.0 79.0 77.0 76.0 100.0 79.0 78.0 101.0 09:09:23 INFO - PROCESS | 1679 | 09:09:23 INFO - PROCESS | 1679 | [#5] imaging-gaussian-blur Cycles:10 Average:84.90 Median:86.00 stddev:1.45 (1.7%) stddev-sans-first:1.48 09:09:23 INFO - PROCESS | 1679 | Values: 86.0 84.0 86.0 84.0 86.0 83.0 87.0 84.0 86.0 83.0 09:09:23 INFO - PROCESS | 1679 | 09:09:23 INFO - PROCESS | 1679 | [#6] imaging-darkroom Cycles:10 Average:92.50 Median:92.50 stddev:1.43 (1.5%) stddev-sans-first:1.51 09:09:23 INFO - PROCESS | 1679 | Values: 93.0 91.0 93.0 92.0 93.0 92.0 92.0 92.0 96.0 91.0 09:09:23 INFO - PROCESS | 1679 | 09:09:23 INFO - PROCESS | 1679 | [#7] imaging-desaturate Cycles:10 Average:84.80 Median:85.00 stddev:1.93 (2.3%) stddev-sans-first:1.76 09:09:23 INFO - PROCESS | 1679 | Values: 82.0 85.0 84.0 85.0 86.0 84.0 87.0 82.0 88.0 85.0 09:09:23 INFO - PROCESS | 1679 | 09:09:23 INFO - PROCESS | 1679 | [#8] json-parse-financial Cycles:10 Average:62.10 Median:56.00 stddev:18.97 (33.9%) stddev-sans-first:20.03 09:09:23 INFO - PROCESS | 1679 | Values: 57.0 56.0 56.0 55.0 56.0 58.0 57.0 116.0 54.0 56.0 09:09:23 INFO - PROCESS | 1679 | 09:09:23 INFO - PROCESS | 1679 | [#9] json-stringify-tinderbox Cycles:10 Average:68.30 Median:68.00 stddev:3.89 (5.7%) stddev-sans-first:3.62 09:09:23 INFO - PROCESS | 1679 | Values: 63.0 74.0 64.0 74.0 69.0 66.0 67.0 72.0 67.0 67.0 09:09:23 INFO - PROCESS | 1679 | 09:09:23 INFO - PROCESS | 1679 | [#10] stanford-crypto-aes Cycles:10 Average:92.80 Median:83.50 stddev:10.99 (13.2%) stddev-sans-first:11.19 09:09:23 INFO - PROCESS | 1679 | Values: 84.0 83.0 93.0 105.0 103.0 79.0 84.0 109.0 102.0 86.0 09:09:23 INFO - PROCESS | 1679 | 09:09:23 INFO - PROCESS | 1679 | [#11] stanford-crypto-ccm Cycles:10 Average:146.10 Median:139.00 stddev:24.79 (17.8%) stddev-sans-first:26.02 09:09:23 INFO - PROCESS | 1679 | Values: 136.0 139.0 135.0 134.0 137.0 139.0 140.0 146.0 216.0 139.0 09:09:23 INFO - PROCESS | 1679 | 09:09:23 INFO - PROCESS | 1679 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.10 Median:165.00 stddev:23.50 (14.2%) stddev-sans-first:24.58 09:09:23 INFO - PROCESS | 1679 | Values: 164.0 162.0 163.0 164.0 162.0 168.0 217.0 222.0 166.0 163.0 09:09:23 INFO - PROCESS | 1679 | 09:09:23 INFO - PROCESS | 1679 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.80 Median:66.00 stddev:6.48 (9.8%) stddev-sans-first:6.22 09:09:23 INFO - PROCESS | 1679 | Values: 59.0 64.0 84.0 67.0 65.0 66.0 66.0 66.0 64.0 67.0 09:09:23 INFO - PROCESS | 1679 | -------- Summary: end -------- 09:09:23 INFO - PROCESS | 1679 | 09:09:24 INFO - TEST-INFO | 1679: exit 0 09:09:24 INFO - TEST-OK | kraken | took 60555ms 09:09:24 INFO - SUITE-END | took 1175s 09:09:24 INFO - Completed test suite (00:19:36) 09:09:24 INFO - javascript benchmark 09:09:24 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2849.98825416977, 3221.726153276971, 3260.646831564901, 3398.980305908227, 3423.0415483664583, 5526.336839579251, 5538.307711536058, 5572.86067848303, 5612.186232996218, 5642.069058605331, 5586.217929489864, 5604.131359639263, 5687.17535957286, 5697.373288938214, 5720.771169153229, 5000.549950504454, 5046.142155833517, 5094.006668699605, 5102.897942041157, 5235.240890070937, 5154.561862241714, 5161.509656582619, 5177.559907407875, 5207.791688332462, 5209.531142197202, 5021.84934451967, 5038.748062596892, 5086.18621020635, 5087.287779710843, 5101.668391554537, 5023.0, 5028.572571331441, 5045.899082018339, 5074.949250507485, 5146.536811686966, 5095.235714642796, 5099.974500127476, 5112.233165025239, 5122.538971492546, 5128.102582048164, 5388.326459192601, 5483.424240454735, 5532.889342213133, 5611.775528978836, 5613.550915926716, 6106.931008400048, 6128.0, 6136.294326152505, 6141.946315431838, 6153.446189842935, 6185.381461853824, 6237.251529816451, 6260.686965651745, 6266.185395898549, 6268.49852011838, 5593.496585307342, 5638.915416268677, 5640.661560306395, 5737.139429085629, 5797.217375654317, 6270.435660790459, 6336.936630633634, 6340.302566717657, 6347.682615869188, 6347.746090156435, 5993.280806303184, 6004.039353703474, 6034.125051867542, 6057.394260573908, 6072.787452439144, 6036.879262414815, 6056.818295451144, 6103.489197737777, 6121.707990350846, 6128.3565225651555, 6029.457348838626, 6066.059760737093, 6072.453479186806, 6074.271087469532, 6090.299615544269, 5222.582193424517, 5987.940120598738, 6071.180390647206, 6102.176206212193, 6139.584134579375, 4830.637702172351, 4831.396075490564, 4842.443119041355, 4869.196582563908, 4872.658913875995, 4311.288637374861, 4319.352097185448, 4328.026194106301, 4333.761643109596, 4345.239583072949, 5467.398586155519, 5486.286782718301, 5504.4770746778995, 5567.248421463131, 5576.581756368289, 6005.189299444519, 6063.757449701962, 6094.177286066413, 6106.2061931948565, 6110.938890611126, 5495.148252020943, 5536.252605898233, 5554.333479982347, 5555.638883472642, 5582.106862901916, 5220.686758794484, 5228.816991405282, 5237.9476205238225, 5242.318498595235, 5288.788448462018, 6197.5351848611535, 6243.656598887014, 6254.405831446005, 6299.118123462719, 6303.432691057827, 6217.937820621736, 6221.160143380585, 6224.782132625336, 6242.781502647385, 6254.437100660963, 6272.090546870587, 6307.779227727191, 6320.1151838743535, 6323.9683801580695, 6332.651704156316, 5876.764929402776, 6054.969725151523, 6077.4834139098675, 6102.176206212104, 6128.0, 6123.118851766045, 6193.535484838565, 6213.5339849512375, 6218.00511918091, 6224.128621993015, 5786.566007549366, 5808.680522571299, 5992.910106348496, 6029.728662210124, 6054.424829641177, 5845.240118784531, 5875.3243377010485, 5916.526677865846, 5984.31180414258, 6005.129256257906, 5820.039693450533, 5825.009748342707, 5831.679257640871, 5839.240898683144, 5992.0, 5837.153612726046, 5837.532997360286, 6026.879462410815, 6053.727582258897, 6066.1204125402455, 5804.709764511673, 5841.883162336814, 5848.444397782204, 5862.267216597926, 5866.706664666664], "value": 5596.4093415530615, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [516.7622893469004, 534.6952237224783, 540.667489493961, 553.4520824383859, 559.6306437751085, 25296.87351563242, 25868.83590238439, 32573.67426325736, 32583.348333033326, 32610.836945815237, 43943.340849887274, 45264.0, 45321.0, 47731.28403073957, 47824.80437989052, 9254.398464099846, 9674.709758707233, 9842.163416109639, 9855.408675479459, 9858.802823943517, 9079.273658107368, 9825.65610203643, 9826.901730982707, 9831.164351030171, 9841.36031157976, 9795.216382689403, 9850.704478865646, 9851.95074024629, 9854.162396196296, 9855.457949812793, 9752.317337786359, 9803.0, 9806.16647584956, 9813.558389872473, 9830.557624906825, 9731.221502279801, 9787.0, 9799.16707079899, 9806.656767013192, 9827.213822894222, 41244.0, 41600.1679966402, 41616.79191604022, 41651.1669766606, 41668.37497437541, 3182.8567714452906, 3344.3311337732553, 3504.56192975878, 3826.598207188233, 3848.692104631623, 28646.856765716246, 29298.70701292981, 30365.392692146255, 30604.693953060407, 30662.386752265054, 2786.122371452986, 2812.9578056329174, 2847.786416018807, 2878.913632591026, 2893.56596510523, 510128.0, 511750.0, 515631.0, 516425.0, 517641.9999999962, 30099.398012039637, 35058.0, 36065.81967090148, 47438.76280618575, 61531.0, 37547.4367884482, 37718.24563508714, 37757.81121094432, 37829.0, 37936.051598710255, 15237.6190595236, 15395.769063464057, 15399.846001540065, 15406.614834629225, 15407.075575465507, 11458.739538650741, 12362.258264503978, 12924.353782310847, 12981.870181298254, 13020.218786872818, 8204.261616454547, 8222.17778222173, 8224.506529608243, 8297.668093276323, 8313.45962512447, 2984.731374176291, 3388.373150967079, 3399.85808940366, 3415.4023045966856, 3426.0920855973186, 956.5791051937265, 990.321629683669, 1011.4487604255551, 1096.4607911172827, 1125.7410795517078, 24982.625955572617, 28255.858720706266, 29680.257993550335, 30087.398252035273, 30535.541966870514, 964.7925695975399, 1118.6923596011, 1138.7267055906689, 1143.1026644084354, 1286.948522059126, 13143.737125257441, 14688.19214943167, 15475.690486190213, 16008.359665613478, 16090.436834710728, 709255.0, 720648.0, 725882.741172592, 728799.0, 732346.0, 64098.35901640924, 64665.67667161634, 64704.35295647077, 64709.0, 64735.0, 1325.4896864707157, 1414.2504472629523, 1432.1478720161488, 1462.0058360314874, 1483.384395475887, 26139.607905881807, 29321.7067829319, 30546.930857419884, 30558.541621875243, 31195.376092478476, 53073.0, 53331.0, 53765.0, 53959.920801582964, 53969.19046214232, 550093.0, 552123.0, 552518.0, 553390.0, 556252.0, 21966.670499942833, 23006.0, 26921.596176057767, 26964.191074267805, 26969.460610788068, 45482.772586138184, 46432.7678361606, 48010.75994620004, 48044.27933581069, 48056.5194348052, 36333.38961335641, 37764.0, 38360.42459363056, 38918.6108138915, 39174.41238381484, 36933.26133477369, 37241.62758372381, 37428.0, 37872.0, 38203.0, 37216.255674886896, 37423.0, 38348.6165138345, 38377.232455351295, 38404.23191536209, 27116.1865144046, 31794.84102579472, 33099.0, 33201.667983320825, 33317.16707082342, 24423.023079076636, 24576.6313505294, 24731.13441029555, 24911.750882490942, 24931.0], "value": 18629.09344507754, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [394.68819632490334, 399.99200015999696, 401.3438028822875, 407.1429640606525, 425.4047322540473, 12053.950828359179, 17475.912620436913, 20014.49963750907, 20071.397858064243, 20974.790252097475, 16425.260863261152, 17153.82846171538, 20225.595488090228, 20963.16147354104, 21041.684374734352, 5604.327480702311, 5845.240118784552, 5876.265466816648, 5903.11453282009, 5927.407259274081, 4991.500849915015, 5505.944940550603, 5774.6535207887455, 5820.185174075633, 5873.412658734135, 3783.6594706476412, 4010.0977280112033, 7052.541584796997, 7409.814754631124, 7412.295831895961, 7390.076240469942, 7398.445116616248, 7481.877718342238, 7487.288707572772, 7494.587797671126, 6689.397954184147, 7012.859742805166, 7201.639918004079, 7233.31283528064, 7242.963785181093, 23501.88249058761, 26969.86515067432, 27805.443891122268, 27880.442391152064, 27990.72009279922, 2247.358905010249, 2262.1856131792542, 2294.9311520654405, 2307.699999000136, 2406.6991626046743, 15676.980996235323, 15708.764368534481, 16050.75923861143, 16159.272832722556, 16408.507744767558, 1517.9089254644646, 1869.3644160985195, 1871.5414723392855, 1883.5950270691867, 1991.163711241282, 140804.59195408018, 144587.0, 146052.0, 146619.0, 147541.26229368785, 20440.795592044185, 20844.853533055826, 20883.68674469884, 21101.261455848973, 21232.0, 35402.114947126014, 35436.29127417437, 35543.28913421769, 35555.82222088873, 35596.46605300922, 10824.891751082547, 10971.83542246867, 10982.670519884416, 11033.779324413626, 11215.551377944954, 9040.593173307218, 9051.321150913707, 9068.047854975264, 9151.95424022889, 9213.683074093322, 7749.961250193713, 9304.95347523258, 9430.056994300516, 9581.808363832684, 9594.737789151583, 2975.0, 3128.562001319817, 3742.868999585056, 3771.2268984858038, 3785.470034195215, 915.5285028210477, 942.5664194470484, 968.1093394077466, 976.9267304952157, 1004.42747633848, 19184.71222931657, 20096.497587560134, 20130.29543965954, 20161.39515814528, 20278.44045570187, 930.6510058727977, 954.379653225409, 983.6999715086824, 1019.6227395863579, 1029.145808978546, 10414.739631509121, 10624.893751062546, 10659.987301206373, 10660.62687805923, 10673.83989240162, 602529.0, 605197.0, 619680.0, 619974.0, 629281.0, 131014.34492827475, 132838.0, 137113.0, 139184.86521078288, 140647.0, 1024.882138554076, 1025.2771795883884, 1047.1099565369375, 1049.5539395756773, 1052.4737631184407, 17604.29582816673, 17801.0, 17883.37408190707, 17991.820081799015, 18156.45530634083, 40222.79888600538, 41560.58439415688, 44682.87219894576, 45754.77122614365, 51975.0, 73226.0, 73706.89224185229, 74703.0, 74825.50348992881, 75756.0, 6682.23154337238, 7826.413019023482, 7992.600369981594, 8091.8381632365845, 8251.968503937007, 8660.437071590326, 11078.390688512209, 12064.577739779066, 12244.571439999558, 12255.080868934687, 9973.252006099427, 10347.327423717434, 10399.376037437778, 10605.840912386475, 10669.626563070255, 5141.562967147835, 8848.867266990872, 9683.757906052406, 10137.239707021856, 10608.840867386842, 8734.519601421984, 10787.67636970892, 10821.891781082088, 11047.72380690489, 11127.221094523306, 7874.881876771968, 7900.604969751605, 7901.920980790119, 8149.307308878811, 8167.591620419074, 7991.84016319682, 9642.951785241028, 10575.735606609896, 10906.61826836057, 10972.177086718688], "value": 10526.8373218027, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1426.7075249573838, 1575.4013474879544, 1624.8618867396272, 1836.1186630417399, 1979.7030445433174, 7214.386777123944, 8128.4310098293145, 9040.14118658728, 9050.456972581642, 9156.221721153694, 8841.690540831072, 8920.884889388826, 9007.144321289466, 9010.54947252638, 9066.546672666356, 2140.283005193262, 2216.5345277491747, 2429.854208747472, 2467.568175569277, 2469.074097213545, 2237.1387015998794, 2266.931992040233, 2267.9886600567056, 2398.07674045492, 2470.8270421070533, 2426.4055306449854, 2434.9756502434925, 2436.5735996200683, 2437.792787613055, 2442.6213936839818, 2392.1986134644917, 2408.963865542018, 2428.344347026293, 2428.659987601737, 2430.769076937688, 2113.5244569971787, 2346.6831977682964, 2403.2910291463886, 2420.1166574200465, 2421.5278020785954, 7679.923200767977, 8998.775030624287, 9007.45955242681, 9042.547872606343, 9049.049849765746, 3606.368885445063, 4655.674102812804, 4658.557437043475, 5476.835694929158, 5479.123340265578, 3428.4171690812373, 3550.0592343029116, 3608.386574282378, 3629.6188900165366, 3632.8183590820354, 1035.1615191694752, 1037.7339645632317, 1038.0812980512226, 1039.2880876599554, 1042.2860340666668, 11872.228305160223, 12025.819612705816, 12028.63914082579, 12073.577424790177, 12120.090993175547, 4623.653226007996, 4639.791209395586, 4759.881002974884, 4779.665423420327, 4782.617390608743, 9465.621375144918, 9466.148046675833, 9474.0, 9477.28920330978, 9485.952570237105, 3979.3633018716864, 5119.0, 5314.973425132927, 5394.838154855361, 5410.242566040757, 4790.401199850019, 4985.750712464363, 5035.395752509649, 5042.42012168598, 5043.117454445531, 3387.0, 3480.8085555294197, 3649.61679023704, 3773.867914622975, 3920.5687374388795, 954.4321128928416, 980.5783513089298, 1172.5661505243115, 1187.2223693480785, 1188.102982248397, 901.7114523352465, 928.6842473559022, 933.1275257634134, 939.1406862720671, 954.9522523873917, 5766.394528574523, 5916.171735956969, 5968.731407086691, 6056.152138700585, 6226.346233645492, 949.7910459698855, 961.5384615384654, 1059.6873922192972, 1075.876274228458, 1085.039739830256, 3900.531936167678, 3965.980170099131, 3991.720579559403, 4037.7779222142476, 4063.1061166543313, 10275.332103413255, 10655.946720266349, 10945.01494865466, 11306.378149201708, 11331.376774277494, 11175.44122793857, 11180.776384472427, 11288.830667539993, 11296.20926535151, 11306.547738090385, 8370.707025254087, 8411.663533458594, 8496.405251632326, 8518.318534517348, 8527.360447966303, 5398.244245805512, 5850.882982340244, 5861.472467477777, 6029.75880964774, 6133.233345831771, 10035.648752293635, 10038.949805250926, 10353.63762268317, 10427.061564459234, 10455.79088418212, 10440.42577658236, 10597.152227821602, 10598.788024239318, 10640.042396184072, 10716.303440276357, 6648.734050638114, 7078.5752854826815, 7191.892121618075, 7289.052423184952, 7333.486655934247, 8011.479253848481, 8334.08325084228, 8650.913490865263, 8662.610182541926, 8741.606627701642, 7631.38948884099, 7762.961185194038, 7782.922170778446, 7882.251186137308, 7909.762707118795, 7537.886931696139, 7571.356434702891, 7598.658060387406, 7624.733134340271, 7780.649870755943, 7141.64291785419, 7195.640217989185, 7224.277572242734, 7238.457115716447, 7243.311885370881, 4894.486078961801, 5017.222330538772, 5044.974775126101, 5046.293518907282, 5150.989309079298, 4630.953690463187, 4966.22708266315, 4992.775325110451, 5035.748212589283, 5217.660852044605], "value": 4611.779011668839, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [294.41117764471056, 307.3376872839031, 323.1243330574144, 327.17388593800666, 330.17456359102243, 4429.778511074442, 5086.771095300711, 5199.402068762093, 5285.550728188104, 5292.115796890695, 4963.776630051647, 5150.948490515094, 5167.870803229912, 5194.246834209038, 5233.267342572043, 3306.0, 3504.844520619813, 3745.8127093645344, 3827.598102199257, 3958.6239307265837, 3582.7492075554724, 3754.9436758448646, 3934.881953541398, 3965.345717956534, 3967.960320396802, 3960.524737031546, 3966.6628336591425, 3972.801359932006, 3990.5410877748996, 3999.560048394674, 3926.7054970877157, 3942.3692209246383, 3954.6638535724496, 3972.7616343019513, 3980.4029395590605, 3469.906979301512, 3473.5484387029524, 3485.7734247274097, 3507.8772242971627, 3510.7717998330027, 4937.629677774181, 4951.92572111415, 4985.1774457214515, 5008.7996480140755, 5018.974905125451, 4552.203364411214, 4552.863414097583, 4561.813928378646, 4562.247229207177, 4588.288815233611, 5243.659162154486, 5256.001359741636, 5429.728513574306, 5448.0, 5530.5299049580835, 3778.6221377861993, 3780.5822816443833, 3785.432185172246, 3789.4694742736037, 3790.2040571479747, 4802.831900883452, 4977.576905962997, 5195.038827868117, 5711.857203569903, 5776.59563830532, 3263.983680081632, 3822.3502004658717, 3832.1186127190904, 3846.788426636506, 3857.1128640412862, 3577.4097273949506, 3863.826127824255, 3908.335582950905, 3910.550286717007, 3910.608939106067, 3738.3831667775057, 4504.054148628759, 4520.660950428731, 4522.683412161117, 4531.56950089741, 5297.284866543045, 5500.477454641803, 5507.614466987273, 5543.703629687025, 5585.553155747531, 1213.4478812140455, 1259.9685007874693, 1280.6542233596876, 1300.4018151650157, 1304.5108084468325, 763.1719584251039, 772.8454309138195, 804.7143264141263, 811.9797005074802, 827.3257295304307, 3590.964090359063, 4263.59495847894, 4320.611144996902, 4345.521992580813, 4363.890902727458, 4007.8797636070963, 4550.726956382628, 4593.093866045554, 4655.344655344656, 4800.927986080212, 3799.6200379962333, 3804.31522325984, 4017.7187596868525, 4283.593058659422, 4301.483821941324, 2235.5520704828186, 2361.976380236176, 2697.608846717215, 2967.8516074196637, 3019.531972544259, 4979.0, 5890.617109887844, 5917.437843404869, 5918.881622367615, 5921.3190483094995, 5533.972330138324, 5675.659460432387, 5914.378990206053, 5919.378465261172, 5958.769273815553, 5213.556847667915, 5217.843464696065, 5376.973115134399, 5858.004139296329, 5945.375735547792, 4843.0, 5655.688937108499, 5685.459881311269, 5694.743736531783, 5711.600187986967, 4718.197906355997, 5095.649652842074, 5328.3605967284175, 5617.831465056055, 5707.429257074426, 5831.562632802472, 5835.416458354301, 5847.152162936435, 5850.912236316373, 5856.531477481876, 3430.7598468106994, 3801.4867992821723, 3839.500864887547, 3845.115623406688, 3850.3261929161727, 4763.4522029967, 5210.8957820844125, 5437.341610808741, 5613.326400831763, 5679.35298763354, 4495.932561011653, 5211.895762084661, 5395.0, 5485.461877089335, 5514.558835293247, 4646.838290427393, 5074.122574647404, 5248.821738978281, 5361.705106219196, 5364.792921592611, 5085.0, 5380.730963451733, 5392.218128371443, 5410.78356865721, 5439.0, 4946.604271658331, 5514.0, 5537.975474537224, 5566.159350048453, 5568.192612071146, 5435.84720861907, 5465.6447330923365, 5483.670979741229, 5493.560515158858, 5497.587680924026], "value": 3947.5656401551532, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [668.5353679192962, 741.7329761285936, 754.5774366354839, 755.6637296403101, 767.5855038279329, 14259.572212833618, 14858.108513489185, 15367.923160384196, 17408.738868916975, 17415.73876391855, 16420.17899105046, 16494.917525412387, 16508.669826603462, 16517.752233716503, 16536.917315413408, 944.079522465594, 960.0879164793474, 961.1685891703696, 965.1988849255137, 972.4748635736694, 765.3265126688507, 796.6176235407008, 918.5499105438354, 959.0026372572551, 961.0773657289011, 994.2443742755486, 1000.3347773730496, 1001.8847832499247, 1004.3772860826298, 1005.0903931941568, 794.7695168401157, 990.9990909181706, 995.2237254941158, 998.1715176403568, 999.8900120986685, 613.9969300153515, 616.300498933709, 617.1976430640194, 715.8711431942248, 744.8435828476026, 15274.083554986737, 15329.233538323038, 15344.923275383664, 15346.002509836937, 15350.155741434217, 12148.514059437612, 12202.267863928104, 12214.450349734283, 12466.501339946391, 12564.560240391635, 10868.945655271753, 10904.781904361947, 11362.147838912031, 11438.428078596036, 11720.765584688344, 3524.2775231077567, 4035.3139966205817, 4184.748915065105, 4189.937150942738, 4264.466941632296, 79017.0, 81570.0, 81920.9999999994, 82096.17903820945, 82134.58932705298, 9239.445633262027, 9280.350375473652, 9432.434053956647, 9540.189083927806, 9740.366876153028, 20089.095990680453, 20190.0, 20215.595688086156, 20225.19099236014, 20230.393088207376, 6697.866042679119, 7315.524490908074, 7365.48441609093, 7405.7778266652085, 7587.962060189664, 4008.599140086026, 4056.756594604274, 4064.552899181088, 4066.2477441673386, 4068.3083875741195, 3393.8642454301553, 3491.371553120412, 3495.790252584802, 3497.108237399447, 3498.772579782306, 605.7243954000919, 626.6960524145786, 628.0453710360227, 635.9554831161867, 638.4477427025589, 495.7001014386244, 542.1325878594297, 542.9484199001089, 571.331542095749, 578.5429510686595, 7579.772606821804, 7787.688492460352, 7863.488873223222, 7979.72070977513, 8038.927416999624, 499.9825006124768, 548.4953842464942, 551.9862003449946, 559.6725915339572, 568.4741614007027, 3671.7613355132366, 3720.144366795598, 3732.6267373263, 3739.8504059837846, 3935.980320098381, 68285.0, 87999.0, 90714.54642726744, 93466.0, 94991.52504237434, 77602.44795104179, 78457.0, 78618.21381786223, 82313.76529352064, 82627.58686206651, 70796.0, 72952.54094918178, 73398.5320293591, 73702.26297737061, 73960.89058664232, 7340.339369456775, 7347.632618369167, 7384.261573842573, 7408.296211859864, 7480.887786683313, 40220.597794022855, 46034.769826152, 46489.767551162025, 46517.0, 46586.0, 57472.425275746704, 59208.0, 60465.69767151136, 60669.0, 61459.0, 11019.614313498989, 11113.206985217585, 11124.944375278072, 11340.376279304717, 11488.36813975206, 12135.939320303341, 12226.510939562319, 12354.505819767108, 12403.503340557218, 12423.130380873252, 11559.0, 11723.35521546323, 11924.403779811148, 11937.701557461132, 12503.0, 11435.256708313933, 11465.942670286595, 11478.024367928485, 11572.826407603725, 12556.0, 11035.83446248323, 11308.943455282671, 11319.603813866805, 11618.360990145624, 11746.412679366169, 8687.087855775053, 9667.323287370098, 9908.919126983414, 9920.454375009444, 10037.096661300518, 8801.647934082564, 9642.903570964481, 9667.129958303787, 9845.606175753177, 9895.65365212214], "value": 6904.625212363408, "unit": "score"}], "name": "dromaeo_css", "value": 7188.373508755225, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 105.0, 107.0, 91.0, 133.0, 151.0, 165.0, 113.0, 111.0, 112.0], "value": 119.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 120.0, 145.0, 235.0, 146.0, 139.0, 133.0, 120.0, 166.0, 120.0], "value": 148.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 187.0, 187.0, 185.0, 186.0, 185.0, 186.0, 183.0, 180.0, 186.0], "value": 185.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 74.0, 57.0, 125.0, 57.0, 61.0, 56.0, 62.0, 112.0, 55.0], "value": 73.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 99.0, 133.0, 79.0, 77.0, 76.0, 100.0, 79.0, 78.0, 101.0], "value": 91.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 86.0, 84.0, 86.0, 83.0, 87.0, 84.0, 86.0, 83.0], "value": 84.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 91.0, 93.0, 92.0, 93.0, 92.0, 92.0, 92.0, 96.0, 91.0], "value": 92.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 85.0, 84.0, 85.0, 86.0, 84.0, 87.0, 82.0, 88.0, 85.0], "value": 84.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 56.0, 56.0, 55.0, 56.0, 58.0, 57.0, 116.0, 54.0, 56.0], "value": 62.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 74.0, 64.0, 74.0, 69.0, 66.0, 67.0, 72.0, 67.0, 67.0], "value": 68.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 93.0, 105.0, 103.0, 79.0, 84.0, 109.0, 102.0, 86.0], "value": 92.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 139.0, 135.0, 134.0, 137.0, 139.0, 140.0, 146.0, 216.0, 139.0], "value": 146.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 162.0, 163.0, 164.0, 162.0, 168.0, 217.0, 222.0, 166.0, 163.0], "value": 175.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 64.0, 84.0, 67.0, 65.0, 66.0, 66.0, 66.0, 64.0, 67.0], "value": 66.8, "unit": "score"}], "name": "kraken", "value": 1491.3999999999996, "alertThreshold": 2.0}]} 09:09:24 INFO - Return code: 0 09:09:24 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 09:09:24 ERROR - # TBPL SUCCESS # 09:09:24 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:09:24 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:09:24 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:09:24 INFO - 2016-05-16 09:09:24.927 screenresolution[1683:10249] starting screenresolution argv=screenresolution get 09:09:24 INFO - 2016-05-16 09:09:24.930 screenresolution[1683:10249] Display 0: 1600x1200x32@60 09:09:24 INFO - 2016-05-16 09:09:24.935 screenresolution[1684:10252] starting screenresolution argv=screenresolution list 09:09:24 INFO - Available Modes on Display 0 09:09:24 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:09:24 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:09:24 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:09:24 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:09:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:09:25 INFO - Intel Iris: 09:09:25 INFO - Chipset Model: Intel Iris 09:09:25 INFO - Type: GPU 09:09:25 INFO - Bus: Built-In 09:09:25 INFO - VRAM (Dynamic, Max): 1536 MB 09:09:25 INFO - Vendor: Intel (0x8086) 09:09:25 INFO - Device ID: 0x0a2e 09:09:25 INFO - Revision ID: 0x0009 09:09:25 INFO - Displays: 09:09:25 INFO - Display: 09:09:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:09:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:09:25 INFO - Display Serial Number: 1600x1200 60 09:09:25 INFO - Main Display: Yes 09:09:25 INFO - Mirror: Off 09:09:25 INFO - Online: Yes 09:09:25 INFO - Rotation: Supported 09:09:25 INFO - Return code: 0 09:09:25 INFO - Running post-action listener: _resource_record_post_action 09:09:25 INFO - ##### 09:09:25 INFO - ##### Finished run-tests step (success) 09:09:25 INFO - ##### 09:09:25 INFO - Running post-run listener: _resource_record_post_run 09:09:25 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 32554496; Write bytes: 694085632; Read time: 327; Write time: 1946 09:09:25 INFO - TinderboxPrint: CPU usage
54.2% 09:09:25 INFO - TinderboxPrint: I/O read bytes / time
32,554,496 / 327 09:09:25 INFO - TinderboxPrint: I/O write bytes / time
694,085,632 / 1,946 09:09:25 INFO - TinderboxPrint: CPU idle
2,221.6 (45.8%) 09:09:25 INFO - TinderboxPrint: CPU system
975.1 (20.1%) 09:09:25 INFO - TinderboxPrint: CPU user
1,648.8 (34.0%) 09:09:25 INFO - TinderboxPrint: Swap in / out
423,903,232 / 0 09:09:25 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 374192640; Write bytes: 368404480; Read time: 27801; Write time: 675 09:09:25 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 31632896; Write bytes: 296620032; Read time: 314; Write time: 1212 09:09:25 INFO - Running post-run listener: _upload_blobber_files 09:09:25 INFO - Blob upload gear active. 09:09:25 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:09: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 09:09: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'] 09:09: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 09:09:26 INFO - (blobuploader) - INFO - Open directory for files ... 09:09:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:09:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:09:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:09:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:09:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:09:27 INFO - (blobuploader) - INFO - Done attempting. 09:09:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:09:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:09:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:09:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:09:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:09:28 INFO - (blobuploader) - INFO - Done attempting. 09:09:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:09:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:09:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:09:30 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:09:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:09:30 INFO - (blobuploader) - INFO - Done attempting. 09:09:30 INFO - (blobuploader) - INFO - Iteration through files over. 09:09:30 INFO - Return code: 0 09:09:30 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:09:30 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:09:30 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/01f23ef31b3b1db744592744ebd217e72f14c5a3d9f4a9b68358ecd32f96b2ec576c56a4d09f67a44166452bd8af0ccd5b17fc50ea6ecbce5b6f3df9fda07649", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a83d7ca9ca12d5c58ce952ee7ecd37a09db92c16edc1d7e0556fb74655dcd8b463eea91c38acacc925a0ca3f73864ff0eb5201eb10c84f7e0007f289698e97a2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/95dc6eafc5103d713e9c366ed30bbf32263c9054dc8a2a6f40a803d35a6d253cbfbb40181a66ecb9c360b1e479d917eb3c3304efce2cf0cc2e99816f103146f7"} 09:09:30 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:09:30 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:09:30 INFO - Contents: 09:09:30 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/01f23ef31b3b1db744592744ebd217e72f14c5a3d9f4a9b68358ecd32f96b2ec576c56a4d09f67a44166452bd8af0ccd5b17fc50ea6ecbce5b6f3df9fda07649", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a83d7ca9ca12d5c58ce952ee7ecd37a09db92c16edc1d7e0556fb74655dcd8b463eea91c38acacc925a0ca3f73864ff0eb5201eb10c84f7e0007f289698e97a2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/95dc6eafc5103d713e9c366ed30bbf32263c9054dc8a2a6f40a803d35a6d253cbfbb40181a66ecb9c360b1e479d917eb3c3304efce2cf0cc2e99816f103146f7"} 09:09:30 INFO - Running post-run listener: copy_logs_to_upload_dir 09:09:30 INFO - Copying logs to upload dir... 09:09:30 INFO - mkdir: /builds/slave/test/build/upload/logs 09:09:30 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1279.530986 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2016-05-16 09:09:30.920020) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-16 09:09:30.927515) ========= 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.pxMWQjMwDO/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.yKsvP79hmN/Listeners TMPDIR=/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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/01f23ef31b3b1db744592744ebd217e72f14c5a3d9f4a9b68358ecd32f96b2ec576c56a4d09f67a44166452bd8af0ccd5b17fc50ea6ecbce5b6f3df9fda07649", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a83d7ca9ca12d5c58ce952ee7ecd37a09db92c16edc1d7e0556fb74655dcd8b463eea91c38acacc925a0ca3f73864ff0eb5201eb10c84f7e0007f289698e97a2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/95dc6eafc5103d713e9c366ed30bbf32263c9054dc8a2a6f40a803d35a6d253cbfbb40181a66ecb9c360b1e479d917eb3c3304efce2cf0cc2e99816f103146f7"} build_url:https://queue.taskcluster.net/v1/task/bsJth3rIRo-exmrUl9e9gQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011078 build_url: 'https://queue.taskcluster.net/v1/task/bsJth3rIRo-exmrUl9e9gQ/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/01f23ef31b3b1db744592744ebd217e72f14c5a3d9f4a9b68358ecd32f96b2ec576c56a4d09f67a44166452bd8af0ccd5b17fc50ea6ecbce5b6f3df9fda07649", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a83d7ca9ca12d5c58ce952ee7ecd37a09db92c16edc1d7e0556fb74655dcd8b463eea91c38acacc925a0ca3f73864ff0eb5201eb10c84f7e0007f289698e97a2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/95dc6eafc5103d713e9c366ed30bbf32263c9054dc8a2a6f40a803d35a6d253cbfbb40181a66ecb9c360b1e479d917eb3c3304efce2cf0cc2e99816f103146f7"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-16 09:09:30.962917) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 09:09:30.963301) ========= 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.pxMWQjMwDO/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.yKsvP79hmN/Listeners TMPDIR=/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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.005262 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 09:09:31.012453) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-16 09:09:31.012792) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-16 09:09:31.013157) ========= ========= Total master_lag: 0.17 =========