builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0205 starttime: 1463417291.02 results: success (0) buildid: 20160516074433 builduid: c2f13e2278c2440aa4c221e7e2eb802a revision: e9533997fcef31fa5d2db464c6ceec0ca6ea560f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-16 09:48:11.020146) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-16 09:48:11.024809) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-16 09:48:11.025142) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-16 09:48:11.100772) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 09:48:11.101325) ========= 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.2EAxMTpO8V/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.ATcVOREf1K/Listeners TMPDIR=/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/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 09:48:11-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 21.8M=0.001s 2016-05-16 09:48:11 (21.8 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.146215 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 09:48:11.282994) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 09:48:11.283356) ========= 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.2EAxMTpO8V/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.ATcVOREf1K/Listeners TMPDIR=/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/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.024635 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 09:48:11.343758) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-16 09:48:11.344310) ========= 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.2EAxMTpO8V/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.ATcVOREf1K/Listeners TMPDIR=/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/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 09:48:11,371 truncating revision to first 12 chars 2016-05-16 09:48:11,371 Setting DEBUG logging. 2016-05-16 09:48:11,371 attempt 1/10 2016-05-16 09:48:11,371 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 09:48:12,803 unpacking tar archive at: fx-team-e9533997fcef/testing/mozharness/ program finished with exit code 0 elapsedTime=1.683295 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-16 09:48:13.047210) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-16 09:48:13.047644) ========= script_repo_revision: e9533997fcef31fa5d2db464c6ceec0ca6ea560f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-16 09:48:13.048433) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-16 09:48:13.048945) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-16 09:48:13.065388) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 48 secs) (at 2016-05-16 09:48:13.065715) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.2EAxMTpO8V/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.ATcVOREf1K/Listeners TMPDIR=/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/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 09:48:13 INFO - MultiFileLogger online at 20160516 09:48:13 in /builds/slave/test 09:48:13 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 09:48:13 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:48:13 INFO - {'append_to_log': False, 09:48:13 INFO - 'base_work_dir': '/builds/slave/test', 09:48:13 INFO - 'blob_upload_branch': 'Fx-Team', 09:48:13 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:48:13 INFO - 'branch': 'Fx-Team', 09:48:13 INFO - 'buildbot_json_path': 'buildprops.json', 09:48:13 INFO - 'config_files': ('talos/mac_config.py',), 09:48:13 INFO - 'default_actions': ('clobber', 09:48:13 INFO - 'read-buildbot-config', 09:48:13 INFO - 'download-and-extract', 09:48:13 INFO - 'populate-webroot', 09:48:13 INFO - 'create-virtualenv', 09:48:13 INFO - 'install', 09:48:13 INFO - 'run-tests'), 09:48:13 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:48:13 INFO - 'download_minidump_stackwalk': True, 09:48:13 INFO - 'download_symbols': 'ondemand', 09:48:13 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:48:13 INFO - 'tooltool.py': '/tools/tooltool.py', 09:48:13 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:48:13 INFO - '/tools/misc-python/virtualenv.py')}, 09:48:13 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:48:13 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:48:13 INFO - 'installer_path': 'installer.exe', 09:48:13 INFO - 'log_level': 'info', 09:48:13 INFO - 'log_name': 'talos', 09:48:13 INFO - 'log_to_console': True, 09:48:13 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:48:13 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:48:13 INFO - 'opt_config_files': (), 09:48:13 INFO - 'pip_index': False, 09:48:13 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:48:13 INFO - 'cmd': ('bash', 09:48:13 INFO - '-c', 09:48:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:48:13 INFO - 'enabled': True, 09:48:13 INFO - 'halt_on_failure': False, 09:48:13 INFO - 'name': 'check_screen_resolution'},), 09:48:13 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:48:13 INFO - 'cmd': ('bash', 09:48:13 INFO - '-c', 09:48:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:48:13 INFO - 'enabled': True, 09:48:13 INFO - 'halt_on_failure': False, 09:48:13 INFO - 'name': 'check_screen_resolution'},), 09:48:13 INFO - 'run_cmd_checks_enabled': True, 09:48:13 INFO - 'sps_profile': False, 09:48:13 INFO - 'sps_profile_interval': 0, 09:48:13 INFO - 'suite': 'dromaeojs-e10s', 09:48:13 INFO - 'system_bits': '32', 09:48:13 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:48:13 INFO - 'title': 't-yosemite-r7-0205', 09:48:13 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:48:13 INFO - 'use_talos_json': True, 09:48:13 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:48:13 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:48:13 INFO - 'work_dir': 'build'} 09:48:13 INFO - ##### 09:48:13 INFO - ##### Running clobber step. 09:48:13 INFO - ##### 09:48:13 INFO - Running pre-action listener: _resource_record_pre_action 09:48:13 INFO - Running main action method: clobber 09:48:13 INFO - rmtree: /builds/slave/test/build 09:48:13 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:48:15 INFO - Running post-action listener: _resource_record_post_action 09:48:15 INFO - ##### 09:48:15 INFO - ##### Finished clobber step (success) 09:48:15 INFO - ##### 09:48:15 INFO - ##### 09:48:15 INFO - ##### Running read-buildbot-config step. 09:48:15 INFO - ##### 09:48:15 INFO - Running pre-action listener: _resource_record_pre_action 09:48:15 INFO - Running main action method: read_buildbot_config 09:48:15 INFO - Using buildbot properties: 09:48:15 INFO - { 09:48:15 INFO - "project": "", 09:48:15 INFO - "product": "firefox", 09:48:15 INFO - "script_repo_revision": "production", 09:48:15 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 09:48:15 INFO - "repository": "", 09:48:15 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 09:48:15 INFO - "buildid": "20160516074433", 09:48:15 INFO - "pgo_build": "False", 09:48:15 INFO - "basedir": "/builds/slave/test", 09:48:15 INFO - "buildnumber": 669, 09:48:15 INFO - "slavename": "t-yosemite-r7-0205", 09:48:15 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 09:48:15 INFO - "platform": "yosemite_r7", 09:48:15 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 09:48:15 INFO - "branch": "fx-team", 09:48:15 INFO - "repo_path": "integration/fx-team", 09:48:15 INFO - "revision": "e9533997fcef31fa5d2db464c6ceec0ca6ea560f", 09:48:15 INFO - "stage_platform": "macosx64", 09:48:15 INFO - "builduid": "c2f13e2278c2440aa4c221e7e2eb802a", 09:48:15 INFO - "slavebuilddir": "test" 09:48:15 INFO - } 09:48:15 INFO - Found installer url https://queue.taskcluster.net/v1/task/bsJth3rIRo-exmrUl9e9gQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 09:48:15 INFO - Running post-action listener: _resource_record_post_action 09:48:15 INFO - ##### 09:48:15 INFO - ##### Finished read-buildbot-config step (success) 09:48:15 INFO - ##### 09:48:15 INFO - ##### 09:48:15 INFO - ##### Running download-and-extract step. 09:48:15 INFO - ##### 09:48:15 INFO - Running pre-action listener: _resource_record_pre_action 09:48:15 INFO - Running main action method: download_and_extract 09:48:15 INFO - mkdir: /builds/slave/test/build/tests 09:48:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:48:15 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 09:48:15 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 09:48:15 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 09:48:15 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 09:48:15 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 09:48:31 INFO - Downloaded 1199 bytes. 09:48:31 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 09:48:31 INFO - Using the following test package requirements: 09:48:31 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 09:48:31 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:48:31 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 09:48:31 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:48:31 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 09:48:31 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:48:31 INFO - u'jsshell-mac.zip'], 09:48:31 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:48:31 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 09:48:31 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 09:48:31 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:48:31 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 09:48:31 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:48:31 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 09:48:31 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:48:31 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 09:48:31 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:48:31 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 09:48:31 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:48:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 09:48:31 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 09:48:31 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 09:48:31 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 09:48:31 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 09:48:31 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 09:48:35 INFO - Downloaded 18267661 bytes. 09:48:35 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'] 09:48:35 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 09:48:38 INFO - Return code: 0 09:48:38 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 09:48:38 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')]} 09:48:38 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 09:48:38 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 09:48:38 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 09:48:38 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 09:48:38 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 09:48:52 INFO - Downloaded 18267661 bytes. 09:48:52 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'] 09:48:52 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 09:48:55 INFO - Return code: 0 09:48:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 09:48:55 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 09:48:55 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 09:48:55 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 09:48:55 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 09:48:55 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 09:48:57 INFO - Downloaded 10840194 bytes. 09:48:57 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'] 09:48:57 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 09:48:58 INFO - Return code: 0 09:48:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:48:58 INFO - https://queue.taskcluster.net/v1/task/bsJth3rIRo-exmrUl9e9gQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 09:48:58 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 09:48:58 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 09:48:58 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 09:48:58 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 09:49:09 INFO - Downloaded 110052757 bytes. 09:49:09 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 09:49:09 INFO - mkdir: /builds/slave/test/properties 09:49:09 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:49:09 INFO - Writing to file /builds/slave/test/properties/build_url 09:49:09 INFO - Contents: 09:49:09 INFO - build_url:https://queue.taskcluster.net/v1/task/bsJth3rIRo-exmrUl9e9gQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 09:49:10 INFO - Running post-action listener: _resource_record_post_action 09:49:10 INFO - Running post-action listener: set_extra_try_arguments 09:49:10 INFO - ##### 09:49:10 INFO - ##### Finished download-and-extract step (success) 09:49:10 INFO - ##### 09:49:10 INFO - ##### 09:49:10 INFO - ##### Running populate-webroot step. 09:49:10 INFO - ##### 09:49:10 INFO - Running pre-action listener: _resource_record_pre_action 09:49:10 INFO - Running main action method: populate_webroot 09:49:10 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:49:10 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:49:10 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:49:10 INFO - '--tppagecycles', 09:49:10 INFO - '7'], 09:49:10 INFO - 'tests': ['tsvgm']}}, 09:49:10 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:49:10 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:49:10 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:49:10 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:49:10 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:49:10 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:49:10 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:49:10 INFO - 'talos_options': ['--disable-e10s'], 09:49:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:49:10 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:49:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:49:10 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:49:10 INFO - 'talos_options': ['--disable-e10s'], 09:49:10 INFO - 'tests': ['damp', 'tps']}, 09:49:10 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:49:10 INFO - 'tests': ['damp', 'tps']}, 09:49:10 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:49:10 INFO - 'tests': ['dromaeo_dom']}, 09:49:10 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:49:10 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:49:10 INFO - 'tests': ['a11yr', 09:49:10 INFO - 'ts_paint', 09:49:10 INFO - 'tpaint', 09:49:10 INFO - 'sessionrestore', 09:49:10 INFO - 'sessionrestore_no_auto_restore', 09:49:10 INFO - 'tabpaint']}, 09:49:10 INFO - 'other-e10s': {'tests': ['a11yr', 09:49:10 INFO - 'ts_paint', 09:49:10 INFO - 'tpaint', 09:49:10 INFO - 'sessionrestore', 09:49:10 INFO - 'sessionrestore_no_auto_restore', 09:49:10 INFO - 'tabpaint']}, 09:49:10 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:49:10 INFO - 'ts_paint', 09:49:10 INFO - 'tpaint', 09:49:10 INFO - 'sessionrestore', 09:49:10 INFO - 'sessionrestore_no_auto_restore', 09:49:10 INFO - 'tabpaint']}, 09:49:10 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:49:10 INFO - 'ts_paint', 09:49:10 INFO - 'tpaint', 09:49:10 INFO - 'sessionrestore', 09:49:10 INFO - 'sessionrestore_no_auto_restore', 09:49:10 INFO - 'tabpaint']}, 09:49:10 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:49:10 INFO - 'tests': ['a11yr', 09:49:10 INFO - 'ts_paint', 09:49:10 INFO - 'tpaint', 09:49:10 INFO - 'sessionrestore', 09:49:10 INFO - 'sessionrestore_no_auto_restore', 09:49:10 INFO - 'tabpaint']}, 09:49:10 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:49:10 INFO - 'tests': ['a11yr', 09:49:10 INFO - 'ts_paint', 09:49:10 INFO - 'tpaint', 09:49:10 INFO - 'sessionrestore', 09:49:10 INFO - 'sessionrestore_no_auto_restore', 09:49:10 INFO - 'tabpaint']}, 09:49:10 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:49:10 INFO - 'tests': ['tsvgx', 09:49:10 INFO - 'tsvgr_opacity', 09:49:10 INFO - 'tart', 09:49:10 INFO - 'tscrollx', 09:49:10 INFO - 'cart']}, 09:49:10 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:49:10 INFO - 'tsvgr_opacity', 09:49:10 INFO - 'tart', 09:49:10 INFO - 'tscrollx', 09:49:10 INFO - 'cart']}, 09:49:10 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:49:10 INFO - 'talos_options': ['--disable-e10s'], 09:49:10 INFO - 'tests': ['tp5o']}, 09:49:10 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:49:10 INFO - 'tests': ['tp5o']}, 09:49:10 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:49:10 INFO - 'talos_options': ['--disable-e10s', 09:49:10 INFO - '--xperf_path', 09:49:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:49:10 INFO - 'tests': ['tp5n']}, 09:49:10 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:49:10 INFO - 'talos_options': ['--xperf_path', 09:49:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:49:10 INFO - 'tests': ['tp5n']}}, 09:49:10 INFO - 'talos.zip': {'path': '', 09:49:10 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:49:10 INFO - Running post-action listener: _resource_record_post_action 09:49:10 INFO - ##### 09:49:10 INFO - ##### Finished populate-webroot step (success) 09:49:10 INFO - ##### 09:49:10 INFO - ##### 09:49:10 INFO - ##### Running create-virtualenv step. 09:49:10 INFO - ##### 09:49:10 INFO - Running pre-action listener: _resource_record_pre_action 09:49:10 INFO - Running main action method: create_virtualenv 09:49:10 INFO - Creating virtualenv /builds/slave/test/build/venv 09:49:10 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:49:10 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:49:10 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:49:10 INFO - Using real prefix '/tools/python27' 09:49:10 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:49:11 INFO - Installing distribute.............................................................................................................................................................................................done. 09:49:14 INFO - Installing pip.................done. 09:49:14 INFO - Return code: 0 09:49:14 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:49:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 09:49:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:49:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 09:49:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:49:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1105a8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1105ae570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd72bc5ca70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11044cd30>, '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 0x110270db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110443b20>, '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.2EAxMTpO8V/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.ATcVOREf1K/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/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 09:49:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:49:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:49:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2EAxMTpO8V/Render', 09:49:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:49:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:49:14 INFO - 'HOME': '/Users/cltbld', 09:49:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:49:14 INFO - 'LOGNAME': 'cltbld', 09:49:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:49:14 INFO - 'MOZ_NO_REMOTE': '1', 09:49:14 INFO - 'NO_EM_RESTART': '1', 09:49:14 INFO - 'PAGER': '/bin/cat', 09:49:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:49:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:49:14 INFO - 'PWD': '/builds/slave/test', 09:49:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:49:14 INFO - 'SHELL': '/bin/bash', 09:49:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ATcVOREf1K/Listeners', 09:49:14 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 09:49:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:49:14 INFO - 'USER': 'cltbld', 09:49:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:49:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:49:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:49:14 INFO - 'XPC_FLAGS': '0x0', 09:49:14 INFO - 'XPC_SERVICE_NAME': '0', 09:49:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:49:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:49:14 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 09:49:14 INFO - Cleaning up... 09:49:14 INFO - Return code: 0 09:49:14 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:49:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 09:49:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:49:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 09:49:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:49:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1105a8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1105ae570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd72bc5ca70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11044cd30>, '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 0x110270db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110443b20>, '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.2EAxMTpO8V/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.ATcVOREf1K/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/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 09:49:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:49:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:49:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2EAxMTpO8V/Render', 09:49:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:49:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:49:14 INFO - 'HOME': '/Users/cltbld', 09:49:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:49:14 INFO - 'LOGNAME': 'cltbld', 09:49:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:49:14 INFO - 'MOZ_NO_REMOTE': '1', 09:49:14 INFO - 'NO_EM_RESTART': '1', 09:49:14 INFO - 'PAGER': '/bin/cat', 09:49:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:49:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:49:14 INFO - 'PWD': '/builds/slave/test', 09:49:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:49:14 INFO - 'SHELL': '/bin/bash', 09:49:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ATcVOREf1K/Listeners', 09:49:14 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 09:49:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:49:14 INFO - 'USER': 'cltbld', 09:49:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:49:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:49:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:49:14 INFO - 'XPC_FLAGS': '0x0', 09:49:14 INFO - 'XPC_SERVICE_NAME': '0', 09:49:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:49:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:49:14 INFO - Downloading/unpacking psutil>=3.1.1 09:49:14 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 09:49:14 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 09:49:14 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 09:49:14 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 09:49:14 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 09:49:14 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 09:49:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:49:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:49:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:49:17 INFO - Installing collected packages: psutil 09:49:17 INFO - Running setup.py install for psutil 09:49:17 INFO - building 'psutil._psutil_osx' extension 09:49:17 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 09:49:18 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 09:49:18 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 09:49:18 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 09:49:18 INFO - building 'psutil._psutil_posix' extension 09:49:18 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 09:49:18 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:49:18 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:49:18 INFO - ^ 09:49:18 INFO - 1 warning generated. 09:49:18 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 09:49:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:49:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:49:18 INFO - Successfully installed psutil 09:49:18 INFO - Cleaning up... 09:49:18 INFO - Return code: 0 09:49:18 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:49:18 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')]} 09:49:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:49:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49: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')]} 09:49:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:49:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49:19 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 0x1105a8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1105ae570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd72bc5ca70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11044cd30>, '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 0x110270db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110443b20>, '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.2EAxMTpO8V/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.ATcVOREf1K/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/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 09:49:19 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 09:49:19 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 09:49:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2EAxMTpO8V/Render', 09:49:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:49:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:49:19 INFO - 'HOME': '/Users/cltbld', 09:49:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:49:19 INFO - 'LOGNAME': 'cltbld', 09:49:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:49:19 INFO - 'MOZ_NO_REMOTE': '1', 09:49:19 INFO - 'NO_EM_RESTART': '1', 09:49:19 INFO - 'PAGER': '/bin/cat', 09:49:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:49:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:49:19 INFO - 'PWD': '/builds/slave/test', 09:49:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:49:19 INFO - 'SHELL': '/bin/bash', 09:49:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ATcVOREf1K/Listeners', 09:49:19 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 09:49:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:49:19 INFO - 'USER': 'cltbld', 09:49:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:49:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:49:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:49:19 INFO - 'XPC_FLAGS': '0x0', 09:49:19 INFO - 'XPC_SERVICE_NAME': '0', 09:49:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:49:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:49:19 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:49:19 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 09:49:19 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 09:49:19 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 09:49:19 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 09:49:19 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 09:49:19 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 09:49:21 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:49:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:49:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:49:22 INFO - Installing collected packages: mozsystemmonitor 09:49:22 INFO - Running setup.py install for mozsystemmonitor 09:49:22 INFO - Successfully installed mozsystemmonitor 09:49:22 INFO - Cleaning up... 09:49:22 INFO - Return code: 0 09:49:22 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:49:22 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')]} 09:49:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:49:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49:22 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')]} 09:49:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:49:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49:22 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 0x1105a8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1105ae570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd72bc5ca70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11044cd30>, '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 0x110270db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110443b20>, '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.2EAxMTpO8V/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.ATcVOREf1K/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/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 09:49:22 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 09:49:22 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 09:49:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2EAxMTpO8V/Render', 09:49:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:49:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:49:22 INFO - 'HOME': '/Users/cltbld', 09:49:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:49:22 INFO - 'LOGNAME': 'cltbld', 09:49:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:49:22 INFO - 'MOZ_NO_REMOTE': '1', 09:49:22 INFO - 'NO_EM_RESTART': '1', 09:49:22 INFO - 'PAGER': '/bin/cat', 09:49:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:49:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:49:22 INFO - 'PWD': '/builds/slave/test', 09:49:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:49:22 INFO - 'SHELL': '/bin/bash', 09:49:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ATcVOREf1K/Listeners', 09:49:22 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 09:49:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:49:22 INFO - 'USER': 'cltbld', 09:49:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:49:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:49:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:49:22 INFO - 'XPC_FLAGS': '0x0', 09:49:22 INFO - 'XPC_SERVICE_NAME': '0', 09:49:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:49:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:49:22 INFO - Downloading/unpacking blobuploader==1.2.4 09:49:22 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 09:49:22 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 09:49:22 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 09:49:22 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 09:49:22 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 09:49:22 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 09:49:24 INFO - Downloading blobuploader-1.2.4.tar.gz 09:49:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:49:25 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:49:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:49:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:49:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:49:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:49:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:49:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:49:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:49:25 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:49:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:49:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:49:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:49:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:49:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:49:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:49:26 INFO - Downloading docopt-0.6.1.tar.gz 09:49:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:49:26 INFO - Installing collected packages: blobuploader, requests, docopt 09:49:26 INFO - Running setup.py install for blobuploader 09:49:26 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:49:26 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:49:26 INFO - Running setup.py install for requests 09:49:26 INFO - Running setup.py install for docopt 09:49:26 INFO - Successfully installed blobuploader requests docopt 09:49:26 INFO - Cleaning up... 09:49:27 INFO - Return code: 0 09:49:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:49:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 09:49:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:49:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 09:49:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:49:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49:27 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 0x1105a8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1105ae570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd72bc5ca70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11044cd30>, '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 0x110270db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110443b20>, '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.2EAxMTpO8V/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.ATcVOREf1K/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/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 09:49:27 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 09:49:27 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 09:49:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2EAxMTpO8V/Render', 09:49:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:49:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:49:27 INFO - 'HOME': '/Users/cltbld', 09:49:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:49:27 INFO - 'LOGNAME': 'cltbld', 09:49:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:49:27 INFO - 'MOZ_NO_REMOTE': '1', 09:49:27 INFO - 'NO_EM_RESTART': '1', 09:49:27 INFO - 'PAGER': '/bin/cat', 09:49:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:49:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:49:27 INFO - 'PWD': '/builds/slave/test', 09:49:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:49:27 INFO - 'SHELL': '/bin/bash', 09:49:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ATcVOREf1K/Listeners', 09:49:27 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 09:49:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:49:27 INFO - 'USER': 'cltbld', 09:49:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:49:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:49:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:49:27 INFO - 'XPC_FLAGS': '0x0', 09:49:27 INFO - 'XPC_SERVICE_NAME': '0', 09:49:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:49:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:49:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:49:27 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-IosJFq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:49:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:49:27 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-Sqs5_u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:49:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:49:27 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-p61fQn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:49:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:49:27 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-BKG3y1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:49:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:49:27 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-Ybq2Z4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:49:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:49:27 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-dlUfQ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:49:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:49:27 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-7wpl00-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:49:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:49:28 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-FNx0cp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:49:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:49:28 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-7sGuf_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:49:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:49:28 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-5Cao2Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:49:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:49:28 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-Kkj2zI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:49:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:49:28 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-beL26m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:49:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:49:28 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-QGPr4N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:49:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:49:28 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-7uScTv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:49:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:49:28 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-D6olKy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:49:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:49:28 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-qAkLpH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:49:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:49:28 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-ioJW6k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:49:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:49:29 INFO - Running setup.py install for manifestparser 09:49:29 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:49:29 INFO - Running setup.py install for mozcrash 09:49:29 INFO - Running setup.py install for mozdebug 09:49:29 INFO - Running setup.py install for mozdevice 09:49:29 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:49:29 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:49:29 INFO - Running setup.py install for mozfile 09:49:29 INFO - Running setup.py install for mozhttpd 09:49:29 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:49:29 INFO - Running setup.py install for mozinfo 09:49:30 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:49:30 INFO - Running setup.py install for mozInstall 09:49:30 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:49:30 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:49:30 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:49:30 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:49:30 INFO - Running setup.py install for mozleak 09:49:30 INFO - Running setup.py install for mozlog 09:49:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:49:30 INFO - Running setup.py install for moznetwork 09:49:30 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:49:30 INFO - Running setup.py install for mozprocess 09:49:30 INFO - Running setup.py install for mozprofile 09:49:31 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:49:31 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:49:31 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:49:31 INFO - Running setup.py install for mozrunner 09:49:31 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:49:31 INFO - Running setup.py install for mozscreenshot 09:49:31 INFO - Running setup.py install for moztest 09:49:31 INFO - Running setup.py install for mozversion 09:49:31 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:49:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:49:31 INFO - Cleaning up... 09:49:31 INFO - Return code: 0 09:49:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:49:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 09:49:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:49:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 09:49:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:49:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49:31 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 0x1105a8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1105ae570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd72bc5ca70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11044cd30>, '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 0x110270db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110443b20>, '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.2EAxMTpO8V/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.ATcVOREf1K/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/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 09:49:31 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 09:49:31 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 09:49:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2EAxMTpO8V/Render', 09:49:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:49:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:49:31 INFO - 'HOME': '/Users/cltbld', 09:49:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:49:31 INFO - 'LOGNAME': 'cltbld', 09:49:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:49:31 INFO - 'MOZ_NO_REMOTE': '1', 09:49:31 INFO - 'NO_EM_RESTART': '1', 09:49:31 INFO - 'PAGER': '/bin/cat', 09:49:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:49:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:49:31 INFO - 'PWD': '/builds/slave/test', 09:49:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:49:31 INFO - 'SHELL': '/bin/bash', 09:49:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ATcVOREf1K/Listeners', 09:49:31 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 09:49:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:49:31 INFO - 'USER': 'cltbld', 09:49:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:49:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:49:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:49:31 INFO - 'XPC_FLAGS': '0x0', 09:49:31 INFO - 'XPC_SERVICE_NAME': '0', 09:49:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:49:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:49:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:49:31 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-xC1sH_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:49:32 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)) 09:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:49:32 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-0KZ17D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:49:32 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)) 09:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:49:32 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-C0NRLr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:49:32 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)) 09:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:49:32 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-cIebuo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:49:32 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)) 09:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:49:32 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-BF5dcy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:49:32 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)) 09:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:49:32 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-npyzrg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:49:32 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)) 09:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:49:32 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-__3lSG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:49:32 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)) 09:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:49:32 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-ZFxo_9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:49:32 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)) 09:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:49:32 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-BS5Wf3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:49:32 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)) 09:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:49:32 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-Eg8BhH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:49:33 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)) 09:49:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:49:33 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-FFsaRM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:49:33 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)) 09:49:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:49:33 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-dSEWIv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:49:33 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)) 09:49:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:49:33 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-UMyp8m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:49:33 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)) 09:49:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:49:33 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-tnMiYH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:49:33 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)) 09:49:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:49:33 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-eSwG5g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:49:33 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)) 09:49:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:49:33 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-oa60vC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:49:33 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)) 09:49:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:49:33 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-mg03lf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:49:33 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)) 09:49:33 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)) 09:49:33 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)) 09:49:33 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)) 09:49:33 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)) 09:49:33 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)) 09:49:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:49:33 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 09:49:33 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 09:49:33 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 09:49:33 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 09:49:33 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 09:49:33 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 09:49:36 INFO - Downloading blessings-1.6.tar.gz 09:49:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:49:36 INFO - Installing collected packages: blessings 09:49:36 INFO - Running setup.py install for blessings 09:49:36 INFO - Successfully installed blessings 09:49:36 INFO - Cleaning up... 09:49:36 INFO - Return code: 0 09:49:36 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:49:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:49:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:49:36 INFO - Reading from file tmpfile_stdout 09:49:36 INFO - Current package versions: 09:49:36 INFO - blessings == 1.6 09:49:36 INFO - blobuploader == 1.2.4 09:49:36 INFO - docopt == 0.6.1 09:49:36 INFO - manifestparser == 1.1 09:49:36 INFO - mozInstall == 1.12 09:49:36 INFO - mozcrash == 0.17 09:49:36 INFO - mozdebug == 0.1 09:49:36 INFO - mozdevice == 0.48 09:49:36 INFO - mozfile == 1.2 09:49:36 INFO - mozhttpd == 0.7 09:49:36 INFO - mozinfo == 0.9 09:49:36 INFO - mozleak == 0.1 09:49:36 INFO - mozlog == 3.2 09:49:36 INFO - moznetwork == 0.27 09:49:36 INFO - mozprocess == 0.22 09:49:36 INFO - mozprofile == 0.28 09:49:36 INFO - mozrunner == 6.11 09:49:36 INFO - mozscreenshot == 0.1 09:49:36 INFO - mozsystemmonitor == 0.3 09:49:36 INFO - moztest == 0.7 09:49:36 INFO - mozversion == 1.4 09:49:36 INFO - psutil == 3.1.1 09:49:36 INFO - requests == 1.2.3 09:49:36 INFO - wsgiref == 0.1.2 09:49:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:49:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 09:49:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:49:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 09:49:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:49:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49:36 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 0x1105a8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1105ae570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd72bc5ca70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11044cd30>, '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 0x110270db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110443b20>, '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.2EAxMTpO8V/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.ATcVOREf1K/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/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 09:49:36 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 09:49:36 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 09:49:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2EAxMTpO8V/Render', 09:49:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:49:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:49:36 INFO - 'HOME': '/Users/cltbld', 09:49:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:49:36 INFO - 'LOGNAME': 'cltbld', 09:49:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:49:36 INFO - 'MOZ_NO_REMOTE': '1', 09:49:36 INFO - 'NO_EM_RESTART': '1', 09:49:36 INFO - 'PAGER': '/bin/cat', 09:49:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:49:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:49:36 INFO - 'PWD': '/builds/slave/test', 09:49:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:49:36 INFO - 'SHELL': '/bin/bash', 09:49:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ATcVOREf1K/Listeners', 09:49:36 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 09:49:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:49:36 INFO - 'USER': 'cltbld', 09:49:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:49:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:49:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:49:36 INFO - 'XPC_FLAGS': '0x0', 09:49:36 INFO - 'XPC_SERVICE_NAME': '0', 09:49:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:49:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:49:37 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)) 09:49:37 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)) 09:49:37 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)) 09:49:37 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)) 09:49:37 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)) 09:49:37 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)) 09:49:37 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)) 09:49:37 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)) 09:49:37 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)) 09:49:37 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)) 09:49:37 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)) 09:49:37 INFO - Cleaning up... 09:49:37 INFO - Return code: 0 09:49:37 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:49:37 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')]} 09:49:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:49:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49:37 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')]} 09:49:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:49:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49:37 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 0x1105a8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1105ae570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd72bc5ca70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11044cd30>, '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 0x110270db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110443b20>, '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.2EAxMTpO8V/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.ATcVOREf1K/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/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 09:49:37 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 09:49:37 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 09:49:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2EAxMTpO8V/Render', 09:49:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:49:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:49:37 INFO - 'HOME': '/Users/cltbld', 09:49:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:49:37 INFO - 'LOGNAME': 'cltbld', 09:49:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:49:37 INFO - 'MOZ_NO_REMOTE': '1', 09:49:37 INFO - 'NO_EM_RESTART': '1', 09:49:37 INFO - 'PAGER': '/bin/cat', 09:49:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:49:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:49:37 INFO - 'PWD': '/builds/slave/test', 09:49:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:49:37 INFO - 'SHELL': '/bin/bash', 09:49:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ATcVOREf1K/Listeners', 09:49:37 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 09:49:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:49:37 INFO - 'USER': 'cltbld', 09:49:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:49:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:49:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:49:37 INFO - 'XPC_FLAGS': '0x0', 09:49:37 INFO - 'XPC_SERVICE_NAME': '0', 09:49:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:49:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:49:37 INFO - Downloading/unpacking jsonschema 09:49:37 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 09:49:37 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 09:49:37 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 09:49:37 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 09:49:37 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 09:49:37 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 09:49:39 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:49:39 INFO - Downloading/unpacking functools32 (from jsonschema) 09:49:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:49:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:49:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:49:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:49:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:49:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:49:40 INFO - Downloading functools32-3.2.3-2.tar.gz 09:49:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:49:40 INFO - warning: no files found matching '*.txt' 09:49:40 INFO - no previously-included directories found matching 'build' 09:49:40 INFO - no previously-included directories found matching 'dist' 09:49:40 INFO - no previously-included directories found matching '.git*' 09:49:40 INFO - Installing collected packages: jsonschema, functools32 09:49:40 INFO - Running setup.py install for functools32 09:49:40 INFO - warning: no files found matching '*.txt' 09:49:40 INFO - no previously-included directories found matching 'build' 09:49:40 INFO - no previously-included directories found matching 'dist' 09:49:40 INFO - no previously-included directories found matching '.git*' 09:49:40 INFO - Successfully installed jsonschema functools32 09:49:40 INFO - Cleaning up... 09:49:40 INFO - Return code: 0 09:49:40 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 09:49:40 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')]} 09:49:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:49:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49:40 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')]} 09:49:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:49:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49:40 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 0x1105a8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1105ae570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd72bc5ca70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11044cd30>, '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 0x110270db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110443b20>, '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.2EAxMTpO8V/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.ATcVOREf1K/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/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 09:49:40 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 09:49:40 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 09:49:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2EAxMTpO8V/Render', 09:49:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:49:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:49:40 INFO - 'HOME': '/Users/cltbld', 09:49:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:49:40 INFO - 'LOGNAME': 'cltbld', 09:49:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:49:40 INFO - 'MOZ_NO_REMOTE': '1', 09:49:40 INFO - 'NO_EM_RESTART': '1', 09:49:40 INFO - 'PAGER': '/bin/cat', 09:49:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:49:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:49:40 INFO - 'PWD': '/builds/slave/test', 09:49:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:49:40 INFO - 'SHELL': '/bin/bash', 09:49:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ATcVOREf1K/Listeners', 09:49:40 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 09:49:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:49:40 INFO - 'USER': 'cltbld', 09:49:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:49:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:49:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:49:40 INFO - 'XPC_FLAGS': '0x0', 09:49:40 INFO - 'XPC_SERVICE_NAME': '0', 09:49:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:49:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:49:40 INFO - Downloading/unpacking flake8 09:49:40 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 09:49:40 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 09:49:40 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 09:49:40 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 09:49:40 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 09:49:40 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 09:49:43 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:49:43 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:49:43 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 09:49:43 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 09:49:43 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 09:49:43 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 09:49:43 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 09:49:43 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 09:49:43 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:49:43 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:49:43 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 09:49:43 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 09:49:43 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 09:49:43 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 09:49:43 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 09:49:43 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 09:49:44 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:49:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:49:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:49:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:49:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:49:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:49:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:49:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 09:49:44 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:49:44 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:49:44 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:49:44 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:49:44 INFO - no previously-included directories found matching 'docs/_build' 09:49:44 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:49:44 INFO - Running setup.py install for pep8 09:49:44 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:49:44 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:49:44 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:49:44 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:49:44 INFO - no previously-included directories found matching 'docs/_build' 09:49:45 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 09:49:45 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:49:45 INFO - Cleaning up... 09:49:45 INFO - Return code: 0 09:49:45 INFO - Running post-action listener: _resource_record_post_action 09:49:45 INFO - Running post-action listener: _start_resource_monitoring 09:49:45 INFO - Starting resource monitoring. 09:49:45 INFO - ##### 09:49:45 INFO - ##### Finished create-virtualenv step (success) 09:49:45 INFO - ##### 09:49:45 INFO - ##### 09:49:45 INFO - ##### Running install step. 09:49:45 INFO - ##### 09:49:45 INFO - Running pre-action listener: _resource_record_pre_action 09:49:45 INFO - Running main action method: install 09:49:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:49:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:49:45 INFO - Reading from file tmpfile_stdout 09:49:45 INFO - Detecting whether we're running mozinstall >=1.0... 09:49:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:49:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:49:45 INFO - Reading from file tmpfile_stdout 09:49:45 INFO - Output received: 09:49:45 INFO - Usage: mozinstall [options] installer 09:49:45 INFO - Options: 09:49:45 INFO - -h, --help show this help message and exit 09:49:45 INFO - -d DEST, --destination=DEST 09:49:45 INFO - Directory to install application into. [default: 09:49:45 INFO - "/builds/slave/test"] 09:49:45 INFO - --app=APP Application being installed. [default: firefox] 09:49:45 INFO - mkdir: /builds/slave/test/build/application 09:49:45 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'] 09:49:45 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 09:50:19 INFO - Reading from file tmpfile_stdout 09:50:19 INFO - Output received: 09:50:19 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:50:19 INFO - Running post-action listener: _resource_record_post_action 09:50:19 INFO - ##### 09:50:19 INFO - ##### Finished install step (success) 09:50:19 INFO - ##### 09:50:19 INFO - ##### 09:50:19 INFO - ##### Running run-tests step. 09:50:19 INFO - ##### 09:50:19 INFO - Running pre-action listener: _resource_record_pre_action 09:50:19 INFO - Running main action method: run_tests 09:50:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:50:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:50:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:50:19 INFO - 2016-05-16 09:50:19.633 screenresolution[1648:9191] starting screenresolution argv=screenresolution get 09:50:19 INFO - 2016-05-16 09:50:19.635 screenresolution[1648:9191] Display 0: 1600x1200x32@60 09:50:19 INFO - 2016-05-16 09:50:19.641 screenresolution[1649:9193] starting screenresolution argv=screenresolution list 09:50:19 INFO - Available Modes on Display 0 09:50:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:50:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:50:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:50:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:50:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:50:19 INFO - Intel Iris: 09:50:19 INFO - Chipset Model: Intel Iris 09:50:19 INFO - Type: GPU 09:50:19 INFO - Bus: Built-In 09:50:19 INFO - VRAM (Dynamic, Max): 1536 MB 09:50:19 INFO - Vendor: Intel (0x8086) 09:50:19 INFO - Device ID: 0x0a2e 09:50:19 INFO - Revision ID: 0x0009 09:50:19 INFO - Displays: 09:50:19 INFO - Display: 09:50:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:50:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:50:19 INFO - Display Serial Number: 1600x1200 60 09:50:19 INFO - Main Display: Yes 09:50:19 INFO - Mirror: Off 09:50:19 INFO - Online: Yes 09:50:19 INFO - Rotation: Supported 09:50:19 INFO - Return code: 0 09:50:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:50:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:50:19 INFO - Python 2.7.3 09:50:19 INFO - Return code: 0 09:50:19 INFO - grabbing minidump binary from tooltool 09:50: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')]} 09:50:19 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 0x11044cd30>, '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 0x110270db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110443b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:50:19 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 09:50:19 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 09:50:19 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 09:50:19 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:50:19 INFO - Return code: 0 09:50:19 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:50:19 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:50:19 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:50:19 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:50:19 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:50:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 09:50:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 09:50:21 INFO - Return code: 0 09:50:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bsJth3rIRo-exmrUl9e9gQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0205', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 09:50:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/bsJth3rIRo-exmrUl9e9gQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0205 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 09:50:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2EAxMTpO8V/Render', 09:50:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:50:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:50:21 INFO - 'HOME': '/Users/cltbld', 09:50:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:50:21 INFO - 'LOGNAME': 'cltbld', 09:50:21 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:50:21 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:50:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:50:21 INFO - 'MOZ_NO_REMOTE': '1', 09:50:21 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:50:21 INFO - 'NO_EM_RESTART': '1', 09:50:21 INFO - 'PAGER': '/bin/cat', 09:50:21 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:50:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:50:21 INFO - 'PWD': '/builds/slave/test', 09:50:21 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:50:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:50:21 INFO - 'SHELL': '/bin/bash', 09:50:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ATcVOREf1K/Listeners', 09:50:21 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 09:50:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:50:21 INFO - 'USER': 'cltbld', 09:50:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:50:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:50:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:50:21 INFO - 'XPC_FLAGS': '0x0', 09:50:21 INFO - 'XPC_SERVICE_NAME': '0', 09:50:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:50:21 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bsJth3rIRo-exmrUl9e9gQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0205', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 09:50:21 INFO - mozversion application_buildid: 20160516074433 09:50:21 INFO - mozversion application_changeset: e9533997fcef31fa5d2db464c6ceec0ca6ea560f 09:50:21 INFO - mozversion application_display_name: Nightly 09:50:21 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:50:21 INFO - mozversion application_name: Firefox 09:50:21 INFO - mozversion application_remotingname: firefox 09:50:21 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 09:50:21 INFO - mozversion application_vendor: Mozilla 09:50:21 INFO - mozversion application_version: 49.0a1 09:50:21 INFO - mozversion platform_buildid: 20160516074433 09:50:21 INFO - mozversion platform_changeset: e9533997fcef31fa5d2db464c6ceec0ca6ea560f 09:50:21 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 09:50:21 INFO - mozversion platform_version: 49.0a1 09:50:21 INFO - using testdate: 1463417421 09:50:21 INFO - actual date: 1463417421 09:50:21 INFO - starting webserver on 'localhost:49277' 09:50:21 INFO - SUITE-START | Running 2 tests 09:50:21 INFO - TEST-START | dromaeo_css 09:50:21 INFO - operating with platform_type : mac_ 09:50:21 INFO - Initialising browser for dromaeo_css test... 09:50:21 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/tmpYEIuOo/profile http://localhost:49277/getInfo.html) 09:50:24 INFO - PROCESS | 1667 | __metrics Screen width/height:1600/1200 09:50:24 INFO - PROCESS | 1667 | colorDepth:24 09:50:24 INFO - PROCESS | 1667 | Browser inner width/height: 1024/689 09:50:24 INFO - PROCESS | 1667 | __metrics 09:50:24 ERROR - PROCESS | 1667 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 09:50:24 INFO - TEST-INFO | 1667: exit 0 09:50:24 INFO - Browser initialized. 09:50:24 INFO - Running cycle 1/1 for dromaeo_css test... 09:50:25 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/tmpYEIuOo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:50:36 INFO - PROCESS | 1671 | RSS: Main: 249835520 09:50:36 INFO - PROCESS | 1671 | 09:51:26 INFO - PROCESS | 1671 | 1463417486664 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] 09:53:37 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49277/tests/dromaeo/cssquery-ext.html) 09:53:38 INFO - PROCESS | 1671 | RSS: Main: 261197824 09:53:38 INFO - PROCESS | 1671 | 09:56:41 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-ext.html (next: http://localhost:49277/tests/dromaeo/cssquery-jquery.html) 09:56:41 INFO - PROCESS | 1671 | RSS: Main: 273633280 09:56:41 INFO - PROCESS | 1671 | 09:59:43 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49277/tests/dromaeo/cssquery-mootools.html) 09:59:44 INFO - PROCESS | 1671 | RSS: Main: 275484672 09:59:44 INFO - PROCESS | 1671 | 10:02:45 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49277/tests/dromaeo/cssquery-prototype.html) 10:02:46 INFO - PROCESS | 1671 | RSS: Main: 277417984 10:02:46 INFO - PROCESS | 1671 | 10:05:49 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49277/tests/dromaeo/cssquery-yui.html) 10:05:50 INFO - PROCESS | 1671 | RSS: Main: 280498176 10:05:50 INFO - PROCESS | 1671 | 10:08:52 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-yui.html (next: http://localhost:49277/tests/dromaeo/cssquery-dojo.html) 10:08:52 INFO - PROCESS | 1671 | RSS: Main: 281088000 10:08:52 INFO - PROCESS | 1671 | 10:08:52 INFO - PROCESS | 1671 | __start_tp_report 10:08:52 INFO - PROCESS | 1671 | _x_x_mozilla_page_load 10:08:52 INFO - PROCESS | 1671 | _x_x_mozilla_page_load_details 10:08:52 INFO - PROCESS | 1671 | |i|pagename|runs| 10:08:52 INFO - PROCESS | 1671 | |0;dojo.html;2887.812292201008;3425.862965481381;3474.114100904269;3507.4212754895443;3533.4169861972778;5768.451997060283;5790.39200883907;5828.446297601731;5856.238688970433;5867.413258674141;5682.346530149034;5701.543876489881;5760.654360738358;5784.2769653793275;5833.562482813784;5185.766640501177;5215.765290561933;5316.654417462872;5320.308359913206;5354.116570765819;202.58762773321598;5289.68261904285;5308.522232999029;5313.123334649778;5377.059014672417;5243.8164664236565;5256.579473642099;5275.841724748245;5283.682979021271;5309.867253318698;5153.871153221162;5285.233641122016;5343.385510666284;5348.545373643245;5374.973125134388;5167.560757335631;5200.453952334989;5231.947680523184;5232.3459567554055;5242.55438287746;5649.887002259973;5692.857678558028;5770.567207459415;5774.740136693859;5804.187413762034;5451.618386712932;6213.689315534205;6241.500679945593;6333.334999825044;6335.68321583919;6250.843728906768;6266.310705822356;6276.058591211309;6288.717007734663;6293.181886354791;5895.498882594985;5914.319853216849;5928.472767382459;5946.524278057745;5974.4622983930785;5462.562994960394;6242.687865606701;6260.060990851318;6288.9056664150075;6299.590526615755;5140.948590514047;6257.499400047986;6273.37266273369;6286.214223222098;6318.7472501100365;6272.311315353143;6278;6279.466245369194;6320.462760665395;6326.5255105867245;6119.837693858789;6195.411435913581;6219.937800621936;6233.688315584293;6311.053341998737;6109.526129009804;6121.148138557471;6139.009643731611;6143.880361275353;6275.6862156891975;4255.936160957588;4908;4922.556969872657;4926.531979461945;4969.527894849984;4173.83304667816;4389.846355377546;4420.425344705232;4465.732056076646;4473.306637471197;5867.471927526543;5945.40545945402;5969.552283578749;5974.970125149346;5979.073243647242;5195.3246078009615;6229.719662615194;6266.592671476339;6279.497640188774;6294.0558916162945;5938.703064846741;5977.820665380045;5983.790567330123;6005.489533389624;6011.609245399035;5458.344998600193;5464.726763661801;5466.180072989084;5470.753816078286;5481.451854814487;6220.968895155585;6361.8727625448155;6492.870142597216;6512.088307636935;6515.315891831289;5589.748461319332;6207.596506227081;6255.530835187288;6268.561200715906;6302.369763023661;6464.838379040561;6475;6484.513661475314;6485.286618472059;6498.772542960974;5424.701641409761;6061.060535616986;6194.938050619436;6205.720742566505;6274.498040156685;6252.374762523711;6255.40573645503;6278;6283.308836027942;6283.780067697791;6140.3552626975315;6224.564280500503;6242.500599951902;6244.4380005797875;6278.058291256166;6083.939160608337;6100.267967843887;6100.853682747703;6115.084638624664;6257.9687101564205;4403.471583409883;5836.037053886062;5900.793472228623;5944.286685597756;6041.305249896318;6127.6936153193055;6166.383361663798;6187.504999600112;6190.164327815867;6216.129741836237;4904.730239836843;5952.077427998667;6005.069214271794;6058.182145410313;6105.389461053859 10:08:52 INFO - PROCESS | 1671 | |1;ext.html;501.78172494964696;539.8110661268554;542.1921337207561;558.9105743081108;569.8432930943991;39053.82838514845;41520;41527.37708934368;41538.79230603843;41722.582774172246;42550.574494255045;42882.35676464855;43265.134697306035;43275.134497310035;43372.132557348836;9563.80872382552;9661.4203147811;9702.078302561245;9723.708288751348;9738.1235688788;9654;9731.464769437678;9743.805123897519;9769.169620582259;9772.31593788435;8967.641294348217;9701.660441884536;9715.077067678594;9730.65942692006;9802.068803463659;9757;9763.170130538912;9769.267304952085;9769.267304952122;9778.315517913748;9541.33210675253;9582.808343833085;9646.987066358002;9672.613095476243;9675.467849268287;39546.80226598877;39815.80092099521;40056.19887602232;40396;40541.189176216605;2851.9714802852122;3073.2470544716466;3122.406742718876;3412.897613071612;3427.674370934757;26978.460430791274;27848.582271265946;28558.286042849097;29266.85366573175;29272.121836344944;2614.359481926921;2637.2879322582794;2641.5773476243708;2720.687120981093;2727.863606819671;489471;491245.08754912706;492251;496367.5181624105;498370;34305.656943430746;35214.82392588072;35363;35381.82309088438;36136;35543.466847997304;35872.641273587455;36052.459213111826;36065;36107.91676249717;15273.923630382;15446.691066178613;15468.149251791034;15528.5341439757;15571.454999074978;12635.368231588383;12676.176048556814;13068;13146.948244140445;13159.60521184366;7937.761867143995;8199.754007379788;8208;8218.63016164262;8226;3442.1566716154202;3452.205992621711;3455.87904423349;3463.9480407793903;3491.94762078569;1000.6647772996128;1031.1956673794452;1048.4261138903973;1076.7792602516465;1173.2198088271375;24442.900069496558;24567.017319307386;29368.678409471624;30008.84995575008;30805.22986925345;998.6155330197926;1061.1139698351963;1090.7000574841823;1090.8581884355142;1164.1676201515957;14564.053336533303;16195.19024048793;16283;16506;16578.50264492067;702240;714591;729371;729796;731042;60621.69689151526;61755.38244617496;62290.68854655698;62408.37591624026;62608.37391626117;1491.1798510819006;1491.940322387114;1498.2658497336226;1513.7502312118377;1517.6281810956386;26171.869140654177;27320.316992075357;29232.415351692423;30118.54822177625;30867.536986944764;51574.484255158466;52097.73951130372;52142;52210.47789522056;52275.47724522706;659142;659596;662960;666044;666409.6679516571;25537.87231063833;25979.870100649376;26093.217203483924;26653.067142649914;26689.733102668724;44894.551054489035;45550;46076.30885536653;46251;46764.29853552132;36334.454983175805;37198.326075326135;37819.243615126994;38152.236955261294;38389.232215356096;36401.453978190875;36799.08002299964;36878.446823297134;36945.6305436953;37825.24349512939;31329.373412531168;36406;37180;37398;37620.05949851275;28190.71809281881;31371.96082254646;31861.522077168396;33070.83464582662;33079;19407.902960485106;24052.39869003289;24355.39111522155;24397.75602244026;24631.38421539476 10:08:52 INFO - PROCESS | 1671 | |2;jquery.html;319.9584054072971;387.32991923971537;392.5564112552813;403.435190732974;417.48857649379516;16267.91866040671;19564.804351956474;20606.278780242694;22251.66622500662;22266.332010039703;20861.37415877526;21197;21303;22194;22430.32709018728;4856.490068542787;5953.136795164699;6205.9069113963105;6208.968955155218;6241.843953901143;5111.51440613143;5615.943840561583;5850.3564607893095;6030.035194368902;6114.388561143895;5834.4165583441745;5878.206442130322;5998.580099393044;6047.971844786375;6062.242219722535;5951.553633477506;5952.851178720523;6003.699815009254;6039.547033972448;6063.363346848583;5955.076963070731;5965.791197308074;5972.014617588093;5980.372060933583;5997.310309314441;13442.32788360578;16778.832211677847;17304.913475432542;26231.868840655865;26286.21141365762;2099.8635088719184;2157.503774131943;2223.6108680980765;2267.2744721689064;2340.976590234093;8440.915590844075;9016.77458063547;10038.397696138256;10198.592056317739;10324;1753.6580366828473;1756.666233415647;1772.2290803500518;1791.7491551182845;1831.9358822441284;92042.07957920402;105394.83815485548;109162.90837091606;119174;124951;10939.507722152523;11511.654650360502;11716.17986740937;11765;11849.289042657296;13072.477100915858;13973.580792576238;14400.207988560729;14784.334704938303;14797.630059248604;8618.439801412887;8718.912810871938;8762.167594078552;8874.245689116367;8967.910320896837;8667;8685.696000640073;8704.956475217583;8723.127687231226;8836.293096552261;4554.610843692705;4595.540445955445;4623.838165664253;4650.674452788272;4709.7174169549935;1974.9901250493942;1994.9900250498654;1999.1403696410682;2028.9043916285336;2064.741907261592;789.5657388436451;948.606328373731;963.7012526116927;992.5781542844406;1003.6838395905266;14482.493112741004;19029.429117126507;19642;19942.90028549848;20472.385828425173;853.4836423963537;924.8104138651695;1000.4997501249376;1001.1190152665753;1023.6366090038078;9636.42181469114;9732.70801875945;10456.477176141163;10551;10582.994615511514;602607;606306;617943;619466;621831;131518.34240828926;137479;139364;139409;141835;897.7441429192648;1017.2675673515205;1030.9123724483352;1031.5873650539695;1034.4793154063514;17719.822801771817;17993.640127197123;18124;18263.45209643713;18412.371546243074;48612.51387486221;50786.98426031532;52470;53878;53955.460445395045;79295.60352198202;79721;80374.59812701133;81442;81725;6906.965465172642;7081.964590177016;7376.889346659912;7438.851222975619;7469.402447804271;11954.820677689668;12822.358882055672;12855.357232138544;13183.14309569875;13222.867771322164;10763.51564179598;11120.944395277971;11205.495752691311;11346.94326528362;11580;8506.362022848187;10895.346279223271;11046.392448415414;11320.547178112784;11489.138314626603;11361.772764544829;11500.82748758786;11503.309801411942;11592.188546801643;11600;5788.971055144697;6742.325767423218;7368.226336234832;7485.513441626277;7762.805929851799;10946.890531094905;11062.391568463812;11243.7751244973;11270.098392128773;11334.319940803578 10:08:52 INFO - PROCESS | 1671 | |3;mootools.html;1557.6417423992482;1600.575847400439;1847.5750577367205;1950.0932066589025;1958.5984873101015;8008.758642410436;8150.673973041086;8877.378583499158;9259.39813912096;9281.535923203837;7890.526568405885;8902.554872256394;9051.502167380791;9067.546622668857;9094.045125261851;2073.5956488484753;2423.309356833303;2467.284487498623;2470.4318006858343;2480.7519248075228;2396.209250947183;2424.9272521824287;2444.498877730061;2446.620773780067;2451.3871532116973;2379.1078345620394;2382.5473160099614;2401.675773770536;2407.277816655005;2414.915477958272;2412.686350774397;2424.806015518754;2429.344077099191;2431.5623187826186;2440.5973014452593;1973.6743437332823;2328.3946173994714;2369.957218823712;2403.9399015024587;2415.842970206931;7744.457887947846;9008.77478063047;9118.86321705175;9139.177474027303;9163.725088247364;5029.3964724232965;5362.981033603603;5418.593605479566;5463.945360546383;5589.385167631557;3260.836958152083;3391.7286617070577;3445.328161008604;3464.688178063961;3465.462853257749;1051.621416290135;1056.392574269798;1065.7921705267477;1066.4347895615335;1071.8285074388139;11786.35175065371;11865.940670296593;11916.106292028133;12048.397580120958;12129.21160124589;4618.861434156981;4645.419322584677;4743.976280118577;4751.83368582098;4760.476347601761;8270.958645206856;9442.858357124649;9448.007959164326;9449.291303152153;9482.099200575934;5164.9741751291;5214.45248248936;5234.869128271747;5237.162054071407;5244.213367994755;4511.368408422823;4855.32025516428;4888.560029597282;5037.29477873098;5042.596592272609;3889.649931506178;3950.6641935435237;4002.519697636244;4016.2971479990883;4042.8584999524874;1231.3596929596738;1234.5432190089725;1235.0613533714443;1243.9675069692314;1245.3773113443278;915.6428992692855;920.1396694091046;925.121134921827;950.77212862332;960.8798900137483;5251.422343542284;5807.448292412214;6067.150598916155;6070.908936365958;6106.572539922163;424.7854833309159;446.475391415086;452.1228816096762;457.2683706070261;476.41401076675885;4020.0150963013666;4055.959440405617;4061.3095773718537;4130.545639979599;4154.148399578079;10789.406582637874;11150.330980141218;11185.217034807485;11199;11238.550457981753;10922.72693182661;10978.890211097947;11049.502772375263;11073.889261107286;11125.221234513669;7660;7874.72438464651;8475.660973560989;8503.362247831437;8583.570821458901;5860.238169037998;5911.497522710446;5953.880922381615;6090.512758979183;6155.738395996637;9805.2646051545;10014.997000600055;10210.234232432746;10346.844797328196;10376.68869933873;10197.541110650187;10288.588456461957;10386.272960892666;10523.421211833424;10655.094316983143;7126.928730712627;7292.124945006633;7309.342159205697;7322.816929576803;7331.3768329692575;8384.58077096155;8417.789555261168;8440.493570385552;8460.70387536458;8573.22840944318;7485.850282994201;7712;7763.805904852424;7898.802529936797;7924.762257132295;7485.625718714152;7506.160990557349;7547.320741133105;7605.201453847267;7759.495632783851;6504.934950650433;6968.930310697031;7176.928230717626;7239.674214660458;7295.343419092307;4101.630853223224;5031.169856973558;5063.443021267584;5071.721055341992;5118.155504341742;4362.258416069212;5030.773615187399;5038.722870242025;5094.51602097815;5152.871178220575 10:08:52 INFO - PROCESS | 1671 | |4;prototype.html;233.9742628310886;287.37495946316756;311.3197663106112;317.71564449817413;330.4267096587419;4660.627149828014;4807.701920481472;4849.660523763338;5544.279243698318;5621.971890140549;4835.107738270691;4918.778654960526;5058.317127187828;5157.793688252475;5177.818776342829;4146.834126634931;4177.624013838747;4186.518550366701;4200.390943313218;4205.243056249874;3997.7401468904573;4008.1983603279314;4205.957940420602;4231.238377092138;4242.342436922282;3968.9007774805573;4116.176764647068;4139.77231252281;4151.522574903895;4154.667626589866;4138.917221655565;4177.9373309400235;4179.811908464127;4181.038361176916;4196.664266858675;2999.460097182506;3614.5843228028853;3659.1218107654236;3659.597444281127;3664.285464334456;4672.486026537078;4905.5283414975365;5246.868828279324;5265.447128051538;5337;4320.006398528325;4466.307722303049;4697.600703940169;4732.52674732524;4743.7429081293485;4671.43942726875;5074.670146440507;5603.439656034405;5732.111522713986;5868.853278668024;3318.0377690469736;3494.3966744775307;3871.399933010388;3926.2736393767245;3970.2258059678084;6043.0331147016705;6045.637261764265;6098.908516372258;6102.96948515259;6138.294096178952;3701.03773019017;3973.2848087344555;3979.5025621797276;3980.7412518186225;4010.4760191127184;3621.492990981265;3795.772253664789;4000.9597504649;4027.81874815634;4089.488813898263;4350.782460876943;4698.483166851644;4713.633046416569;4759.524047595282;4766;5850.795222167204;5896.823095307148;5904.61619994699;5909.645421274738;5927.911081333784;1113.4154568851418;1184.4314728930162;1356.796480527909;1376.3806287170812;1390.603677951779;762.4015148108818;820.6758330459435;831.584207896052;836.6235194162651;853.4452605806275;4297.505786834491;4542.410156445218;4564.543545645475;4679.947011922358;4682;4389.200427824577;4562.612177964844;5102.5302794099625;5140.946106048251;5150.836648607594;3993.7004724645185;4198.908283846222;4301.849435269751;4614.607758340511;4626.814927402934;2318.6174281243743;2340.1224540797202;2369;2490.6762120924163;2500.6871392519074;4874.953154278576;5187.844364669066;5307.12579816736;5351.234092749383;5363.436839131913;4565.644251475163;5176.327077479904;5244.564810049424;5297.708626025528;5305.602079844027;5488.615796894179;5554.91667624986;6318.526110541636;6360.268569114519;6377.831537277348;5112.97443512795;5541.3904470509015;5896.321922978912;6093.360197179232;6136.64993701385;5358.839234822962;5670.3272534601565;5836.941630583639;5939.406059394026;6031.396860314109;5657.500762297912;6035;6309.274433440213;6318.704665543461;6360.363963603602;4028.3554631258858;4051.2100140472326;4054.4729185205733;4058.553559108555;4076.4089207064217;5075.644704870623;5244.790208391774;5462.699551524545;5975.970120149372;6113.2969708482315;5152.505773325694;5715.714214289185;5853.8536536585225;5889.52883769306;5907.143464197753;4673;5672.432756724294;5872.5301975842685;5888.823335299948;5896.528277737685;5072.67027643202;5113.8977220454635;5572;5660.443378071063;5774.740136693775;4705.411823522059;5394.973025134849;5891.23413956183;5891.440313170242;5906;4779.944623811794;4827.734474603931;5065.581637141606;5793.841231753582;5809.50619197373 10:08:52 INFO - PROCESS | 1671 | |5;yui.html;675.3044364304766;715.8961950517172;716.3695947566141;738.830069084111;752.6650640464992;15127.243637818106;15477;15637;15637.905346625741;15665.13841738704;14023.018388712795;14056.156630602145;14308.642283942907;14359.425622975068;14382.784258236108;898.6854600889685;913.118840319092;918.4443411735904;920.9631614735403;923.3490389275578;667.5260564998856;740.2708332292688;897.5467388968594;928.5171710710426;929.9349045566813;975.8682577852005;982.9950850245739;983.8179936711696;987.3088837813523;987.8024395120968;859.2009431228954;874.9737507874773;976.9853452198223;977.5161295158871;980.9852852207223;676.2527407215055;704.5983789240136;730.0983285642228;730.1238513783428;731.016782427634;11699.415029248503;13235.073544851864;13789.51736689211;13877;14868.330925108396;12380.752384952251;12492.125551211418;12520.373981300989;12584.559540416132;12883.098183127186;8309.626066827008;10111.24165687569;10137.594496220143;10217.233707471969;10677.305975111592;3245.350929814023;3449.8792542261153;3472.878449254289;3557.1640664443835;3747.887563373076;57852;79467;80880;83402.58298708468;84346;9233.430316846152;9351;9446.527673616429;9518.23854091685;10154.593816247267;19437.708434373497;19752.617316787688;21628.45928851791;21636;21638.675419868716;7039.049728286615;7150.5352152109945;7154.962530433162;7159.749408770668;7337.119545654449;3949.5260568731937;4004.6596039336982;4022.3162062449455;4028.529586700833;4089.2230476209425;3333.916652083669;3424.828758562062;3434.7252219822353;3438.209211881231;3449.913752156216;575.23493753308;591.6864062047122;593.1458699472745;604.703695189354;608.2032537376051;524.4964833759557;532.9200619907045;554.3957086775434;562.4993755557557;569.2343797592232;7976.680932762624;8076.3942704297415;8167;8248.46384984974;8284.337253019865;559.4098226371217;566.2921348314607;583.8948989181988;589.1604463639383;590.5541316306247;3186;3862.7102967277565;3875.767453952772;3977.661898738582;4054.5991464526064;91623;92801.53599232096;93641;93784;95912;81606.59196703979;82407.587962061;83410.58294708487;83787.5810620943;84603.57698211593;73472;73752;74011.88982165272;74230.6288468565;74983;6868.381845633917;7068.71725130989;7527.360174385239;7625.542467451971;7703.730369437043;45935.77032114818;45976.77011614921;46284.53715462802;46394.53605463902;47107.764461177474;61084.69457652683;61748;61907.38092619016;62669.68665156645;62864.371356285854;9234.353595248536;9970.853351864629;10351.275410721177;10356.223283253636;10378.636747713794;10882.727931801768;10990.285631433931;10991.560337586727;11036.944815275872;11040.227184097035;10379.377237365783;10459.354092509022;10470.94764526203;10569.418681972258;10830.729231769268;10303.433311167957;10318.535665894902;10402.791944161227;10417.531211095367;10449.791004180026;9322.239196317763;10000.349977251455;10282.794344113225;10318.43248621303;10454.843177352193;8576.656933722581;8612.440191387539;8700.390972632109;8931.73204803857;9084.818303634023;7537.54774713519;8555.743327700178;8609.440386374865;8676.089010654043;8853.29173666118 10:08:52 INFO - PROCESS | 1671 | __end_tp_report 10:08:52 INFO - PROCESS | 1671 | __start_cc_report 10:08:52 INFO - PROCESS | 1671 | _x_x_mozilla_cycle_collect,412 10:08:52 INFO - PROCESS | 1671 | __end_cc_report 10:08:52 INFO - PROCESS | 1671 | __startTimestamp1463418532700__endTimestamp 10:08:52 INFO - PROCESS | 1671 | 10:08:52 INFO - PROCESS | 1671 | ------- Summary: start ------- 10:08:52 INFO - PROCESS | 1671 | Number of tests: 6 10:08:52 INFO - PROCESS | 1671 | 10:08:52 INFO - PROCESS | 1671 | [#0] dojo.html Cycles:165 Average:5731.26 Median:5977.82 stddev:798.46 (13.4%) stddev-sans-first:769.11 10:08:52 INFO - PROCESS | 1671 | Values: 2887.8 3425.9 3474.1 3507.4 3533.4 5768.5 5790.4 5828.4 5856.2 5867.4 5682.3 5701.5 5760.7 5784.3 5833.6 5185.8 5215.8 5316.7 5320.3 5354.1 202.6 5289.7 5308.5 5313.1 5377.1 5243.8 5256.6 5275.8 5283.7 5309.9 5153.9 5285.2 5343.4 5348.5 5375.0 5167.6 5200.5 5231.9 5232.3 5242.6 5649.9 5692.9 5770.6 5774.7 5804.2 5451.6 6213.7 6241.5 6333.3 6335.7 6250.8 6266.3 6276.1 6288.7 6293.2 5895.5 5914.3 5928.5 5946.5 5974.5 5462.6 6242.7 6260.1 6288.9 6299.6 5140.9 6257.5 6273.4 6286.2 6318.7 6272.3 6278.0 6279.5 6320.5 6326.5 6119.8 6195.4 6219.9 6233.7 6311.1 6109.5 6121.1 6139.0 6143.9 6275.7 4255.9 4908.0 4922.6 4926.5 4969.5 4173.8 4389.8 4420.4 4465.7 4473.3 5867.5 5945.4 5969.6 5975.0 5979.1 5195.3 6229.7 6266.6 6279.5 6294.1 5938.7 5977.8 5983.8 6005.5 6011.6 5458.3 5464.7 5466.2 5470.8 5481.5 6221.0 6361.9 6492.9 6512.1 6515.3 5589.7 6207.6 6255.5 6268.6 6302.4 6464.8 6475.0 6484.5 6485.3 6498.8 5424.7 6061.1 6194.9 6205.7 6274.5 6252.4 6255.4 6278.0 6283.3 6283.8 6140.4 6224.6 6242.5 6244.4 6278.1 6083.9 6100.3 6100.9 6115.1 6258.0 4403.5 5836.0 5900.8 5944.3 6041.3 6127.7 6166.4 6187.5 6190.2 6216.1 4904.7 5952.1 6005.1 6058.2 6105.4 10:08:52 INFO - PROCESS | 1671 | 10:08:52 INFO - PROCESS | 1671 | [#1] ext.html Cycles:180 Average:72892.91 Median:37062.82 stddev:170320.66 (459.5%) stddev-sans-first:170711.73 10:08:52 INFO - PROCESS | 1671 | Values: 501.8 539.8 542.2 558.9 569.8 39053.8 41520.0 41527.4 41538.8 41722.6 42550.6 42882.4 43265.1 43275.1 43372.1 9563.8 9661.4 9702.1 9723.7 9738.1 9654.0 9731.5 9743.8 9769.2 9772.3 8967.6 9701.7 9715.1 9730.7 9802.1 9757.0 9763.2 9769.3 9769.3 9778.3 9541.3 9582.8 9647.0 9672.6 9675.5 39546.8 39815.8 40056.2 40396.0 40541.2 2852.0 3073.2 3122.4 3412.9 3427.7 26978.5 27848.6 28558.3 29266.9 29272.1 2614.4 2637.3 2641.6 2720.7 2727.9 489471.0 491245.1 492251.0 496367.5 498370.0 34305.7 35214.8 35363.0 35381.8 36136.0 35543.5 35872.6 36052.5 36065.0 36107.9 15273.9 15446.7 15468.1 15528.5 15571.5 12635.4 12676.2 13068.0 13146.9 13159.6 7937.8 8199.8 8208.0 8218.6 8226.0 3442.2 3452.2 3455.9 3463.9 3491.9 1000.7 1031.2 1048.4 1076.8 1173.2 24442.9 24567.0 29368.7 30008.8 30805.2 998.6 1061.1 1090.7 1090.9 1164.2 14564.1 16195.2 16283.0 16506.0 16578.5 702240.0 714591.0 729371.0 729796.0 731042.0 60621.7 61755.4 62290.7 62408.4 62608.4 1491.2 1491.9 1498.3 1513.8 1517.6 26171.9 27320.3 29232.4 30118.5 30867.5 51574.5 52097.7 52142.0 52210.5 52275.5 659142.0 659596.0 662960.0 666044.0 666409.7 25537.9 25979.9 26093.2 26653.1 26689.7 44894.6 45550.0 46076.3 46251.0 46764.3 36334.5 37198.3 37819.2 38152.2 38389.2 36401.5 36799.1 36878.4 36945.6 37825.2 31329.4 36406.0 37180.0 37398.0 37620.1 28190.7 31372.0 31861.5 33070.8 33079.0 19407.9 24052.4 24355.4 24397.8 24631.4 10:08:52 INFO - PROCESS | 1671 | 10:08:52 INFO - PROCESS | 1671 | [#2] jquery.html Cycles:180 Average:35304.61 Median:22259.00 stddev:102464.72 (460.3%) stddev-sans-first:102718.49 10:08:52 INFO - PROCESS | 1671 | Values: 320.0 387.3 392.6 403.4 417.5 16267.9 19564.8 20606.3 22251.7 22266.3 20861.4 21197.0 21303.0 22194.0 22430.3 4856.5 5953.1 6205.9 6209.0 6241.8 5111.5 5615.9 5850.4 6030.0 6114.4 5834.4 5878.2 5998.6 6048.0 6062.2 5951.6 5952.9 6003.7 6039.5 6063.4 5955.1 5965.8 5972.0 5980.4 5997.3 13442.3 16778.8 17304.9 26231.9 26286.2 2099.9 2157.5 2223.6 2267.3 2341.0 8440.9 9016.8 10038.4 10198.6 10324.0 1753.7 1756.7 1772.2 1791.7 1831.9 92042.1 105394.8 109162.9 119174.0 124951.0 10939.5 11511.7 11716.2 11765.0 11849.3 13072.5 13973.6 14400.2 14784.3 14797.6 8618.4 8718.9 8762.2 8874.2 8967.9 8667.0 8685.7 8705.0 8723.1 8836.3 4554.6 4595.5 4623.8 4650.7 4709.7 1975.0 1995.0 1999.1 2028.9 2064.7 789.6 948.6 963.7 992.6 1003.7 14482.5 19029.4 19642.0 19942.9 20472.4 853.5 924.8 1000.5 1001.1 1023.6 9636.4 9732.7 10456.5 10551.0 10583.0 602607.0 606306.0 617943.0 619466.0 621831.0 131518.3 137479.0 139364.0 139409.0 141835.0 897.7 1017.3 1030.9 1031.6 1034.5 17719.8 17993.6 18124.0 18263.5 18412.4 48612.5 50787.0 52470.0 53878.0 53955.5 79295.6 79721.0 80374.6 81442.0 81725.0 6907.0 7082.0 7376.9 7438.9 7469.4 11954.8 12822.4 12855.4 13183.1 13222.9 10763.5 11120.9 11205.5 11346.9 11580.0 8506.4 10895.3 11046.4 11320.5 11489.1 11361.8 11500.8 11503.3 11592.2 11600.0 5789.0 6742.3 7368.2 7485.5 7762.8 10946.9 11062.4 11243.8 11270.1 11334.3 10:08:52 INFO - PROCESS | 1671 | 10:08:52 INFO - PROCESS | 1671 | [#3] mootools.html Cycles:180 Average:5757.21 Median:2618.45 stddev:3273.34 (125.0%) stddev-sans-first:3267.31 10:08:52 INFO - PROCESS | 1671 | Values: 1557.6 1600.6 1847.6 1950.1 1958.6 8008.8 8150.7 8877.4 9259.4 9281.5 7890.5 8902.6 9051.5 9067.5 9094.0 2073.6 2423.3 2467.3 2470.4 2480.8 2396.2 2424.9 2444.5 2446.6 2451.4 2379.1 2382.5 2401.7 2407.3 2414.9 2412.7 2424.8 2429.3 2431.6 2440.6 1973.7 2328.4 2370.0 2403.9 2415.8 7744.5 9008.8 9118.9 9139.2 9163.7 5029.4 5363.0 5418.6 5463.9 5589.4 3260.8 3391.7 3445.3 3464.7 3465.5 1051.6 1056.4 1065.8 1066.4 1071.8 11786.4 11865.9 11916.1 12048.4 12129.2 4618.9 4645.4 4744.0 4751.8 4760.5 8271.0 9442.9 9448.0 9449.3 9482.1 5165.0 5214.5 5234.9 5237.2 5244.2 4511.4 4855.3 4888.6 5037.3 5042.6 3889.6 3950.7 4002.5 4016.3 4042.9 1231.4 1234.5 1235.1 1244.0 1245.4 915.6 920.1 925.1 950.8 960.9 5251.4 5807.4 6067.2 6070.9 6106.6 424.8 446.5 452.1 457.3 476.4 4020.0 4056.0 4061.3 4130.5 4154.1 10789.4 11150.3 11185.2 11199.0 11238.6 10922.7 10978.9 11049.5 11073.9 11125.2 7660.0 7874.7 8475.7 8503.4 8583.6 5860.2 5911.5 5953.9 6090.5 6155.7 9805.3 10015.0 10210.2 10346.8 10376.7 10197.5 10288.6 10386.3 10523.4 10655.1 7126.9 7292.1 7309.3 7322.8 7331.4 8384.6 8417.8 8440.5 8460.7 8573.2 7485.9 7712.0 7763.8 7898.8 7924.8 7485.6 7506.2 7547.3 7605.2 7759.5 6504.9 6968.9 7176.9 7239.7 7295.3 4101.6 5031.2 5063.4 5071.7 5118.2 4362.3 5030.8 5038.7 5094.5 5152.9 10:08:52 INFO - PROCESS | 1671 | 10:08:52 INFO - PROCESS | 1671 | [#4] prototype.html Cycles:180 Average:4535.63 Median:4831.42 stddev:1429.61 (29.6%) stddev-sans-first:1396.69 10:08:52 INFO - PROCESS | 1671 | Values: 234.0 287.4 311.3 317.7 330.4 4660.6 4807.7 4849.7 5544.3 5622.0 4835.1 4918.8 5058.3 5157.8 5177.8 4146.8 4177.6 4186.5 4200.4 4205.2 3997.7 4008.2 4206.0 4231.2 4242.3 3968.9 4116.2 4139.8 4151.5 4154.7 4138.9 4177.9 4179.8 4181.0 4196.7 2999.5 3614.6 3659.1 3659.6 3664.3 4672.5 4905.5 5246.9 5265.4 5337.0 4320.0 4466.3 4697.6 4732.5 4743.7 4671.4 5074.7 5603.4 5732.1 5868.9 3318.0 3494.4 3871.4 3926.3 3970.2 6043.0 6045.6 6098.9 6103.0 6138.3 3701.0 3973.3 3979.5 3980.7 4010.5 3621.5 3795.8 4001.0 4027.8 4089.5 4350.8 4698.5 4713.6 4759.5 4766.0 5850.8 5896.8 5904.6 5909.6 5927.9 1113.4 1184.4 1356.8 1376.4 1390.6 762.4 820.7 831.6 836.6 853.4 4297.5 4542.4 4564.5 4679.9 4682.0 4389.2 4562.6 5102.5 5140.9 5150.8 3993.7 4198.9 4301.8 4614.6 4626.8 2318.6 2340.1 2369.0 2490.7 2500.7 4875.0 5187.8 5307.1 5351.2 5363.4 4565.6 5176.3 5244.6 5297.7 5305.6 5488.6 5554.9 6318.5 6360.3 6377.8 5113.0 5541.4 5896.3 6093.4 6136.6 5358.8 5670.3 5836.9 5939.4 6031.4 5657.5 6035.0 6309.3 6318.7 6360.4 4028.4 4051.2 4054.5 4058.6 4076.4 5075.6 5244.8 5462.7 5976.0 6113.3 5152.5 5715.7 5853.9 5889.5 5907.1 4673.0 5672.4 5872.5 5888.8 5896.5 5072.7 5113.9 5572.0 5660.4 5774.7 4705.4 5395.0 5891.2 5891.4 5906.0 4779.9 4827.7 5065.6 5793.8 5809.5 10:08:52 INFO - PROCESS | 1671 | 10:08:52 INFO - PROCESS | 1671 | [#5] yui.html Cycles:180 Average:17942.51 Median:598.92 stddev:25868.38 (4319.1%) stddev-sans-first:25908.46 10:08:52 INFO - PROCESS | 1671 | Values: 675.3 715.9 716.4 738.8 752.7 15127.2 15477.0 15637.0 15637.9 15665.1 14023.0 14056.2 14308.6 14359.4 14382.8 898.7 913.1 918.4 921.0 923.3 667.5 740.3 897.5 928.5 929.9 975.9 983.0 983.8 987.3 987.8 859.2 875.0 977.0 977.5 981.0 676.3 704.6 730.1 730.1 731.0 11699.4 13235.1 13789.5 13877.0 14868.3 12380.8 12492.1 12520.4 12584.6 12883.1 8309.6 10111.2 10137.6 10217.2 10677.3 3245.4 3449.9 3472.9 3557.2 3747.9 57852.0 79467.0 80880.0 83402.6 84346.0 9233.4 9351.0 9446.5 9518.2 10154.6 19437.7 19752.6 21628.5 21636.0 21638.7 7039.0 7150.5 7155.0 7159.7 7337.1 3949.5 4004.7 4022.3 4028.5 4089.2 3333.9 3424.8 3434.7 3438.2 3449.9 575.2 591.7 593.1 604.7 608.2 524.5 532.9 554.4 562.5 569.2 7976.7 8076.4 8167.0 8248.5 8284.3 559.4 566.3 583.9 589.2 590.6 3186.0 3862.7 3875.8 3977.7 4054.6 91623.0 92801.5 93641.0 93784.0 95912.0 81606.6 82407.6 83410.6 83787.6 84603.6 73472.0 73752.0 74011.9 74230.6 74983.0 6868.4 7068.7 7527.4 7625.5 7703.7 45935.8 45976.8 46284.5 46394.5 47107.8 61084.7 61748.0 61907.4 62669.7 62864.4 9234.4 9970.9 10351.3 10356.2 10378.6 10882.7 10990.3 10991.6 11036.9 11040.2 10379.4 10459.4 10470.9 10569.4 10830.7 10303.4 10318.5 10402.8 10417.5 10449.8 9322.2 10000.3 10282.8 10318.4 10454.8 8576.7 8612.4 8700.4 8931.7 9084.8 7537.5 8555.7 8609.4 8676.1 8853.3 10:08:52 INFO - PROCESS | 1671 | -------- Summary: end -------- 10:08:52 INFO - PROCESS | 1671 | 10:08:53 INFO - TEST-INFO | 1671: exit 0 10:08:53 INFO - TEST-OK | dromaeo_css | took 1112000ms 10:08:53 INFO - TEST-START | kraken 10:08:53 INFO - operating with platform_type : mac_ 10:08:53 INFO - Initialising browser for kraken test... 10:08:53 INFO - TEST-INFO | started process 1678 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/tmpohuY5K/profile http://localhost:49277/getInfo.html) 10:08:56 INFO - PROCESS | 1678 | __metrics Screen width/height:1600/1200 10:08:56 INFO - PROCESS | 1678 | colorDepth:24 10:08:56 INFO - PROCESS | 1678 | Browser inner width/height: 1024/689 10:08:56 INFO - PROCESS | 1678 | __metrics 10:08:56 ERROR - PROCESS | 1678 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 10:08:57 INFO - TEST-INFO | 1678: exit 0 10:08:57 INFO - Browser initialized. 10:08:57 INFO - Running cycle 1/1 for kraken test... 10:08:57 INFO - TEST-INFO | started process 1681 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/tmpohuY5K/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:09:09 INFO - PROCESS | 1681 | RSS: Main: 249864192 10:09:09 INFO - PROCESS | 1681 | 10:09:11 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection) 10:09:12 INFO - PROCESS | 1681 | RSS: Main: 261570560 10:09:12 INFO - PROCESS | 1681 | 10:09:15 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-dft) 10:09:16 INFO - PROCESS | 1681 | RSS: Main: 266293248 10:09:16 INFO - PROCESS | 1681 | 10:09:19 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-fft) 10:09:20 INFO - PROCESS | 1681 | RSS: Main: 267120640 10:09:20 INFO - PROCESS | 1681 | 10:09:22 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator) 10:09:23 INFO - PROCESS | 1681 | RSS: Main: 267759616 10:09:23 INFO - PROCESS | 1681 | 10:09:25 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:09:26 INFO - PROCESS | 1681 | RSS: Main: 267763712 10:09:26 INFO - PROCESS | 1681 | 10:09:28 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom) 10:09:29 INFO - PROCESS | 1681 | RSS: Main: 268238848 10:09:29 INFO - PROCESS | 1681 | 10:09:32 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate) 10:09:33 INFO - PROCESS | 1681 | RSS: Main: 268800000 10:09:33 INFO - PROCESS | 1681 | 10:09:36 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial) 10:09:37 INFO - PROCESS | 1681 | RSS: Main: 268570624 10:09:37 INFO - PROCESS | 1681 | 10:09:38 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:09:39 INFO - PROCESS | 1681 | RSS: Main: 267911168 10:09:39 INFO - PROCESS | 1681 | 10:09:41 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:09:42 INFO - PROCESS | 1681 | RSS: Main: 268677120 10:09:42 INFO - PROCESS | 1681 | 10:09:44 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:09:45 INFO - PROCESS | 1681 | RSS: Main: 268357632 10:09:45 INFO - PROCESS | 1681 | 10:09:48 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:09:48 INFO - PROCESS | 1681 | RSS: Main: 268414976 10:09:48 INFO - PROCESS | 1681 | 10:09:51 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:09:52 INFO - PROCESS | 1681 | RSS: Main: 268439552 10:09:52 INFO - PROCESS | 1681 | 10:09:54 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49277/tests/kraken/driver.html?testName=ai-astar) 10:09:54 INFO - PROCESS | 1681 | RSS: Main: 270352384 10:09:54 INFO - PROCESS | 1681 | 10:09:54 INFO - PROCESS | 1681 | __start_tp_report 10:09:54 INFO - PROCESS | 1681 | _x_x_mozilla_page_load 10:09:54 INFO - PROCESS | 1681 | _x_x_mozilla_page_load_details 10:09:54 INFO - PROCESS | 1681 | |i|pagename|runs| 10:09:54 INFO - PROCESS | 1681 | |0;ai-astar;92;139;165;171;115;114;106;114;113;116 10:09:54 INFO - PROCESS | 1681 | |1;audio-beat-detection;158;113;179;248;130;137;144;155;175;127 10:09:54 INFO - PROCESS | 1681 | |2;audio-dft;196;202;206;267;277;197;275;269;199;193 10:09:54 INFO - PROCESS | 1681 | |3;audio-fft;67;80;74;169;66;57;59;68;119;99 10:09:54 INFO - PROCESS | 1681 | |4;audio-oscillator;82;110;178;79;82;81;120;82;82;120 10:09:54 INFO - PROCESS | 1681 | |5;imaging-gaussian-blur;88;86;89;106;87;85;88;86;89;84 10:09:54 INFO - PROCESS | 1681 | |6;imaging-darkroom;137;151;96;95;95;95;96;94;94;95 10:09:54 INFO - PROCESS | 1681 | |7;imaging-desaturate;144;139;89;119;152;93;92;91;93;150 10:09:54 INFO - PROCESS | 1681 | |8;json-parse-financial;56;60;60;57;59;61;59;122;62;57 10:09:54 INFO - PROCESS | 1681 | |9;json-stringify-tinderbox;64;67;67;74;79;71;67;65;67;66 10:09:54 INFO - PROCESS | 1681 | |10;stanford-crypto-aes;86;87;87;89;107;86;87;83;88;89 10:09:54 INFO - PROCESS | 1681 | |11;stanford-crypto-ccm;136;136;139;149;154;153;141;174;235;148 10:09:54 INFO - PROCESS | 1681 | |12;stanford-crypto-pbkdf2;164;167;166;164;169;167;160;164;165;161 10:09:54 INFO - PROCESS | 1681 | |13;stanford-crypto-sha256-iterative;67;67;66;62;68;69;82;66;72;78 10:09:54 INFO - PROCESS | 1681 | __end_tp_report 10:09:54 INFO - PROCESS | 1681 | __start_cc_report 10:09:54 INFO - PROCESS | 1681 | _x_x_mozilla_cycle_collect,1075 10:09:54 INFO - PROCESS | 1681 | __end_cc_report 10:09:54 INFO - PROCESS | 1681 | __startTimestamp1463418594339__endTimestamp 10:09:54 INFO - PROCESS | 1681 | 10:09:54 INFO - PROCESS | 1681 | ------- Summary: start ------- 10:09:54 INFO - PROCESS | 1681 | Number of tests: 14 10:09:54 INFO - PROCESS | 1681 | 10:09:54 INFO - PROCESS | 1681 | [#0] ai-astar Cycles:10 Average:124.50 Median:127.50 stddev:25.66 (20.1%) stddev-sans-first:24.37 10:09:54 INFO - PROCESS | 1681 | Values: 92.0 139.0 165.0 171.0 115.0 114.0 106.0 114.0 113.0 116.0 10:09:54 INFO - PROCESS | 1681 | 10:09:54 INFO - PROCESS | 1681 | [#1] audio-beat-detection Cycles:10 Average:156.60 Median:156.50 stddev:38.34 (24.5%) stddev-sans-first:40.66 10:09:54 INFO - PROCESS | 1681 | Values: 158.0 113.0 179.0 248.0 130.0 137.0 144.0 155.0 175.0 127.0 10:09:54 INFO - PROCESS | 1681 | 10:09:54 INFO - PROCESS | 1681 | [#2] audio-dft Cycles:10 Average:228.10 Median:236.50 stddev:38.04 (16.1%) stddev-sans-first:38.53 10:09:54 INFO - PROCESS | 1681 | Values: 196.0 202.0 206.0 267.0 277.0 197.0 275.0 269.0 199.0 193.0 10:09:54 INFO - PROCESS | 1681 | 10:09:54 INFO - PROCESS | 1681 | [#3] audio-fft Cycles:10 Average:85.80 Median:67.50 stddev:34.93 (51.7%) stddev-sans-first:36.38 10:09:54 INFO - PROCESS | 1681 | Values: 67.0 80.0 74.0 169.0 66.0 57.0 59.0 68.0 119.0 99.0 10:09:54 INFO - PROCESS | 1681 | 10:09:54 INFO - PROCESS | 1681 | [#4] audio-oscillator Cycles:10 Average:101.60 Median:81.50 stddev:31.72 (38.9%) stddev-sans-first:32.84 10:09:54 INFO - PROCESS | 1681 | Values: 82.0 110.0 178.0 79.0 82.0 81.0 120.0 82.0 82.0 120.0 10:09:54 INFO - PROCESS | 1681 | 10:09:54 INFO - PROCESS | 1681 | [#5] imaging-gaussian-blur Cycles:10 Average:88.80 Median:87.50 stddev:6.27 (7.2%) stddev-sans-first:6.64 10:09:54 INFO - PROCESS | 1681 | Values: 88.0 86.0 89.0 106.0 87.0 85.0 88.0 86.0 89.0 84.0 10:09:54 INFO - PROCESS | 1681 | 10:09:54 INFO - PROCESS | 1681 | [#6] imaging-darkroom Cycles:10 Average:104.80 Median:95.00 stddev:20.93 (22.0%) stddev-sans-first:18.68 10:09:54 INFO - PROCESS | 1681 | Values: 137.0 151.0 96.0 95.0 95.0 95.0 96.0 94.0 94.0 95.0 10:09:54 INFO - PROCESS | 1681 | 10:09:54 INFO - PROCESS | 1681 | [#7] imaging-desaturate Cycles:10 Average:116.20 Median:90.00 stddev:27.41 (30.5%) stddev-sans-first:27.16 10:09:54 INFO - PROCESS | 1681 | Values: 144.0 139.0 89.0 119.0 152.0 93.0 92.0 91.0 93.0 150.0 10:09:54 INFO - PROCESS | 1681 | 10:09:54 INFO - PROCESS | 1681 | [#8] json-parse-financial Cycles:10 Average:65.30 Median:59.50 stddev:20.01 (33.6%) stddev-sans-first:20.94 10:09:54 INFO - PROCESS | 1681 | Values: 56.0 60.0 60.0 57.0 59.0 61.0 59.0 122.0 62.0 57.0 10:09:54 INFO - PROCESS | 1681 | 10:09:54 INFO - PROCESS | 1681 | [#9] json-stringify-tinderbox Cycles:10 Average:68.70 Median:67.00 stddev:4.64 (6.9%) stddev-sans-first:4.60 10:09:54 INFO - PROCESS | 1681 | Values: 64.0 67.0 67.0 74.0 79.0 71.0 67.0 65.0 67.0 66.0 10:09:54 INFO - PROCESS | 1681 | 10:09:54 INFO - PROCESS | 1681 | [#10] stanford-crypto-aes Cycles:10 Average:88.90 Median:87.00 stddev:6.59 (7.6%) stddev-sans-first:6.91 10:09:54 INFO - PROCESS | 1681 | Values: 86.0 87.0 87.0 89.0 107.0 86.0 87.0 83.0 88.0 89.0 10:09:54 INFO - PROCESS | 1681 | 10:09:54 INFO - PROCESS | 1681 | [#11] stanford-crypto-ccm Cycles:10 Average:156.50 Median:151.00 stddev:29.82 (19.7%) stddev-sans-first:30.69 10:09:54 INFO - PROCESS | 1681 | Values: 136.0 136.0 139.0 149.0 154.0 153.0 141.0 174.0 235.0 148.0 10:09:54 INFO - PROCESS | 1681 | 10:09:54 INFO - PROCESS | 1681 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.70 Median:165.50 stddev:2.75 (1.7%) stddev-sans-first:2.91 10:09:54 INFO - PROCESS | 1681 | Values: 164.0 167.0 166.0 164.0 169.0 167.0 160.0 164.0 165.0 161.0 10:09:54 INFO - PROCESS | 1681 | 10:09:54 INFO - PROCESS | 1681 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.70 Median:68.50 stddev:6.06 (8.8%) stddev-sans-first:6.34 10:09:54 INFO - PROCESS | 1681 | Values: 67.0 67.0 66.0 62.0 68.0 69.0 82.0 66.0 72.0 78.0 10:09:54 INFO - PROCESS | 1681 | -------- Summary: end -------- 10:09:54 INFO - PROCESS | 1681 | 10:09:55 INFO - TEST-INFO | 1681: exit 0 10:09:55 INFO - TEST-OK | kraken | took 61850ms 10:09:55 INFO - SUITE-END | took 1173s 10:09:55 INFO - Completed test suite (00:19:34) 10:09:55 INFO - javascript benchmark 10:09:55 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7048.4199063873275, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2887.812292201008, 3425.862965481381, 3474.114100904269, 3507.4212754895443, 3533.4169861972778, 5768.451997060283, 5790.39200883907, 5828.446297601731, 5856.238688970433, 5867.413258674141, 5682.346530149034, 5701.543876489881, 5760.654360738358, 5784.2769653793275, 5833.562482813784, 5185.766640501177, 5215.765290561933, 5316.654417462872, 5320.308359913206, 5354.116570765819, 202.58762773321598, 5289.68261904285, 5308.522232999029, 5313.123334649778, 5377.059014672417, 5243.8164664236565, 5256.579473642099, 5275.841724748245, 5283.682979021271, 5309.867253318698, 5153.871153221162, 5285.233641122016, 5343.385510666284, 5348.545373643245, 5374.973125134388, 5167.560757335631, 5200.453952334989, 5231.947680523184, 5232.3459567554055, 5242.55438287746, 5649.887002259973, 5692.857678558028, 5770.567207459415, 5774.740136693859, 5804.187413762034, 5451.618386712932, 6213.689315534205, 6241.500679945593, 6333.334999825044, 6335.68321583919, 6250.843728906768, 6266.310705822356, 6276.058591211309, 6288.717007734663, 6293.181886354791, 5895.498882594985, 5914.319853216849, 5928.472767382459, 5946.524278057745, 5974.4622983930785, 5462.562994960394, 6242.687865606701, 6260.060990851318, 6288.9056664150075, 6299.590526615755, 5140.948590514047, 6257.499400047986, 6273.37266273369, 6286.214223222098, 6318.7472501100365, 6272.311315353143, 6278.0, 6279.466245369194, 6320.462760665395, 6326.5255105867245, 6119.837693858789, 6195.411435913581, 6219.937800621936, 6233.688315584293, 6311.053341998737, 6109.526129009804, 6121.148138557471, 6139.009643731611, 6143.880361275353, 6275.6862156891975, 4255.936160957588, 4908.0, 4922.556969872657, 4926.531979461945, 4969.527894849984, 4173.83304667816, 4389.846355377546, 4420.425344705232, 4465.732056076646, 4473.306637471197, 5867.471927526543, 5945.40545945402, 5969.552283578749, 5974.970125149346, 5979.073243647242, 5195.3246078009615, 6229.719662615194, 6266.592671476339, 6279.497640188774, 6294.0558916162945, 5938.703064846741, 5977.820665380045, 5983.790567330123, 6005.489533389624, 6011.609245399035, 5458.344998600193, 5464.726763661801, 5466.180072989084, 5470.753816078286, 5481.451854814487, 6220.968895155585, 6361.8727625448155, 6492.870142597216, 6512.088307636935, 6515.315891831289, 5589.748461319332, 6207.596506227081, 6255.530835187288, 6268.561200715906, 6302.369763023661, 6464.838379040561, 6475.0, 6484.513661475314, 6485.286618472059, 6498.772542960974, 5424.701641409761, 6061.060535616986, 6194.938050619436, 6205.720742566505, 6274.498040156685, 6252.374762523711, 6255.40573645503, 6278.0, 6283.308836027942, 6283.780067697791, 6140.3552626975315, 6224.564280500503, 6242.500599951902, 6244.4380005797875, 6278.058291256166, 6083.939160608337, 6100.267967843887, 6100.853682747703, 6115.084638624664, 6257.9687101564205, 4403.471583409883, 5836.037053886062, 5900.793472228623, 5944.286685597756, 6041.305249896318, 6127.6936153193055, 6166.383361663798, 6187.504999600112, 6190.164327815867, 6216.129741836237, 4904.730239836843, 5952.077427998667, 6005.069214271794, 6058.182145410313, 6105.389461053859], "value": 5683.549848514311, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [501.78172494964696, 539.8110661268554, 542.1921337207561, 558.9105743081108, 569.8432930943991, 39053.82838514845, 41520.0, 41527.37708934368, 41538.79230603843, 41722.582774172246, 42550.574494255045, 42882.35676464855, 43265.134697306035, 43275.134497310035, 43372.132557348836, 9563.80872382552, 9661.4203147811, 9702.078302561245, 9723.708288751348, 9738.1235688788, 9654.0, 9731.464769437678, 9743.805123897519, 9769.169620582259, 9772.31593788435, 8967.641294348217, 9701.660441884536, 9715.077067678594, 9730.65942692006, 9802.068803463659, 9757.0, 9763.170130538912, 9769.267304952085, 9769.267304952122, 9778.315517913748, 9541.33210675253, 9582.808343833085, 9646.987066358002, 9672.613095476243, 9675.467849268287, 39546.80226598877, 39815.80092099521, 40056.19887602232, 40396.0, 40541.189176216605, 2851.9714802852122, 3073.2470544716466, 3122.406742718876, 3412.897613071612, 3427.674370934757, 26978.460430791274, 27848.582271265946, 28558.286042849097, 29266.85366573175, 29272.121836344944, 2614.359481926921, 2637.2879322582794, 2641.5773476243708, 2720.687120981093, 2727.863606819671, 489471.0, 491245.08754912706, 492251.0, 496367.5181624105, 498370.0, 34305.656943430746, 35214.82392588072, 35363.0, 35381.82309088438, 36136.0, 35543.466847997304, 35872.641273587455, 36052.459213111826, 36065.0, 36107.91676249717, 15273.923630382, 15446.691066178613, 15468.149251791034, 15528.5341439757, 15571.454999074978, 12635.368231588383, 12676.176048556814, 13068.0, 13146.948244140445, 13159.60521184366, 7937.761867143995, 8199.754007379788, 8208.0, 8218.63016164262, 8226.0, 3442.1566716154202, 3452.205992621711, 3455.87904423349, 3463.9480407793903, 3491.94762078569, 1000.6647772996128, 1031.1956673794452, 1048.4261138903973, 1076.7792602516465, 1173.2198088271375, 24442.900069496558, 24567.017319307386, 29368.678409471624, 30008.84995575008, 30805.22986925345, 998.6155330197926, 1061.1139698351963, 1090.7000574841823, 1090.8581884355142, 1164.1676201515957, 14564.053336533303, 16195.19024048793, 16283.0, 16506.0, 16578.50264492067, 702240.0, 714591.0, 729371.0, 729796.0, 731042.0, 60621.69689151526, 61755.38244617496, 62290.68854655698, 62408.37591624026, 62608.37391626117, 1491.1798510819006, 1491.940322387114, 1498.2658497336226, 1513.7502312118377, 1517.6281810956386, 26171.869140654177, 27320.316992075357, 29232.415351692423, 30118.54822177625, 30867.536986944764, 51574.484255158466, 52097.73951130372, 52142.0, 52210.47789522056, 52275.47724522706, 659142.0, 659596.0, 662960.0, 666044.0, 666409.6679516571, 25537.87231063833, 25979.870100649376, 26093.217203483924, 26653.067142649914, 26689.733102668724, 44894.551054489035, 45550.0, 46076.30885536653, 46251.0, 46764.29853552132, 36334.454983175805, 37198.326075326135, 37819.243615126994, 38152.236955261294, 38389.232215356096, 36401.453978190875, 36799.08002299964, 36878.446823297134, 36945.6305436953, 37825.24349512939, 31329.373412531168, 36406.0, 37180.0, 37398.0, 37620.05949851275, 28190.71809281881, 31371.96082254646, 31861.522077168396, 33070.83464582662, 33079.0, 19407.902960485106, 24052.39869003289, 24355.39111522155, 24397.75602244026, 24631.38421539476], "value": 18509.09227487923, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [319.9584054072971, 387.32991923971537, 392.5564112552813, 403.435190732974, 417.48857649379516, 16267.91866040671, 19564.804351956474, 20606.278780242694, 22251.66622500662, 22266.332010039703, 20861.37415877526, 21197.0, 21303.0, 22194.0, 22430.32709018728, 4856.490068542787, 5953.136795164699, 6205.9069113963105, 6208.968955155218, 6241.843953901143, 5111.51440613143, 5615.943840561583, 5850.3564607893095, 6030.035194368902, 6114.388561143895, 5834.4165583441745, 5878.206442130322, 5998.580099393044, 6047.971844786375, 6062.242219722535, 5951.553633477506, 5952.851178720523, 6003.699815009254, 6039.547033972448, 6063.363346848583, 5955.076963070731, 5965.791197308074, 5972.014617588093, 5980.372060933583, 5997.310309314441, 13442.32788360578, 16778.832211677847, 17304.913475432542, 26231.868840655865, 26286.21141365762, 2099.8635088719184, 2157.503774131943, 2223.6108680980765, 2267.2744721689064, 2340.976590234093, 8440.915590844075, 9016.77458063547, 10038.397696138256, 10198.592056317739, 10324.0, 1753.6580366828473, 1756.666233415647, 1772.2290803500518, 1791.7491551182845, 1831.9358822441284, 92042.07957920402, 105394.83815485548, 109162.90837091606, 119174.0, 124951.0, 10939.507722152523, 11511.654650360502, 11716.17986740937, 11765.0, 11849.289042657296, 13072.477100915858, 13973.580792576238, 14400.207988560729, 14784.334704938303, 14797.630059248604, 8618.439801412887, 8718.912810871938, 8762.167594078552, 8874.245689116367, 8967.910320896837, 8667.0, 8685.696000640073, 8704.956475217583, 8723.127687231226, 8836.293096552261, 4554.610843692705, 4595.540445955445, 4623.838165664253, 4650.674452788272, 4709.7174169549935, 1974.9901250493942, 1994.9900250498654, 1999.1403696410682, 2028.9043916285336, 2064.741907261592, 789.5657388436451, 948.606328373731, 963.7012526116927, 992.5781542844406, 1003.6838395905266, 14482.493112741004, 19029.429117126507, 19642.0, 19942.90028549848, 20472.385828425173, 853.4836423963537, 924.8104138651695, 1000.4997501249376, 1001.1190152665753, 1023.6366090038078, 9636.42181469114, 9732.70801875945, 10456.477176141163, 10551.0, 10582.994615511514, 602607.0, 606306.0, 617943.0, 619466.0, 621831.0, 131518.34240828926, 137479.0, 139364.0, 139409.0, 141835.0, 897.7441429192648, 1017.2675673515205, 1030.9123724483352, 1031.5873650539695, 1034.4793154063514, 17719.822801771817, 17993.640127197123, 18124.0, 18263.45209643713, 18412.371546243074, 48612.51387486221, 50786.98426031532, 52470.0, 53878.0, 53955.460445395045, 79295.60352198202, 79721.0, 80374.59812701133, 81442.0, 81725.0, 6906.965465172642, 7081.964590177016, 7376.889346659912, 7438.851222975619, 7469.402447804271, 11954.820677689668, 12822.358882055672, 12855.357232138544, 13183.14309569875, 13222.867771322164, 10763.51564179598, 11120.944395277971, 11205.495752691311, 11346.94326528362, 11580.0, 8506.362022848187, 10895.346279223271, 11046.392448415414, 11320.547178112784, 11489.138314626603, 11361.772764544829, 11500.82748758786, 11503.309801411942, 11592.188546801643, 11600.0, 5788.971055144697, 6742.325767423218, 7368.226336234832, 7485.513441626277, 7762.805929851799, 10946.890531094905, 11062.391568463812, 11243.7751244973, 11270.098392128773, 11334.319940803578], "value": 9454.103309757584, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1557.6417423992482, 1600.575847400439, 1847.5750577367205, 1950.0932066589025, 1958.5984873101015, 8008.758642410436, 8150.673973041086, 8877.378583499158, 9259.39813912096, 9281.535923203837, 7890.526568405885, 8902.554872256394, 9051.502167380791, 9067.546622668857, 9094.045125261851, 2073.5956488484753, 2423.309356833303, 2467.284487498623, 2470.4318006858343, 2480.7519248075228, 2396.209250947183, 2424.9272521824287, 2444.498877730061, 2446.620773780067, 2451.3871532116973, 2379.1078345620394, 2382.5473160099614, 2401.675773770536, 2407.277816655005, 2414.915477958272, 2412.686350774397, 2424.806015518754, 2429.344077099191, 2431.5623187826186, 2440.5973014452593, 1973.6743437332823, 2328.3946173994714, 2369.957218823712, 2403.9399015024587, 2415.842970206931, 7744.457887947846, 9008.77478063047, 9118.86321705175, 9139.177474027303, 9163.725088247364, 5029.3964724232965, 5362.981033603603, 5418.593605479566, 5463.945360546383, 5589.385167631557, 3260.836958152083, 3391.7286617070577, 3445.328161008604, 3464.688178063961, 3465.462853257749, 1051.621416290135, 1056.392574269798, 1065.7921705267477, 1066.4347895615335, 1071.8285074388139, 11786.35175065371, 11865.940670296593, 11916.106292028133, 12048.397580120958, 12129.21160124589, 4618.861434156981, 4645.419322584677, 4743.976280118577, 4751.83368582098, 4760.476347601761, 8270.958645206856, 9442.858357124649, 9448.007959164326, 9449.291303152153, 9482.099200575934, 5164.9741751291, 5214.45248248936, 5234.869128271747, 5237.162054071407, 5244.213367994755, 4511.368408422823, 4855.32025516428, 4888.560029597282, 5037.29477873098, 5042.596592272609, 3889.649931506178, 3950.6641935435237, 4002.519697636244, 4016.2971479990883, 4042.8584999524874, 1231.3596929596738, 1234.5432190089725, 1235.0613533714443, 1243.9675069692314, 1245.3773113443278, 915.6428992692855, 920.1396694091046, 925.121134921827, 950.77212862332, 960.8798900137483, 5251.422343542284, 5807.448292412214, 6067.150598916155, 6070.908936365958, 6106.572539922163, 424.7854833309159, 446.475391415086, 452.1228816096762, 457.2683706070261, 476.41401076675885, 4020.0150963013666, 4055.959440405617, 4061.3095773718537, 4130.545639979599, 4154.148399578079, 10789.406582637874, 11150.330980141218, 11185.217034807485, 11199.0, 11238.550457981753, 10922.72693182661, 10978.890211097947, 11049.502772375263, 11073.889261107286, 11125.221234513669, 7660.0, 7874.72438464651, 8475.660973560989, 8503.362247831437, 8583.570821458901, 5860.238169037998, 5911.497522710446, 5953.880922381615, 6090.512758979183, 6155.738395996637, 9805.2646051545, 10014.997000600055, 10210.234232432746, 10346.844797328196, 10376.68869933873, 10197.541110650187, 10288.588456461957, 10386.272960892666, 10523.421211833424, 10655.094316983143, 7126.928730712627, 7292.124945006633, 7309.342159205697, 7322.816929576803, 7331.3768329692575, 8384.58077096155, 8417.789555261168, 8440.493570385552, 8460.70387536458, 8573.22840944318, 7485.850282994201, 7712.0, 7763.805904852424, 7898.802529936797, 7924.762257132295, 7485.625718714152, 7506.160990557349, 7547.320741133105, 7605.201453847267, 7759.495632783851, 6504.934950650433, 6968.930310697031, 7176.928230717626, 7239.674214660458, 7295.343419092307, 4101.630853223224, 5031.169856973558, 5063.443021267584, 5071.721055341992, 5118.155504341742, 4362.258416069212, 5030.773615187399, 5038.722870242025, 5094.51602097815, 5152.871178220575], "value": 4544.831339213606, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [233.9742628310886, 287.37495946316756, 311.3197663106112, 317.71564449817413, 330.4267096587419, 4660.627149828014, 4807.701920481472, 4849.660523763338, 5544.279243698318, 5621.971890140549, 4835.107738270691, 4918.778654960526, 5058.317127187828, 5157.793688252475, 5177.818776342829, 4146.834126634931, 4177.624013838747, 4186.518550366701, 4200.390943313218, 4205.243056249874, 3997.7401468904573, 4008.1983603279314, 4205.957940420602, 4231.238377092138, 4242.342436922282, 3968.9007774805573, 4116.176764647068, 4139.77231252281, 4151.522574903895, 4154.667626589866, 4138.917221655565, 4177.9373309400235, 4179.811908464127, 4181.038361176916, 4196.664266858675, 2999.460097182506, 3614.5843228028853, 3659.1218107654236, 3659.597444281127, 3664.285464334456, 4672.486026537078, 4905.5283414975365, 5246.868828279324, 5265.447128051538, 5337.0, 4320.006398528325, 4466.307722303049, 4697.600703940169, 4732.52674732524, 4743.7429081293485, 4671.43942726875, 5074.670146440507, 5603.439656034405, 5732.111522713986, 5868.853278668024, 3318.0377690469736, 3494.3966744775307, 3871.399933010388, 3926.2736393767245, 3970.2258059678084, 6043.0331147016705, 6045.637261764265, 6098.908516372258, 6102.96948515259, 6138.294096178952, 3701.03773019017, 3973.2848087344555, 3979.5025621797276, 3980.7412518186225, 4010.4760191127184, 3621.492990981265, 3795.772253664789, 4000.9597504649, 4027.81874815634, 4089.488813898263, 4350.782460876943, 4698.483166851644, 4713.633046416569, 4759.524047595282, 4766.0, 5850.795222167204, 5896.823095307148, 5904.61619994699, 5909.645421274738, 5927.911081333784, 1113.4154568851418, 1184.4314728930162, 1356.796480527909, 1376.3806287170812, 1390.603677951779, 762.4015148108818, 820.6758330459435, 831.584207896052, 836.6235194162651, 853.4452605806275, 4297.505786834491, 4542.410156445218, 4564.543545645475, 4679.947011922358, 4682.0, 4389.200427824577, 4562.612177964844, 5102.5302794099625, 5140.946106048251, 5150.836648607594, 3993.7004724645185, 4198.908283846222, 4301.849435269751, 4614.607758340511, 4626.814927402934, 2318.6174281243743, 2340.1224540797202, 2369.0, 2490.6762120924163, 2500.6871392519074, 4874.953154278576, 5187.844364669066, 5307.12579816736, 5351.234092749383, 5363.436839131913, 4565.644251475163, 5176.327077479904, 5244.564810049424, 5297.708626025528, 5305.602079844027, 5488.615796894179, 5554.91667624986, 6318.526110541636, 6360.268569114519, 6377.831537277348, 5112.97443512795, 5541.3904470509015, 5896.321922978912, 6093.360197179232, 6136.64993701385, 5358.839234822962, 5670.3272534601565, 5836.941630583639, 5939.406059394026, 6031.396860314109, 5657.500762297912, 6035.0, 6309.274433440213, 6318.704665543461, 6360.363963603602, 4028.3554631258858, 4051.2100140472326, 4054.4729185205733, 4058.553559108555, 4076.4089207064217, 5075.644704870623, 5244.790208391774, 5462.699551524545, 5975.970120149372, 6113.2969708482315, 5152.505773325694, 5715.714214289185, 5853.8536536585225, 5889.52883769306, 5907.143464197753, 4673.0, 5672.432756724294, 5872.5301975842685, 5888.823335299948, 5896.528277737685, 5072.67027643202, 5113.8977220454635, 5572.0, 5660.443378071063, 5774.740136693775, 4705.411823522059, 5394.973025134849, 5891.23413956183, 5891.440313170242, 5906.0, 4779.944623811794, 4827.734474603931, 5065.581637141606, 5793.841231753582, 5809.50619197373], "value": 4069.2430450130014, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [675.3044364304766, 715.8961950517172, 716.3695947566141, 738.830069084111, 752.6650640464992, 15127.243637818106, 15477.0, 15637.0, 15637.905346625741, 15665.13841738704, 14023.018388712795, 14056.156630602145, 14308.642283942907, 14359.425622975068, 14382.784258236108, 898.6854600889685, 913.118840319092, 918.4443411735904, 920.9631614735403, 923.3490389275578, 667.5260564998856, 740.2708332292688, 897.5467388968594, 928.5171710710426, 929.9349045566813, 975.8682577852005, 982.9950850245739, 983.8179936711696, 987.3088837813523, 987.8024395120968, 859.2009431228954, 874.9737507874773, 976.9853452198223, 977.5161295158871, 980.9852852207223, 676.2527407215055, 704.5983789240136, 730.0983285642228, 730.1238513783428, 731.016782427634, 11699.415029248503, 13235.073544851864, 13789.51736689211, 13877.0, 14868.330925108396, 12380.752384952251, 12492.125551211418, 12520.373981300989, 12584.559540416132, 12883.098183127186, 8309.626066827008, 10111.24165687569, 10137.594496220143, 10217.233707471969, 10677.305975111592, 3245.350929814023, 3449.8792542261153, 3472.878449254289, 3557.1640664443835, 3747.887563373076, 57852.0, 79467.0, 80880.0, 83402.58298708468, 84346.0, 9233.430316846152, 9351.0, 9446.527673616429, 9518.23854091685, 10154.593816247267, 19437.708434373497, 19752.617316787688, 21628.45928851791, 21636.0, 21638.675419868716, 7039.049728286615, 7150.5352152109945, 7154.962530433162, 7159.749408770668, 7337.119545654449, 3949.5260568731937, 4004.6596039336982, 4022.3162062449455, 4028.529586700833, 4089.2230476209425, 3333.916652083669, 3424.828758562062, 3434.7252219822353, 3438.209211881231, 3449.913752156216, 575.23493753308, 591.6864062047122, 593.1458699472745, 604.703695189354, 608.2032537376051, 524.4964833759557, 532.9200619907045, 554.3957086775434, 562.4993755557557, 569.2343797592232, 7976.680932762624, 8076.3942704297415, 8167.0, 8248.46384984974, 8284.337253019865, 559.4098226371217, 566.2921348314607, 583.8948989181988, 589.1604463639383, 590.5541316306247, 3186.0, 3862.7102967277565, 3875.767453952772, 3977.661898738582, 4054.5991464526064, 91623.0, 92801.53599232096, 93641.0, 93784.0, 95912.0, 81606.59196703979, 82407.587962061, 83410.58294708487, 83787.5810620943, 84603.57698211593, 73472.0, 73752.0, 74011.88982165272, 74230.6288468565, 74983.0, 6868.381845633917, 7068.71725130989, 7527.360174385239, 7625.542467451971, 7703.730369437043, 45935.77032114818, 45976.77011614921, 46284.53715462802, 46394.53605463902, 47107.764461177474, 61084.69457652683, 61748.0, 61907.38092619016, 62669.68665156645, 62864.371356285854, 9234.353595248536, 9970.853351864629, 10351.275410721177, 10356.223283253636, 10378.636747713794, 10882.727931801768, 10990.285631433931, 10991.560337586727, 11036.944815275872, 11040.227184097035, 10379.377237365783, 10459.354092509022, 10470.94764526203, 10569.418681972258, 10830.729231769268, 10303.433311167957, 10318.535665894902, 10402.791944161227, 10417.531211095367, 10449.791004180026, 9322.239196317763, 10000.349977251455, 10282.794344113225, 10318.43248621303, 10454.843177352193, 8576.656933722581, 8612.440191387539, 8700.390972632109, 8931.73204803857, 9084.818303634023, 7537.54774713519, 8555.743327700178, 8609.440386374865, 8676.089010654043, 8853.29173666118], "value": 6665.773196626004, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1620.2000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 139.0, 165.0, 171.0, 115.0, 114.0, 106.0, 114.0, 113.0, 116.0], "value": 124.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 113.0, 179.0, 248.0, 130.0, 137.0, 144.0, 155.0, 175.0, 127.0], "value": 156.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 202.0, 206.0, 267.0, 277.0, 197.0, 275.0, 269.0, 199.0, 193.0], "value": 228.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 80.0, 74.0, 169.0, 66.0, 57.0, 59.0, 68.0, 119.0, 99.0], "value": 85.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 110.0, 178.0, 79.0, 82.0, 81.0, 120.0, 82.0, 82.0, 120.0], "value": 101.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 89.0, 106.0, 87.0, 85.0, 88.0, 86.0, 89.0, 84.0], "value": 88.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 151.0, 96.0, 95.0, 95.0, 95.0, 96.0, 94.0, 94.0, 95.0], "value": 104.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 139.0, 89.0, 119.0, 152.0, 93.0, 92.0, 91.0, 93.0, 150.0], "value": 116.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 60.0, 60.0, 57.0, 59.0, 61.0, 59.0, 122.0, 62.0, 57.0], "value": 65.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 67.0, 67.0, 74.0, 79.0, 71.0, 67.0, 65.0, 67.0, 66.0], "value": 68.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 87.0, 89.0, 107.0, 86.0, 87.0, 83.0, 88.0, 89.0], "value": 88.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 136.0, 139.0, 149.0, 154.0, 153.0, 141.0, 174.0, 235.0, 148.0], "value": 156.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 167.0, 166.0, 164.0, 169.0, 167.0, 160.0, 164.0, 165.0, 161.0], "value": 164.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 66.0, 62.0, 68.0, 69.0, 82.0, 66.0, 72.0, 78.0], "value": 69.7, "unit": "score"}]}]} 10:09:55 INFO - Return code: 0 10:09:55 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 10:09:55 ERROR - # TBPL SUCCESS # 10:09:55 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:09:55 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:09:55 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:09:55 INFO - 2016-05-16 10:09:55.652 screenresolution[1686:11770] starting screenresolution argv=screenresolution get 10:09:55 INFO - 2016-05-16 10:09:55.655 screenresolution[1686:11770] Display 0: 1600x1200x32@60 10:09:55 INFO - 2016-05-16 10:09:55.661 screenresolution[1687:11773] starting screenresolution argv=screenresolution list 10:09:55 INFO - Available Modes on Display 0 10:09:55 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:09:55 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:09:55 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:09:55 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:09:55 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:09:55 INFO - Intel Iris: 10:09:55 INFO - Chipset Model: Intel Iris 10:09:55 INFO - Type: GPU 10:09:55 INFO - Bus: Built-In 10:09:55 INFO - VRAM (Dynamic, Max): 1536 MB 10:09:55 INFO - Vendor: Intel (0x8086) 10:09:55 INFO - Device ID: 0x0a2e 10:09:55 INFO - Revision ID: 0x0009 10:09:55 INFO - Displays: 10:09:55 INFO - Display: 10:09:55 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:09:55 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:09:55 INFO - Display Serial Number: 1600x1200 60 10:09:55 INFO - Main Display: Yes 10:09:55 INFO - Mirror: Off 10:09:55 INFO - Online: Yes 10:09:55 INFO - Rotation: Supported 10:09:55 INFO - Return code: 0 10:09:55 INFO - Running post-action listener: _resource_record_post_action 10:09:55 INFO - ##### 10:09:55 INFO - ##### Finished run-tests step (success) 10:09:55 INFO - ##### 10:09:55 INFO - Running post-run listener: _resource_record_post_run 10:09:55 INFO - Total resource usage - Wall time: 1210s; CPU: 55.0%; Read bytes: 30948864; Write bytes: 696785920; Read time: 203; Write time: 1917 10:09:55 INFO - TinderboxPrint: CPU usage
54.6% 10:09:55 INFO - TinderboxPrint: I/O read bytes / time
30,948,864 / 203 10:09:55 INFO - TinderboxPrint: I/O write bytes / time
696,785,920 / 1,917 10:09:55 INFO - TinderboxPrint: CPU idle
2,198.3 (45.4%) 10:09:55 INFO - TinderboxPrint: CPU system
976.2 (20.2%) 10:09:55 INFO - TinderboxPrint: CPU user
1,667.3 (34.4%) 10:09:55 INFO - TinderboxPrint: Swap in / out
422,182,912 / 0 10:09:55 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 388872704; Write bytes: 381401088; Read time: 29910; Write time: 767 10:09:55 INFO - run-tests - Wall time: 1176s; CPU: 55.0%; Read bytes: 29167104; Write bytes: 298071040; Read time: 189; Write time: 1102 10:09:56 INFO - Running post-run listener: _upload_blobber_files 10:09:56 INFO - Blob upload gear active. 10:09:56 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:09:56 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 10:09:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:09:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:09:56 INFO - (blobuploader) - INFO - Open directory for files ... 10:09:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 10:09:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:09:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:09:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 10:09:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:09:57 INFO - (blobuploader) - INFO - Done attempting. 10:09:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 10:09:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:09:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:09:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 10:09:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:09:58 INFO - (blobuploader) - INFO - Done attempting. 10:09:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:09:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:09:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:10:01 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:10:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:10:01 INFO - (blobuploader) - INFO - Done attempting. 10:10:01 INFO - (blobuploader) - INFO - Iteration through files over. 10:10:01 INFO - Return code: 0 10:10:01 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:10:01 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:10:01 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2a3a1e0060a0ce6ac7442e44f9410d2b619cb00f8a197281524eb62830e83572f2dee77591cdf91fc280d96681b0e3b71641b92b39e687a5f0e415713f64cf74", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/10b7afad374e68cf994b03c165bc54c248b2e1b20e565002e2d4d261a0ede9ecfc9ba2527f01ccbddd85be02f82f0ad7911d8e8ff4d995ea1ab508d1356f1f7d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/26b21bb89ec030500d615806c1094c732af4816696ffa1b8ffbdfc9bf9856deba2c1e0f624af550241fdc403ceb5054aba200758993f0e50df22c7e7e1824803"} 10:10:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:10:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:10:01 INFO - Contents: 10:10:01 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2a3a1e0060a0ce6ac7442e44f9410d2b619cb00f8a197281524eb62830e83572f2dee77591cdf91fc280d96681b0e3b71641b92b39e687a5f0e415713f64cf74", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/10b7afad374e68cf994b03c165bc54c248b2e1b20e565002e2d4d261a0ede9ecfc9ba2527f01ccbddd85be02f82f0ad7911d8e8ff4d995ea1ab508d1356f1f7d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/26b21bb89ec030500d615806c1094c732af4816696ffa1b8ffbdfc9bf9856deba2c1e0f624af550241fdc403ceb5054aba200758993f0e50df22c7e7e1824803"} 10:10:01 INFO - Running post-run listener: copy_logs_to_upload_dir 10:10:01 INFO - Copying logs to upload dir... 10:10:01 INFO - mkdir: /builds/slave/test/build/upload/logs 10:10:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1308.562357 ========= master_lag: 0.07 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 48 secs) (at 2016-05-16 10:10:01.698745) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-16 10:10:01.701882) ========= 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.2EAxMTpO8V/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.ATcVOREf1K/Listeners TMPDIR=/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2a3a1e0060a0ce6ac7442e44f9410d2b619cb00f8a197281524eb62830e83572f2dee77591cdf91fc280d96681b0e3b71641b92b39e687a5f0e415713f64cf74", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/10b7afad374e68cf994b03c165bc54c248b2e1b20e565002e2d4d261a0ede9ecfc9ba2527f01ccbddd85be02f82f0ad7911d8e8ff4d995ea1ab508d1356f1f7d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/26b21bb89ec030500d615806c1094c732af4816696ffa1b8ffbdfc9bf9856deba2c1e0f624af550241fdc403ceb5054aba200758993f0e50df22c7e7e1824803"} 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.011125 build_url: 'https://queue.taskcluster.net/v1/task/bsJth3rIRo-exmrUl9e9gQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2a3a1e0060a0ce6ac7442e44f9410d2b619cb00f8a197281524eb62830e83572f2dee77591cdf91fc280d96681b0e3b71641b92b39e687a5f0e415713f64cf74", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/10b7afad374e68cf994b03c165bc54c248b2e1b20e565002e2d4d261a0ede9ecfc9ba2527f01ccbddd85be02f82f0ad7911d8e8ff4d995ea1ab508d1356f1f7d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/26b21bb89ec030500d615806c1094c732af4816696ffa1b8ffbdfc9bf9856deba2c1e0f624af550241fdc403ceb5054aba200758993f0e50df22c7e7e1824803"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-16 10:10:01.730893) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 10:10:01.731225) ========= 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.2EAxMTpO8V/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.ATcVOREf1K/Listeners TMPDIR=/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/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.005136 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 10:10:01.780857) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-16 10:10:01.781206) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-16 10:10:01.781605) ========= ========= Total master_lag: 0.22 =========