builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0036 starttime: 1461772868.59 results: success (0) buildid: 20160427071020 builduid: c68ab51b35d04f3eb59d2ae2ec182e95 revision: ed934827bebaeee8a1aeb1726a3af1021d2a8364 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-27 09:01:08.595073) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-27 09:01:08.595520) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-27 09:01:08.595816) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-27 09:01:08.835216) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 09:01:08.835674) ========= 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.EPC9MeT5oa/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.cFkqwX8c79/Listeners TMPDIR=/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-04-27 09:01:09-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 161M=0s 2016-04-27 09:01:09 (161 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.536418 ========= master_lag: 0.32 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 09:01:09.690266) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 09:01:09.690965) ========= 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.EPC9MeT5oa/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.cFkqwX8c79/Listeners TMPDIR=/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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.020722 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 09:01:09.755665) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 09:01:09.756693) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev ed934827bebaeee8a1aeb1726a3af1021d2a8364 --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 ed934827bebaeee8a1aeb1726a3af1021d2a8364 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.EPC9MeT5oa/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.cFkqwX8c79/Listeners TMPDIR=/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-04-27 09:01:09,819 truncating revision to first 12 chars 2016-04-27 09:01:09,820 Setting DEBUG logging. 2016-04-27 09:01:09,820 attempt 1/10 2016-04-27 09:01:09,820 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/ed934827beba?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-27 09:01:10,445 unpacking tar archive at: fx-team-ed934827beba/testing/mozharness/ program finished with exit code 0 elapsedTime=0.867168 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 09:01:10.642401) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-27 09:01:10.642702) ========= script_repo_revision: ed934827bebaeee8a1aeb1726a3af1021d2a8364 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-27 09:01:10.643230) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-27 09:01:10.643510) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-27 09:01:10.658818) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 36 secs) (at 2016-04-27 09:01:10.659227) ========= /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.EPC9MeT5oa/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.cFkqwX8c79/Listeners TMPDIR=/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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:01:10 INFO - MultiFileLogger online at 20160427 09:01:10 in /builds/slave/test 09:01:10 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:01:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:01:10 INFO - {'append_to_log': False, 09:01:10 INFO - 'base_work_dir': '/builds/slave/test', 09:01:10 INFO - 'blob_upload_branch': 'Fx-Team', 09:01:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:01:10 INFO - 'branch': 'Fx-Team', 09:01:10 INFO - 'buildbot_json_path': 'buildprops.json', 09:01:10 INFO - 'config_files': ('talos/mac_config.py',), 09:01:10 INFO - 'default_actions': ('clobber', 09:01:10 INFO - 'read-buildbot-config', 09:01:10 INFO - 'download-and-extract', 09:01:10 INFO - 'populate-webroot', 09:01:10 INFO - 'create-virtualenv', 09:01:10 INFO - 'install', 09:01:10 INFO - 'run-tests'), 09:01:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:01:10 INFO - 'download_minidump_stackwalk': True, 09:01:10 INFO - 'download_symbols': 'ondemand', 09:01:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:01:10 INFO - 'tooltool.py': '/tools/tooltool.py', 09:01:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:01:10 INFO - '/tools/misc-python/virtualenv.py')}, 09:01:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:01:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:01:10 INFO - 'installer_path': 'installer.exe', 09:01:10 INFO - 'log_level': 'info', 09:01:10 INFO - 'log_name': 'talos', 09:01:10 INFO - 'log_to_console': True, 09:01:10 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:01:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:01:10 INFO - 'opt_config_files': (), 09:01:10 INFO - 'pip_index': False, 09:01:10 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:01:10 INFO - 'cmd': ('bash', 09:01:10 INFO - '-c', 09:01:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:01:10 INFO - 'enabled': True, 09:01:10 INFO - 'halt_on_failure': False, 09:01:10 INFO - 'name': 'check_screen_resolution'},), 09:01:10 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:01:10 INFO - 'cmd': ('bash', 09:01:10 INFO - '-c', 09:01:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:01:10 INFO - 'enabled': True, 09:01:10 INFO - 'halt_on_failure': False, 09:01:10 INFO - 'name': 'check_screen_resolution'},), 09:01:10 INFO - 'run_cmd_checks_enabled': True, 09:01:10 INFO - 'sps_profile': False, 09:01:10 INFO - 'sps_profile_interval': 0, 09:01:10 INFO - 'suite': 'dromaeojs', 09:01:10 INFO - 'system_bits': '32', 09:01:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:01:10 INFO - 'title': 't-yosemite-r7-0036', 09:01:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:01:10 INFO - 'use_talos_json': True, 09:01:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:01:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:01:10 INFO - 'work_dir': 'build'} 09:01:10 INFO - ##### 09:01:10 INFO - ##### Running clobber step. 09:01:10 INFO - ##### 09:01:10 INFO - Running pre-action listener: _resource_record_pre_action 09:01:10 INFO - Running main action method: clobber 09:01:10 INFO - rmtree: /builds/slave/test/build 09:01:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:01:12 INFO - Running post-action listener: _resource_record_post_action 09:01:12 INFO - ##### 09:01:12 INFO - ##### Running read-buildbot-config step. 09:01:12 INFO - ##### 09:01:12 INFO - Running pre-action listener: _resource_record_pre_action 09:01:12 INFO - Running main action method: read_buildbot_config 09:01:12 INFO - Using buildbot properties: 09:01:12 INFO - { 09:01:12 INFO - "project": "", 09:01:12 INFO - "product": "firefox", 09:01:12 INFO - "script_repo_revision": "production", 09:01:12 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 09:01:12 INFO - "repository": "", 09:01:12 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 09:01:12 INFO - "buildid": "20160427071020", 09:01:12 INFO - "pgo_build": "False", 09:01:12 INFO - "basedir": "/builds/slave/test", 09:01:12 INFO - "buildnumber": 674, 09:01:12 INFO - "slavename": "t-yosemite-r7-0036", 09:01:12 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 09:01:12 INFO - "platform": "yosemite_r7", 09:01:12 INFO - "scheduler": "tests-fx-team-macosx64-talos", 09:01:12 INFO - "branch": "fx-team", 09:01:12 INFO - "repo_path": "integration/fx-team", 09:01:12 INFO - "revision": "ed934827bebaeee8a1aeb1726a3af1021d2a8364", 09:01:12 INFO - "stage_platform": "macosx64", 09:01:12 INFO - "builduid": "c68ab51b35d04f3eb59d2ae2ec182e95", 09:01:12 INFO - "slavebuilddir": "test" 09:01:12 INFO - } 09:01:12 INFO - Found installer url https://queue.taskcluster.net/v1/task/Yfu3EQrzTAuR7TR_vmxUKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 09:01:12 INFO - Running post-action listener: _resource_record_post_action 09:01:12 INFO - ##### 09:01:12 INFO - ##### Running download-and-extract step. 09:01:12 INFO - ##### 09:01:12 INFO - Running pre-action listener: _resource_record_pre_action 09:01:12 INFO - Running main action method: download_and_extract 09:01:12 INFO - mkdir: /builds/slave/test/build/tests 09:01: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:01:12 INFO - https://queue.taskcluster.net/v1/task/Yfu3EQrzTAuR7TR_vmxUKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 09:01:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yfu3EQrzTAuR7TR_vmxUKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 09:01:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yfu3EQrzTAuR7TR_vmxUKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 09:01:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yfu3EQrzTAuR7TR_vmxUKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 09:01:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yfu3EQrzTAuR7TR_vmxUKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 09:01:31 INFO - Downloaded 1199 bytes. 09:01:31 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 09:01:31 INFO - Using the following test package requirements: 09:01:31 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 09:01:31 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:01:31 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 09:01:31 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:01:31 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 09:01:31 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:01:31 INFO - u'jsshell-mac.zip'], 09:01:31 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:01:31 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 09:01:31 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 09:01:31 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:01:31 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 09:01:31 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:01:31 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 09:01:31 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:01:31 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 09:01:31 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:01:31 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 09:01:31 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:01: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:01:31 INFO - https://queue.taskcluster.net/v1/task/Yfu3EQrzTAuR7TR_vmxUKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:01:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yfu3EQrzTAuR7TR_vmxUKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 09:01:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yfu3EQrzTAuR7TR_vmxUKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 09:01:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yfu3EQrzTAuR7TR_vmxUKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 09:01:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yfu3EQrzTAuR7TR_vmxUKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:01:39 INFO - Downloaded 18117659 bytes. 09:01:39 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:01:39 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:01:43 INFO - Return code: 0 09:01:43 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 09:01:43 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:01:43 INFO - https://queue.taskcluster.net/v1/task/Yfu3EQrzTAuR7TR_vmxUKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:01:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yfu3EQrzTAuR7TR_vmxUKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 09:01:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yfu3EQrzTAuR7TR_vmxUKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 09:01:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yfu3EQrzTAuR7TR_vmxUKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 09:01:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yfu3EQrzTAuR7TR_vmxUKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:01:44 INFO - Downloaded 18117659 bytes. 09:01:44 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:01:44 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:01:48 INFO - Return code: 0 09:01:48 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:01:48 INFO - https://queue.taskcluster.net/v1/task/Yfu3EQrzTAuR7TR_vmxUKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 09:01:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yfu3EQrzTAuR7TR_vmxUKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 09:01:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yfu3EQrzTAuR7TR_vmxUKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 09:01:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yfu3EQrzTAuR7TR_vmxUKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 09:01:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yfu3EQrzTAuR7TR_vmxUKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:01:49 INFO - Downloaded 10840145 bytes. 09:01:49 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:01:49 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 09:01:49 INFO - Return code: 0 09:01:49 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:01:49 INFO - https://queue.taskcluster.net/v1/task/Yfu3EQrzTAuR7TR_vmxUKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 09:01:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yfu3EQrzTAuR7TR_vmxUKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 09:01:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yfu3EQrzTAuR7TR_vmxUKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 09:01:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yfu3EQrzTAuR7TR_vmxUKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 09:01:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yfu3EQrzTAuR7TR_vmxUKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 09:01:54 INFO - Downloaded 110187503 bytes. 09:01:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Yfu3EQrzTAuR7TR_vmxUKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 09:01:54 INFO - mkdir: /builds/slave/test/properties 09:01:54 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:01:54 INFO - Writing to file /builds/slave/test/properties/build_url 09:01:54 INFO - Contents: 09:01:54 INFO - build_url:https://queue.taskcluster.net/v1/task/Yfu3EQrzTAuR7TR_vmxUKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 09:01:55 INFO - Running post-action listener: _resource_record_post_action 09:01:55 INFO - Running post-action listener: set_extra_try_arguments 09:01:55 INFO - ##### 09:01:55 INFO - ##### Running populate-webroot step. 09:01:55 INFO - ##### 09:01:55 INFO - Running pre-action listener: _resource_record_pre_action 09:01:55 INFO - Running main action method: populate_webroot 09:01:55 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:01:55 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:01:55 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:01:55 INFO - '--tppagecycles', 09:01:55 INFO - '7'], 09:01:55 INFO - 'tests': ['tsvgm']}}, 09:01:55 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:01:55 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:01:55 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:01:55 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:01:55 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:01:55 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:01:55 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:01:55 INFO - 'talos_options': ['--disable-e10s'], 09:01:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:01:55 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:01:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:01:55 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:01:55 INFO - 'talos_options': ['--disable-e10s'], 09:01:55 INFO - 'tests': ['damp', 'tps']}, 09:01:55 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:01:55 INFO - 'tests': ['damp', 'tps']}, 09:01:55 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:01:55 INFO - 'tests': ['dromaeo_dom']}, 09:01:55 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:01:55 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:01:55 INFO - 'tests': ['a11yr', 09:01:55 INFO - 'ts_paint', 09:01:55 INFO - 'tpaint', 09:01:55 INFO - 'sessionrestore', 09:01:55 INFO - 'sessionrestore_no_auto_restore', 09:01:55 INFO - 'tabpaint']}, 09:01:55 INFO - 'other-e10s': {'tests': ['a11yr', 09:01:55 INFO - 'ts_paint', 09:01:55 INFO - 'tpaint', 09:01:55 INFO - 'sessionrestore', 09:01:55 INFO - 'sessionrestore_no_auto_restore', 09:01:55 INFO - 'tabpaint']}, 09:01:55 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:01:55 INFO - 'ts_paint', 09:01:55 INFO - 'tpaint', 09:01:55 INFO - 'sessionrestore', 09:01:55 INFO - 'sessionrestore_no_auto_restore', 09:01:55 INFO - 'tabpaint']}, 09:01:55 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:01:55 INFO - 'ts_paint', 09:01:55 INFO - 'tpaint', 09:01:55 INFO - 'sessionrestore', 09:01:55 INFO - 'sessionrestore_no_auto_restore', 09:01:55 INFO - 'tabpaint']}, 09:01:55 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:01:55 INFO - 'tests': ['a11yr', 09:01:55 INFO - 'ts_paint', 09:01:55 INFO - 'tpaint', 09:01:55 INFO - 'sessionrestore', 09:01:55 INFO - 'sessionrestore_no_auto_restore', 09:01:55 INFO - 'tabpaint']}, 09:01:55 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:01:55 INFO - 'tests': ['a11yr', 09:01:55 INFO - 'ts_paint', 09:01:55 INFO - 'tpaint', 09:01:55 INFO - 'sessionrestore', 09:01:55 INFO - 'sessionrestore_no_auto_restore', 09:01:55 INFO - 'tabpaint']}, 09:01:55 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:01:55 INFO - 'tests': ['tsvgx', 09:01:55 INFO - 'tsvgr_opacity', 09:01:55 INFO - 'tart', 09:01:55 INFO - 'tscrollx', 09:01:55 INFO - 'cart']}, 09:01:55 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:01:55 INFO - 'tsvgr_opacity', 09:01:55 INFO - 'tart', 09:01:55 INFO - 'tscrollx', 09:01:55 INFO - 'cart']}, 09:01:55 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:01:55 INFO - 'talos_options': ['--disable-e10s'], 09:01:55 INFO - 'tests': ['tp5o']}, 09:01:55 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:01:55 INFO - 'tests': ['tp5o']}, 09:01:55 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:01:55 INFO - 'talos_options': ['--disable-e10s', 09:01:55 INFO - '--xperf_path', 09:01:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:01:55 INFO - 'tests': ['tp5n']}, 09:01:55 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:01:55 INFO - 'talos_options': ['--xperf_path', 09:01:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:01:55 INFO - 'tests': ['tp5n']}}, 09:01:55 INFO - 'talos.zip': {'path': '', 09:01:55 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:01:55 INFO - Running post-action listener: _resource_record_post_action 09:01:55 INFO - ##### 09:01:55 INFO - ##### Running create-virtualenv step. 09:01:55 INFO - ##### 09:01:55 INFO - Running pre-action listener: _resource_record_pre_action 09:01:55 INFO - Running main action method: create_virtualenv 09:01:55 INFO - Creating virtualenv /builds/slave/test/build/venv 09:01:55 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:01:55 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:01:55 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:01:55 INFO - Using real prefix '/tools/python27' 09:01:55 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:01:56 INFO - Installing distribute.............................................................................................................................................................................................done. 09:01:59 INFO - Installing pip.................done. 09:01:59 INFO - Return code: 0 09:01:59 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:01:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:01:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:01:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:59 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 0x10e73da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e74d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcfba4e2970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5dce00>, '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 0x10e405db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e5d7b20>, '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.EPC9MeT5oa/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.cFkqwX8c79/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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:01:59 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:01:59 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:01:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EPC9MeT5oa/Render', 09:01:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:01:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:01:59 INFO - 'HOME': '/Users/cltbld', 09:01:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:01:59 INFO - 'LOGNAME': 'cltbld', 09:01:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:59 INFO - 'MOZ_NO_REMOTE': '1', 09:01:59 INFO - 'NO_EM_RESTART': '1', 09:01:59 INFO - 'PAGER': '/bin/cat', 09:01:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:01:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:59 INFO - 'PWD': '/builds/slave/test', 09:01:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:01:59 INFO - 'SHELL': '/bin/bash', 09:01:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cFkqwX8c79/Listeners', 09:01:59 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 09:01:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:01:59 INFO - 'USER': 'cltbld', 09:01:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:01:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:01:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:59 INFO - 'XPC_FLAGS': '0x0', 09:01:59 INFO - 'XPC_SERVICE_NAME': '0', 09:01:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:01:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:59 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:01:59 INFO - Cleaning up... 09:01:59 INFO - Return code: 0 09:01:59 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 09:01:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:01:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:01:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e73da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e74d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcfba4e2970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5dce00>, '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 0x10e405db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e5d7b20>, '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.EPC9MeT5oa/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.cFkqwX8c79/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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:01:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 09:01:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 09:01:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EPC9MeT5oa/Render', 09:01:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:01:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:01:59 INFO - 'HOME': '/Users/cltbld', 09:01:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:01:59 INFO - 'LOGNAME': 'cltbld', 09:01:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:59 INFO - 'MOZ_NO_REMOTE': '1', 09:01:59 INFO - 'NO_EM_RESTART': '1', 09:01:59 INFO - 'PAGER': '/bin/cat', 09:01:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:01:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:59 INFO - 'PWD': '/builds/slave/test', 09:01:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:01:59 INFO - 'SHELL': '/bin/bash', 09:01:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cFkqwX8c79/Listeners', 09:01:59 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 09:01:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:01:59 INFO - 'USER': 'cltbld', 09:01:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:01:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:01:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:59 INFO - 'XPC_FLAGS': '0x0', 09:01:59 INFO - 'XPC_SERVICE_NAME': '0', 09:01:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:01:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:59 INFO - Downloading/unpacking psutil>=0.7.1 09:01:59 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:01:59 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:01:59 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:01:59 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:01:59 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:01:59 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:02:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:02:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:02:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:02:02 INFO - Installing collected packages: psutil 09:02:02 INFO - Running setup.py install for psutil 09:02:02 INFO - building 'psutil._psutil_osx' extension 09:02:02 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:02:03 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:02:03 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:02:03 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:02:03 INFO - building 'psutil._psutil_posix' extension 09:02:03 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:02:03 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:02:03 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:02:03 INFO - ^ 09:02:03 INFO - 1 warning generated. 09:02:03 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:02:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:02:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:02:03 INFO - Successfully installed psutil 09:02:03 INFO - Cleaning up... 09:02:03 INFO - Return code: 0 09:02:03 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 09:02:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:02:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:02:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e73da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e74d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcfba4e2970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5dce00>, '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 0x10e405db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e5d7b20>, '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.EPC9MeT5oa/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.cFkqwX8c79/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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:02:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 09:02:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 09:02:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EPC9MeT5oa/Render', 09:02:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:02:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:02:03 INFO - 'HOME': '/Users/cltbld', 09:02:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:02:03 INFO - 'LOGNAME': 'cltbld', 09:02:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:03 INFO - 'MOZ_NO_REMOTE': '1', 09:02:03 INFO - 'NO_EM_RESTART': '1', 09:02:03 INFO - 'PAGER': '/bin/cat', 09:02:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:02:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:03 INFO - 'PWD': '/builds/slave/test', 09:02:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:02:03 INFO - 'SHELL': '/bin/bash', 09:02:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cFkqwX8c79/Listeners', 09:02:03 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 09:02:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:02:03 INFO - 'USER': 'cltbld', 09:02:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:02:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:02:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:03 INFO - 'XPC_FLAGS': '0x0', 09:02:03 INFO - 'XPC_SERVICE_NAME': '0', 09:02:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:02:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:03 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:02:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:06 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:02:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:02:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 09:02:06 INFO - Installing collected packages: mozsystemmonitor 09:02:06 INFO - Running setup.py install for mozsystemmonitor 09:02:06 INFO - Successfully installed mozsystemmonitor 09:02:06 INFO - Cleaning up... 09:02:06 INFO - Return code: 0 09:02:06 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:02:06 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:02:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:06 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:02:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:06 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 0x10e73da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e74d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcfba4e2970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5dce00>, '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 0x10e405db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e5d7b20>, '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.EPC9MeT5oa/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.cFkqwX8c79/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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:02:06 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:02:06 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:02:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EPC9MeT5oa/Render', 09:02:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:02:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:02:06 INFO - 'HOME': '/Users/cltbld', 09:02:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:02:06 INFO - 'LOGNAME': 'cltbld', 09:02:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:06 INFO - 'MOZ_NO_REMOTE': '1', 09:02:06 INFO - 'NO_EM_RESTART': '1', 09:02:06 INFO - 'PAGER': '/bin/cat', 09:02:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:02:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:06 INFO - 'PWD': '/builds/slave/test', 09:02:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:02:06 INFO - 'SHELL': '/bin/bash', 09:02:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cFkqwX8c79/Listeners', 09:02:06 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 09:02:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:02:06 INFO - 'USER': 'cltbld', 09:02:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:02:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:02:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:06 INFO - 'XPC_FLAGS': '0x0', 09:02:06 INFO - 'XPC_SERVICE_NAME': '0', 09:02:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:02:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:06 INFO - Downloading/unpacking blobuploader==1.2.4 09:02:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:09 INFO - Downloading blobuploader-1.2.4.tar.gz 09:02:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:02:09 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:02:09 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:02:09 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:02:09 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:02:09 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:02:09 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:02:09 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:02:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:02:10 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:02:10 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:02:10 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:02:10 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:02:10 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:02:10 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:02:10 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:02:10 INFO - Downloading docopt-0.6.1.tar.gz 09:02:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:02:10 INFO - Installing collected packages: blobuploader, requests, docopt 09:02:10 INFO - Running setup.py install for blobuploader 09:02:10 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:02:10 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:02:10 INFO - Running setup.py install for requests 09:02:11 INFO - Running setup.py install for docopt 09:02:11 INFO - Successfully installed blobuploader requests docopt 09:02:11 INFO - Cleaning up... 09:02:11 INFO - Return code: 0 09:02:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:02: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:02:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02: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:02:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:11 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 0x10e73da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e74d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcfba4e2970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5dce00>, '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 0x10e405db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e5d7b20>, '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.EPC9MeT5oa/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.cFkqwX8c79/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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:02:11 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:02:11 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:02:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EPC9MeT5oa/Render', 09:02:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:02:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:02:11 INFO - 'HOME': '/Users/cltbld', 09:02:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:02:11 INFO - 'LOGNAME': 'cltbld', 09:02:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:11 INFO - 'MOZ_NO_REMOTE': '1', 09:02:11 INFO - 'NO_EM_RESTART': '1', 09:02:11 INFO - 'PAGER': '/bin/cat', 09:02:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:02:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:11 INFO - 'PWD': '/builds/slave/test', 09:02:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:02:11 INFO - 'SHELL': '/bin/bash', 09:02:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cFkqwX8c79/Listeners', 09:02:11 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 09:02:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:02:11 INFO - 'USER': 'cltbld', 09:02:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:02:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:02:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:11 INFO - 'XPC_FLAGS': '0x0', 09:02:11 INFO - 'XPC_SERVICE_NAME': '0', 09:02:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:02:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:02:11 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-nzCNoU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:02:11 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-W49RyV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:02:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:02:11 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-8UlOlh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:02:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:02:12 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-08O2LX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:02:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:02:12 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-gONjOn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:02:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:02:12 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-8LE9fX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:02:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:02:12 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-whLi6V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:02:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:02:12 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-HZ2lg8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:02:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:02:12 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-7UZL4L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:02:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:02:12 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-oZpILN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:02:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:02:12 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-WEamUo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:02:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:02:12 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-THPkJM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:02:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:02:13 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-9Xv_iq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:02:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:02:13 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-tn5Dfh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:02:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:02:13 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-K6JN0A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:02:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:02:13 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-bfcTPV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:02:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:02:13 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-AX5T30-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:02:13 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:02:13 INFO - Running setup.py install for manifestparser 09:02:13 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:02:13 INFO - Running setup.py install for mozcrash 09:02:13 INFO - Running setup.py install for mozdebug 09:02:13 INFO - Running setup.py install for mozdevice 09:02:14 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:02:14 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:02:14 INFO - Running setup.py install for mozfile 09:02:14 INFO - Running setup.py install for mozhttpd 09:02:14 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:02:14 INFO - Running setup.py install for mozinfo 09:02:14 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:02:14 INFO - Running setup.py install for mozInstall 09:02:14 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:02:14 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:02:14 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:02:14 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:02:14 INFO - Running setup.py install for mozleak 09:02:14 INFO - Running setup.py install for mozlog 09:02:15 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:02:15 INFO - Running setup.py install for moznetwork 09:02:15 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:02:15 INFO - Running setup.py install for mozprocess 09:02:15 INFO - Running setup.py install for mozprofile 09:02:15 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:02:15 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:02:15 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:02:15 INFO - Running setup.py install for mozrunner 09:02:15 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:02:15 INFO - Running setup.py install for mozscreenshot 09:02:15 INFO - Running setup.py install for moztest 09:02:15 INFO - Running setup.py install for mozversion 09:02:16 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:02:16 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:02:16 INFO - Cleaning up... 09:02:16 INFO - Return code: 0 09:02:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:02:16 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:02:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:16 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:02:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:16 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 0x10e73da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e74d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcfba4e2970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5dce00>, '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 0x10e405db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e5d7b20>, '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.EPC9MeT5oa/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.cFkqwX8c79/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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:02:16 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:02:16 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:02:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EPC9MeT5oa/Render', 09:02:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:02:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:02:16 INFO - 'HOME': '/Users/cltbld', 09:02:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:02:16 INFO - 'LOGNAME': 'cltbld', 09:02:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:16 INFO - 'MOZ_NO_REMOTE': '1', 09:02:16 INFO - 'NO_EM_RESTART': '1', 09:02:16 INFO - 'PAGER': '/bin/cat', 09:02:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:02:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:16 INFO - 'PWD': '/builds/slave/test', 09:02:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:02:16 INFO - 'SHELL': '/bin/bash', 09:02:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cFkqwX8c79/Listeners', 09:02:16 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 09:02:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:02:16 INFO - 'USER': 'cltbld', 09:02:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:02:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:02:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:16 INFO - 'XPC_FLAGS': '0x0', 09:02:16 INFO - 'XPC_SERVICE_NAME': '0', 09:02:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:02:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:02:16 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-n9coeH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:02:16 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:02:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:02:16 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-xHpViy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:02:16 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:02:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:02:16 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-IU0Qv0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:02:16 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:02:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:02:16 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-mlS3wl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:02:16 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:02:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:02:16 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-i4YQlV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:02:16 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:02:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:02:16 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-guY7cS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:02:17 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:02:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:02:17 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-b11sQj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:02:17 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:02:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:02:17 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-Njv5Jb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:02:17 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:02:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:02:17 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-aXSc_v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:02:17 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:02:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:02:17 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-PePFVr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:02:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:02:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:02:17 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-JslIEn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:02:17 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:02:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:02:17 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-80Rbm8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:02:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:02:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:02:17 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-2Ewnbo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:02:17 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:02:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:02:17 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-bA4tDp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:02:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:02:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:02:17 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-2eEn1f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:02:18 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:02:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:02:18 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-OWcls7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:02:18 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:02:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:02:18 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-7H8kpJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:02:18 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:02:18 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:02:18 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:02:18 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:02:18 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:02:18 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:02:18 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:02: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:02: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:02: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:02: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:02: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:02: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:02:21 INFO - Downloading blessings-1.6.tar.gz 09:02:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:02:21 INFO - Installing collected packages: blessings 09:02:21 INFO - Running setup.py install for blessings 09:02:21 INFO - Successfully installed blessings 09:02:21 INFO - Cleaning up... 09:02:21 INFO - Return code: 0 09:02:21 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:02:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:02:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:02:21 INFO - Reading from file tmpfile_stdout 09:02:21 INFO - Current package versions: 09:02:21 INFO - blessings == 1.6 09:02:21 INFO - blobuploader == 1.2.4 09:02:21 INFO - docopt == 0.6.1 09:02:21 INFO - manifestparser == 1.1 09:02:21 INFO - mozInstall == 1.12 09:02:21 INFO - mozcrash == 0.17 09:02:21 INFO - mozdebug == 0.1 09:02:21 INFO - mozdevice == 0.48 09:02:21 INFO - mozfile == 1.2 09:02:21 INFO - mozhttpd == 0.7 09:02:21 INFO - mozinfo == 0.9 09:02:21 INFO - mozleak == 0.1 09:02:21 INFO - mozlog == 3.1 09:02:21 INFO - moznetwork == 0.27 09:02:21 INFO - mozprocess == 0.22 09:02:21 INFO - mozprofile == 0.28 09:02:21 INFO - mozrunner == 6.11 09:02:21 INFO - mozscreenshot == 0.1 09:02:21 INFO - mozsystemmonitor == 0.0 09:02:21 INFO - moztest == 0.7 09:02:21 INFO - mozversion == 1.4 09:02:21 INFO - psutil == 3.1.1 09:02:21 INFO - requests == 1.2.3 09:02:21 INFO - wsgiref == 0.1.2 09:02:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:02: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:02:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02: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:02:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:21 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 0x10e73da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e74d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcfba4e2970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5dce00>, '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 0x10e405db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e5d7b20>, '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.EPC9MeT5oa/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.cFkqwX8c79/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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:02:21 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:02:21 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:02:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EPC9MeT5oa/Render', 09:02:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:02:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:02:21 INFO - 'HOME': '/Users/cltbld', 09:02:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:02:21 INFO - 'LOGNAME': 'cltbld', 09:02:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:21 INFO - 'MOZ_NO_REMOTE': '1', 09:02:21 INFO - 'NO_EM_RESTART': '1', 09:02:21 INFO - 'PAGER': '/bin/cat', 09:02:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:02:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:21 INFO - 'PWD': '/builds/slave/test', 09:02:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:02:21 INFO - 'SHELL': '/bin/bash', 09:02:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cFkqwX8c79/Listeners', 09:02:21 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 09:02:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:02:21 INFO - 'USER': 'cltbld', 09:02:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:02:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:02:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:21 INFO - 'XPC_FLAGS': '0x0', 09:02:21 INFO - 'XPC_SERVICE_NAME': '0', 09:02:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:02:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:22 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:02:22 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:02:22 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:02:22 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:02:22 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:02:22 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:02:22 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:02:22 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:02:22 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:02:22 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:02:22 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:02:22 INFO - Cleaning up... 09:02:22 INFO - Return code: 0 09:02:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:02:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:02:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:02:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e73da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e74d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcfba4e2970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5dce00>, '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 0x10e405db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e5d7b20>, '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.EPC9MeT5oa/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.cFkqwX8c79/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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:02:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:02:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:02:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EPC9MeT5oa/Render', 09:02:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:02:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:02:22 INFO - 'HOME': '/Users/cltbld', 09:02:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:02:22 INFO - 'LOGNAME': 'cltbld', 09:02:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:22 INFO - 'MOZ_NO_REMOTE': '1', 09:02:22 INFO - 'NO_EM_RESTART': '1', 09:02:22 INFO - 'PAGER': '/bin/cat', 09:02:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:02:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:22 INFO - 'PWD': '/builds/slave/test', 09:02:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:02:22 INFO - 'SHELL': '/bin/bash', 09:02:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cFkqwX8c79/Listeners', 09:02:22 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 09:02:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:02:22 INFO - 'USER': 'cltbld', 09:02:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:02:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:02:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:22 INFO - 'XPC_FLAGS': '0x0', 09:02:22 INFO - 'XPC_SERVICE_NAME': '0', 09:02:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:02:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:22 INFO - Downloading/unpacking jsonschema 09:02: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:02: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:02: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:02: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:02: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:02: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:02:24 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:02:24 INFO - Downloading/unpacking functools32 (from jsonschema) 09:02: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:02: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:02: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:02: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:02: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:02: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:02:25 INFO - Downloading functools32-3.2.3-2.tar.gz 09:02:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:02:25 INFO - warning: no files found matching '*.txt' 09:02:25 INFO - no previously-included directories found matching 'build' 09:02:25 INFO - no previously-included directories found matching 'dist' 09:02:25 INFO - no previously-included directories found matching '.git*' 09:02:25 INFO - Installing collected packages: jsonschema, functools32 09:02:25 INFO - Running setup.py install for functools32 09:02:25 INFO - warning: no files found matching '*.txt' 09:02:25 INFO - no previously-included directories found matching 'build' 09:02:25 INFO - no previously-included directories found matching 'dist' 09:02:25 INFO - no previously-included directories found matching '.git*' 09:02:25 INFO - Successfully installed jsonschema functools32 09:02:25 INFO - Cleaning up... 09:02:25 INFO - Return code: 0 09:02:25 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 09:02:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:02:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:02:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e73da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e74d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcfba4e2970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5dce00>, '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 0x10e405db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e5d7b20>, '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.EPC9MeT5oa/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.cFkqwX8c79/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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:02:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 09:02:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 09:02:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EPC9MeT5oa/Render', 09:02:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:02:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:02:25 INFO - 'HOME': '/Users/cltbld', 09:02:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:02:25 INFO - 'LOGNAME': 'cltbld', 09:02:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:25 INFO - 'MOZ_NO_REMOTE': '1', 09:02:25 INFO - 'NO_EM_RESTART': '1', 09:02:25 INFO - 'PAGER': '/bin/cat', 09:02:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:02:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:25 INFO - 'PWD': '/builds/slave/test', 09:02:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:02:25 INFO - 'SHELL': '/bin/bash', 09:02:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cFkqwX8c79/Listeners', 09:02:25 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 09:02:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:02:25 INFO - 'USER': 'cltbld', 09:02:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:02:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:02:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:25 INFO - 'XPC_FLAGS': '0x0', 09:02:25 INFO - 'XPC_SERVICE_NAME': '0', 09:02:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:02:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:25 INFO - Downloading/unpacking flake8 09:02: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:02: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:02: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:02: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:02: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:02: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:02:28 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:02:28 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:02:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:28 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:02:28 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:02:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:28 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:02:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 09:02:29 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:02:29 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:02:29 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:02:29 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:02:29 INFO - no previously-included directories found matching 'docs/_build' 09:02:29 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:02:29 INFO - Running setup.py install for pep8 09:02:29 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:02:29 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:02:29 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:02:29 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:02:29 INFO - no previously-included directories found matching 'docs/_build' 09:02:29 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 09:02:29 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:02:29 INFO - Cleaning up... 09:02:29 INFO - Return code: 0 09:02:29 INFO - Running post-action listener: _resource_record_post_action 09:02:29 INFO - Running post-action listener: _start_resource_monitoring 09:02:29 INFO - Starting resource monitoring. 09:02:29 INFO - ##### 09:02:29 INFO - ##### Running install step. 09:02:29 INFO - ##### 09:02:29 INFO - Running pre-action listener: _resource_record_pre_action 09:02:29 INFO - Running main action method: install 09:02:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:02:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:02:30 INFO - Reading from file tmpfile_stdout 09:02:30 INFO - Detecting whether we're running mozinstall >=1.0... 09:02:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:02:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:02:30 INFO - Reading from file tmpfile_stdout 09:02:30 INFO - Output received: 09:02:30 INFO - Usage: mozinstall [options] installer 09:02:30 INFO - Options: 09:02:30 INFO - -h, --help show this help message and exit 09:02:30 INFO - -d DEST, --destination=DEST 09:02:30 INFO - Directory to install application into. [default: 09:02:30 INFO - "/builds/slave/test"] 09:02:30 INFO - --app=APP Application being installed. [default: firefox] 09:02:30 INFO - mkdir: /builds/slave/test/build/application 09:02:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:02:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 09:03:03 INFO - Reading from file tmpfile_stdout 09:03:03 INFO - Output received: 09:03:03 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:03:03 INFO - Running post-action listener: _resource_record_post_action 09:03:03 INFO - ##### 09:03:03 INFO - ##### Running run-tests step. 09:03:03 INFO - ##### 09:03:03 INFO - Running pre-action listener: _resource_record_pre_action 09:03:03 INFO - Running main action method: run_tests 09:03:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:03:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:03:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:03:03 INFO - 2016-04-27 09:03:03.915 screenresolution[7970:157252] starting screenresolution argv=screenresolution get 09:03:03 INFO - 2016-04-27 09:03:03.917 screenresolution[7970:157252] Display 0: 1600x1200x32@60 09:03:03 INFO - 2016-04-27 09:03:03.922 screenresolution[7971:157254] starting screenresolution argv=screenresolution list 09:03:03 INFO - Available Modes on Display 0 09:03:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:03:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:03:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:03:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:03:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:03:04 INFO - Intel Iris: 09:03:04 INFO - Chipset Model: Intel Iris 09:03:04 INFO - Type: GPU 09:03:04 INFO - Bus: Built-In 09:03:04 INFO - VRAM (Dynamic, Max): 1536 MB 09:03:04 INFO - Vendor: Intel (0x8086) 09:03:04 INFO - Device ID: 0x0a2e 09:03:04 INFO - Revision ID: 0x0009 09:03:04 INFO - Displays: 09:03:04 INFO - Display: 09:03:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:03:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:03:04 INFO - Display Serial Number: 1600x1200 60 09:03:04 INFO - Main Display: Yes 09:03:04 INFO - Mirror: Off 09:03:04 INFO - Online: Yes 09:03:04 INFO - Rotation: Supported 09:03:04 INFO - Return code: 0 09:03:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:03:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:03:04 INFO - Python 2.7.3 09:03:04 INFO - Return code: 0 09:03:04 INFO - grabbing minidump binary from tooltool 09:03:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:03:04 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5dce00>, '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 0x10e405db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e5d7b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:03:04 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:03:04 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:03:04 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:03:04 INFO - Return code: 0 09:03:04 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:03:04 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:03:04 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:03:04 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:03:04 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:03:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 09:03:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 09:03:05 INFO - Return code: 0 09:03:05 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/Yfu3EQrzTAuR7TR_vmxUKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0036', '--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:03:05 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/Yfu3EQrzTAuR7TR_vmxUKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0036 --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:03:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EPC9MeT5oa/Render', 09:03:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:03:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:03:05 INFO - 'HOME': '/Users/cltbld', 09:03:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:03:05 INFO - 'LOGNAME': 'cltbld', 09:03:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:03:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:03:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:03:05 INFO - 'MOZ_NO_REMOTE': '1', 09:03:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:03:05 INFO - 'NO_EM_RESTART': '1', 09:03:05 INFO - 'PAGER': '/bin/cat', 09:03:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:03:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:03:05 INFO - 'PWD': '/builds/slave/test', 09:03:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:03:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:03:05 INFO - 'SHELL': '/bin/bash', 09:03:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cFkqwX8c79/Listeners', 09:03:05 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 09:03:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:03:05 INFO - 'USER': 'cltbld', 09:03:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:03:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:03:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:03:05 INFO - 'XPC_FLAGS': '0x0', 09:03:05 INFO - 'XPC_SERVICE_NAME': '0', 09:03:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:03:05 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/Yfu3EQrzTAuR7TR_vmxUKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0036', '--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:03:05 INFO - mozversion application_buildid: 20160427071020 09:03:05 INFO - mozversion application_changeset: ed934827bebaeee8a1aeb1726a3af1021d2a8364 09:03:05 INFO - mozversion application_display_name: Nightly 09:03:05 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:03:05 INFO - mozversion application_name: Firefox 09:03:05 INFO - mozversion application_remotingname: firefox 09:03:05 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 09:03:05 INFO - mozversion application_vendor: Mozilla 09:03:05 INFO - mozversion application_version: 49.0a1 09:03:05 INFO - mozversion platform_buildid: 20160427071020 09:03:05 INFO - mozversion platform_changeset: ed934827bebaeee8a1aeb1726a3af1021d2a8364 09:03:05 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 09:03:05 INFO - mozversion platform_version: 49.0a1 09:03:05 INFO - using testdate: 1461772985 09:03:05 INFO - actual date: 1461772985 09:03:05 INFO - starting webserver on 'localhost:65210' 09:03:05 INFO - SUITE-START | Running 2 tests 09:03:05 INFO - TEST-START | dromaeo_css 09:03:05 INFO - operating with platform_type : mac_ 09:03:05 INFO - Initialising browser for dromaeo_css test... 09:03:05 INFO - TEST-INFO | started process 7989 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/tmpOj58to/profile http://localhost:65210/getInfo.html) 09:03:07 INFO - PROCESS | 7989 | __metrics Screen width/height:1600/1200 09:03:07 INFO - PROCESS | 7989 | colorDepth:24 09:03:07 INFO - PROCESS | 7989 | Browser inner width/height: 1024/689 09:03:07 INFO - PROCESS | 7989 | __metrics 09:03:07 INFO - PROCESS | 7989 | 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:03:07 ERROR - PROCESS | 7989 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:03:08 INFO - TEST-INFO | 7989: exit 0 09:03:08 INFO - Browser initialized. 09:03:08 INFO - Running cycle 1/1 for dromaeo_css test... 09:03:08 INFO - TEST-INFO | started process 7991 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/tmpOj58to/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:03:20 INFO - PROCESS | 7991 | RSS: Main: 239235072 09:03:20 INFO - PROCESS | 7991 | 09:04:11 INFO - PROCESS | 7991 | 1461773051904 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:06:21 INFO - PROCESS | 7991 | Cycle 1(1): loaded http://localhost:65210/tests/dromaeo/cssquery-dojo.html (next: http://localhost:65210/tests/dromaeo/cssquery-ext.html) 09:06:23 INFO - PROCESS | 7991 | RSS: Main: 385781760 09:06:23 INFO - PROCESS | 7991 | 09:09:26 INFO - PROCESS | 7991 | Cycle 1(1): loaded http://localhost:65210/tests/dromaeo/cssquery-ext.html (next: http://localhost:65210/tests/dromaeo/cssquery-jquery.html) 09:09:27 INFO - PROCESS | 7991 | RSS: Main: 376586240 09:09:27 INFO - PROCESS | 7991 | 09:12:29 INFO - PROCESS | 7991 | Cycle 1(1): loaded http://localhost:65210/tests/dromaeo/cssquery-jquery.html (next: http://localhost:65210/tests/dromaeo/cssquery-mootools.html) 09:12:29 INFO - PROCESS | 7991 | RSS: Main: 379277312 09:12:29 INFO - PROCESS | 7991 | 09:15:32 INFO - PROCESS | 7991 | Cycle 1(1): loaded http://localhost:65210/tests/dromaeo/cssquery-mootools.html (next: http://localhost:65210/tests/dromaeo/cssquery-prototype.html) 09:15:32 INFO - PROCESS | 7991 | RSS: Main: 377081856 09:15:32 INFO - PROCESS | 7991 | 09:18:36 INFO - PROCESS | 7991 | Cycle 1(1): loaded http://localhost:65210/tests/dromaeo/cssquery-prototype.html (next: http://localhost:65210/tests/dromaeo/cssquery-yui.html) 09:18:36 INFO - PROCESS | 7991 | RSS: Main: 347705344 09:18:36 INFO - PROCESS | 7991 | 09:21:39 INFO - PROCESS | 7991 | Cycle 1(1): loaded http://localhost:65210/tests/dromaeo/cssquery-yui.html (next: http://localhost:65210/tests/dromaeo/cssquery-dojo.html) 09:21:40 INFO - PROCESS | 7991 | RSS: Main: 382132224 09:21:40 INFO - PROCESS | 7991 | 09:21:40 INFO - PROCESS | 7991 | __start_tp_report 09:21:40 INFO - PROCESS | 7991 | _x_x_mozilla_page_load 09:21:40 INFO - PROCESS | 7991 | _x_x_mozilla_page_load_details 09:21:40 INFO - PROCESS | 7991 | |i|pagename|runs| 09:21:40 INFO - PROCESS | 7991 | |0;dojo.html;3312.48656458249;3360.4791257355105;3378.7128094112;3383.188034871631;3415.5389022481972;5520.530754885839;5578.804741834037;5591.916121258179;5592.804251851186;5616.634918730285;5559.388467268597;5596.552275817935;5663.490285874271;5685.8578535536635;5698.230738850254;5028.446870844205;5032.949670503293;5073.619478539106;5084.847454576368;5168.250603662466;5151.124308867483;5193.1171700810955;5195.974020129894;5197.48025197481;5254.238135470355;5059.671121377099;5074.771635276385;5154.587632989371;5155.871103222412;5164.328637277167;5093;5096.668716533413;5136.666116702402;5165.431802501722;5177;5055.823046193365;5090.9745451272875;5120.513551212629;5152.227165925104;5174.094533456668;5456.781728730846;5539.723013849291;5551.750171242304;5557.722113894289;5564.387917329091;6028.6985650717725;6073.167686352853;6086.2087928569445;6112.327643959161;6133.693315334215;6145.907811382833;6162.137300777895;6228.906566401508;6231.813045608594;6241.157443745127;5648.350439699487;5712.485876271156;5714.685692286923;5717.656940583537;5718.51392631631;6225.844353891099;6261.592996455216;6313.652749098844;6318.210223722035;6319.905201421982;6059.909101363483;6070.150178974947;6070.3019152798;6080.574359794772;6088.573799833969;6107.877842443127;6129.417705317987;6198.659073750897;6201.751929922752;6213.471854892385;6107.969460152671;6112.777056696867;6115.6330620162935;6154.323024467305;6179.258488981351;6101.803350793711;6186.133941248228;6186.845328866814;6187.133801267826;6202.472789812826;4726.645501587394;4864;4877.853664390003;4882;4917.557419832232;4348.478182618106;4360.563943605677;4398.912021759547;4423.07115505741;4469.575390337913;5591.608587398843;5618.016847051749;5621.297337832771;5626.352969408488;5631.436856314418;6021.096835474714;6221.47117495018;6236.03341482071;6243.250809902841;6252.562320637512;4663.673542851968;5556.555475561945;5563.638363506359;5599.160125981135;5600.803971860965;5298.788048478018;5330.813421530228;5336.573074154058;5340.385855626575;5342.145256758978;6271.247450305992;6281.371862813682;6297.149884765589;6299.181106456132;6303.212098487689;6188.597741146811;6224.253089629274;6243.937560624336;6245.18812554365;6255.999040153553;6212.81361559154;6216.409441103088;6279.963805971964;6280.654563998841;6282.4974002080635;6117.066276512034;6135.569531795875;6137.836677219377;6138.296437970319;6143.72682107623;6148.292946311231;6191.19514463117;6198.163247961467;6215.129881816459;6217.875642487215;5873.647581145145;5964.4035596441745;5997.970010149921;6001.639901605918;6032.668203248863;5984.461398474158;6022.307434645072;6036.547258955684;6042.728077236447;6054.212952316347;5726.370099288992;5823.330317013598;5828.329742079715;5839.912401314069;5850.912236316373;5861.882762344644;6026.819195424145;6033.607815491979;6053.848653783691;6106.786262480792;5176.922346164735;5800.6809625471005;5848;5891.793787217427;5896.9115463268945 09:21:40 INFO - PROCESS | 7991 | |1;ext.html;489.42003725585187;510.7752588860901;521.6087934049463;557.5678848892109;560.7159604505682;28568.857155714217;36788.632113678854;37511.81244093779;37716.43425348622;37735.86792396226;39235.01912452183;39792;39905.20189596206;39911.800440997824;40027.999300017516;8452.239298463137;9254.49100299485;9485.573149208301;9488.525573721286;9521.476318802463;8491.490510569354;11083.445827708581;11319.094472442226;11357.772844543146;11362.545498180105;11257.268277561972;11373.772524549546;11387.943060284688;11399.829002564968;11401.714957126096;8353.958230208871;11109.388983605899;11186.161037922147;11346.262492937933;11362.886371136348;11083.115870301988;11240.887591124067;11244.606438774685;11256.493457794419;11263.21157518974;36636.084097897496;37128.44307335392;37144.25711485782;37184;37199.62800371989;3205.5031470122167;3214.373197226542;3299.8185099819502;3448.8448019839166;3584.731145164123;27485.725142748717;27734.167974960783;27776;28116.71883281182;28323.433531329465;2688.126358933335;2689.6772387313454;2694.528457519926;2712.6066720325443;2833.648458862482;355424;356961;358004.4199557997;358095;358397;57587.71206144026;57934;57946.42053579495;58070.41929580735;58096;59020.70489647524;60196.398036019076;60356.69821650864;60381;60525.78948421095;16141.03153810775;16253.756193657104;16363;16378.918105409397;16381.344746210256;10907.781844363068;11330.660080197607;11518.308901465938;12635.305058221886;12666.556670516487;18457.353992610195;18458.63082738338;18475.445736627924;18484.39554286901;18516.722249166272;7452.925470745223;7679.155292917774;7707.691692332244;7752.728654497066;7801.765947021599;933.9392939458913;936.4334577580602;945.7493764152505;1057.476549108181;1106.6956586938657;23315.95078221484;27604.44791104207;27782.722172778416;28128;28374.00690975806;1040.869891263592;1107.44074242507;1111.6942840718705;1124.3141683573015;1184.5972369394447;14269;14350.206224221973;14636.268186590627;14735.92632036833;14840.628984275263;477416;477791;484215;484774;485301;56356.43643563594;57153.714231428574;57211.427885721445;57376.71311643415;57665;1341.1952828303135;1403.7753959366657;1423.3096947980428;1453.5857280674957;1463.4219483304034;26900.730992689823;27137.72862271352;27177.72822271831;27620.723792761815;27770.5834412488;47834;48629.5137048625;48819.75590122169;48823.51176488332;48842.5115748838;297763;297961;298177;298692;300716;25455.745442545336;37279.44080838844;38693.64572239958;38850.22299553936;38865.6113438862;40644.79677601593;41224.969375765846;41430;41694;42860.78569607132;34910.65089349176;34960.475592865616;34966.30067398587;35487.82256088703;36146.81926590455;34451.96644100681;34475.4828677565;35001.47497787586;35343.76296829599;35382.8230858844;35246.471302930986;35576.46635300421;35606.465903010954;36189.457158143174;36380.726674566264;30472.390552189274;30603.693963060083;30607.54088688627;30622.84688576543;31042;19450.513737156685;22597.887010564842;22982;23544.882275588512;23835.284941451784 09:21:40 INFO - PROCESS | 7991 | |2;jquery.html;390.24682363039335;397.4495323976292;399.5145897734253;404.1069236986262;412.38966329831845;18807;19207.71188432173;19835.50411239718;19885.701714474275;19946.501337466572;19342.323018694347;19513.804861951376;21232.150713971423;21247.68128478074;21326.786732132714;5851.15158302046;5975.970120149394;6130.172426722403;6137.9386206137815;6145.354737752538;4570.771461426932;5966.79116230932;6049.274087109531;6063;6066.211392518989;5882.441168089046;6041.788537401192;6062.484688801435;6066;6076.726547305382;5956.9873121569435;6008.75964961401;6026.608270462406;6035.909461358083;6048.45563899249;5218.582513398919;5249.921251181236;5286.365636123652;5290.470952904679;5299.735013249322;24878.751212487823;24939.127130550525;25106.99572017117;25621;25678.871605641853;2167.5881582499433;2183.235867446397;2277.81777457803;2391.689080419552;2634.893344795191;14077.507287244896;14232.715345693028;14250.358733857001;14326.283685815772;14977.925110374379;1651.8182999870005;1698.7451882217642;1715.6482921001166;1798.3885478937225;1801.0634470075488;85133.72299415513;103997.48001259945;106740.4662976688;107719.46140269327;109611;10612.310199836986;10904.890951090547;11121.388323642115;11158.051565617014;11240.21318507713;14112.153270803785;14148.575542733735;14165.85834141666;14200.431982720782;14208.715825683428;8429.957850210709;8631.6978905738;8678.78303042429;8708.956455217683;8721.60752766127;8561.400701950928;8568.400211985227;8569.700060497853;8721.171488708562;8723.956380218186;6605.735770569219;6726.630035348005;6726.96636516824;6738.764143254963;6756.729730810713;1833.7799464064399;1863.2174486715612;1871.2679487947478;1879.2107314927764;1907.7710674719121;920.3833431600843;962.2879069488667;991.2813210422386;1019.2864994503728;1044.4151275285865;20146.69779953302;20223.4944126398;20236.797632023787;20558.38324850257;20560.074796634188;949.8100379924043;963.2919803944095;999.6201443451442;1007.6775431861734;1050.9001644843727;11095.167862410352;11330;11359.886401135884;11363.659090227306;11398.65804025864;531694;604434.9778251081;609658;625855;630751.8462407751;83898.58050709707;98199;102392.48803755933;104312;105084;1002.2082554781787;1005.8591797148405;1034.1468288661883;1051.3271506236015;1054.3568423261956;17061.744073839152;17585.824141758418;17672.381466648607;17724.847884887957;18071.548211294823;46139.76930115328;46732.766336168104;47766;49396.259056114905;49578.75210623924;62392.68803655953;62631.37368626438;63084;63447.365526345995;66014;5380.811671591476;5890.970545147246;7142.428605711634;7395.482316237812;8194.34445244367;10248.64129755455;11913.642590722293;12056.276623402624;12065.215760975509;12205.084618653815;10167.7458063549;10186.694399168036;10418.739531511774;10540.472976350999;10575.947120264349;9428.35003874333;9700.272479564203;9911.157551608194;9980.800383992135;10430.843537347098;10522.316809213602;10548;10724.302920310154;10730.62442814529;10749.838752418564;7716.305532502101;7905.4070944680525;8121.837563248584;11184.273022253528;11240.369991772355;10567.577296908345;10629.081334405917;10702;10762.246642735246;11136.665900023012 09:21:40 INFO - PROCESS | 7991 | |3;mootools.html;1398.3008495752124;1623.853853153216;1624.0336999485307;1898.079431475734;2019.939401817946;7820.92179078209;8531.146885311466;8726.825463490726;8728.781780455492;8729.86905196421;8306.584670766468;8552.059273479912;8560;8571.699990500334;8585.14148585141;2106.536561956367;2359.7640235976437;2404.9639255411184;2432.756724327562;2436.317831007321;2449.1672831237374;2477.318737347226;2477.6283557466436;2477.863717495537;2480.268320845346;2372.1697405907967;2409.409694624819;2413.4931664350506;2420.007796803302;2424.781769640732;1751.5796208909833;2349.565330413879;2382.4401265702545;2426.575349313863;2426.8907899144583;1969.2123150739676;2373.76262373763;2398.0287983366766;2403.9158629447884;2405.2663937499055;7106.928930710678;7178.49750517464;8503.277221436123;8517;8531.402801803877;4268.615824575803;5346.866328341784;5373.892522149535;5385.5960802939935;5388.299521062276;3358.714509266715;3414.231797845465;3581.71346292296;3584.2473080653094;3587.461880717887;1022.371241686365;1022.6983040003217;1026.009541489508;1034.120997152422;1055.018832485788;11887.702807429883;11952.40237988097;11960.222585531912;11964.82052769209;11980.041596672245;4691.502313123935;4799.616030717534;4824.541668541483;4854.68444551103;4861.6596838220985;8319;10060.0945914868;10082.546285417175;10087.29388942767;10088.69733907984;5311.229871668586;5355.946440535623;5422.6475279106735;5423.484768946943;5424.810131645373;4392.912141757147;4583.54164583539;4646.279826626878;4856.781444834991;4861.756912154378;3838.788866612363;3872.9031774205305;3993.6805055595482;4003.4795476587856;4030.9798451008146;1318.235423454394;1339.7186590815843;1340.3030424179371;1348.9595312140652;1349;771.7646117934084;885.8095509465496;891.9241864441466;900.8963969143501;920.4201353147474;5379.462053794668;5472.50747432725;5680.724723666665;5778.797742079091;5938.376470470538;909.6816114359921;928.5682157796657;979.2410881566842;1051.1433181956681;1068.6847380022912;3503.386907291214;3658.689011434058;3755.75587586806;3839.8080095995088;3851.730378873508;10318.317237518298;10650.201234907412;10666.466676666136;10687.198460115522;10711.517981690842;10541.156707463384;10701.678949631523;10762.838557421645;10770.845830833709;10784.676459706221;8060.073091594424;8157.102718700939;8227.259546640831;8230.8353832923;8233.012038555293;5176.482351764794;5350.571954243574;5428.104362780097;5462.399136094947;5583.55331573465;9288.210502107397;9871.950640246752;9883.703488895344;9947.602095916081;9980.60077596888;9248.768780780265;9892.258080644124;9920.305578609428;10069.647562335284;10157.847632285373;6922.342377474132;6948.5483443576;6991.545549539263;7007.859842803014;7037.824054398477;7997.440179187401;8059.758207253792;8144.674213031412;8145.389095817719;8149.6960486321905;7126.429885609035;7296.306850849161;7331.8533629328185;7448.292412220831;7475.663595138123;7121.074260346122;7207.747728829466;7217.170025447141;7372.889406658797;7382.778516644509;6765.627890466071;6849.280825513449;6875.690593923186;6894.310568943066;6906.827329316808;4682.555157260055;4783.091212669582;4883.8290659826735;4900.093482705711;4971.935022020907;4840.698202582587;4888.5111488850835;4896.583790377716;4940.184869496637;4967.453580106113 09:21:40 INFO - PROCESS | 7991 | |4;prototype.html;322.4985148094712;325.34767790579883;331.3770112189084;333.33998682608444;337.3303991576721;4659.301104834272;4900.411950565937;5014.648974571777;5355.866103347418;5400.594955378352;5149.76826042828;5270.209468579706;5323.630984212027;5345.705986170759;5361.544268737152;3611.3499570077374;3685.7235707321993;3954.6440820326166;3983.5618082010956;4098.610631989971;3244.5619841321472;3915.882523524299;3944.1717239379764;3953.5487997441483;4074;3330.1684073759457;3755;3836.558795738498;3886.2810380079636;4054.5742697016685;3701.407774756053;3876.7594369801673;3923.274194274054;4076.5923407658997;4127.504699436087;2922.6931172226823;3547.1486843157713;3586.946195807065;3596.5504311961004;3614.367485689994;4580.9541904580865;4587.541245875419;4749.8575042748425;5180.4042535108565;5186.429492755794;3806.7144964127524;4040.2323558523785;4323.351497275434;4560.749158796265;4665.743384113873;5541.196526503675;5548.916766248469;5557.416136401106;5615.22046586938;5629.183768353607;3827.5406951166037;3830.655241028293;3839.308924393608;3840.1743625120453;3870;5556.638818476786;5813.418658134153;5883.79406720767;5886.529077673818;5928.970355148197;3444.7254515829304;3825.7895815730403;3836.635519625631;3890.463267009515;3910.667593254548;3607.260511595117;3629.1471504196493;3785.205106927521;3928.8035598219976;3957.5053118360206;3706.3513885070006;4473.619742321939;4517.525659805673;4547.886302842456;4554.633609917011;5049.974750126299;5514.393416724157;5608.495235428831;5708.828735137952;5730.799422020293;1333.6532501549473;1422.1893520693106;1435.2034620785564;1447.117258472331;1449.8477659845564;898.9505577193318;914.3827916156567;942.528735632184;955.19763398744;1003.8494225866178;4948.826791062295;5293.285406470154;5677.489025987681;5700.857478563069;5703.828885133453;5043.470435604282;5137.972405518911;5820.330661973927;5991.430814072656;6210;4561.475430325555;4684.672072954929;4762.285657151455;4984.252362145708;5643.1535269709875;2576.600626902833;2779.9444011119663;2949.631296087989;3219.517072439153;3330.701026599628;5989.580729348991;6353;6540.149780528501;6635.5023373247195;6648.900266496006;5713.600047996601;5732.656040637575;5882.823515294548;6534.9673251634385;6565;5628.887422251614;6065.057712486919;6671.499637527205;6672.366125218096;6673.733050677918;5812.854678633068;6001.0398336267735;6131.294901086432;6306.306306306211;6316.273628532777;5908.084246941731;6170.382961703794;6188;6229.283632382334;6248.656323902229;5825.854353641193;5873;6179.81460556184;6357.459615932513;6672.432843208382;3874.941875871808;3883.223355329002;3906.5312162540677;3927.8625248116177;3946.506686664167;5794.797182098607;5800.50695690871;5848.2689663792025;5895.054631971525;5917.142014407973;4762.785674644719;5275.709835959059;5672.716364181857;5691.666416769105;5787.305523337226;5539.224508568723;5643.661380317194;5655.377908430151;5696.572757043155;5712.685802280915;4872.902541949212;5301.443348448357;5403.919216156706;5432.918506222489;5607.271054762855;4783.760811959319;4925.408950925912;5155.917257375993;5670.291213598301;5673.432656734293;4689.640004398686;4906.386701662293;5218.608604354765;5566.471185237396;5570.080936645408 09:21:40 INFO - PROCESS | 7991 | |5;yui.html;629.8645791154901;645.2837350540899;663.0783211336244;666.2970566052812;753.1865585168017;14564.417423303068;14660;14807.629809254773;14840;14851.999999999987;11144.498497567609;14214.786778198335;14229.573112806607;14230.217338046403;14243;732.1909290234295;754.9509281896659;907.2787134228304;924.5469719837265;927.6938610258599;893.1425831202054;941.8493041113423;944.0087907696925;944.4474982135459;950.9729492148498;979.990200097997;991.3110388280148;993.9055789699967;995.9840962218533;997.0826839307854;764.0029761161682;947.1239103828931;981.8085473332632;982.7690492734201;988.9159421449222;721.7401735375261;727.2146082231178;728.2753660108226;729.748236858283;731.5098883747902;14941.252937353089;14987.475438359714;14996.925015374962;15032.17323047232;15052.54842354731;12278.140530162893;12365.9753399619;12462.314572698499;12644.68388290291;12806.29565373904;8527.872081918777;8763.386562940597;9621.951890240574;9842.852357214648;9918.355306905029;3427.1260828488826;3437.1063523483826;3469.3755124077657;3486.9651303486894;3677.1358730698266;63998.080057598345;68468;70660;73632;73792.89310660398;9250.768730781785;9511.90488095124;9574.425534467955;9677.419354838732;9806.509674516246;21970.780292196872;21979.340619781433;21983.34049978503;21997.560048799256;22029.66955495669;5877.735501902424;6996.657174693574;7096.290370962863;7177.784666460015;7185.245549217361;3868.729188956746;3911.2959667260166;3939.763614183158;3948.2103579284258;3949.4668219790533;3432.1419645088727;3549.2723991581665;3549.893503194908;3550.751447398657;3554.0759402555523;620.4013127332146;623.4638211138417;629.3549112159981;643.5752403413725;646.1922596754057;481.605083831262;498.6833360815908;607.6809674920613;607.9787207447806;609.3510411411833;8017.799555011172;8064.596770161468;8124.7562573122905;8217.753467395987;8294.046184688837;537.6720200677584;552.6674154846283;586.1647152807233;599.8320470268332;605.7455868535226;3708.165662725919;3736.3274610569815;3834.6357096075826;3849.287881741887;3872.8838134856;89468;91294.54352728327;92820;92901;93157;53654.195187072226;73517.63241183867;75308.62345688237;75549.24450755422;75658;64928;65385;65408.69182616321;66378.33621663915;67187.32812671909;7939.126696063544;8022.558759268296;8206.179382061746;8227.382946278933;8283.58582070882;40481.59518404778;41017.43721004625;43266.70199894008;43715.56284437242;45317.54682453133;54935.45064549303;57367;57890.710546447;57995.710021449624;58423.70788146032;10837.078848297668;10952.78094438091;11077.335359878432;11546.480408381474;11959.282443053444;11992.700182495159;12000.219985700902;12029.218100823418;12367.381630918599;12378.257304561756;11370.090392768725;11370.374629395463;11520.366379849189;11609.76780464369;11635.952764251258;11303.1522635801;11320.660380188607;11648.883511165119;11726.237794543327;11765.52937882475;11024.889751102386;11096.33421994683;11230.270032447865;11588.362640054878;11863.881361186623;9583.616655333708;9680.128788409076;9684.467354295299;9851.507424628884;9984.150559714488;8955.507447090473;9288.814223715353;9423.19902808269;9460.668876589287;9498.24014078886 09:21:40 INFO - PROCESS | 7991 | __end_tp_report 09:21:40 INFO - PROCESS | 7991 | __start_cc_report 09:21:40 INFO - PROCESS | 7991 | _x_x_mozilla_cycle_collect,1549 09:21:40 INFO - PROCESS | 7991 | __end_cc_report 09:21:40 INFO - PROCESS | 7991 | __startTimestamp1461774100069__endTimestamp 09:21:40 INFO - PROCESS | 7991 | 09:21:40 INFO - PROCESS | 7991 | ------- Summary: start ------- 09:21:40 INFO - PROCESS | 7991 | Number of tests: 6 09:21:40 INFO - PROCESS | 7991 | 09:21:40 INFO - PROCESS | 7991 | [#0] dojo.html Cycles:165 Average:5668.44 Median:5850.91 stddev:639.44 (10.9%) stddev-sans-first:614.12 09:21:40 INFO - PROCESS | 7991 | Values: 3312.5 3360.5 3378.7 3383.2 3415.5 5520.5 5578.8 5591.9 5592.8 5616.6 5559.4 5596.6 5663.5 5685.9 5698.2 5028.4 5032.9 5073.6 5084.8 5168.3 5151.1 5193.1 5196.0 5197.5 5254.2 5059.7 5074.8 5154.6 5155.9 5164.3 5093.0 5096.7 5136.7 5165.4 5177.0 5055.8 5091.0 5120.5 5152.2 5174.1 5456.8 5539.7 5551.8 5557.7 5564.4 6028.7 6073.2 6086.2 6112.3 6133.7 6145.9 6162.1 6228.9 6231.8 6241.2 5648.4 5712.5 5714.7 5717.7 5718.5 6225.8 6261.6 6313.7 6318.2 6319.9 6059.9 6070.2 6070.3 6080.6 6088.6 6107.9 6129.4 6198.7 6201.8 6213.5 6108.0 6112.8 6115.6 6154.3 6179.3 6101.8 6186.1 6186.8 6187.1 6202.5 4726.6 4864.0 4877.9 4882.0 4917.6 4348.5 4360.6 4398.9 4423.1 4469.6 5591.6 5618.0 5621.3 5626.4 5631.4 6021.1 6221.5 6236.0 6243.3 6252.6 4663.7 5556.6 5563.6 5599.2 5600.8 5298.8 5330.8 5336.6 5340.4 5342.1 6271.2 6281.4 6297.1 6299.2 6303.2 6188.6 6224.3 6243.9 6245.2 6256.0 6212.8 6216.4 6280.0 6280.7 6282.5 6117.1 6135.6 6137.8 6138.3 6143.7 6148.3 6191.2 6198.2 6215.1 6217.9 5873.6 5964.4 5998.0 6001.6 6032.7 5984.5 6022.3 6036.5 6042.7 6054.2 5726.4 5823.3 5828.3 5839.9 5850.9 5861.9 6026.8 6033.6 6053.8 6106.8 5176.9 5800.7 5848.0 5891.8 5896.9 09:21:40 INFO - PROCESS | 7991 | 09:21:40 INFO - PROCESS | 7991 | [#1] ext.html Cycles:180 Average:53478.12 Median:34463.72 stddev:102366.71 (297.0%) stddev-sans-first:102576.56 09:21:40 INFO - PROCESS | 7991 | Values: 489.4 510.8 521.6 557.6 560.7 28568.9 36788.6 37511.8 37716.4 37735.9 39235.0 39792.0 39905.2 39911.8 40028.0 8452.2 9254.5 9485.6 9488.5 9521.5 8491.5 11083.4 11319.1 11357.8 11362.5 11257.3 11373.8 11387.9 11399.8 11401.7 8354.0 11109.4 11186.2 11346.3 11362.9 11083.1 11240.9 11244.6 11256.5 11263.2 36636.1 37128.4 37144.3 37184.0 37199.6 3205.5 3214.4 3299.8 3448.8 3584.7 27485.7 27734.2 27776.0 28116.7 28323.4 2688.1 2689.7 2694.5 2712.6 2833.6 355424.0 356961.0 358004.4 358095.0 358397.0 57587.7 57934.0 57946.4 58070.4 58096.0 59020.7 60196.4 60356.7 60381.0 60525.8 16141.0 16253.8 16363.0 16378.9 16381.3 10907.8 11330.7 11518.3 12635.3 12666.6 18457.4 18458.6 18475.4 18484.4 18516.7 7452.9 7679.2 7707.7 7752.7 7801.8 933.9 936.4 945.7 1057.5 1106.7 23316.0 27604.4 27782.7 28128.0 28374.0 1040.9 1107.4 1111.7 1124.3 1184.6 14269.0 14350.2 14636.3 14735.9 14840.6 477416.0 477791.0 484215.0 484774.0 485301.0 56356.4 57153.7 57211.4 57376.7 57665.0 1341.2 1403.8 1423.3 1453.6 1463.4 26900.7 27137.7 27177.7 27620.7 27770.6 47834.0 48629.5 48819.8 48823.5 48842.5 297763.0 297961.0 298177.0 298692.0 300716.0 25455.7 37279.4 38693.6 38850.2 38865.6 40644.8 41225.0 41430.0 41694.0 42860.8 34910.7 34960.5 34966.3 35487.8 36146.8 34452.0 34475.5 35001.5 35343.8 35382.8 35246.5 35576.5 35606.5 36189.5 36380.7 30472.4 30603.7 30607.5 30622.8 31042.0 19450.5 22597.9 22982.0 23544.9 23835.3 09:21:40 INFO - PROCESS | 7991 | 09:21:40 INFO - PROCESS | 7991 | [#2] jquery.html Cycles:180 Average:33314.17 Median:2230.53 stddev:99239.57 (4449.2%) stddev-sans-first:99487.17 09:21:40 INFO - PROCESS | 7991 | Values: 390.2 397.4 399.5 404.1 412.4 18807.0 19207.7 19835.5 19885.7 19946.5 19342.3 19513.8 21232.2 21247.7 21326.8 5851.2 5976.0 6130.2 6137.9 6145.4 4570.8 5966.8 6049.3 6063.0 6066.2 5882.4 6041.8 6062.5 6066.0 6076.7 5957.0 6008.8 6026.6 6035.9 6048.5 5218.6 5249.9 5286.4 5290.5 5299.7 24878.8 24939.1 25107.0 25621.0 25678.9 2167.6 2183.2 2277.8 2391.7 2634.9 14077.5 14232.7 14250.4 14326.3 14977.9 1651.8 1698.7 1715.6 1798.4 1801.1 85133.7 103997.5 106740.5 107719.5 109611.0 10612.3 10904.9 11121.4 11158.1 11240.2 14112.2 14148.6 14165.9 14200.4 14208.7 8430.0 8631.7 8678.8 8709.0 8721.6 8561.4 8568.4 8569.7 8721.2 8724.0 6605.7 6726.6 6727.0 6738.8 6756.7 1833.8 1863.2 1871.3 1879.2 1907.8 920.4 962.3 991.3 1019.3 1044.4 20146.7 20223.5 20236.8 20558.4 20560.1 949.8 963.3 999.6 1007.7 1050.9 11095.2 11330.0 11359.9 11363.7 11398.7 531694.0 604435.0 609658.0 625855.0 630751.8 83898.6 98199.0 102392.5 104312.0 105084.0 1002.2 1005.9 1034.1 1051.3 1054.4 17061.7 17585.8 17672.4 17724.8 18071.5 46139.8 46732.8 47766.0 49396.3 49578.8 62392.7 62631.4 63084.0 63447.4 66014.0 5380.8 5891.0 7142.4 7395.5 8194.3 10248.6 11913.6 12056.3 12065.2 12205.1 10167.7 10186.7 10418.7 10540.5 10575.9 9428.4 9700.3 9911.2 9980.8 10430.8 10522.3 10548.0 10724.3 10730.6 10749.8 7716.3 7905.4 8121.8 11184.3 11240.4 10567.6 10629.1 10702.0 10762.2 11136.7 09:21:40 INFO - PROCESS | 7991 | 09:21:40 INFO - PROCESS | 7991 | [#3] mootools.html Cycles:180 Average:5613.83 Median:4861.71 stddev:3146.85 (64.7%) stddev-sans-first:3139.73 09:21:40 INFO - PROCESS | 7991 | Values: 1398.3 1623.9 1624.0 1898.1 2019.9 7820.9 8531.1 8726.8 8728.8 8729.9 8306.6 8552.1 8560.0 8571.7 8585.1 2106.5 2359.8 2405.0 2432.8 2436.3 2449.2 2477.3 2477.6 2477.9 2480.3 2372.2 2409.4 2413.5 2420.0 2424.8 1751.6 2349.6 2382.4 2426.6 2426.9 1969.2 2373.8 2398.0 2403.9 2405.3 7106.9 7178.5 8503.3 8517.0 8531.4 4268.6 5346.9 5373.9 5385.6 5388.3 3358.7 3414.2 3581.7 3584.2 3587.5 1022.4 1022.7 1026.0 1034.1 1055.0 11887.7 11952.4 11960.2 11964.8 11980.0 4691.5 4799.6 4824.5 4854.7 4861.7 8319.0 10060.1 10082.5 10087.3 10088.7 5311.2 5355.9 5422.6 5423.5 5424.8 4392.9 4583.5 4646.3 4856.8 4861.8 3838.8 3872.9 3993.7 4003.5 4031.0 1318.2 1339.7 1340.3 1349.0 1349.0 771.8 885.8 891.9 900.9 920.4 5379.5 5472.5 5680.7 5778.8 5938.4 909.7 928.6 979.2 1051.1 1068.7 3503.4 3658.7 3755.8 3839.8 3851.7 10318.3 10650.2 10666.5 10687.2 10711.5 10541.2 10701.7 10762.8 10770.8 10784.7 8060.1 8157.1 8227.3 8230.8 8233.0 5176.5 5350.6 5428.1 5462.4 5583.6 9288.2 9872.0 9883.7 9947.6 9980.6 9248.8 9892.3 9920.3 10069.6 10157.8 6922.3 6948.5 6991.5 7007.9 7037.8 7997.4 8059.8 8144.7 8145.4 8149.7 7126.4 7296.3 7331.9 7448.3 7475.7 7121.1 7207.7 7217.2 7372.9 7382.8 6765.6 6849.3 6875.7 6894.3 6906.8 4682.6 4783.1 4883.8 4900.1 4971.9 4840.7 4888.5 4896.6 4940.2 4967.5 09:21:40 INFO - PROCESS | 7991 | 09:21:40 INFO - PROCESS | 7991 | [#4] prototype.html Cycles:180 Average:4628.75 Median:5143.87 stddev:1487.70 (28.9%) stddev-sans-first:1456.34 09:21:40 INFO - PROCESS | 7991 | Values: 322.5 325.3 331.4 333.3 337.3 4659.3 4900.4 5014.6 5355.9 5400.6 5149.8 5270.2 5323.6 5345.7 5361.5 3611.3 3685.7 3954.6 3983.6 4098.6 3244.6 3915.9 3944.2 3953.5 4074.0 3330.2 3755.0 3836.6 3886.3 4054.6 3701.4 3876.8 3923.3 4076.6 4127.5 2922.7 3547.1 3586.9 3596.6 3614.4 4581.0 4587.5 4749.9 5180.4 5186.4 3806.7 4040.2 4323.4 4560.7 4665.7 5541.2 5548.9 5557.4 5615.2 5629.2 3827.5 3830.7 3839.3 3840.2 3870.0 5556.6 5813.4 5883.8 5886.5 5929.0 3444.7 3825.8 3836.6 3890.5 3910.7 3607.3 3629.1 3785.2 3928.8 3957.5 3706.4 4473.6 4517.5 4547.9 4554.6 5050.0 5514.4 5608.5 5708.8 5730.8 1333.7 1422.2 1435.2 1447.1 1449.8 899.0 914.4 942.5 955.2 1003.8 4948.8 5293.3 5677.5 5700.9 5703.8 5043.5 5138.0 5820.3 5991.4 6210.0 4561.5 4684.7 4762.3 4984.3 5643.2 2576.6 2779.9 2949.6 3219.5 3330.7 5989.6 6353.0 6540.1 6635.5 6648.9 5713.6 5732.7 5882.8 6535.0 6565.0 5628.9 6065.1 6671.5 6672.4 6673.7 5812.9 6001.0 6131.3 6306.3 6316.3 5908.1 6170.4 6188.0 6229.3 6248.7 5825.9 5873.0 6179.8 6357.5 6672.4 3874.9 3883.2 3906.5 3927.9 3946.5 5794.8 5800.5 5848.3 5895.1 5917.1 4762.8 5275.7 5672.7 5691.7 5787.3 5539.2 5643.7 5655.4 5696.6 5712.7 4872.9 5301.4 5403.9 5432.9 5607.3 4783.8 4925.4 5155.9 5670.3 5673.4 4689.6 4906.4 5218.6 5566.5 5570.1 09:21:40 INFO - PROCESS | 7991 | 09:21:40 INFO - PROCESS | 7991 | [#5] yui.html Cycles:180 Average:17143.82 Median:608.66 stddev:23562.34 (3871.2%) stddev-sans-first:23595.81 09:21:40 INFO - PROCESS | 7991 | Values: 629.9 645.3 663.1 666.3 753.2 14564.4 14660.0 14807.6 14840.0 14852.0 11144.5 14214.8 14229.6 14230.2 14243.0 732.2 755.0 907.3 924.5 927.7 893.1 941.8 944.0 944.4 951.0 980.0 991.3 993.9 996.0 997.1 764.0 947.1 981.8 982.8 988.9 721.7 727.2 728.3 729.7 731.5 14941.3 14987.5 14996.9 15032.2 15052.5 12278.1 12366.0 12462.3 12644.7 12806.3 8527.9 8763.4 9622.0 9842.9 9918.4 3427.1 3437.1 3469.4 3487.0 3677.1 63998.1 68468.0 70660.0 73632.0 73792.9 9250.8 9511.9 9574.4 9677.4 9806.5 21970.8 21979.3 21983.3 21997.6 22029.7 5877.7 6996.7 7096.3 7177.8 7185.2 3868.7 3911.3 3939.8 3948.2 3949.5 3432.1 3549.3 3549.9 3550.8 3554.1 620.4 623.5 629.4 643.6 646.2 481.6 498.7 607.7 608.0 609.4 8017.8 8064.6 8124.8 8217.8 8294.0 537.7 552.7 586.2 599.8 605.7 3708.2 3736.3 3834.6 3849.3 3872.9 89468.0 91294.5 92820.0 92901.0 93157.0 53654.2 73517.6 75308.6 75549.2 75658.0 64928.0 65385.0 65408.7 66378.3 67187.3 7939.1 8022.6 8206.2 8227.4 8283.6 40481.6 41017.4 43266.7 43715.6 45317.5 54935.5 57367.0 57890.7 57995.7 58423.7 10837.1 10952.8 11077.3 11546.5 11959.3 11992.7 12000.2 12029.2 12367.4 12378.3 11370.1 11370.4 11520.4 11609.8 11636.0 11303.2 11320.7 11648.9 11726.2 11765.5 11024.9 11096.3 11230.3 11588.4 11863.9 9583.6 9680.1 9684.5 9851.5 9984.2 8955.5 9288.8 9423.2 9460.7 9498.2 09:21:40 INFO - PROCESS | 7991 | -------- Summary: end -------- 09:21:40 INFO - PROCESS | 7991 | 09:21:40 INFO - TEST-INFO | 7991: exit 0 09:21:40 INFO - TEST-OK | dromaeo_css | took 1115079ms 09:21:40 INFO - TEST-START | kraken 09:21:40 INFO - operating with platform_type : mac_ 09:21:40 INFO - Initialising browser for kraken test... 09:21:40 INFO - TEST-INFO | started process 7993 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/tmpIHSwKu/profile http://localhost:65210/getInfo.html) 09:21:42 INFO - PROCESS | 7993 | __metrics Screen width/height:1600/1200 09:21:42 INFO - PROCESS | 7993 | colorDepth:24 09:21:42 INFO - PROCESS | 7993 | Browser inner width/height: 1024/689 09:21:42 INFO - PROCESS | 7993 | __metrics 09:21:42 INFO - PROCESS | 7993 | 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:21:42 ERROR - PROCESS | 7993 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:21:43 INFO - TEST-INFO | 7993: exit 0 09:21:43 INFO - Browser initialized. 09:21:43 INFO - Running cycle 1/1 for kraken test... 09:21:43 INFO - TEST-INFO | started process 7995 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/tmpIHSwKu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:21:55 INFO - PROCESS | 7995 | RSS: Main: 239435776 09:21:55 INFO - PROCESS | 7995 | 09:21:58 INFO - PROCESS | 7995 | Cycle 1(1): loaded http://localhost:65210/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:65210/tests/kraken/driver.html?testName=audio-beat-detection) 09:21:58 INFO - PROCESS | 7995 | RSS: Main: 287154176 09:21:58 INFO - PROCESS | 7995 | 09:22:01 INFO - PROCESS | 7995 | Cycle 1(1): loaded http://localhost:65210/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:65210/tests/kraken/driver.html?testName=audio-dft) 09:22:02 INFO - PROCESS | 7995 | RSS: Main: 300376064 09:22:02 INFO - PROCESS | 7995 | 09:22:06 INFO - PROCESS | 7995 | Cycle 1(1): loaded http://localhost:65210/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:65210/tests/kraken/driver.html?testName=audio-fft) 09:22:06 INFO - PROCESS | 7995 | RSS: Main: 389525504 09:22:06 INFO - PROCESS | 7995 | 09:22:08 INFO - PROCESS | 7995 | Cycle 1(1): loaded http://localhost:65210/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:65210/tests/kraken/driver.html?testName=audio-oscillator) 09:22:09 INFO - PROCESS | 7995 | RSS: Main: 289624064 09:22:09 INFO - PROCESS | 7995 | 09:22:11 INFO - PROCESS | 7995 | Cycle 1(1): loaded http://localhost:65210/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:65210/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:22:12 INFO - PROCESS | 7995 | RSS: Main: 293826560 09:22:12 INFO - PROCESS | 7995 | 09:22:15 INFO - PROCESS | 7995 | Cycle 1(1): loaded http://localhost:65210/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:65210/tests/kraken/driver.html?testName=imaging-darkroom) 09:22:15 INFO - PROCESS | 7995 | RSS: Main: 332136448 09:22:15 INFO - PROCESS | 7995 | 09:22:18 INFO - PROCESS | 7995 | Cycle 1(1): loaded http://localhost:65210/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:65210/tests/kraken/driver.html?testName=imaging-desaturate) 09:22:19 INFO - PROCESS | 7995 | RSS: Main: 332939264 09:22:19 INFO - PROCESS | 7995 | 09:22:22 INFO - PROCESS | 7995 | Cycle 1(1): loaded http://localhost:65210/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:65210/tests/kraken/driver.html?testName=json-parse-financial) 09:22:22 INFO - PROCESS | 7995 | RSS: Main: 331280384 09:22:22 INFO - PROCESS | 7995 | 09:22:24 INFO - PROCESS | 7995 | Cycle 1(1): loaded http://localhost:65210/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:65210/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:22:25 INFO - PROCESS | 7995 | RSS: Main: 322314240 09:22:25 INFO - PROCESS | 7995 | 09:22:26 INFO - PROCESS | 7995 | Cycle 1(1): loaded http://localhost:65210/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:65210/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:22:27 INFO - PROCESS | 7995 | RSS: Main: 328679424 09:22:27 INFO - PROCESS | 7995 | 09:22:29 INFO - PROCESS | 7995 | Cycle 1(1): loaded http://localhost:65210/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:65210/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:22:30 INFO - PROCESS | 7995 | RSS: Main: 330944512 09:22:30 INFO - PROCESS | 7995 | 09:22:33 INFO - PROCESS | 7995 | Cycle 1(1): loaded http://localhost:65210/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:65210/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:22:33 INFO - PROCESS | 7995 | RSS: Main: 327061504 09:22:33 INFO - PROCESS | 7995 | 09:22:37 INFO - PROCESS | 7995 | Cycle 1(1): loaded http://localhost:65210/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:65210/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:22:37 INFO - PROCESS | 7995 | RSS: Main: 327995392 09:22:37 INFO - PROCESS | 7995 | 09:22:39 INFO - PROCESS | 7995 | Cycle 1(1): loaded http://localhost:65210/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:65210/tests/kraken/driver.html?testName=ai-astar) 09:22:39 INFO - PROCESS | 7995 | RSS: Main: 392208384 09:22:39 INFO - PROCESS | 7995 | 09:22:39 INFO - PROCESS | 7995 | __start_tp_report 09:22:39 INFO - PROCESS | 7995 | _x_x_mozilla_page_load 09:22:39 INFO - PROCESS | 7995 | _x_x_mozilla_page_load_details 09:22:39 INFO - PROCESS | 7995 | |i|pagename|runs| 09:22:39 INFO - PROCESS | 7995 | |0;ai-astar;112;112;107;112;111;111;113;112;106;113 09:22:39 INFO - PROCESS | 7995 | |1;audio-beat-detection;160;122;150;205;148;142;142;123;212;129 09:22:39 INFO - PROCESS | 7995 | |2;audio-dft;186;186;188;187;186;234;263;186;186;186 09:22:39 INFO - PROCESS | 7995 | |3;audio-fft;78;72;90;93;57;58;59;84;89;56 09:22:39 INFO - PROCESS | 7995 | |4;audio-oscillator;93;97;139;107;116;121;135;82;104;106 09:22:39 INFO - PROCESS | 7995 | |5;imaging-gaussian-blur;87;83;86;90;135;112;87;88;136;113 09:22:39 INFO - PROCESS | 7995 | |6;imaging-darkroom;93;109;150;106;101;92;92;92;93;92 09:22:39 INFO - PROCESS | 7995 | |7;imaging-desaturate;86;85;83;80;96;86;86;85;102;142 09:22:39 INFO - PROCESS | 7995 | |8;json-parse-financial;61;62;61;62;60;60;62;81;59;58 09:22:39 INFO - PROCESS | 7995 | |9;json-stringify-tinderbox;65;62;63;79;75;69;67;69;68;70 09:22:39 INFO - PROCESS | 7995 | |10;stanford-crypto-aes;82;82;83;78;82;82;82;80;82;83 09:22:39 INFO - PROCESS | 7995 | |11;stanford-crypto-ccm;141;140;138;138;134;136;150;137;192;164 09:22:39 INFO - PROCESS | 7995 | |12;stanford-crypto-pbkdf2;199;199;200;200;196;199;196;200;212;266 09:22:39 INFO - PROCESS | 7995 | |13;stanford-crypto-sha256-iterative;71;68;70;73;71;70;69;71;70;71 09:22:39 INFO - PROCESS | 7995 | __end_tp_report 09:22:39 INFO - PROCESS | 7995 | __start_cc_report 09:22:39 INFO - PROCESS | 7995 | _x_x_mozilla_cycle_collect,2056 09:22:39 INFO - PROCESS | 7995 | __end_cc_report 09:22:39 INFO - PROCESS | 7995 | __startTimestamp1461774159851__endTimestamp 09:22:39 INFO - PROCESS | 7995 | 09:22:39 INFO - PROCESS | 7995 | ------- Summary: start ------- 09:22:39 INFO - PROCESS | 7995 | Number of tests: 14 09:22:39 INFO - PROCESS | 7995 | 09:22:39 INFO - PROCESS | 7995 | [#0] ai-astar Cycles:10 Average:110.90 Median:112.00 stddev:2.42 (2.2%) stddev-sans-first:2.54 09:22:39 INFO - PROCESS | 7995 | Values: 112.0 112.0 107.0 112.0 111.0 111.0 113.0 112.0 106.0 113.0 09:22:39 INFO - PROCESS | 7995 | 09:22:39 INFO - PROCESS | 7995 | [#1] audio-beat-detection Cycles:10 Average:153.30 Median:149.00 stddev:31.53 (21.2%) stddev-sans-first:33.35 09:22:39 INFO - PROCESS | 7995 | Values: 160.0 122.0 150.0 205.0 148.0 142.0 142.0 123.0 212.0 129.0 09:22:39 INFO - PROCESS | 7995 | 09:22:39 INFO - PROCESS | 7995 | [#2] audio-dft Cycles:10 Average:198.80 Median:186.50 stddev:27.08 (14.5%) stddev-sans-first:28.32 09:22:39 INFO - PROCESS | 7995 | Values: 186.0 186.0 188.0 187.0 186.0 234.0 263.0 186.0 186.0 186.0 09:22:39 INFO - PROCESS | 7995 | 09:22:39 INFO - PROCESS | 7995 | [#3] audio-fft Cycles:10 Average:73.60 Median:81.00 stddev:15.11 (18.7%) stddev-sans-first:15.94 09:22:39 INFO - PROCESS | 7995 | Values: 78.0 72.0 90.0 93.0 57.0 58.0 59.0 84.0 89.0 56.0 09:22:39 INFO - PROCESS | 7995 | 09:22:39 INFO - PROCESS | 7995 | [#4] audio-oscillator Cycles:10 Average:110.00 Median:137.00 stddev:18.03 (13.2%) stddev-sans-first:18.04 09:22:39 INFO - PROCESS | 7995 | Values: 93.0 97.0 139.0 107.0 116.0 121.0 135.0 82.0 104.0 106.0 09:22:39 INFO - PROCESS | 7995 | 09:22:39 INFO - PROCESS | 7995 | [#5] imaging-gaussian-blur Cycles:10 Average:101.70 Median:86.50 stddev:20.74 (24.0%) stddev-sans-first:21.31 09:22:39 INFO - PROCESS | 7995 | Values: 87.0 83.0 86.0 90.0 135.0 112.0 87.0 88.0 136.0 113.0 09:22:39 INFO - PROCESS | 7995 | 09:22:39 INFO - PROCESS | 7995 | [#6] imaging-darkroom Cycles:10 Average:102.00 Median:92.00 stddev:18.05 (19.6%) stddev-sans-first:18.85 09:22:39 INFO - PROCESS | 7995 | Values: 93.0 109.0 150.0 106.0 101.0 92.0 92.0 92.0 93.0 92.0 09:22:39 INFO - PROCESS | 7995 | 09:22:39 INFO - PROCESS | 7995 | [#7] imaging-desaturate Cycles:10 Average:93.10 Median:85.50 stddev:18.36 (21.5%) stddev-sans-first:19.30 09:22:39 INFO - PROCESS | 7995 | Values: 86.0 85.0 83.0 80.0 96.0 86.0 86.0 85.0 102.0 142.0 09:22:39 INFO - PROCESS | 7995 | 09:22:39 INFO - PROCESS | 7995 | [#8] json-parse-financial Cycles:10 Average:62.60 Median:61.50 stddev:6.60 (10.7%) stddev-sans-first:6.98 09:22:39 INFO - PROCESS | 7995 | Values: 61.0 62.0 61.0 62.0 60.0 60.0 62.0 81.0 59.0 58.0 09:22:39 INFO - PROCESS | 7995 | 09:22:39 INFO - PROCESS | 7995 | [#9] json-stringify-tinderbox Cycles:10 Average:68.70 Median:69.00 stddev:5.19 (7.5%) stddev-sans-first:5.33 09:22:39 INFO - PROCESS | 7995 | Values: 65.0 62.0 63.0 79.0 75.0 69.0 67.0 69.0 68.0 70.0 09:22:39 INFO - PROCESS | 7995 | 09:22:39 INFO - PROCESS | 7995 | [#10] stanford-crypto-aes Cycles:10 Average:81.60 Median:82.00 stddev:1.51 (1.8%) stddev-sans-first:1.59 09:22:39 INFO - PROCESS | 7995 | Values: 82.0 82.0 83.0 78.0 82.0 82.0 82.0 80.0 82.0 83.0 09:22:39 INFO - PROCESS | 7995 | 09:22:39 INFO - PROCESS | 7995 | [#11] stanford-crypto-ccm Cycles:10 Average:147.00 Median:140.50 stddev:18.14 (12.9%) stddev-sans-first:19.10 09:22:39 INFO - PROCESS | 7995 | Values: 141.0 140.0 138.0 138.0 134.0 136.0 150.0 137.0 192.0 164.0 09:22:39 INFO - PROCESS | 7995 | 09:22:39 INFO - PROCESS | 7995 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:206.70 Median:200.00 stddev:21.31 (10.7%) stddev-sans-first:22.42 09:22:39 INFO - PROCESS | 7995 | Values: 199.0 199.0 200.0 200.0 196.0 199.0 196.0 200.0 212.0 266.0 09:22:39 INFO - PROCESS | 7995 | 09:22:39 INFO - PROCESS | 7995 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.40 Median:71.00 stddev:1.35 (1.9%) stddev-sans-first:1.41 09:22:39 INFO - PROCESS | 7995 | Values: 71.0 68.0 70.0 73.0 71.0 70.0 69.0 71.0 70.0 71.0 09:22:39 INFO - PROCESS | 7995 | -------- Summary: end -------- 09:22:39 INFO - PROCESS | 7995 | 09:22:40 INFO - TEST-INFO | 7995: exit 0 09:22:40 INFO - TEST-OK | kraken | took 60034ms 09:22:40 INFO - SUITE-END | took 1175s 09:22:41 INFO - Completed test suite (00:19:35) 09:22:41 INFO - javascript benchmark 09:22:41 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3312.48656458249, 3360.4791257355105, 3378.7128094112, 3383.188034871631, 3415.5389022481972, 5520.530754885839, 5578.804741834037, 5591.916121258179, 5592.804251851186, 5616.634918730285, 5559.388467268597, 5596.552275817935, 5663.490285874271, 5685.8578535536635, 5698.230738850254, 5028.446870844205, 5032.949670503293, 5073.619478539106, 5084.847454576368, 5168.250603662466, 5151.124308867483, 5193.1171700810955, 5195.974020129894, 5197.48025197481, 5254.238135470355, 5059.671121377099, 5074.771635276385, 5154.587632989371, 5155.871103222412, 5164.328637277167, 5093.0, 5096.668716533413, 5136.666116702402, 5165.431802501722, 5177.0, 5055.823046193365, 5090.9745451272875, 5120.513551212629, 5152.227165925104, 5174.094533456668, 5456.781728730846, 5539.723013849291, 5551.750171242304, 5557.722113894289, 5564.387917329091, 6028.6985650717725, 6073.167686352853, 6086.2087928569445, 6112.327643959161, 6133.693315334215, 6145.907811382833, 6162.137300777895, 6228.906566401508, 6231.813045608594, 6241.157443745127, 5648.350439699487, 5712.485876271156, 5714.685692286923, 5717.656940583537, 5718.51392631631, 6225.844353891099, 6261.592996455216, 6313.652749098844, 6318.210223722035, 6319.905201421982, 6059.909101363483, 6070.150178974947, 6070.3019152798, 6080.574359794772, 6088.573799833969, 6107.877842443127, 6129.417705317987, 6198.659073750897, 6201.751929922752, 6213.471854892385, 6107.969460152671, 6112.777056696867, 6115.6330620162935, 6154.323024467305, 6179.258488981351, 6101.803350793711, 6186.133941248228, 6186.845328866814, 6187.133801267826, 6202.472789812826, 4726.645501587394, 4864.0, 4877.853664390003, 4882.0, 4917.557419832232, 4348.478182618106, 4360.563943605677, 4398.912021759547, 4423.07115505741, 4469.575390337913, 5591.608587398843, 5618.016847051749, 5621.297337832771, 5626.352969408488, 5631.436856314418, 6021.096835474714, 6221.47117495018, 6236.03341482071, 6243.250809902841, 6252.562320637512, 4663.673542851968, 5556.555475561945, 5563.638363506359, 5599.160125981135, 5600.803971860965, 5298.788048478018, 5330.813421530228, 5336.573074154058, 5340.385855626575, 5342.145256758978, 6271.247450305992, 6281.371862813682, 6297.149884765589, 6299.181106456132, 6303.212098487689, 6188.597741146811, 6224.253089629274, 6243.937560624336, 6245.18812554365, 6255.999040153553, 6212.81361559154, 6216.409441103088, 6279.963805971964, 6280.654563998841, 6282.4974002080635, 6117.066276512034, 6135.569531795875, 6137.836677219377, 6138.296437970319, 6143.72682107623, 6148.292946311231, 6191.19514463117, 6198.163247961467, 6215.129881816459, 6217.875642487215, 5873.647581145145, 5964.4035596441745, 5997.970010149921, 6001.639901605918, 6032.668203248863, 5984.461398474158, 6022.307434645072, 6036.547258955684, 6042.728077236447, 6054.212952316347, 5726.370099288992, 5823.330317013598, 5828.329742079715, 5839.912401314069, 5850.912236316373, 5861.882762344644, 6026.819195424145, 6033.607815491979, 6053.848653783691, 6106.786262480792, 5176.922346164735, 5800.6809625471005, 5848.0, 5891.793787217427, 5896.9115463268945], "value": 5627.250699326097, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [489.42003725585187, 510.7752588860901, 521.6087934049463, 557.5678848892109, 560.7159604505682, 28568.857155714217, 36788.632113678854, 37511.81244093779, 37716.43425348622, 37735.86792396226, 39235.01912452183, 39792.0, 39905.20189596206, 39911.800440997824, 40027.999300017516, 8452.239298463137, 9254.49100299485, 9485.573149208301, 9488.525573721286, 9521.476318802463, 8491.490510569354, 11083.445827708581, 11319.094472442226, 11357.772844543146, 11362.545498180105, 11257.268277561972, 11373.772524549546, 11387.943060284688, 11399.829002564968, 11401.714957126096, 8353.958230208871, 11109.388983605899, 11186.161037922147, 11346.262492937933, 11362.886371136348, 11083.115870301988, 11240.887591124067, 11244.606438774685, 11256.493457794419, 11263.21157518974, 36636.084097897496, 37128.44307335392, 37144.25711485782, 37184.0, 37199.62800371989, 3205.5031470122167, 3214.373197226542, 3299.8185099819502, 3448.8448019839166, 3584.731145164123, 27485.725142748717, 27734.167974960783, 27776.0, 28116.71883281182, 28323.433531329465, 2688.126358933335, 2689.6772387313454, 2694.528457519926, 2712.6066720325443, 2833.648458862482, 355424.0, 356961.0, 358004.4199557997, 358095.0, 358397.0, 57587.71206144026, 57934.0, 57946.42053579495, 58070.41929580735, 58096.0, 59020.70489647524, 60196.398036019076, 60356.69821650864, 60381.0, 60525.78948421095, 16141.03153810775, 16253.756193657104, 16363.0, 16378.918105409397, 16381.344746210256, 10907.781844363068, 11330.660080197607, 11518.308901465938, 12635.305058221886, 12666.556670516487, 18457.353992610195, 18458.63082738338, 18475.445736627924, 18484.39554286901, 18516.722249166272, 7452.925470745223, 7679.155292917774, 7707.691692332244, 7752.728654497066, 7801.765947021599, 933.9392939458913, 936.4334577580602, 945.7493764152505, 1057.476549108181, 1106.6956586938657, 23315.95078221484, 27604.44791104207, 27782.722172778416, 28128.0, 28374.00690975806, 1040.869891263592, 1107.44074242507, 1111.6942840718705, 1124.3141683573015, 1184.5972369394447, 14269.0, 14350.206224221973, 14636.268186590627, 14735.92632036833, 14840.628984275263, 477416.0, 477791.0, 484215.0, 484774.0, 485301.0, 56356.43643563594, 57153.714231428574, 57211.427885721445, 57376.71311643415, 57665.0, 1341.1952828303135, 1403.7753959366657, 1423.3096947980428, 1453.5857280674957, 1463.4219483304034, 26900.730992689823, 27137.72862271352, 27177.72822271831, 27620.723792761815, 27770.5834412488, 47834.0, 48629.5137048625, 48819.75590122169, 48823.51176488332, 48842.5115748838, 297763.0, 297961.0, 298177.0, 298692.0, 300716.0, 25455.745442545336, 37279.44080838844, 38693.64572239958, 38850.22299553936, 38865.6113438862, 40644.79677601593, 41224.969375765846, 41430.0, 41694.0, 42860.78569607132, 34910.65089349176, 34960.475592865616, 34966.30067398587, 35487.82256088703, 36146.81926590455, 34451.96644100681, 34475.4828677565, 35001.47497787586, 35343.76296829599, 35382.8230858844, 35246.471302930986, 35576.46635300421, 35606.465903010954, 36189.457158143174, 36380.726674566264, 30472.390552189274, 30603.693963060083, 30607.54088688627, 30622.84688576543, 31042.0, 19450.513737156685, 22597.887010564842, 22982.0, 23544.882275588512, 23835.284941451784], "value": 18955.66936381641, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [390.24682363039335, 397.4495323976292, 399.5145897734253, 404.1069236986262, 412.38966329831845, 18807.0, 19207.71188432173, 19835.50411239718, 19885.701714474275, 19946.501337466572, 19342.323018694347, 19513.804861951376, 21232.150713971423, 21247.68128478074, 21326.786732132714, 5851.15158302046, 5975.970120149394, 6130.172426722403, 6137.9386206137815, 6145.354737752538, 4570.771461426932, 5966.79116230932, 6049.274087109531, 6063.0, 6066.211392518989, 5882.441168089046, 6041.788537401192, 6062.484688801435, 6066.0, 6076.726547305382, 5956.9873121569435, 6008.75964961401, 6026.608270462406, 6035.909461358083, 6048.45563899249, 5218.582513398919, 5249.921251181236, 5286.365636123652, 5290.470952904679, 5299.735013249322, 24878.751212487823, 24939.127130550525, 25106.99572017117, 25621.0, 25678.871605641853, 2167.5881582499433, 2183.235867446397, 2277.81777457803, 2391.689080419552, 2634.893344795191, 14077.507287244896, 14232.715345693028, 14250.358733857001, 14326.283685815772, 14977.925110374379, 1651.8182999870005, 1698.7451882217642, 1715.6482921001166, 1798.3885478937225, 1801.0634470075488, 85133.72299415513, 103997.48001259945, 106740.4662976688, 107719.46140269327, 109611.0, 10612.310199836986, 10904.890951090547, 11121.388323642115, 11158.051565617014, 11240.21318507713, 14112.153270803785, 14148.575542733735, 14165.85834141666, 14200.431982720782, 14208.715825683428, 8429.957850210709, 8631.6978905738, 8678.78303042429, 8708.956455217683, 8721.60752766127, 8561.400701950928, 8568.400211985227, 8569.700060497853, 8721.171488708562, 8723.956380218186, 6605.735770569219, 6726.630035348005, 6726.96636516824, 6738.764143254963, 6756.729730810713, 1833.7799464064399, 1863.2174486715612, 1871.2679487947478, 1879.2107314927764, 1907.7710674719121, 920.3833431600843, 962.2879069488667, 991.2813210422386, 1019.2864994503728, 1044.4151275285865, 20146.69779953302, 20223.4944126398, 20236.797632023787, 20558.38324850257, 20560.074796634188, 949.8100379924043, 963.2919803944095, 999.6201443451442, 1007.6775431861734, 1050.9001644843727, 11095.167862410352, 11330.0, 11359.886401135884, 11363.659090227306, 11398.65804025864, 531694.0, 604434.9778251081, 609658.0, 625855.0, 630751.8462407751, 83898.58050709707, 98199.0, 102392.48803755933, 104312.0, 105084.0, 1002.2082554781787, 1005.8591797148405, 1034.1468288661883, 1051.3271506236015, 1054.3568423261956, 17061.744073839152, 17585.824141758418, 17672.381466648607, 17724.847884887957, 18071.548211294823, 46139.76930115328, 46732.766336168104, 47766.0, 49396.259056114905, 49578.75210623924, 62392.68803655953, 62631.37368626438, 63084.0, 63447.365526345995, 66014.0, 5380.811671591476, 5890.970545147246, 7142.428605711634, 7395.482316237812, 8194.34445244367, 10248.64129755455, 11913.642590722293, 12056.276623402624, 12065.215760975509, 12205.084618653815, 10167.7458063549, 10186.694399168036, 10418.739531511774, 10540.472976350999, 10575.947120264349, 9428.35003874333, 9700.272479564203, 9911.157551608194, 9980.800383992135, 10430.843537347098, 10522.316809213602, 10548.0, 10724.302920310154, 10730.62442814529, 10749.838752418564, 7716.305532502101, 7905.4070944680525, 8121.837563248584, 11184.273022253528, 11240.369991772355, 10567.577296908345, 10629.081334405917, 10702.0, 10762.246642735246, 11136.665900023012], "value": 9487.745001037229, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1398.3008495752124, 1623.853853153216, 1624.0336999485307, 1898.079431475734, 2019.939401817946, 7820.92179078209, 8531.146885311466, 8726.825463490726, 8728.781780455492, 8729.86905196421, 8306.584670766468, 8552.059273479912, 8560.0, 8571.699990500334, 8585.14148585141, 2106.536561956367, 2359.7640235976437, 2404.9639255411184, 2432.756724327562, 2436.317831007321, 2449.1672831237374, 2477.318737347226, 2477.6283557466436, 2477.863717495537, 2480.268320845346, 2372.1697405907967, 2409.409694624819, 2413.4931664350506, 2420.007796803302, 2424.781769640732, 1751.5796208909833, 2349.565330413879, 2382.4401265702545, 2426.575349313863, 2426.8907899144583, 1969.2123150739676, 2373.76262373763, 2398.0287983366766, 2403.9158629447884, 2405.2663937499055, 7106.928930710678, 7178.49750517464, 8503.277221436123, 8517.0, 8531.402801803877, 4268.615824575803, 5346.866328341784, 5373.892522149535, 5385.5960802939935, 5388.299521062276, 3358.714509266715, 3414.231797845465, 3581.71346292296, 3584.2473080653094, 3587.461880717887, 1022.371241686365, 1022.6983040003217, 1026.009541489508, 1034.120997152422, 1055.018832485788, 11887.702807429883, 11952.40237988097, 11960.222585531912, 11964.82052769209, 11980.041596672245, 4691.502313123935, 4799.616030717534, 4824.541668541483, 4854.68444551103, 4861.6596838220985, 8319.0, 10060.0945914868, 10082.546285417175, 10087.29388942767, 10088.69733907984, 5311.229871668586, 5355.946440535623, 5422.6475279106735, 5423.484768946943, 5424.810131645373, 4392.912141757147, 4583.54164583539, 4646.279826626878, 4856.781444834991, 4861.756912154378, 3838.788866612363, 3872.9031774205305, 3993.6805055595482, 4003.4795476587856, 4030.9798451008146, 1318.235423454394, 1339.7186590815843, 1340.3030424179371, 1348.9595312140652, 1349.0, 771.7646117934084, 885.8095509465496, 891.9241864441466, 900.8963969143501, 920.4201353147474, 5379.462053794668, 5472.50747432725, 5680.724723666665, 5778.797742079091, 5938.376470470538, 909.6816114359921, 928.5682157796657, 979.2410881566842, 1051.1433181956681, 1068.6847380022912, 3503.386907291214, 3658.689011434058, 3755.75587586806, 3839.8080095995088, 3851.730378873508, 10318.317237518298, 10650.201234907412, 10666.466676666136, 10687.198460115522, 10711.517981690842, 10541.156707463384, 10701.678949631523, 10762.838557421645, 10770.845830833709, 10784.676459706221, 8060.073091594424, 8157.102718700939, 8227.259546640831, 8230.8353832923, 8233.012038555293, 5176.482351764794, 5350.571954243574, 5428.104362780097, 5462.399136094947, 5583.55331573465, 9288.210502107397, 9871.950640246752, 9883.703488895344, 9947.602095916081, 9980.60077596888, 9248.768780780265, 9892.258080644124, 9920.305578609428, 10069.647562335284, 10157.847632285373, 6922.342377474132, 6948.5483443576, 6991.545549539263, 7007.859842803014, 7037.824054398477, 7997.440179187401, 8059.758207253792, 8144.674213031412, 8145.389095817719, 8149.6960486321905, 7126.429885609035, 7296.306850849161, 7331.8533629328185, 7448.292412220831, 7475.663595138123, 7121.074260346122, 7207.747728829466, 7217.170025447141, 7372.889406658797, 7382.778516644509, 6765.627890466071, 6849.280825513449, 6875.690593923186, 6894.310568943066, 6906.827329316808, 4682.555157260055, 4783.091212669582, 4883.8290659826735, 4900.093482705711, 4971.935022020907, 4840.698202582587, 4888.5111488850835, 4896.583790377716, 4940.184869496637, 4967.453580106113], "value": 4535.265347809041, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [322.4985148094712, 325.34767790579883, 331.3770112189084, 333.33998682608444, 337.3303991576721, 4659.301104834272, 4900.411950565937, 5014.648974571777, 5355.866103347418, 5400.594955378352, 5149.76826042828, 5270.209468579706, 5323.630984212027, 5345.705986170759, 5361.544268737152, 3611.3499570077374, 3685.7235707321993, 3954.6440820326166, 3983.5618082010956, 4098.610631989971, 3244.5619841321472, 3915.882523524299, 3944.1717239379764, 3953.5487997441483, 4074.0, 3330.1684073759457, 3755.0, 3836.558795738498, 3886.2810380079636, 4054.5742697016685, 3701.407774756053, 3876.7594369801673, 3923.274194274054, 4076.5923407658997, 4127.504699436087, 2922.6931172226823, 3547.1486843157713, 3586.946195807065, 3596.5504311961004, 3614.367485689994, 4580.9541904580865, 4587.541245875419, 4749.8575042748425, 5180.4042535108565, 5186.429492755794, 3806.7144964127524, 4040.2323558523785, 4323.351497275434, 4560.749158796265, 4665.743384113873, 5541.196526503675, 5548.916766248469, 5557.416136401106, 5615.22046586938, 5629.183768353607, 3827.5406951166037, 3830.655241028293, 3839.308924393608, 3840.1743625120453, 3870.0, 5556.638818476786, 5813.418658134153, 5883.79406720767, 5886.529077673818, 5928.970355148197, 3444.7254515829304, 3825.7895815730403, 3836.635519625631, 3890.463267009515, 3910.667593254548, 3607.260511595117, 3629.1471504196493, 3785.205106927521, 3928.8035598219976, 3957.5053118360206, 3706.3513885070006, 4473.619742321939, 4517.525659805673, 4547.886302842456, 4554.633609917011, 5049.974750126299, 5514.393416724157, 5608.495235428831, 5708.828735137952, 5730.799422020293, 1333.6532501549473, 1422.1893520693106, 1435.2034620785564, 1447.117258472331, 1449.8477659845564, 898.9505577193318, 914.3827916156567, 942.528735632184, 955.19763398744, 1003.8494225866178, 4948.826791062295, 5293.285406470154, 5677.489025987681, 5700.857478563069, 5703.828885133453, 5043.470435604282, 5137.972405518911, 5820.330661973927, 5991.430814072656, 6210.0, 4561.475430325555, 4684.672072954929, 4762.285657151455, 4984.252362145708, 5643.1535269709875, 2576.600626902833, 2779.9444011119663, 2949.631296087989, 3219.517072439153, 3330.701026599628, 5989.580729348991, 6353.0, 6540.149780528501, 6635.5023373247195, 6648.900266496006, 5713.600047996601, 5732.656040637575, 5882.823515294548, 6534.9673251634385, 6565.0, 5628.887422251614, 6065.057712486919, 6671.499637527205, 6672.366125218096, 6673.733050677918, 5812.854678633068, 6001.0398336267735, 6131.294901086432, 6306.306306306211, 6316.273628532777, 5908.084246941731, 6170.382961703794, 6188.0, 6229.283632382334, 6248.656323902229, 5825.854353641193, 5873.0, 6179.81460556184, 6357.459615932513, 6672.432843208382, 3874.941875871808, 3883.223355329002, 3906.5312162540677, 3927.8625248116177, 3946.506686664167, 5794.797182098607, 5800.50695690871, 5848.2689663792025, 5895.054631971525, 5917.142014407973, 4762.785674644719, 5275.709835959059, 5672.716364181857, 5691.666416769105, 5787.305523337226, 5539.224508568723, 5643.661380317194, 5655.377908430151, 5696.572757043155, 5712.685802280915, 4872.902541949212, 5301.443348448357, 5403.919216156706, 5432.918506222489, 5607.271054762855, 4783.760811959319, 4925.408950925912, 5155.917257375993, 5670.291213598301, 5673.432656734293, 4689.640004398686, 4906.386701662293, 5218.608604354765, 5566.471185237396, 5570.080936645408], "value": 4170.843171073219, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [629.8645791154901, 645.2837350540899, 663.0783211336244, 666.2970566052812, 753.1865585168017, 14564.417423303068, 14660.0, 14807.629809254773, 14840.0, 14851.999999999987, 11144.498497567609, 14214.786778198335, 14229.573112806607, 14230.217338046403, 14243.0, 732.1909290234295, 754.9509281896659, 907.2787134228304, 924.5469719837265, 927.6938610258599, 893.1425831202054, 941.8493041113423, 944.0087907696925, 944.4474982135459, 950.9729492148498, 979.990200097997, 991.3110388280148, 993.9055789699967, 995.9840962218533, 997.0826839307854, 764.0029761161682, 947.1239103828931, 981.8085473332632, 982.7690492734201, 988.9159421449222, 721.7401735375261, 727.2146082231178, 728.2753660108226, 729.748236858283, 731.5098883747902, 14941.252937353089, 14987.475438359714, 14996.925015374962, 15032.17323047232, 15052.54842354731, 12278.140530162893, 12365.9753399619, 12462.314572698499, 12644.68388290291, 12806.29565373904, 8527.872081918777, 8763.386562940597, 9621.951890240574, 9842.852357214648, 9918.355306905029, 3427.1260828488826, 3437.1063523483826, 3469.3755124077657, 3486.9651303486894, 3677.1358730698266, 63998.080057598345, 68468.0, 70660.0, 73632.0, 73792.89310660398, 9250.768730781785, 9511.90488095124, 9574.425534467955, 9677.419354838732, 9806.509674516246, 21970.780292196872, 21979.340619781433, 21983.34049978503, 21997.560048799256, 22029.66955495669, 5877.735501902424, 6996.657174693574, 7096.290370962863, 7177.784666460015, 7185.245549217361, 3868.729188956746, 3911.2959667260166, 3939.763614183158, 3948.2103579284258, 3949.4668219790533, 3432.1419645088727, 3549.2723991581665, 3549.893503194908, 3550.751447398657, 3554.0759402555523, 620.4013127332146, 623.4638211138417, 629.3549112159981, 643.5752403413725, 646.1922596754057, 481.605083831262, 498.6833360815908, 607.6809674920613, 607.9787207447806, 609.3510411411833, 8017.799555011172, 8064.596770161468, 8124.7562573122905, 8217.753467395987, 8294.046184688837, 537.6720200677584, 552.6674154846283, 586.1647152807233, 599.8320470268332, 605.7455868535226, 3708.165662725919, 3736.3274610569815, 3834.6357096075826, 3849.287881741887, 3872.8838134856, 89468.0, 91294.54352728327, 92820.0, 92901.0, 93157.0, 53654.195187072226, 73517.63241183867, 75308.62345688237, 75549.24450755422, 75658.0, 64928.0, 65385.0, 65408.69182616321, 66378.33621663915, 67187.32812671909, 7939.126696063544, 8022.558759268296, 8206.179382061746, 8227.382946278933, 8283.58582070882, 40481.59518404778, 41017.43721004625, 43266.70199894008, 43715.56284437242, 45317.54682453133, 54935.45064549303, 57367.0, 57890.710546447, 57995.710021449624, 58423.70788146032, 10837.078848297668, 10952.78094438091, 11077.335359878432, 11546.480408381474, 11959.282443053444, 11992.700182495159, 12000.219985700902, 12029.218100823418, 12367.381630918599, 12378.257304561756, 11370.090392768725, 11370.374629395463, 11520.366379849189, 11609.76780464369, 11635.952764251258, 11303.1522635801, 11320.660380188607, 11648.883511165119, 11726.237794543327, 11765.52937882475, 11024.889751102386, 11096.33421994683, 11230.270032447865, 11588.362640054878, 11863.881361186623, 9583.616655333708, 9680.128788409076, 9684.467354295299, 9851.507424628884, 9984.150559714488, 8955.507447090473, 9288.814223715353, 9423.19902808269, 9460.668876589287, 9498.24014078886], "value": 6727.862940685863, "unit": "score"}], "name": "dromaeo_css", "value": 7106.530389985832, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 112.0, 107.0, 112.0, 111.0, 111.0, 113.0, 112.0, 106.0, 113.0], "value": 110.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 122.0, 150.0, 205.0, 148.0, 142.0, 142.0, 123.0, 212.0, 129.0], "value": 153.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 186.0, 188.0, 187.0, 186.0, 234.0, 263.0, 186.0, 186.0, 186.0], "value": 198.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 72.0, 90.0, 93.0, 57.0, 58.0, 59.0, 84.0, 89.0, 56.0], "value": 73.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 97.0, 139.0, 107.0, 116.0, 121.0, 135.0, 82.0, 104.0, 106.0], "value": 110.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 83.0, 86.0, 90.0, 135.0, 112.0, 87.0, 88.0, 136.0, 113.0], "value": 101.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 109.0, 150.0, 106.0, 101.0, 92.0, 92.0, 92.0, 93.0, 92.0], "value": 102.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 83.0, 80.0, 96.0, 86.0, 86.0, 85.0, 102.0, 142.0], "value": 93.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 62.0, 61.0, 62.0, 60.0, 60.0, 62.0, 81.0, 59.0, 58.0], "value": 62.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 62.0, 63.0, 79.0, 75.0, 69.0, 67.0, 69.0, 68.0, 70.0], "value": 68.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 83.0, 78.0, 82.0, 82.0, 82.0, 80.0, 82.0, 83.0], "value": 81.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 140.0, 138.0, 138.0, 134.0, 136.0, 150.0, 137.0, 192.0, 164.0], "value": 147.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 199.0, 200.0, 200.0, 196.0, 199.0, 196.0, 200.0, 212.0, 266.0], "value": 206.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 68.0, 70.0, 73.0, 71.0, 70.0, 69.0, 71.0, 70.0, 71.0], "value": 70.4, "unit": "score"}], "name": "kraken", "value": 1580.4, "alertThreshold": 2.0}]} 09:22:41 INFO - Return code: 0 09:22:41 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 09:22:41 ERROR - # TBPL SUCCESS # 09:22:41 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:22:41 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:22:41 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:22:41 INFO - 2016-04-27 09:22:41.148 screenresolution[7999:159245] starting screenresolution argv=screenresolution get 09:22:41 INFO - 2016-04-27 09:22:41.151 screenresolution[7999:159245] Display 0: 1600x1200x32@60 09:22:41 INFO - 2016-04-27 09:22:41.157 screenresolution[8000:159248] starting screenresolution argv=screenresolution list 09:22:41 INFO - Available Modes on Display 0 09:22:41 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:22:41 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:22:41 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:22:41 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:22:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:22:41 INFO - Intel Iris: 09:22:41 INFO - Chipset Model: Intel Iris 09:22:41 INFO - Type: GPU 09:22:41 INFO - Bus: Built-In 09:22:41 INFO - VRAM (Dynamic, Max): 1536 MB 09:22:41 INFO - Vendor: Intel (0x8086) 09:22:41 INFO - Device ID: 0x0a2e 09:22:41 INFO - Revision ID: 0x0009 09:22:41 INFO - Displays: 09:22:41 INFO - Display: 09:22:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:22:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:22:41 INFO - Display Serial Number: 1600x1200 60 09:22:41 INFO - Main Display: Yes 09:22:41 INFO - Mirror: Off 09:22:41 INFO - Online: Yes 09:22:41 INFO - Rotation: Supported 09:22:41 INFO - Return code: 0 09:22:41 INFO - Running post-action listener: _resource_record_post_action 09:22:41 INFO - Running post-run listener: _resource_record_post_run 09:22:42 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 0; Write bytes: 695960064; Read time: 0; Write time: 1963 09:22:42 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 396239360; Write bytes: 398611968; Read time: 29689; Write time: 781 09:22:42 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 0; Write bytes: 296500224; Read time: 0; Write time: 1179 09:22:42 INFO - Running post-run listener: _upload_blobber_files 09:22:42 INFO - Blob upload gear active. 09:22:42 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:22:42 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:22:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:22:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:22:42 INFO - (blobuploader) - INFO - Open directory for files ... 09:22:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:22:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:22:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:22:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:22:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:22:44 INFO - (blobuploader) - INFO - Done attempting. 09:22:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:22:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:22:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:22:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:22:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:22:45 INFO - (blobuploader) - INFO - Done attempting. 09:22:45 INFO - (blobuploader) - INFO - Iteration through files over. 09:22:45 INFO - Return code: 0 09:22:45 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:22:45 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:22:45 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c47f86774c8033c7f316ed3fe365f36fcb4bbc7f58ff4a3356fdf37d5d92520fb2ddf353c49a1c8e01ad22b4ed6fb81f07eb640b5850bb95b0547b01999cd94c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c220e3f2ad9fe7c35821b8b613f1e58bd4eb2c69298559945ce62852a2b72ae6a4b6c69aa632a3b3bb70e6f1db4f2e10a5c2726e1cbc9697c1beb1ad66116c99"} 09:22:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:22:45 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:22:45 INFO - Contents: 09:22:45 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c47f86774c8033c7f316ed3fe365f36fcb4bbc7f58ff4a3356fdf37d5d92520fb2ddf353c49a1c8e01ad22b4ed6fb81f07eb640b5850bb95b0547b01999cd94c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c220e3f2ad9fe7c35821b8b613f1e58bd4eb2c69298559945ce62852a2b72ae6a4b6c69aa632a3b3bb70e6f1db4f2e10a5c2726e1cbc9697c1beb1ad66116c99"} 09:22:45 INFO - Running post-run listener: copy_logs_to_upload_dir 09:22:45 INFO - Copying logs to upload dir... 09:22:45 INFO - mkdir: /builds/slave/test/build/upload/logs 09:22:45 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1294.426469 ========= master_lag: 1.97 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 36 secs) (at 2016-04-27 09:22:47.056436) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-27 09:22:47.060287) ========= 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.EPC9MeT5oa/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.cFkqwX8c79/Listeners TMPDIR=/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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/c47f86774c8033c7f316ed3fe365f36fcb4bbc7f58ff4a3356fdf37d5d92520fb2ddf353c49a1c8e01ad22b4ed6fb81f07eb640b5850bb95b0547b01999cd94c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c220e3f2ad9fe7c35821b8b613f1e58bd4eb2c69298559945ce62852a2b72ae6a4b6c69aa632a3b3bb70e6f1db4f2e10a5c2726e1cbc9697c1beb1ad66116c99"} build_url:https://queue.taskcluster.net/v1/task/Yfu3EQrzTAuR7TR_vmxUKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011323 build_url: 'https://queue.taskcluster.net/v1/task/Yfu3EQrzTAuR7TR_vmxUKQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c47f86774c8033c7f316ed3fe365f36fcb4bbc7f58ff4a3356fdf37d5d92520fb2ddf353c49a1c8e01ad22b4ed6fb81f07eb640b5850bb95b0547b01999cd94c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c220e3f2ad9fe7c35821b8b613f1e58bd4eb2c69298559945ce62852a2b72ae6a4b6c69aa632a3b3bb70e6f1db4f2e10a5c2726e1cbc9697c1beb1ad66116c99"}' ========= master_lag: 0.53 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-27 09:22:47.603309) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 09:22:47.603636) ========= 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.EPC9MeT5oa/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.cFkqwX8c79/Listeners TMPDIR=/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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.005429 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 09:22:47.660134) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-27 09:22:47.660451) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-27 09:22:47.661274) ========= ========= Total master_lag: 2.93 =========