builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0136 starttime: 1461304453.33 results: success (0) buildid: 20160421210019 builduid: f5216cf861fc4210a2a0cec93fcd4bee revision: 8969263da89689bb3519a93ce0e142e88800643b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-21 22:54:13.335091) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-21 22:54:13.335485) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-21 22:54:13.335792) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-21 22:54:13.355077) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-21 22:54:13.355337) ========= 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.7anYwbXRF5/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.LMUoDmT7Of/Listeners TMPDIR=/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/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-04-21 22:54:13-- 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% 12.8M=0.001s 2016-04-21 22:54:13 (12.8 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.092923 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-21 22:54:13.465270) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-21 22:54:13.465565) ========= 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.7anYwbXRF5/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.LMUoDmT7Of/Listeners TMPDIR=/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/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.027067 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-21 22:54:13.514082) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-04-21 22:54:13.514401) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 8969263da89689bb3519a93ce0e142e88800643b --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 8969263da89689bb3519a93ce0e142e88800643b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.7anYwbXRF5/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.LMUoDmT7Of/Listeners TMPDIR=/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/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-04-21 22:54:13,956 truncating revision to first 12 chars 2016-04-21 22:54:13,956 Setting DEBUG logging. 2016-04-21 22:54:13,956 attempt 1/10 2016-04-21 22:54:13,956 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/8969263da896?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-21 22:54:15,822 unpacking tar archive at: fx-team-8969263da896/testing/mozharness/ program finished with exit code 0 elapsedTime=2.111010 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-04-21 22:54:15.643912) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-21 22:54:15.644420) ========= script_repo_revision: 8969263da89689bb3519a93ce0e142e88800643b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-21 22:54:15.645036) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-21 22:54:15.645476) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-21 22:54:15.661914) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-04-21 22:54:15.662344) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.7anYwbXRF5/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.LMUoDmT7Of/Listeners TMPDIR=/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/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 22:54:16 INFO - MultiFileLogger online at 20160421 22:54:16 in /builds/slave/test 22:54:16 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 22:54:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:54:16 INFO - {'append_to_log': False, 22:54:16 INFO - 'base_work_dir': '/builds/slave/test', 22:54:16 INFO - 'blob_upload_branch': 'Fx-Team', 22:54:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:54:16 INFO - 'branch': 'Fx-Team', 22:54:16 INFO - 'buildbot_json_path': 'buildprops.json', 22:54:16 INFO - 'config_files': ('talos/mac_config.py',), 22:54:16 INFO - 'default_actions': ('clobber', 22:54:16 INFO - 'read-buildbot-config', 22:54:16 INFO - 'download-and-extract', 22:54:16 INFO - 'populate-webroot', 22:54:16 INFO - 'create-virtualenv', 22:54:16 INFO - 'install', 22:54:16 INFO - 'run-tests'), 22:54:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:54:16 INFO - 'download_minidump_stackwalk': True, 22:54:16 INFO - 'download_symbols': 'ondemand', 22:54:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:54:16 INFO - 'tooltool.py': '/tools/tooltool.py', 22:54:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:54:16 INFO - '/tools/misc-python/virtualenv.py')}, 22:54:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:54:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:54:16 INFO - 'installer_path': 'installer.exe', 22:54:16 INFO - 'log_level': 'info', 22:54:16 INFO - 'log_name': 'talos', 22:54:16 INFO - 'log_to_console': True, 22:54:16 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 22:54:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 22:54:16 INFO - 'opt_config_files': (), 22:54:16 INFO - 'pip_index': False, 22:54:16 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:54:16 INFO - 'cmd': ('bash', 22:54:16 INFO - '-c', 22:54:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:54:16 INFO - 'enabled': True, 22:54:16 INFO - 'halt_on_failure': False, 22:54:16 INFO - 'name': 'check_screen_resolution'},), 22:54:16 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:54:16 INFO - 'cmd': ('bash', 22:54:16 INFO - '-c', 22:54:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:54:16 INFO - 'enabled': True, 22:54:16 INFO - 'halt_on_failure': False, 22:54:16 INFO - 'name': 'check_screen_resolution'},), 22:54:16 INFO - 'run_cmd_checks_enabled': True, 22:54:16 INFO - 'sps_profile': False, 22:54:16 INFO - 'sps_profile_interval': 0, 22:54:16 INFO - 'suite': 'dromaeojs', 22:54:16 INFO - 'system_bits': '32', 22:54:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:54:16 INFO - 'title': 't-yosemite-r7-0136', 22:54:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:54:16 INFO - 'use_talos_json': True, 22:54:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:54:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:54:16 INFO - 'work_dir': 'build'} 22:54:16 INFO - ##### 22:54:16 INFO - ##### Running clobber step. 22:54:16 INFO - ##### 22:54:16 INFO - Running pre-action listener: _resource_record_pre_action 22:54:16 INFO - Running main action method: clobber 22:54:16 INFO - rmtree: /builds/slave/test/build 22:54:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:54:17 INFO - Running post-action listener: _resource_record_post_action 22:54:17 INFO - ##### 22:54:17 INFO - ##### Running read-buildbot-config step. 22:54:17 INFO - ##### 22:54:17 INFO - Running pre-action listener: _resource_record_pre_action 22:54:17 INFO - Running main action method: read_buildbot_config 22:54:17 INFO - Using buildbot properties: 22:54:17 INFO - { 22:54:17 INFO - "project": "", 22:54:17 INFO - "product": "firefox", 22:54:17 INFO - "script_repo_revision": "production", 22:54:17 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 22:54:17 INFO - "repository": "", 22:54:17 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 22:54:17 INFO - "buildid": "20160421210019", 22:54:17 INFO - "pgo_build": "False", 22:54:17 INFO - "basedir": "/builds/slave/test", 22:54:17 INFO - "buildnumber": 696, 22:54:17 INFO - "slavename": "t-yosemite-r7-0136", 22:54:17 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 22:54:17 INFO - "platform": "yosemite_r7", 22:54:17 INFO - "scheduler": "tests-fx-team-macosx64-talos", 22:54:17 INFO - "branch": "fx-team", 22:54:17 INFO - "repo_path": "integration/fx-team", 22:54:17 INFO - "revision": "8969263da89689bb3519a93ce0e142e88800643b", 22:54:17 INFO - "stage_platform": "macosx64", 22:54:17 INFO - "builduid": "f5216cf861fc4210a2a0cec93fcd4bee", 22:54:17 INFO - "slavebuilddir": "test" 22:54:17 INFO - } 22:54:17 INFO - Found installer url https://queue.taskcluster.net/v1/task/MQrQ9wvwQG2CG1zlHY4pbg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 22:54:17 INFO - Running post-action listener: _resource_record_post_action 22:54:17 INFO - ##### 22:54:17 INFO - ##### Running download-and-extract step. 22:54:17 INFO - ##### 22:54:17 INFO - Running pre-action listener: _resource_record_pre_action 22:54:17 INFO - Running main action method: download_and_extract 22:54:17 INFO - mkdir: /builds/slave/test/build/tests 22:54:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:54:17 INFO - https://queue.taskcluster.net/v1/task/MQrQ9wvwQG2CG1zlHY4pbg/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 22:54:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MQrQ9wvwQG2CG1zlHY4pbg/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 22:54:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MQrQ9wvwQG2CG1zlHY4pbg/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 22:54:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MQrQ9wvwQG2CG1zlHY4pbg/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 22:54:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MQrQ9wvwQG2CG1zlHY4pbg/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json'}, attempt #1 22:54:18 INFO - Downloaded 1199 bytes. 22:54:18 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 22:54:18 INFO - Using the following test package requirements: 22:54:18 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 22:54:18 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 22:54:18 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 22:54:18 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 22:54:18 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 22:54:18 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 22:54:18 INFO - u'jsshell-mac.zip'], 22:54:18 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 22:54:18 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 22:54:18 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 22:54:18 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 22:54:18 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 22:54:18 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 22:54:18 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 22:54:18 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 22:54:18 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 22:54:18 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 22:54:18 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 22:54:18 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 22:54: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')]} 22:54:18 INFO - https://queue.taskcluster.net/v1/task/MQrQ9wvwQG2CG1zlHY4pbg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 22:54:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MQrQ9wvwQG2CG1zlHY4pbg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 22:54:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MQrQ9wvwQG2CG1zlHY4pbg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 22:54:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MQrQ9wvwQG2CG1zlHY4pbg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 22:54:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MQrQ9wvwQG2CG1zlHY4pbg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:54:20 INFO - Downloaded 18105890 bytes. 22:54:20 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:54:20 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 22:54:23 INFO - Return code: 0 22:54:23 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip', u'firefox-48.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 22:54:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 22:54:23 INFO - https://queue.taskcluster.net/v1/task/MQrQ9wvwQG2CG1zlHY4pbg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 22:54:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MQrQ9wvwQG2CG1zlHY4pbg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 22:54:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MQrQ9wvwQG2CG1zlHY4pbg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 22:54:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MQrQ9wvwQG2CG1zlHY4pbg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 22:54:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MQrQ9wvwQG2CG1zlHY4pbg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:54:24 INFO - Downloaded 18105890 bytes. 22:54:24 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:54:24 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 22:54:28 INFO - Return code: 0 22:54:28 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')]} 22:54:28 INFO - https://queue.taskcluster.net/v1/task/MQrQ9wvwQG2CG1zlHY4pbg/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 22:54:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MQrQ9wvwQG2CG1zlHY4pbg/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 22:54:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MQrQ9wvwQG2CG1zlHY4pbg/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 22:54:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MQrQ9wvwQG2CG1zlHY4pbg/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip 22:54:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MQrQ9wvwQG2CG1zlHY4pbg/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip'}, attempt #1 22:54:29 INFO - Downloaded 10845952 bytes. 22:54:29 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:54:29 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 22:54:29 INFO - Return code: 0 22:54:29 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')]} 22:54:29 INFO - https://queue.taskcluster.net/v1/task/MQrQ9wvwQG2CG1zlHY4pbg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 22:54:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MQrQ9wvwQG2CG1zlHY4pbg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 22:54:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MQrQ9wvwQG2CG1zlHY4pbg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 22:54:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MQrQ9wvwQG2CG1zlHY4pbg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 22:54:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MQrQ9wvwQG2CG1zlHY4pbg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg'}, attempt #1 22:54:32 INFO - Downloaded 110037069 bytes. 22:54:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/MQrQ9wvwQG2CG1zlHY4pbg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 22:54:32 INFO - mkdir: /builds/slave/test/properties 22:54:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:54:32 INFO - Writing to file /builds/slave/test/properties/build_url 22:54:32 INFO - Contents: 22:54:32 INFO - build_url:https://queue.taskcluster.net/v1/task/MQrQ9wvwQG2CG1zlHY4pbg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 22:54:33 INFO - Running post-action listener: _resource_record_post_action 22:54:33 INFO - Running post-action listener: set_extra_try_arguments 22:54:33 INFO - ##### 22:54:33 INFO - ##### Running populate-webroot step. 22:54:33 INFO - ##### 22:54:33 INFO - Running pre-action listener: _resource_record_pre_action 22:54:33 INFO - Running main action method: populate_webroot 22:54:33 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:54:33 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:54:33 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:54:33 INFO - '--tppagecycles', 22:54:33 INFO - '7'], 22:54:33 INFO - 'tests': ['tsvgm']}}, 22:54:33 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:54:33 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:54:33 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:54:33 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:54:33 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:54:33 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:54:33 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:54:33 INFO - 'talos_options': ['--disable-e10s'], 22:54:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:54:33 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:54:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:54:33 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:54:33 INFO - 'talos_options': ['--disable-e10s'], 22:54:33 INFO - 'tests': ['damp', 'tps']}, 22:54:33 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:54:33 INFO - 'tests': ['damp', 'tps']}, 22:54:33 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:54:33 INFO - 'tests': ['dromaeo_dom']}, 22:54:33 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:54:33 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:54:33 INFO - 'tests': ['a11yr', 22:54:33 INFO - 'ts_paint', 22:54:33 INFO - 'tpaint', 22:54:33 INFO - 'sessionrestore', 22:54:33 INFO - 'sessionrestore_no_auto_restore', 22:54:33 INFO - 'tabpaint']}, 22:54:33 INFO - 'other-e10s': {'tests': ['a11yr', 22:54:33 INFO - 'ts_paint', 22:54:33 INFO - 'tpaint', 22:54:33 INFO - 'sessionrestore', 22:54:33 INFO - 'sessionrestore_no_auto_restore', 22:54:33 INFO - 'tabpaint']}, 22:54:33 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:54:33 INFO - 'ts_paint', 22:54:33 INFO - 'tpaint', 22:54:33 INFO - 'sessionrestore', 22:54:33 INFO - 'sessionrestore_no_auto_restore', 22:54:33 INFO - 'tabpaint']}, 22:54:33 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:54:33 INFO - 'ts_paint', 22:54:33 INFO - 'tpaint', 22:54:33 INFO - 'sessionrestore', 22:54:33 INFO - 'sessionrestore_no_auto_restore', 22:54:33 INFO - 'tabpaint']}, 22:54:33 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:54:33 INFO - 'tests': ['a11yr', 22:54:33 INFO - 'ts_paint', 22:54:33 INFO - 'tpaint', 22:54:33 INFO - 'sessionrestore', 22:54:33 INFO - 'sessionrestore_no_auto_restore', 22:54:33 INFO - 'tabpaint']}, 22:54:33 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:54:33 INFO - 'tests': ['a11yr', 22:54:33 INFO - 'ts_paint', 22:54:33 INFO - 'tpaint', 22:54:33 INFO - 'sessionrestore', 22:54:33 INFO - 'sessionrestore_no_auto_restore', 22:54:33 INFO - 'tabpaint']}, 22:54:33 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:54:33 INFO - 'tests': ['tsvgx', 22:54:33 INFO - 'tsvgr_opacity', 22:54:33 INFO - 'tart', 22:54:33 INFO - 'tscrollx', 22:54:33 INFO - 'cart']}, 22:54:33 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:54:33 INFO - 'tsvgr_opacity', 22:54:33 INFO - 'tart', 22:54:33 INFO - 'tscrollx', 22:54:33 INFO - 'cart']}, 22:54:33 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:54:33 INFO - 'talos_options': ['--disable-e10s'], 22:54:33 INFO - 'tests': ['tp5o']}, 22:54:33 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:54:33 INFO - 'tests': ['tp5o']}, 22:54:33 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:54:33 INFO - 'talos_options': ['--disable-e10s', 22:54:33 INFO - '--xperf_path', 22:54:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:54:33 INFO - 'tests': ['tp5n']}, 22:54:33 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:54:33 INFO - 'talos_options': ['--xperf_path', 22:54:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:54:33 INFO - 'tests': ['tp5n']}}, 22:54:33 INFO - 'talos.zip': {'path': '', 22:54:33 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:54:33 INFO - Running post-action listener: _resource_record_post_action 22:54:33 INFO - ##### 22:54:33 INFO - ##### Running create-virtualenv step. 22:54:33 INFO - ##### 22:54:33 INFO - Running pre-action listener: _resource_record_pre_action 22:54:33 INFO - Running main action method: create_virtualenv 22:54:33 INFO - Creating virtualenv /builds/slave/test/build/venv 22:54:33 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 22:54:33 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:54:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:54:34 INFO - Using real prefix '/tools/python27' 22:54:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:54:34 INFO - Installing distribute.............................................................................................................................................................................................done. 22:54:37 INFO - Installing pip.................done. 22:54:37 INFO - Return code: 0 22:54:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:54: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')]} 22:54:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:54:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:54: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')]} 22:54:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:54:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:54: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ff40a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff50420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbf91d92c20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fddfe00>, '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 0x10fc08db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fddab20>, '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.7anYwbXRF5/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.LMUoDmT7Of/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/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 22:54: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', 'pip>=1.5'] in /builds/slave/test/build 22:54: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 pip>=1.5 22:54:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7anYwbXRF5/Render', 22:54:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:54:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:54:37 INFO - 'HOME': '/Users/cltbld', 22:54:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:54:37 INFO - 'LOGNAME': 'cltbld', 22:54:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:54:37 INFO - 'MOZ_NO_REMOTE': '1', 22:54:37 INFO - 'NO_EM_RESTART': '1', 22:54:37 INFO - 'PAGER': '/bin/cat', 22:54:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:54:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:54:37 INFO - 'PWD': '/builds/slave/test', 22:54:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:54:37 INFO - 'SHELL': '/bin/bash', 22:54:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LMUoDmT7Of/Listeners', 22:54:37 INFO - 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/', 22:54:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:54:37 INFO - 'USER': 'cltbld', 22:54:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:54:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:54:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:54:37 INFO - 'XPC_FLAGS': '0x0', 22:54:37 INFO - 'XPC_SERVICE_NAME': '0', 22:54:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:54:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:54:37 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 22:54:37 INFO - Cleaning up... 22:54:37 INFO - Return code: 0 22:54:37 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 22:54: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')]} 22:54:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:54:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:54: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')]} 22:54:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:54:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:54: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', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ff40a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff50420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbf91d92c20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fddfe00>, '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 0x10fc08db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fddab20>, '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.7anYwbXRF5/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.LMUoDmT7Of/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/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 22:54: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', 'psutil>=0.7.1'] in /builds/slave/test/build 22:54: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 psutil>=0.7.1 22:54:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7anYwbXRF5/Render', 22:54:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:54:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:54:37 INFO - 'HOME': '/Users/cltbld', 22:54:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:54:37 INFO - 'LOGNAME': 'cltbld', 22:54:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:54:37 INFO - 'MOZ_NO_REMOTE': '1', 22:54:37 INFO - 'NO_EM_RESTART': '1', 22:54:37 INFO - 'PAGER': '/bin/cat', 22:54:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:54:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:54:37 INFO - 'PWD': '/builds/slave/test', 22:54:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:54:37 INFO - 'SHELL': '/bin/bash', 22:54:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LMUoDmT7Of/Listeners', 22:54:37 INFO - 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/', 22:54:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:54:37 INFO - 'USER': 'cltbld', 22:54:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:54:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:54:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:54:37 INFO - 'XPC_FLAGS': '0x0', 22:54:37 INFO - 'XPC_SERVICE_NAME': '0', 22:54:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:54:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:54:38 INFO - Downloading/unpacking psutil>=0.7.1 22:54:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:54:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:54:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:54:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:54:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:54:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:54:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:54:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:54:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:54:41 INFO - Installing collected packages: psutil 22:54:41 INFO - Running setup.py install for psutil 22:54:41 INFO - building 'psutil._psutil_osx' extension 22:54:41 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 22:54:41 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 22:54:42 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 22:54:42 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 22:54:42 INFO - building 'psutil._psutil_posix' extension 22:54:42 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 22:54:42 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:54:42 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:54:42 INFO - ^ 22:54:42 INFO - 1 warning generated. 22:54:42 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 22:54:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:54:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:54:42 INFO - Successfully installed psutil 22:54:42 INFO - Cleaning up... 22:54:42 INFO - Return code: 0 22:54:42 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 22:54:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:54:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:54:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:54:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:54:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:54:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:54:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ff40a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff50420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbf91d92c20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fddfe00>, '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 0x10fc08db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fddab20>, '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.7anYwbXRF5/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.LMUoDmT7Of/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/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 22:54:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 22:54:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 22:54:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7anYwbXRF5/Render', 22:54:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:54:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:54:42 INFO - 'HOME': '/Users/cltbld', 22:54:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:54:42 INFO - 'LOGNAME': 'cltbld', 22:54:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:54:42 INFO - 'MOZ_NO_REMOTE': '1', 22:54:42 INFO - 'NO_EM_RESTART': '1', 22:54:42 INFO - 'PAGER': '/bin/cat', 22:54:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:54:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:54:42 INFO - 'PWD': '/builds/slave/test', 22:54:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:54:42 INFO - 'SHELL': '/bin/bash', 22:54:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LMUoDmT7Of/Listeners', 22:54:42 INFO - 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/', 22:54:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:54:42 INFO - 'USER': 'cltbld', 22:54:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:54:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:54:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:54:42 INFO - 'XPC_FLAGS': '0x0', 22:54:42 INFO - 'XPC_SERVICE_NAME': '0', 22:54:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:54:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:54:42 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 22:54:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:54:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:54:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:54:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:54:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:54:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:54:45 INFO - Downloading mozsystemmonitor-0.0.tar.gz 22:54:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:54:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 22:54:45 INFO - Installing collected packages: mozsystemmonitor 22:54:45 INFO - Running setup.py install for mozsystemmonitor 22:54:45 INFO - Successfully installed mozsystemmonitor 22:54:45 INFO - Cleaning up... 22:54:45 INFO - Return code: 0 22:54:45 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:54:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 22:54:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:54:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:54:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 22:54:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:54:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:54:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ff40a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff50420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbf91d92c20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fddfe00>, '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 0x10fc08db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fddab20>, '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.7anYwbXRF5/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.LMUoDmT7Of/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/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 22:54:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 22:54:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 22:54:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7anYwbXRF5/Render', 22:54:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:54:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:54:45 INFO - 'HOME': '/Users/cltbld', 22:54:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:54:45 INFO - 'LOGNAME': 'cltbld', 22:54:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:54:45 INFO - 'MOZ_NO_REMOTE': '1', 22:54:45 INFO - 'NO_EM_RESTART': '1', 22:54:45 INFO - 'PAGER': '/bin/cat', 22:54:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:54:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:54:45 INFO - 'PWD': '/builds/slave/test', 22:54:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:54:45 INFO - 'SHELL': '/bin/bash', 22:54:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LMUoDmT7Of/Listeners', 22:54:45 INFO - 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/', 22:54:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:54:45 INFO - 'USER': 'cltbld', 22:54:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:54:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:54:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:54:45 INFO - 'XPC_FLAGS': '0x0', 22:54:45 INFO - 'XPC_SERVICE_NAME': '0', 22:54:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:54:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:54:45 INFO - Downloading/unpacking blobuploader==1.2.4 22:54:45 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 22:54:45 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 22:54:45 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 22:54:45 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 22:54:45 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 22:54:45 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 22:54:48 INFO - Downloading blobuploader-1.2.4.tar.gz 22:54:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:54:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:54:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:54:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:54:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:54:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:54:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:54:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:54:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:54:48 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:54:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:54:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:54:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:54:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:54:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:54:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:54:49 INFO - Downloading docopt-0.6.1.tar.gz 22:54:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:54:49 INFO - Installing collected packages: blobuploader, requests, docopt 22:54:49 INFO - Running setup.py install for blobuploader 22:54:49 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:54:49 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:54:49 INFO - Running setup.py install for requests 22:54:50 INFO - Running setup.py install for docopt 22:54:50 INFO - Successfully installed blobuploader requests docopt 22:54:50 INFO - Cleaning up... 22:54:50 INFO - Return code: 0 22:54:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:54:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:54:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:54:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:54:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:54:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:54:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:54:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ff40a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff50420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbf91d92c20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fddfe00>, '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 0x10fc08db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fddab20>, '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.7anYwbXRF5/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.LMUoDmT7Of/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/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 22:54:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 22:54:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:54:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7anYwbXRF5/Render', 22:54:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:54:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:54:50 INFO - 'HOME': '/Users/cltbld', 22:54:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:54:50 INFO - 'LOGNAME': 'cltbld', 22:54:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:54:50 INFO - 'MOZ_NO_REMOTE': '1', 22:54:50 INFO - 'NO_EM_RESTART': '1', 22:54:50 INFO - 'PAGER': '/bin/cat', 22:54:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:54:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:54:50 INFO - 'PWD': '/builds/slave/test', 22:54:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:54:50 INFO - 'SHELL': '/bin/bash', 22:54:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LMUoDmT7Of/Listeners', 22:54:50 INFO - 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/', 22:54:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:54:50 INFO - 'USER': 'cltbld', 22:54:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:54:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:54:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:54:50 INFO - 'XPC_FLAGS': '0x0', 22:54:50 INFO - 'XPC_SERVICE_NAME': '0', 22:54:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:54:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:54:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:54:50 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-esMInV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:54:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:54:50 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-9cPSun-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:54:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:54:50 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-UoDrJk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:54:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:54:50 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-1ictrk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:54:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:54:50 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-d4ZQqT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:54:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:54:51 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-4gHoRp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:54:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:54:51 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-3Zmiaq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:54:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:54:51 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-ruJqK0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:54:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:54:51 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-ID7pht-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:54:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:54:51 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-2nH7Q2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:54:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:54:51 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-X2UBiX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:54:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:54:51 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-wJ24tF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:54:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:54:51 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-ikdEqG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:54:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:54:51 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-iY085C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:54:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:54:51 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-iIKpFH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:54:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:54:52 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-VIiO2w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:54:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:54:52 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-vxVQwE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:54:52 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:54:52 INFO - Running setup.py install for manifestparser 22:54:52 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:54:52 INFO - Running setup.py install for mozcrash 22:54:52 INFO - Running setup.py install for mozdebug 22:54:52 INFO - Running setup.py install for mozdevice 22:54:52 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:54:52 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:54:52 INFO - Running setup.py install for mozfile 22:54:53 INFO - Running setup.py install for mozhttpd 22:54:53 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:54:53 INFO - Running setup.py install for mozinfo 22:54:53 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:54:53 INFO - Running setup.py install for mozInstall 22:54:53 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:54:53 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:54:53 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:54:53 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:54:53 INFO - Running setup.py install for mozleak 22:54:53 INFO - Running setup.py install for mozlog 22:54:53 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:54:53 INFO - Running setup.py install for moznetwork 22:54:53 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:54:53 INFO - Running setup.py install for mozprocess 22:54:54 INFO - Running setup.py install for mozprofile 22:54:54 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:54:54 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:54:54 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:54:54 INFO - Running setup.py install for mozrunner 22:54:54 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:54:54 INFO - Running setup.py install for mozscreenshot 22:54:54 INFO - Running setup.py install for moztest 22:54:54 INFO - Running setup.py install for mozversion 22:54:54 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:54:54 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:54:54 INFO - Cleaning up... 22:54:54 INFO - Return code: 0 22:54:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:54:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:54:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:54:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:54:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:54:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:54:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:54:54 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 0x10ff40a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff50420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbf91d92c20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fddfe00>, '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 0x10fc08db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fddab20>, '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.7anYwbXRF5/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.LMUoDmT7Of/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/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 22:54:54 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 22:54:54 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 22:54:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7anYwbXRF5/Render', 22:54:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:54:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:54:54 INFO - 'HOME': '/Users/cltbld', 22:54:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:54:54 INFO - 'LOGNAME': 'cltbld', 22:54:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:54:54 INFO - 'MOZ_NO_REMOTE': '1', 22:54:54 INFO - 'NO_EM_RESTART': '1', 22:54:54 INFO - 'PAGER': '/bin/cat', 22:54:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:54:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:54:54 INFO - 'PWD': '/builds/slave/test', 22:54:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:54:54 INFO - 'SHELL': '/bin/bash', 22:54:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LMUoDmT7Of/Listeners', 22:54:54 INFO - 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/', 22:54:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:54:54 INFO - 'USER': 'cltbld', 22:54:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:54:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:54:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:54:54 INFO - 'XPC_FLAGS': '0x0', 22:54:54 INFO - 'XPC_SERVICE_NAME': '0', 22:54:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:54:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:54:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:54:55 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-SbEK6D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:54:55 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 22:54:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:54:55 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-WqBQlZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:54:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:54:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:54:55 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-gX1Zqm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:54:55 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)) 22:54:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:54:55 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-fqqnfK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:54:55 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)) 22:54:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:54:55 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-xrAqDb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:54:55 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)) 22:54:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:54:55 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-P4icWp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:54:55 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)) 22:54:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:54:55 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-dlMJ6A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:54:55 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)) 22:54:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:54:55 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-v5FNRC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:54:55 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)) 22:54:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:54:55 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-RIDE_N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:54:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 22:54:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:54:56 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-Ra8Pub-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:54:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 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)) 22:54:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:54:56 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-mNS4M4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:54:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 22:54:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:54:56 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-t6i6FW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:54:56 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)) 22:54:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:54:56 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-6jzZoB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:54:56 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)) 22:54:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:54:56 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-D07Vsx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:54:56 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)) 22:54:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:54:56 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-2reCbV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:54:56 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)) 22:54:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:54:56 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-TxTeQT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:54:56 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)) 22:54:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:54:56 INFO - Running setup.py (path:/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/pip-7612Np-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:54:56 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)) 22:54:56 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)) 22:54:56 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)) 22:54:56 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)) 22:54:56 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)) 22:54:56 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)) 22:54:56 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:54:56 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 22:54:56 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 22:54:56 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 22:54:56 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 22:54:56 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 22:54:56 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 22:54:59 INFO - Downloading blessings-1.6.tar.gz 22:54:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:54:59 INFO - Installing collected packages: blessings 22:54:59 INFO - Running setup.py install for blessings 22:54:59 INFO - Successfully installed blessings 22:54:59 INFO - Cleaning up... 22:54:59 INFO - Return code: 0 22:54:59 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:54:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:54:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:54:59 INFO - Reading from file tmpfile_stdout 22:54:59 INFO - Current package versions: 22:54:59 INFO - blessings == 1.6 22:54:59 INFO - blobuploader == 1.2.4 22:54:59 INFO - docopt == 0.6.1 22:54:59 INFO - manifestparser == 1.1 22:54:59 INFO - mozInstall == 1.12 22:54:59 INFO - mozcrash == 0.17 22:54:59 INFO - mozdebug == 0.1 22:54:59 INFO - mozdevice == 0.48 22:54:59 INFO - mozfile == 1.2 22:54:59 INFO - mozhttpd == 0.7 22:54:59 INFO - mozinfo == 0.9 22:54:59 INFO - mozleak == 0.1 22:54:59 INFO - mozlog == 3.1 22:54:59 INFO - moznetwork == 0.27 22:54:59 INFO - mozprocess == 0.22 22:54:59 INFO - mozprofile == 0.28 22:54:59 INFO - mozrunner == 6.11 22:54:59 INFO - mozscreenshot == 0.1 22:54:59 INFO - mozsystemmonitor == 0.0 22:54:59 INFO - moztest == 0.7 22:54:59 INFO - mozversion == 1.4 22:54:59 INFO - psutil == 3.1.1 22:54:59 INFO - requests == 1.2.3 22:54:59 INFO - wsgiref == 0.1.2 22:54:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:54:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:54:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:54:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:54:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:54:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:54:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:54:59 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 0x10ff40a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff50420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbf91d92c20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fddfe00>, '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 0x10fc08db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fddab20>, '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.7anYwbXRF5/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.LMUoDmT7Of/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/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 22:54:59 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 22:54:59 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 22:54:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7anYwbXRF5/Render', 22:54:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:54:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:54:59 INFO - 'HOME': '/Users/cltbld', 22:54:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:54:59 INFO - 'LOGNAME': 'cltbld', 22:54:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:54:59 INFO - 'MOZ_NO_REMOTE': '1', 22:54:59 INFO - 'NO_EM_RESTART': '1', 22:54:59 INFO - 'PAGER': '/bin/cat', 22:54:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:54:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:54:59 INFO - 'PWD': '/builds/slave/test', 22:54:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:54:59 INFO - 'SHELL': '/bin/bash', 22:54:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LMUoDmT7Of/Listeners', 22:54:59 INFO - 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/', 22:54:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:54:59 INFO - 'USER': 'cltbld', 22:54:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:54:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:54:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:54:59 INFO - 'XPC_FLAGS': '0x0', 22:54:59 INFO - 'XPC_SERVICE_NAME': '0', 22:54:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:55:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:55:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 22:55:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 22:55:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 22:55:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 22:55:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 22:55:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 22:55:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 22:55:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 22:55:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 22:55:00 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 22:55:00 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 22:55:00 INFO - Cleaning up... 22:55:00 INFO - Return code: 0 22:55:00 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:55:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 22:55:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:55:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:55:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 22:55:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:55:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:55:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ff40a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff50420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbf91d92c20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fddfe00>, '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 0x10fc08db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fddab20>, '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.7anYwbXRF5/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.LMUoDmT7Of/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/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 22:55:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 22:55:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 22:55:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7anYwbXRF5/Render', 22:55:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:55:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:55:00 INFO - 'HOME': '/Users/cltbld', 22:55:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:55:00 INFO - 'LOGNAME': 'cltbld', 22:55:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:55:00 INFO - 'MOZ_NO_REMOTE': '1', 22:55:00 INFO - 'NO_EM_RESTART': '1', 22:55:00 INFO - 'PAGER': '/bin/cat', 22:55:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:55:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:55:00 INFO - 'PWD': '/builds/slave/test', 22:55:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:55:00 INFO - 'SHELL': '/bin/bash', 22:55:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LMUoDmT7Of/Listeners', 22:55:00 INFO - 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/', 22:55:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:55:00 INFO - 'USER': 'cltbld', 22:55:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:55:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:55:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:55:00 INFO - 'XPC_FLAGS': '0x0', 22:55:00 INFO - 'XPC_SERVICE_NAME': '0', 22:55:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:55:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:55:00 INFO - Downloading/unpacking jsonschema 22:55:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:55:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:55:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:55:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:55:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:55:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:55:02 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:55:02 INFO - Downloading/unpacking functools32 (from jsonschema) 22:55:02 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 22:55:02 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 22:55:02 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 22:55:02 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 22:55:02 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 22:55:02 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 22:55:03 INFO - Downloading functools32-3.2.3-2.tar.gz 22:55:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:55:03 INFO - warning: no files found matching '*.txt' 22:55:03 INFO - no previously-included directories found matching 'build' 22:55:03 INFO - no previously-included directories found matching 'dist' 22:55:03 INFO - no previously-included directories found matching '.git*' 22:55:03 INFO - Installing collected packages: jsonschema, functools32 22:55:03 INFO - Running setup.py install for functools32 22:55:03 INFO - warning: no files found matching '*.txt' 22:55:03 INFO - no previously-included directories found matching 'build' 22:55:03 INFO - no previously-included directories found matching 'dist' 22:55:03 INFO - no previously-included directories found matching '.git*' 22:55:03 INFO - Successfully installed jsonschema functools32 22:55:03 INFO - Cleaning up... 22:55:03 INFO - Return code: 0 22:55:03 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 22:55:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:55:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:55:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:55:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:55:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:55:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:55:03 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 0x10ff40a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff50420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbf91d92c20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fddfe00>, '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 0x10fc08db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fddab20>, '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.7anYwbXRF5/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.LMUoDmT7Of/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/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 22:55:03 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 22:55:03 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 22:55:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7anYwbXRF5/Render', 22:55:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:55:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:55:03 INFO - 'HOME': '/Users/cltbld', 22:55:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:55:03 INFO - 'LOGNAME': 'cltbld', 22:55:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:55:03 INFO - 'MOZ_NO_REMOTE': '1', 22:55:03 INFO - 'NO_EM_RESTART': '1', 22:55:03 INFO - 'PAGER': '/bin/cat', 22:55:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:55:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:55:03 INFO - 'PWD': '/builds/slave/test', 22:55:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:55:03 INFO - 'SHELL': '/bin/bash', 22:55:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LMUoDmT7Of/Listeners', 22:55:03 INFO - 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/', 22:55:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:55:03 INFO - 'USER': 'cltbld', 22:55:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:55:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:55:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:55:03 INFO - 'XPC_FLAGS': '0x0', 22:55:03 INFO - 'XPC_SERVICE_NAME': '0', 22:55:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:55:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:55:03 INFO - Downloading/unpacking flake8 22:55:03 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 22:55:03 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 22:55:03 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 22:55:03 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 22:55:03 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 22:55:03 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 22:55:06 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 22:55:06 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 22:55:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:55:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:55:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:55:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:55:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:55:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:55:06 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 22:55:06 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 22:55:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:55:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:55:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:55:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:55:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:55:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:55:07 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 22:55:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:55:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:55:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:55:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:55:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:55:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:55:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 22:55:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:55:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:55:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:55:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:55:07 INFO - no previously-included directories found matching 'docs/_build' 22:55:07 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 22:55:07 INFO - Running setup.py install for pep8 22:55:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:55:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:55:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:55:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:55:07 INFO - no previously-included directories found matching 'docs/_build' 22:55:07 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 22:55:07 INFO - Successfully installed flake8 mccabe pyflakes pep8 22:55:07 INFO - Cleaning up... 22:55:07 INFO - Return code: 0 22:55:07 INFO - Running post-action listener: _resource_record_post_action 22:55:07 INFO - Running post-action listener: _start_resource_monitoring 22:55:07 INFO - Starting resource monitoring. 22:55:07 INFO - ##### 22:55:07 INFO - ##### Running install step. 22:55:07 INFO - ##### 22:55:07 INFO - Running pre-action listener: _resource_record_pre_action 22:55:07 INFO - Running main action method: install 22:55:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:55:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:55:08 INFO - Reading from file tmpfile_stdout 22:55:08 INFO - Detecting whether we're running mozinstall >=1.0... 22:55:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:55:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:55:08 INFO - Reading from file tmpfile_stdout 22:55:08 INFO - Output received: 22:55:08 INFO - Usage: mozinstall [options] installer 22:55:08 INFO - Options: 22:55:08 INFO - -h, --help show this help message and exit 22:55:08 INFO - -d DEST, --destination=DEST 22:55:08 INFO - Directory to install application into. [default: 22:55:08 INFO - "/builds/slave/test"] 22:55:08 INFO - --app=APP Application being installed. [default: firefox] 22:55:08 INFO - mkdir: /builds/slave/test/build/application 22:55:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 22:55:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 22:55:42 INFO - Reading from file tmpfile_stdout 22:55:42 INFO - Output received: 22:55:42 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 22:55:42 INFO - Running post-action listener: _resource_record_post_action 22:55:42 INFO - ##### 22:55:42 INFO - ##### Running run-tests step. 22:55:42 INFO - ##### 22:55:42 INFO - Running pre-action listener: _resource_record_pre_action 22:55:42 INFO - Running main action method: run_tests 22:55:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:55:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:55:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:55:42 INFO - 2016-04-21 22:55:42.021 screenresolution[1642:7439] starting screenresolution argv=screenresolution get 22:55:42 INFO - 2016-04-21 22:55:42.024 screenresolution[1642:7439] Display 0: 1600x1200x32@60 22:55:42 INFO - 2016-04-21 22:55:42.030 screenresolution[1643:7441] starting screenresolution argv=screenresolution list 22:55:42 INFO - Available Modes on Display 0 22:55:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:55:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:55:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:55:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:55:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:55:42 INFO - Intel Iris: 22:55:42 INFO - Chipset Model: Intel Iris 22:55:42 INFO - Type: GPU 22:55:42 INFO - Bus: Built-In 22:55:42 INFO - VRAM (Dynamic, Max): 1536 MB 22:55:42 INFO - Vendor: Intel (0x8086) 22:55:42 INFO - Device ID: 0x0a2e 22:55:42 INFO - Revision ID: 0x0009 22:55:42 INFO - Displays: 22:55:42 INFO - Display: 22:55:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:55:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:55:42 INFO - Display Serial Number: 1600x1200 60 22:55:42 INFO - Main Display: Yes 22:55:42 INFO - Mirror: Off 22:55:42 INFO - Online: Yes 22:55:42 INFO - Rotation: Supported 22:55:42 INFO - Return code: 0 22:55:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:55:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:55:42 INFO - Python 2.7.3 22:55:42 INFO - Return code: 0 22:55:42 INFO - grabbing minidump binary from tooltool 22:55:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:55:42 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: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fddfe00>, '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 0x10fc08db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fddab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:55:42 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 22:55:42 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 22:55:42 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:55:42 INFO - Return code: 0 22:55:42 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:55:42 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:55:42 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:55:42 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:55:42 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:55:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 22:55:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 22:55:43 INFO - Return code: 0 22:55:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MQrQ9wvwQG2CG1zlHY4pbg/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0136', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 22:55:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/MQrQ9wvwQG2CG1zlHY4pbg/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0136 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 22:55:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7anYwbXRF5/Render', 22:55:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:55:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:55:43 INFO - 'HOME': '/Users/cltbld', 22:55:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:55:43 INFO - 'LOGNAME': 'cltbld', 22:55:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:55:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:55:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:55:43 INFO - 'MOZ_NO_REMOTE': '1', 22:55:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:55:43 INFO - 'NO_EM_RESTART': '1', 22:55:43 INFO - 'PAGER': '/bin/cat', 22:55:43 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:55:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:55:43 INFO - 'PWD': '/builds/slave/test', 22:55:43 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:55:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:55:43 INFO - 'SHELL': '/bin/bash', 22:55:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LMUoDmT7Of/Listeners', 22:55:43 INFO - 'TMPDIR': '/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/', 22:55:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:55:43 INFO - 'USER': 'cltbld', 22:55:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:55:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:55:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:55:43 INFO - 'XPC_FLAGS': '0x0', 22:55:43 INFO - 'XPC_SERVICE_NAME': '0', 22:55:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:55:43 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MQrQ9wvwQG2CG1zlHY4pbg/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0136', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 22:55:43 INFO - mozversion application_buildid: 20160421210019 22:55:43 INFO - mozversion application_changeset: 8969263da89689bb3519a93ce0e142e88800643b 22:55:43 INFO - mozversion application_display_name: Nightly 22:55:43 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:55:43 INFO - mozversion application_name: Firefox 22:55:43 INFO - mozversion application_remotingname: firefox 22:55:43 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 22:55:43 INFO - mozversion application_vendor: Mozilla 22:55:43 INFO - mozversion application_version: 48.0a1 22:55:43 INFO - mozversion platform_buildid: 20160421210019 22:55:43 INFO - mozversion platform_changeset: 8969263da89689bb3519a93ce0e142e88800643b 22:55:43 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 22:55:43 INFO - mozversion platform_version: 48.0a1 22:55:43 INFO - using testdate: 1461304543 22:55:43 INFO - actual date: 1461304543 22:55:43 INFO - starting webserver on 'localhost:49270' 22:55:43 INFO - SUITE-START | Running 2 tests 22:55:43 INFO - TEST-START | dromaeo_css 22:55:43 INFO - operating with platform_type : mac_ 22:55:43 INFO - Initialising browser for dromaeo_css test... 22:55:43 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/tmpac3t3T/profile http://localhost:49270/getInfo.html) 22:55:45 INFO - PROCESS | 1661 | __metrics Screen width/height:1600/1200 22:55:45 INFO - PROCESS | 1661 | colorDepth:24 22:55:45 INFO - PROCESS | 1661 | Browser inner width/height: 1024/689 22:55:45 INFO - PROCESS | 1661 | __metrics 22:55:46 INFO - PROCESS | 1661 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 22:55:46 ERROR - PROCESS | 1661 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 22:55:46 INFO - TEST-INFO | 1661: exit 0 22:55:46 INFO - Browser initialized. 22:55:46 INFO - Running cycle 1/1 for dromaeo_css test... 22:55:46 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/tmpac3t3T/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:55:58 INFO - PROCESS | 1663 | RSS: Main: 238645248 22:55:58 INFO - PROCESS | 1663 | 22:56:49 INFO - PROCESS | 1663 | 1461304609785 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] 22:59:01 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49270/tests/dromaeo/cssquery-ext.html) 22:59:02 INFO - PROCESS | 1663 | RSS: Main: 381313024 22:59:02 INFO - PROCESS | 1663 | 23:02:05 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-ext.html (next: http://localhost:49270/tests/dromaeo/cssquery-jquery.html) 23:02:06 INFO - PROCESS | 1663 | RSS: Main: 375324672 23:02:06 INFO - PROCESS | 1663 | 23:05:08 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49270/tests/dromaeo/cssquery-mootools.html) 23:05:09 INFO - PROCESS | 1663 | RSS: Main: 373944320 23:05:09 INFO - PROCESS | 1663 | 23:08:11 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49270/tests/dromaeo/cssquery-prototype.html) 23:08:12 INFO - PROCESS | 1663 | RSS: Main: 374800384 23:08:12 INFO - PROCESS | 1663 | 23:11:15 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49270/tests/dromaeo/cssquery-yui.html) 23:11:15 INFO - PROCESS | 1663 | RSS: Main: 344608768 23:11:15 INFO - PROCESS | 1663 | 23:14:18 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-yui.html (next: http://localhost:49270/tests/dromaeo/cssquery-dojo.html) 23:14:19 INFO - PROCESS | 1663 | RSS: Main: 384651264 23:14:19 INFO - PROCESS | 1663 | 23:14:19 INFO - PROCESS | 1663 | __start_tp_report 23:14:19 INFO - PROCESS | 1663 | _x_x_mozilla_page_load 23:14:19 INFO - PROCESS | 1663 | _x_x_mozilla_page_load_details 23:14:19 INFO - PROCESS | 1663 | |i|pagename|runs| 23:14:19 INFO - PROCESS | 1663 | |0;dojo.html;3350.4806754952974;3415.7779744316617;3457.9135521611965;3461.705755010824;3477.356689012533;5634.295713035871;5705.543556515479;5720.170575266589;5734.942650573493;5738.225339579156;5031.52200540948;5705.971470142654;5735.311762588485;5740.655560666363;5805.82582522525;3671.7980511071883;5115.565176959963;5138.2806407103035;5142.382914050319;5242.397124330712;4647.6049535789325;5228.058949389108;5247.86880327991;5260.92108618371;5278.392984806739;5070.518300761421;5155.381354237478;5175.171972484403;5176.016556854186;5178.249153872667;5148.202028685522;5166.535011848914;5173.922391164136;5179.507946745053;5181.740912954338;5086.516780905808;5108.208227724708;5117.232415137759;5131.486851314876;5134.0502007128425;5556.749946252428;5623.746931388097;5676.063449530822;5690.2033715279895;5701.629394089412;6211.823273179838;6232.594881332699;6239.688015599202;6262.655553944532;6272.027835685476;5414.296141501619;6266.780662676784;6329.493640508748;6330.240371155444;6330.303666596671;5703.458171473703;5794.449527294901;5805.477507024388;5887.322957859816;5911.231539899785;6287.874242515124;6304.590201636879;6327.778527751507;6330.746770129235;6338.556301058881;6167;6169.537284703665;6180.8763824723255;6186.133941248228;6195.2255968004;6115.727150604525;6204.875902481925;6309.274433440213;6324.08300796382;6330.303666596671;6179.196704428396;6204.751809927642;6223.1287619733275;6232.968835155795;6256.155419018465;5579.721013949286;5903.586748927534;5984.910226346608;6178.223723425357;6197.318294987548;4888.193448081027;4903.5586797188425;4909.705417674951;4917.0903382874285;4922.261660750844;4419.381286619876;4445.488768791565;4462.486814016364;4481.282994720894;4485.439320084989;5638.2670252866865;5707.200991861143;5750.654960702372;5756.280464941882;5758.481736643722;6232.844178895564;6258.780942666985;6312.053192021233;6319.747210111636;6324.652144132025;5681.517071049007;5689.317281926113;5689.829305120853;5696.601237913306;5720.513756330759;5286.841394758163;5341.145416733304;5388.353397592314;5415.296011518478;5435.619506634498;6240.875182496416;6275.152854364603;6288;6290.937090629127;6299.6850157491945;6244.969580019246;6300.905486417616;6322.0516922462;6325.936740632719;6337.841553961188;6231.688415579112;6252.874942501216;6271.905921411274;6299.496040316855;6342.619442833445;6181.660008699565;6188.845278868064;6206.193194884636;6214.8135555933395;6225.968870155621;6276.811695649138;6292.779752708633;6296.779612713533;6303.117563541016;6305.4009869062365;5838.766449341979;5928.199693041557;6006.699665016644;6108.4807791338235;6128.785492507741;6043.728032238472;6054.697265136813;6063.636181829104;6065.848353791014;6083.482903953213;5917.053271476543;5924.111383292368;5924.348321684697;5925.05199168131;5927.7036148191555;5833.7666493341485;5959.135925290895;5993.190919226023;6058.90911636317;6114.38856114385;5907.704614769158;5925.200097986716;5925.674087925206;5930.258717660293;5949.791757288474 23:14:19 INFO - PROCESS | 1663 | |1;ext.html;505.4137200847017;524.62751446473;525.1020754509788;561.9267199648672;562.6933321339869;30769.384612307742;31253.374932501363;31262.374752504937;31289.843550782214;31304.686953130462;39691.801540992325;40982.59017409825;41106.97232569187;41121.383179252334;41140.58859411405;9448.622055117805;9720;9722.708318750414;9728.951355243214;9738.51307434629;8556.700515481929;8799.516026618534;9511.191548718367;9635.421874687507;9664.371815831948;8684.478931264144;8765.649374025032;8784;11269.436528173599;11655.242409243414;11322.490487928064;11498.19512634116;11509.366984815832;11511.827322590168;11519;11299.096072314194;11453.599124030703;11458.541658333657;11463.713407164803;11477;32167;33040.83479582611;33055;33067.50398744021;40974.180516389504;3224.7258982986473;3301.7193538549254;3325.4512257176316;3548.6806187443253;3939.808754908533;27658.617069146665;27914.581281280796;28616.570751438747;28795;28798.712012879812;2240.372695645222;2683.758461738433;2718.9672293942403;2734.753872151516;2923.63454568179;397245;489365.5531722319;492201;494100;494815;31456.37087258242;63478.36521634817;64357.35642643514;64496.677516612115;64522;34818;34990.47514287288;35021.29957400889;35036.824815876265;35065;14558.199299038655;14881.851181488262;14891.255437228096;14898.925505372621;14942.775858362133;12698.301593412267;12762.61712148637;12766.106372553832;12766.744665106646;12769.489220431078;7619.580923049286;8019.679212831421;8196.67213311461;8252.174782521699;8270.297024752843;3177.3168768714836;3275.4922986937063;3377.6960073593496;3390.660933906639;3426.5716785401823;839.8446287436722;1064.3135177810425;1074.7796701676139;1138.9886101139048;1186.6143503361443;27626.447471050466;28849.85575072111;29139.562906556417;29384.004719197786;29558.408831823243;1102.536934487517;1118.8462210245245;1142.3831131189231;1173.1142987044727;1202.0864143251192;15341.463048793463;15559.766603500957;15760.92119539395;15977.680446391007;16207.675846483004;637912;645817;649203;651686;652623.736881322;54893;62652.68673656602;63587.36412635907;63798;64979;1485.0421478146534;1486.524312220079;1500.5198336532205;1500.8424115467938;1504.7818066380314;28464.003759868305;28622;29104.562634053374;29313.827446901883;29369.118926432242;54590.18114728362;55764.72117639386;55886.72056639854;55924.720376399484;55944.72027639836;685099;713436;733683;734713;736401.3179934066;26307.473850523264;26713.33216669599;27102.593461097706;27223.59164612493;27258;38099.619003810716;46130.61608151761;46306.536934630225;46359.53640463687;46619.067618648114;37392.62607373892;37696.86909392723;37868.810655946545;38384.808075959445;38547.614523855525;36497.635023649425;37475.2504949894;38324.80837595794;38435.846924592304;38614.4207836877;36544;37474.8126259367;37643.870683879526;38615.80692096522;38698.80650596729;31035.53446698256;32201.35597287994;32592.511112333807;32892.83553582217;33023.174420639676;21851;24232;24770.752292476845;24811;24957.772584816183 23:14:19 INFO - PROCESS | 1663 | |2;jquery.html;325.99511007334894;385.53350445960376;404.799624186028;405.84577860413054;416.56885123896774;18953.810461895395;21259.25592604259;21971.780282197175;21984.670229946565;22015;20847.95760211987;21417.67873481895;21832.781672183275;22009.11963521457;22020.229291974785;5668.518175955049;6229.408206220402;6233.7818176363835;6237.313895471494;6240.656763877985;4230.280852255124;4504.504504504502;6064.605800622968;6404.775832845851;6790.864182716343;6087.360827113156;6728.562643428161;6742.662866856662;6748.6625668716615;6750.729970801162;6109.236345456818;6723.159605049369;6732.899006514906;6733.932660673404;6738.629375384352;3760.811959402019;3978.8010599469912;5511.291499635126;5865.912011319833;5889.234399528077;25496.87251563749;25792.355191120183;25835.61246581303;26189.214323570322;26254.474910501875;2156.4501052231735;2233.3746550965593;2343.6601692754625;2412.3245491262473;2422.4912768318673;11099.944500277446;14548.70902581953;14666.19335936523;14725.263736813224;14778.778318325127;1698.872584556151;1766.4877185616156;1775.902325372104;1800.1089460716898;1823.8358547730636;113020;115475.42262288718;116180;116990;117335;10776.515056822462;11034.448277586089;11376.431178441046;11415.315081094996;11547.364894930692;14485.565433037025;14571.052881562664;14572.708545829024;14595.635109122357;14599.19704416246;8711.433756805787;8901.109889011048;8923.330750193762;9035.186833185046;9050.502222377701;6749.392554670103;7271.854562908818;8944.955275223581;9023.413478124032;9036.45781253127;5736.959411511905;6758.276639457005;6873.2783057778215;6877.804539231165;6887.655617219119;1689.7549855270918;1763.2946821271594;1811.5923917118807;1885.981140188608;1900.8099190081157;798.3692882622663;1003.9749006274756;1073.5705717712976;1088.874779400379;1137.0676045642658;20084.799152008585;21352;21596.352109436742;21622.808111546045;21936.45158871041;981.5533932060895;1006.1347241372414;1063.9732657985082;1067.4289255248405;1090.1823632275793;11175.608853690082;11217.270877392943;11674.416279186007;11730.648080557427;11736.354500502384;525849;528042;537223;543661;543682;111005.88994110117;114205.53877940228;115468.42265788786;117088.89820526559;117601.4119929395;999.1665294185107;1066.973325666849;1085.4789700943434;1088.2817340555196;1088.3089238333557;15217.847821521644;18191.999440030926;18292.81707182911;18909.71635425497;19568;49574.76063098307;51004.979900402526;51059.48940510547;51175;51924;72010;73472.26527734654;75597.62201188959;75672.62163689366;78584.21415785768;7578.658960346687;7658.00445942024;7977.641006154621;7981.680732770624;8396.412251142361;10670.35977841332;11936;12265.754684906073;12272.509099636272;12432.451347473927;8608.526531040852;9372.109649583279;10090.69727908164;10972.561097556327;11024.118070554176;10432.68701938943;10641.787164256517;10795.460226988776;10914.236003479999;10976.121910247002;10761.139108871428;10880.67357979262;10916;11027.71276885231;11320.150988675718;7747.573883436465;7957.5623044104495;8046.155153709011;8131.064927533409;8238.970128733908;10663.733406664895;10755.193360497837;10964.06805421548;10966.396848173426;11031.889681103406 23:14:19 INFO - PROCESS | 1663 | |3;mootools.html;1389.117910127069;1564.202256849007;1565.9999999999995;1795.595990902047;1933.400645799803;7959.960200198998;8248.711295104673;8250.381221408388;8253.999999999993;8262.834743305139;8426;8781.034086250507;8790.86813697795;8793.516356600401;8795.692150774725;2413.9275821725378;2489.8132640052067;2492.028109037477;2492.3146134812982;2503.5994240921455;2400.195934361982;2455.2020593307157;2456.4104614892476;2457.680501534798;2458.815588830836;2413.456972181254;2435.8173137014705;2447.571674956884;2457.127719659522;2462.064415522099;2394.7844693977627;2422.563938491071;2435.099013365066;2437.463757973243;2448.7306396296394;2397.0531640002278;2426.9271921842374;2427.5873101572774;2432.477017441241;2434.42790944128;8773.780655483664;8838.337124715608;8839.381243312971;8844.29245660352;8866.556672166365;5195.896082078338;5230.581553475713;5413.729313534347;5476.26070480484;5478.534324582375;3466.3587236361354;3508.2632647143873;3573.5890372607223;3587.3901436755814;3603.765755225902;992.7319623701633;998.2313618513776;1033.4935881418126;1036.041661463151;1042.2652030318684;11658.41707914601;11765.647030589096;11786.764264714659;11798.46906889192;11813.818618138203;4545.068261006486;4715.905681886343;4779.3070004849105;5007.69953802773;5039.470855560114;9193.172614464731;9204.815903681889;9290.349675522784;9303.58133883977;9317.580708868118;5309.017831701148;5309.867253318698;5309.91146814902;5319.867003324948;5326.92009619856;4119.732217405919;4475.1049790042125;4513.435820522434;4886.853394398174;4991.351124353883;3798.2973149966792;3838.7120965927666;3869.3035253654048;3909.4722212500947;3916;1190.6309044196328;1202.356739144571;1205.3250179899285;1217.6834023154045;1225.185251807556;624.475440629864;655.8458762190882;881.8721285413708;903.4940433357341;973.9464329461948;5597.580181486404;5696.658200507983;5961.344252132262;6039.033754599243;6072.301685306158;456.8560903315445;488.7385248891826;496.74417674897455;503.2803091579004;654.0124412137611;3827.75119617224;4006.23881462527;4073.572274911092;4077.26609210345;4087.46862907826;10031.598736050622;10470.790584188426;10958.506867190996;11085.168612354106;11105.111591072695;10457.163426925828;10507.789844203073;10565.366078035342;10571.788564228826;10582.523786429629;7939.483933544301;8060.032796064393;8248.917510824935;8251.381146414044;8322.6670933162;5906.202662640489;6054.90917636227;6055.485283750931;6118.357572454828;6122.387761223842;9995.600175992879;10093.798124037625;10116.140128089195;10129.696109116738;10162.593496260066;9566.330356874952;10259.846102308322;10263.897361026295;10378.740531486472;10378.792424151627;6345.333739957423;7407.370373518312;7548.358389536951;7570.015897933234;7621.809454763676;7973.162817904036;8055.718049868226;8071.556064416513;8149.714759983609;8199.79500512468;6427.6786160690845;6772.86454270902;7264.818379540342;7317.926820731725;7338.96330518344;7188.820279493055;7193.3166349196745;7253.455990800606;7257.52826066304;7375;6866.588004719732;6874.175098988154;6944.097267355211;6946.305369463013;6964.721411143498;4848.321235027029;4866.148424025852;4958.404991400911;4992.8751781205765;5148.819791307436;4987.401511818605;5082.237664350229;5123.308353372247;5138.023775482647;5309.2301616266195 23:14:19 INFO - PROCESS | 1663 | |4;prototype.html;312.4220192643608;321.8004837001059;332.3850875879623;333.1687439837604;334.58344361270224;5364.812231571896;5725.14122881568;5973.641581505112;6047.606905551147;6094.238220222473;4824.348712923755;5670.391619854871;5710.514606258473;5870.70646467677;6001.339852616209;4408;4414.271645178542;4430.645548356124;4471.686981911275;4477.462704475468;3686;4465.062336909236;4475.859829657554;4478.753668548228;4508.143452743968;4050.858219962302;4293.22721910056;4311;4449.911001779962;4452.910941781162;4407.537208593099;4409.228385032607;4417.734935903856;4431.189177307513;4453.064856380164;3367.8652853885815;3861.826217820205;3867.497225360685;3867.8259478323544;3876.379779235337;4679.298105284201;4947.579455746266;5501.807436739695;5741.25363702716;5747.6551406915305;4410.250257456208;5054.570361519275;5155.587552995751;5161.406438259611;5181.0674078665825;5195.454477279869;6206.131141640174;6298.000769511394;6313.40022697843;6321.462675672573;3433.53647257622;4194;4214.494260688706;4228.957710422887;4257.382679511485;6550.377714117151;6606.273309935903;6646.734130634769;6648.700808463631;6655.966720166417;4246.745195288293;4256;4262.381954616563;4264;4267.695444459957;3884.78778644022;4218.325067989107;4328.285832837547;4340.088581397878;4344.56554344563;5029.748512574357;5055.7219352935945;5063.797448102035;5065.822696205615;5066.164082926276;5456.263404440349;6325.746970121144;6351.841203969845;6353.428191462698;6480;1409.584172669065;1412.0750908154969;1448.3699590677877;1456.3155317000992;1498.1235976953387;954.4178051388786;959.6065613098735;976.858355538443;981.087588542648;982.258394911837;4624.583787459145;5852.297724273115;5860;5866.67733274674;5873.559483038789;6437.388448097423;6457.160569125984;6473.935260647428;6493.71414866186;6518.869622607522;5296.3379577552805;5488.451154884559;5627.268455100811;5785.421457854265;5827.35899051104;2680.5041067402594;3384.966150338514;3397.9490307645406;3415.1462134466383;3425.1950791563963;5319.010741943543;5595.992721310203;6436.517261205428;6679.799606011828;6745;6077.696115194222;6598.997456157437;6599.967000165065;6612.966935165389;6635;5729.742161602655;6677.933220667731;6694.732210711614;6700.899486507707;6744.730210791611;6182.670725793976;6260.248770147429;6268.811935641938;6648.401643852077;6658.267590565131;5429.511343979061;6519.641419721771;6548.660183306643;6578.309277525982;6588.352911772057;6595.736170553316;6830.8633827324165;6897.586144831326;6898.655067246718;6915.377616014583;4155.418241446139;4221.430106935524;4244.91510169801;4245.936310955276;4276.48682158143;5360.303160589255;5449.645773024899;6580.407763301135;6592.24189218246;6617.305182955913;6328.968355158379;6355.650439225887;6376.808695739135;6393.936060639334;6396.296407395276;5065.556316449794;5536.7785288588;6370.617762934239;6379.872402552016;6390.680465976776;5682.090865461506;5849.181114643869;6213.254409470893;6228.750849966132;6230.134138296435;5863.618864773776;6328.810135695937;6354.491640668828;6370.6814659267775;6379.330170332234;5655.575831812548;5679.062954612608;6227.906581401373;6281;6283.8114856554375 23:14:19 INFO - PROCESS | 1663 | |5;yui.html;577.7602295047556;735.7755884455239;741.3253938915587;747.5888261456198;756.0964647246543;14573.927130364347;15044.323005464767;15050.096994180356;15069;15125.394984200619;13735.725285494284;13828.516001939934;13831.723365532709;13839.446422143103;13858.930705346484;714.7212587091038;877.7717793373705;888.6178943054484;919.8896132464114;920.9723708288728;897.6768363389207;916.7112359606735;918.4213945214506;919.1635611593451;920.6225447566499;987.7629368951436;989.2234595842265;989.2778271861546;990.479998001048;993.2004736187403;745.5638451505876;768.3545821509903;769.542122437149;769.8075481129717;926.5228407370209;717.9389751871097;718.838261391188;719.022129903331;721.8844984802458;722.8445884134884;14860.925695371454;14872.479463218844;14892;14935.477258296016;14995.775063373949;12401.689957751129;12717.618471445872;12720.681982950407;12752.936235318857;13059.542915997894;9968.651097211563;9985;10178.13485853696;10481.475926203735;10561.894381056169;3448.4654878493875;3452.2922800825772;3458.1181798641437;3490.127468132967;3580.3018411409785;85713.99999999937;87150.56424717836;87492;87575;87577.12422875817;9424.623015079456;9433.433993960385;9573.377730447637;9676.080772326617;9898.60405583783;22116.557668846533;22533.549329013327;22558;22592;22598.887005564866;7535.284148005931;7606.885896711554;7606.923930760732;7643.121041080236;8032.638531266107;4100.200460910094;4103.835846566164;4126.7523948563185;4160.9375859362135;4417.60241578253;3486.930261394758;3520.331137083946;3543.53933988585;3560.4659301105044;3566.054995426214;632.8607706304606;635.8187916443791;637.1144109687446;637.1207733328071;646.5571083807513;493.4399456616766;562.353293712226;598.4075764992626;599.6881621556767;604.6311749832598;7761.534307941542;7791.532508049535;8345.95827020861;8419.410641255054;8532.8720069199;541.2206422751226;591.7692100080972;604.3322129047427;609.6189881324173;614.4132353602299;3755.6432138946757;3845.3270677631863;4018.598140185958;4133.855315064018;4140.668746500272;94745;96563.06873862483;98087;99348.50325748324;99499.00500994897;80352.59823700962;81888;82603.5869820647;84790.15209847823;84828.7275690865;67308.99036514456;68074;69691.30308696849;69944;74386;8069.677212911418;8242.711505097293;8281.17188281167;8290.269268984319;8356.03905550845;40597.1880562393;40679.7966010168;42364;42860.35709464298;47378;45394;53166;57813;61045.77908441895;64340.817846839804;11019.063379612502;11059.285810699355;11144.832827507431;11288.2662626929;11357.375344355809;11978.76042479128;11978.880211198126;11999.280043197436;12047.397630118634;12528.248305101723;11516.94241528787;11573.88426115728;11630.76738465209;11884.702882428008;11945.2832830027;11299.3785341807;11374.943125284652;11464.713382165513;11636;11639.59261425846;11242.325460472397;11398.316101033965;11514.94242528782;11724.472398741906;11834.230774999598;9351.812963740824;9531;9857.852132218166;9973.30186886911;10060.346077504939;7840.607969601383;8556.57217139153;9437.339386242897;9608.231341492525;9785.07041831025 23:14:19 INFO - PROCESS | 1663 | __end_tp_report 23:14:19 INFO - PROCESS | 1663 | __start_cc_report 23:14:19 INFO - PROCESS | 1663 | _x_x_mozilla_cycle_collect,1392 23:14:19 INFO - PROCESS | 1663 | __end_cc_report 23:14:19 INFO - PROCESS | 1663 | __startTimestamp1461305659210__endTimestamp 23:14:19 INFO - PROCESS | 1663 | 23:14:19 INFO - PROCESS | 1663 | ------- Summary: start ------- 23:14:19 INFO - PROCESS | 1663 | Number of tests: 6 23:14:19 INFO - PROCESS | 1663 | 23:14:19 INFO - PROCESS | 1663 | [#0] dojo.html Cycles:165 Average:5717.69 Median:5924.11 stddev:659.47 (11.1%) stddev-sans-first:634.81 23:14:19 INFO - PROCESS | 1663 | Values: 3350.5 3415.8 3457.9 3461.7 3477.4 5634.3 5705.5 5720.2 5734.9 5738.2 5031.5 5706.0 5735.3 5740.7 5805.8 3671.8 5115.6 5138.3 5142.4 5242.4 4647.6 5228.1 5247.9 5260.9 5278.4 5070.5 5155.4 5175.2 5176.0 5178.2 5148.2 5166.5 5173.9 5179.5 5181.7 5086.5 5108.2 5117.2 5131.5 5134.1 5556.7 5623.7 5676.1 5690.2 5701.6 6211.8 6232.6 6239.7 6262.7 6272.0 5414.3 6266.8 6329.5 6330.2 6330.3 5703.5 5794.4 5805.5 5887.3 5911.2 6287.9 6304.6 6327.8 6330.7 6338.6 6167.0 6169.5 6180.9 6186.1 6195.2 6115.7 6204.9 6309.3 6324.1 6330.3 6179.2 6204.8 6223.1 6233.0 6256.2 5579.7 5903.6 5984.9 6178.2 6197.3 4888.2 4903.6 4909.7 4917.1 4922.3 4419.4 4445.5 4462.5 4481.3 4485.4 5638.3 5707.2 5750.7 5756.3 5758.5 6232.8 6258.8 6312.1 6319.7 6324.7 5681.5 5689.3 5689.8 5696.6 5720.5 5286.8 5341.1 5388.4 5415.3 5435.6 6240.9 6275.2 6288.0 6290.9 6299.7 6245.0 6300.9 6322.1 6325.9 6337.8 6231.7 6252.9 6271.9 6299.5 6342.6 6181.7 6188.8 6206.2 6214.8 6226.0 6276.8 6292.8 6296.8 6303.1 6305.4 5838.8 5928.2 6006.7 6108.5 6128.8 6043.7 6054.7 6063.6 6065.8 6083.5 5917.1 5924.1 5924.3 5925.1 5927.7 5833.8 5959.1 5993.2 6058.9 6114.4 5907.7 5925.2 5925.7 5930.3 5949.8 23:14:19 INFO - PROCESS | 1663 | 23:14:19 INFO - PROCESS | 1663 | [#1] ext.html Cycles:180 Average:72308.56 Median:3351.57 stddev:167496.35 (4997.5%) stddev-sans-first:167879.46 23:14:19 INFO - PROCESS | 1663 | Values: 505.4 524.6 525.1 561.9 562.7 30769.4 31253.4 31262.4 31289.8 31304.7 39691.8 40982.6 41107.0 41121.4 41140.6 9448.6 9720.0 9722.7 9729.0 9738.5 8556.7 8799.5 9511.2 9635.4 9664.4 8684.5 8765.6 8784.0 11269.4 11655.2 11322.5 11498.2 11509.4 11511.8 11519.0 11299.1 11453.6 11458.5 11463.7 11477.0 32167.0 33040.8 33055.0 33067.5 40974.2 3224.7 3301.7 3325.5 3548.7 3939.8 27658.6 27914.6 28616.6 28795.0 28798.7 2240.4 2683.8 2719.0 2734.8 2923.6 397245.0 489365.6 492201.0 494100.0 494815.0 31456.4 63478.4 64357.4 64496.7 64522.0 34818.0 34990.5 35021.3 35036.8 35065.0 14558.2 14881.9 14891.3 14898.9 14942.8 12698.3 12762.6 12766.1 12766.7 12769.5 7619.6 8019.7 8196.7 8252.2 8270.3 3177.3 3275.5 3377.7 3390.7 3426.6 839.8 1064.3 1074.8 1139.0 1186.6 27626.4 28849.9 29139.6 29384.0 29558.4 1102.5 1118.8 1142.4 1173.1 1202.1 15341.5 15559.8 15760.9 15977.7 16207.7 637912.0 645817.0 649203.0 651686.0 652623.7 54893.0 62652.7 63587.4 63798.0 64979.0 1485.0 1486.5 1500.5 1500.8 1504.8 28464.0 28622.0 29104.6 29313.8 29369.1 54590.2 55764.7 55886.7 55924.7 55944.7 685099.0 713436.0 733683.0 734713.0 736401.3 26307.5 26713.3 27102.6 27223.6 27258.0 38099.6 46130.6 46306.5 46359.5 46619.1 37392.6 37696.9 37868.8 38384.8 38547.6 36497.6 37475.3 38324.8 38435.8 38614.4 36544.0 37474.8 37643.9 38615.8 38698.8 31035.5 32201.4 32592.5 32892.8 33023.2 21851.0 24232.0 24770.8 24811.0 24957.8 23:14:19 INFO - PROCESS | 1663 | 23:14:19 INFO - PROCESS | 1663 | [#2] jquery.html Cycles:180 Average:32932.45 Median:21954.12 stddev:89541.45 (407.9%) stddev-sans-first:89759.17 23:14:19 INFO - PROCESS | 1663 | Values: 326.0 385.5 404.8 405.8 416.6 18953.8 21259.3 21971.8 21984.7 22015.0 20848.0 21417.7 21832.8 22009.1 22020.2 5668.5 6229.4 6233.8 6237.3 6240.7 4230.3 4504.5 6064.6 6404.8 6790.9 6087.4 6728.6 6742.7 6748.7 6750.7 6109.2 6723.2 6732.9 6733.9 6738.6 3760.8 3978.8 5511.3 5865.9 5889.2 25496.9 25792.4 25835.6 26189.2 26254.5 2156.5 2233.4 2343.7 2412.3 2422.5 11099.9 14548.7 14666.2 14725.3 14778.8 1698.9 1766.5 1775.9 1800.1 1823.8 113020.0 115475.4 116180.0 116990.0 117335.0 10776.5 11034.4 11376.4 11415.3 11547.4 14485.6 14571.1 14572.7 14595.6 14599.2 8711.4 8901.1 8923.3 9035.2 9050.5 6749.4 7271.9 8945.0 9023.4 9036.5 5737.0 6758.3 6873.3 6877.8 6887.7 1689.8 1763.3 1811.6 1886.0 1900.8 798.4 1004.0 1073.6 1088.9 1137.1 20084.8 21352.0 21596.4 21622.8 21936.5 981.6 1006.1 1064.0 1067.4 1090.2 11175.6 11217.3 11674.4 11730.6 11736.4 525849.0 528042.0 537223.0 543661.0 543682.0 111005.9 114205.5 115468.4 117088.9 117601.4 999.2 1067.0 1085.5 1088.3 1088.3 15217.8 18192.0 18292.8 18909.7 19568.0 49574.8 51005.0 51059.5 51175.0 51924.0 72010.0 73472.3 75597.6 75672.6 78584.2 7578.7 7658.0 7977.6 7981.7 8396.4 10670.4 11936.0 12265.8 12272.5 12432.5 8608.5 9372.1 10090.7 10972.6 11024.1 10432.7 10641.8 10795.5 10914.2 10976.1 10761.1 10880.7 10916.0 11027.7 11320.2 7747.6 7957.6 8046.2 8131.1 8239.0 10663.7 10755.2 10964.1 10966.4 11031.9 23:14:19 INFO - PROCESS | 1663 | 23:14:19 INFO - PROCESS | 1663 | [#3] mootools.html Cycles:180 Average:5667.12 Median:4992.11 stddev:3171.02 (63.5%) stddev-sans-first:3163.62 23:14:19 INFO - PROCESS | 1663 | Values: 1389.1 1564.2 1566.0 1795.6 1933.4 7960.0 8248.7 8250.4 8254.0 8262.8 8426.0 8781.0 8790.9 8793.5 8795.7 2413.9 2489.8 2492.0 2492.3 2503.6 2400.2 2455.2 2456.4 2457.7 2458.8 2413.5 2435.8 2447.6 2457.1 2462.1 2394.8 2422.6 2435.1 2437.5 2448.7 2397.1 2426.9 2427.6 2432.5 2434.4 8773.8 8838.3 8839.4 8844.3 8866.6 5195.9 5230.6 5413.7 5476.3 5478.5 3466.4 3508.3 3573.6 3587.4 3603.8 992.7 998.2 1033.5 1036.0 1042.3 11658.4 11765.6 11786.8 11798.5 11813.8 4545.1 4715.9 4779.3 5007.7 5039.5 9193.2 9204.8 9290.3 9303.6 9317.6 5309.0 5309.9 5309.9 5319.9 5326.9 4119.7 4475.1 4513.4 4886.9 4991.4 3798.3 3838.7 3869.3 3909.5 3916.0 1190.6 1202.4 1205.3 1217.7 1225.2 624.5 655.8 881.9 903.5 973.9 5597.6 5696.7 5961.3 6039.0 6072.3 456.9 488.7 496.7 503.3 654.0 3827.8 4006.2 4073.6 4077.3 4087.5 10031.6 10470.8 10958.5 11085.2 11105.1 10457.2 10507.8 10565.4 10571.8 10582.5 7939.5 8060.0 8248.9 8251.4 8322.7 5906.2 6054.9 6055.5 6118.4 6122.4 9995.6 10093.8 10116.1 10129.7 10162.6 9566.3 10259.8 10263.9 10378.7 10378.8 6345.3 7407.4 7548.4 7570.0 7621.8 7973.2 8055.7 8071.6 8149.7 8199.8 6427.7 6772.9 7264.8 7317.9 7339.0 7188.8 7193.3 7253.5 7257.5 7375.0 6866.6 6874.2 6944.1 6946.3 6964.7 4848.3 4866.1 4958.4 4992.9 5148.8 4987.4 5082.2 5123.3 5138.0 5309.2 23:14:19 INFO - PROCESS | 1663 | 23:14:19 INFO - PROCESS | 1663 | [#4] prototype.html Cycles:180 Average:5082.04 Median:5680.58 stddev:1606.38 (28.3%) stddev-sans-first:1570.49 23:14:19 INFO - PROCESS | 1663 | Values: 312.4 321.8 332.4 333.2 334.6 5364.8 5725.1 5973.6 6047.6 6094.2 4824.3 5670.4 5710.5 5870.7 6001.3 4408.0 4414.3 4430.6 4471.7 4477.5 3686.0 4465.1 4475.9 4478.8 4508.1 4050.9 4293.2 4311.0 4449.9 4452.9 4407.5 4409.2 4417.7 4431.2 4453.1 3367.9 3861.8 3867.5 3867.8 3876.4 4679.3 4947.6 5501.8 5741.3 5747.7 4410.3 5054.6 5155.6 5161.4 5181.1 5195.5 6206.1 6298.0 6313.4 6321.5 3433.5 4194.0 4214.5 4229.0 4257.4 6550.4 6606.3 6646.7 6648.7 6656.0 4246.7 4256.0 4262.4 4264.0 4267.7 3884.8 4218.3 4328.3 4340.1 4344.6 5029.7 5055.7 5063.8 5065.8 5066.2 5456.3 6325.7 6351.8 6353.4 6480.0 1409.6 1412.1 1448.4 1456.3 1498.1 954.4 959.6 976.9 981.1 982.3 4624.6 5852.3 5860.0 5866.7 5873.6 6437.4 6457.2 6473.9 6493.7 6518.9 5296.3 5488.5 5627.3 5785.4 5827.4 2680.5 3385.0 3397.9 3415.1 3425.2 5319.0 5596.0 6436.5 6679.8 6745.0 6077.7 6599.0 6600.0 6613.0 6635.0 5729.7 6677.9 6694.7 6700.9 6744.7 6182.7 6260.2 6268.8 6648.4 6658.3 5429.5 6519.6 6548.7 6578.3 6588.4 6595.7 6830.9 6897.6 6898.7 6915.4 4155.4 4221.4 4244.9 4245.9 4276.5 5360.3 5449.6 6580.4 6592.2 6617.3 6329.0 6355.7 6376.8 6393.9 6396.3 5065.6 5536.8 6370.6 6379.9 6390.7 5682.1 5849.2 6213.3 6228.8 6230.1 5863.6 6328.8 6354.5 6370.7 6379.3 5655.6 5679.1 6227.9 6281.0 6283.8 23:14:19 INFO - PROCESS | 1663 | 23:14:19 INFO - PROCESS | 1663 | [#5] yui.html Cycles:180 Average:18286.15 Median:607.13 stddev:26169.43 (4310.4%) stddev-sans-first:26209.06 23:14:19 INFO - PROCESS | 1663 | Values: 577.8 735.8 741.3 747.6 756.1 14573.9 15044.3 15050.1 15069.0 15125.4 13735.7 13828.5 13831.7 13839.4 13858.9 714.7 877.8 888.6 919.9 921.0 897.7 916.7 918.4 919.2 920.6 987.8 989.2 989.3 990.5 993.2 745.6 768.4 769.5 769.8 926.5 717.9 718.8 719.0 721.9 722.8 14860.9 14872.5 14892.0 14935.5 14995.8 12401.7 12717.6 12720.7 12752.9 13059.5 9968.7 9985.0 10178.1 10481.5 10561.9 3448.5 3452.3 3458.1 3490.1 3580.3 85714.0 87150.6 87492.0 87575.0 87577.1 9424.6 9433.4 9573.4 9676.1 9898.6 22116.6 22533.5 22558.0 22592.0 22598.9 7535.3 7606.9 7606.9 7643.1 8032.6 4100.2 4103.8 4126.8 4160.9 4417.6 3486.9 3520.3 3543.5 3560.5 3566.1 632.9 635.8 637.1 637.1 646.6 493.4 562.4 598.4 599.7 604.6 7761.5 7791.5 8346.0 8419.4 8532.9 541.2 591.8 604.3 609.6 614.4 3755.6 3845.3 4018.6 4133.9 4140.7 94745.0 96563.1 98087.0 99348.5 99499.0 80352.6 81888.0 82603.6 84790.2 84828.7 67309.0 68074.0 69691.3 69944.0 74386.0 8069.7 8242.7 8281.2 8290.3 8356.0 40597.2 40679.8 42364.0 42860.4 47378.0 45394.0 53166.0 57813.0 61045.8 64340.8 11019.1 11059.3 11144.8 11288.3 11357.4 11978.8 11978.9 11999.3 12047.4 12528.2 11516.9 11573.9 11630.8 11884.7 11945.3 11299.4 11374.9 11464.7 11636.0 11639.6 11242.3 11398.3 11514.9 11724.5 11834.2 9351.8 9531.0 9857.9 9973.3 10060.3 7840.6 8556.6 9437.3 9608.2 9785.1 23:14:19 INFO - PROCESS | 1663 | -------- Summary: end -------- 23:14:19 INFO - PROCESS | 1663 | 23:14:19 INFO - TEST-INFO | 1663: exit 0 23:14:19 INFO - TEST-OK | dromaeo_css | took 1115979ms 23:14:19 INFO - TEST-START | kraken 23:14:19 INFO - operating with platform_type : mac_ 23:14:19 INFO - Initialising browser for kraken test... 23:14:19 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/tmpeMrKiM/profile http://localhost:49270/getInfo.html) 23:14:22 INFO - PROCESS | 1672 | __metrics Screen width/height:1600/1200 23:14:22 INFO - PROCESS | 1672 | colorDepth:24 23:14:22 INFO - PROCESS | 1672 | Browser inner width/height: 1024/689 23:14:22 INFO - PROCESS | 1672 | __metrics 23:14:22 INFO - PROCESS | 1672 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 23:14:22 ERROR - PROCESS | 1672 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 23:14:22 INFO - TEST-INFO | 1672: exit 0 23:14:22 INFO - Browser initialized. 23:14:22 INFO - Running cycle 1/1 for kraken test... 23:14:22 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/tmpeMrKiM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:14:34 INFO - PROCESS | 1674 | RSS: Main: 238800896 23:14:34 INFO - PROCESS | 1674 | 23:14:37 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection) 23:14:38 INFO - PROCESS | 1674 | RSS: Main: 285995008 23:14:38 INFO - PROCESS | 1674 | 23:14:41 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-dft) 23:14:41 INFO - PROCESS | 1674 | RSS: Main: 298881024 23:14:41 INFO - PROCESS | 1674 | 23:14:45 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-fft) 23:14:45 INFO - PROCESS | 1674 | RSS: Main: 386408448 23:14:45 INFO - PROCESS | 1674 | 23:14:47 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator) 23:14:48 INFO - PROCESS | 1674 | RSS: Main: 285528064 23:14:48 INFO - PROCESS | 1674 | 23:14:50 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:14:51 INFO - PROCESS | 1674 | RSS: Main: 290537472 23:14:51 INFO - PROCESS | 1674 | 23:14:53 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom) 23:14:54 INFO - PROCESS | 1674 | RSS: Main: 329347072 23:14:54 INFO - PROCESS | 1674 | 23:14:57 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate) 23:14:58 INFO - PROCESS | 1674 | RSS: Main: 330174464 23:14:58 INFO - PROCESS | 1674 | 23:15:00 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial) 23:15:01 INFO - PROCESS | 1674 | RSS: Main: 328699904 23:15:01 INFO - PROCESS | 1674 | 23:15:03 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:15:03 INFO - PROCESS | 1674 | RSS: Main: 319954944 23:15:03 INFO - PROCESS | 1674 | 23:15:05 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:15:06 INFO - PROCESS | 1674 | RSS: Main: 326651904 23:15:06 INFO - PROCESS | 1674 | 23:15:08 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:15:09 INFO - PROCESS | 1674 | RSS: Main: 329314304 23:15:09 INFO - PROCESS | 1674 | 23:15:12 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:15:12 INFO - PROCESS | 1674 | RSS: Main: 331063296 23:15:12 INFO - PROCESS | 1674 | 23:15:15 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:15:16 INFO - PROCESS | 1674 | RSS: Main: 327741440 23:15:16 INFO - PROCESS | 1674 | 23:15:17 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49270/tests/kraken/driver.html?testName=ai-astar) 23:15:18 INFO - PROCESS | 1674 | RSS: Main: 392732672 23:15:18 INFO - PROCESS | 1674 | 23:15:18 INFO - PROCESS | 1674 | __start_tp_report 23:15:18 INFO - PROCESS | 1674 | _x_x_mozilla_page_load 23:15:18 INFO - PROCESS | 1674 | _x_x_mozilla_page_load_details 23:15:18 INFO - PROCESS | 1674 | |i|pagename|runs| 23:15:18 INFO - PROCESS | 1674 | |0;ai-astar;112;110;111;113;113;113;117;111;111;111 23:15:18 INFO - PROCESS | 1674 | |1;audio-beat-detection;158;122;145;192;153;172;139;119;170;116 23:15:18 INFO - PROCESS | 1674 | |2;audio-dft;185;187;186;180;186;182;183;184;184;186 23:15:18 INFO - PROCESS | 1674 | |3;audio-fft;77;72;86;90;56;60;58;80;80;46 23:15:18 INFO - PROCESS | 1674 | |4;audio-oscillator;89;95;139;80;79;81;126;79;80;125 23:15:18 INFO - PROCESS | 1674 | |5;imaging-gaussian-blur;87;85;88;84;86;84;89;131;128;85 23:15:18 INFO - PROCESS | 1674 | |6;imaging-darkroom;94;94;95;94;93;94;94;93;93;94 23:15:18 INFO - PROCESS | 1674 | |7;imaging-desaturate;90;89;89;89;90;89;89;89;90;84 23:15:18 INFO - PROCESS | 1674 | |8;json-parse-financial;60;58;71;85;90;90;75;75;60;90 23:15:18 INFO - PROCESS | 1674 | |9;json-stringify-tinderbox;62;62;67;75;73;69;66;68;67;66 23:15:18 INFO - PROCESS | 1674 | |10;stanford-crypto-aes;82;82;82;82;81;82;78;80;84;83 23:15:18 INFO - PROCESS | 1674 | |11;stanford-crypto-ccm;139;142;154;158;139;136;124;149;238;177 23:15:18 INFO - PROCESS | 1674 | |12;stanford-crypto-pbkdf2;169;172;172;171;171;210;170;172;170;171 23:15:18 INFO - PROCESS | 1674 | |13;stanford-crypto-sha256-iterative;71;70;69;68;70;70;69;69;68;67 23:15:18 INFO - PROCESS | 1674 | __end_tp_report 23:15:18 INFO - PROCESS | 1674 | __start_cc_report 23:15:18 INFO - PROCESS | 1674 | _x_x_mozilla_cycle_collect,1928 23:15:18 INFO - PROCESS | 1674 | __end_cc_report 23:15:18 INFO - PROCESS | 1674 | __startTimestamp1461305718131__endTimestamp 23:15:18 INFO - PROCESS | 1674 | 23:15:18 INFO - PROCESS | 1674 | ------- Summary: start ------- 23:15:18 INFO - PROCESS | 1674 | Number of tests: 14 23:15:18 INFO - PROCESS | 1674 | 23:15:18 INFO - PROCESS | 1674 | [#0] ai-astar Cycles:10 Average:112.20 Median:112.50 stddev:1.99 (1.8%) stddev-sans-first:2.11 23:15:18 INFO - PROCESS | 1674 | Values: 112.0 110.0 111.0 113.0 113.0 113.0 117.0 111.0 111.0 111.0 23:15:18 INFO - PROCESS | 1674 | 23:15:18 INFO - PROCESS | 1674 | [#1] audio-beat-detection Cycles:10 Average:148.60 Median:155.50 stddev:25.27 (16.3%) stddev-sans-first:26.58 23:15:18 INFO - PROCESS | 1674 | Values: 158.0 122.0 145.0 192.0 153.0 172.0 139.0 119.0 170.0 116.0 23:15:18 INFO - PROCESS | 1674 | 23:15:18 INFO - PROCESS | 1674 | [#2] audio-dft Cycles:10 Average:184.30 Median:185.50 stddev:2.16 (1.2%) stddev-sans-first:2.28 23:15:18 INFO - PROCESS | 1674 | Values: 185.0 187.0 186.0 180.0 186.0 182.0 183.0 184.0 184.0 186.0 23:15:18 INFO - PROCESS | 1674 | 23:15:18 INFO - PROCESS | 1674 | [#3] audio-fft Cycles:10 Average:70.50 Median:78.50 stddev:14.62 (18.6%) stddev-sans-first:15.31 23:15:18 INFO - PROCESS | 1674 | Values: 77.0 72.0 86.0 90.0 56.0 60.0 58.0 80.0 80.0 46.0 23:15:18 INFO - PROCESS | 1674 | 23:15:18 INFO - PROCESS | 1674 | [#4] audio-oscillator Cycles:10 Average:97.30 Median:80.00 stddev:23.42 (29.3%) stddev-sans-first:24.65 23:15:18 INFO - PROCESS | 1674 | Values: 89.0 95.0 139.0 80.0 79.0 81.0 126.0 79.0 80.0 125.0 23:15:18 INFO - PROCESS | 1674 | 23:15:18 INFO - PROCESS | 1674 | [#5] imaging-gaussian-blur Cycles:10 Average:94.70 Median:85.50 stddev:18.43 (21.6%) stddev-sans-first:19.33 23:15:18 INFO - PROCESS | 1674 | Values: 87.0 85.0 88.0 84.0 86.0 84.0 89.0 131.0 128.0 85.0 23:15:18 INFO - PROCESS | 1674 | 23:15:18 INFO - PROCESS | 1674 | [#6] imaging-darkroom Cycles:10 Average:93.80 Median:94.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 23:15:18 INFO - PROCESS | 1674 | Values: 94.0 94.0 95.0 94.0 93.0 94.0 94.0 93.0 93.0 94.0 23:15:18 INFO - PROCESS | 1674 | 23:15:18 INFO - PROCESS | 1674 | [#7] imaging-desaturate Cycles:10 Average:88.80 Median:89.00 stddev:1.75 (2.0%) stddev-sans-first:1.80 23:15:18 INFO - PROCESS | 1674 | Values: 90.0 89.0 89.0 89.0 90.0 89.0 89.0 89.0 90.0 84.0 23:15:18 INFO - PROCESS | 1674 | 23:15:18 INFO - PROCESS | 1674 | [#8] json-parse-financial Cycles:10 Average:75.40 Median:80.00 stddev:13.03 (16.3%) stddev-sans-first:12.57 23:15:18 INFO - PROCESS | 1674 | Values: 60.0 58.0 71.0 85.0 90.0 90.0 75.0 75.0 60.0 90.0 23:15:18 INFO - PROCESS | 1674 | 23:15:18 INFO - PROCESS | 1674 | [#9] json-stringify-tinderbox Cycles:10 Average:67.50 Median:67.50 stddev:4.14 (6.1%) stddev-sans-first:3.89 23:15:18 INFO - PROCESS | 1674 | Values: 62.0 62.0 67.0 75.0 73.0 69.0 66.0 68.0 67.0 66.0 23:15:18 INFO - PROCESS | 1674 | 23:15:18 INFO - PROCESS | 1674 | [#10] stanford-crypto-aes Cycles:10 Average:81.60 Median:82.00 stddev:1.65 (2.0%) stddev-sans-first:1.74 23:15:18 INFO - PROCESS | 1674 | Values: 82.0 82.0 82.0 82.0 81.0 82.0 78.0 80.0 84.0 83.0 23:15:18 INFO - PROCESS | 1674 | 23:15:18 INFO - PROCESS | 1674 | [#11] stanford-crypto-ccm Cycles:10 Average:155.60 Median:151.50 stddev:32.35 (21.4%) stddev-sans-first:33.75 23:15:18 INFO - PROCESS | 1674 | Values: 139.0 142.0 154.0 158.0 139.0 136.0 124.0 149.0 238.0 177.0 23:15:18 INFO - PROCESS | 1674 | 23:15:18 INFO - PROCESS | 1674 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.80 Median:171.50 stddev:12.41 (7.2%) stddev-sans-first:12.98 23:15:18 INFO - PROCESS | 1674 | Values: 169.0 172.0 172.0 171.0 171.0 210.0 170.0 172.0 170.0 171.0 23:15:18 INFO - PROCESS | 1674 | 23:15:18 INFO - PROCESS | 1674 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.10 Median:69.50 stddev:1.20 (1.7%) stddev-sans-first:1.05 23:15:18 INFO - PROCESS | 1674 | Values: 71.0 70.0 69.0 68.0 70.0 70.0 69.0 69.0 68.0 67.0 23:15:18 INFO - PROCESS | 1674 | -------- Summary: end -------- 23:15:18 INFO - PROCESS | 1674 | 23:15:19 INFO - TEST-INFO | 1674: exit 0 23:15:19 INFO - TEST-OK | kraken | took 59329ms 23:15:19 INFO - SUITE-END | took 1175s 23:15:19 INFO - Completed test suite (00:19:35) 23:15:19 INFO - javascript benchmark 23:15:19 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3350.4806754952974, 3415.7779744316617, 3457.9135521611965, 3461.705755010824, 3477.356689012533, 5634.295713035871, 5705.543556515479, 5720.170575266589, 5734.942650573493, 5738.225339579156, 5031.52200540948, 5705.971470142654, 5735.311762588485, 5740.655560666363, 5805.82582522525, 3671.7980511071883, 5115.565176959963, 5138.2806407103035, 5142.382914050319, 5242.397124330712, 4647.6049535789325, 5228.058949389108, 5247.86880327991, 5260.92108618371, 5278.392984806739, 5070.518300761421, 5155.381354237478, 5175.171972484403, 5176.016556854186, 5178.249153872667, 5148.202028685522, 5166.535011848914, 5173.922391164136, 5179.507946745053, 5181.740912954338, 5086.516780905808, 5108.208227724708, 5117.232415137759, 5131.486851314876, 5134.0502007128425, 5556.749946252428, 5623.746931388097, 5676.063449530822, 5690.2033715279895, 5701.629394089412, 6211.823273179838, 6232.594881332699, 6239.688015599202, 6262.655553944532, 6272.027835685476, 5414.296141501619, 6266.780662676784, 6329.493640508748, 6330.240371155444, 6330.303666596671, 5703.458171473703, 5794.449527294901, 5805.477507024388, 5887.322957859816, 5911.231539899785, 6287.874242515124, 6304.590201636879, 6327.778527751507, 6330.746770129235, 6338.556301058881, 6167.0, 6169.537284703665, 6180.8763824723255, 6186.133941248228, 6195.2255968004, 6115.727150604525, 6204.875902481925, 6309.274433440213, 6324.08300796382, 6330.303666596671, 6179.196704428396, 6204.751809927642, 6223.1287619733275, 6232.968835155795, 6256.155419018465, 5579.721013949286, 5903.586748927534, 5984.910226346608, 6178.223723425357, 6197.318294987548, 4888.193448081027, 4903.5586797188425, 4909.705417674951, 4917.0903382874285, 4922.261660750844, 4419.381286619876, 4445.488768791565, 4462.486814016364, 4481.282994720894, 4485.439320084989, 5638.2670252866865, 5707.200991861143, 5750.654960702372, 5756.280464941882, 5758.481736643722, 6232.844178895564, 6258.780942666985, 6312.053192021233, 6319.747210111636, 6324.652144132025, 5681.517071049007, 5689.317281926113, 5689.829305120853, 5696.601237913306, 5720.513756330759, 5286.841394758163, 5341.145416733304, 5388.353397592314, 5415.296011518478, 5435.619506634498, 6240.875182496416, 6275.152854364603, 6288.0, 6290.937090629127, 6299.6850157491945, 6244.969580019246, 6300.905486417616, 6322.0516922462, 6325.936740632719, 6337.841553961188, 6231.688415579112, 6252.874942501216, 6271.905921411274, 6299.496040316855, 6342.619442833445, 6181.660008699565, 6188.845278868064, 6206.193194884636, 6214.8135555933395, 6225.968870155621, 6276.811695649138, 6292.779752708633, 6296.779612713533, 6303.117563541016, 6305.4009869062365, 5838.766449341979, 5928.199693041557, 6006.699665016644, 6108.4807791338235, 6128.785492507741, 6043.728032238472, 6054.697265136813, 6063.636181829104, 6065.848353791014, 6083.482903953213, 5917.053271476543, 5924.111383292368, 5924.348321684697, 5925.05199168131, 5927.7036148191555, 5833.7666493341485, 5959.135925290895, 5993.190919226023, 6058.90911636317, 6114.38856114385, 5907.704614769158, 5925.200097986716, 5925.674087925206, 5930.258717660293, 5949.791757288474], "value": 5675.836572343642, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [505.4137200847017, 524.62751446473, 525.1020754509788, 561.9267199648672, 562.6933321339869, 30769.384612307742, 31253.374932501363, 31262.374752504937, 31289.843550782214, 31304.686953130462, 39691.801540992325, 40982.59017409825, 41106.97232569187, 41121.383179252334, 41140.58859411405, 9448.622055117805, 9720.0, 9722.708318750414, 9728.951355243214, 9738.51307434629, 8556.700515481929, 8799.516026618534, 9511.191548718367, 9635.421874687507, 9664.371815831948, 8684.478931264144, 8765.649374025032, 8784.0, 11269.436528173599, 11655.242409243414, 11322.490487928064, 11498.19512634116, 11509.366984815832, 11511.827322590168, 11519.0, 11299.096072314194, 11453.599124030703, 11458.541658333657, 11463.713407164803, 11477.0, 32167.0, 33040.83479582611, 33055.0, 33067.50398744021, 40974.180516389504, 3224.7258982986473, 3301.7193538549254, 3325.4512257176316, 3548.6806187443253, 3939.808754908533, 27658.617069146665, 27914.581281280796, 28616.570751438747, 28795.0, 28798.712012879812, 2240.372695645222, 2683.758461738433, 2718.9672293942403, 2734.753872151516, 2923.63454568179, 397245.0, 489365.5531722319, 492201.0, 494100.0, 494815.0, 31456.37087258242, 63478.36521634817, 64357.35642643514, 64496.677516612115, 64522.0, 34818.0, 34990.47514287288, 35021.29957400889, 35036.824815876265, 35065.0, 14558.199299038655, 14881.851181488262, 14891.255437228096, 14898.925505372621, 14942.775858362133, 12698.301593412267, 12762.61712148637, 12766.106372553832, 12766.744665106646, 12769.489220431078, 7619.580923049286, 8019.679212831421, 8196.67213311461, 8252.174782521699, 8270.297024752843, 3177.3168768714836, 3275.4922986937063, 3377.6960073593496, 3390.660933906639, 3426.5716785401823, 839.8446287436722, 1064.3135177810425, 1074.7796701676139, 1138.9886101139048, 1186.6143503361443, 27626.447471050466, 28849.85575072111, 29139.562906556417, 29384.004719197786, 29558.408831823243, 1102.536934487517, 1118.8462210245245, 1142.3831131189231, 1173.1142987044727, 1202.0864143251192, 15341.463048793463, 15559.766603500957, 15760.92119539395, 15977.680446391007, 16207.675846483004, 637912.0, 645817.0, 649203.0, 651686.0, 652623.736881322, 54893.0, 62652.68673656602, 63587.36412635907, 63798.0, 64979.0, 1485.0421478146534, 1486.524312220079, 1500.5198336532205, 1500.8424115467938, 1504.7818066380314, 28464.003759868305, 28622.0, 29104.562634053374, 29313.827446901883, 29369.118926432242, 54590.18114728362, 55764.72117639386, 55886.72056639854, 55924.720376399484, 55944.72027639836, 685099.0, 713436.0, 733683.0, 734713.0, 736401.3179934066, 26307.473850523264, 26713.33216669599, 27102.593461097706, 27223.59164612493, 27258.0, 38099.619003810716, 46130.61608151761, 46306.536934630225, 46359.53640463687, 46619.067618648114, 37392.62607373892, 37696.86909392723, 37868.810655946545, 38384.808075959445, 38547.614523855525, 36497.635023649425, 37475.2504949894, 38324.80837595794, 38435.846924592304, 38614.4207836877, 36544.0, 37474.8126259367, 37643.870683879526, 38615.80692096522, 38698.80650596729, 31035.53446698256, 32201.35597287994, 32592.511112333807, 32892.83553582217, 33023.174420639676, 21851.0, 24232.0, 24770.752292476845, 24811.0, 24957.772584816183], "value": 18757.189629898403, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [325.99511007334894, 385.53350445960376, 404.799624186028, 405.84577860413054, 416.56885123896774, 18953.810461895395, 21259.25592604259, 21971.780282197175, 21984.670229946565, 22015.0, 20847.95760211987, 21417.67873481895, 21832.781672183275, 22009.11963521457, 22020.229291974785, 5668.518175955049, 6229.408206220402, 6233.7818176363835, 6237.313895471494, 6240.656763877985, 4230.280852255124, 4504.504504504502, 6064.605800622968, 6404.775832845851, 6790.864182716343, 6087.360827113156, 6728.562643428161, 6742.662866856662, 6748.6625668716615, 6750.729970801162, 6109.236345456818, 6723.159605049369, 6732.899006514906, 6733.932660673404, 6738.629375384352, 3760.811959402019, 3978.8010599469912, 5511.291499635126, 5865.912011319833, 5889.234399528077, 25496.87251563749, 25792.355191120183, 25835.61246581303, 26189.214323570322, 26254.474910501875, 2156.4501052231735, 2233.3746550965593, 2343.6601692754625, 2412.3245491262473, 2422.4912768318673, 11099.944500277446, 14548.70902581953, 14666.19335936523, 14725.263736813224, 14778.778318325127, 1698.872584556151, 1766.4877185616156, 1775.902325372104, 1800.1089460716898, 1823.8358547730636, 113020.0, 115475.42262288718, 116180.0, 116990.0, 117335.0, 10776.515056822462, 11034.448277586089, 11376.431178441046, 11415.315081094996, 11547.364894930692, 14485.565433037025, 14571.052881562664, 14572.708545829024, 14595.635109122357, 14599.19704416246, 8711.433756805787, 8901.109889011048, 8923.330750193762, 9035.186833185046, 9050.502222377701, 6749.392554670103, 7271.854562908818, 8944.955275223581, 9023.413478124032, 9036.45781253127, 5736.959411511905, 6758.276639457005, 6873.2783057778215, 6877.804539231165, 6887.655617219119, 1689.7549855270918, 1763.2946821271594, 1811.5923917118807, 1885.981140188608, 1900.8099190081157, 798.3692882622663, 1003.9749006274756, 1073.5705717712976, 1088.874779400379, 1137.0676045642658, 20084.799152008585, 21352.0, 21596.352109436742, 21622.808111546045, 21936.45158871041, 981.5533932060895, 1006.1347241372414, 1063.9732657985082, 1067.4289255248405, 1090.1823632275793, 11175.608853690082, 11217.270877392943, 11674.416279186007, 11730.648080557427, 11736.354500502384, 525849.0, 528042.0, 537223.0, 543661.0, 543682.0, 111005.88994110117, 114205.53877940228, 115468.42265788786, 117088.89820526559, 117601.4119929395, 999.1665294185107, 1066.973325666849, 1085.4789700943434, 1088.2817340555196, 1088.3089238333557, 15217.847821521644, 18191.999440030926, 18292.81707182911, 18909.71635425497, 19568.0, 49574.76063098307, 51004.979900402526, 51059.48940510547, 51175.0, 51924.0, 72010.0, 73472.26527734654, 75597.62201188959, 75672.62163689366, 78584.21415785768, 7578.658960346687, 7658.00445942024, 7977.641006154621, 7981.680732770624, 8396.412251142361, 10670.35977841332, 11936.0, 12265.754684906073, 12272.509099636272, 12432.451347473927, 8608.526531040852, 9372.109649583279, 10090.69727908164, 10972.561097556327, 11024.118070554176, 10432.68701938943, 10641.787164256517, 10795.460226988776, 10914.236003479999, 10976.121910247002, 10761.139108871428, 10880.67357979262, 10916.0, 11027.71276885231, 11320.150988675718, 7747.573883436465, 7957.5623044104495, 8046.155153709011, 8131.064927533409, 8238.970128733908, 10663.733406664895, 10755.193360497837, 10964.06805421548, 10966.396848173426, 11031.889681103406], "value": 9735.623336587483, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1389.117910127069, 1564.202256849007, 1565.9999999999995, 1795.595990902047, 1933.400645799803, 7959.960200198998, 8248.711295104673, 8250.381221408388, 8253.999999999993, 8262.834743305139, 8426.0, 8781.034086250507, 8790.86813697795, 8793.516356600401, 8795.692150774725, 2413.9275821725378, 2489.8132640052067, 2492.028109037477, 2492.3146134812982, 2503.5994240921455, 2400.195934361982, 2455.2020593307157, 2456.4104614892476, 2457.680501534798, 2458.815588830836, 2413.456972181254, 2435.8173137014705, 2447.571674956884, 2457.127719659522, 2462.064415522099, 2394.7844693977627, 2422.563938491071, 2435.099013365066, 2437.463757973243, 2448.7306396296394, 2397.0531640002278, 2426.9271921842374, 2427.5873101572774, 2432.477017441241, 2434.42790944128, 8773.780655483664, 8838.337124715608, 8839.381243312971, 8844.29245660352, 8866.556672166365, 5195.896082078338, 5230.581553475713, 5413.729313534347, 5476.26070480484, 5478.534324582375, 3466.3587236361354, 3508.2632647143873, 3573.5890372607223, 3587.3901436755814, 3603.765755225902, 992.7319623701633, 998.2313618513776, 1033.4935881418126, 1036.041661463151, 1042.2652030318684, 11658.41707914601, 11765.647030589096, 11786.764264714659, 11798.46906889192, 11813.818618138203, 4545.068261006486, 4715.905681886343, 4779.3070004849105, 5007.69953802773, 5039.470855560114, 9193.172614464731, 9204.815903681889, 9290.349675522784, 9303.58133883977, 9317.580708868118, 5309.017831701148, 5309.867253318698, 5309.91146814902, 5319.867003324948, 5326.92009619856, 4119.732217405919, 4475.1049790042125, 4513.435820522434, 4886.853394398174, 4991.351124353883, 3798.2973149966792, 3838.7120965927666, 3869.3035253654048, 3909.4722212500947, 3916.0, 1190.6309044196328, 1202.356739144571, 1205.3250179899285, 1217.6834023154045, 1225.185251807556, 624.475440629864, 655.8458762190882, 881.8721285413708, 903.4940433357341, 973.9464329461948, 5597.580181486404, 5696.658200507983, 5961.344252132262, 6039.033754599243, 6072.301685306158, 456.8560903315445, 488.7385248891826, 496.74417674897455, 503.2803091579004, 654.0124412137611, 3827.75119617224, 4006.23881462527, 4073.572274911092, 4077.26609210345, 4087.46862907826, 10031.598736050622, 10470.790584188426, 10958.506867190996, 11085.168612354106, 11105.111591072695, 10457.163426925828, 10507.789844203073, 10565.366078035342, 10571.788564228826, 10582.523786429629, 7939.483933544301, 8060.032796064393, 8248.917510824935, 8251.381146414044, 8322.6670933162, 5906.202662640489, 6054.90917636227, 6055.485283750931, 6118.357572454828, 6122.387761223842, 9995.600175992879, 10093.798124037625, 10116.140128089195, 10129.696109116738, 10162.593496260066, 9566.330356874952, 10259.846102308322, 10263.897361026295, 10378.740531486472, 10378.792424151627, 6345.333739957423, 7407.370373518312, 7548.358389536951, 7570.015897933234, 7621.809454763676, 7973.162817904036, 8055.718049868226, 8071.556064416513, 8149.714759983609, 8199.79500512468, 6427.6786160690845, 6772.86454270902, 7264.818379540342, 7317.926820731725, 7338.96330518344, 7188.820279493055, 7193.3166349196745, 7253.455990800606, 7257.52826066304, 7375.0, 6866.588004719732, 6874.175098988154, 6944.097267355211, 6946.305369463013, 6964.721411143498, 4848.321235027029, 4866.148424025852, 4958.404991400911, 4992.8751781205765, 5148.819791307436, 4987.401511818605, 5082.237664350229, 5123.308353372247, 5138.023775482647, 5309.2301616266195], "value": 4496.44939246567, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [312.4220192643608, 321.8004837001059, 332.3850875879623, 333.1687439837604, 334.58344361270224, 5364.812231571896, 5725.14122881568, 5973.641581505112, 6047.606905551147, 6094.238220222473, 4824.348712923755, 5670.391619854871, 5710.514606258473, 5870.70646467677, 6001.339852616209, 4408.0, 4414.271645178542, 4430.645548356124, 4471.686981911275, 4477.462704475468, 3686.0, 4465.062336909236, 4475.859829657554, 4478.753668548228, 4508.143452743968, 4050.858219962302, 4293.22721910056, 4311.0, 4449.911001779962, 4452.910941781162, 4407.537208593099, 4409.228385032607, 4417.734935903856, 4431.189177307513, 4453.064856380164, 3367.8652853885815, 3861.826217820205, 3867.497225360685, 3867.8259478323544, 3876.379779235337, 4679.298105284201, 4947.579455746266, 5501.807436739695, 5741.25363702716, 5747.6551406915305, 4410.250257456208, 5054.570361519275, 5155.587552995751, 5161.406438259611, 5181.0674078665825, 5195.454477279869, 6206.131141640174, 6298.000769511394, 6313.40022697843, 6321.462675672573, 3433.53647257622, 4194.0, 4214.494260688706, 4228.957710422887, 4257.382679511485, 6550.377714117151, 6606.273309935903, 6646.734130634769, 6648.700808463631, 6655.966720166417, 4246.745195288293, 4256.0, 4262.381954616563, 4264.0, 4267.695444459957, 3884.78778644022, 4218.325067989107, 4328.285832837547, 4340.088581397878, 4344.56554344563, 5029.748512574357, 5055.7219352935945, 5063.797448102035, 5065.822696205615, 5066.164082926276, 5456.263404440349, 6325.746970121144, 6351.841203969845, 6353.428191462698, 6480.0, 1409.584172669065, 1412.0750908154969, 1448.3699590677877, 1456.3155317000992, 1498.1235976953387, 954.4178051388786, 959.6065613098735, 976.858355538443, 981.087588542648, 982.258394911837, 4624.583787459145, 5852.297724273115, 5860.0, 5866.67733274674, 5873.559483038789, 6437.388448097423, 6457.160569125984, 6473.935260647428, 6493.71414866186, 6518.869622607522, 5296.3379577552805, 5488.451154884559, 5627.268455100811, 5785.421457854265, 5827.35899051104, 2680.5041067402594, 3384.966150338514, 3397.9490307645406, 3415.1462134466383, 3425.1950791563963, 5319.010741943543, 5595.992721310203, 6436.517261205428, 6679.799606011828, 6745.0, 6077.696115194222, 6598.997456157437, 6599.967000165065, 6612.966935165389, 6635.0, 5729.742161602655, 6677.933220667731, 6694.732210711614, 6700.899486507707, 6744.730210791611, 6182.670725793976, 6260.248770147429, 6268.811935641938, 6648.401643852077, 6658.267590565131, 5429.511343979061, 6519.641419721771, 6548.660183306643, 6578.309277525982, 6588.352911772057, 6595.736170553316, 6830.8633827324165, 6897.586144831326, 6898.655067246718, 6915.377616014583, 4155.418241446139, 4221.430106935524, 4244.91510169801, 4245.936310955276, 4276.48682158143, 5360.303160589255, 5449.645773024899, 6580.407763301135, 6592.24189218246, 6617.305182955913, 6328.968355158379, 6355.650439225887, 6376.808695739135, 6393.936060639334, 6396.296407395276, 5065.556316449794, 5536.7785288588, 6370.617762934239, 6379.872402552016, 6390.680465976776, 5682.090865461506, 5849.181114643869, 6213.254409470893, 6228.750849966132, 6230.134138296435, 5863.618864773776, 6328.810135695937, 6354.491640668828, 6370.6814659267775, 6379.330170332234, 5655.575831812548, 5679.062954612608, 6227.906581401373, 6281.0, 6283.8114856554375], "value": 4564.107717032726, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [577.7602295047556, 735.7755884455239, 741.3253938915587, 747.5888261456198, 756.0964647246543, 14573.927130364347, 15044.323005464767, 15050.096994180356, 15069.0, 15125.394984200619, 13735.725285494284, 13828.516001939934, 13831.723365532709, 13839.446422143103, 13858.930705346484, 714.7212587091038, 877.7717793373705, 888.6178943054484, 919.8896132464114, 920.9723708288728, 897.6768363389207, 916.7112359606735, 918.4213945214506, 919.1635611593451, 920.6225447566499, 987.7629368951436, 989.2234595842265, 989.2778271861546, 990.479998001048, 993.2004736187403, 745.5638451505876, 768.3545821509903, 769.542122437149, 769.8075481129717, 926.5228407370209, 717.9389751871097, 718.838261391188, 719.022129903331, 721.8844984802458, 722.8445884134884, 14860.925695371454, 14872.479463218844, 14892.0, 14935.477258296016, 14995.775063373949, 12401.689957751129, 12717.618471445872, 12720.681982950407, 12752.936235318857, 13059.542915997894, 9968.651097211563, 9985.0, 10178.13485853696, 10481.475926203735, 10561.894381056169, 3448.4654878493875, 3452.2922800825772, 3458.1181798641437, 3490.127468132967, 3580.3018411409785, 85713.99999999937, 87150.56424717836, 87492.0, 87575.0, 87577.12422875817, 9424.623015079456, 9433.433993960385, 9573.377730447637, 9676.080772326617, 9898.60405583783, 22116.557668846533, 22533.549329013327, 22558.0, 22592.0, 22598.887005564866, 7535.284148005931, 7606.885896711554, 7606.923930760732, 7643.121041080236, 8032.638531266107, 4100.200460910094, 4103.835846566164, 4126.7523948563185, 4160.9375859362135, 4417.60241578253, 3486.930261394758, 3520.331137083946, 3543.53933988585, 3560.4659301105044, 3566.054995426214, 632.8607706304606, 635.8187916443791, 637.1144109687446, 637.1207733328071, 646.5571083807513, 493.4399456616766, 562.353293712226, 598.4075764992626, 599.6881621556767, 604.6311749832598, 7761.534307941542, 7791.532508049535, 8345.95827020861, 8419.410641255054, 8532.8720069199, 541.2206422751226, 591.7692100080972, 604.3322129047427, 609.6189881324173, 614.4132353602299, 3755.6432138946757, 3845.3270677631863, 4018.598140185958, 4133.855315064018, 4140.668746500272, 94745.0, 96563.06873862483, 98087.0, 99348.50325748324, 99499.00500994897, 80352.59823700962, 81888.0, 82603.5869820647, 84790.15209847823, 84828.7275690865, 67308.99036514456, 68074.0, 69691.30308696849, 69944.0, 74386.0, 8069.677212911418, 8242.711505097293, 8281.17188281167, 8290.269268984319, 8356.03905550845, 40597.1880562393, 40679.7966010168, 42364.0, 42860.35709464298, 47378.0, 45394.0, 53166.0, 57813.0, 61045.77908441895, 64340.817846839804, 11019.063379612502, 11059.285810699355, 11144.832827507431, 11288.2662626929, 11357.375344355809, 11978.76042479128, 11978.880211198126, 11999.280043197436, 12047.397630118634, 12528.248305101723, 11516.94241528787, 11573.88426115728, 11630.76738465209, 11884.702882428008, 11945.2832830027, 11299.3785341807, 11374.943125284652, 11464.713382165513, 11636.0, 11639.59261425846, 11242.325460472397, 11398.316101033965, 11514.94242528782, 11724.472398741906, 11834.230774999598, 9351.812963740824, 9531.0, 9857.852132218166, 9973.30186886911, 10060.346077504939, 7840.607969601383, 8556.57217139153, 9437.339386242897, 9608.231341492525, 9785.07041831025], "value": 6878.483382834985, "unit": "score"}], "name": "dromaeo_css", "value": 7259.164660844354, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 110.0, 111.0, 113.0, 113.0, 113.0, 117.0, 111.0, 111.0, 111.0], "value": 112.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 122.0, 145.0, 192.0, 153.0, 172.0, 139.0, 119.0, 170.0, 116.0], "value": 148.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 187.0, 186.0, 180.0, 186.0, 182.0, 183.0, 184.0, 184.0, 186.0], "value": 184.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 72.0, 86.0, 90.0, 56.0, 60.0, 58.0, 80.0, 80.0, 46.0], "value": 70.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 95.0, 139.0, 80.0, 79.0, 81.0, 126.0, 79.0, 80.0, 125.0], "value": 97.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 88.0, 84.0, 86.0, 84.0, 89.0, 131.0, 128.0, 85.0], "value": 94.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 94.0, 93.0, 94.0, 94.0, 93.0, 93.0, 94.0], "value": 93.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 89.0, 89.0, 89.0, 90.0, 89.0, 89.0, 89.0, 90.0, 84.0], "value": 88.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 58.0, 71.0, 85.0, 90.0, 90.0, 75.0, 75.0, 60.0, 90.0], "value": 75.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 67.0, 75.0, 73.0, 69.0, 66.0, 68.0, 67.0, 66.0], "value": 67.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 82.0, 82.0, 81.0, 82.0, 78.0, 80.0, 84.0, 83.0], "value": 81.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 142.0, 154.0, 158.0, 139.0, 136.0, 124.0, 149.0, 238.0, 177.0], "value": 155.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 172.0, 172.0, 171.0, 171.0, 210.0, 170.0, 172.0, 170.0, 171.0], "value": 174.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 70.0, 69.0, 68.0, 70.0, 70.0, 69.0, 69.0, 68.0, 67.0], "value": 69.1, "unit": "score"}], "name": "kraken", "value": 1514.1999999999996, "alertThreshold": 2.0}]} 23:15:19 INFO - Return code: 0 23:15:19 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 23:15:19 ERROR - # TBPL SUCCESS # 23:15:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:15:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:15:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:15:19 INFO - 2016-04-21 23:15:19.475 screenresolution[1678:9580] starting screenresolution argv=screenresolution get 23:15:19 INFO - 2016-04-21 23:15:19.478 screenresolution[1678:9580] Display 0: 1600x1200x32@60 23:15:19 INFO - 2016-04-21 23:15:19.484 screenresolution[1679:9583] starting screenresolution argv=screenresolution list 23:15:19 INFO - Available Modes on Display 0 23:15:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:15:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:15:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:15:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:15:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:15:19 INFO - Intel Iris: 23:15:19 INFO - Chipset Model: Intel Iris 23:15:19 INFO - Type: GPU 23:15:19 INFO - Bus: Built-In 23:15:19 INFO - VRAM (Dynamic, Max): 1536 MB 23:15:19 INFO - Vendor: Intel (0x8086) 23:15:19 INFO - Device ID: 0x0a2e 23:15:19 INFO - Revision ID: 0x0009 23:15:19 INFO - Displays: 23:15:19 INFO - Display: 23:15:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:15:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:15:19 INFO - Display Serial Number: 1600x1200 60 23:15:19 INFO - Main Display: Yes 23:15:19 INFO - Mirror: Off 23:15:19 INFO - Online: Yes 23:15:19 INFO - Rotation: Supported 23:15:19 INFO - Return code: 0 23:15:19 INFO - Running post-action listener: _resource_record_post_action 23:15:19 INFO - Running post-run listener: _resource_record_post_run 23:15:20 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 37942784; Write bytes: 691204096; Read time: 399; Write time: 1927 23:15:20 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 395772416; Write bytes: 396707840; Read time: 29617; Write time: 689 23:15:20 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 36115968; Write bytes: 293648384; Read time: 379; Write time: 1236 23:15:20 INFO - Running post-run listener: _upload_blobber_files 23:15:20 INFO - Blob upload gear active. 23:15:20 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:15:20 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 23:15:20 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'] 23:15:20 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 23:15:21 INFO - (blobuploader) - INFO - Open directory for files ... 23:15:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:15:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:15:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:15:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:15:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:15:22 INFO - (blobuploader) - INFO - Done attempting. 23:15:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:15:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:15:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:15:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:15:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:15:23 INFO - (blobuploader) - INFO - Done attempting. 23:15:23 INFO - (blobuploader) - INFO - Iteration through files over. 23:15:23 INFO - Return code: 0 23:15:23 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:15:23 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:15:23 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c3714976422d6fe7fe7caabaf428b5c08e8acf59836d0ade7d519c163fc968f6d98a67a2236bf831f6e919f9314b50bd8b4390051ba1131fa0b2ce12b2db03e4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0970f63f952d0a30df0589ec95d28853254bb5874c42de6e10962811835623b12d2ad5bc4e04a6c6e0f6f07d0f30f13f054de98011f8d2d5f42c7a981ebcb6e7"} 23:15:23 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:15:23 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:15:23 INFO - Contents: 23:15:23 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c3714976422d6fe7fe7caabaf428b5c08e8acf59836d0ade7d519c163fc968f6d98a67a2236bf831f6e919f9314b50bd8b4390051ba1131fa0b2ce12b2db03e4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0970f63f952d0a30df0589ec95d28853254bb5874c42de6e10962811835623b12d2ad5bc4e04a6c6e0f6f07d0f30f13f054de98011f8d2d5f42c7a981ebcb6e7"} 23:15:23 INFO - Running post-run listener: copy_logs_to_upload_dir 23:15:23 INFO - Copying logs to upload dir... 23:15:23 INFO - mkdir: /builds/slave/test/build/upload/logs 23:15:23 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1267.264509 ========= master_lag: 3.34 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-04-21 23:15:26.264420) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-21 23:15:26.268380) ========= 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.7anYwbXRF5/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.LMUoDmT7Of/Listeners TMPDIR=/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c3714976422d6fe7fe7caabaf428b5c08e8acf59836d0ade7d519c163fc968f6d98a67a2236bf831f6e919f9314b50bd8b4390051ba1131fa0b2ce12b2db03e4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0970f63f952d0a30df0589ec95d28853254bb5874c42de6e10962811835623b12d2ad5bc4e04a6c6e0f6f07d0f30f13f054de98011f8d2d5f42c7a981ebcb6e7"} build_url:https://queue.taskcluster.net/v1/task/MQrQ9wvwQG2CG1zlHY4pbg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011090 build_url: 'https://queue.taskcluster.net/v1/task/MQrQ9wvwQG2CG1zlHY4pbg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c3714976422d6fe7fe7caabaf428b5c08e8acf59836d0ade7d519c163fc968f6d98a67a2236bf831f6e919f9314b50bd8b4390051ba1131fa0b2ce12b2db03e4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0970f63f952d0a30df0589ec95d28853254bb5874c42de6e10962811835623b12d2ad5bc4e04a6c6e0f6f07d0f30f13f054de98011f8d2d5f42c7a981ebcb6e7"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-21 23:15:26.306998) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-21 23:15:26.307473) ========= 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.7anYwbXRF5/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.LMUoDmT7Of/Listeners TMPDIR=/var/folders/xw/1xk600bn7yzb_vgtx1q14h5000000w/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.005071 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-21 23:15:26.363476) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-21 23:15:26.363813) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-21 23:15:26.364232) ========= ========= Total master_lag: 3.47 =========