builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0083 starttime: 1468893768.46 results: success (0) buildid: 20160718155219 builduid: 4067c172f185448bb79ac3227fd8d75d revision: 62bb8900cfef139ac0ea2f9bc4db742166d6f9a5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-18 19:02:48.463992) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-18 19:02:48.464453) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-18 19:02:48.464726) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-18 19:02:48.605152) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 19:02:48.605414) ========= 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.m8C0zbtCSK/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.qe29MHCqtW/Listeners TMPDIR=/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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-07-18 19:02:48-- 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% 5.79M=0.002s 2016-07-18 19:02:48 (5.79 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.144879 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 19:02:48.771785) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 19:02:48.772085) ========= 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.m8C0zbtCSK/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.qe29MHCqtW/Listeners TMPDIR=/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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.020362 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 19:02:48.825652) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-18 19:02:48.826020) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 62bb8900cfef139ac0ea2f9bc4db742166d6f9a5 --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 62bb8900cfef139ac0ea2f9bc4db742166d6f9a5 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.m8C0zbtCSK/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.qe29MHCqtW/Listeners TMPDIR=/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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-07-18 19:02:48,899 truncating revision to first 12 chars 2016-07-18 19:02:48,899 Setting DEBUG logging. 2016-07-18 19:02:48,899 attempt 1/10 2016-07-18 19:02:48,899 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/62bb8900cfef?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-18 19:02:49,893 unpacking tar archive at: fx-team-62bb8900cfef/testing/mozharness/ program finished with exit code 0 elapsedTime=1.271902 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-18 19:02:50.119175) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-18 19:02:50.119453) ========= script_repo_revision: 62bb8900cfef139ac0ea2f9bc4db742166d6f9a5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-18 19:02:50.119843) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-18 19:02:50.120132) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-18 19:02:50.137794) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-07-18 19:02:50.138071) ========= /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.m8C0zbtCSK/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.qe29MHCqtW/Listeners TMPDIR=/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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 19:02:50 INFO - MultiFileLogger online at 20160718 19:02:50 in /builds/slave/test 19:02:50 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 19:02:50 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:02:50 INFO - {'append_to_log': False, 19:02:50 INFO - 'base_work_dir': '/builds/slave/test', 19:02:50 INFO - 'blob_upload_branch': 'Fx-Team', 19:02:50 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:02:50 INFO - 'branch': 'Fx-Team', 19:02:50 INFO - 'buildbot_json_path': 'buildprops.json', 19:02:50 INFO - 'config_files': ('talos/mac_config.py',), 19:02:50 INFO - 'default_actions': ('clobber', 19:02:50 INFO - 'read-buildbot-config', 19:02:50 INFO - 'download-and-extract', 19:02:50 INFO - 'populate-webroot', 19:02:50 INFO - 'create-virtualenv', 19:02:50 INFO - 'install', 19:02:50 INFO - 'run-tests'), 19:02:50 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:02:50 INFO - 'download_minidump_stackwalk': True, 19:02:50 INFO - 'download_symbols': 'ondemand', 19:02:50 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:02:50 INFO - 'tooltool.py': '/tools/tooltool.py', 19:02:50 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:02:50 INFO - '/tools/misc-python/virtualenv.py')}, 19:02:50 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:02:50 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:02:50 INFO - 'installer_path': 'installer.exe', 19:02:50 INFO - 'log_level': 'info', 19:02:50 INFO - 'log_name': 'talos', 19:02:50 INFO - 'log_to_console': True, 19:02:50 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:02:50 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:02:50 INFO - 'opt_config_files': (), 19:02:50 INFO - 'pip_index': False, 19:02:50 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:02:50 INFO - 'cmd': ('bash', 19:02:50 INFO - '-c', 19:02:50 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:02:50 INFO - 'enabled': True, 19:02:50 INFO - 'halt_on_failure': False, 19:02:50 INFO - 'name': 'check_screen_resolution'},), 19:02:50 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:02:50 INFO - 'cmd': ('bash', 19:02:50 INFO - '-c', 19:02:50 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:02:50 INFO - 'enabled': True, 19:02:50 INFO - 'halt_on_failure': False, 19:02:50 INFO - 'name': 'check_screen_resolution'},), 19:02:50 INFO - 'run_cmd_checks_enabled': True, 19:02:50 INFO - 'sps_profile': False, 19:02:50 INFO - 'sps_profile_interval': 0, 19:02:50 INFO - 'suite': 'dromaeojs', 19:02:50 INFO - 'system_bits': '32', 19:02:50 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:02:50 INFO - 'title': 't-yosemite-r7-0083', 19:02:50 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:02:50 INFO - 'use_talos_json': True, 19:02:50 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:02:50 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:02:50 INFO - 'work_dir': 'build'} 19:02:50 INFO - ##### 19:02:50 INFO - ##### Running clobber step. 19:02:50 INFO - ##### 19:02:50 INFO - Running pre-action listener: _resource_record_pre_action 19:02:50 INFO - Running main action method: clobber 19:02:50 INFO - rmtree: /builds/slave/test/build 19:02:50 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:02:51 INFO - Running post-action listener: _resource_record_post_action 19:02:51 INFO - ##### 19:02:51 INFO - ##### Finished clobber step (success) 19:02:51 INFO - ##### 19:02:51 INFO - ##### 19:02:51 INFO - ##### Running read-buildbot-config step. 19:02:51 INFO - ##### 19:02:51 INFO - Running pre-action listener: _resource_record_pre_action 19:02:51 INFO - Running main action method: read_buildbot_config 19:02:51 INFO - Using buildbot properties: 19:02:51 INFO - { 19:02:51 INFO - "project": "", 19:02:51 INFO - "product": "firefox", 19:02:51 INFO - "script_repo_revision": "production", 19:02:51 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 19:02:51 INFO - "repository": "", 19:02:51 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 19:02:51 INFO - "buildid": "20160718155219", 19:02:51 INFO - "pgo_build": "False", 19:02:51 INFO - "basedir": "/builds/slave/test", 19:02:51 INFO - "buildnumber": 112, 19:02:51 INFO - "slavename": "t-yosemite-r7-0083", 19:02:51 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 19:02:51 INFO - "platform": "yosemite_r7", 19:02:51 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 19:02:51 INFO - "branch": "fx-team", 19:02:51 INFO - "repo_path": "integration/fx-team", 19:02:51 INFO - "revision": "62bb8900cfef139ac0ea2f9bc4db742166d6f9a5", 19:02:51 INFO - "stage_platform": "macosx64", 19:02:51 INFO - "builduid": "4067c172f185448bb79ac3227fd8d75d", 19:02:51 INFO - "slavebuilddir": "test" 19:02:51 INFO - } 19:02:51 INFO - Found installer url https://queue.taskcluster.net/v1/task/WFwGERC8Reqp_v0iQbVGkg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 19:02:51 INFO - Running post-action listener: _resource_record_post_action 19:02:51 INFO - ##### 19:02:51 INFO - ##### Finished read-buildbot-config step (success) 19:02:51 INFO - ##### 19:02:51 INFO - ##### 19:02:51 INFO - ##### Running download-and-extract step. 19:02:51 INFO - ##### 19:02:51 INFO - Running pre-action listener: _resource_record_pre_action 19:02:51 INFO - Running main action method: download_and_extract 19:02:51 INFO - mkdir: /builds/slave/test/build/tests 19:02:51 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')]} 19:02:51 INFO - https://queue.taskcluster.net/v1/task/WFwGERC8Reqp_v0iQbVGkg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 19:02:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WFwGERC8Reqp_v0iQbVGkg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 19:02:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WFwGERC8Reqp_v0iQbVGkg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 19:02:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WFwGERC8Reqp_v0iQbVGkg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 19:02:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WFwGERC8Reqp_v0iQbVGkg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json'}, attempt #1 19:02:58 INFO - Downloaded 1199 bytes. 19:02:58 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 19:02:58 INFO - Using the following test package requirements: 19:02:58 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 19:02:58 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 19:02:58 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 19:02:58 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 19:02:58 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 19:02:58 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 19:02:58 INFO - u'jsshell-mac.zip'], 19:02:58 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 19:02:58 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 19:02:58 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 19:02:58 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 19:02:58 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 19:02:58 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 19:02:58 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 19:02:58 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 19:02:58 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 19:02:58 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 19:02:58 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 19:02:58 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 19:02:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:02:58 INFO - https://queue.taskcluster.net/v1/task/WFwGERC8Reqp_v0iQbVGkg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 19:02:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WFwGERC8Reqp_v0iQbVGkg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 19:02:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WFwGERC8Reqp_v0iQbVGkg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 19:02:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WFwGERC8Reqp_v0iQbVGkg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 19:02:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WFwGERC8Reqp_v0iQbVGkg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:03:00 INFO - Downloaded 18835419 bytes. 19:03:00 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 19:03:00 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 19:03:03 INFO - Return code: 0 19:03:03 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip', u'firefox-50.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 19:03: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')]} 19:03:03 INFO - https://queue.taskcluster.net/v1/task/WFwGERC8Reqp_v0iQbVGkg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 19:03:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WFwGERC8Reqp_v0iQbVGkg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 19:03:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WFwGERC8Reqp_v0iQbVGkg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 19:03:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WFwGERC8Reqp_v0iQbVGkg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 19:03:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WFwGERC8Reqp_v0iQbVGkg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:03:05 INFO - Downloaded 18835419 bytes. 19:03:05 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 19:03:05 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 19:03:09 INFO - Return code: 0 19:03:09 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')]} 19:03:09 INFO - https://queue.taskcluster.net/v1/task/WFwGERC8Reqp_v0iQbVGkg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 19:03:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WFwGERC8Reqp_v0iQbVGkg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 19:03:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WFwGERC8Reqp_v0iQbVGkg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 19:03:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WFwGERC8Reqp_v0iQbVGkg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 19:03:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WFwGERC8Reqp_v0iQbVGkg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip'}, attempt #1 19:03:10 INFO - Downloaded 14403317 bytes. 19:03:10 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 19:03:10 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 19:03:11 INFO - Return code: 0 19:03:11 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')]} 19:03:11 INFO - https://queue.taskcluster.net/v1/task/WFwGERC8Reqp_v0iQbVGkg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 19:03:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WFwGERC8Reqp_v0iQbVGkg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 19:03:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WFwGERC8Reqp_v0iQbVGkg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 19:03:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WFwGERC8Reqp_v0iQbVGkg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 19:03:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WFwGERC8Reqp_v0iQbVGkg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 19:03:15 INFO - Downloaded 112869047 bytes. 19:03:15 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WFwGERC8Reqp_v0iQbVGkg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 19:03:15 INFO - mkdir: /builds/slave/test/properties 19:03:15 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:03:15 INFO - Writing to file /builds/slave/test/properties/build_url 19:03:15 INFO - Contents: 19:03:15 INFO - build_url:https://queue.taskcluster.net/v1/task/WFwGERC8Reqp_v0iQbVGkg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 19:03:16 INFO - Running post-action listener: _resource_record_post_action 19:03:16 INFO - Running post-action listener: set_extra_try_arguments 19:03:16 INFO - ##### 19:03:16 INFO - ##### Finished download-and-extract step (success) 19:03:16 INFO - ##### 19:03:16 INFO - ##### 19:03:16 INFO - ##### Running populate-webroot step. 19:03:16 INFO - ##### 19:03:16 INFO - Running pre-action listener: _resource_record_pre_action 19:03:16 INFO - Running main action method: populate_webroot 19:03:16 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:03:16 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:03:16 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:03:16 INFO - '--tppagecycles', 19:03:16 INFO - '7'], 19:03:16 INFO - 'tests': ['tsvgm']}}, 19:03:16 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:03:16 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:03:16 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:03:16 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:03:16 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:03:16 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:03:16 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:03:16 INFO - 'talos_options': ['--disable-e10s'], 19:03:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:03:16 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:03:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:03:16 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:03:16 INFO - 'talos_options': ['--disable-e10s'], 19:03:16 INFO - 'tests': ['damp', 'tps']}, 19:03:16 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:03:16 INFO - 'tests': ['damp', 'tps']}, 19:03:16 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:03:16 INFO - 'tests': ['dromaeo_dom']}, 19:03:16 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:03:16 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:03:16 INFO - 'tests': ['basic_compositor_video']}, 19:03:16 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:03:16 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:03:16 INFO - 'tests': ['a11yr', 19:03:16 INFO - 'ts_paint', 19:03:16 INFO - 'tpaint', 19:03:16 INFO - 'sessionrestore', 19:03:16 INFO - 'sessionrestore_no_auto_restore', 19:03:16 INFO - 'tabpaint']}, 19:03:16 INFO - 'other-e10s': {'tests': ['a11yr', 19:03:16 INFO - 'ts_paint', 19:03:16 INFO - 'tpaint', 19:03:16 INFO - 'sessionrestore', 19:03:16 INFO - 'sessionrestore_no_auto_restore', 19:03:16 INFO - 'tabpaint']}, 19:03:16 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:03:16 INFO - 'ts_paint', 19:03:16 INFO - 'tpaint', 19:03:16 INFO - 'sessionrestore', 19:03:16 INFO - 'sessionrestore_no_auto_restore', 19:03:16 INFO - 'tabpaint']}, 19:03:16 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:03:16 INFO - 'ts_paint', 19:03:16 INFO - 'tpaint', 19:03:16 INFO - 'sessionrestore', 19:03:16 INFO - 'sessionrestore_no_auto_restore', 19:03:16 INFO - 'tabpaint']}, 19:03:16 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:03:16 INFO - 'tests': ['a11yr', 19:03:16 INFO - 'ts_paint', 19:03:16 INFO - 'tpaint', 19:03:16 INFO - 'sessionrestore', 19:03:16 INFO - 'sessionrestore_no_auto_restore', 19:03:16 INFO - 'tabpaint']}, 19:03:16 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:03:16 INFO - 'tests': ['a11yr', 19:03:16 INFO - 'ts_paint', 19:03:16 INFO - 'tpaint', 19:03:16 INFO - 'sessionrestore', 19:03:16 INFO - 'sessionrestore_no_auto_restore', 19:03:16 INFO - 'tabpaint']}, 19:03:16 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:03:16 INFO - 'tests': ['tsvgx', 19:03:16 INFO - 'tsvgr_opacity', 19:03:16 INFO - 'tart', 19:03:16 INFO - 'tscrollx', 19:03:16 INFO - 'cart']}, 19:03:16 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:03:16 INFO - 'tsvgr_opacity', 19:03:16 INFO - 'tart', 19:03:16 INFO - 'tscrollx', 19:03:16 INFO - 'cart']}, 19:03:16 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:03:16 INFO - 'talos_options': ['--disable-e10s'], 19:03:16 INFO - 'tests': ['tp5o']}, 19:03:16 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:03:16 INFO - 'tests': ['tp5o']}, 19:03:16 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:03:16 INFO - 'talos_options': ['--disable-e10s', 19:03:16 INFO - '--xperf_path', 19:03:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:03:16 INFO - 'tests': ['tp5n']}, 19:03:16 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:03:16 INFO - 'talos_options': ['--xperf_path', 19:03:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:03:16 INFO - 'tests': ['tp5n']}}, 19:03:16 INFO - 'talos.zip': {'path': '', 19:03:16 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:03:16 INFO - Running post-action listener: _resource_record_post_action 19:03:16 INFO - ##### 19:03:16 INFO - ##### Finished populate-webroot step (success) 19:03:16 INFO - ##### 19:03:16 INFO - ##### 19:03:16 INFO - ##### Running create-virtualenv step. 19:03:16 INFO - ##### 19:03:16 INFO - Running pre-action listener: _resource_record_pre_action 19:03:16 INFO - Running main action method: create_virtualenv 19:03:16 INFO - Creating virtualenv /builds/slave/test/build/venv 19:03:16 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 19:03:16 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:03:16 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:03:16 INFO - Using real prefix '/tools/python27' 19:03:16 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:03:17 INFO - Installing distribute.............................................................................................................................................................................................done. 19:03:20 INFO - Installing pip.................done. 19:03:20 INFO - Return code: 0 19:03:20 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:03:20 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')]} 19:03:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:03:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:03:20 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')]} 19:03:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:03:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:03:20 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 0x101d8ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e20618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcde14d9f20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101cefd30>, '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 0x101a54db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ce7b20>, '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.m8C0zbtCSK/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.qe29MHCqtW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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 19:03:20 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 19:03:20 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 19:03:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m8C0zbtCSK/Render', 19:03:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:03:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:03:20 INFO - 'HOME': '/Users/cltbld', 19:03:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:03:20 INFO - 'LOGNAME': 'cltbld', 19:03:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:03:20 INFO - 'MOZ_NO_REMOTE': '1', 19:03:20 INFO - 'NO_EM_RESTART': '1', 19:03:20 INFO - 'PAGER': '/bin/cat', 19:03:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:03:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:03:20 INFO - 'PWD': '/builds/slave/test', 19:03:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:03:20 INFO - 'SHELL': '/bin/bash', 19:03:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qe29MHCqtW/Listeners', 19:03:20 INFO - 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/', 19:03:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:03:20 INFO - 'USER': 'cltbld', 19:03:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:03:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:03:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:03:20 INFO - 'XPC_FLAGS': '0x0', 19:03:20 INFO - 'XPC_SERVICE_NAME': '0', 19:03:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:03:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:03:20 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 19:03:20 INFO - Cleaning up... 19:03:20 INFO - Return code: 0 19:03:20 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:03:20 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')]} 19:03:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:03:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:03:20 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')]} 19:03:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:03:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:03:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101d8ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e20618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcde14d9f20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101cefd30>, '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 0x101a54db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ce7b20>, '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.m8C0zbtCSK/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.qe29MHCqtW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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 19:03:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 19:03:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 19:03:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m8C0zbtCSK/Render', 19:03:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:03:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:03:20 INFO - 'HOME': '/Users/cltbld', 19:03:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:03:20 INFO - 'LOGNAME': 'cltbld', 19:03:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:03:20 INFO - 'MOZ_NO_REMOTE': '1', 19:03:20 INFO - 'NO_EM_RESTART': '1', 19:03:20 INFO - 'PAGER': '/bin/cat', 19:03:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:03:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:03:20 INFO - 'PWD': '/builds/slave/test', 19:03:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:03:20 INFO - 'SHELL': '/bin/bash', 19:03:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qe29MHCqtW/Listeners', 19:03:20 INFO - 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/', 19:03:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:03:20 INFO - 'USER': 'cltbld', 19:03:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:03:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:03:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:03:20 INFO - 'XPC_FLAGS': '0x0', 19:03:20 INFO - 'XPC_SERVICE_NAME': '0', 19:03:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:03:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:03:20 INFO - Downloading/unpacking psutil>=3.1.1 19:03:20 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 19:03:20 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 19:03:20 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 19:03:20 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 19:03:20 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 19:03:20 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 19:03:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:03:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:03:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:03:24 INFO - Installing collected packages: psutil 19:03:24 INFO - Running setup.py install for psutil 19:03:24 INFO - building 'psutil._psutil_osx' extension 19:03:24 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 19:03:24 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 19:03:24 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 19:03:24 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 19:03:24 INFO - building 'psutil._psutil_posix' extension 19:03:24 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 19:03:24 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:03:24 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:03:24 INFO - ^ 19:03:24 INFO - 1 warning generated. 19:03:24 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 19:03:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:03:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:03:25 INFO - Successfully installed psutil 19:03:25 INFO - Cleaning up... 19:03:25 INFO - Return code: 0 19:03:25 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:03:25 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')]} 19:03:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:03:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:03:25 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')]} 19:03:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:03:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:03:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101d8ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e20618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcde14d9f20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101cefd30>, '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 0x101a54db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ce7b20>, '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.m8C0zbtCSK/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.qe29MHCqtW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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 19:03:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 19:03:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 19:03:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m8C0zbtCSK/Render', 19:03:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:03:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:03:25 INFO - 'HOME': '/Users/cltbld', 19:03:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:03:25 INFO - 'LOGNAME': 'cltbld', 19:03:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:03:25 INFO - 'MOZ_NO_REMOTE': '1', 19:03:25 INFO - 'NO_EM_RESTART': '1', 19:03:25 INFO - 'PAGER': '/bin/cat', 19:03:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:03:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:03:25 INFO - 'PWD': '/builds/slave/test', 19:03:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:03:25 INFO - 'SHELL': '/bin/bash', 19:03:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qe29MHCqtW/Listeners', 19:03:25 INFO - 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/', 19:03:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:03:25 INFO - 'USER': 'cltbld', 19:03:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:03:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:03:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:03:25 INFO - 'XPC_FLAGS': '0x0', 19:03:25 INFO - 'XPC_SERVICE_NAME': '0', 19:03:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:03:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:03:25 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:03:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:03:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:03:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:03:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:03:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:03:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:03:27 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:03:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:03:27 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:03:27 INFO - Installing collected packages: mozsystemmonitor 19:03:27 INFO - Running setup.py install for mozsystemmonitor 19:03:28 INFO - Successfully installed mozsystemmonitor 19:03:28 INFO - Cleaning up... 19:03:28 INFO - Return code: 0 19:03:28 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:03: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')]} 19:03:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:03:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:03: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')]} 19:03:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:03:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:03:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101d8ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e20618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcde14d9f20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101cefd30>, '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 0x101a54db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ce7b20>, '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.m8C0zbtCSK/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.qe29MHCqtW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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 19:03:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 19:03:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 19:03:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m8C0zbtCSK/Render', 19:03:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:03:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:03:28 INFO - 'HOME': '/Users/cltbld', 19:03:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:03:28 INFO - 'LOGNAME': 'cltbld', 19:03:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:03:28 INFO - 'MOZ_NO_REMOTE': '1', 19:03:28 INFO - 'NO_EM_RESTART': '1', 19:03:28 INFO - 'PAGER': '/bin/cat', 19:03:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:03:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:03:28 INFO - 'PWD': '/builds/slave/test', 19:03:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:03:28 INFO - 'SHELL': '/bin/bash', 19:03:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qe29MHCqtW/Listeners', 19:03:28 INFO - 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/', 19:03:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:03:28 INFO - 'USER': 'cltbld', 19:03:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:03:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:03:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:03:28 INFO - 'XPC_FLAGS': '0x0', 19:03:28 INFO - 'XPC_SERVICE_NAME': '0', 19:03:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:03:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:03:28 INFO - Downloading/unpacking jsonschema==2.5.1 19:03:28 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 19:03:28 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 19:03:28 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 19:03:28 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 19:03:28 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 19:03:28 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 19:03:30 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:03:30 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:03:30 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 19:03:30 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 19:03:30 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 19:03:30 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 19:03:30 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 19:03:30 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 19:03:31 INFO - Downloading functools32-3.2.3-2.tar.gz 19:03:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:03:31 INFO - warning: no files found matching '*.txt' 19:03:31 INFO - no previously-included directories found matching 'build' 19:03:31 INFO - no previously-included directories found matching 'dist' 19:03:31 INFO - no previously-included directories found matching '.git*' 19:03:31 INFO - Installing collected packages: jsonschema, functools32 19:03:31 INFO - Running setup.py install for functools32 19:03:31 INFO - warning: no files found matching '*.txt' 19:03:31 INFO - no previously-included directories found matching 'build' 19:03:31 INFO - no previously-included directories found matching 'dist' 19:03:31 INFO - no previously-included directories found matching '.git*' 19:03:31 INFO - Successfully installed jsonschema functools32 19:03:31 INFO - Cleaning up... 19:03:31 INFO - Return code: 0 19:03:31 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:03:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:03:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:03:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:03:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:03:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:03:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:03:31 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 0x101d8ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e20618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcde14d9f20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101cefd30>, '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 0x101a54db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ce7b20>, '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.m8C0zbtCSK/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.qe29MHCqtW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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 19:03:31 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 19:03:31 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 19:03:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m8C0zbtCSK/Render', 19:03:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:03:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:03:31 INFO - 'HOME': '/Users/cltbld', 19:03:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:03:31 INFO - 'LOGNAME': 'cltbld', 19:03:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:03:31 INFO - 'MOZ_NO_REMOTE': '1', 19:03:31 INFO - 'NO_EM_RESTART': '1', 19:03:31 INFO - 'PAGER': '/bin/cat', 19:03:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:03:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:03:31 INFO - 'PWD': '/builds/slave/test', 19:03:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:03:31 INFO - 'SHELL': '/bin/bash', 19:03:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qe29MHCqtW/Listeners', 19:03:31 INFO - 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/', 19:03:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:03:31 INFO - 'USER': 'cltbld', 19:03:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:03:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:03:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:03:31 INFO - 'XPC_FLAGS': '0x0', 19:03:31 INFO - 'XPC_SERVICE_NAME': '0', 19:03:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:03:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:03:31 INFO - Downloading/unpacking blobuploader==1.2.4 19:03:31 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 19:03:31 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 19:03:31 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 19:03:31 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 19:03:31 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 19:03:31 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 19:03:34 INFO - Downloading blobuploader-1.2.4.tar.gz 19:03:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:03:34 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:03:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:03:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:03:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:03:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:03:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:03:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:03:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:03:35 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:03:35 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 19:03:35 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 19:03:35 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 19:03:35 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 19:03:35 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 19:03:35 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 19:03:35 INFO - Downloading docopt-0.6.1.tar.gz 19:03:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:03:35 INFO - Installing collected packages: blobuploader, requests, docopt 19:03:35 INFO - Running setup.py install for blobuploader 19:03:35 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:03:35 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:03:35 INFO - Running setup.py install for requests 19:03:36 INFO - Running setup.py install for docopt 19:03:36 INFO - Successfully installed blobuploader requests docopt 19:03:36 INFO - Cleaning up... 19:03:36 INFO - Return code: 0 19:03:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:03:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:03:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:03:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:03:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:03:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:03:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:03:36 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 0x101d8ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e20618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcde14d9f20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101cefd30>, '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 0x101a54db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ce7b20>, '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.m8C0zbtCSK/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.qe29MHCqtW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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 19:03:36 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 19:03:36 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 19:03:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m8C0zbtCSK/Render', 19:03:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:03:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:03:36 INFO - 'HOME': '/Users/cltbld', 19:03:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:03:36 INFO - 'LOGNAME': 'cltbld', 19:03:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:03:36 INFO - 'MOZ_NO_REMOTE': '1', 19:03:36 INFO - 'NO_EM_RESTART': '1', 19:03:36 INFO - 'PAGER': '/bin/cat', 19:03:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:03:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:03:36 INFO - 'PWD': '/builds/slave/test', 19:03:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:03:36 INFO - 'SHELL': '/bin/bash', 19:03:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qe29MHCqtW/Listeners', 19:03:36 INFO - 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/', 19:03:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:03:36 INFO - 'USER': 'cltbld', 19:03:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:03:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:03:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:03:36 INFO - 'XPC_FLAGS': '0x0', 19:03:36 INFO - 'XPC_SERVICE_NAME': '0', 19:03:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:03:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:03:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:03:36 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-8fLTl8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:03:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:03:36 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-sg6XJN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:03:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:03:36 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-e_muNv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:03:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:03:37 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-uqQ8TL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:03:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:03:37 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-sFb7UB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:03:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:03:37 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-O5s2p2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:03:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:03:37 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-V4aRNo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:03:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:03:37 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-7LXs8Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:03:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:03:37 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-rCH3_L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:03:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:03:37 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-gUbDva-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:03:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:03:37 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-twx189-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:03:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:03:37 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-2Cv9d7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:03:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:03:37 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-Lo0bN6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:03:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:03:38 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-nUsDti-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:03:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:03:38 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-LpLGJx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:03:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:03:38 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-lZh3_k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:03:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:03:38 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-ZoklRa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:03:38 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:03:38 INFO - Running setup.py install for manifestparser 19:03:38 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:03:38 INFO - Running setup.py install for mozcrash 19:03:38 INFO - Running setup.py install for mozdebug 19:03:38 INFO - Running setup.py install for mozdevice 19:03:39 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:03:39 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:03:39 INFO - Running setup.py install for mozfile 19:03:39 INFO - Running setup.py install for mozhttpd 19:03:39 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:03:39 INFO - Running setup.py install for mozinfo 19:03:39 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:03:39 INFO - Running setup.py install for mozInstall 19:03:39 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:03:39 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:03:39 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:03:39 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:03:39 INFO - Running setup.py install for mozleak 19:03:39 INFO - Running setup.py install for mozlog 19:03:39 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:03:39 INFO - Running setup.py install for moznetwork 19:03:40 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:03:40 INFO - Running setup.py install for mozprocess 19:03:40 INFO - Running setup.py install for mozprofile 19:03:40 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:03:40 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:03:40 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:03:40 INFO - Running setup.py install for mozrunner 19:03:40 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:03:40 INFO - Running setup.py install for mozscreenshot 19:03:40 INFO - Running setup.py install for moztest 19:03:40 INFO - Running setup.py install for mozversion 19:03:40 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:03:40 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:03:40 INFO - Cleaning up... 19:03:41 INFO - Return code: 0 19:03:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:03:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:03:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:03:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:03:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:03:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:03:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:03:41 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 0x101d8ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e20618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcde14d9f20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101cefd30>, '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 0x101a54db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ce7b20>, '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.m8C0zbtCSK/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.qe29MHCqtW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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 19:03:41 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 19:03:41 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 19:03:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m8C0zbtCSK/Render', 19:03:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:03:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:03:41 INFO - 'HOME': '/Users/cltbld', 19:03:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:03:41 INFO - 'LOGNAME': 'cltbld', 19:03:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:03:41 INFO - 'MOZ_NO_REMOTE': '1', 19:03:41 INFO - 'NO_EM_RESTART': '1', 19:03:41 INFO - 'PAGER': '/bin/cat', 19:03:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:03:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:03:41 INFO - 'PWD': '/builds/slave/test', 19:03:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:03:41 INFO - 'SHELL': '/bin/bash', 19:03:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qe29MHCqtW/Listeners', 19:03:41 INFO - 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/', 19:03:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:03:41 INFO - 'USER': 'cltbld', 19:03:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:03:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:03:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:03:41 INFO - 'XPC_FLAGS': '0x0', 19:03:41 INFO - 'XPC_SERVICE_NAME': '0', 19:03:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:03:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:03:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:03:41 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-onvXdG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:03:41 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)) 19:03:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:03:41 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-LV9x07-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:03:41 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)) 19:03:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:03:41 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-D1FBTZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:03:41 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)) 19:03:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:03:41 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-ilQ_B9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:03:41 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)) 19:03:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:03:41 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-OlxYek-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:03:41 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)) 19:03:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:03:41 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-Pk0SJ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:03:41 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)) 19:03:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:03:41 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-zYUZpN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:03:42 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)) 19:03:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:03:42 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-FO76tG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:03:42 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)) 19:03:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:03:42 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-2lBQAJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:03:42 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)) 19:03:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:03:42 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-A4Drpm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:03:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:03:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:03:42 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-JnwzF5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:03:42 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)) 19:03:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:03:42 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-x1C85w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:03:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 19:03:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:03:42 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-frsyB5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:03:42 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)) 19:03:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:03:42 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-Z7A7rm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:03:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 19:03:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:03:42 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-cgOZJG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:03:42 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)) 19:03:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:03:42 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-Gq_gMt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:03:42 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)) 19:03:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:03:42 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-WO9sRZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:03:43 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)) 19:03:43 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)) 19:03:43 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)) 19:03:43 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)) 19:03:43 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)) 19:03:43 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)) 19:03:43 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:03:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:03:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:03:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:03:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:03:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:03:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:03:45 INFO - Downloading blessings-1.6.tar.gz 19:03:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:03:45 INFO - Installing collected packages: blessings 19:03:45 INFO - Running setup.py install for blessings 19:03:45 INFO - Successfully installed blessings 19:03:45 INFO - Cleaning up... 19:03:45 INFO - Return code: 0 19:03:45 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:03:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:03:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:03:46 INFO - Reading from file tmpfile_stdout 19:03:46 INFO - Current package versions: 19:03:46 INFO - blessings == 1.6 19:03:46 INFO - blobuploader == 1.2.4 19:03:46 INFO - docopt == 0.6.1 19:03:46 INFO - functools32 == 3.2.3-2 19:03:46 INFO - jsonschema == 2.5.1 19:03:46 INFO - manifestparser == 1.1 19:03:46 INFO - mozInstall == 1.12 19:03:46 INFO - mozcrash == 0.17 19:03:46 INFO - mozdebug == 0.1 19:03:46 INFO - mozdevice == 0.48 19:03:46 INFO - mozfile == 1.2 19:03:46 INFO - mozhttpd == 0.7 19:03:46 INFO - mozinfo == 0.9 19:03:46 INFO - mozleak == 0.1 19:03:46 INFO - mozlog == 3.2 19:03:46 INFO - moznetwork == 0.27 19:03:46 INFO - mozprocess == 0.23 19:03:46 INFO - mozprofile == 0.28 19:03:46 INFO - mozrunner == 6.12 19:03:46 INFO - mozscreenshot == 0.1 19:03:46 INFO - mozsystemmonitor == 0.3 19:03:46 INFO - moztest == 0.7 19:03:46 INFO - mozversion == 1.4 19:03:46 INFO - psutil == 3.1.1 19:03:46 INFO - requests == 1.2.3 19:03:46 INFO - wsgiref == 0.1.2 19:03:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:03:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:03:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:03:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:03:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:03:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:03:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:03:46 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 0x101d8ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e20618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcde14d9f20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101cefd30>, '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 0x101a54db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ce7b20>, '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.m8C0zbtCSK/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.qe29MHCqtW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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 19:03:46 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 19:03:46 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 19:03:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m8C0zbtCSK/Render', 19:03:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:03:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:03:46 INFO - 'HOME': '/Users/cltbld', 19:03:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:03:46 INFO - 'LOGNAME': 'cltbld', 19:03:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:03:46 INFO - 'MOZ_NO_REMOTE': '1', 19:03:46 INFO - 'NO_EM_RESTART': '1', 19:03:46 INFO - 'PAGER': '/bin/cat', 19:03:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:03:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:03:46 INFO - 'PWD': '/builds/slave/test', 19:03:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:03:46 INFO - 'SHELL': '/bin/bash', 19:03:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qe29MHCqtW/Listeners', 19:03:46 INFO - 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/', 19:03:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:03:46 INFO - 'USER': 'cltbld', 19:03:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:03:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:03:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:03:46 INFO - 'XPC_FLAGS': '0x0', 19:03:46 INFO - 'XPC_SERVICE_NAME': '0', 19:03:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:03:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:03:46 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)) 19:03:46 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)) 19:03:46 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)) 19:03:46 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)) 19:03:46 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)) 19:03:46 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)) 19:03:46 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)) 19:03:46 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)) 19:03:46 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)) 19:03:46 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)) 19:03:46 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)) 19:03:46 INFO - Cleaning up... 19:03:46 INFO - Return code: 0 19:03:46 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:03:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:03:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:03:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:03:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:03:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:03:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:03:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101d8ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e20618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcde14d9f20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101cefd30>, '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 0x101a54db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ce7b20>, '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.m8C0zbtCSK/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.qe29MHCqtW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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 19:03:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 19:03:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 19:03:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m8C0zbtCSK/Render', 19:03:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:03:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:03:46 INFO - 'HOME': '/Users/cltbld', 19:03:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:03:46 INFO - 'LOGNAME': 'cltbld', 19:03:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:03:46 INFO - 'MOZ_NO_REMOTE': '1', 19:03:46 INFO - 'NO_EM_RESTART': '1', 19:03:46 INFO - 'PAGER': '/bin/cat', 19:03:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:03:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:03:46 INFO - 'PWD': '/builds/slave/test', 19:03:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:03:46 INFO - 'SHELL': '/bin/bash', 19:03:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qe29MHCqtW/Listeners', 19:03:46 INFO - 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/', 19:03:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:03:46 INFO - 'USER': 'cltbld', 19:03:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:03:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:03:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:03:46 INFO - 'XPC_FLAGS': '0x0', 19:03:46 INFO - 'XPC_SERVICE_NAME': '0', 19:03:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:03:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:03:46 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:03:46 INFO - Cleaning up... 19:03:46 INFO - Return code: 0 19:03:46 INFO - Running post-action listener: _resource_record_post_action 19:03:46 INFO - Running post-action listener: _start_resource_monitoring 19:03:46 INFO - Starting resource monitoring. 19:03:46 INFO - ##### 19:03:46 INFO - ##### Finished create-virtualenv step (success) 19:03:46 INFO - ##### 19:03:46 INFO - ##### 19:03:46 INFO - ##### Running install step. 19:03:46 INFO - ##### 19:03:46 INFO - Running pre-action listener: _resource_record_pre_action 19:03:46 INFO - Running main action method: install 19:03:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:03:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:03:46 INFO - Reading from file tmpfile_stdout 19:03:46 INFO - Detecting whether we're running mozinstall >=1.0... 19:03:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:03:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:03:47 INFO - Reading from file tmpfile_stdout 19:03:47 INFO - Output received: 19:03:47 INFO - Usage: mozinstall [options] installer 19:03:47 INFO - Options: 19:03:47 INFO - -h, --help show this help message and exit 19:03:47 INFO - -d DEST, --destination=DEST 19:03:47 INFO - Directory to install application into. [default: 19:03:47 INFO - "/builds/slave/test"] 19:03:47 INFO - --app=APP Application being installed. [default: firefox] 19:03:47 INFO - mkdir: /builds/slave/test/build/application 19:03:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 19:03:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 19:04:21 INFO - Reading from file tmpfile_stdout 19:04:21 INFO - Output received: 19:04:21 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 19:04:21 INFO - Running post-action listener: _resource_record_post_action 19:04:21 INFO - ##### 19:04:21 INFO - ##### Finished install step (success) 19:04:21 INFO - ##### 19:04:21 INFO - ##### 19:04:21 INFO - ##### Running run-tests step. 19:04:21 INFO - ##### 19:04:21 INFO - Running pre-action listener: _resource_record_pre_action 19:04:21 INFO - Running main action method: run_tests 19:04:21 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:04:21 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:04:21 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:04:21 INFO - 2016-07-18 19:04:21.354 screenresolution[1911:12336] starting screenresolution argv=screenresolution get 19:04:21 INFO - 2016-07-18 19:04:21.357 screenresolution[1911:12336] Display 0: 1600x1200x32@60 19:04:21 INFO - 2016-07-18 19:04:21.362 screenresolution[1912:12338] starting screenresolution argv=screenresolution list 19:04:21 INFO - Available Modes on Display 0 19:04:21 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:04:21 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:04:21 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:04:21 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:04:21 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:04:21 INFO - Intel Iris: 19:04:21 INFO - Chipset Model: Intel Iris 19:04:21 INFO - Type: GPU 19:04:21 INFO - Bus: Built-In 19:04:21 INFO - VRAM (Dynamic, Max): 1536 MB 19:04:21 INFO - Vendor: Intel (0x8086) 19:04:21 INFO - Device ID: 0x0a2e 19:04:21 INFO - Revision ID: 0x0009 19:04:21 INFO - Displays: 19:04:21 INFO - Display: 19:04:21 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:04:21 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:04:21 INFO - Display Serial Number: 1600x1200 60 19:04:21 INFO - Main Display: Yes 19:04:21 INFO - Mirror: Off 19:04:21 INFO - Online: Yes 19:04:21 INFO - Rotation: Supported 19:04:21 INFO - Return code: 0 19:04:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:04:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:04:21 INFO - Python 2.7.3 19:04:21 INFO - Return code: 0 19:04:21 INFO - grabbing minidump binary from tooltool 19:04:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:04:21 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101cefd30>, '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 0x101a54db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ce7b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:04:21 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 19:04:21 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 19:04:21 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 19:04:21 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:04:21 INFO - Return code: 0 19:04:21 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:04:21 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:04:21 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:04:21 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:04:21 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:04:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WFwGERC8Reqp_v0iQbVGkg/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0083', '--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 19:04:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/WFwGERC8Reqp_v0iQbVGkg/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0083 --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 19:04:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m8C0zbtCSK/Render', 19:04:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:04:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:04:21 INFO - 'HOME': '/Users/cltbld', 19:04:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:04:21 INFO - 'LOGNAME': 'cltbld', 19:04:21 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:04:21 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:04:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:04:21 INFO - 'MOZ_NO_REMOTE': '1', 19:04:21 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:04:21 INFO - 'NO_EM_RESTART': '1', 19:04:21 INFO - 'PAGER': '/bin/cat', 19:04:21 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:04:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:04:21 INFO - 'PWD': '/builds/slave/test', 19:04:21 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:04:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:04:21 INFO - 'SHELL': '/bin/bash', 19:04:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qe29MHCqtW/Listeners', 19:04:21 INFO - 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/', 19:04:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:04:21 INFO - 'USER': 'cltbld', 19:04:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:04:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:04:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:04:21 INFO - 'XPC_FLAGS': '0x0', 19:04:21 INFO - 'XPC_SERVICE_NAME': '0', 19:04:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:04:21 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WFwGERC8Reqp_v0iQbVGkg/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0083', '--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 19:04:21 INFO - mozversion application_buildid: 20160718155219 19:04:21 INFO - mozversion application_changeset: 62bb8900cfef139ac0ea2f9bc4db742166d6f9a5 19:04:21 INFO - mozversion application_display_name: Nightly 19:04:21 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:04:21 INFO - mozversion application_name: Firefox 19:04:21 INFO - mozversion application_remotingname: firefox 19:04:21 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 19:04:21 INFO - mozversion application_vendor: Mozilla 19:04:21 INFO - mozversion application_version: 50.0a1 19:04:21 INFO - mozversion platform_buildid: 20160718155219 19:04:21 INFO - mozversion platform_changeset: 62bb8900cfef139ac0ea2f9bc4db742166d6f9a5 19:04:21 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 19:04:21 INFO - mozversion platform_version: 50.0a1 19:04:21 INFO - using testdate: 1468893861 19:04:21 INFO - actual date: 1468893861 19:04:21 INFO - starting webserver on 'localhost:49702' 19:04:21 INFO - SUITE-START | Running 2 tests 19:04:21 INFO - TEST-START | dromaeo_css 19:04:21 INFO - operating with platform_type : mac_ 19:04:21 INFO - Initialising browser for dromaeo_css test... 19:04:21 INFO - TEST-INFO | started process 1922 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/tmpVvFphc/profile http://localhost:49702/getInfo.html) 19:04:24 INFO - PROCESS | 1922 | __metrics Screen width/height:1600/1200 19:04:24 INFO - PROCESS | 1922 | colorDepth:24 19:04:24 INFO - PROCESS | 1922 | Browser inner width/height: 1024/689 19:04:24 INFO - PROCESS | 1922 | __metrics 19:04:24 INFO - PROCESS | 1922 | 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" 19:04:24 INFO - PROCESS | 1922 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:04:24 INFO - TEST-INFO | 1922: exit 0 19:04:24 INFO - Browser initialized. 19:04:24 INFO - Running cycle 1/1 for dromaeo_css test... 19:04:24 INFO - TEST-INFO | started process 1924 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/tmpVvFphc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:04:36 INFO - PROCESS | 1924 | RSS: Main: 244969472 19:04:36 INFO - PROCESS | 1924 | 19:05:27 INFO - PROCESS | 1924 | 1468893927756 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] 19:07:39 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:49702/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49702/tests/dromaeo/cssquery-ext.html) 19:07:40 INFO - PROCESS | 1924 | RSS: Main: 382910464 19:07:40 INFO - PROCESS | 1924 | 19:10:43 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:49702/tests/dromaeo/cssquery-ext.html (next: http://localhost:49702/tests/dromaeo/cssquery-jquery.html) 19:10:44 INFO - PROCESS | 1924 | RSS: Main: 380948480 19:10:44 INFO - PROCESS | 1924 | 19:13:46 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:49702/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49702/tests/dromaeo/cssquery-mootools.html) 19:13:47 INFO - PROCESS | 1924 | RSS: Main: 372428800 19:13:47 INFO - PROCESS | 1924 | 19:16:49 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:49702/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49702/tests/dromaeo/cssquery-prototype.html) 19:16:50 INFO - PROCESS | 1924 | RSS: Main: 372404224 19:16:50 INFO - PROCESS | 1924 | 19:19:53 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:49702/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49702/tests/dromaeo/cssquery-yui.html) 19:19:54 INFO - PROCESS | 1924 | RSS: Main: 341712896 19:19:54 INFO - PROCESS | 1924 | 19:22:57 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:49702/tests/dromaeo/cssquery-yui.html (next: http://localhost:49702/tests/dromaeo/cssquery-dojo.html) 19:22:57 INFO - PROCESS | 1924 | RSS: Main: 383139840 19:22:57 INFO - PROCESS | 1924 | 19:22:57 INFO - PROCESS | 1924 | __start_tp_report 19:22:57 INFO - PROCESS | 1924 | _x_x_mozilla_page_load 19:22:57 INFO - PROCESS | 1924 | _x_x_mozilla_page_load_details 19:22:57 INFO - PROCESS | 1924 | |i|pagename|runs| 19:22:57 INFO - PROCESS | 1924 | |0;dojo.html;2986.8058576192548;3335.3329334133177;3346.682065203805;3366.8316584170784;3388.322335532894;5602.691851948141;5673.43265673433;5683.886322273552;5735.971320143399;5745.712714364286;5716.571257155709;5760.7407666655;5777.480026797587;5790.044642633971;5806.216160818289;5175.353080864901;5208.895822083556;5222.112240919034;5241.475852414766;5293.629445938766;5283.445238249986;5301.814436494724;5303.946960530384;5307.071262529062;5311.256424100629;5196.194589838581;5207.687538747668;5210.088234558956;5232.555232805216;5291.947080529203;4337.696361254713;5301.6818990860675;5318.149096144637;5338.012467693489;5365.517103460707;5124.846254612367;5134.486551344873;5182.015417070782;5214.478552144793;5227.242049902743;5589.245451863987;5622.128570071645;5667.121596152603;5702.287214098238;5781.653100813965;6227.719752611143;6240.625562466266;6248.468880145193;6253.1558239637525;6259.593126446766;5380.91928621071;6257.280412752546;6266.154069200691;6330.715117819709;6334.556581039329;5888.676122813244;5916.201312822714;5941.376155503696;5951.851203719942;5954.493868021266;6315.494760419156;6355.968220158962;6356.396142366468;6364.586301890362;6367.968160159169;6009.308929473079;6060.484858787053;6126.884121540732;6238.656873871981;6253.093301471261;6288.717007734663;6315.715792789336;6317.210348706411;6317.431431171217;6329.461995730322;6210.006398976142;6256.124142620037;6282.968585157045;6294.590851594632;6311.084892690534;6110.771402862501;6177.722002509808;6178.196834411588;6341.619502829845;6354.777582784581;3987.2623564640635;4877.195262781602;4911.7298548580175;4936.086823937511;4942.851714448572;4432.468103827561;4440.955590444054;4455.799489023002;4458.242098843204;4479.924818043712;5575.498205161555;5816.854578635483;5841.678707671034;5849.093390524476;5881.735321910524;5364.624476286623;6268.655223962726;6324.620522768557;6355.3009168991375;6366.140571022946;5324.334458192726;5383.542398896059;5785.942140578624;5796.594238403271;5809.738561764731;5302.655327403706;5402.648827826179;5406;5438.293021907206;5457.972710136424;6258.968705156536;6304.873902522016;6342.365763423805;6352.4918006559365;6356.300806911144;6345.8730825384155;6346.365363463617;6362.45919096882;6378.585391949508;6383.329750376143;6323.083152942706;6364.172657554488;6366.045093236052;6370.203724534434;6389.2971773105855;6136.120845438751;6194.34959329282;6228.252609686685;6269.310375858743;6274.090256912813;6285.59143655661;6333.081703153109;6347.904781428375;6348.047792831112;6356.364363563606;6037.577369584087;6104.944249919257;6161.014237721943;6227.097070924781;6229.688515574294;4673.995091055303;6144.969275153596;6215.254169499689;6219.657918814509;6226.1905952225925;6009.338972713085;6044.365341639064;6057.4851137653795;6078.513718902565;6091.817245482643;5994.3705910878725;6116.792316687851;6140.8771824564155;6210.813675589739;6232.5325600581045;5317.175837745156;5377.516023557742;6017.759289628366;6048.12302216185;6063.393660633901 19:22:57 INFO - PROCESS | 1924 | |1;ext.html;456.3223612934792;508.65665675668913;535.386981905718;537.7150110441465;554.3237250554323;23999.880000599995;29798.255043623893;31606;31638.36723265533;31656.68343316566;31935.201619959513;41878.371824422655;43220.3516947246;43257;45776.542234577646;9700.660476883293;9707.805843883118;9738.853917191247;9751.658691945784;9757.609695612167;7492.887606685904;9714.854277185848;9798.41209527427;9798.412095274307;9825.508724563742;8813.471191728484;9083.636654533811;9099.772505687344;9106.86339704905;11820.881791182064;11844.407779611029;12149.696257593543;12167.51329946801;12176.878231217663;12245.632631021084;12000.399980001052;12093.274403535816;12093.45579448927;12097.93951030248;12115.333656648882;40982.77051688454;41130;41141.38287925684;41267.79366103181;41309.793451032856;3515.9472607910902;3700.9259814803822;3703.2778608171416;3778.735488515805;4225.260579398593;31635;31709.36581268385;31984.520232196537;32034.839825800722;32584.837075814707;2903.4483448144783;3270.9509357359657;3282.506568994343;3366.4108780963234;3377.6453472385538;491129.0887091119;540775;542062.289688553;542618;544482;33343.66656333405;59062;59135.40864591299;59382;59451;33794.831025845204;35253.64746352555;35268;35272;35343.4698479523;14786.92606536982;15066.698666026618;15081.622959426102;15086.773698394532;15106.622334441507;12664;12764.744705105846;12780.233186008869;12796.424160912782;12804.743905121846;6934.895976560356;7752.8449431012195;7768.456208065381;7835.764927052198;7867.921320786719;3074.6632559255127;3124.1877111950603;3219.806811591312;3224.290656055664;3247.0908145719236;837.7905523619095;911.7583840282331;1035.0736091198335;1066.7279843639978;1074.08702602787;29921.40157196844;30041.549376759365;30565.694343056286;30804.075877723255;30860.845695771826;1122.9775404491863;1141.8743938166795;1147.6384938744268;1170.3387586013876;1180.8464899563173;15254.694906101815;15708.371665133494;15925.522234332988;15941.12323822201;16016.599585010468;716946;718260;722370;723374.5975241503;724778;51197.976040478985;59427.70286148541;59956.700216499514;59980.80038399208;60047.39952600506;1178.6699724077273;1224.55303814109;1387.7016441465134;1424.8575142485668;1439.503371336887;27199;31641;31716.36567268688;32427.27232047383;32486.51270230901;50093.74953125211;50853.49146508636;51286.230706538685;51292.974140516235;51365.48634513607;644569;649521;655495;659018;928330;26362.340941476617;26940.86529567418;26982.32544186411;27302;27878.58182127229;47933;47965.76017119892;48341.27488087611;49502.01991920283;49573.00853982972;39717.00707482336;39998.40002399908;40034;41054.38418423666;41145.38281925714;39551.80224098861;40048.1990360197;40383.3942490857;41214.38178427386;41241.793791030854;38894.61105389023;38992.83021509359;39182.41226381664;39522.60477395189;39646.60353396429;33002.83498582492;34587.30825383528;35306.470402943465;35349;35629.64370356263;25187.61468119271;25319.62020569656;25324.873375633004;25519.23442296734;26008.609870851575 19:22:57 INFO - PROCESS | 1924 | |2;jquery.html;343.7920058364688;345.4420563259698;380.2983495450893;389.40032350180724;414.4342971843433;13564.321783910802;17278.740818887727;21464;21509.03209355579;21624;17404;21418.571628567457;21443.57112857746;22282.777172228274;22330.218442354522;5875.500582450497;6055.182550355712;6127.295361033472;6136.386361363872;6670.833229169285;6620.23867255267;6621.205455345341;6689.030090636855;6696.397324240817;6702.463802895781;6348.174737284146;6626.933730662704;6630.138082049351;6633.436157926558;6634.966825165868;6329.746810127589;6549.246836613779;6565.441937435324;6643.966780166116;6645.169353830771;5717.199592057115;5740.913886291709;5745.052066409037;5752.568557358215;5756.971215143897;26027.869860650764;26681.332966675793;27017.054403095994;27023.919043238246;27181.592276115676;2468.0004598137784;2665.746754058361;2676.585129304961;2819.2951762059483;2964.4960356739407;13480.460781568725;15182.772258416133;16094;16334.83665163345;16415.589610259838;1517.6054225901235;1709;1759.472158352489;1763.4709587123812;1845.2342277954633;91847;113037;115192;125157;127665.36167319104;8655.913440865637;9883.308168428284;10072.740907386577;10083.899161008296;10370.170386369073;12399;12487.75024499505;13142.40859161321;13688.384022719001;13752.174869507862;6892.551984121016;8115.458062968017;8473.279771219391;8619.870701939475;8685.218330350172;8611.440256383315;8612.5693715314;8617.827643447095;8648.308135349156;8713.128687131237;3725.453936616274;4536.364908912755;4595.379623750818;4646.697964632355;4691.483936766953;1627.2189349112493;1903.5812121333286;1951.1024928533034;1952.9804701953083;1960.9313674021626;885.4775682347446;986.156835905305;1000.6447711062614;1025.5590096258532;1033.7570670892335;16332.775041871908;22510.54978900413;22622.886885565465;22636.207732729275;22640.433989150402;961.2838435365697;967.4002118686319;968.660968660963;1027.6146445083093;1040.5005597313332;8624.956875215585;11478.024367928652;11664;11751.47118379675;11853.9331460169;512132;518641.4067929712;519304;615595;621158;117724;125675;127373.36313318374;128806;138330;972.1153750087386;976.3751199232497;1017.2218253036433;1017.7811770469384;1018.2007124407453;17304.65390692204;19254.35965073175;19339.613207735485;20070.799292006894;20304.289349872684;50349.74125646888;58473.70763146327;59082;59590;59913.700431497564;78303.21696782959;82737;83584.49384238222;83643.5817820907;84383.57808210919;7245.710171593288;7512.5492470451945;7550.848983020199;7774.805629859073;8426.368022398223;12438.315892625993;13403;13403.994700397314;13680.97392695532;13768.793468097787;5562.5549956002615;6586.571872828251;11037.123688972733;11071.778564428505;11708.121890858049;11448.026917712088;11526.308421494738;11640.767184656428;11766.705832353917;11804.409779511161;11378.374189419661;11734.589289374831;12031.578894738643;12097.092718046006;12121.151519393557;6491.578047426902;7488.775336739906;7574.204708505527;7655.272749088827;7686.615669216405;10580.682579522627;11463.598774042868;11532.769344613229;11712.648620541397;11921.642350729491 19:22:57 INFO - PROCESS | 1924 | |3;mootools.html;1355.8644135586437;1436.8778653814425;1509.9471518496855;1665.450401367549;1890.659681257374;7815.140334563193;8458.19647133522;8694.478331300126;8694.956525217382;8712.477251364915;8422.199891010345;8455.154484551556;8605.612747426365;8687.913120868805;8689;2088.968665470019;2159.7840215978354;2310.6418505131733;2362.3149286706835;2362.397588614901;2154.482924098221;2160.82713382929;2162.686410470481;2299.5975704251773;2366.6213405855065;2333.801626861719;2337.3689103941924;2340.1692399198296;2341.590221711202;2347.7769611886843;2301.1945818963313;2316.397736588482;2335.953280934372;2343.695319608457;2348.894299756515;2076.9065392057396;2262.6719125726677;2280.8289378296695;2326.744058153619;2333;8643.481391116553;8718.825623487495;8719;8731.039585645574;8737.169968852948;5144.6655967362;5297.841064768063;5302.708351040691;5303.840884773463;5312.043832110218;2888.812227205225;3527.3650742866275;3598.24436868258;3601.4957905893198;3601.621829707869;1085.10478854945;1087.255230167338;1097.6706987903597;1106.3030290916752;1107.5735841700923;11506.13703972197;11714.121440891968;11737.882621173765;11749.588764393206;11761.647150585497;4011.9799401002806;4146.543880173179;4530.821986283524;4552.362669226245;4753.857384278477;10074.748131296776;10117.988201179971;10124.797504050026;10130.696079117639;10137.797244055226;5333.919991200135;5352.57179425645;5373.7581808818695;5375.650582712111;5392.191171324333;4814.759262036884;4898.755062246873;4914.311996320518;4915.0415668944215;4922.483139270396;3749.643783840531;3864.9806750966063;3868.574456809749;3897.4738410314812;3906.4726261954843;1253.6928452529007;1288.2077522323702;1305.3473263368317;1306.915050521713;1311.9081664283408;909.981800363989;912.4479689787715;922.3506025593241;922.3820040572832;966.4201479112477;5260.763265653055;5624.240727501817;5855.502282306052;6004.729787159588;6101.8474538136015;870.5647176411794;978.921686265097;979.7207795778312;981.2983716642635;1005.6731562242301;3860.9034774130873;4011.618896204856;4016.819243134066;4059.878203653836;4099.344104943195;10361.119304859021;10410;10755.139588833032;10767.83848242277;10853;10577.471126443646;10781.568737250578;10786.676399708022;10795.40625265618;10797.730056748644;8133.796655083552;8194.836103277901;8355.707550235713;8360.331173505983;8366.707165249187;5853.414658534283;5900.940990590039;5901.911471327848;5908.881822363614;5988.520918326611;9966.302358835102;10116.797664046826;10122.240831937486;10137;10141;9964;10113.848292275474;10160.949195254272;10230.283880128618;10362.689119326433;7502.099748030271;7547.16981132064;7660.34887034586;7664.961675191588;7675.923240767745;8173.959130204311;8264.917350826414;8328.292095171979;8341.666333346599;8343.499390036617;7514.286142816423;7536.397088233038;7541.396688265036;7702.537847729154;7730.497517661334;7163.10461192351;7338.009368735153;7350.889736653848;7353.374963128194;7566.054243219598;6837.760678376432;6849;6917.515773895779;6921.065656136358;6930.06444130036;4933.087378834927;4935.210366341368;4953.653244272867;4955.2567114933045;5016.498350164954;4931.211006238985;5028.673136246132;5048.545630893091;5083.364579427572;5124.5131712487255 19:22:57 INFO - PROCESS | 1924 | |4;prototype.html;305.34808184526037;307.5248740695625;312.9186411533001;317.0630786027291;319.32144193588624;5455.618106732531;5473.397926228112;5476.315460567429;5479.917801232975;5544.3346798384255;4576.359309696637;5381.838544843661;5381.838544843661;5418.674879507233;5436.891262174764;3841.8463261469506;3895.8636447724334;4059.7158198926086;4110.054687421894;4133;3514.297140571883;4017.236725022251;4085.3871919212206;4088.7751173685438;4142.399352093945;3970.8484539483516;4077.7145599808027;4079.8164082616204;4080.1635664688815;4083.5099788025477;3221.822799746013;3938.4289278054807;3978.9403158952637;4042.58509521668;4125.381192821071;2960.86676099576;3529.188286694049;3551.254236610315;3555.235624340779;3563.376409128392;4294.914101717979;4617.030423611046;5145.76844042019;5253.737313134328;5349.625526213166;4479.723278865507;4554.681172317939;4690.554397332248;4698.778317637405;4716.457607375161;5574.526165275956;5599.776008959637;5615.213870058195;5627.155926611;5630.436956304377;3426.6573342665783;3920.5491368492703;3936.3308237599676;3947.144841924291;3962.0094976255937;5289.233061206103;5317.388500322435;5534.667919924817;5562.415946325618;5934.673592952386;3771.641694039062;3842.654161125512;3875.6124387561017;3879.9806000969816;3882.456456096149;3756.6431189037;3937.901552461154;3944.4872166618156;3953.4267531207815;3981.223661386002;3590.6947909427467;4252.4897012358715;4267.679924005712;4307.719998200107;4626.583607475343;5685.630434021776;5714.371419143891;5730.169125476783;5738.799142030009;5743.908657355089;1312.744014917082;1330.082243252153;1348.7167694784205;1351.398627610704;1354.817099691529;674.844785699292;821.3470291118456;830.7092517618785;838.5723281126669;839.6515446089926;4545.704529205591;5048.873778155502;5101.974490127525;5245.763940622681;5258.792136796551;5821.359650438449;5828.795992140254;5829.417058294137;5880.382559831156;5888.730670264024;4907.705537667752;5049.444561098277;5298.28473156119;5310.751973286275;5337.784819299947;2584.3539115221197;2647.391100047;3013.9547906781418;3042.756579473637;3061.5101583746496;4967.726775027336;5804.448577385226;5950.464458198783;5976.28079223952;5988.9401105989255;5078.562290100773;5576.832695019156;5954.970225148846;5971.014492753623;5976.760929562769;5941.851453713692;5971.313298970674;5974.61165024272;5980.073088671263;5986.5809393342915;5221.407470721467;5419.430959749169;5498.776162645583;5807.245058142415;5812.3025236971835;5822.912656310074;5826.3977406213635;5828.70856457167;5848.157830283447;5849.94150058494;6120.265568131853;6131.63210207389;6146;6156.722947467464;6173.81478555644;3159.984200079077;3202.9199270017502;3815.4658347831883;3927.312720273998;4045.4134150548593;5054.898902022012;5782.571320045507;5846.123081537803;5875.383084776037;5900.291964964232;4866.9847375914305;5511.834644960658;5733.684647344269;5772.393898640582;5833.941660583339;5339.973300133474;5703.574106473382;5709.885802284014;5724.084146536534;5753;4425.557444255549;5116.769745361393;5315.787368505371;5574.414686458026;5596.664200148004;4448.798824317351;5193.792248310177;5584.972075139598;5595.916061259003;5718.656880587178;4853.878653033702;5587;5594.510442852891;5612.466815652507;5621.100623900156 19:22:57 INFO - PROCESS | 1924 | |5;yui.html;737.6311844077961;752.4319139050017;760.6843160088561;761.3262262897334;854.7863034241442;15224.923875380635;15968.201589920487;15982.920085399572;15988.76016859748;16003.27985240664;12205.084618653615;14679.853201467982;14814.55556333309;14836.48072317469;14846.777298340534;695.3046953046953;881.391839630656;909.7634615000114;911.5578944212052;916.1571354353977;860.4665107633276;870.1516021878681;870.4907629037021;871.0505548951642;873.1050673060132;739.3789217057671;934.3319526538523;935.3078721745893;937.4047479850276;940.9247260219166;924.7734305095261;951.1202138022302;952.2239374909427;953.0755167487491;955.7801705607749;702.1609177033447;702.2521015118933;703.0754557756534;704.2042491984076;707.3633729643311;14397.424103035866;14412.13527188372;14422.84617230619;14439.927800361036;14452.205128717917;12746.936265318614;13266.800997985038;13868.722625547534;14005;14054.789178162337;11127.975198180595;12181.817272740916;12289.56986505477;12295.4467048983;12813.615591532269;3681.2005035137067;3709.77741335518;3738.420544815531;3756.5116534850567;3843.98078009611;103765.96234037714;104348;106868;106916.46541767319;107448.31379215504;10761.677149685522;10913.563457461612;10979.1765617579;11005.669829905115;11459.541618335339;21455.89272053661;21463.67804482934;21466.356009319745;21470.355889323346;21472.141114355563;8017.559034253172;8269.8346033079;8295.668173273121;8319.750407487785;8338.62476188573;4431.933520997188;4565.611922986517;4571.1086338164405;4589.54104589545;4755.5719985201495;3426.862925482953;3563.376409128392;3565.16218688608;3611.7110631149444;3852.7110466715108;709.9396551293095;715.506300652548;720.5928650312557;723.3092396761081;732.2604169788552;591.9585629006015;633.822529691687;690.9654517274116;691.2983321928247;716.7383904874788;9364.438133711863;9394.877512218947;9418.670346537838;9841.163501102343;9892.851607225897;679.3342524326089;683.1017212365723;699.4439420660588;706.6466766616692;708.1164103484706;4570.634349252052;4783.6651434399955;4799.568038876518;4847.830325938575;4914.311996320518;97194;106713;148266.51733482527;159996;160751;88133;95859;114925;130384;130970;91056;101573.49213253887;107196.4640176794;107474;108574.45712771386;8686.39195256326;8966.193042626195;9053.728388148365;9084.046175151514;9190.40262382943;46395.53604463912;48630.27054594113;50495.485135446;59123.704381477815;59336.40663593309;68851;97163;98261.50869245894;98588;100171.49914250674;11958.820617690568;11961.162718609614;12630.557930472389;12665.556705515262;13467.528636497676;14561.635959101108;15138.697226055638;15330.540083797057;15348.275159007364;16325.918370408546;13208.80186797218;13901.860981390057;14844.035137716446;15064.096154230781;15064.322105505498;13725.931370343083;13983.650408739863;14366.353514091681;14806.62983425423;14843.183624900734;13681.384337704629;13906.51327203543;14183;14311.069780464237;14331.713365732418;11504.884951150381;11511.36687482163;11564.190506664452;11903.107266955187;12114.939425302817;9626.426738298966;10792.298500597437;10868.67393978182;11561.537538498702;11805.527778888749 19:22:57 INFO - PROCESS | 1924 | __end_tp_report 19:22:57 INFO - PROCESS | 1924 | __start_cc_report 19:22:57 INFO - PROCESS | 1924 | _x_x_mozilla_cycle_collect,1469 19:22:57 INFO - PROCESS | 1924 | __end_cc_report 19:22:57 INFO - PROCESS | 1924 | __startTimestamp1468894977837__endTimestamp 19:22:57 INFO - PROCESS | 1924 | 19:22:57 INFO - PROCESS | 1924 | ------- Summary: start ------- 19:22:57 INFO - PROCESS | 1924 | Number of tests: 6 19:22:57 INFO - PROCESS | 1924 | 19:22:57 INFO - PROCESS | 1924 | [#0] dojo.html Cycles:165 Average:5752.31 Median:6009.34 stddev:689.20 (11.5%) stddev-sans-first:656.28 19:22:57 INFO - PROCESS | 1924 | Values: 2986.8 3335.3 3346.7 3366.8 3388.3 5602.7 5673.4 5683.9 5736.0 5745.7 5716.6 5760.7 5777.5 5790.0 5806.2 5175.4 5208.9 5222.1 5241.5 5293.6 5283.4 5301.8 5303.9 5307.1 5311.3 5196.2 5207.7 5210.1 5232.6 5291.9 4337.7 5301.7 5318.1 5338.0 5365.5 5124.8 5134.5 5182.0 5214.5 5227.2 5589.2 5622.1 5667.1 5702.3 5781.7 6227.7 6240.6 6248.5 6253.2 6259.6 5380.9 6257.3 6266.2 6330.7 6334.6 5888.7 5916.2 5941.4 5951.9 5954.5 6315.5 6356.0 6356.4 6364.6 6368.0 6009.3 6060.5 6126.9 6238.7 6253.1 6288.7 6315.7 6317.2 6317.4 6329.5 6210.0 6256.1 6283.0 6294.6 6311.1 6110.8 6177.7 6178.2 6341.6 6354.8 3987.3 4877.2 4911.7 4936.1 4942.9 4432.5 4441.0 4455.8 4458.2 4479.9 5575.5 5816.9 5841.7 5849.1 5881.7 5364.6 6268.7 6324.6 6355.3 6366.1 5324.3 5383.5 5785.9 5796.6 5809.7 5302.7 5402.6 5406.0 5438.3 5458.0 6259.0 6304.9 6342.4 6352.5 6356.3 6345.9 6346.4 6362.5 6378.6 6383.3 6323.1 6364.2 6366.0 6370.2 6389.3 6136.1 6194.3 6228.3 6269.3 6274.1 6285.6 6333.1 6347.9 6348.0 6356.4 6037.6 6104.9 6161.0 6227.1 6229.7 4674.0 6145.0 6215.3 6219.7 6226.2 6009.3 6044.4 6057.5 6078.5 6091.8 5994.4 6116.8 6140.9 6210.8 6232.5 5317.2 5377.5 6017.8 6048.1 6063.4 19:22:57 INFO - PROCESS | 1924 | 19:22:57 INFO - PROCESS | 1924 | [#1] ext.html Cycles:180 Average:76080.47 Median:35489.32 stddev:178176.32 (502.1%) stddev-sans-first:178585.68 19:22:57 INFO - PROCESS | 1924 | Values: 456.3 508.7 535.4 537.7 554.3 23999.9 29798.3 31606.0 31638.4 31656.7 31935.2 41878.4 43220.4 43257.0 45776.5 9700.7 9707.8 9738.9 9751.7 9757.6 7492.9 9714.9 9798.4 9798.4 9825.5 8813.5 9083.6 9099.8 9106.9 11820.9 11844.4 12149.7 12167.5 12176.9 12245.6 12000.4 12093.3 12093.5 12097.9 12115.3 40982.8 41130.0 41141.4 41267.8 41309.8 3515.9 3700.9 3703.3 3778.7 4225.3 31635.0 31709.4 31984.5 32034.8 32584.8 2903.4 3271.0 3282.5 3366.4 3377.6 491129.1 540775.0 542062.3 542618.0 544482.0 33343.7 59062.0 59135.4 59382.0 59451.0 33794.8 35253.6 35268.0 35272.0 35343.5 14786.9 15066.7 15081.6 15086.8 15106.6 12664.0 12764.7 12780.2 12796.4 12804.7 6934.9 7752.8 7768.5 7835.8 7867.9 3074.7 3124.2 3219.8 3224.3 3247.1 837.8 911.8 1035.1 1066.7 1074.1 29921.4 30041.5 30565.7 30804.1 30860.8 1123.0 1141.9 1147.6 1170.3 1180.8 15254.7 15708.4 15925.5 15941.1 16016.6 716946.0 718260.0 722370.0 723374.6 724778.0 51198.0 59427.7 59956.7 59980.8 60047.4 1178.7 1224.6 1387.7 1424.9 1439.5 27199.0 31641.0 31716.4 32427.3 32486.5 50093.7 50853.5 51286.2 51293.0 51365.5 644569.0 649521.0 655495.0 659018.0 928330.0 26362.3 26940.9 26982.3 27302.0 27878.6 47933.0 47965.8 48341.3 49502.0 49573.0 39717.0 39998.4 40034.0 41054.4 41145.4 39551.8 40048.2 40383.4 41214.4 41241.8 38894.6 38992.8 39182.4 39522.6 39646.6 33002.8 34587.3 35306.5 35349.0 35629.6 25187.6 25319.6 25324.9 25519.2 26008.6 19:22:57 INFO - PROCESS | 1924 | 19:22:57 INFO - PROCESS | 1924 | [#2] jquery.html Cycles:180 Average:34212.59 Median:14678.96 stddev:93791.58 (639.0%) stddev-sans-first:94020.22 19:22:57 INFO - PROCESS | 1924 | Values: 343.8 345.4 380.3 389.4 414.4 13564.3 17278.7 21464.0 21509.0 21624.0 17404.0 21418.6 21443.6 22282.8 22330.2 5875.5 6055.2 6127.3 6136.4 6670.8 6620.2 6621.2 6689.0 6696.4 6702.5 6348.2 6626.9 6630.1 6633.4 6635.0 6329.7 6549.2 6565.4 6644.0 6645.2 5717.2 5740.9 5745.1 5752.6 5757.0 26027.9 26681.3 27017.1 27023.9 27181.6 2468.0 2665.7 2676.6 2819.3 2964.5 13480.5 15182.8 16094.0 16334.8 16415.6 1517.6 1709.0 1759.5 1763.5 1845.2 91847.0 113037.0 115192.0 125157.0 127665.4 8655.9 9883.3 10072.7 10083.9 10370.2 12399.0 12487.8 13142.4 13688.4 13752.2 6892.6 8115.5 8473.3 8619.9 8685.2 8611.4 8612.6 8617.8 8648.3 8713.1 3725.5 4536.4 4595.4 4646.7 4691.5 1627.2 1903.6 1951.1 1953.0 1960.9 885.5 986.2 1000.6 1025.6 1033.8 16332.8 22510.5 22622.9 22636.2 22640.4 961.3 967.4 968.7 1027.6 1040.5 8625.0 11478.0 11664.0 11751.5 11853.9 512132.0 518641.4 519304.0 615595.0 621158.0 117724.0 125675.0 127373.4 128806.0 138330.0 972.1 976.4 1017.2 1017.8 1018.2 17304.7 19254.4 19339.6 20070.8 20304.3 50349.7 58473.7 59082.0 59590.0 59913.7 78303.2 82737.0 83584.5 83643.6 84383.6 7245.7 7512.5 7550.8 7774.8 8426.4 12438.3 13403.0 13404.0 13681.0 13768.8 5562.6 6586.6 11037.1 11071.8 11708.1 11448.0 11526.3 11640.8 11766.7 11804.4 11378.4 11734.6 12031.6 12097.1 12121.2 6491.6 7488.8 7574.2 7655.3 7686.6 10580.7 11463.6 11532.8 11712.6 11921.6 19:22:57 INFO - PROCESS | 1924 | 19:22:57 INFO - PROCESS | 1924 | [#3] mootools.html Cycles:180 Average:5706.31 Median:4906.53 stddev:3220.09 (65.6%) stddev-sans-first:3212.52 19:22:57 INFO - PROCESS | 1924 | Values: 1355.9 1436.9 1509.9 1665.5 1890.7 7815.1 8458.2 8694.5 8695.0 8712.5 8422.2 8455.2 8605.6 8687.9 8689.0 2089.0 2159.8 2310.6 2362.3 2362.4 2154.5 2160.8 2162.7 2299.6 2366.6 2333.8 2337.4 2340.2 2341.6 2347.8 2301.2 2316.4 2336.0 2343.7 2348.9 2076.9 2262.7 2280.8 2326.7 2333.0 8643.5 8718.8 8719.0 8731.0 8737.2 5144.7 5297.8 5302.7 5303.8 5312.0 2888.8 3527.4 3598.2 3601.5 3601.6 1085.1 1087.3 1097.7 1106.3 1107.6 11506.1 11714.1 11737.9 11749.6 11761.6 4012.0 4146.5 4530.8 4552.4 4753.9 10074.7 10118.0 10124.8 10130.7 10137.8 5333.9 5352.6 5373.8 5375.7 5392.2 4814.8 4898.8 4914.3 4915.0 4922.5 3749.6 3865.0 3868.6 3897.5 3906.5 1253.7 1288.2 1305.3 1306.9 1311.9 910.0 912.4 922.4 922.4 966.4 5260.8 5624.2 5855.5 6004.7 6101.8 870.6 978.9 979.7 981.3 1005.7 3860.9 4011.6 4016.8 4059.9 4099.3 10361.1 10410.0 10755.1 10767.8 10853.0 10577.5 10781.6 10786.7 10795.4 10797.7 8133.8 8194.8 8355.7 8360.3 8366.7 5853.4 5900.9 5901.9 5908.9 5988.5 9966.3 10116.8 10122.2 10137.0 10141.0 9964.0 10113.8 10160.9 10230.3 10362.7 7502.1 7547.2 7660.3 7665.0 7675.9 8174.0 8264.9 8328.3 8341.7 8343.5 7514.3 7536.4 7541.4 7702.5 7730.5 7163.1 7338.0 7350.9 7353.4 7566.1 6837.8 6849.0 6917.5 6921.1 6930.1 4933.1 4935.2 4953.7 4955.3 5016.5 4931.2 5028.7 5048.5 5083.4 5124.5 19:22:57 INFO - PROCESS | 1924 | 19:22:57 INFO - PROCESS | 1924 | [#4] prototype.html Cycles:180 Average:4586.50 Median:5256.26 stddev:1444.73 (27.5%) stddev-sans-first:1412.60 19:22:57 INFO - PROCESS | 1924 | Values: 305.3 307.5 312.9 317.1 319.3 5455.6 5473.4 5476.3 5479.9 5544.3 4576.4 5381.8 5381.8 5418.7 5436.9 3841.8 3895.9 4059.7 4110.1 4133.0 3514.3 4017.2 4085.4 4088.8 4142.4 3970.8 4077.7 4079.8 4080.2 4083.5 3221.8 3938.4 3978.9 4042.6 4125.4 2960.9 3529.2 3551.3 3555.2 3563.4 4294.9 4617.0 5145.8 5253.7 5349.6 4479.7 4554.7 4690.6 4698.8 4716.5 5574.5 5599.8 5615.2 5627.2 5630.4 3426.7 3920.5 3936.3 3947.1 3962.0 5289.2 5317.4 5534.7 5562.4 5934.7 3771.6 3842.7 3875.6 3880.0 3882.5 3756.6 3937.9 3944.5 3953.4 3981.2 3590.7 4252.5 4267.7 4307.7 4626.6 5685.6 5714.4 5730.2 5738.8 5743.9 1312.7 1330.1 1348.7 1351.4 1354.8 674.8 821.3 830.7 838.6 839.7 4545.7 5048.9 5102.0 5245.8 5258.8 5821.4 5828.8 5829.4 5880.4 5888.7 4907.7 5049.4 5298.3 5310.8 5337.8 2584.4 2647.4 3014.0 3042.8 3061.5 4967.7 5804.4 5950.5 5976.3 5988.9 5078.6 5576.8 5955.0 5971.0 5976.8 5941.9 5971.3 5974.6 5980.1 5986.6 5221.4 5419.4 5498.8 5807.2 5812.3 5822.9 5826.4 5828.7 5848.2 5849.9 6120.3 6131.6 6146.0 6156.7 6173.8 3160.0 3202.9 3815.5 3927.3 4045.4 5054.9 5782.6 5846.1 5875.4 5900.3 4867.0 5511.8 5733.7 5772.4 5833.9 5340.0 5703.6 5709.9 5724.1 5753.0 4425.6 5116.8 5315.8 5574.4 5596.7 4448.8 5193.8 5585.0 5595.9 5718.7 4853.9 5587.0 5594.5 5612.5 5621.1 19:22:57 INFO - PROCESS | 1924 | 19:22:57 INFO - PROCESS | 1924 | [#5] yui.html Cycles:180 Average:23552.52 Median:3695.49 stddev:36745.27 (994.3%) stddev-sans-first:36808.42 19:22:57 INFO - PROCESS | 1924 | Values: 737.6 752.4 760.7 761.3 854.8 15224.9 15968.2 15982.9 15988.8 16003.3 12205.1 14679.9 14814.6 14836.5 14846.8 695.3 881.4 909.8 911.6 916.2 860.5 870.2 870.5 871.1 873.1 739.4 934.3 935.3 937.4 940.9 924.8 951.1 952.2 953.1 955.8 702.2 702.3 703.1 704.2 707.4 14397.4 14412.1 14422.8 14439.9 14452.2 12746.9 13266.8 13868.7 14005.0 14054.8 11128.0 12181.8 12289.6 12295.4 12813.6 3681.2 3709.8 3738.4 3756.5 3844.0 103766.0 104348.0 106868.0 106916.5 107448.3 10761.7 10913.6 10979.2 11005.7 11459.5 21455.9 21463.7 21466.4 21470.4 21472.1 8017.6 8269.8 8295.7 8319.8 8338.6 4431.9 4565.6 4571.1 4589.5 4755.6 3426.9 3563.4 3565.2 3611.7 3852.7 709.9 715.5 720.6 723.3 732.3 592.0 633.8 691.0 691.3 716.7 9364.4 9394.9 9418.7 9841.2 9892.9 679.3 683.1 699.4 706.6 708.1 4570.6 4783.7 4799.6 4847.8 4914.3 97194.0 106713.0 148266.5 159996.0 160751.0 88133.0 95859.0 114925.0 130384.0 130970.0 91056.0 101573.5 107196.5 107474.0 108574.5 8686.4 8966.2 9053.7 9084.0 9190.4 46395.5 48630.3 50495.5 59123.7 59336.4 68851.0 97163.0 98261.5 98588.0 100171.5 11958.8 11961.2 12630.6 12665.6 13467.5 14561.6 15138.7 15330.5 15348.3 16325.9 13208.8 13901.9 14844.0 15064.1 15064.3 13725.9 13983.7 14366.4 14806.6 14843.2 13681.4 13906.5 14183.0 14311.1 14331.7 11504.9 11511.4 11564.2 11903.1 12114.9 9626.4 10792.3 10868.7 11561.5 11805.5 19:22:57 INFO - PROCESS | 1924 | -------- Summary: end -------- 19:22:57 INFO - PROCESS | 1924 | 19:22:58 INFO - TEST-INFO | 1924: exit 0 19:22:58 INFO - TEST-OK | dromaeo_css | took 1116501ms 19:22:58 INFO - TEST-START | kraken 19:22:58 INFO - operating with platform_type : mac_ 19:22:58 INFO - Initialising browser for kraken test... 19:22:58 INFO - TEST-INFO | started process 1929 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/tmpJMaszK/profile http://localhost:49702/getInfo.html) 19:23:00 INFO - PROCESS | 1929 | __metrics Screen width/height:1600/1200 19:23:00 INFO - PROCESS | 1929 | colorDepth:24 19:23:00 INFO - PROCESS | 1929 | Browser inner width/height: 1024/689 19:23:00 INFO - PROCESS | 1929 | __metrics 19:23:00 INFO - PROCESS | 1929 | 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" 19:23:00 INFO - PROCESS | 1929 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:23:01 INFO - TEST-INFO | 1929: exit 0 19:23:01 INFO - Browser initialized. 19:23:01 INFO - Running cycle 1/1 for kraken test... 19:23:01 INFO - TEST-INFO | started process 1930 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/tmpJMaszK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:23:13 INFO - PROCESS | 1930 | RSS: Main: 244981760 19:23:13 INFO - PROCESS | 1930 | 19:23:16 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:49702/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49702/tests/kraken/driver.html?testName=audio-beat-detection) 19:23:16 INFO - PROCESS | 1930 | RSS: Main: 294686720 19:23:16 INFO - PROCESS | 1930 | 19:23:19 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:49702/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49702/tests/kraken/driver.html?testName=audio-dft) 19:23:20 INFO - PROCESS | 1930 | RSS: Main: 305418240 19:23:20 INFO - PROCESS | 1930 | 19:23:23 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:49702/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49702/tests/kraken/driver.html?testName=audio-fft) 19:23:24 INFO - PROCESS | 1930 | RSS: Main: 390168576 19:23:24 INFO - PROCESS | 1930 | 19:23:26 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:49702/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49702/tests/kraken/driver.html?testName=audio-oscillator) 19:23:26 INFO - PROCESS | 1930 | RSS: Main: 292659200 19:23:26 INFO - PROCESS | 1930 | 19:23:28 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:49702/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49702/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:23:29 INFO - PROCESS | 1930 | RSS: Main: 296632320 19:23:29 INFO - PROCESS | 1930 | 19:23:32 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:49702/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49702/tests/kraken/driver.html?testName=imaging-darkroom) 19:23:32 INFO - PROCESS | 1930 | RSS: Main: 333029376 19:23:32 INFO - PROCESS | 1930 | 19:23:35 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:49702/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49702/tests/kraken/driver.html?testName=imaging-desaturate) 19:23:36 INFO - PROCESS | 1930 | RSS: Main: 337625088 19:23:36 INFO - PROCESS | 1930 | 19:23:39 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:49702/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49702/tests/kraken/driver.html?testName=json-parse-financial) 19:23:39 INFO - PROCESS | 1930 | RSS: Main: 336764928 19:23:39 INFO - PROCESS | 1930 | 19:23:41 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:49702/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49702/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:23:42 INFO - PROCESS | 1930 | RSS: Main: 313901056 19:23:42 INFO - PROCESS | 1930 | 19:23:43 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:49702/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49702/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:23:44 INFO - PROCESS | 1930 | RSS: Main: 320299008 19:23:44 INFO - PROCESS | 1930 | 19:23:46 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:49702/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49702/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:23:47 INFO - PROCESS | 1930 | RSS: Main: 321441792 19:23:47 INFO - PROCESS | 1930 | 19:23:50 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:49702/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49702/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:23:51 INFO - PROCESS | 1930 | RSS: Main: 332853248 19:23:51 INFO - PROCESS | 1930 | 19:23:53 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:49702/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49702/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:23:54 INFO - PROCESS | 1930 | RSS: Main: 329007104 19:23:54 INFO - PROCESS | 1930 | 19:23:56 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:49702/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49702/tests/kraken/driver.html?testName=ai-astar) 19:23:56 INFO - PROCESS | 1930 | RSS: Main: 372760576 19:23:56 INFO - PROCESS | 1930 | 19:23:56 INFO - PROCESS | 1930 | __start_tp_report 19:23:56 INFO - PROCESS | 1930 | _x_x_mozilla_page_load 19:23:56 INFO - PROCESS | 1930 | _x_x_mozilla_page_load_details 19:23:56 INFO - PROCESS | 1930 | |i|pagename|runs| 19:23:56 INFO - PROCESS | 1930 | |0;ai-astar;103;114;113;114;114;113;114;111;114;113 19:23:56 INFO - PROCESS | 1930 | |1;audio-beat-detection;146;112;137;197;127;129;127;106;157;106 19:23:56 INFO - PROCESS | 1930 | |2;audio-dft;186;184;184;181;185;181;181;182;184;184 19:23:56 INFO - PROCESS | 1930 | |3;audio-fft;74;76;58;86;58;69;61;64;83;56 19:23:56 INFO - PROCESS | 1930 | |4;audio-oscillator;85;82;93;70;71;68;95;69;67;97 19:23:56 INFO - PROCESS | 1930 | |5;imaging-gaussian-blur;90;85;88;85;88;84;88;86;87;85 19:23:56 INFO - PROCESS | 1930 | |6;imaging-darkroom;94;94;95;144;146;95;95;94;95;94 19:23:56 INFO - PROCESS | 1930 | |7;imaging-desaturate;84;87;86;85;85;85;86;86;83;85 19:23:56 INFO - PROCESS | 1930 | |8;json-parse-financial;55;55;53;52;54;53;52;53;54;52 19:23:56 INFO - PROCESS | 1930 | |9;json-stringify-tinderbox;66;68;77;79;73;73;70;71;72;75 19:23:56 INFO - PROCESS | 1930 | |10;stanford-crypto-aes;94;102;88;85;84;87;83;83;93;102 19:23:56 INFO - PROCESS | 1930 | |11;stanford-crypto-ccm;137;139;135;137;160;148;142;127;179;147 19:23:56 INFO - PROCESS | 1930 | |12;stanford-crypto-pbkdf2;167;163;162;166;163;163;163;163;163;163 19:23:56 INFO - PROCESS | 1930 | |13;stanford-crypto-sha256-iterative;67;67;67;66;66;66;67;65;66;68 19:23:56 INFO - PROCESS | 1930 | __end_tp_report 19:23:56 INFO - PROCESS | 1930 | __start_cc_report 19:23:56 INFO - PROCESS | 1930 | _x_x_mozilla_cycle_collect,2075 19:23:56 INFO - PROCESS | 1930 | __end_cc_report 19:23:56 INFO - PROCESS | 1930 | __startTimestamp1468895036562__endTimestamp 19:23:56 INFO - PROCESS | 1930 | 19:23:56 INFO - PROCESS | 1930 | ------- Summary: start ------- 19:23:56 INFO - PROCESS | 1930 | Number of tests: 14 19:23:56 INFO - PROCESS | 1930 | 19:23:56 INFO - PROCESS | 1930 | [#0] ai-astar Cycles:10 Average:112.30 Median:114.00 stddev:3.40 (3.0%) stddev-sans-first:1.00 19:23:56 INFO - PROCESS | 1930 | Values: 103.0 114.0 113.0 114.0 114.0 113.0 114.0 111.0 114.0 113.0 19:23:56 INFO - PROCESS | 1930 | 19:23:56 INFO - PROCESS | 1930 | [#1] audio-beat-detection Cycles:10 Average:134.40 Median:133.00 stddev:27.54 (20.7%) stddev-sans-first:28.89 19:23:56 INFO - PROCESS | 1930 | Values: 146.0 112.0 137.0 197.0 127.0 129.0 127.0 106.0 157.0 106.0 19:23:56 INFO - PROCESS | 1930 | 19:23:56 INFO - PROCESS | 1930 | [#2] audio-dft Cycles:10 Average:183.20 Median:184.00 stddev:1.81 (1.0%) stddev-sans-first:1.62 19:23:56 INFO - PROCESS | 1930 | Values: 186.0 184.0 184.0 181.0 185.0 181.0 181.0 182.0 184.0 184.0 19:23:56 INFO - PROCESS | 1930 | 19:23:56 INFO - PROCESS | 1930 | [#3] audio-fft Cycles:10 Average:68.50 Median:71.50 stddev:10.83 (15.2%) stddev-sans-first:11.31 19:23:56 INFO - PROCESS | 1930 | Values: 74.0 76.0 58.0 86.0 58.0 69.0 61.0 64.0 83.0 56.0 19:23:56 INFO - PROCESS | 1930 | 19:23:56 INFO - PROCESS | 1930 | [#4] audio-oscillator Cycles:10 Average:79.70 Median:83.50 stddev:12.14 (14.5%) stddev-sans-first:12.72 19:23:56 INFO - PROCESS | 1930 | Values: 85.0 82.0 93.0 70.0 71.0 68.0 95.0 69.0 67.0 97.0 19:23:56 INFO - PROCESS | 1930 | 19:23:56 INFO - PROCESS | 1930 | [#5] imaging-gaussian-blur Cycles:10 Average:86.60 Median:87.50 stddev:1.90 (2.2%) stddev-sans-first:1.56 19:23:56 INFO - PROCESS | 1930 | Values: 90.0 85.0 88.0 85.0 88.0 84.0 88.0 86.0 87.0 85.0 19:23:56 INFO - PROCESS | 1930 | 19:23:56 INFO - PROCESS | 1930 | [#6] imaging-darkroom Cycles:10 Average:104.60 Median:94.50 stddev:21.30 (22.5%) stddev-sans-first:22.25 19:23:56 INFO - PROCESS | 1930 | Values: 94.0 94.0 95.0 144.0 146.0 95.0 95.0 94.0 95.0 94.0 19:23:56 INFO - PROCESS | 1930 | 19:23:56 INFO - PROCESS | 1930 | [#7] imaging-desaturate Cycles:10 Average:85.20 Median:85.50 stddev:1.14 (1.3%) stddev-sans-first:1.12 19:23:56 INFO - PROCESS | 1930 | Values: 84.0 87.0 86.0 85.0 85.0 85.0 86.0 86.0 83.0 85.0 19:23:56 INFO - PROCESS | 1930 | 19:23:56 INFO - PROCESS | 1930 | [#8] json-parse-financial Cycles:10 Average:53.30 Median:53.50 stddev:1.16 (2.2%) stddev-sans-first:1.05 19:23:56 INFO - PROCESS | 1930 | Values: 55.0 55.0 53.0 52.0 54.0 53.0 52.0 53.0 54.0 52.0 19:23:56 INFO - PROCESS | 1930 | 19:23:56 INFO - PROCESS | 1930 | [#9] json-stringify-tinderbox Cycles:10 Average:72.40 Median:73.00 stddev:3.95 (5.4%) stddev-sans-first:3.44 19:23:56 INFO - PROCESS | 1930 | Values: 66.0 68.0 77.0 79.0 73.0 73.0 70.0 71.0 72.0 75.0 19:23:56 INFO - PROCESS | 1930 | 19:23:56 INFO - PROCESS | 1930 | [#10] stanford-crypto-aes Cycles:10 Average:90.10 Median:86.00 stddev:7.34 (8.5%) stddev-sans-first:7.65 19:23:56 INFO - PROCESS | 1930 | Values: 94.0 102.0 88.0 85.0 84.0 87.0 83.0 83.0 93.0 102.0 19:23:56 INFO - PROCESS | 1930 | 19:23:56 INFO - PROCESS | 1930 | [#11] stanford-crypto-ccm Cycles:10 Average:145.10 Median:144.50 stddev:14.87 (10.3%) stddev-sans-first:15.48 19:23:56 INFO - PROCESS | 1930 | Values: 137.0 139.0 135.0 137.0 160.0 148.0 142.0 127.0 179.0 147.0 19:23:56 INFO - PROCESS | 1930 | 19:23:56 INFO - PROCESS | 1930 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.60 Median:163.00 stddev:1.58 (1.0%) stddev-sans-first:1.09 19:23:56 INFO - PROCESS | 1930 | Values: 167.0 163.0 162.0 166.0 163.0 163.0 163.0 163.0 163.0 163.0 19:23:56 INFO - PROCESS | 1930 | 19:23:56 INFO - PROCESS | 1930 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.50 Median:67.00 stddev:0.85 (1.3%) stddev-sans-first:0.88 19:23:56 INFO - PROCESS | 1930 | Values: 67.0 67.0 67.0 66.0 66.0 66.0 67.0 65.0 66.0 68.0 19:23:56 INFO - PROCESS | 1930 | -------- Summary: end -------- 19:23:56 INFO - PROCESS | 1930 | 19:23:57 INFO - TEST-INFO | 1930: exit 0 19:23:57 INFO - TEST-OK | kraken | took 58946ms 19:23:57 INFO - SUITE-END | took 1175s 19:23:57 INFO - Completed test suite (00:19:35) 19:23:57 INFO - javascript benchmark 19:23:57 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2986.8058576192548, 3335.3329334133177, 3346.682065203805, 3366.8316584170784, 3388.322335532894, 5602.691851948141, 5673.43265673433, 5683.886322273552, 5735.971320143399, 5745.712714364286, 5716.571257155709, 5760.7407666655, 5777.480026797587, 5790.044642633971, 5806.216160818289, 5175.353080864901, 5208.895822083556, 5222.112240919034, 5241.475852414766, 5293.629445938766, 5283.445238249986, 5301.814436494724, 5303.946960530384, 5307.071262529062, 5311.256424100629, 5196.194589838581, 5207.687538747668, 5210.088234558956, 5232.555232805216, 5291.947080529203, 4337.696361254713, 5301.6818990860675, 5318.149096144637, 5338.012467693489, 5365.517103460707, 5124.846254612367, 5134.486551344873, 5182.015417070782, 5214.478552144793, 5227.242049902743, 5589.245451863987, 5622.128570071645, 5667.121596152603, 5702.287214098238, 5781.653100813965, 6227.719752611143, 6240.625562466266, 6248.468880145193, 6253.1558239637525, 6259.593126446766, 5380.91928621071, 6257.280412752546, 6266.154069200691, 6330.715117819709, 6334.556581039329, 5888.676122813244, 5916.201312822714, 5941.376155503696, 5951.851203719942, 5954.493868021266, 6315.494760419156, 6355.968220158962, 6356.396142366468, 6364.586301890362, 6367.968160159169, 6009.308929473079, 6060.484858787053, 6126.884121540732, 6238.656873871981, 6253.093301471261, 6288.717007734663, 6315.715792789336, 6317.210348706411, 6317.431431171217, 6329.461995730322, 6210.006398976142, 6256.124142620037, 6282.968585157045, 6294.590851594632, 6311.084892690534, 6110.771402862501, 6177.722002509808, 6178.196834411588, 6341.619502829845, 6354.777582784581, 3987.2623564640635, 4877.195262781602, 4911.7298548580175, 4936.086823937511, 4942.851714448572, 4432.468103827561, 4440.955590444054, 4455.799489023002, 4458.242098843204, 4479.924818043712, 5575.498205161555, 5816.854578635483, 5841.678707671034, 5849.093390524476, 5881.735321910524, 5364.624476286623, 6268.655223962726, 6324.620522768557, 6355.3009168991375, 6366.140571022946, 5324.334458192726, 5383.542398896059, 5785.942140578624, 5796.594238403271, 5809.738561764731, 5302.655327403706, 5402.648827826179, 5406.0, 5438.293021907206, 5457.972710136424, 6258.968705156536, 6304.873902522016, 6342.365763423805, 6352.4918006559365, 6356.300806911144, 6345.8730825384155, 6346.365363463617, 6362.45919096882, 6378.585391949508, 6383.329750376143, 6323.083152942706, 6364.172657554488, 6366.045093236052, 6370.203724534434, 6389.2971773105855, 6136.120845438751, 6194.34959329282, 6228.252609686685, 6269.310375858743, 6274.090256912813, 6285.59143655661, 6333.081703153109, 6347.904781428375, 6348.047792831112, 6356.364363563606, 6037.577369584087, 6104.944249919257, 6161.014237721943, 6227.097070924781, 6229.688515574294, 4673.995091055303, 6144.969275153596, 6215.254169499689, 6219.657918814509, 6226.1905952225925, 6009.338972713085, 6044.365341639064, 6057.4851137653795, 6078.513718902565, 6091.817245482643, 5994.3705910878725, 6116.792316687851, 6140.8771824564155, 6210.813675589739, 6232.5325600581045, 5317.175837745156, 5377.516023557742, 6017.759289628366, 6048.12302216185, 6063.393660633901], "value": 5706.909413819117, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [456.3223612934792, 508.65665675668913, 535.386981905718, 537.7150110441465, 554.3237250554323, 23999.880000599995, 29798.255043623893, 31606.0, 31638.36723265533, 31656.68343316566, 31935.201619959513, 41878.371824422655, 43220.3516947246, 43257.0, 45776.542234577646, 9700.660476883293, 9707.805843883118, 9738.853917191247, 9751.658691945784, 9757.609695612167, 7492.887606685904, 9714.854277185848, 9798.41209527427, 9798.412095274307, 9825.508724563742, 8813.471191728484, 9083.636654533811, 9099.772505687344, 9106.86339704905, 11820.881791182064, 11844.407779611029, 12149.696257593543, 12167.51329946801, 12176.878231217663, 12245.632631021084, 12000.399980001052, 12093.274403535816, 12093.45579448927, 12097.93951030248, 12115.333656648882, 40982.77051688454, 41130.0, 41141.38287925684, 41267.79366103181, 41309.793451032856, 3515.9472607910902, 3700.9259814803822, 3703.2778608171416, 3778.735488515805, 4225.260579398593, 31635.0, 31709.36581268385, 31984.520232196537, 32034.839825800722, 32584.837075814707, 2903.4483448144783, 3270.9509357359657, 3282.506568994343, 3366.4108780963234, 3377.6453472385538, 491129.0887091119, 540775.0, 542062.289688553, 542618.0, 544482.0, 33343.66656333405, 59062.0, 59135.40864591299, 59382.0, 59451.0, 33794.831025845204, 35253.64746352555, 35268.0, 35272.0, 35343.4698479523, 14786.92606536982, 15066.698666026618, 15081.622959426102, 15086.773698394532, 15106.622334441507, 12664.0, 12764.744705105846, 12780.233186008869, 12796.424160912782, 12804.743905121846, 6934.895976560356, 7752.8449431012195, 7768.456208065381, 7835.764927052198, 7867.921320786719, 3074.6632559255127, 3124.1877111950603, 3219.806811591312, 3224.290656055664, 3247.0908145719236, 837.7905523619095, 911.7583840282331, 1035.0736091198335, 1066.7279843639978, 1074.08702602787, 29921.40157196844, 30041.549376759365, 30565.694343056286, 30804.075877723255, 30860.845695771826, 1122.9775404491863, 1141.8743938166795, 1147.6384938744268, 1170.3387586013876, 1180.8464899563173, 15254.694906101815, 15708.371665133494, 15925.522234332988, 15941.12323822201, 16016.599585010468, 716946.0, 718260.0, 722370.0, 723374.5975241503, 724778.0, 51197.976040478985, 59427.70286148541, 59956.700216499514, 59980.80038399208, 60047.39952600506, 1178.6699724077273, 1224.55303814109, 1387.7016441465134, 1424.8575142485668, 1439.503371336887, 27199.0, 31641.0, 31716.36567268688, 32427.27232047383, 32486.51270230901, 50093.74953125211, 50853.49146508636, 51286.230706538685, 51292.974140516235, 51365.48634513607, 644569.0, 649521.0, 655495.0, 659018.0, 928330.0, 26362.340941476617, 26940.86529567418, 26982.32544186411, 27302.0, 27878.58182127229, 47933.0, 47965.76017119892, 48341.27488087611, 49502.01991920283, 49573.00853982972, 39717.00707482336, 39998.40002399908, 40034.0, 41054.38418423666, 41145.38281925714, 39551.80224098861, 40048.1990360197, 40383.3942490857, 41214.38178427386, 41241.793791030854, 38894.61105389023, 38992.83021509359, 39182.41226381664, 39522.60477395189, 39646.60353396429, 33002.83498582492, 34587.30825383528, 35306.470402943465, 35349.0, 35629.64370356263, 25187.61468119271, 25319.62020569656, 25324.873375633004, 25519.23442296734, 26008.609870851575], "value": 19196.94841393628, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [343.7920058364688, 345.4420563259698, 380.2983495450893, 389.40032350180724, 414.4342971843433, 13564.321783910802, 17278.740818887727, 21464.0, 21509.03209355579, 21624.0, 17404.0, 21418.571628567457, 21443.57112857746, 22282.777172228274, 22330.218442354522, 5875.500582450497, 6055.182550355712, 6127.295361033472, 6136.386361363872, 6670.833229169285, 6620.23867255267, 6621.205455345341, 6689.030090636855, 6696.397324240817, 6702.463802895781, 6348.174737284146, 6626.933730662704, 6630.138082049351, 6633.436157926558, 6634.966825165868, 6329.746810127589, 6549.246836613779, 6565.441937435324, 6643.966780166116, 6645.169353830771, 5717.199592057115, 5740.913886291709, 5745.052066409037, 5752.568557358215, 5756.971215143897, 26027.869860650764, 26681.332966675793, 27017.054403095994, 27023.919043238246, 27181.592276115676, 2468.0004598137784, 2665.746754058361, 2676.585129304961, 2819.2951762059483, 2964.4960356739407, 13480.460781568725, 15182.772258416133, 16094.0, 16334.83665163345, 16415.589610259838, 1517.6054225901235, 1709.0, 1759.472158352489, 1763.4709587123812, 1845.2342277954633, 91847.0, 113037.0, 115192.0, 125157.0, 127665.36167319104, 8655.913440865637, 9883.308168428284, 10072.740907386577, 10083.899161008296, 10370.170386369073, 12399.0, 12487.75024499505, 13142.40859161321, 13688.384022719001, 13752.174869507862, 6892.551984121016, 8115.458062968017, 8473.279771219391, 8619.870701939475, 8685.218330350172, 8611.440256383315, 8612.5693715314, 8617.827643447095, 8648.308135349156, 8713.128687131237, 3725.453936616274, 4536.364908912755, 4595.379623750818, 4646.697964632355, 4691.483936766953, 1627.2189349112493, 1903.5812121333286, 1951.1024928533034, 1952.9804701953083, 1960.9313674021626, 885.4775682347446, 986.156835905305, 1000.6447711062614, 1025.5590096258532, 1033.7570670892335, 16332.775041871908, 22510.54978900413, 22622.886885565465, 22636.207732729275, 22640.433989150402, 961.2838435365697, 967.4002118686319, 968.660968660963, 1027.6146445083093, 1040.5005597313332, 8624.956875215585, 11478.024367928652, 11664.0, 11751.47118379675, 11853.9331460169, 512132.0, 518641.4067929712, 519304.0, 615595.0, 621158.0, 117724.0, 125675.0, 127373.36313318374, 128806.0, 138330.0, 972.1153750087386, 976.3751199232497, 1017.2218253036433, 1017.7811770469384, 1018.2007124407453, 17304.65390692204, 19254.35965073175, 19339.613207735485, 20070.799292006894, 20304.289349872684, 50349.74125646888, 58473.70763146327, 59082.0, 59590.0, 59913.700431497564, 78303.21696782959, 82737.0, 83584.49384238222, 83643.5817820907, 84383.57808210919, 7245.710171593288, 7512.5492470451945, 7550.848983020199, 7774.805629859073, 8426.368022398223, 12438.315892625993, 13403.0, 13403.994700397314, 13680.97392695532, 13768.793468097787, 5562.5549956002615, 6586.571872828251, 11037.123688972733, 11071.778564428505, 11708.121890858049, 11448.026917712088, 11526.308421494738, 11640.767184656428, 11766.705832353917, 11804.409779511161, 11378.374189419661, 11734.589289374831, 12031.578894738643, 12097.092718046006, 12121.151519393557, 6491.578047426902, 7488.775336739906, 7574.204708505527, 7655.272749088827, 7686.615669216405, 10580.682579522627, 11463.598774042868, 11532.769344613229, 11712.648620541397, 11921.642350729491], "value": 9766.73204511332, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1355.8644135586437, 1436.8778653814425, 1509.9471518496855, 1665.450401367549, 1890.659681257374, 7815.140334563193, 8458.19647133522, 8694.478331300126, 8694.956525217382, 8712.477251364915, 8422.199891010345, 8455.154484551556, 8605.612747426365, 8687.913120868805, 8689.0, 2088.968665470019, 2159.7840215978354, 2310.6418505131733, 2362.3149286706835, 2362.397588614901, 2154.482924098221, 2160.82713382929, 2162.686410470481, 2299.5975704251773, 2366.6213405855065, 2333.801626861719, 2337.3689103941924, 2340.1692399198296, 2341.590221711202, 2347.7769611886843, 2301.1945818963313, 2316.397736588482, 2335.953280934372, 2343.695319608457, 2348.894299756515, 2076.9065392057396, 2262.6719125726677, 2280.8289378296695, 2326.744058153619, 2333.0, 8643.481391116553, 8718.825623487495, 8719.0, 8731.039585645574, 8737.169968852948, 5144.6655967362, 5297.841064768063, 5302.708351040691, 5303.840884773463, 5312.043832110218, 2888.812227205225, 3527.3650742866275, 3598.24436868258, 3601.4957905893198, 3601.621829707869, 1085.10478854945, 1087.255230167338, 1097.6706987903597, 1106.3030290916752, 1107.5735841700923, 11506.13703972197, 11714.121440891968, 11737.882621173765, 11749.588764393206, 11761.647150585497, 4011.9799401002806, 4146.543880173179, 4530.821986283524, 4552.362669226245, 4753.857384278477, 10074.748131296776, 10117.988201179971, 10124.797504050026, 10130.696079117639, 10137.797244055226, 5333.919991200135, 5352.57179425645, 5373.7581808818695, 5375.650582712111, 5392.191171324333, 4814.759262036884, 4898.755062246873, 4914.311996320518, 4915.0415668944215, 4922.483139270396, 3749.643783840531, 3864.9806750966063, 3868.574456809749, 3897.4738410314812, 3906.4726261954843, 1253.6928452529007, 1288.2077522323702, 1305.3473263368317, 1306.915050521713, 1311.9081664283408, 909.981800363989, 912.4479689787715, 922.3506025593241, 922.3820040572832, 966.4201479112477, 5260.763265653055, 5624.240727501817, 5855.502282306052, 6004.729787159588, 6101.8474538136015, 870.5647176411794, 978.921686265097, 979.7207795778312, 981.2983716642635, 1005.6731562242301, 3860.9034774130873, 4011.618896204856, 4016.819243134066, 4059.878203653836, 4099.344104943195, 10361.119304859021, 10410.0, 10755.139588833032, 10767.83848242277, 10853.0, 10577.471126443646, 10781.568737250578, 10786.676399708022, 10795.40625265618, 10797.730056748644, 8133.796655083552, 8194.836103277901, 8355.707550235713, 8360.331173505983, 8366.707165249187, 5853.414658534283, 5900.940990590039, 5901.911471327848, 5908.881822363614, 5988.520918326611, 9966.302358835102, 10116.797664046826, 10122.240831937486, 10137.0, 10141.0, 9964.0, 10113.848292275474, 10160.949195254272, 10230.283880128618, 10362.689119326433, 7502.099748030271, 7547.16981132064, 7660.34887034586, 7664.961675191588, 7675.923240767745, 8173.959130204311, 8264.917350826414, 8328.292095171979, 8341.666333346599, 8343.499390036617, 7514.286142816423, 7536.397088233038, 7541.396688265036, 7702.537847729154, 7730.497517661334, 7163.10461192351, 7338.009368735153, 7350.889736653848, 7353.374963128194, 7566.054243219598, 6837.760678376432, 6849.0, 6917.515773895779, 6921.065656136358, 6930.06444130036, 4933.087378834927, 4935.210366341368, 4953.653244272867, 4955.2567114933045, 5016.498350164954, 4931.211006238985, 5028.673136246132, 5048.545630893091, 5083.364579427572, 5124.5131712487255], "value": 4576.954486540296, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [305.34808184526037, 307.5248740695625, 312.9186411533001, 317.0630786027291, 319.32144193588624, 5455.618106732531, 5473.397926228112, 5476.315460567429, 5479.917801232975, 5544.3346798384255, 4576.359309696637, 5381.838544843661, 5381.838544843661, 5418.674879507233, 5436.891262174764, 3841.8463261469506, 3895.8636447724334, 4059.7158198926086, 4110.054687421894, 4133.0, 3514.297140571883, 4017.236725022251, 4085.3871919212206, 4088.7751173685438, 4142.399352093945, 3970.8484539483516, 4077.7145599808027, 4079.8164082616204, 4080.1635664688815, 4083.5099788025477, 3221.822799746013, 3938.4289278054807, 3978.9403158952637, 4042.58509521668, 4125.381192821071, 2960.86676099576, 3529.188286694049, 3551.254236610315, 3555.235624340779, 3563.376409128392, 4294.914101717979, 4617.030423611046, 5145.76844042019, 5253.737313134328, 5349.625526213166, 4479.723278865507, 4554.681172317939, 4690.554397332248, 4698.778317637405, 4716.457607375161, 5574.526165275956, 5599.776008959637, 5615.213870058195, 5627.155926611, 5630.436956304377, 3426.6573342665783, 3920.5491368492703, 3936.3308237599676, 3947.144841924291, 3962.0094976255937, 5289.233061206103, 5317.388500322435, 5534.667919924817, 5562.415946325618, 5934.673592952386, 3771.641694039062, 3842.654161125512, 3875.6124387561017, 3879.9806000969816, 3882.456456096149, 3756.6431189037, 3937.901552461154, 3944.4872166618156, 3953.4267531207815, 3981.223661386002, 3590.6947909427467, 4252.4897012358715, 4267.679924005712, 4307.719998200107, 4626.583607475343, 5685.630434021776, 5714.371419143891, 5730.169125476783, 5738.799142030009, 5743.908657355089, 1312.744014917082, 1330.082243252153, 1348.7167694784205, 1351.398627610704, 1354.817099691529, 674.844785699292, 821.3470291118456, 830.7092517618785, 838.5723281126669, 839.6515446089926, 4545.704529205591, 5048.873778155502, 5101.974490127525, 5245.763940622681, 5258.792136796551, 5821.359650438449, 5828.795992140254, 5829.417058294137, 5880.382559831156, 5888.730670264024, 4907.705537667752, 5049.444561098277, 5298.28473156119, 5310.751973286275, 5337.784819299947, 2584.3539115221197, 2647.391100047, 3013.9547906781418, 3042.756579473637, 3061.5101583746496, 4967.726775027336, 5804.448577385226, 5950.464458198783, 5976.28079223952, 5988.9401105989255, 5078.562290100773, 5576.832695019156, 5954.970225148846, 5971.014492753623, 5976.760929562769, 5941.851453713692, 5971.313298970674, 5974.61165024272, 5980.073088671263, 5986.5809393342915, 5221.407470721467, 5419.430959749169, 5498.776162645583, 5807.245058142415, 5812.3025236971835, 5822.912656310074, 5826.3977406213635, 5828.70856457167, 5848.157830283447, 5849.94150058494, 6120.265568131853, 6131.63210207389, 6146.0, 6156.722947467464, 6173.81478555644, 3159.984200079077, 3202.9199270017502, 3815.4658347831883, 3927.312720273998, 4045.4134150548593, 5054.898902022012, 5782.571320045507, 5846.123081537803, 5875.383084776037, 5900.291964964232, 4866.9847375914305, 5511.834644960658, 5733.684647344269, 5772.393898640582, 5833.941660583339, 5339.973300133474, 5703.574106473382, 5709.885802284014, 5724.084146536534, 5753.0, 4425.557444255549, 5116.769745361393, 5315.787368505371, 5574.414686458026, 5596.664200148004, 4448.798824317351, 5193.792248310177, 5584.972075139598, 5595.916061259003, 5718.656880587178, 4853.878653033702, 5587.0, 5594.510442852891, 5612.466815652507, 5621.100623900156], "value": 4119.154966631746, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [737.6311844077961, 752.4319139050017, 760.6843160088561, 761.3262262897334, 854.7863034241442, 15224.923875380635, 15968.201589920487, 15982.920085399572, 15988.76016859748, 16003.27985240664, 12205.084618653615, 14679.853201467982, 14814.55556333309, 14836.48072317469, 14846.777298340534, 695.3046953046953, 881.391839630656, 909.7634615000114, 911.5578944212052, 916.1571354353977, 860.4665107633276, 870.1516021878681, 870.4907629037021, 871.0505548951642, 873.1050673060132, 739.3789217057671, 934.3319526538523, 935.3078721745893, 937.4047479850276, 940.9247260219166, 924.7734305095261, 951.1202138022302, 952.2239374909427, 953.0755167487491, 955.7801705607749, 702.1609177033447, 702.2521015118933, 703.0754557756534, 704.2042491984076, 707.3633729643311, 14397.424103035866, 14412.13527188372, 14422.84617230619, 14439.927800361036, 14452.205128717917, 12746.936265318614, 13266.800997985038, 13868.722625547534, 14005.0, 14054.789178162337, 11127.975198180595, 12181.817272740916, 12289.56986505477, 12295.4467048983, 12813.615591532269, 3681.2005035137067, 3709.77741335518, 3738.420544815531, 3756.5116534850567, 3843.98078009611, 103765.96234037714, 104348.0, 106868.0, 106916.46541767319, 107448.31379215504, 10761.677149685522, 10913.563457461612, 10979.1765617579, 11005.669829905115, 11459.541618335339, 21455.89272053661, 21463.67804482934, 21466.356009319745, 21470.355889323346, 21472.141114355563, 8017.559034253172, 8269.8346033079, 8295.668173273121, 8319.750407487785, 8338.62476188573, 4431.933520997188, 4565.611922986517, 4571.1086338164405, 4589.54104589545, 4755.5719985201495, 3426.862925482953, 3563.376409128392, 3565.16218688608, 3611.7110631149444, 3852.7110466715108, 709.9396551293095, 715.506300652548, 720.5928650312557, 723.3092396761081, 732.2604169788552, 591.9585629006015, 633.822529691687, 690.9654517274116, 691.2983321928247, 716.7383904874788, 9364.438133711863, 9394.877512218947, 9418.670346537838, 9841.163501102343, 9892.851607225897, 679.3342524326089, 683.1017212365723, 699.4439420660588, 706.6466766616692, 708.1164103484706, 4570.634349252052, 4783.6651434399955, 4799.568038876518, 4847.830325938575, 4914.311996320518, 97194.0, 106713.0, 148266.51733482527, 159996.0, 160751.0, 88133.0, 95859.0, 114925.0, 130384.0, 130970.0, 91056.0, 101573.49213253887, 107196.4640176794, 107474.0, 108574.45712771386, 8686.39195256326, 8966.193042626195, 9053.728388148365, 9084.046175151514, 9190.40262382943, 46395.53604463912, 48630.27054594113, 50495.485135446, 59123.704381477815, 59336.40663593309, 68851.0, 97163.0, 98261.50869245894, 98588.0, 100171.49914250674, 11958.820617690568, 11961.162718609614, 12630.557930472389, 12665.556705515262, 13467.528636497676, 14561.635959101108, 15138.697226055638, 15330.540083797057, 15348.275159007364, 16325.918370408546, 13208.80186797218, 13901.860981390057, 14844.035137716446, 15064.096154230781, 15064.322105505498, 13725.931370343083, 13983.650408739863, 14366.353514091681, 14806.62983425423, 14843.183624900734, 13681.384337704629, 13906.51327203543, 14183.0, 14311.069780464237, 14331.713365732418, 11504.884951150381, 11511.36687482163, 11564.190506664452, 11903.107266955187, 12114.939425302817, 9626.426738298966, 10792.298500597437, 10868.67393978182, 11561.537538498702, 11805.527778888749], "value": 7870.834717411294, "unit": "score"}], "name": "dromaeo_css", "value": 7358.773286413718, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 114.0, 113.0, 114.0, 114.0, 113.0, 114.0, 111.0, 114.0, 113.0], "value": 112.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 112.0, 137.0, 197.0, 127.0, 129.0, 127.0, 106.0, 157.0, 106.0], "value": 134.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 184.0, 184.0, 181.0, 185.0, 181.0, 181.0, 182.0, 184.0, 184.0], "value": 183.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 58.0, 86.0, 58.0, 69.0, 61.0, 64.0, 83.0, 56.0], "value": 68.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 82.0, 93.0, 70.0, 71.0, 68.0, 95.0, 69.0, 67.0, 97.0], "value": 79.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 85.0, 88.0, 85.0, 88.0, 84.0, 88.0, 86.0, 87.0, 85.0], "value": 86.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 144.0, 146.0, 95.0, 95.0, 94.0, 95.0, 94.0], "value": 104.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 87.0, 86.0, 85.0, 85.0, 85.0, 86.0, 86.0, 83.0, 85.0], "value": 85.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 53.0, 52.0, 54.0, 53.0, 52.0, 53.0, 54.0, 52.0], "value": 53.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 77.0, 79.0, 73.0, 73.0, 70.0, 71.0, 72.0, 75.0], "value": 72.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 102.0, 88.0, 85.0, 84.0, 87.0, 83.0, 83.0, 93.0, 102.0], "value": 90.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 139.0, 135.0, 137.0, 160.0, 148.0, 142.0, 127.0, 179.0, 147.0], "value": 145.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 163.0, 162.0, 166.0, 163.0, 163.0, 163.0, 163.0, 163.0, 163.0], "value": 163.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 67.0, 66.0, 66.0, 66.0, 67.0, 65.0, 66.0, 68.0], "value": 66.5, "unit": "score"}], "name": "kraken", "value": 1445.4999999999998, "alertThreshold": 2.0}]} 19:23:57 INFO - Return code: 0 19:23:57 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:23:57 INFO - # TBPL SUCCESS # 19:23:57 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:23:57 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:23:57 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:23:57 INFO - 2016-07-18 19:23:57.852 screenresolution[1934:14389] starting screenresolution argv=screenresolution get 19:23:57 INFO - 2016-07-18 19:23:57.855 screenresolution[1934:14389] Display 0: 1600x1200x32@60 19:23:57 INFO - 2016-07-18 19:23:57.861 screenresolution[1935:14392] starting screenresolution argv=screenresolution list 19:23:57 INFO - Available Modes on Display 0 19:23:57 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:23:57 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:23:57 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:23:57 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:23:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:23:58 INFO - Intel Iris: 19:23:58 INFO - Chipset Model: Intel Iris 19:23:58 INFO - Type: GPU 19:23:58 INFO - Bus: Built-In 19:23:58 INFO - VRAM (Dynamic, Max): 1536 MB 19:23:58 INFO - Vendor: Intel (0x8086) 19:23:58 INFO - Device ID: 0x0a2e 19:23:58 INFO - Revision ID: 0x0009 19:23:58 INFO - Displays: 19:23:58 INFO - Display: 19:23:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:23:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:23:58 INFO - Display Serial Number: 1600x1200 60 19:23:58 INFO - Main Display: Yes 19:23:58 INFO - Mirror: Off 19:23:58 INFO - Online: Yes 19:23:58 INFO - Rotation: Supported 19:23:58 INFO - Return code: 0 19:23:58 INFO - Running post-action listener: _resource_record_post_action 19:23:58 INFO - ##### 19:23:58 INFO - ##### Finished run-tests step (success) 19:23:58 INFO - ##### 19:23:58 INFO - Running post-run listener: _resource_record_post_run 19:23:58 INFO - Total resource usage - Wall time: 1210s; CPU: 53.0%; Read bytes: 1290240; Write bytes: 701263360; Read time: 16; Write time: 2008 19:23:58 INFO - TinderboxPrint: CPU usage
53.3% 19:23:58 INFO - TinderboxPrint: I/O read bytes / time
1,290,240 / 16 19:23:58 INFO - TinderboxPrint: I/O write bytes / time
701,263,360 / 2,008 19:23:58 INFO - TinderboxPrint: CPU idle
2,261.5 (46.7%) 19:23:58 INFO - TinderboxPrint: CPU system
963.6 (19.9%) 19:23:58 INFO - TinderboxPrint: CPU user
1,616.5 (33.4%) 19:23:58 INFO - TinderboxPrint: Swap in / out
408,543,232 / 0 19:23:58 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 394897920; Write bytes: 388990976; Read time: 29336; Write time: 716 19:23:58 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 1286144; Write bytes: 290452992; Read time: 16; Write time: 1246 19:23:58 INFO - Running post-run listener: _upload_blobber_files 19:23:58 INFO - Blob upload gear active. 19:23:58 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:23:58 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 19:23:58 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'] 19:23:58 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 19:23:58 INFO - (blobuploader) - INFO - Open directory for files ... 19:23:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:23:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:23:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:24:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:24:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:24:00 INFO - (blobuploader) - INFO - Done attempting. 19:24:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:24:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:24:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:24:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:24:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:24:01 INFO - (blobuploader) - INFO - Done attempting. 19:24:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:24:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:24:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:24:03 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:24:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:24:03 INFO - (blobuploader) - INFO - Done attempting. 19:24:03 INFO - (blobuploader) - INFO - Iteration through files over. 19:24:03 INFO - Return code: 0 19:24:03 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:24:03 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:24:03 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4cd3feeab83dd42403ef01ab00bffda06038a1879c7fb46741c3288ce5d23a023a4ff9b25b1a9f5e3db61363d944b84a2decc9c6a81681887beb149e21beea28", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cbbe5c137008e9cd5552a9067be78216ac1b02781b01ff5809c6ecb32cb00c98708825600f62415dcd93b7c258220a0d09d18dfb62fe1dcea876c8eb3fbe67fa", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3b80708cfb13aff6a7aacd37be6fbb80f5332bedb7adf6ad9483458d3073f719d8d19f1eaa2a273ae388f8df209de49d6abbbd17239e4522f5a5a66c4c8f691c"} 19:24:03 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:24:03 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:24:03 INFO - Contents: 19:24:03 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4cd3feeab83dd42403ef01ab00bffda06038a1879c7fb46741c3288ce5d23a023a4ff9b25b1a9f5e3db61363d944b84a2decc9c6a81681887beb149e21beea28", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cbbe5c137008e9cd5552a9067be78216ac1b02781b01ff5809c6ecb32cb00c98708825600f62415dcd93b7c258220a0d09d18dfb62fe1dcea876c8eb3fbe67fa", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3b80708cfb13aff6a7aacd37be6fbb80f5332bedb7adf6ad9483458d3073f719d8d19f1eaa2a273ae388f8df209de49d6abbbd17239e4522f5a5a66c4c8f691c"} 19:24:03 INFO - Running post-run listener: copy_logs_to_upload_dir 19:24:03 INFO - Copying logs to upload dir... 19:24:03 INFO - mkdir: /builds/slave/test/build/upload/logs 19:24:03 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.239841 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-07-18 19:24:03.415669) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-18 19:24:03.419452) ========= 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.m8C0zbtCSK/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.qe29MHCqtW/Listeners TMPDIR=/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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/4cd3feeab83dd42403ef01ab00bffda06038a1879c7fb46741c3288ce5d23a023a4ff9b25b1a9f5e3db61363d944b84a2decc9c6a81681887beb149e21beea28", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cbbe5c137008e9cd5552a9067be78216ac1b02781b01ff5809c6ecb32cb00c98708825600f62415dcd93b7c258220a0d09d18dfb62fe1dcea876c8eb3fbe67fa", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3b80708cfb13aff6a7aacd37be6fbb80f5332bedb7adf6ad9483458d3073f719d8d19f1eaa2a273ae388f8df209de49d6abbbd17239e4522f5a5a66c4c8f691c"} build_url:https://queue.taskcluster.net/v1/task/WFwGERC8Reqp_v0iQbVGkg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011366 build_url: 'https://queue.taskcluster.net/v1/task/WFwGERC8Reqp_v0iQbVGkg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4cd3feeab83dd42403ef01ab00bffda06038a1879c7fb46741c3288ce5d23a023a4ff9b25b1a9f5e3db61363d944b84a2decc9c6a81681887beb149e21beea28", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cbbe5c137008e9cd5552a9067be78216ac1b02781b01ff5809c6ecb32cb00c98708825600f62415dcd93b7c258220a0d09d18dfb62fe1dcea876c8eb3fbe67fa", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3b80708cfb13aff6a7aacd37be6fbb80f5332bedb7adf6ad9483458d3073f719d8d19f1eaa2a273ae388f8df209de49d6abbbd17239e4522f5a5a66c4c8f691c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-18 19:24:03.451227) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 19:24:03.451524) ========= 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.m8C0zbtCSK/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.qe29MHCqtW/Listeners TMPDIR=/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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.005308 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 19:24:03.499465) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-18 19:24:03.499740) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-18 19:24:03.500031) ========= ========= Total master_lag: 0.18 =========