builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0327 starttime: 1468428407.25 results: success (0) buildid: 20160713065327 builduid: 58f35192df144429924015cc81a0a36b revision: 845645460806f28a8f249c5d3c60f07b46cef63c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-13 09:46:47.253727) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-13 09:46:47.254490) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-13 09:46:47.255175) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-13 09:46:47.279784) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 09:46:47.280127) ========= 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.86SyqnmCSa/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.agCptg3otQ/Listeners TMPDIR=/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/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-13 09:46:47-- 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% 17.5M=0.001s 2016-07-13 09:46:47 (17.5 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.132844 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 09:46:47.437281) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 09:46:47.437781) ========= 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.86SyqnmCSa/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.agCptg3otQ/Listeners TMPDIR=/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/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.025791 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 09:46:47.486989) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-13 09:46:47.487355) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 845645460806f28a8f249c5d3c60f07b46cef63c --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 845645460806f28a8f249c5d3c60f07b46cef63c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.86SyqnmCSa/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.agCptg3otQ/Listeners TMPDIR=/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/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-13 09:46:47,570 truncating revision to first 12 chars 2016-07-13 09:46:47,570 Setting DEBUG logging. 2016-07-13 09:46:47,570 attempt 1/10 2016-07-13 09:46:47,570 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/845645460806?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-13 09:46:48,469 unpacking tar archive at: fx-team-845645460806/testing/mozharness/ program finished with exit code 0 elapsedTime=1.178919 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-13 09:46:48.687881) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-13 09:46:48.688198) ========= script_repo_revision: 845645460806f28a8f249c5d3c60f07b46cef63c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-13 09:46:48.688624) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-13 09:46:48.688904) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-13 09:46:48.706438) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-07-13 09:46:48.706719) ========= /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.86SyqnmCSa/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.agCptg3otQ/Listeners TMPDIR=/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 09:46:48 INFO - MultiFileLogger online at 20160713 09:46:48 in /builds/slave/test 09:46:48 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 09:46:48 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:46:48 INFO - {'append_to_log': False, 09:46:48 INFO - 'base_work_dir': '/builds/slave/test', 09:46:48 INFO - 'blob_upload_branch': 'Fx-Team', 09:46:48 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:46:48 INFO - 'branch': 'Fx-Team', 09:46:48 INFO - 'buildbot_json_path': 'buildprops.json', 09:46:48 INFO - 'config_files': ('talos/mac_config.py',), 09:46:48 INFO - 'default_actions': ('clobber', 09:46:48 INFO - 'read-buildbot-config', 09:46:48 INFO - 'download-and-extract', 09:46:48 INFO - 'populate-webroot', 09:46:48 INFO - 'create-virtualenv', 09:46:48 INFO - 'install', 09:46:48 INFO - 'run-tests'), 09:46:48 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:46:48 INFO - 'download_minidump_stackwalk': True, 09:46:48 INFO - 'download_symbols': 'ondemand', 09:46:48 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:46:48 INFO - 'tooltool.py': '/tools/tooltool.py', 09:46:48 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:46:48 INFO - '/tools/misc-python/virtualenv.py')}, 09:46:48 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:46:48 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:46:48 INFO - 'installer_path': 'installer.exe', 09:46:48 INFO - 'log_level': 'info', 09:46:48 INFO - 'log_name': 'talos', 09:46:48 INFO - 'log_to_console': True, 09:46:48 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:46:48 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:46:48 INFO - 'opt_config_files': (), 09:46:48 INFO - 'pip_index': False, 09:46:48 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:46:48 INFO - 'cmd': ('bash', 09:46:48 INFO - '-c', 09:46:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:46:48 INFO - 'enabled': True, 09:46:48 INFO - 'halt_on_failure': False, 09:46:48 INFO - 'name': 'check_screen_resolution'},), 09:46:48 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:46:48 INFO - 'cmd': ('bash', 09:46:48 INFO - '-c', 09:46:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:46:48 INFO - 'enabled': True, 09:46:48 INFO - 'halt_on_failure': False, 09:46:48 INFO - 'name': 'check_screen_resolution'},), 09:46:48 INFO - 'run_cmd_checks_enabled': True, 09:46:48 INFO - 'sps_profile': False, 09:46:48 INFO - 'sps_profile_interval': 0, 09:46:48 INFO - 'suite': 'dromaeojs', 09:46:48 INFO - 'system_bits': '32', 09:46:48 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:46:48 INFO - 'title': 't-yosemite-r7-0327', 09:46:48 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:46:48 INFO - 'use_talos_json': True, 09:46:48 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:46:48 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:46:48 INFO - 'work_dir': 'build'} 09:46:48 INFO - ##### 09:46:48 INFO - ##### Running clobber step. 09:46:48 INFO - ##### 09:46:48 INFO - Running pre-action listener: _resource_record_pre_action 09:46:48 INFO - Running main action method: clobber 09:46:48 INFO - rmtree: /builds/slave/test/build 09:46:48 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:46:50 INFO - Running post-action listener: _resource_record_post_action 09:46:50 INFO - ##### 09:46:50 INFO - ##### Finished clobber step (success) 09:46:50 INFO - ##### 09:46:50 INFO - ##### 09:46:50 INFO - ##### Running read-buildbot-config step. 09:46:50 INFO - ##### 09:46:50 INFO - Running pre-action listener: _resource_record_pre_action 09:46:50 INFO - Running main action method: read_buildbot_config 09:46:50 INFO - Using buildbot properties: 09:46:50 INFO - { 09:46:50 INFO - "project": "", 09:46:50 INFO - "product": "firefox", 09:46:50 INFO - "script_repo_revision": "production", 09:46:50 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 09:46:50 INFO - "repository": "", 09:46:50 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 09:46:50 INFO - "buildid": "20160713065327", 09:46:50 INFO - "pgo_build": "False", 09:46:50 INFO - "basedir": "/builds/slave/test", 09:46:50 INFO - "buildnumber": 104, 09:46:50 INFO - "slavename": "t-yosemite-r7-0327", 09:46:50 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 09:46:50 INFO - "platform": "yosemite_r7", 09:46:50 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 09:46:50 INFO - "branch": "fx-team", 09:46:50 INFO - "repo_path": "integration/fx-team", 09:46:50 INFO - "revision": "845645460806f28a8f249c5d3c60f07b46cef63c", 09:46:50 INFO - "stage_platform": "macosx64", 09:46:50 INFO - "builduid": "58f35192df144429924015cc81a0a36b", 09:46:50 INFO - "slavebuilddir": "test" 09:46:50 INFO - } 09:46:50 INFO - Found installer url https://queue.taskcluster.net/v1/task/OXPh7zg_RsOreeknn0zseQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 09:46:50 INFO - Running post-action listener: _resource_record_post_action 09:46:50 INFO - ##### 09:46:50 INFO - ##### Finished read-buildbot-config step (success) 09:46:50 INFO - ##### 09:46:50 INFO - ##### 09:46:50 INFO - ##### Running download-and-extract step. 09:46:50 INFO - ##### 09:46:50 INFO - Running pre-action listener: _resource_record_pre_action 09:46:50 INFO - Running main action method: download_and_extract 09:46:50 INFO - mkdir: /builds/slave/test/build/tests 09:46:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:46:50 INFO - https://queue.taskcluster.net/v1/task/OXPh7zg_RsOreeknn0zseQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 09:46:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OXPh7zg_RsOreeknn0zseQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 09:46:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OXPh7zg_RsOreeknn0zseQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 09:46:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OXPh7zg_RsOreeknn0zseQ/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 09:46:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OXPh7zg_RsOreeknn0zseQ/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 09:46:52 INFO - Downloaded 1199 bytes. 09:46:52 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 09:46:52 INFO - Using the following test package requirements: 09:46:52 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 09:46:52 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:46:52 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 09:46:52 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:46:52 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 09:46:52 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:46:52 INFO - u'jsshell-mac.zip'], 09:46:52 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:46:52 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 09:46:52 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 09:46:52 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:46:52 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 09:46:52 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:46:52 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 09:46:52 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:46:52 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 09:46:52 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:46:52 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 09:46:52 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:46:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:46:52 INFO - https://queue.taskcluster.net/v1/task/OXPh7zg_RsOreeknn0zseQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:46:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OXPh7zg_RsOreeknn0zseQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:46:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OXPh7zg_RsOreeknn0zseQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:46:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OXPh7zg_RsOreeknn0zseQ/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 09:46:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OXPh7zg_RsOreeknn0zseQ/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 09:46:53 INFO - Downloaded 18822771 bytes. 09:46:53 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'] 09:46:53 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 09:46:56 INFO - Return code: 0 09:46:56 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 09:46:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:46:56 INFO - https://queue.taskcluster.net/v1/task/OXPh7zg_RsOreeknn0zseQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:46:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OXPh7zg_RsOreeknn0zseQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:46:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OXPh7zg_RsOreeknn0zseQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:46:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OXPh7zg_RsOreeknn0zseQ/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 09:46:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OXPh7zg_RsOreeknn0zseQ/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 09:46:57 INFO - Downloaded 18822771 bytes. 09:46:57 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'] 09:46:57 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 09:47:00 INFO - Return code: 0 09:47:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:47:00 INFO - https://queue.taskcluster.net/v1/task/OXPh7zg_RsOreeknn0zseQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 09:47:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OXPh7zg_RsOreeknn0zseQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 09:47:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OXPh7zg_RsOreeknn0zseQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 09:47:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OXPh7zg_RsOreeknn0zseQ/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 09:47:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OXPh7zg_RsOreeknn0zseQ/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 09:47:01 INFO - Downloaded 14403317 bytes. 09:47:01 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'] 09:47:01 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 09:47:02 INFO - Return code: 0 09:47:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:47:02 INFO - https://queue.taskcluster.net/v1/task/OXPh7zg_RsOreeknn0zseQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 09:47:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OXPh7zg_RsOreeknn0zseQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:47:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OXPh7zg_RsOreeknn0zseQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:47:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OXPh7zg_RsOreeknn0zseQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 09:47:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OXPh7zg_RsOreeknn0zseQ/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 09:47:05 INFO - Downloaded 112753794 bytes. 09:47:05 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/OXPh7zg_RsOreeknn0zseQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:47:05 INFO - mkdir: /builds/slave/test/properties 09:47:05 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:47:05 INFO - Writing to file /builds/slave/test/properties/build_url 09:47:05 INFO - Contents: 09:47:05 INFO - build_url:https://queue.taskcluster.net/v1/task/OXPh7zg_RsOreeknn0zseQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:47:07 INFO - Running post-action listener: _resource_record_post_action 09:47:07 INFO - Running post-action listener: set_extra_try_arguments 09:47:07 INFO - ##### 09:47:07 INFO - ##### Finished download-and-extract step (success) 09:47:07 INFO - ##### 09:47:07 INFO - ##### 09:47:07 INFO - ##### Running populate-webroot step. 09:47:07 INFO - ##### 09:47:07 INFO - Running pre-action listener: _resource_record_pre_action 09:47:07 INFO - Running main action method: populate_webroot 09:47:07 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:47:07 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:47:07 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:47:07 INFO - '--tppagecycles', 09:47:07 INFO - '7'], 09:47:07 INFO - 'tests': ['tsvgm']}}, 09:47:07 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:47:07 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:47:07 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:47:07 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:47:07 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:47:07 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:47:07 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:47:07 INFO - 'talos_options': ['--disable-e10s'], 09:47:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:47:07 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:47:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:47:07 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:47:07 INFO - 'talos_options': ['--disable-e10s'], 09:47:07 INFO - 'tests': ['damp', 'tps']}, 09:47:07 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:47:07 INFO - 'tests': ['damp', 'tps']}, 09:47:07 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:47:07 INFO - 'tests': ['dromaeo_dom']}, 09:47:07 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:47:07 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:47:07 INFO - 'tests': ['basic_compositor_video']}, 09:47:07 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:47:07 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:47:07 INFO - 'tests': ['a11yr', 09:47:07 INFO - 'ts_paint', 09:47:07 INFO - 'tpaint', 09:47:07 INFO - 'sessionrestore', 09:47:07 INFO - 'sessionrestore_no_auto_restore', 09:47:07 INFO - 'tabpaint']}, 09:47:07 INFO - 'other-e10s': {'tests': ['a11yr', 09:47:07 INFO - 'ts_paint', 09:47:07 INFO - 'tpaint', 09:47:07 INFO - 'sessionrestore', 09:47:07 INFO - 'sessionrestore_no_auto_restore', 09:47:07 INFO - 'tabpaint']}, 09:47:07 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:47:07 INFO - 'ts_paint', 09:47:07 INFO - 'tpaint', 09:47:07 INFO - 'sessionrestore', 09:47:07 INFO - 'sessionrestore_no_auto_restore', 09:47:07 INFO - 'tabpaint']}, 09:47:07 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:47:07 INFO - 'ts_paint', 09:47:07 INFO - 'tpaint', 09:47:07 INFO - 'sessionrestore', 09:47:07 INFO - 'sessionrestore_no_auto_restore', 09:47:07 INFO - 'tabpaint']}, 09:47:07 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:47:07 INFO - 'tests': ['a11yr', 09:47:07 INFO - 'ts_paint', 09:47:07 INFO - 'tpaint', 09:47:07 INFO - 'sessionrestore', 09:47:07 INFO - 'sessionrestore_no_auto_restore', 09:47:07 INFO - 'tabpaint']}, 09:47:07 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:47:07 INFO - 'tests': ['a11yr', 09:47:07 INFO - 'ts_paint', 09:47:07 INFO - 'tpaint', 09:47:07 INFO - 'sessionrestore', 09:47:07 INFO - 'sessionrestore_no_auto_restore', 09:47:07 INFO - 'tabpaint']}, 09:47:07 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:47:07 INFO - 'tests': ['tsvgx', 09:47:07 INFO - 'tsvgr_opacity', 09:47:07 INFO - 'tart', 09:47:07 INFO - 'tscrollx', 09:47:07 INFO - 'cart']}, 09:47:07 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:47:07 INFO - 'tsvgr_opacity', 09:47:07 INFO - 'tart', 09:47:07 INFO - 'tscrollx', 09:47:07 INFO - 'cart']}, 09:47:07 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:47:07 INFO - 'talos_options': ['--disable-e10s'], 09:47:07 INFO - 'tests': ['tp5o']}, 09:47:07 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:47:07 INFO - 'tests': ['tp5o']}, 09:47:07 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:47:07 INFO - 'talos_options': ['--disable-e10s', 09:47:07 INFO - '--xperf_path', 09:47:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:47:07 INFO - 'tests': ['tp5n']}, 09:47:07 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:47:07 INFO - 'talos_options': ['--xperf_path', 09:47:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:47:07 INFO - 'tests': ['tp5n']}}, 09:47:07 INFO - 'talos.zip': {'path': '', 09:47:07 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:47:07 INFO - Running post-action listener: _resource_record_post_action 09:47:07 INFO - ##### 09:47:07 INFO - ##### Finished populate-webroot step (success) 09:47:07 INFO - ##### 09:47:07 INFO - ##### 09:47:07 INFO - ##### Running create-virtualenv step. 09:47:07 INFO - ##### 09:47:07 INFO - Running pre-action listener: _resource_record_pre_action 09:47:07 INFO - Running main action method: create_virtualenv 09:47:07 INFO - Creating virtualenv /builds/slave/test/build/venv 09:47:07 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:47:07 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:47:07 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:47:07 INFO - Using real prefix '/tools/python27' 09:47:07 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:47:07 INFO - Installing distribute.............................................................................................................................................................................................done. 09:47:10 INFO - Installing pip.................done. 09:47:10 INFO - Return code: 0 09:47:10 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:47:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:47:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:47:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:47:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:47:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:10 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 0x104180a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104215618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ada462370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040e4d30>, '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 0x103e49db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1040dcb20>, '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.86SyqnmCSa/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.agCptg3otQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:47:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:47:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:47:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.86SyqnmCSa/Render', 09:47:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:47:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:47:10 INFO - 'HOME': '/Users/cltbld', 09:47:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:47:10 INFO - 'LOGNAME': 'cltbld', 09:47:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:47:10 INFO - 'MOZ_NO_REMOTE': '1', 09:47:10 INFO - 'NO_EM_RESTART': '1', 09:47:10 INFO - 'PAGER': '/bin/cat', 09:47:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:47:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:47:10 INFO - 'PWD': '/builds/slave/test', 09:47:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:47:10 INFO - 'SHELL': '/bin/bash', 09:47:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.agCptg3otQ/Listeners', 09:47:10 INFO - 'TMPDIR': '/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/', 09:47:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:47:10 INFO - 'USER': 'cltbld', 09:47:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:47:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:47:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:47:10 INFO - 'XPC_FLAGS': '0x0', 09:47:10 INFO - 'XPC_SERVICE_NAME': '0', 09:47:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:47:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:47:11 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:47:11 INFO - Cleaning up... 09:47:11 INFO - Return code: 0 09:47:11 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:47: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')]} 09:47:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:47:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47: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')]} 09:47:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:47:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:11 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 0x104180a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104215618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ada462370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040e4d30>, '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 0x103e49db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1040dcb20>, '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.86SyqnmCSa/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.agCptg3otQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:47:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:47:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:47:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.86SyqnmCSa/Render', 09:47:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:47:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:47:11 INFO - 'HOME': '/Users/cltbld', 09:47:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:47:11 INFO - 'LOGNAME': 'cltbld', 09:47:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:47:11 INFO - 'MOZ_NO_REMOTE': '1', 09:47:11 INFO - 'NO_EM_RESTART': '1', 09:47:11 INFO - 'PAGER': '/bin/cat', 09:47:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:47:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:47:11 INFO - 'PWD': '/builds/slave/test', 09:47:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:47:11 INFO - 'SHELL': '/bin/bash', 09:47:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.agCptg3otQ/Listeners', 09:47:11 INFO - 'TMPDIR': '/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/', 09:47:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:47:11 INFO - 'USER': 'cltbld', 09:47:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:47:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:47:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:47:11 INFO - 'XPC_FLAGS': '0x0', 09:47:11 INFO - 'XPC_SERVICE_NAME': '0', 09:47:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:47:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:47:11 INFO - Downloading/unpacking psutil>=3.1.1 09:47:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:47:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:47:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:47:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:47:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:47:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:47:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:47:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:47:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:47:14 INFO - Installing collected packages: psutil 09:47:14 INFO - Running setup.py install for psutil 09:47:14 INFO - building 'psutil._psutil_osx' extension 09:47:14 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:47:14 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:47:14 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:47:14 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 09:47:14 INFO - building 'psutil._psutil_posix' extension 09:47:14 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 09:47:14 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:47:14 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:47:14 INFO - ^ 09:47:14 INFO - 1 warning generated. 09:47:14 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 09:47:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:47:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:47:15 INFO - Successfully installed psutil 09:47:15 INFO - Cleaning up... 09:47:15 INFO - Return code: 0 09:47:15 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:47:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:47:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:47:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:47:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:47:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:15 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 0x104180a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104215618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ada462370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040e4d30>, '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 0x103e49db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1040dcb20>, '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.86SyqnmCSa/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.agCptg3otQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:47:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:47:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:47:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.86SyqnmCSa/Render', 09:47:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:47:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:47:15 INFO - 'HOME': '/Users/cltbld', 09:47:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:47:15 INFO - 'LOGNAME': 'cltbld', 09:47:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:47:15 INFO - 'MOZ_NO_REMOTE': '1', 09:47:15 INFO - 'NO_EM_RESTART': '1', 09:47:15 INFO - 'PAGER': '/bin/cat', 09:47:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:47:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:47:15 INFO - 'PWD': '/builds/slave/test', 09:47:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:47:15 INFO - 'SHELL': '/bin/bash', 09:47:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.agCptg3otQ/Listeners', 09:47:15 INFO - 'TMPDIR': '/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/', 09:47:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:47:15 INFO - 'USER': 'cltbld', 09:47:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:47:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:47:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:47:15 INFO - 'XPC_FLAGS': '0x0', 09:47:15 INFO - 'XPC_SERVICE_NAME': '0', 09:47:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:47:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:47:15 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:47:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:47:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:47:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:47:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:47:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:47:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:47:18 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:47:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:47:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:47:18 INFO - Installing collected packages: mozsystemmonitor 09:47:18 INFO - Running setup.py install for mozsystemmonitor 09:47:18 INFO - Successfully installed mozsystemmonitor 09:47:18 INFO - Cleaning up... 09:47:18 INFO - Return code: 0 09:47:18 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:47:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:47:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:47:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:47:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:47:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:18 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 0x104180a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104215618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ada462370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040e4d30>, '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 0x103e49db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1040dcb20>, '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.86SyqnmCSa/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.agCptg3otQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:47:18 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 09:47:18 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 09:47:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.86SyqnmCSa/Render', 09:47:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:47:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:47:18 INFO - 'HOME': '/Users/cltbld', 09:47:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:47:18 INFO - 'LOGNAME': 'cltbld', 09:47:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:47:18 INFO - 'MOZ_NO_REMOTE': '1', 09:47:18 INFO - 'NO_EM_RESTART': '1', 09:47:18 INFO - 'PAGER': '/bin/cat', 09:47:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:47:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:47:18 INFO - 'PWD': '/builds/slave/test', 09:47:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:47:18 INFO - 'SHELL': '/bin/bash', 09:47:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.agCptg3otQ/Listeners', 09:47:18 INFO - 'TMPDIR': '/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/', 09:47:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:47:18 INFO - 'USER': 'cltbld', 09:47:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:47:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:47:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:47:18 INFO - 'XPC_FLAGS': '0x0', 09:47:18 INFO - 'XPC_SERVICE_NAME': '0', 09:47:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:47:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:47:18 INFO - Downloading/unpacking jsonschema==2.5.1 09:47:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:47:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:47:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:47:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:47:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:47:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:47:21 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:47:21 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:47:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:47:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:47:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:47:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:47:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:47:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:47:21 INFO - Downloading functools32-3.2.3-2.tar.gz 09:47:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:47:21 INFO - warning: no files found matching '*.txt' 09:47:21 INFO - no previously-included directories found matching 'build' 09:47:21 INFO - no previously-included directories found matching 'dist' 09:47:21 INFO - no previously-included directories found matching '.git*' 09:47:21 INFO - Installing collected packages: jsonschema, functools32 09:47:21 INFO - Running setup.py install for functools32 09:47:21 INFO - warning: no files found matching '*.txt' 09:47:21 INFO - no previously-included directories found matching 'build' 09:47:21 INFO - no previously-included directories found matching 'dist' 09:47:21 INFO - no previously-included directories found matching '.git*' 09:47:21 INFO - Successfully installed jsonschema functools32 09:47:21 INFO - Cleaning up... 09:47:21 INFO - Return code: 0 09:47:21 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:47: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')]} 09:47:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:47:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47: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')]} 09:47:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:47:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:21 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 0x104180a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104215618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ada462370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040e4d30>, '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 0x103e49db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1040dcb20>, '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.86SyqnmCSa/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.agCptg3otQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:47:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:47:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:47:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.86SyqnmCSa/Render', 09:47:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:47:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:47:21 INFO - 'HOME': '/Users/cltbld', 09:47:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:47:21 INFO - 'LOGNAME': 'cltbld', 09:47:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:47:21 INFO - 'MOZ_NO_REMOTE': '1', 09:47:21 INFO - 'NO_EM_RESTART': '1', 09:47:21 INFO - 'PAGER': '/bin/cat', 09:47:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:47:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:47:21 INFO - 'PWD': '/builds/slave/test', 09:47:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:47:21 INFO - 'SHELL': '/bin/bash', 09:47:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.agCptg3otQ/Listeners', 09:47:21 INFO - 'TMPDIR': '/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/', 09:47:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:47:21 INFO - 'USER': 'cltbld', 09:47:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:47:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:47:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:47:21 INFO - 'XPC_FLAGS': '0x0', 09:47:21 INFO - 'XPC_SERVICE_NAME': '0', 09:47:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:47:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:47:22 INFO - Downloading/unpacking blobuploader==1.2.4 09:47:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:47:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:47:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:47:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:47:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:47:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:47:24 INFO - Downloading blobuploader-1.2.4.tar.gz 09:47:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:47:24 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:47:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:47:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:47:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:47:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:47:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:47:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:47:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:47:25 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:47:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:47:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:47:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:47:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:47:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:47:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:47:25 INFO - Downloading docopt-0.6.1.tar.gz 09:47:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:47:25 INFO - Installing collected packages: blobuploader, requests, docopt 09:47:25 INFO - Running setup.py install for blobuploader 09:47:25 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:47:25 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:47:26 INFO - Running setup.py install for requests 09:47:26 INFO - Running setup.py install for docopt 09:47:26 INFO - Successfully installed blobuploader requests docopt 09:47:26 INFO - Cleaning up... 09:47:26 INFO - Return code: 0 09:47:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:47:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:47:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:47:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:47:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:47:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:26 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 0x104180a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104215618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ada462370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040e4d30>, '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 0x103e49db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1040dcb20>, '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.86SyqnmCSa/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.agCptg3otQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:47:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:47:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:47:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.86SyqnmCSa/Render', 09:47:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:47:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:47:26 INFO - 'HOME': '/Users/cltbld', 09:47:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:47:26 INFO - 'LOGNAME': 'cltbld', 09:47:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:47:26 INFO - 'MOZ_NO_REMOTE': '1', 09:47:26 INFO - 'NO_EM_RESTART': '1', 09:47:26 INFO - 'PAGER': '/bin/cat', 09:47:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:47:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:47:26 INFO - 'PWD': '/builds/slave/test', 09:47:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:47:26 INFO - 'SHELL': '/bin/bash', 09:47:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.agCptg3otQ/Listeners', 09:47:26 INFO - 'TMPDIR': '/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/', 09:47:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:47:26 INFO - 'USER': 'cltbld', 09:47:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:47:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:47:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:47:26 INFO - 'XPC_FLAGS': '0x0', 09:47:26 INFO - 'XPC_SERVICE_NAME': '0', 09:47:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:47:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:47:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:47:27 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-weW6hn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:47:27 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-V1GmSM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:47:27 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-LbQdRH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:47:27 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-yfb5QY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:47:27 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-23hE1e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:47:27 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-cI9Rr3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:47:27 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-9VpwV2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:47:27 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-XJUE8z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:47:27 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-7FoJqE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:47:27 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-_lVAhd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:47:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:47:28 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-DpDro0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:47:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:47:28 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-GQ7XC2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:47:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:47:28 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-OetQ5u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:47:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:47:28 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-1E21Kv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:47:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:47:28 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-qi3Ydg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:47:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:47:28 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-zCTfsY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:47:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:47:28 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-8v6koX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:47:28 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:47:28 INFO - Running setup.py install for manifestparser 09:47:28 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:47:28 INFO - Running setup.py install for mozcrash 09:47:29 INFO - Running setup.py install for mozdebug 09:47:29 INFO - Running setup.py install for mozdevice 09:47:29 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:47:29 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:47:29 INFO - Running setup.py install for mozfile 09:47:29 INFO - Running setup.py install for mozhttpd 09:47:29 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:47:29 INFO - Running setup.py install for mozinfo 09:47:29 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:47:29 INFO - Running setup.py install for mozInstall 09:47:29 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:47:29 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:47:29 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:47:29 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:47:29 INFO - Running setup.py install for mozleak 09:47:30 INFO - Running setup.py install for mozlog 09:47:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:47:30 INFO - Running setup.py install for moznetwork 09:47:30 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:47:30 INFO - Running setup.py install for mozprocess 09:47:30 INFO - Running setup.py install for mozprofile 09:47:30 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:47:30 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:47:30 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:47:30 INFO - Running setup.py install for mozrunner 09:47:30 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:47:30 INFO - Running setup.py install for mozscreenshot 09:47:31 INFO - Running setup.py install for moztest 09:47:31 INFO - Running setup.py install for mozversion 09:47:31 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:47:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:47:31 INFO - Cleaning up... 09:47:31 INFO - Return code: 0 09:47:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:47:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:47:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:47:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:47:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:47:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104180a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104215618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ada462370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040e4d30>, '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 0x103e49db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1040dcb20>, '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.86SyqnmCSa/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.agCptg3otQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:47:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:47:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:47:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.86SyqnmCSa/Render', 09:47:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:47:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:47:31 INFO - 'HOME': '/Users/cltbld', 09:47:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:47:31 INFO - 'LOGNAME': 'cltbld', 09:47:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:47:31 INFO - 'MOZ_NO_REMOTE': '1', 09:47:31 INFO - 'NO_EM_RESTART': '1', 09:47:31 INFO - 'PAGER': '/bin/cat', 09:47:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:47:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:47:31 INFO - 'PWD': '/builds/slave/test', 09:47:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:47:31 INFO - 'SHELL': '/bin/bash', 09:47:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.agCptg3otQ/Listeners', 09:47:31 INFO - 'TMPDIR': '/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/', 09:47:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:47:31 INFO - 'USER': 'cltbld', 09:47:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:47:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:47:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:47:31 INFO - 'XPC_FLAGS': '0x0', 09:47:31 INFO - 'XPC_SERVICE_NAME': '0', 09:47:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:47:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:47:31 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-0fop9r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:47:31 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:47:31 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-Nktftd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:47:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:47:31 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-VBq6Oc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:47:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:47:31 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-dfYDp9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:47:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:47:32 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-QKsPBd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:47:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:47:32 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-rrtyip-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:47:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:47:32 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-MC9qIn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:47:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:47:32 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-pSJa5a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:47:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:47:32 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-SfSUFc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:47:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:47:32 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-zW0HQe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:47:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:47:32 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-Xgzmja-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:47:32 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:47:32 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-fJKUdU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:47:32 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)) 09:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:47:32 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-29dGkd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:47:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:47:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:47:33 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-Vcs4_6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:47:33 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)) 09:47:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:47:33 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-Y7TNQ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:47:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:47:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:47:33 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-fuPVKd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:47:33 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:47:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:47:33 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-Xoy8rX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:47:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:47:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:47:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:47:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:47:33 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:47:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:47:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:47:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:47:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:47:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:47:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:47:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:47:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:47:35 INFO - Downloading blessings-1.6.tar.gz 09:47:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:47:35 INFO - Installing collected packages: blessings 09:47:35 INFO - Running setup.py install for blessings 09:47:36 INFO - Successfully installed blessings 09:47:36 INFO - Cleaning up... 09:47:36 INFO - Return code: 0 09:47:36 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:47:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:47:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:47:36 INFO - Reading from file tmpfile_stdout 09:47:36 INFO - Current package versions: 09:47:36 INFO - blessings == 1.6 09:47:36 INFO - blobuploader == 1.2.4 09:47:36 INFO - docopt == 0.6.1 09:47:36 INFO - functools32 == 3.2.3-2 09:47:36 INFO - jsonschema == 2.5.1 09:47:36 INFO - manifestparser == 1.1 09:47:36 INFO - mozInstall == 1.12 09:47:36 INFO - mozcrash == 0.17 09:47:36 INFO - mozdebug == 0.1 09:47:36 INFO - mozdevice == 0.48 09:47:36 INFO - mozfile == 1.2 09:47:36 INFO - mozhttpd == 0.7 09:47:36 INFO - mozinfo == 0.9 09:47:36 INFO - mozleak == 0.1 09:47:36 INFO - mozlog == 3.2 09:47:36 INFO - moznetwork == 0.27 09:47:36 INFO - mozprocess == 0.23 09:47:36 INFO - mozprofile == 0.28 09:47:36 INFO - mozrunner == 6.12 09:47:36 INFO - mozscreenshot == 0.1 09:47:36 INFO - mozsystemmonitor == 0.3 09:47:36 INFO - moztest == 0.7 09:47:36 INFO - mozversion == 1.4 09:47:36 INFO - psutil == 3.1.1 09:47:36 INFO - requests == 1.2.3 09:47:36 INFO - wsgiref == 0.1.2 09:47:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:47:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:47:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:47:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:47:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:47:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104180a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104215618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ada462370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040e4d30>, '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 0x103e49db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1040dcb20>, '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.86SyqnmCSa/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.agCptg3otQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:47:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:47:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:47:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.86SyqnmCSa/Render', 09:47:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:47:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:47:36 INFO - 'HOME': '/Users/cltbld', 09:47:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:47:36 INFO - 'LOGNAME': 'cltbld', 09:47:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:47:36 INFO - 'MOZ_NO_REMOTE': '1', 09:47:36 INFO - 'NO_EM_RESTART': '1', 09:47:36 INFO - 'PAGER': '/bin/cat', 09:47:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:47:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:47:36 INFO - 'PWD': '/builds/slave/test', 09:47:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:47:36 INFO - 'SHELL': '/bin/bash', 09:47:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.agCptg3otQ/Listeners', 09:47:36 INFO - 'TMPDIR': '/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/', 09:47:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:47:36 INFO - 'USER': 'cltbld', 09:47:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:47:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:47:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:47:36 INFO - 'XPC_FLAGS': '0x0', 09:47:36 INFO - 'XPC_SERVICE_NAME': '0', 09:47:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:47:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:47:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:47:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:47:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:47:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:47:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:47:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:47:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:47:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:47:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:47:36 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:47:36 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:47:36 INFO - Cleaning up... 09:47:36 INFO - Return code: 0 09:47:36 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:47:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:47:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:47:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:47:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:47:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:47:36 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 0x104180a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104215618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ada462370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040e4d30>, '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 0x103e49db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1040dcb20>, '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.86SyqnmCSa/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.agCptg3otQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:47:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:47:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:47:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.86SyqnmCSa/Render', 09:47:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:47:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:47:36 INFO - 'HOME': '/Users/cltbld', 09:47:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:47:36 INFO - 'LOGNAME': 'cltbld', 09:47:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:47:36 INFO - 'MOZ_NO_REMOTE': '1', 09:47:36 INFO - 'NO_EM_RESTART': '1', 09:47:36 INFO - 'PAGER': '/bin/cat', 09:47:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:47:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:47:36 INFO - 'PWD': '/builds/slave/test', 09:47:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:47:36 INFO - 'SHELL': '/bin/bash', 09:47:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.agCptg3otQ/Listeners', 09:47:36 INFO - 'TMPDIR': '/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/', 09:47:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:47:36 INFO - 'USER': 'cltbld', 09:47:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:47:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:47:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:47:36 INFO - 'XPC_FLAGS': '0x0', 09:47:36 INFO - 'XPC_SERVICE_NAME': '0', 09:47:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:47:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:47:37 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:47:37 INFO - Cleaning up... 09:47:37 INFO - Return code: 0 09:47:37 INFO - Running post-action listener: _resource_record_post_action 09:47:37 INFO - Running post-action listener: _start_resource_monitoring 09:47:37 INFO - Starting resource monitoring. 09:47:37 INFO - ##### 09:47:37 INFO - ##### Finished create-virtualenv step (success) 09:47:37 INFO - ##### 09:47:37 INFO - ##### 09:47:37 INFO - ##### Running install step. 09:47:37 INFO - ##### 09:47:37 INFO - Running pre-action listener: _resource_record_pre_action 09:47:37 INFO - Running main action method: install 09:47:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:47:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:47:37 INFO - Reading from file tmpfile_stdout 09:47:37 INFO - Detecting whether we're running mozinstall >=1.0... 09:47:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:47:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:47:37 INFO - Reading from file tmpfile_stdout 09:47:37 INFO - Output received: 09:47:37 INFO - Usage: mozinstall [options] installer 09:47:37 INFO - Options: 09:47:37 INFO - -h, --help show this help message and exit 09:47:37 INFO - -d DEST, --destination=DEST 09:47:37 INFO - Directory to install application into. [default: 09:47:37 INFO - "/builds/slave/test"] 09:47:37 INFO - --app=APP Application being installed. [default: firefox] 09:47:37 INFO - mkdir: /builds/slave/test/build/application 09:47:37 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'] 09:47:37 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 09:48:11 INFO - Reading from file tmpfile_stdout 09:48:11 INFO - Output received: 09:48:11 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:48:11 INFO - Running post-action listener: _resource_record_post_action 09:48:11 INFO - ##### 09:48:11 INFO - ##### Finished install step (success) 09:48:11 INFO - ##### 09:48:11 INFO - ##### 09:48:11 INFO - ##### Running run-tests step. 09:48:11 INFO - ##### 09:48:11 INFO - Running pre-action listener: _resource_record_pre_action 09:48:11 INFO - Running main action method: run_tests 09:48:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:48:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:48:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:48:11 INFO - 2016-07-13 09:48:11.936 screenresolution[1642:7831] starting screenresolution argv=screenresolution get 09:48:11 INFO - 2016-07-13 09:48:11.939 screenresolution[1642:7831] Display 0: 1600x1200x32@60 09:48:11 INFO - 2016-07-13 09:48:11.944 screenresolution[1643:7833] starting screenresolution argv=screenresolution list 09:48:11 INFO - Available Modes on Display 0 09:48:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:48:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:48:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:48:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:48:12 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:48:12 INFO - Intel Iris: 09:48:12 INFO - Chipset Model: Intel Iris 09:48:12 INFO - Type: GPU 09:48:12 INFO - Bus: Built-In 09:48:12 INFO - VRAM (Dynamic, Max): 1536 MB 09:48:12 INFO - Vendor: Intel (0x8086) 09:48:12 INFO - Device ID: 0x0a2e 09:48:12 INFO - Revision ID: 0x0009 09:48:12 INFO - Displays: 09:48:12 INFO - Display: 09:48:12 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:48:12 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:48:12 INFO - Display Serial Number: 1600x1200 60 09:48:12 INFO - Main Display: Yes 09:48:12 INFO - Mirror: Off 09:48:12 INFO - Online: Yes 09:48:12 INFO - Rotation: Supported 09:48:12 INFO - Return code: 0 09:48:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:48:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:48:12 INFO - Python 2.7.3 09:48:12 INFO - Return code: 0 09:48:12 INFO - grabbing minidump binary from tooltool 09:48:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:48:12 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 0x1040e4d30>, '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 0x103e49db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1040dcb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:48:12 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:48:12 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 09:48:12 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:48:12 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:48:12 INFO - Return code: 0 09:48:12 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:48:12 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:48:12 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:48:12 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:48:12 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:48:12 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/OXPh7zg_RsOreeknn0zseQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0327', '--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 09:48:12 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/OXPh7zg_RsOreeknn0zseQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0327 --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 09:48:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.86SyqnmCSa/Render', 09:48:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:48:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:48:12 INFO - 'HOME': '/Users/cltbld', 09:48:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:48:12 INFO - 'LOGNAME': 'cltbld', 09:48:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:48:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:48:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:12 INFO - 'MOZ_NO_REMOTE': '1', 09:48:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:48:12 INFO - 'NO_EM_RESTART': '1', 09:48:12 INFO - 'PAGER': '/bin/cat', 09:48:12 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:48:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:12 INFO - 'PWD': '/builds/slave/test', 09:48:12 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:48:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:48:12 INFO - 'SHELL': '/bin/bash', 09:48:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.agCptg3otQ/Listeners', 09:48:12 INFO - 'TMPDIR': '/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/', 09:48:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:48:12 INFO - 'USER': 'cltbld', 09:48:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:48:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:48:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:12 INFO - 'XPC_FLAGS': '0x0', 09:48:12 INFO - 'XPC_SERVICE_NAME': '0', 09:48:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:48:12 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/OXPh7zg_RsOreeknn0zseQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0327', '--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 09:48:12 INFO - mozversion application_buildid: 20160713065327 09:48:12 INFO - mozversion application_changeset: 845645460806f28a8f249c5d3c60f07b46cef63c 09:48:12 INFO - mozversion application_display_name: Nightly 09:48:12 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:48:12 INFO - mozversion application_name: Firefox 09:48:12 INFO - mozversion application_remotingname: firefox 09:48:12 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 09:48:12 INFO - mozversion application_vendor: Mozilla 09:48:12 INFO - mozversion application_version: 50.0a1 09:48:12 INFO - mozversion platform_buildid: 20160713065327 09:48:12 INFO - mozversion platform_changeset: 845645460806f28a8f249c5d3c60f07b46cef63c 09:48:12 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 09:48:12 INFO - mozversion platform_version: 50.0a1 09:48:12 INFO - using testdate: 1468428492 09:48:12 INFO - actual date: 1468428492 09:48:12 INFO - starting webserver on 'localhost:49279' 09:48:12 INFO - SUITE-START | Running 2 tests 09:48:12 INFO - TEST-START | dromaeo_css 09:48:12 INFO - operating with platform_type : mac_ 09:48:12 INFO - Initialising browser for dromaeo_css test... 09:48:12 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/tmpgghcY0/profile http://localhost:49279/getInfo.html) 09:48:14 INFO - PROCESS | 1653 | __metrics Screen width/height:1600/1200 09:48:14 INFO - PROCESS | 1653 | colorDepth:24 09:48:14 INFO - PROCESS | 1653 | Browser inner width/height: 1024/689 09:48:14 INFO - PROCESS | 1653 | __metrics 09:48:15 INFO - PROCESS | 1653 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:48:15 INFO - PROCESS | 1653 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:48:15 INFO - TEST-INFO | 1653: exit 0 09:48:15 INFO - Browser initialized. 09:48:15 INFO - Running cycle 1/1 for dromaeo_css test... 09:48:15 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/tmpgghcY0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:48:27 INFO - PROCESS | 1655 | RSS: Main: 243396608 09:48:27 INFO - PROCESS | 1655 | 09:49:19 INFO - PROCESS | 1655 | 1468428559410 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 09:51:30 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49279/tests/dromaeo/cssquery-ext.html) 09:51:31 INFO - PROCESS | 1655 | RSS: Main: 381497344 09:51:31 INFO - PROCESS | 1655 | 09:54:34 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-ext.html (next: http://localhost:49279/tests/dromaeo/cssquery-jquery.html) 09:54:35 INFO - PROCESS | 1655 | RSS: Main: 380805120 09:54:35 INFO - PROCESS | 1655 | 09:57:37 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49279/tests/dromaeo/cssquery-mootools.html) 09:57:38 INFO - PROCESS | 1655 | RSS: Main: 371064832 09:57:38 INFO - PROCESS | 1655 | 10:00:40 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49279/tests/dromaeo/cssquery-prototype.html) 10:00:41 INFO - PROCESS | 1655 | RSS: Main: 370995200 10:00:41 INFO - PROCESS | 1655 | 10:03:44 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49279/tests/dromaeo/cssquery-yui.html) 10:03:45 INFO - PROCESS | 1655 | RSS: Main: 336670720 10:03:45 INFO - PROCESS | 1655 | 10:06:48 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-yui.html (next: http://localhost:49279/tests/dromaeo/cssquery-dojo.html) 10:06:49 INFO - PROCESS | 1655 | RSS: Main: 393584640 10:06:49 INFO - PROCESS | 1655 | 10:06:49 INFO - PROCESS | 1655 | __start_tp_report 10:06:49 INFO - PROCESS | 1655 | _x_x_mozilla_page_load 10:06:49 INFO - PROCESS | 1655 | _x_x_mozilla_page_load_details 10:06:49 INFO - PROCESS | 1655 | |i|pagename|runs| 10:06:49 INFO - PROCESS | 1655 | |0;dojo.html;3082.9383412331745;3277.491988741745;3287.91780205495;3306.30567580808;3346.29727757171;5421.972890135544;5517.944820551798;5553.222548843165;5565.137403702428;5604.439556044394;5460.781568737246;5499.285092937923;5646.068398714207;5649.6327738696955;5664.490195882369;4898.730569818658;4959.826406075788;4988.2766998785155;4995.6752811067345;5196.272521846944;4018.4976877890263;5115.309433226523;5121.846344609649;5148.845534633967;5151.278820965068;4166;4955.529224723664;5017.84946451607;5017.996400719834;5091.694498330112;5011.649184557083;5029.3964724232965;5036.093503169428;5052.46949070346;5058.0895438821;5016.924746128811;5029.1953287473825;5074.720890351029;5110.821121260738;5124.923126153073;5459.372172200208;5575.358833734132;5675.120356344773;5686.772529098831;5687.43125687428;6003.159557661931;6053.727582258809;6066.977768300391;6070.57505974582;6095.603785753956;5129.692218466904;6138.570300078996;6171.506279497629;6182.010878259502;6189.566730328879;5276.3932147802725;5478.178273259043;5545.944540554543;5690.573207009491;5707.429257074259;6146.907796383058;6216.627002379872;6233.3143354231;6286.214223222098;6289.150964619809;5978.073398623221;5980.102984552352;6009.519238460913;6010.248718910137;6026.126211699367;5844.298684157929;5972.761089556456;6132.202813634288;6149.96925015381;6163.506919446433;5966.463018328372;5978.073398623221;5984.521238301013;5989.311229208697;5990.850228744316;5965;6028.306744724413;6031.5476339273855;6034.63792172471;6049.243844519435;4787.736674482937;4834.178189707758;4834.7824347904425;4841.12859685253;4858.222684370484;4248.915021699549;4321.567843215653;4340.8480703175865;4353.064091220403;4369.694121411534;5597.888042239132;5599.1041433370465;5608.1027035674915;5660.943390566124;5662.688552129673;6011.158437818709;6027.336992930774;6060.757569697251;6071.150038994544;6100.206973093558;5594.832155035356;5598.384177740445;5600.215969764236;5610.298712660918;5614.157876318503;5186.688798672092;5278.049951008856;5292.047431462297;5298.814541491107;5328.573714102862;6194.318624951252;6198.81403557894;6215.844603884939;6242.375762423721;6274.623522588659;6166.352532983972;6166.784162554289;6177.4749146323065;6177.84555386119;6182.1345011697495;6145.047517634774;6259.436650701458;6260.530460215593;6289.528285378524;6292.465140463112;5935;5937.435943585352;5987.700614969322;6050.1227322037175;6057.242844644419;6121.8469538260115;6132.35610260934;6154.599951003171;6158.876822463615;6164.47601953839;4507.481639611356;5732.856678583069;5825.3883342248455;5908.379620139824;5935.347111817783;5840.649561026352;5922.970385148046;5933.465988061095;5939;5983.222181116427;4809.711417314972;5730.684812335361;5770.394108618535;5794.565407594363;5820.912686309794;5724.055530837365;5796.565257605781;5815.185873977562;5923.229980102731;5982.312034116132;5733.397993210653;5771.105478650816;5776.682282474504;5776.971115144397;5836 10:06:49 INFO - PROCESS | 1655 | |1;ext.html;486.65447532252097;522.8954209158168;530.6046994988734;557.7741014888971;562.814271290474;30825.537616935737;42131;42903.78548107255;43033.35449968245;43194.352084718754;44097.338539921926;44410.555894441044;44468.11063778723;44493.332600010945;44549.7772511137;8721.694740684077;9678.516074196297;9877.35797173185;9879.407235565854;9884.654037108667;8702.825943481126;11865.466054027589;12005.519779208822;12008.579699710512;12384.009279257683;10724.624638137666;11540.192186546945;12026.098042646792;12074.396280186;12117.151799374047;8747.956260218722;11817.881821181763;12037.879621203763;12084.214526055823;12157.088218383613;11958.820617690742;12036.45835937385;12058.974987126105;12075.27548347102;12078.939605302005;41929.1614167718;42207.36688949668;42259;42260.78869605633;42354.78822605898;3078.7998780079224;3615.349237137314;3662.377395842713;3690.3910854708943;4263.957360426387;32054.878079267113;33408;33563.72739694588;33694.49458258128;33716.81991130323;3230.224746060952;3239.3683231769814;3290.144562413766;3378.729701623864;3434.553508043963;516215;516638;516911;517639;519241;61123.69438152781;61309.38690613037;61480.69259653673;61648;61715.69142154261;64931.67534162394;65082.67458662677;65107.67446162834;65233.673831630535;65306.346936530026;16671.33314667424;16924.153792310335;16943.745843812354;16948.661026779395;16953;11975.640730777915;12147.271163730204;12840.48638054486;12933.353332333534;13170.604881853367;13499.730005399837;16555.50333489997;16585.83414165867;16595.253213605418;16797.748033779502;6502.187226596676;6561.639109848908;6583.637899915455;6593.175853018372;6634.170728658918;861.6036623153419;944.2398868910593;947.9526023698926;975.4878688688495;1027.4041056187393;27902.023429180288;29797.702022979494;30228.84885575558;30731;32458;951.8762560867043;1049.2130901823632;1115.1134847796022;1158.362900404791;1158.7740390623746;15512.92243538775;16354;16368;16384.426545070866;16846.32614695423;184813;185341;185548;185740;186181;61346;61690.383096168465;61715;61837;61919.69040154861;1264.3425418782365;1387.4796951143321;1395.4139261509988;1419.269076425621;1431.484665520412;32118;32316.515252270765;32322.83838580792;32331.838340808146;33519.497207542394;59986.70006649939;60909.390906090375;60938.695306524955;60976.39023609884;61134.694326528086;613399;630530;639454;639714;641378;24096.759032409453;26978.325541860824;27024.594631080156;27079.864600676872;27673;49921.00157996892;50435.74782126066;50575.74712126416;50623;51228.74385628048;38364.61635383611;41308.58691413168;41874.95312617209;41977;42094.73715788531;38028;39218.607813921495;40866.38700419555;41269.38095928504;42304.365434517895;39309;39896;40078.198436030536;40207.59792402039;40416.59583404246;33828.66171338255;34863;35619.82190089033;35723.64276357203;36409;22328.66507002364;25473.363165921;26162;26216.60675089837;27145.72854271511 10:06:49 INFO - PROCESS | 1655 | |2;jquery.html;347.34525419584077;385.1296070879812;391.50670155603933;393.3882812226986;414.0580180090294;15039.624009399757;15044.849551504481;21161.153553857865;21369.786302136974;21692.56614867702;18337.90831045846;18566.907165464152;18574.81425185748;20609.86645734484;22265;5740.225069615601;5952.464278214959;6006.129111278862;6010.609310394832;6338.492920566344;6795.422389096932;6831.3168683131535;6831.487638427137;6843.692033858489;6854.1089658344345;6498.220213574354;6686.097376854135;6697.933020669804;6708.530402871801;6797.4562035037325;5849.093390524476;6675.098861653663;6680.265170831229;6738.966305168492;6741.292164322749;5125.179971204627;5854.297484301868;5951;5978.461938425563;6013.098035294697;12546.309952952584;16402.917985410117;20069.69895451555;25745.613815792778;26385.3403664908;2191.7260342457193;2271.5116250006326;2393.6170212765874;2436.4030812450887;2522.2433270019105;9981.65064222756;10025.147862431702;10242;10706;10730.624428145056;1898.4684288399271;1909.30310436691;1917.5589614388768;1988.6122206169803;1991.9601607967904;90371;104684;117005;129477.70522294845;129855.99999999905;11390.601328953448;11554.826677599844;11607.477663504993;11612.303261804318;11707.76584468306;14334.784978225334;14491.985561010839;14512.564623061326;14517.709645807025;14556.272186390637;8514.914850851412;8523.74428767138;8550.187732165434;8578.313734901192;8688.435251708745;8550.74347769568;8580.442271252348;8583.570821458901;8717.254064969515;8726.65093396257;4147.937780933288;4185.958140418617;4517.457905051415;4543.681942264076;4589.5639914208095;1881.6048629787897;1931.9323823666384;1938.6898096304806;1970.1626808606286;1974.1412485568824;874.4709450782312;940.5250348573926;979.3487330924997;998.256299057207;1042.1089968077335;20765.480862978246;21646.56706865854;21721.565568688537;21728.565428691338;21929.561408771737;958.6692591056075;971.9757006074764;998.7453199434243;1001.1190152665607;1043.160255993917;10496.73758156037;10997.670069897917;11391.145664075228;11420.885791141982;11422.600208992646;515914;531813;544751;550923;550971.490285092;127221.91112355402;128583.35708321398;134015.65984340032;134123.98814017797;134549;990.2869933647762;992.5533509920565;1006.9194464442827;1011.7672935224939;1012.1851909213007;19019.524511887314;19276;19330.936798476218;19342.4197274082;19361.806381936563;53186.46813531815;55344.893102138536;56167;56912.71543642255;57096.42903570911;75050.62474687591;77106;78830.6058469704;79500;81484.59257703673;8018.1981801821685;8193.71322003727;8270.090290067967;8294.709685160991;8513.233808957224;11328.830067549157;12827.871721282667;12975.221486710827;13105.737885242052;13390.866091339352;11230.270032447865;11400.543978241109;11410.600628977945;11413.657590272305;11569.6529104127;11080.05819505351;11140;11286.435678215892;11418.429078546205;11464.94267528657;11486;11590.246633968765;11596.594119205787;11681.941590291994;11876.584319548774;7133.82165445868;8134.471259368122;8223.753287401387;8405.36959728035;8409.747707568782;7252.4560657949805;11153.163512736739;11202.94398528002;11319.886801132212;11398.145139114433 10:06:49 INFO - PROCESS | 1655 | |3;mootools.html;1385.1412124482822;1489.4265707702532;1550.0854495847452;1796.6765982123213;1971.2706298669502;7789.532628042314;8539.018012928507;8765.824683506326;8766.123387661231;8888.64445422184;8465.44974576652;8733.606987685553;8751.081136480658;8756.299496040318;8777;1704.9659006819857;2177.1835561664375;2274.8521346112534;2340.3330050935488;2382.4996750682376;2377.09670325277;2418.141559746291;2419.213755529451;2421.7457166997474;2423.4062654649633;2338.286822519131;2377.714674239085;2379.048380647746;2380.6191009438494;2383.7377888432256;2070.9689354659617;2268.6597010448563;2360.1503458754833;2360.9645855312183;2367.562001029815;2362.4212068043266;2407.3018824540864;2408.6025805742206;2408.843425177358;2410.2287268074224;8584;8624.396292259544;8635.481871087755;8639.52482613456;8657.307415406814;5139.820106296262;5140.974295128538;5259.447757985395;5297.920531191997;5315.149576067849;3066.6626671066165;3412.8634854605784;3566.589842168132;3632;3652.6164752700847;1077.0575746222055;1094.2668412163869;1095.0746619106842;1119.2109562758235;1121.506537123663;11324.03745681618;11530;11537.769244615145;11552;11561;4578.35902973584;4597.632189424838;4796.112719146969;4806.975965120152;4825.734584597881;9966.651167209258;9972.055449187485;9980.700578982642;9985.400875947467;10029.29794914349;5258.842234732964;5331.866703332447;5340.893182136336;5341.572674186134;5370.677759334374;4196.853110141131;4359.542248063971;4510.729356238636;4513.864584062483;4523.389342438729;3936.566977632458;4033.495813023372;4093.63157315843;4096.40602112692;4099.467069280974;1229.59423390281;1264.1403845385043;1268.492602958824;1278.2649976263685;1279.2452453052558;911.0934620052957;923.6997975657938;923.6997975657938;933.9073284257435;964.5707660091332;5711.6001879868845;5798.884022319615;5864.208331875142;5910.704464776744;6025.849353766191;416.8332666933251;431.4757569553008;432.06242453875166;438.65346376362953;451.02128381412416;3904.082540602956;3963.781991990468;4048.716589838683;4054.351303791379;4061.634452899253;10284.177265818716;10541.84187237192;10573.471326433648;10677.305975111592;10684.358938463716;10620.840687389695;10638.521266543024;10642.148628109733;10648.467576621139;10656.360618362924;7998.3201427879285;8073.631035361536;8200.0979892213;8202.26179643835;8207.891934588753;5111.846644600668;5772.3650398456975;5810.273715785527;5810.883782324415;5820.301563812201;9726.70819875405;9861.802763944825;9956.651517196864;9990.200783937413;10004.29969902129;9132.086791320815;9718;10110.8483372748;10122.898771012196;10186.541605627617;6929;7275.454340924346;7470.813229669302;7487.812804679927;7588;8072.757817265491;8079.878801818095;8096.190380962093;8142.592870356577;8158.836823263383;7367.300106489874;7428.9628551856895;7482.064741907262;7543.811404714926;7573.091229052549;6668.699603577255;6782.694778735067;7007.859842803014;7078.610676412641;7250.3112204340505;6880.759173428906;6958;6965.407940324926;7032.437405007689;7043.859122817617;4677.742724150205;4840.95159048405;4867.853964381075;4904.803807847647;4918.852434426973;4830.2513110467935;4857.805687772591;4905.484924083063;4945.431275403231;5022.623303252344 10:06:49 INFO - PROCESS | 1655 | |4;prototype.html;271.8433067298644;303.1904814146229;309.6980444067035;312.44073109134644;319.9456092464281;4298.613124818766;5159.045576568333;5164.140909272659;5204.713740744252;5221.95560887823;4414.432876328897;4441.5336389679105;5124.718140502271;5166.819161329354;5183.818566350178;3745.9324092633606;3921.8823435296986;3922.3724204127348;3933.9346842907416;3934.5475270343845;3835.6931445484433;3850.210706805098;3916.4516967624554;3929.803509824512;4013.8394464221396;3286.260591366947;3851.8074096295213;3954.307996100685;3985.5615882253073;3987.1826275613575;3498.912527186828;3917.0794863207125;3993.221321842242;4002.3196056670436;4015.2371049500794;3268.5750852389274;3343.180920674426;3459.1697992481877;3487.843047062888;3507.5089487471773;4081.551029386765;4255.978720106411;5042.773075211624;5084.237364395371;5090.287359769635;3790.218597259284;4565.908681826378;4586.793594288265;4594.701344412636;4610.331501932201;4565.497795242521;5060.544550990428;5429.348478182644;5487.451254874521;5494.170502191375;3105.7204851563242;3251.0734440684528;3270.3622793555264;3613.7713177519768;3743.3636281832155;4756.738379389132;4815.340341340571;5347.51872331492;5410.3507579090365;5700.515456186229;3612.5845527764645;3688.004238855494;3729.3846515324667;3734.439834024918;3795.658390744791;3679.7424180307657;3714.6285371462636;3849.4418309344987;3859.5754467008605;3888;3882.5535063467496;3928.332183528807;4445.622122119656;4452.354408610798;4504.2793153096;5175.249588809676;5467.781288748405;5470.671759694352;5472.2065300531995;5477.287952566141;1238.9442475088642;1242.211195890616;1260.174585646403;1294.6600268721827;1304.660788195076;722.9819254518574;738.5457943364792;793.4366599714152;800.3877034068942;810.6109067647562;4278.593533614302;4339.1972485090355;4349.782510874444;4376.978115109404;4389.003003633339;3837.731358804857;4016.49793775778;4366.628836548865;4692.343071969927;4721.677930179555;3674.265146970563;4286.742795432284;4323.2434323993175;4329.415528903621;4334.783260836945;2465.4452748131525;2650.6084305739255;2958.556216567532;2959.955600665992;2970.3168271297723;5143.97428012865;5149.76826042829;5630.635133243965;5832.3292821326095;5863.384344643751;4514.864554063383;5373.95207934449;5755.453231943043;5755.568332375088;5822.592418530747;5466.266866566717;5918.437748413894;5932.822015339547;5934.554908381802;5955.940440595626;4728.62171026324;4963.975180124076;5459.945400546103;5722.198892155185;5727.367700205422;4666.9533304667875;4924.507549245047;5331.866703332293;5618.494335509824;5655.688937108499;4947.703137811743;5065.4428012917815;5118.104331742004;5661.858453538694;5824.359320474659;3795.658390744846;3813.5995720448955;3825.5218097737784;3827.923441531098;3852.4413959975086;5183.533481986639;5734.827955161351;5748.48263656273;5750.6837123958585;5758.581395813684;4582.816687332603;5180.430152683277;5322.627416080992;5402.810901618424;5421.457854214547;5452.6183167177915;5476.726163691879;5499.890002199853;5503.3395992481155;5558.221848941071;5100.591952643854;5369.328833895763;5394.649347792381;5440.211169380338;5448.8365349038;4427.5572442755465;4534.9389162315965;4786.617070634411;5564.805231817029;5579.414161512983;4658.114958158075;5377.193420986883;5377.193420986883;5403.486668766461;5404.891902162013 10:06:49 INFO - PROCESS | 1655 | |5;yui.html;724.9637518124094;791.7783020754188;804.4288555125862;808.4704518540354;820.8563501387256;15186.620334491643;16439;16440.75338869918;16441;16463.67072658546;15039.473618423357;15174.620634484143;15286.159261240628;15299.464518741846;15303.387864485436;709.7125664106032;747.0325927923325;887.9112088791134;913.2602591900582;916.9821498137062;842.1704620948361;898;898.9280857531381;899.0739538275586;903.1510380242551;953.461294368683;953.6519170502751;954.8615450759636;955.2214944819984;955.6607404371453;739.1019910808367;787.9724209652692;914.5381582300962;946.4983558713893;949.5632009275741;704.5103652961194;706.8126946359218;709.4537206351132;709.4856229233816;711.0791524975169;15460;15475.535733928;15520.61198470036;15533.301001454962;15541.378344866192;11761.705957351134;14478.348474318682;14552.92723536386;14575.19836408997;14708.97037207396;12846.164999275017;12860.099793014493;12992.350382480838;13146.4084116215;13438.798418023738;4551.891967366789;4594.517575654575;4619.260918253097;4751.596114330286;4810.494898035691;115508.42245788718;116859;118877.81122188753;119157.40421297838;119398;10831.458427078614;11088.501017454233;11213.327200368005;11357.20499565039;11964.700882478008;19381;19600.901995490218;23495.530089398115;23516.412089697893;23537.646935295983;8077.27304542594;8087.312578430781;8112.513249205066;8181.713640022571;8252.628631711588;3893.766374017568;3929.5088113985753;4372.147431250875;4463.085067561142;4477.6417886569;3488.7906725596545;3596.8561257549404;3602.171500554887;3604.351216780952;3613.710903127796;644.4168027934727;647.7247169952751;651.0494677770414;652.8694261147789;667.7028722218566;513.5147285814869;531.7793115856954;567.1351189436143;595.2499850188793;597.7698586044404;8588.527630980356;8661.566921653892;8767.25478334336;8874.733757987142;8875.644974201088;571.7341436232172;587.9911801322984;597.560792817279;605.2253116011512;608.458471959956;4188.895277618084;4243.384709217208;4267;4349.543297953733;4372.344148377705;82029.76955345675;82672;95495;132267;149643.0071398566;125417;125773;126932;127101;129016.35491822481;72775;92739.53630231805;106956.46521767341;108387.91612083778;109371.45314273378;8020.238077382639;8187.959060204661;8289.378296627656;8402.07577166499;8422.494650321001;48712;53405;55132.72433637806;63019;66413.66793166003;64394.35605643884;84078;89494;90438;98650.01349986695;12903.806442903551;13158.473661053451;13287.933560332136;13430.597082087554;13442.865571344162;11085.556577736801;14554.27228638585;14620.976531642684;14621.731170899388;14892.106473611619;9912.35569687968;11695.707607309885;11704.180707350404;11964.58123965657;12198.57304994321;11513.251638643462;12000.03999680006;12077.758444830879;12087.758244834878;12143.271403715806;9970.501474926079;10372.688819335432;10658.413787241467;10750.032497074988;10752.731181720206;9650.469224192737;9820.803583928138;10113.241506886865;10147.247595022018;10471.162307015573;9415.246780257427;9774.902250977684;9776.462294573867;9811.607535698493;9820.705378838646 10:06:49 INFO - PROCESS | 1655 | __end_tp_report 10:06:49 INFO - PROCESS | 1655 | __start_cc_report 10:06:49 INFO - PROCESS | 1655 | _x_x_mozilla_cycle_collect,1397 10:06:49 INFO - PROCESS | 1655 | __end_cc_report 10:06:49 INFO - PROCESS | 1655 | __startTimestamp1468429609052__endTimestamp 10:06:49 INFO - PROCESS | 1655 | 10:06:49 INFO - PROCESS | 1655 | ------- Summary: start ------- 10:06:49 INFO - PROCESS | 1655 | Number of tests: 6 10:06:49 INFO - PROCESS | 1655 | 10:06:49 INFO - PROCESS | 1655 | [#0] dojo.html Cycles:165 Average:5574.17 Median:5770.39 stddev:658.97 (11.4%) stddev-sans-first:631.34 10:06:49 INFO - PROCESS | 1655 | Values: 3082.9 3277.5 3287.9 3306.3 3346.3 5422.0 5517.9 5553.2 5565.1 5604.4 5460.8 5499.3 5646.1 5649.6 5664.5 4898.7 4959.8 4988.3 4995.7 5196.3 4018.5 5115.3 5121.8 5148.8 5151.3 4166.0 4955.5 5017.8 5018.0 5091.7 5011.6 5029.4 5036.1 5052.5 5058.1 5016.9 5029.2 5074.7 5110.8 5124.9 5459.4 5575.4 5675.1 5686.8 5687.4 6003.2 6053.7 6067.0 6070.6 6095.6 5129.7 6138.6 6171.5 6182.0 6189.6 5276.4 5478.2 5545.9 5690.6 5707.4 6146.9 6216.6 6233.3 6286.2 6289.2 5978.1 5980.1 6009.5 6010.2 6026.1 5844.3 5972.8 6132.2 6150.0 6163.5 5966.5 5978.1 5984.5 5989.3 5990.9 5965.0 6028.3 6031.5 6034.6 6049.2 4787.7 4834.2 4834.8 4841.1 4858.2 4248.9 4321.6 4340.8 4353.1 4369.7 5597.9 5599.1 5608.1 5660.9 5662.7 6011.2 6027.3 6060.8 6071.2 6100.2 5594.8 5598.4 5600.2 5610.3 5614.2 5186.7 5278.0 5292.0 5298.8 5328.6 6194.3 6198.8 6215.8 6242.4 6274.6 6166.4 6166.8 6177.5 6177.8 6182.1 6145.0 6259.4 6260.5 6289.5 6292.5 5935.0 5937.4 5987.7 6050.1 6057.2 6121.8 6132.4 6154.6 6158.9 6164.5 4507.5 5732.9 5825.4 5908.4 5935.3 5840.6 5923.0 5933.5 5939.0 5983.2 4809.7 5730.7 5770.4 5794.6 5820.9 5724.1 5796.6 5815.2 5923.2 5982.3 5733.4 5771.1 5776.7 5777.0 5836.0 10:06:49 INFO - PROCESS | 1655 | 10:06:49 INFO - PROCESS | 1655 | [#1] ext.html Cycles:180 Average:61183.88 Median:20035.69 stddev:129860.61 (648.1%) stddev-sans-first:130144.94 10:06:49 INFO - PROCESS | 1655 | Values: 486.7 522.9 530.6 557.8 562.8 30825.5 42131.0 42903.8 43033.4 43194.4 44097.3 44410.6 44468.1 44493.3 44549.8 8721.7 9678.5 9877.4 9879.4 9884.7 8702.8 11865.5 12005.5 12008.6 12384.0 10724.6 11540.2 12026.1 12074.4 12117.2 8748.0 11817.9 12037.9 12084.2 12157.1 11958.8 12036.5 12059.0 12075.3 12078.9 41929.2 42207.4 42259.0 42260.8 42354.8 3078.8 3615.3 3662.4 3690.4 4264.0 32054.9 33408.0 33563.7 33694.5 33716.8 3230.2 3239.4 3290.1 3378.7 3434.6 516215.0 516638.0 516911.0 517639.0 519241.0 61123.7 61309.4 61480.7 61648.0 61715.7 64931.7 65082.7 65107.7 65233.7 65306.3 16671.3 16924.2 16943.7 16948.7 16953.0 11975.6 12147.3 12840.5 12933.4 13170.6 13499.7 16555.5 16585.8 16595.3 16797.7 6502.2 6561.6 6583.6 6593.2 6634.2 861.6 944.2 948.0 975.5 1027.4 27902.0 29797.7 30228.8 30731.0 32458.0 951.9 1049.2 1115.1 1158.4 1158.8 15512.9 16354.0 16368.0 16384.4 16846.3 184813.0 185341.0 185548.0 185740.0 186181.0 61346.0 61690.4 61715.0 61837.0 61919.7 1264.3 1387.5 1395.4 1419.3 1431.5 32118.0 32316.5 32322.8 32331.8 33519.5 59986.7 60909.4 60938.7 60976.4 61134.7 613399.0 630530.0 639454.0 639714.0 641378.0 24096.8 26978.3 27024.6 27079.9 27673.0 49921.0 50435.7 50575.7 50623.0 51228.7 38364.6 41308.6 41875.0 41977.0 42094.7 38028.0 39218.6 40866.4 41269.4 42304.4 39309.0 39896.0 40078.2 40207.6 40416.6 33828.7 34863.0 35619.8 35723.6 36409.0 22328.7 25473.4 26162.0 26216.6 27145.7 10:06:49 INFO - PROCESS | 1655 | 10:06:49 INFO - PROCESS | 1655 | [#2] jquery.html Cycles:180 Average:33416.35 Median:22097.28 stddev:90631.15 (410.1%) stddev-sans-first:90851.39 10:06:49 INFO - PROCESS | 1655 | Values: 347.3 385.1 391.5 393.4 414.1 15039.6 15044.8 21161.2 21369.8 21692.6 18337.9 18566.9 18574.8 20609.9 22265.0 5740.2 5952.5 6006.1 6010.6 6338.5 6795.4 6831.3 6831.5 6843.7 6854.1 6498.2 6686.1 6697.9 6708.5 6797.5 5849.1 6675.1 6680.3 6739.0 6741.3 5125.2 5854.3 5951.0 5978.5 6013.1 12546.3 16402.9 20069.7 25745.6 26385.3 2191.7 2271.5 2393.6 2436.4 2522.2 9981.7 10025.1 10242.0 10706.0 10730.6 1898.5 1909.3 1917.6 1988.6 1992.0 90371.0 104684.0 117005.0 129477.7 129856.0 11390.6 11554.8 11607.5 11612.3 11707.8 14334.8 14492.0 14512.6 14517.7 14556.3 8514.9 8523.7 8550.2 8578.3 8688.4 8550.7 8580.4 8583.6 8717.3 8726.7 4147.9 4186.0 4517.5 4543.7 4589.6 1881.6 1931.9 1938.7 1970.2 1974.1 874.5 940.5 979.3 998.3 1042.1 20765.5 21646.6 21721.6 21728.6 21929.6 958.7 972.0 998.7 1001.1 1043.2 10496.7 10997.7 11391.1 11420.9 11422.6 515914.0 531813.0 544751.0 550923.0 550971.5 127221.9 128583.4 134015.7 134124.0 134549.0 990.3 992.6 1006.9 1011.8 1012.2 19019.5 19276.0 19330.9 19342.4 19361.8 53186.5 55344.9 56167.0 56912.7 57096.4 75050.6 77106.0 78830.6 79500.0 81484.6 8018.2 8193.7 8270.1 8294.7 8513.2 11328.8 12827.9 12975.2 13105.7 13390.9 11230.3 11400.5 11410.6 11413.7 11569.7 11080.1 11140.0 11286.4 11418.4 11464.9 11486.0 11590.2 11596.6 11681.9 11876.6 7133.8 8134.5 8223.8 8405.4 8409.7 7252.5 11153.2 11202.9 11319.9 11398.1 10:06:49 INFO - PROCESS | 1655 | 10:06:49 INFO - PROCESS | 1655 | [#3] mootools.html Cycles:180 Average:5634.70 Median:4827.99 stddev:3178.46 (65.8%) stddev-sans-first:3171.33 10:06:49 INFO - PROCESS | 1655 | Values: 1385.1 1489.4 1550.1 1796.7 1971.3 7789.5 8539.0 8765.8 8766.1 8888.6 8465.4 8733.6 8751.1 8756.3 8777.0 1705.0 2177.2 2274.9 2340.3 2382.5 2377.1 2418.1 2419.2 2421.7 2423.4 2338.3 2377.7 2379.0 2380.6 2383.7 2071.0 2268.7 2360.2 2361.0 2367.6 2362.4 2407.3 2408.6 2408.8 2410.2 8584.0 8624.4 8635.5 8639.5 8657.3 5139.8 5141.0 5259.4 5297.9 5315.1 3066.7 3412.9 3566.6 3632.0 3652.6 1077.1 1094.3 1095.1 1119.2 1121.5 11324.0 11530.0 11537.8 11552.0 11561.0 4578.4 4597.6 4796.1 4807.0 4825.7 9966.7 9972.1 9980.7 9985.4 10029.3 5258.8 5331.9 5340.9 5341.6 5370.7 4196.9 4359.5 4510.7 4513.9 4523.4 3936.6 4033.5 4093.6 4096.4 4099.5 1229.6 1264.1 1268.5 1278.3 1279.2 911.1 923.7 923.7 933.9 964.6 5711.6 5798.9 5864.2 5910.7 6025.8 416.8 431.5 432.1 438.7 451.0 3904.1 3963.8 4048.7 4054.4 4061.6 10284.2 10541.8 10573.5 10677.3 10684.4 10620.8 10638.5 10642.1 10648.5 10656.4 7998.3 8073.6 8200.1 8202.3 8207.9 5111.8 5772.4 5810.3 5810.9 5820.3 9726.7 9861.8 9956.7 9990.2 10004.3 9132.1 9718.0 10110.8 10122.9 10186.5 6929.0 7275.5 7470.8 7487.8 7588.0 8072.8 8079.9 8096.2 8142.6 8158.8 7367.3 7429.0 7482.1 7543.8 7573.1 6668.7 6782.7 7007.9 7078.6 7250.3 6880.8 6958.0 6965.4 7032.4 7043.9 4677.7 4841.0 4867.9 4904.8 4918.9 4830.3 4857.8 4905.5 4945.4 5022.6 10:06:49 INFO - PROCESS | 1655 | 10:06:49 INFO - PROCESS | 1655 | [#4] prototype.html Cycles:180 Average:4314.43 Median:4590.75 stddev:1338.91 (29.2%) stddev-sans-first:1307.83 10:06:49 INFO - PROCESS | 1655 | Values: 271.8 303.2 309.7 312.4 319.9 4298.6 5159.0 5164.1 5204.7 5222.0 4414.4 4441.5 5124.7 5166.8 5183.8 3745.9 3921.9 3922.4 3933.9 3934.5 3835.7 3850.2 3916.5 3929.8 4013.8 3286.3 3851.8 3954.3 3985.6 3987.2 3498.9 3917.1 3993.2 4002.3 4015.2 3268.6 3343.2 3459.2 3487.8 3507.5 4081.6 4256.0 5042.8 5084.2 5090.3 3790.2 4565.9 4586.8 4594.7 4610.3 4565.5 5060.5 5429.3 5487.5 5494.2 3105.7 3251.1 3270.4 3613.8 3743.4 4756.7 4815.3 5347.5 5410.4 5700.5 3612.6 3688.0 3729.4 3734.4 3795.7 3679.7 3714.6 3849.4 3859.6 3888.0 3882.6 3928.3 4445.6 4452.4 4504.3 5175.2 5467.8 5470.7 5472.2 5477.3 1238.9 1242.2 1260.2 1294.7 1304.7 723.0 738.5 793.4 800.4 810.6 4278.6 4339.2 4349.8 4377.0 4389.0 3837.7 4016.5 4366.6 4692.3 4721.7 3674.3 4286.7 4323.2 4329.4 4334.8 2465.4 2650.6 2958.6 2960.0 2970.3 5144.0 5149.8 5630.6 5832.3 5863.4 4514.9 5374.0 5755.5 5755.6 5822.6 5466.3 5918.4 5932.8 5934.6 5955.9 4728.6 4964.0 5459.9 5722.2 5727.4 4667.0 4924.5 5331.9 5618.5 5655.7 4947.7 5065.4 5118.1 5661.9 5824.4 3795.7 3813.6 3825.5 3827.9 3852.4 5183.5 5734.8 5748.5 5750.7 5758.6 4582.8 5180.4 5322.6 5402.8 5421.5 5452.6 5476.7 5499.9 5503.3 5558.2 5100.6 5369.3 5394.6 5440.2 5448.8 4427.6 4534.9 4786.6 5564.8 5579.4 4658.1 5377.2 5377.2 5403.5 5404.9 10:06:49 INFO - PROCESS | 1655 | 10:06:49 INFO - PROCESS | 1655 | [#5] yui.html Cycles:180 Average:23080.66 Median:4514.77 stddev:36181.89 (801.4%) stddev-sans-first:36244.45 10:06:49 INFO - PROCESS | 1655 | Values: 725.0 791.8 804.4 808.5 820.9 15186.6 16439.0 16440.8 16441.0 16463.7 15039.5 15174.6 15286.2 15299.5 15303.4 709.7 747.0 887.9 913.3 917.0 842.2 898.0 898.9 899.1 903.2 953.5 953.7 954.9 955.2 955.7 739.1 788.0 914.5 946.5 949.6 704.5 706.8 709.5 709.5 711.1 15460.0 15475.5 15520.6 15533.3 15541.4 11761.7 14478.3 14552.9 14575.2 14709.0 12846.2 12860.1 12992.4 13146.4 13438.8 4551.9 4594.5 4619.3 4751.6 4810.5 115508.4 116859.0 118877.8 119157.4 119398.0 10831.5 11088.5 11213.3 11357.2 11964.7 19381.0 19600.9 23495.5 23516.4 23537.6 8077.3 8087.3 8112.5 8181.7 8252.6 3893.8 3929.5 4372.1 4463.1 4477.6 3488.8 3596.9 3602.2 3604.4 3613.7 644.4 647.7 651.0 652.9 667.7 513.5 531.8 567.1 595.2 597.8 8588.5 8661.6 8767.3 8874.7 8875.6 571.7 588.0 597.6 605.2 608.5 4188.9 4243.4 4267.0 4349.5 4372.3 82029.8 82672.0 95495.0 132267.0 149643.0 125417.0 125773.0 126932.0 127101.0 129016.4 72775.0 92739.5 106956.5 108387.9 109371.5 8020.2 8188.0 8289.4 8402.1 8422.5 48712.0 53405.0 55132.7 63019.0 66413.7 64394.4 84078.0 89494.0 90438.0 98650.0 12903.8 13158.5 13287.9 13430.6 13442.9 11085.6 14554.3 14621.0 14621.7 14892.1 9912.4 11695.7 11704.2 11964.6 12198.6 11513.3 12000.0 12077.8 12087.8 12143.3 9970.5 10372.7 10658.4 10750.0 10752.7 9650.5 9820.8 10113.2 10147.2 10471.2 9415.2 9774.9 9776.5 9811.6 9820.7 10:06:49 INFO - PROCESS | 1655 | -------- Summary: end -------- 10:06:49 INFO - PROCESS | 1655 | 10:06:49 INFO - TEST-INFO | 1655: exit 0 10:06:49 INFO - TEST-OK | dromaeo_css | took 1117136ms 10:06:49 INFO - TEST-START | kraken 10:06:49 INFO - operating with platform_type : mac_ 10:06:49 INFO - Initialising browser for kraken test... 10:06:49 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/tmpYbqnO6/profile http://localhost:49279/getInfo.html) 10:06:51 INFO - PROCESS | 1661 | __metrics Screen width/height:1600/1200 10:06:51 INFO - PROCESS | 1661 | colorDepth:24 10:06:51 INFO - PROCESS | 1661 | Browser inner width/height: 1024/689 10:06:51 INFO - PROCESS | 1661 | __metrics 10:06:51 INFO - PROCESS | 1661 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:06:51 INFO - PROCESS | 1661 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:06:52 INFO - TEST-INFO | 1661: exit 0 10:06:52 INFO - Browser initialized. 10:06:52 INFO - Running cycle 1/1 for kraken test... 10:06:52 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/tmpYbqnO6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:07:04 INFO - PROCESS | 1662 | RSS: Main: 243798016 10:07:04 INFO - PROCESS | 1662 | 10:07:07 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-beat-detection) 10:07:07 INFO - PROCESS | 1662 | RSS: Main: 293867520 10:07:07 INFO - PROCESS | 1662 | 10:07:10 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-dft) 10:07:11 INFO - PROCESS | 1662 | RSS: Main: 304283648 10:07:11 INFO - PROCESS | 1662 | 10:07:14 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-fft) 10:07:15 INFO - PROCESS | 1662 | RSS: Main: 388562944 10:07:15 INFO - PROCESS | 1662 | 10:07:17 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-oscillator) 10:07:17 INFO - PROCESS | 1662 | RSS: Main: 289759232 10:07:17 INFO - PROCESS | 1662 | 10:07:20 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:07:20 INFO - PROCESS | 1662 | RSS: Main: 293912576 10:07:20 INFO - PROCESS | 1662 | 10:07:23 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-darkroom) 10:07:24 INFO - PROCESS | 1662 | RSS: Main: 329187328 10:07:24 INFO - PROCESS | 1662 | 10:07:26 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-desaturate) 10:07:27 INFO - PROCESS | 1662 | RSS: Main: 332730368 10:07:27 INFO - PROCESS | 1662 | 10:07:30 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49279/tests/kraken/driver.html?testName=json-parse-financial) 10:07:30 INFO - PROCESS | 1662 | RSS: Main: 332595200 10:07:30 INFO - PROCESS | 1662 | 10:07:32 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49279/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:07:33 INFO - PROCESS | 1662 | RSS: Main: 311468032 10:07:33 INFO - PROCESS | 1662 | 10:07:35 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:07:35 INFO - PROCESS | 1662 | RSS: Main: 318439424 10:07:35 INFO - PROCESS | 1662 | 10:07:37 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:07:38 INFO - PROCESS | 1662 | RSS: Main: 317665280 10:07:38 INFO - PROCESS | 1662 | 10:07:41 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:07:42 INFO - PROCESS | 1662 | RSS: Main: 324288512 10:07:42 INFO - PROCESS | 1662 | 10:07:44 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:07:45 INFO - PROCESS | 1662 | RSS: Main: 326848512 10:07:45 INFO - PROCESS | 1662 | 10:07:47 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49279/tests/kraken/driver.html?testName=ai-astar) 10:07:47 INFO - PROCESS | 1662 | RSS: Main: 370348032 10:07:47 INFO - PROCESS | 1662 | 10:07:47 INFO - PROCESS | 1662 | __start_tp_report 10:07:47 INFO - PROCESS | 1662 | _x_x_mozilla_page_load 10:07:47 INFO - PROCESS | 1662 | _x_x_mozilla_page_load_details 10:07:47 INFO - PROCESS | 1662 | |i|pagename|runs| 10:07:47 INFO - PROCESS | 1662 | |0;ai-astar;102;114;112;111;112;112;113;111;112;112 10:07:47 INFO - PROCESS | 1662 | |1;audio-beat-detection;144;105;107;170;138;140;127;128;143;107 10:07:47 INFO - PROCESS | 1662 | |2;audio-dft;180;183;183;183;183;183;179;183;182;181 10:07:47 INFO - PROCESS | 1662 | |3;audio-fft;76;70;58;104;58;60;61;65;97;59 10:07:47 INFO - PROCESS | 1662 | |4;audio-oscillator;87;87;91;70;70;68;95;69;67;100 10:07:47 INFO - PROCESS | 1662 | |5;imaging-gaussian-blur;87;84;89;83;86;84;87;88;135;84 10:07:47 INFO - PROCESS | 1662 | |6;imaging-darkroom;94;94;95;95;94;94;94;93;93;92 10:07:47 INFO - PROCESS | 1662 | |7;imaging-desaturate;84;85;86;83;85;83;84;85;82;83 10:07:47 INFO - PROCESS | 1662 | |8;json-parse-financial;56;54;53;55;54;55;54;54;55;55 10:07:47 INFO - PROCESS | 1662 | |9;json-stringify-tinderbox;66;65;73;79;75;72;68;78;105;100 10:07:47 INFO - PROCESS | 1662 | |10;stanford-crypto-aes;82;82;83;84;84;85;83;84;87;94 10:07:47 INFO - PROCESS | 1662 | |11;stanford-crypto-ccm;151;147;130;134;133;133;143;145;222;126 10:07:47 INFO - PROCESS | 1662 | |12;stanford-crypto-pbkdf2;164;166;163;165;161;163;163;163;160;163 10:07:47 INFO - PROCESS | 1662 | |13;stanford-crypto-sha256-iterative;67;69;69;82;85;85;89;65;66;65 10:07:47 INFO - PROCESS | 1662 | __end_tp_report 10:07:47 INFO - PROCESS | 1662 | __start_cc_report 10:07:47 INFO - PROCESS | 1662 | _x_x_mozilla_cycle_collect,2069 10:07:47 INFO - PROCESS | 1662 | __end_cc_report 10:07:47 INFO - PROCESS | 1662 | __startTimestamp1468429667719__endTimestamp 10:07:47 INFO - PROCESS | 1662 | 10:07:47 INFO - PROCESS | 1662 | ------- Summary: start ------- 10:07:47 INFO - PROCESS | 1662 | Number of tests: 14 10:07:47 INFO - PROCESS | 1662 | 10:07:47 INFO - PROCESS | 1662 | [#0] ai-astar Cycles:10 Average:111.10 Median:112.00 stddev:3.31 (3.0%) stddev-sans-first:0.93 10:07:47 INFO - PROCESS | 1662 | Values: 102.0 114.0 112.0 111.0 112.0 112.0 113.0 111.0 112.0 112.0 10:07:47 INFO - PROCESS | 1662 | 10:07:47 INFO - PROCESS | 1662 | [#1] audio-beat-detection Cycles:10 Average:130.90 Median:139.00 stddev:20.59 (14.8%) stddev-sans-first:21.29 10:07:47 INFO - PROCESS | 1662 | Values: 144.0 105.0 107.0 170.0 138.0 140.0 127.0 128.0 143.0 107.0 10:07:47 INFO - PROCESS | 1662 | 10:07:47 INFO - PROCESS | 1662 | [#2] audio-dft Cycles:10 Average:182.00 Median:183.00 stddev:1.49 (0.8%) stddev-sans-first:1.39 10:07:47 INFO - PROCESS | 1662 | Values: 180.0 183.0 183.0 183.0 183.0 183.0 179.0 183.0 182.0 181.0 10:07:47 INFO - PROCESS | 1662 | 10:07:47 INFO - PROCESS | 1662 | [#3] audio-fft Cycles:10 Average:70.80 Median:63.00 stddev:16.77 (26.6%) stddev-sans-first:17.68 10:07:47 INFO - PROCESS | 1662 | Values: 76.0 70.0 58.0 104.0 58.0 60.0 61.0 65.0 97.0 59.0 10:07:47 INFO - PROCESS | 1662 | 10:07:47 INFO - PROCESS | 1662 | [#4] audio-oscillator Cycles:10 Average:80.40 Median:78.50 stddev:12.81 (16.3%) stddev-sans-first:13.36 10:07:47 INFO - PROCESS | 1662 | Values: 87.0 87.0 91.0 70.0 70.0 68.0 95.0 69.0 67.0 100.0 10:07:47 INFO - PROCESS | 1662 | 10:07:47 INFO - PROCESS | 1662 | [#5] imaging-gaussian-blur Cycles:10 Average:90.70 Median:86.50 stddev:15.69 (18.1%) stddev-sans-first:16.59 10:07:47 INFO - PROCESS | 1662 | Values: 87.0 84.0 89.0 83.0 86.0 84.0 87.0 88.0 135.0 84.0 10:07:47 INFO - PROCESS | 1662 | 10:07:47 INFO - PROCESS | 1662 | [#6] imaging-darkroom Cycles:10 Average:93.80 Median:94.00 stddev:0.92 (1.0%) stddev-sans-first:0.97 10:07:47 INFO - PROCESS | 1662 | Values: 94.0 94.0 95.0 95.0 94.0 94.0 94.0 93.0 93.0 92.0 10:07:47 INFO - PROCESS | 1662 | 10:07:47 INFO - PROCESS | 1662 | [#7] imaging-desaturate Cycles:10 Average:84.00 Median:84.50 stddev:1.25 (1.5%) stddev-sans-first:1.32 10:07:47 INFO - PROCESS | 1662 | Values: 84.0 85.0 86.0 83.0 85.0 83.0 84.0 85.0 82.0 83.0 10:07:47 INFO - PROCESS | 1662 | 10:07:47 INFO - PROCESS | 1662 | [#8] json-parse-financial Cycles:10 Average:54.50 Median:55.00 stddev:0.85 (1.5%) stddev-sans-first:0.71 10:07:47 INFO - PROCESS | 1662 | Values: 56.0 54.0 53.0 55.0 54.0 55.0 54.0 54.0 55.0 55.0 10:07:47 INFO - PROCESS | 1662 | 10:07:47 INFO - PROCESS | 1662 | [#9] json-stringify-tinderbox Cycles:10 Average:78.10 Median:72.50 stddev:13.73 (18.9%) stddev-sans-first:13.85 10:07:47 INFO - PROCESS | 1662 | Values: 66.0 65.0 73.0 79.0 75.0 72.0 68.0 78.0 105.0 100.0 10:07:47 INFO - PROCESS | 1662 | 10:07:47 INFO - PROCESS | 1662 | [#10] stanford-crypto-aes Cycles:10 Average:84.80 Median:84.00 stddev:3.55 (4.2%) stddev-sans-first:3.62 10:07:47 INFO - PROCESS | 1662 | Values: 82.0 82.0 83.0 84.0 84.0 85.0 83.0 84.0 87.0 94.0 10:07:47 INFO - PROCESS | 1662 | 10:07:47 INFO - PROCESS | 1662 | [#11] stanford-crypto-ccm Cycles:10 Average:146.40 Median:144.00 stddev:27.79 (19.3%) stddev-sans-first:29.42 10:07:47 INFO - PROCESS | 1662 | Values: 151.0 147.0 130.0 134.0 133.0 133.0 143.0 145.0 222.0 126.0 10:07:47 INFO - PROCESS | 1662 | 10:07:47 INFO - PROCESS | 1662 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.10 Median:163.00 stddev:1.73 (1.1%) stddev-sans-first:1.80 10:07:47 INFO - PROCESS | 1662 | Values: 164.0 166.0 163.0 165.0 161.0 163.0 163.0 163.0 160.0 163.0 10:07:47 INFO - PROCESS | 1662 | 10:07:47 INFO - PROCESS | 1662 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.20 Median:75.50 stddev:9.75 (12.9%) stddev-sans-first:9.99 10:07:47 INFO - PROCESS | 1662 | Values: 67.0 69.0 69.0 82.0 85.0 85.0 89.0 65.0 66.0 65.0 10:07:47 INFO - PROCESS | 1662 | -------- Summary: end -------- 10:07:47 INFO - PROCESS | 1662 | 10:07:48 INFO - TEST-INFO | 1662: exit 0 10:07:48 INFO - TEST-OK | kraken | took 58914ms 10:07:48 INFO - SUITE-END | took 1176s 10:07:48 INFO - Completed test suite (00:19:36) 10:07:48 INFO - javascript benchmark 10:07:48 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3082.9383412331745, 3277.491988741745, 3287.91780205495, 3306.30567580808, 3346.29727757171, 5421.972890135544, 5517.944820551798, 5553.222548843165, 5565.137403702428, 5604.439556044394, 5460.781568737246, 5499.285092937923, 5646.068398714207, 5649.6327738696955, 5664.490195882369, 4898.730569818658, 4959.826406075788, 4988.2766998785155, 4995.6752811067345, 5196.272521846944, 4018.4976877890263, 5115.309433226523, 5121.846344609649, 5148.845534633967, 5151.278820965068, 4166.0, 4955.529224723664, 5017.84946451607, 5017.996400719834, 5091.694498330112, 5011.649184557083, 5029.3964724232965, 5036.093503169428, 5052.46949070346, 5058.0895438821, 5016.924746128811, 5029.1953287473825, 5074.720890351029, 5110.821121260738, 5124.923126153073, 5459.372172200208, 5575.358833734132, 5675.120356344773, 5686.772529098831, 5687.43125687428, 6003.159557661931, 6053.727582258809, 6066.977768300391, 6070.57505974582, 6095.603785753956, 5129.692218466904, 6138.570300078996, 6171.506279497629, 6182.010878259502, 6189.566730328879, 5276.3932147802725, 5478.178273259043, 5545.944540554543, 5690.573207009491, 5707.429257074259, 6146.907796383058, 6216.627002379872, 6233.3143354231, 6286.214223222098, 6289.150964619809, 5978.073398623221, 5980.102984552352, 6009.519238460913, 6010.248718910137, 6026.126211699367, 5844.298684157929, 5972.761089556456, 6132.202813634288, 6149.96925015381, 6163.506919446433, 5966.463018328372, 5978.073398623221, 5984.521238301013, 5989.311229208697, 5990.850228744316, 5965.0, 6028.306744724413, 6031.5476339273855, 6034.63792172471, 6049.243844519435, 4787.736674482937, 4834.178189707758, 4834.7824347904425, 4841.12859685253, 4858.222684370484, 4248.915021699549, 4321.567843215653, 4340.8480703175865, 4353.064091220403, 4369.694121411534, 5597.888042239132, 5599.1041433370465, 5608.1027035674915, 5660.943390566124, 5662.688552129673, 6011.158437818709, 6027.336992930774, 6060.757569697251, 6071.150038994544, 6100.206973093558, 5594.832155035356, 5598.384177740445, 5600.215969764236, 5610.298712660918, 5614.157876318503, 5186.688798672092, 5278.049951008856, 5292.047431462297, 5298.814541491107, 5328.573714102862, 6194.318624951252, 6198.81403557894, 6215.844603884939, 6242.375762423721, 6274.623522588659, 6166.352532983972, 6166.784162554289, 6177.4749146323065, 6177.84555386119, 6182.1345011697495, 6145.047517634774, 6259.436650701458, 6260.530460215593, 6289.528285378524, 6292.465140463112, 5935.0, 5937.435943585352, 5987.700614969322, 6050.1227322037175, 6057.242844644419, 6121.8469538260115, 6132.35610260934, 6154.599951003171, 6158.876822463615, 6164.47601953839, 4507.481639611356, 5732.856678583069, 5825.3883342248455, 5908.379620139824, 5935.347111817783, 5840.649561026352, 5922.970385148046, 5933.465988061095, 5939.0, 5983.222181116427, 4809.711417314972, 5730.684812335361, 5770.394108618535, 5794.565407594363, 5820.912686309794, 5724.055530837365, 5796.565257605781, 5815.185873977562, 5923.229980102731, 5982.312034116132, 5733.397993210653, 5771.105478650816, 5776.682282474504, 5776.971115144397, 5836.0], "value": 5531.846899796473, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [486.65447532252097, 522.8954209158168, 530.6046994988734, 557.7741014888971, 562.814271290474, 30825.537616935737, 42131.0, 42903.78548107255, 43033.35449968245, 43194.352084718754, 44097.338539921926, 44410.555894441044, 44468.11063778723, 44493.332600010945, 44549.7772511137, 8721.694740684077, 9678.516074196297, 9877.35797173185, 9879.407235565854, 9884.654037108667, 8702.825943481126, 11865.466054027589, 12005.519779208822, 12008.579699710512, 12384.009279257683, 10724.624638137666, 11540.192186546945, 12026.098042646792, 12074.396280186, 12117.151799374047, 8747.956260218722, 11817.881821181763, 12037.879621203763, 12084.214526055823, 12157.088218383613, 11958.820617690742, 12036.45835937385, 12058.974987126105, 12075.27548347102, 12078.939605302005, 41929.1614167718, 42207.36688949668, 42259.0, 42260.78869605633, 42354.78822605898, 3078.7998780079224, 3615.349237137314, 3662.377395842713, 3690.3910854708943, 4263.957360426387, 32054.878079267113, 33408.0, 33563.72739694588, 33694.49458258128, 33716.81991130323, 3230.224746060952, 3239.3683231769814, 3290.144562413766, 3378.729701623864, 3434.553508043963, 516215.0, 516638.0, 516911.0, 517639.0, 519241.0, 61123.69438152781, 61309.38690613037, 61480.69259653673, 61648.0, 61715.69142154261, 64931.67534162394, 65082.67458662677, 65107.67446162834, 65233.673831630535, 65306.346936530026, 16671.33314667424, 16924.153792310335, 16943.745843812354, 16948.661026779395, 16953.0, 11975.640730777915, 12147.271163730204, 12840.48638054486, 12933.353332333534, 13170.604881853367, 13499.730005399837, 16555.50333489997, 16585.83414165867, 16595.253213605418, 16797.748033779502, 6502.187226596676, 6561.639109848908, 6583.637899915455, 6593.175853018372, 6634.170728658918, 861.6036623153419, 944.2398868910593, 947.9526023698926, 975.4878688688495, 1027.4041056187393, 27902.023429180288, 29797.702022979494, 30228.84885575558, 30731.0, 32458.0, 951.8762560867043, 1049.2130901823632, 1115.1134847796022, 1158.362900404791, 1158.7740390623746, 15512.92243538775, 16354.0, 16368.0, 16384.426545070866, 16846.32614695423, 184813.0, 185341.0, 185548.0, 185740.0, 186181.0, 61346.0, 61690.383096168465, 61715.0, 61837.0, 61919.69040154861, 1264.3425418782365, 1387.4796951143321, 1395.4139261509988, 1419.269076425621, 1431.484665520412, 32118.0, 32316.515252270765, 32322.83838580792, 32331.838340808146, 33519.497207542394, 59986.70006649939, 60909.390906090375, 60938.695306524955, 60976.39023609884, 61134.694326528086, 613399.0, 630530.0, 639454.0, 639714.0, 641378.0, 24096.759032409453, 26978.325541860824, 27024.594631080156, 27079.864600676872, 27673.0, 49921.00157996892, 50435.74782126066, 50575.74712126416, 50623.0, 51228.74385628048, 38364.61635383611, 41308.58691413168, 41874.95312617209, 41977.0, 42094.73715788531, 38028.0, 39218.607813921495, 40866.38700419555, 41269.38095928504, 42304.365434517895, 39309.0, 39896.0, 40078.198436030536, 40207.59792402039, 40416.59583404246, 33828.66171338255, 34863.0, 35619.82190089033, 35723.64276357203, 36409.0, 22328.66507002364, 25473.363165921, 26162.0, 26216.60675089837, 27145.72854271511], "value": 20248.745465217955, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.34525419584077, 385.1296070879812, 391.50670155603933, 393.3882812226986, 414.0580180090294, 15039.624009399757, 15044.849551504481, 21161.153553857865, 21369.786302136974, 21692.56614867702, 18337.90831045846, 18566.907165464152, 18574.81425185748, 20609.86645734484, 22265.0, 5740.225069615601, 5952.464278214959, 6006.129111278862, 6010.609310394832, 6338.492920566344, 6795.422389096932, 6831.3168683131535, 6831.487638427137, 6843.692033858489, 6854.1089658344345, 6498.220213574354, 6686.097376854135, 6697.933020669804, 6708.530402871801, 6797.4562035037325, 5849.093390524476, 6675.098861653663, 6680.265170831229, 6738.966305168492, 6741.292164322749, 5125.179971204627, 5854.297484301868, 5951.0, 5978.461938425563, 6013.098035294697, 12546.309952952584, 16402.917985410117, 20069.69895451555, 25745.613815792778, 26385.3403664908, 2191.7260342457193, 2271.5116250006326, 2393.6170212765874, 2436.4030812450887, 2522.2433270019105, 9981.65064222756, 10025.147862431702, 10242.0, 10706.0, 10730.624428145056, 1898.4684288399271, 1909.30310436691, 1917.5589614388768, 1988.6122206169803, 1991.9601607967904, 90371.0, 104684.0, 117005.0, 129477.70522294845, 129855.99999999905, 11390.601328953448, 11554.826677599844, 11607.477663504993, 11612.303261804318, 11707.76584468306, 14334.784978225334, 14491.985561010839, 14512.564623061326, 14517.709645807025, 14556.272186390637, 8514.914850851412, 8523.74428767138, 8550.187732165434, 8578.313734901192, 8688.435251708745, 8550.74347769568, 8580.442271252348, 8583.570821458901, 8717.254064969515, 8726.65093396257, 4147.937780933288, 4185.958140418617, 4517.457905051415, 4543.681942264076, 4589.5639914208095, 1881.6048629787897, 1931.9323823666384, 1938.6898096304806, 1970.1626808606286, 1974.1412485568824, 874.4709450782312, 940.5250348573926, 979.3487330924997, 998.256299057207, 1042.1089968077335, 20765.480862978246, 21646.56706865854, 21721.565568688537, 21728.565428691338, 21929.561408771737, 958.6692591056075, 971.9757006074764, 998.7453199434243, 1001.1190152665607, 1043.160255993917, 10496.73758156037, 10997.670069897917, 11391.145664075228, 11420.885791141982, 11422.600208992646, 515914.0, 531813.0, 544751.0, 550923.0, 550971.490285092, 127221.91112355402, 128583.35708321398, 134015.65984340032, 134123.98814017797, 134549.0, 990.2869933647762, 992.5533509920565, 1006.9194464442827, 1011.7672935224939, 1012.1851909213007, 19019.524511887314, 19276.0, 19330.936798476218, 19342.4197274082, 19361.806381936563, 53186.46813531815, 55344.893102138536, 56167.0, 56912.71543642255, 57096.42903570911, 75050.62474687591, 77106.0, 78830.6058469704, 79500.0, 81484.59257703673, 8018.1981801821685, 8193.71322003727, 8270.090290067967, 8294.709685160991, 8513.233808957224, 11328.830067549157, 12827.871721282667, 12975.221486710827, 13105.737885242052, 13390.866091339352, 11230.270032447865, 11400.543978241109, 11410.600628977945, 11413.657590272305, 11569.6529104127, 11080.05819505351, 11140.0, 11286.435678215892, 11418.429078546205, 11464.94267528657, 11486.0, 11590.246633968765, 11596.594119205787, 11681.941590291994, 11876.584319548774, 7133.82165445868, 8134.471259368122, 8223.753287401387, 8405.36959728035, 8409.747707568782, 7252.4560657949805, 11153.163512736739, 11202.94398528002, 11319.886801132212, 11398.145139114433], "value": 9687.276200909502, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1385.1412124482822, 1489.4265707702532, 1550.0854495847452, 1796.6765982123213, 1971.2706298669502, 7789.532628042314, 8539.018012928507, 8765.824683506326, 8766.123387661231, 8888.64445422184, 8465.44974576652, 8733.606987685553, 8751.081136480658, 8756.299496040318, 8777.0, 1704.9659006819857, 2177.1835561664375, 2274.8521346112534, 2340.3330050935488, 2382.4996750682376, 2377.09670325277, 2418.141559746291, 2419.213755529451, 2421.7457166997474, 2423.4062654649633, 2338.286822519131, 2377.714674239085, 2379.048380647746, 2380.6191009438494, 2383.7377888432256, 2070.9689354659617, 2268.6597010448563, 2360.1503458754833, 2360.9645855312183, 2367.562001029815, 2362.4212068043266, 2407.3018824540864, 2408.6025805742206, 2408.843425177358, 2410.2287268074224, 8584.0, 8624.396292259544, 8635.481871087755, 8639.52482613456, 8657.307415406814, 5139.820106296262, 5140.974295128538, 5259.447757985395, 5297.920531191997, 5315.149576067849, 3066.6626671066165, 3412.8634854605784, 3566.589842168132, 3632.0, 3652.6164752700847, 1077.0575746222055, 1094.2668412163869, 1095.0746619106842, 1119.2109562758235, 1121.506537123663, 11324.03745681618, 11530.0, 11537.769244615145, 11552.0, 11561.0, 4578.35902973584, 4597.632189424838, 4796.112719146969, 4806.975965120152, 4825.734584597881, 9966.651167209258, 9972.055449187485, 9980.700578982642, 9985.400875947467, 10029.29794914349, 5258.842234732964, 5331.866703332447, 5340.893182136336, 5341.572674186134, 5370.677759334374, 4196.853110141131, 4359.542248063971, 4510.729356238636, 4513.864584062483, 4523.389342438729, 3936.566977632458, 4033.495813023372, 4093.63157315843, 4096.40602112692, 4099.467069280974, 1229.59423390281, 1264.1403845385043, 1268.492602958824, 1278.2649976263685, 1279.2452453052558, 911.0934620052957, 923.6997975657938, 923.6997975657938, 933.9073284257435, 964.5707660091332, 5711.6001879868845, 5798.884022319615, 5864.208331875142, 5910.704464776744, 6025.849353766191, 416.8332666933251, 431.4757569553008, 432.06242453875166, 438.65346376362953, 451.02128381412416, 3904.082540602956, 3963.781991990468, 4048.716589838683, 4054.351303791379, 4061.634452899253, 10284.177265818716, 10541.84187237192, 10573.471326433648, 10677.305975111592, 10684.358938463716, 10620.840687389695, 10638.521266543024, 10642.148628109733, 10648.467576621139, 10656.360618362924, 7998.3201427879285, 8073.631035361536, 8200.0979892213, 8202.26179643835, 8207.891934588753, 5111.846644600668, 5772.3650398456975, 5810.273715785527, 5810.883782324415, 5820.301563812201, 9726.70819875405, 9861.802763944825, 9956.651517196864, 9990.200783937413, 10004.29969902129, 9132.086791320815, 9718.0, 10110.8483372748, 10122.898771012196, 10186.541605627617, 6929.0, 7275.454340924346, 7470.813229669302, 7487.812804679927, 7588.0, 8072.757817265491, 8079.878801818095, 8096.190380962093, 8142.592870356577, 8158.836823263383, 7367.300106489874, 7428.9628551856895, 7482.064741907262, 7543.811404714926, 7573.091229052549, 6668.699603577255, 6782.694778735067, 7007.859842803014, 7078.610676412641, 7250.3112204340505, 6880.759173428906, 6958.0, 6965.407940324926, 7032.437405007689, 7043.859122817617, 4677.742724150205, 4840.95159048405, 4867.853964381075, 4904.803807847647, 4918.852434426973, 4830.2513110467935, 4857.805687772591, 4905.484924083063, 4945.431275403231, 5022.623303252344], "value": 4457.208157880412, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [271.8433067298644, 303.1904814146229, 309.6980444067035, 312.44073109134644, 319.9456092464281, 4298.613124818766, 5159.045576568333, 5164.140909272659, 5204.713740744252, 5221.95560887823, 4414.432876328897, 4441.5336389679105, 5124.718140502271, 5166.819161329354, 5183.818566350178, 3745.9324092633606, 3921.8823435296986, 3922.3724204127348, 3933.9346842907416, 3934.5475270343845, 3835.6931445484433, 3850.210706805098, 3916.4516967624554, 3929.803509824512, 4013.8394464221396, 3286.260591366947, 3851.8074096295213, 3954.307996100685, 3985.5615882253073, 3987.1826275613575, 3498.912527186828, 3917.0794863207125, 3993.221321842242, 4002.3196056670436, 4015.2371049500794, 3268.5750852389274, 3343.180920674426, 3459.1697992481877, 3487.843047062888, 3507.5089487471773, 4081.551029386765, 4255.978720106411, 5042.773075211624, 5084.237364395371, 5090.287359769635, 3790.218597259284, 4565.908681826378, 4586.793594288265, 4594.701344412636, 4610.331501932201, 4565.497795242521, 5060.544550990428, 5429.348478182644, 5487.451254874521, 5494.170502191375, 3105.7204851563242, 3251.0734440684528, 3270.3622793555264, 3613.7713177519768, 3743.3636281832155, 4756.738379389132, 4815.340341340571, 5347.51872331492, 5410.3507579090365, 5700.515456186229, 3612.5845527764645, 3688.004238855494, 3729.3846515324667, 3734.439834024918, 3795.658390744791, 3679.7424180307657, 3714.6285371462636, 3849.4418309344987, 3859.5754467008605, 3888.0, 3882.5535063467496, 3928.332183528807, 4445.622122119656, 4452.354408610798, 4504.2793153096, 5175.249588809676, 5467.781288748405, 5470.671759694352, 5472.2065300531995, 5477.287952566141, 1238.9442475088642, 1242.211195890616, 1260.174585646403, 1294.6600268721827, 1304.660788195076, 722.9819254518574, 738.5457943364792, 793.4366599714152, 800.3877034068942, 810.6109067647562, 4278.593533614302, 4339.1972485090355, 4349.782510874444, 4376.978115109404, 4389.003003633339, 3837.731358804857, 4016.49793775778, 4366.628836548865, 4692.343071969927, 4721.677930179555, 3674.265146970563, 4286.742795432284, 4323.2434323993175, 4329.415528903621, 4334.783260836945, 2465.4452748131525, 2650.6084305739255, 2958.556216567532, 2959.955600665992, 2970.3168271297723, 5143.97428012865, 5149.76826042829, 5630.635133243965, 5832.3292821326095, 5863.384344643751, 4514.864554063383, 5373.95207934449, 5755.453231943043, 5755.568332375088, 5822.592418530747, 5466.266866566717, 5918.437748413894, 5932.822015339547, 5934.554908381802, 5955.940440595626, 4728.62171026324, 4963.975180124076, 5459.945400546103, 5722.198892155185, 5727.367700205422, 4666.9533304667875, 4924.507549245047, 5331.866703332293, 5618.494335509824, 5655.688937108499, 4947.703137811743, 5065.4428012917815, 5118.104331742004, 5661.858453538694, 5824.359320474659, 3795.658390744846, 3813.5995720448955, 3825.5218097737784, 3827.923441531098, 3852.4413959975086, 5183.533481986639, 5734.827955161351, 5748.48263656273, 5750.6837123958585, 5758.581395813684, 4582.816687332603, 5180.430152683277, 5322.627416080992, 5402.810901618424, 5421.457854214547, 5452.6183167177915, 5476.726163691879, 5499.890002199853, 5503.3395992481155, 5558.221848941071, 5100.591952643854, 5369.328833895763, 5394.649347792381, 5440.211169380338, 5448.8365349038, 4427.5572442755465, 4534.9389162315965, 4786.617070634411, 5564.805231817029, 5579.414161512983, 4658.114958158075, 5377.193420986883, 5377.193420986883, 5403.486668766461, 5404.891902162013], "value": 3889.2796063041583, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [724.9637518124094, 791.7783020754188, 804.4288555125862, 808.4704518540354, 820.8563501387256, 15186.620334491643, 16439.0, 16440.75338869918, 16441.0, 16463.67072658546, 15039.473618423357, 15174.620634484143, 15286.159261240628, 15299.464518741846, 15303.387864485436, 709.7125664106032, 747.0325927923325, 887.9112088791134, 913.2602591900582, 916.9821498137062, 842.1704620948361, 898.0, 898.9280857531381, 899.0739538275586, 903.1510380242551, 953.461294368683, 953.6519170502751, 954.8615450759636, 955.2214944819984, 955.6607404371453, 739.1019910808367, 787.9724209652692, 914.5381582300962, 946.4983558713893, 949.5632009275741, 704.5103652961194, 706.8126946359218, 709.4537206351132, 709.4856229233816, 711.0791524975169, 15460.0, 15475.535733928, 15520.61198470036, 15533.301001454962, 15541.378344866192, 11761.705957351134, 14478.348474318682, 14552.92723536386, 14575.19836408997, 14708.97037207396, 12846.164999275017, 12860.099793014493, 12992.350382480838, 13146.4084116215, 13438.798418023738, 4551.891967366789, 4594.517575654575, 4619.260918253097, 4751.596114330286, 4810.494898035691, 115508.42245788718, 116859.0, 118877.81122188753, 119157.40421297838, 119398.0, 10831.458427078614, 11088.501017454233, 11213.327200368005, 11357.20499565039, 11964.700882478008, 19381.0, 19600.901995490218, 23495.530089398115, 23516.412089697893, 23537.646935295983, 8077.27304542594, 8087.312578430781, 8112.513249205066, 8181.713640022571, 8252.628631711588, 3893.766374017568, 3929.5088113985753, 4372.147431250875, 4463.085067561142, 4477.6417886569, 3488.7906725596545, 3596.8561257549404, 3602.171500554887, 3604.351216780952, 3613.710903127796, 644.4168027934727, 647.7247169952751, 651.0494677770414, 652.8694261147789, 667.7028722218566, 513.5147285814869, 531.7793115856954, 567.1351189436143, 595.2499850188793, 597.7698586044404, 8588.527630980356, 8661.566921653892, 8767.25478334336, 8874.733757987142, 8875.644974201088, 571.7341436232172, 587.9911801322984, 597.560792817279, 605.2253116011512, 608.458471959956, 4188.895277618084, 4243.384709217208, 4267.0, 4349.543297953733, 4372.344148377705, 82029.76955345675, 82672.0, 95495.0, 132267.0, 149643.0071398566, 125417.0, 125773.0, 126932.0, 127101.0, 129016.35491822481, 72775.0, 92739.53630231805, 106956.46521767341, 108387.91612083778, 109371.45314273378, 8020.238077382639, 8187.959060204661, 8289.378296627656, 8402.07577166499, 8422.494650321001, 48712.0, 53405.0, 55132.72433637806, 63019.0, 66413.66793166003, 64394.35605643884, 84078.0, 89494.0, 90438.0, 98650.01349986695, 12903.806442903551, 13158.473661053451, 13287.933560332136, 13430.597082087554, 13442.865571344162, 11085.556577736801, 14554.27228638585, 14620.976531642684, 14621.731170899388, 14892.106473611619, 9912.35569687968, 11695.707607309885, 11704.180707350404, 11964.58123965657, 12198.57304994321, 11513.251638643462, 12000.03999680006, 12077.758444830879, 12087.758244834878, 12143.271403715806, 9970.501474926079, 10372.688819335432, 10658.413787241467, 10750.032497074988, 10752.731181720206, 9650.469224192737, 9820.803583928138, 10113.241506886865, 10147.247595022018, 10471.162307015573, 9415.246780257427, 9774.902250977684, 9776.462294573867, 9811.607535698493, 9820.705378838646], "value": 7583.144445099498, "unit": "score"}], "name": "dromaeo_css", "value": 7228.535832608377, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 114.0, 112.0, 111.0, 112.0, 112.0, 113.0, 111.0, 112.0, 112.0], "value": 111.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 105.0, 107.0, 170.0, 138.0, 140.0, 127.0, 128.0, 143.0, 107.0], "value": 130.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 183.0, 183.0, 183.0, 183.0, 183.0, 179.0, 183.0, 182.0, 181.0], "value": 182.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 70.0, 58.0, 104.0, 58.0, 60.0, 61.0, 65.0, 97.0, 59.0], "value": 70.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 87.0, 91.0, 70.0, 70.0, 68.0, 95.0, 69.0, 67.0, 100.0], "value": 80.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 89.0, 83.0, 86.0, 84.0, 87.0, 88.0, 135.0, 84.0], "value": 90.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 95.0, 94.0, 94.0, 94.0, 93.0, 93.0, 92.0], "value": 93.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 86.0, 83.0, 85.0, 83.0, 84.0, 85.0, 82.0, 83.0], "value": 84.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 54.0, 53.0, 55.0, 54.0, 55.0, 54.0, 54.0, 55.0, 55.0], "value": 54.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 73.0, 79.0, 75.0, 72.0, 68.0, 78.0, 105.0, 100.0], "value": 78.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 83.0, 84.0, 84.0, 85.0, 83.0, 84.0, 87.0, 94.0], "value": 84.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 147.0, 130.0, 134.0, 133.0, 133.0, 143.0, 145.0, 222.0, 126.0], "value": 146.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 166.0, 163.0, 165.0, 161.0, 163.0, 163.0, 163.0, 160.0, 163.0], "value": 163.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 69.0, 69.0, 82.0, 85.0, 85.0, 89.0, 65.0, 66.0, 65.0], "value": 74.2, "unit": "score"}], "name": "kraken", "value": 1444.8000000000002, "alertThreshold": 2.0}]} 10:07:48 INFO - Return code: 0 10:07:49 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:07:49 INFO - # TBPL SUCCESS # 10:07:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:07:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:07:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:07:49 INFO - 2016-07-13 10:07:49.038 screenresolution[1666:9879] starting screenresolution argv=screenresolution get 10:07:49 INFO - 2016-07-13 10:07:49.040 screenresolution[1666:9879] Display 0: 1600x1200x32@60 10:07:49 INFO - 2016-07-13 10:07:49.046 screenresolution[1667:9882] starting screenresolution argv=screenresolution list 10:07:49 INFO - Available Modes on Display 0 10:07:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:07:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:07:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:07:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:07:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:07:49 INFO - Intel Iris: 10:07:49 INFO - Chipset Model: Intel Iris 10:07:49 INFO - Type: GPU 10:07:49 INFO - Bus: Built-In 10:07:49 INFO - VRAM (Dynamic, Max): 1536 MB 10:07:49 INFO - Vendor: Intel (0x8086) 10:07:49 INFO - Device ID: 0x0a2e 10:07:49 INFO - Revision ID: 0x0009 10:07:49 INFO - Displays: 10:07:49 INFO - Display: 10:07:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:07:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:07:49 INFO - Display Serial Number: 1600x1200 60 10:07:49 INFO - Main Display: Yes 10:07:49 INFO - Mirror: Off 10:07:49 INFO - Online: Yes 10:07:49 INFO - Rotation: Supported 10:07:49 INFO - Return code: 0 10:07:49 INFO - Running post-action listener: _resource_record_post_action 10:07:49 INFO - ##### 10:07:49 INFO - ##### Finished run-tests step (success) 10:07:49 INFO - ##### 10:07:49 INFO - Running post-run listener: _resource_record_post_run 10:07:49 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 36455936; Write bytes: 703931392; Read time: 232; Write time: 1956 10:07:49 INFO - TinderboxPrint: CPU usage
53.3% 10:07:49 INFO - TinderboxPrint: I/O read bytes / time
36,455,936 / 232 10:07:49 INFO - TinderboxPrint: I/O write bytes / time
703,931,392 / 1,956 10:07:49 INFO - TinderboxPrint: CPU idle
2,264.5 (46.7%) 10:07:49 INFO - TinderboxPrint: CPU system
962.8 (19.9%) 10:07:49 INFO - TinderboxPrint: CPU user
1,618.4 (33.4%) 10:07:49 INFO - TinderboxPrint: Swap in / out
443,686,912 / 0 10:07:49 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 395551232; Write bytes: 383076352; Read time: 29392; Write time: 844 10:07:49 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 30203392; Write bytes: 293596160; Read time: 180; Write time: 1046 10:07:49 INFO - Running post-run listener: _upload_blobber_files 10:07:49 INFO - Blob upload gear active. 10:07:49 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:07:49 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 10:07:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:07:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:07:50 INFO - (blobuploader) - INFO - Open directory for files ... 10:07:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:07:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:07:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:07:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:07:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:07:51 INFO - (blobuploader) - INFO - Done attempting. 10:07:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:07:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:07:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:07:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:07:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:07:52 INFO - (blobuploader) - INFO - Done attempting. 10:07:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:07:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:07:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:07:54 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:07:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:07:54 INFO - (blobuploader) - INFO - Done attempting. 10:07:54 INFO - (blobuploader) - INFO - Iteration through files over. 10:07:54 INFO - Return code: 0 10:07:54 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:07:54 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:07:54 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f6814069f57bb5f8add59383691ae42e433b517a0d282af26022fcc15576460ccb2604328acc83e4991962a2ff80b1bb17e4ed38b060fc5a3f49bcac57d0d5b9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7d7af9ec12b61cba6665bf3457cce1af95d5b75b81a783668dfe01b65503f2611c86e0d858078f68ffa036c70305beec6334ea96b062042bc7b94faf8b71923c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ce26a8d84209167c1f428d27851ca9bf8a17d7bbdf0fe9183e1fc84d1c854e315faf3c658515ef67e8037a024b0ad4f8761fd3e05d77debce48310548e8e84a1"} 10:07:54 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:07:54 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:07:54 INFO - Contents: 10:07:54 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f6814069f57bb5f8add59383691ae42e433b517a0d282af26022fcc15576460ccb2604328acc83e4991962a2ff80b1bb17e4ed38b060fc5a3f49bcac57d0d5b9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7d7af9ec12b61cba6665bf3457cce1af95d5b75b81a783668dfe01b65503f2611c86e0d858078f68ffa036c70305beec6334ea96b062042bc7b94faf8b71923c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ce26a8d84209167c1f428d27851ca9bf8a17d7bbdf0fe9183e1fc84d1c854e315faf3c658515ef67e8037a024b0ad4f8761fd3e05d77debce48310548e8e84a1"} 10:07:54 INFO - Running post-run listener: copy_logs_to_upload_dir 10:07:54 INFO - Copying logs to upload dir... 10:07:54 INFO - mkdir: /builds/slave/test/build/upload/logs 10:07:54 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.020533 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-07-13 10:07:54.767682) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-13 10:07:54.771196) ========= 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.86SyqnmCSa/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.agCptg3otQ/Listeners TMPDIR=/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/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/f6814069f57bb5f8add59383691ae42e433b517a0d282af26022fcc15576460ccb2604328acc83e4991962a2ff80b1bb17e4ed38b060fc5a3f49bcac57d0d5b9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7d7af9ec12b61cba6665bf3457cce1af95d5b75b81a783668dfe01b65503f2611c86e0d858078f68ffa036c70305beec6334ea96b062042bc7b94faf8b71923c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ce26a8d84209167c1f428d27851ca9bf8a17d7bbdf0fe9183e1fc84d1c854e315faf3c658515ef67e8037a024b0ad4f8761fd3e05d77debce48310548e8e84a1"} build_url:https://queue.taskcluster.net/v1/task/OXPh7zg_RsOreeknn0zseQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010883 build_url: 'https://queue.taskcluster.net/v1/task/OXPh7zg_RsOreeknn0zseQ/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/f6814069f57bb5f8add59383691ae42e433b517a0d282af26022fcc15576460ccb2604328acc83e4991962a2ff80b1bb17e4ed38b060fc5a3f49bcac57d0d5b9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7d7af9ec12b61cba6665bf3457cce1af95d5b75b81a783668dfe01b65503f2611c86e0d858078f68ffa036c70305beec6334ea96b062042bc7b94faf8b71923c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ce26a8d84209167c1f428d27851ca9bf8a17d7bbdf0fe9183e1fc84d1c854e315faf3c658515ef67e8037a024b0ad4f8761fd3e05d77debce48310548e8e84a1"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-13 10:07:54.804615) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 10:07:54.805276) ========= 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.86SyqnmCSa/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.agCptg3otQ/Listeners TMPDIR=/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/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.004939 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 10:07:54.855005) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-13 10:07:54.855378) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-13 10:07:54.855749) ========= ========= Total master_lag: 0.18 =========