builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0360 starttime: 1468322116.11 results: success (0) buildid: 20160712020826 builduid: a21200693adf4b949f69d01a1892ad4c revision: efc08ad3e8afd42499a1cd20ade4e7c0cd05e3a8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-12 04:15:16.110470) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-12 04:15:16.111095) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 2 secs) (at 2016-07-12 04:15:16.111543) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 2 secs) (at 2016-07-12 04:15:18.996822) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 04:15:18.997239) ========= 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.0mUfHgd1p4/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.tbJZE3IPuZ/Listeners TMPDIR=/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-07-12 04:15:19-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 223M=0s 2016-07-12 04:15:19 (223 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.132058 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 04:15:19.150556) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 04:15:19.150897) ========= 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.0mUfHgd1p4/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.tbJZE3IPuZ/Listeners TMPDIR=/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/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.020236 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 04:15:19.201752) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-12 04:15:19.202363) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev efc08ad3e8afd42499a1cd20ade4e7c0cd05e3a8 --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 efc08ad3e8afd42499a1cd20ade4e7c0cd05e3a8 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.0mUfHgd1p4/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.tbJZE3IPuZ/Listeners TMPDIR=/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-07-12 04:15:19,292 truncating revision to first 12 chars 2016-07-12 04:15:19,292 Setting DEBUG logging. 2016-07-12 04:15:19,292 attempt 1/10 2016-07-12 04:15:19,293 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/efc08ad3e8af?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-12 04:15:20,324 unpacking tar archive at: fx-team-efc08ad3e8af/testing/mozharness/ program finished with exit code 0 elapsedTime=1.300526 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-12 04:15:20.527418) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-12 04:15:20.528149) ========= script_repo_revision: efc08ad3e8afd42499a1cd20ade4e7c0cd05e3a8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-12 04:15:20.529060) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-12 04:15:20.529572) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-12 04:15:20.549453) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-07-12 04:15:20.550177) ========= /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.0mUfHgd1p4/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.tbJZE3IPuZ/Listeners TMPDIR=/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/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 04:15:20 INFO - MultiFileLogger online at 20160712 04:15:20 in /builds/slave/test 04:15:20 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 04:15:20 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:15:20 INFO - {'append_to_log': False, 04:15:20 INFO - 'base_work_dir': '/builds/slave/test', 04:15:20 INFO - 'blob_upload_branch': 'Fx-Team', 04:15:20 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:15:20 INFO - 'branch': 'Fx-Team', 04:15:20 INFO - 'buildbot_json_path': 'buildprops.json', 04:15:20 INFO - 'config_files': ('talos/mac_config.py',), 04:15:20 INFO - 'default_actions': ('clobber', 04:15:20 INFO - 'read-buildbot-config', 04:15:20 INFO - 'download-and-extract', 04:15:20 INFO - 'populate-webroot', 04:15:20 INFO - 'create-virtualenv', 04:15:20 INFO - 'install', 04:15:20 INFO - 'run-tests'), 04:15:20 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:15:20 INFO - 'download_minidump_stackwalk': True, 04:15:20 INFO - 'download_symbols': 'ondemand', 04:15:20 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:15:20 INFO - 'tooltool.py': '/tools/tooltool.py', 04:15:20 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:15:20 INFO - '/tools/misc-python/virtualenv.py')}, 04:15:20 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:15:20 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:15:20 INFO - 'installer_path': 'installer.exe', 04:15:20 INFO - 'log_level': 'info', 04:15:20 INFO - 'log_name': 'talos', 04:15:20 INFO - 'log_to_console': True, 04:15:20 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:15:20 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:15:20 INFO - 'opt_config_files': (), 04:15:20 INFO - 'pip_index': False, 04:15:20 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:15:20 INFO - 'cmd': ('bash', 04:15:20 INFO - '-c', 04:15:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:15:20 INFO - 'enabled': True, 04:15:20 INFO - 'halt_on_failure': False, 04:15:20 INFO - 'name': 'check_screen_resolution'},), 04:15:20 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:15:20 INFO - 'cmd': ('bash', 04:15:20 INFO - '-c', 04:15:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:15:20 INFO - 'enabled': True, 04:15:20 INFO - 'halt_on_failure': False, 04:15:20 INFO - 'name': 'check_screen_resolution'},), 04:15:20 INFO - 'run_cmd_checks_enabled': True, 04:15:20 INFO - 'sps_profile': False, 04:15:20 INFO - 'sps_profile_interval': 0, 04:15:20 INFO - 'suite': 'dromaeojs', 04:15:20 INFO - 'system_bits': '32', 04:15:20 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:15:20 INFO - 'title': 't-yosemite-r7-0360', 04:15:20 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:15:20 INFO - 'use_talos_json': True, 04:15:20 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:15:20 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:15:20 INFO - 'work_dir': 'build'} 04:15:20 INFO - ##### 04:15:20 INFO - ##### Running clobber step. 04:15:20 INFO - ##### 04:15:20 INFO - Running pre-action listener: _resource_record_pre_action 04:15:20 INFO - Running main action method: clobber 04:15:20 INFO - rmtree: /builds/slave/test/build 04:15:20 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:15:20 INFO - Running post-action listener: _resource_record_post_action 04:15:20 INFO - ##### 04:15:20 INFO - ##### Finished clobber step (success) 04:15:20 INFO - ##### 04:15:20 INFO - ##### 04:15:20 INFO - ##### Running read-buildbot-config step. 04:15:20 INFO - ##### 04:15:20 INFO - Running pre-action listener: _resource_record_pre_action 04:15:20 INFO - Running main action method: read_buildbot_config 04:15:20 INFO - Using buildbot properties: 04:15:20 INFO - { 04:15:20 INFO - "project": "", 04:15:20 INFO - "product": "firefox", 04:15:20 INFO - "script_repo_revision": "production", 04:15:20 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 04:15:20 INFO - "repository": "", 04:15:20 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 04:15:20 INFO - "buildid": "20160712020826", 04:15:20 INFO - "pgo_build": "False", 04:15:20 INFO - "basedir": "/builds/slave/test", 04:15:20 INFO - "buildnumber": 95, 04:15:20 INFO - "slavename": "t-yosemite-r7-0360", 04:15:20 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 04:15:20 INFO - "platform": "yosemite_r7", 04:15:20 INFO - "scheduler": "tests-fx-team-macosx64-talos", 04:15:20 INFO - "branch": "fx-team", 04:15:20 INFO - "repo_path": "integration/fx-team", 04:15:20 INFO - "revision": "efc08ad3e8afd42499a1cd20ade4e7c0cd05e3a8", 04:15:20 INFO - "stage_platform": "macosx64", 04:15:20 INFO - "builduid": "a21200693adf4b949f69d01a1892ad4c", 04:15:20 INFO - "slavebuilddir": "test" 04:15:20 INFO - } 04:15:20 INFO - Found installer url https://queue.taskcluster.net/v1/task/E6I7gtcPRc-B7Hf3ncDN8Q/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 04:15:20 INFO - Running post-action listener: _resource_record_post_action 04:15:20 INFO - ##### 04:15:20 INFO - ##### Finished read-buildbot-config step (success) 04:15:20 INFO - ##### 04:15:20 INFO - ##### 04:15:20 INFO - ##### Running download-and-extract step. 04:15:20 INFO - ##### 04:15:20 INFO - Running pre-action listener: _resource_record_pre_action 04:15:20 INFO - Running main action method: download_and_extract 04:15:20 INFO - mkdir: /builds/slave/test/build/tests 04:15:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:15:20 INFO - https://queue.taskcluster.net/v1/task/E6I7gtcPRc-B7Hf3ncDN8Q/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 04:15:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E6I7gtcPRc-B7Hf3ncDN8Q/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 04:15:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E6I7gtcPRc-B7Hf3ncDN8Q/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 04:15:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E6I7gtcPRc-B7Hf3ncDN8Q/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 04:15:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E6I7gtcPRc-B7Hf3ncDN8Q/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json'}, attempt #1 04:15:22 INFO - Downloaded 1199 bytes. 04:15:22 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 04:15:22 INFO - Using the following test package requirements: 04:15:22 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 04:15:22 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 04:15:22 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 04:15:22 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 04:15:22 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 04:15:22 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 04:15:22 INFO - u'jsshell-mac.zip'], 04:15:22 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 04:15:22 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 04:15:22 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 04:15:22 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 04:15:22 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 04:15:22 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 04:15:22 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 04:15:22 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 04:15:22 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 04:15:22 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 04:15:22 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 04:15:22 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:15: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')]} 04:15:22 INFO - https://queue.taskcluster.net/v1/task/E6I7gtcPRc-B7Hf3ncDN8Q/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:15:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E6I7gtcPRc-B7Hf3ncDN8Q/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 04:15:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E6I7gtcPRc-B7Hf3ncDN8Q/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 04:15:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E6I7gtcPRc-B7Hf3ncDN8Q/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 04:15:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E6I7gtcPRc-B7Hf3ncDN8Q/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:15:23 INFO - Downloaded 18772391 bytes. 04:15:23 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:15:23 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 04:15:26 INFO - Return code: 0 04:15:26 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip', u'firefox-50.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 04:15:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:15:26 INFO - https://queue.taskcluster.net/v1/task/E6I7gtcPRc-B7Hf3ncDN8Q/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:15:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E6I7gtcPRc-B7Hf3ncDN8Q/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 04:15:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E6I7gtcPRc-B7Hf3ncDN8Q/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 04:15:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E6I7gtcPRc-B7Hf3ncDN8Q/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 04:15:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E6I7gtcPRc-B7Hf3ncDN8Q/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:15:27 INFO - Downloaded 18772391 bytes. 04:15:27 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:15:27 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 04:15:31 INFO - Return code: 0 04:15: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')]} 04:15:31 INFO - https://queue.taskcluster.net/v1/task/E6I7gtcPRc-B7Hf3ncDN8Q/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 04:15:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E6I7gtcPRc-B7Hf3ncDN8Q/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 04:15:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E6I7gtcPRc-B7Hf3ncDN8Q/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 04:15:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E6I7gtcPRc-B7Hf3ncDN8Q/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 04:15:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E6I7gtcPRc-B7Hf3ncDN8Q/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:15:32 INFO - Downloaded 14403317 bytes. 04:15:32 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:15:32 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 04:15:32 INFO - Return code: 0 04:15:32 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')]} 04:15:32 INFO - https://queue.taskcluster.net/v1/task/E6I7gtcPRc-B7Hf3ncDN8Q/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 04:15:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E6I7gtcPRc-B7Hf3ncDN8Q/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 04:15:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E6I7gtcPRc-B7Hf3ncDN8Q/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 04:15:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E6I7gtcPRc-B7Hf3ncDN8Q/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 04:15:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E6I7gtcPRc-B7Hf3ncDN8Q/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 04:15:35 INFO - Downloaded 112432454 bytes. 04:15:35 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/E6I7gtcPRc-B7Hf3ncDN8Q/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 04:15:35 INFO - mkdir: /builds/slave/test/properties 04:15:35 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:15:35 INFO - Writing to file /builds/slave/test/properties/build_url 04:15:35 INFO - Contents: 04:15:35 INFO - build_url:https://queue.taskcluster.net/v1/task/E6I7gtcPRc-B7Hf3ncDN8Q/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 04:15:36 INFO - Running post-action listener: _resource_record_post_action 04:15:36 INFO - Running post-action listener: set_extra_try_arguments 04:15:36 INFO - ##### 04:15:36 INFO - ##### Finished download-and-extract step (success) 04:15:36 INFO - ##### 04:15:36 INFO - ##### 04:15:36 INFO - ##### Running populate-webroot step. 04:15:36 INFO - ##### 04:15:36 INFO - Running pre-action listener: _resource_record_pre_action 04:15:36 INFO - Running main action method: populate_webroot 04:15:36 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:15:36 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:15:36 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:15:36 INFO - '--tppagecycles', 04:15:36 INFO - '7'], 04:15:36 INFO - 'tests': ['tsvgm']}}, 04:15:36 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:15:36 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:15:36 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:15:36 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:15:36 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:15:36 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:15:36 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:15:36 INFO - 'talos_options': ['--disable-e10s'], 04:15:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:15:36 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:15:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:15:36 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:15:36 INFO - 'talos_options': ['--disable-e10s'], 04:15:36 INFO - 'tests': ['damp', 'tps']}, 04:15:36 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:15:36 INFO - 'tests': ['damp', 'tps']}, 04:15:36 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:15:36 INFO - 'tests': ['dromaeo_dom']}, 04:15:36 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:15:36 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:15:36 INFO - 'tests': ['basic_compositor_video']}, 04:15:36 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:15:36 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:15:36 INFO - 'tests': ['a11yr', 04:15:36 INFO - 'ts_paint', 04:15:36 INFO - 'tpaint', 04:15:36 INFO - 'sessionrestore', 04:15:36 INFO - 'sessionrestore_no_auto_restore', 04:15:36 INFO - 'tabpaint']}, 04:15:36 INFO - 'other-e10s': {'tests': ['a11yr', 04:15:36 INFO - 'ts_paint', 04:15:36 INFO - 'tpaint', 04:15:36 INFO - 'sessionrestore', 04:15:36 INFO - 'sessionrestore_no_auto_restore', 04:15:36 INFO - 'tabpaint']}, 04:15:36 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:15:36 INFO - 'ts_paint', 04:15:36 INFO - 'tpaint', 04:15:36 INFO - 'sessionrestore', 04:15:36 INFO - 'sessionrestore_no_auto_restore', 04:15:36 INFO - 'tabpaint']}, 04:15:36 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:15:36 INFO - 'ts_paint', 04:15:36 INFO - 'tpaint', 04:15:36 INFO - 'sessionrestore', 04:15:36 INFO - 'sessionrestore_no_auto_restore', 04:15:36 INFO - 'tabpaint']}, 04:15:36 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:15:36 INFO - 'tests': ['a11yr', 04:15:36 INFO - 'ts_paint', 04:15:36 INFO - 'tpaint', 04:15:36 INFO - 'sessionrestore', 04:15:36 INFO - 'sessionrestore_no_auto_restore', 04:15:36 INFO - 'tabpaint']}, 04:15:36 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:15:36 INFO - 'tests': ['a11yr', 04:15:36 INFO - 'ts_paint', 04:15:36 INFO - 'tpaint', 04:15:36 INFO - 'sessionrestore', 04:15:36 INFO - 'sessionrestore_no_auto_restore', 04:15:36 INFO - 'tabpaint']}, 04:15:36 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:15:36 INFO - 'tests': ['tsvgx', 04:15:36 INFO - 'tsvgr_opacity', 04:15:36 INFO - 'tart', 04:15:36 INFO - 'tscrollx', 04:15:36 INFO - 'cart']}, 04:15:36 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:15:36 INFO - 'tsvgr_opacity', 04:15:36 INFO - 'tart', 04:15:36 INFO - 'tscrollx', 04:15:36 INFO - 'cart']}, 04:15:36 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:15:36 INFO - 'talos_options': ['--disable-e10s'], 04:15:36 INFO - 'tests': ['tp5o']}, 04:15:36 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:15:36 INFO - 'tests': ['tp5o']}, 04:15:36 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:15:36 INFO - 'talos_options': ['--disable-e10s', 04:15:36 INFO - '--xperf_path', 04:15:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:15:36 INFO - 'tests': ['tp5n']}, 04:15:36 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:15:36 INFO - 'talos_options': ['--xperf_path', 04:15:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:15:36 INFO - 'tests': ['tp5n']}}, 04:15:36 INFO - 'talos.zip': {'path': '', 04:15:36 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:15:36 INFO - Running post-action listener: _resource_record_post_action 04:15:36 INFO - ##### 04:15:36 INFO - ##### Finished populate-webroot step (success) 04:15:36 INFO - ##### 04:15:36 INFO - ##### 04:15:36 INFO - ##### Running create-virtualenv step. 04:15:36 INFO - ##### 04:15:36 INFO - Running pre-action listener: _resource_record_pre_action 04:15:36 INFO - Running main action method: create_virtualenv 04:15:36 INFO - Creating virtualenv /builds/slave/test/build/venv 04:15:36 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 04:15:36 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:15:36 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:15:36 INFO - Using real prefix '/tools/python27' 04:15:36 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:15:37 INFO - Installing distribute.............................................................................................................................................................................................done. 04:15:40 INFO - Installing pip.................done. 04:15:40 INFO - Return code: 0 04:15:40 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:15:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:15:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:15:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:15:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:15:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:15:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:15:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e89ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e933618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd651dca740>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e802d30>, '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 0x10e567db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e7fab20>, '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.0mUfHgd1p4/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.tbJZE3IPuZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/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 04:15:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:15:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:15:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0mUfHgd1p4/Render', 04:15:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:15:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:15:40 INFO - 'HOME': '/Users/cltbld', 04:15:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:15:40 INFO - 'LOGNAME': 'cltbld', 04:15:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:15:40 INFO - 'MOZ_NO_REMOTE': '1', 04:15:40 INFO - 'NO_EM_RESTART': '1', 04:15:40 INFO - 'PAGER': '/bin/cat', 04:15:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:15:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:15:40 INFO - 'PWD': '/builds/slave/test', 04:15:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:15:40 INFO - 'SHELL': '/bin/bash', 04:15:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tbJZE3IPuZ/Listeners', 04:15:40 INFO - 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/', 04:15:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:15:40 INFO - 'USER': 'cltbld', 04:15:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:15:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:15:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:15:40 INFO - 'XPC_FLAGS': '0x0', 04:15:40 INFO - 'XPC_SERVICE_NAME': '0', 04:15:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:15:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:15:40 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 04:15:40 INFO - Cleaning up... 04:15:40 INFO - Return code: 0 04:15:40 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:15:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:15:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:15:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:15:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:15:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:15:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:15:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e89ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e933618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd651dca740>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e802d30>, '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 0x10e567db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e7fab20>, '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.0mUfHgd1p4/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.tbJZE3IPuZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/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 04:15:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:15:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:15:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0mUfHgd1p4/Render', 04:15:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:15:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:15:40 INFO - 'HOME': '/Users/cltbld', 04:15:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:15:40 INFO - 'LOGNAME': 'cltbld', 04:15:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:15:40 INFO - 'MOZ_NO_REMOTE': '1', 04:15:40 INFO - 'NO_EM_RESTART': '1', 04:15:40 INFO - 'PAGER': '/bin/cat', 04:15:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:15:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:15:40 INFO - 'PWD': '/builds/slave/test', 04:15:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:15:40 INFO - 'SHELL': '/bin/bash', 04:15:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tbJZE3IPuZ/Listeners', 04:15:40 INFO - 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/', 04:15:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:15:40 INFO - 'USER': 'cltbld', 04:15:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:15:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:15:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:15:40 INFO - 'XPC_FLAGS': '0x0', 04:15:40 INFO - 'XPC_SERVICE_NAME': '0', 04:15:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:15:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:15:41 INFO - Downloading/unpacking psutil>=3.1.1 04:15:41 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 04:15:41 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 04:15:41 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 04:15:41 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 04:15:41 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 04:15:41 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 04:15:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:15:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:15:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:15:44 INFO - Installing collected packages: psutil 04:15:44 INFO - Running setup.py install for psutil 04:15:44 INFO - building 'psutil._psutil_osx' extension 04:15:44 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 04:15:44 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 04:15:44 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 04:15:44 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 04:15:44 INFO - building 'psutil._psutil_posix' extension 04:15:44 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 04:15:44 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:15:44 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:15:44 INFO - ^ 04:15:44 INFO - 1 warning generated. 04:15:44 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 04:15:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:15:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:15:44 INFO - Successfully installed psutil 04:15:44 INFO - Cleaning up... 04:15:45 INFO - Return code: 0 04:15:45 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:15:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:15:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:15:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:15:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:15:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:15:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:15:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e89ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e933618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd651dca740>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e802d30>, '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 0x10e567db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e7fab20>, '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.0mUfHgd1p4/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.tbJZE3IPuZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/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 04:15:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:15:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 04:15:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0mUfHgd1p4/Render', 04:15:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:15:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:15:45 INFO - 'HOME': '/Users/cltbld', 04:15:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:15:45 INFO - 'LOGNAME': 'cltbld', 04:15:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:15:45 INFO - 'MOZ_NO_REMOTE': '1', 04:15:45 INFO - 'NO_EM_RESTART': '1', 04:15:45 INFO - 'PAGER': '/bin/cat', 04:15:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:15:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:15:45 INFO - 'PWD': '/builds/slave/test', 04:15:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:15:45 INFO - 'SHELL': '/bin/bash', 04:15:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tbJZE3IPuZ/Listeners', 04:15:45 INFO - 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/', 04:15:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:15:45 INFO - 'USER': 'cltbld', 04:15:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:15:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:15:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:15:45 INFO - 'XPC_FLAGS': '0x0', 04:15:45 INFO - 'XPC_SERVICE_NAME': '0', 04:15:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:15:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:15:45 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:15:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:15:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:15:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:15:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:15:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:15:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:15:47 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:15:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:15:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:15:47 INFO - Installing collected packages: mozsystemmonitor 04:15:47 INFO - Running setup.py install for mozsystemmonitor 04:15:47 INFO - Successfully installed mozsystemmonitor 04:15:47 INFO - Cleaning up... 04:15:48 INFO - Return code: 0 04:15:48 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:15: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')]} 04:15:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:15:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:15: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')]} 04:15:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:15:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:15:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e89ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e933618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd651dca740>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e802d30>, '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 0x10e567db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e7fab20>, '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.0mUfHgd1p4/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.tbJZE3IPuZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/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 04:15:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 04:15:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 04:15:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0mUfHgd1p4/Render', 04:15:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:15:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:15:48 INFO - 'HOME': '/Users/cltbld', 04:15:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:15:48 INFO - 'LOGNAME': 'cltbld', 04:15:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:15:48 INFO - 'MOZ_NO_REMOTE': '1', 04:15:48 INFO - 'NO_EM_RESTART': '1', 04:15:48 INFO - 'PAGER': '/bin/cat', 04:15:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:15:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:15:48 INFO - 'PWD': '/builds/slave/test', 04:15:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:15:48 INFO - 'SHELL': '/bin/bash', 04:15:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tbJZE3IPuZ/Listeners', 04:15:48 INFO - 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/', 04:15:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:15:48 INFO - 'USER': 'cltbld', 04:15:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:15:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:15:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:15:48 INFO - 'XPC_FLAGS': '0x0', 04:15:48 INFO - 'XPC_SERVICE_NAME': '0', 04:15:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:15:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:15:48 INFO - Downloading/unpacking jsonschema==2.5.1 04:15:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:15:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:15:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:15:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:15:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:15:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:15:50 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:15:50 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:15:50 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 04:15:50 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 04:15:50 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 04:15:50 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 04:15:50 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 04:15:50 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 04:15:51 INFO - Downloading functools32-3.2.3-2.tar.gz 04:15:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:15:51 INFO - warning: no files found matching '*.txt' 04:15:51 INFO - no previously-included directories found matching 'build' 04:15:51 INFO - no previously-included directories found matching 'dist' 04:15:51 INFO - no previously-included directories found matching '.git*' 04:15:51 INFO - Installing collected packages: jsonschema, functools32 04:15:51 INFO - Running setup.py install for functools32 04:15:51 INFO - warning: no files found matching '*.txt' 04:15:51 INFO - no previously-included directories found matching 'build' 04:15:51 INFO - no previously-included directories found matching 'dist' 04:15:51 INFO - no previously-included directories found matching '.git*' 04:15:51 INFO - Successfully installed jsonschema functools32 04:15:51 INFO - Cleaning up... 04:15:51 INFO - Return code: 0 04:15:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:15:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:15:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:15:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:15:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:15:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:15:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:15:51 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 0x10e89ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e933618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd651dca740>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e802d30>, '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 0x10e567db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e7fab20>, '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.0mUfHgd1p4/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.tbJZE3IPuZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/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 04:15:51 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 04:15:51 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 04:15:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0mUfHgd1p4/Render', 04:15:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:15:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:15:51 INFO - 'HOME': '/Users/cltbld', 04:15:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:15:51 INFO - 'LOGNAME': 'cltbld', 04:15:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:15:51 INFO - 'MOZ_NO_REMOTE': '1', 04:15:51 INFO - 'NO_EM_RESTART': '1', 04:15:51 INFO - 'PAGER': '/bin/cat', 04:15:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:15:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:15:51 INFO - 'PWD': '/builds/slave/test', 04:15:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:15:51 INFO - 'SHELL': '/bin/bash', 04:15:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tbJZE3IPuZ/Listeners', 04:15:51 INFO - 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/', 04:15:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:15:51 INFO - 'USER': 'cltbld', 04:15:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:15:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:15:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:15:51 INFO - 'XPC_FLAGS': '0x0', 04:15:51 INFO - 'XPC_SERVICE_NAME': '0', 04:15:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:15:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:15:51 INFO - Downloading/unpacking blobuploader==1.2.4 04:15:51 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 04:15:51 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 04:15:51 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 04:15:51 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 04:15:51 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 04:15:51 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 04:15:54 INFO - Downloading blobuploader-1.2.4.tar.gz 04:15:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:15:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:15:54 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 04:15:54 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 04:15:54 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 04:15:54 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 04:15:54 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 04:15:54 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 04:15:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:15:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:15:54 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 04:15:54 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 04:15:54 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 04:15:54 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 04:15:54 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 04:15:54 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 04:15:55 INFO - Downloading docopt-0.6.1.tar.gz 04:15:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:15:55 INFO - Installing collected packages: blobuploader, requests, docopt 04:15:55 INFO - Running setup.py install for blobuploader 04:15:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:15:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:15:55 INFO - Running setup.py install for requests 04:15:56 INFO - Running setup.py install for docopt 04:15:56 INFO - Successfully installed blobuploader requests docopt 04:15:56 INFO - Cleaning up... 04:15:56 INFO - Return code: 0 04:15:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:15:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:15:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:15:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:15:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:15:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:15:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:15:56 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 0x10e89ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e933618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd651dca740>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e802d30>, '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 0x10e567db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e7fab20>, '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.0mUfHgd1p4/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.tbJZE3IPuZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/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 04:15:56 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 04:15:56 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 04:15:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0mUfHgd1p4/Render', 04:15:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:15:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:15:56 INFO - 'HOME': '/Users/cltbld', 04:15:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:15:56 INFO - 'LOGNAME': 'cltbld', 04:15:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:15:56 INFO - 'MOZ_NO_REMOTE': '1', 04:15:56 INFO - 'NO_EM_RESTART': '1', 04:15:56 INFO - 'PAGER': '/bin/cat', 04:15:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:15:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:15:56 INFO - 'PWD': '/builds/slave/test', 04:15:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:15:56 INFO - 'SHELL': '/bin/bash', 04:15:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tbJZE3IPuZ/Listeners', 04:15:56 INFO - 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/', 04:15:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:15:56 INFO - 'USER': 'cltbld', 04:15:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:15:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:15:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:15:56 INFO - 'XPC_FLAGS': '0x0', 04:15:56 INFO - 'XPC_SERVICE_NAME': '0', 04:15:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:15:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:15:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:15:56 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-ANg_in-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:15:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:15:56 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-NPCGgH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:15:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:15:56 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-NlpaV8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:15:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:15:56 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-wa3urL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:15:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:15:57 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-IrXiBU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:15:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:15:57 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-kZrxUb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:15:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:15:57 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-HtIFKP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:15:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:15:57 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-96VuTr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:15:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:15:57 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-u135A_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:15:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:15:57 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-F6N6Us-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:15:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:15:57 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-9CXemN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:15:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:15:57 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-Mxg2wk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:15:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:15:57 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-a2dX7k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:15:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:15:57 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-l2fbNq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:15:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:15:58 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-kIrmla-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:15:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:15:58 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-2pS8dn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:15:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:15:58 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-Sk1e65-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:15:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:15:58 INFO - Running setup.py install for manifestparser 04:15:58 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:15:58 INFO - Running setup.py install for mozcrash 04:15:58 INFO - Running setup.py install for mozdebug 04:15:58 INFO - Running setup.py install for mozdevice 04:15:59 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:15:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:15:59 INFO - Running setup.py install for mozfile 04:15:59 INFO - Running setup.py install for mozhttpd 04:15:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:15:59 INFO - Running setup.py install for mozinfo 04:15:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:15:59 INFO - Running setup.py install for mozInstall 04:15:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:15:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:15:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:15:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:15:59 INFO - Running setup.py install for mozleak 04:15:59 INFO - Running setup.py install for mozlog 04:15:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:15:59 INFO - Running setup.py install for moznetwork 04:16:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:16:00 INFO - Running setup.py install for mozprocess 04:16:00 INFO - Running setup.py install for mozprofile 04:16:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:16:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:16:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:16:00 INFO - Running setup.py install for mozrunner 04:16:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:16:00 INFO - Running setup.py install for mozscreenshot 04:16:00 INFO - Running setup.py install for moztest 04:16:00 INFO - Running setup.py install for mozversion 04:16:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:16:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:16:00 INFO - Cleaning up... 04:16:00 INFO - Return code: 0 04:16:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:16:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:16:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:16:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:16:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:16:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16:00 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 0x10e89ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e933618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd651dca740>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e802d30>, '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 0x10e567db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e7fab20>, '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.0mUfHgd1p4/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.tbJZE3IPuZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/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 04:16:00 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 04:16:00 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 04:16:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0mUfHgd1p4/Render', 04:16:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:16:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:16:00 INFO - 'HOME': '/Users/cltbld', 04:16:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:16:00 INFO - 'LOGNAME': 'cltbld', 04:16:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:16:00 INFO - 'MOZ_NO_REMOTE': '1', 04:16:00 INFO - 'NO_EM_RESTART': '1', 04:16:00 INFO - 'PAGER': '/bin/cat', 04:16:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:16:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:16:00 INFO - 'PWD': '/builds/slave/test', 04:16:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:16:00 INFO - 'SHELL': '/bin/bash', 04:16:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tbJZE3IPuZ/Listeners', 04:16:00 INFO - 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/', 04:16:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:16:00 INFO - 'USER': 'cltbld', 04:16:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:16:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:16:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:16:00 INFO - 'XPC_FLAGS': '0x0', 04:16:00 INFO - 'XPC_SERVICE_NAME': '0', 04:16:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:16:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:16:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:16:01 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-i9irHV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:16:01 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)) 04:16:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:16:01 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-cmzbfq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:16:01 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)) 04:16:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:16:01 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-6e0WsX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:16:01 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)) 04:16:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:16:01 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-kf_TgI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:16:01 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)) 04:16:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:16:01 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-n_y_7i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:16:01 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)) 04:16:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:16:01 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-PjIifL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:16:01 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)) 04:16:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:16:01 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-g64HKY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:16:01 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)) 04:16:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:16:01 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-5nBP7I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:16:02 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)) 04:16:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:16:02 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-znyZak-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:16:02 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)) 04:16:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:16:02 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-BrRzjK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:16:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:16:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:16:02 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-dXxOJN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:16:02 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)) 04:16:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:16:02 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-YJ2Qc8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:16:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 04:16:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:16:02 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-sCZX0j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:16:02 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)) 04:16:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:16:02 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-OEUaRP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:16:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 04:16:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:16:02 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-DdLllr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:16:02 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)) 04:16:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:16:02 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-tUrgIZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:16:02 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)) 04:16:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:16:02 INFO - Running setup.py (path:/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/pip-c525m1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:16:03 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)) 04:16:03 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)) 04:16:03 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)) 04:16:03 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)) 04:16:03 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)) 04:16:03 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)) 04:16:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:16: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 04:16: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 04:16: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 04:16: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 04:16: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 04:16: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 04:16:05 INFO - Downloading blessings-1.6.tar.gz 04:16:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:16:05 INFO - Installing collected packages: blessings 04:16:05 INFO - Running setup.py install for blessings 04:16:05 INFO - Successfully installed blessings 04:16:05 INFO - Cleaning up... 04:16:05 INFO - Return code: 0 04:16:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:16:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:16:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:16:06 INFO - Reading from file tmpfile_stdout 04:16:06 INFO - Current package versions: 04:16:06 INFO - blessings == 1.6 04:16:06 INFO - blobuploader == 1.2.4 04:16:06 INFO - docopt == 0.6.1 04:16:06 INFO - functools32 == 3.2.3-2 04:16:06 INFO - jsonschema == 2.5.1 04:16:06 INFO - manifestparser == 1.1 04:16:06 INFO - mozInstall == 1.12 04:16:06 INFO - mozcrash == 0.17 04:16:06 INFO - mozdebug == 0.1 04:16:06 INFO - mozdevice == 0.48 04:16:06 INFO - mozfile == 1.2 04:16:06 INFO - mozhttpd == 0.7 04:16:06 INFO - mozinfo == 0.9 04:16:06 INFO - mozleak == 0.1 04:16:06 INFO - mozlog == 3.2 04:16:06 INFO - moznetwork == 0.27 04:16:06 INFO - mozprocess == 0.23 04:16:06 INFO - mozprofile == 0.28 04:16:06 INFO - mozrunner == 6.12 04:16:06 INFO - mozscreenshot == 0.1 04:16:06 INFO - mozsystemmonitor == 0.3 04:16:06 INFO - moztest == 0.7 04:16:06 INFO - mozversion == 1.4 04:16:06 INFO - psutil == 3.1.1 04:16:06 INFO - requests == 1.2.3 04:16:06 INFO - wsgiref == 0.1.2 04:16:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:16: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')]} 04:16:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:16:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16: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')]} 04:16:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:16:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16:06 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 0x10e89ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e933618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd651dca740>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e802d30>, '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 0x10e567db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e7fab20>, '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.0mUfHgd1p4/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.tbJZE3IPuZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/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 04:16:06 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 04:16:06 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 04:16:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0mUfHgd1p4/Render', 04:16:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:16:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:16:06 INFO - 'HOME': '/Users/cltbld', 04:16:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:16:06 INFO - 'LOGNAME': 'cltbld', 04:16:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:16:06 INFO - 'MOZ_NO_REMOTE': '1', 04:16:06 INFO - 'NO_EM_RESTART': '1', 04:16:06 INFO - 'PAGER': '/bin/cat', 04:16:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:16:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:16:06 INFO - 'PWD': '/builds/slave/test', 04:16:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:16:06 INFO - 'SHELL': '/bin/bash', 04:16:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tbJZE3IPuZ/Listeners', 04:16:06 INFO - 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/', 04:16:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:16:06 INFO - 'USER': 'cltbld', 04:16:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:16:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:16:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:16:06 INFO - 'XPC_FLAGS': '0x0', 04:16:06 INFO - 'XPC_SERVICE_NAME': '0', 04:16:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:16:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:16:06 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)) 04:16:06 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)) 04:16:06 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)) 04:16:06 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)) 04:16:06 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)) 04:16:06 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)) 04:16:06 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)) 04:16:06 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)) 04:16:06 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)) 04:16:06 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)) 04:16:06 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)) 04:16:06 INFO - Cleaning up... 04:16:06 INFO - Return code: 0 04:16:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:16: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')]} 04:16:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:16:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16: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')]} 04:16:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:16:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:16: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e89ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e933618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd651dca740>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e802d30>, '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 0x10e567db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e7fab20>, '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.0mUfHgd1p4/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.tbJZE3IPuZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/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 04:16: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', 'jsonschema'] in /builds/slave/test/build 04:16: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 jsonschema 04:16:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0mUfHgd1p4/Render', 04:16:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:16:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:16:06 INFO - 'HOME': '/Users/cltbld', 04:16:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:16:06 INFO - 'LOGNAME': 'cltbld', 04:16:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:16:06 INFO - 'MOZ_NO_REMOTE': '1', 04:16:06 INFO - 'NO_EM_RESTART': '1', 04:16:06 INFO - 'PAGER': '/bin/cat', 04:16:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:16:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:16:06 INFO - 'PWD': '/builds/slave/test', 04:16:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:16:06 INFO - 'SHELL': '/bin/bash', 04:16:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tbJZE3IPuZ/Listeners', 04:16:06 INFO - 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/', 04:16:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:16:06 INFO - 'USER': 'cltbld', 04:16:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:16:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:16:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:16:06 INFO - 'XPC_FLAGS': '0x0', 04:16:06 INFO - 'XPC_SERVICE_NAME': '0', 04:16:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:16:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:16:06 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:16:06 INFO - Cleaning up... 04:16:06 INFO - Return code: 0 04:16:06 INFO - Running post-action listener: _resource_record_post_action 04:16:06 INFO - Running post-action listener: _start_resource_monitoring 04:16:06 INFO - Starting resource monitoring. 04:16:06 INFO - ##### 04:16:06 INFO - ##### Finished create-virtualenv step (success) 04:16:06 INFO - ##### 04:16:06 INFO - ##### 04:16:06 INFO - ##### Running install step. 04:16:06 INFO - ##### 04:16:06 INFO - Running pre-action listener: _resource_record_pre_action 04:16:06 INFO - Running main action method: install 04:16:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:16:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:16:07 INFO - Reading from file tmpfile_stdout 04:16:07 INFO - Detecting whether we're running mozinstall >=1.0... 04:16:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:16:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:16:07 INFO - Reading from file tmpfile_stdout 04:16:07 INFO - Output received: 04:16:07 INFO - Usage: mozinstall [options] installer 04:16:07 INFO - Options: 04:16:07 INFO - -h, --help show this help message and exit 04:16:07 INFO - -d DEST, --destination=DEST 04:16:07 INFO - Directory to install application into. [default: 04:16:07 INFO - "/builds/slave/test"] 04:16:07 INFO - --app=APP Application being installed. [default: firefox] 04:16:07 INFO - mkdir: /builds/slave/test/build/application 04:16:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 04:16:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 04:16:41 INFO - Reading from file tmpfile_stdout 04:16:41 INFO - Output received: 04:16:41 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 04:16:41 INFO - Running post-action listener: _resource_record_post_action 04:16:41 INFO - ##### 04:16:41 INFO - ##### Finished install step (success) 04:16:41 INFO - ##### 04:16:41 INFO - ##### 04:16:41 INFO - ##### Running run-tests step. 04:16:41 INFO - ##### 04:16:41 INFO - Running pre-action listener: _resource_record_pre_action 04:16:41 INFO - Running main action method: run_tests 04:16:41 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:16:41 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:16:41 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:16:41 INFO - 2016-07-12 04:16:41.825 screenresolution[2076:22318] starting screenresolution argv=screenresolution get 04:16:41 INFO - 2016-07-12 04:16:41.827 screenresolution[2076:22318] Display 0: 1600x1200x32@60 04:16:41 INFO - 2016-07-12 04:16:41.833 screenresolution[2077:22320] starting screenresolution argv=screenresolution list 04:16:41 INFO - Available Modes on Display 0 04:16:41 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:16:41 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:16:41 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:16:41 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:16:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:16:42 INFO - Intel Iris: 04:16:42 INFO - Chipset Model: Intel Iris 04:16:42 INFO - Type: GPU 04:16:42 INFO - Bus: Built-In 04:16:42 INFO - VRAM (Dynamic, Max): 1536 MB 04:16:42 INFO - Vendor: Intel (0x8086) 04:16:42 INFO - Device ID: 0x0a2e 04:16:42 INFO - Revision ID: 0x0009 04:16:42 INFO - Displays: 04:16:42 INFO - Display: 04:16:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:16:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:16:42 INFO - Display Serial Number: 1600x1200 60 04:16:42 INFO - Main Display: Yes 04:16:42 INFO - Mirror: Off 04:16:42 INFO - Online: Yes 04:16:42 INFO - Rotation: Supported 04:16:42 INFO - Return code: 0 04:16:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:16:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:16:42 INFO - Python 2.7.3 04:16:42 INFO - Return code: 0 04:16:42 INFO - grabbing minidump binary from tooltool 04:16:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:16:42 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e802d30>, '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 0x10e567db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e7fab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:16:42 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 04:16:42 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 04:16:42 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 04:16:42 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:16:42 INFO - Return code: 0 04:16:42 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:16:42 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:16:42 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:16:42 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:16:42 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:16:42 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/E6I7gtcPRc-B7Hf3ncDN8Q/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0360', '--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 04:16:42 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/E6I7gtcPRc-B7Hf3ncDN8Q/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0360 --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 04:16:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0mUfHgd1p4/Render', 04:16:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:16:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:16:42 INFO - 'HOME': '/Users/cltbld', 04:16:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:16:42 INFO - 'LOGNAME': 'cltbld', 04:16:42 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:16:42 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:16:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:16:42 INFO - 'MOZ_NO_REMOTE': '1', 04:16:42 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:16:42 INFO - 'NO_EM_RESTART': '1', 04:16:42 INFO - 'PAGER': '/bin/cat', 04:16:42 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:16:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:16:42 INFO - 'PWD': '/builds/slave/test', 04:16:42 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:16:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:16:42 INFO - 'SHELL': '/bin/bash', 04:16:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tbJZE3IPuZ/Listeners', 04:16:42 INFO - 'TMPDIR': '/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/', 04:16:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:16:42 INFO - 'USER': 'cltbld', 04:16:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:16:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:16:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:16:42 INFO - 'XPC_FLAGS': '0x0', 04:16:42 INFO - 'XPC_SERVICE_NAME': '0', 04:16:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:16:42 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/E6I7gtcPRc-B7Hf3ncDN8Q/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0360', '--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 04:16:42 INFO - mozversion application_buildid: 20160712020826 04:16:42 INFO - mozversion application_changeset: efc08ad3e8afd42499a1cd20ade4e7c0cd05e3a8 04:16:42 INFO - mozversion application_display_name: Nightly 04:16:42 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:16:42 INFO - mozversion application_name: Firefox 04:16:42 INFO - mozversion application_remotingname: firefox 04:16:42 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 04:16:42 INFO - mozversion application_vendor: Mozilla 04:16:42 INFO - mozversion application_version: 50.0a1 04:16:42 INFO - mozversion platform_buildid: 20160712020826 04:16:42 INFO - mozversion platform_changeset: efc08ad3e8afd42499a1cd20ade4e7c0cd05e3a8 04:16:42 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 04:16:42 INFO - mozversion platform_version: 50.0a1 04:16:42 INFO - using testdate: 1468322202 04:16:42 INFO - actual date: 1468322202 04:16:42 INFO - starting webserver on 'localhost:49357' 04:16:42 INFO - SUITE-START | Running 2 tests 04:16:42 INFO - TEST-START | dromaeo_css 04:16:42 INFO - operating with platform_type : mac_ 04:16:42 INFO - Initialising browser for dromaeo_css test... 04:16:42 INFO - TEST-INFO | started process 2087 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/tmp7kL8uu/profile http://localhost:49357/getInfo.html) 04:16:44 INFO - PROCESS | 2087 | __metrics Screen width/height:1600/1200 04:16:44 INFO - PROCESS | 2087 | colorDepth:24 04:16:44 INFO - PROCESS | 2087 | Browser inner width/height: 1024/689 04:16:44 INFO - PROCESS | 2087 | __metrics 04:16:45 INFO - PROCESS | 2087 | 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" 04:16:45 INFO - PROCESS | 2087 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:16:45 INFO - TEST-INFO | 2087: exit 0 04:16:45 INFO - Browser initialized. 04:16:45 INFO - Running cycle 1/1 for dromaeo_css test... 04:16:45 INFO - TEST-INFO | started process 2090 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/tmp7kL8uu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:16:58 INFO - PROCESS | 2090 | RSS: Main: 243478528 04:16:58 INFO - PROCESS | 2090 | 04:17:49 INFO - PROCESS | 2090 | 1468322269579 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] 04:20:00 INFO - PROCESS | 2090 | Cycle 1(1): loaded http://localhost:49357/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49357/tests/dromaeo/cssquery-ext.html) 04:20:01 INFO - PROCESS | 2090 | RSS: Main: 377880576 04:20:01 INFO - PROCESS | 2090 | 04:23:04 INFO - PROCESS | 2090 | Cycle 1(1): loaded http://localhost:49357/tests/dromaeo/cssquery-ext.html (next: http://localhost:49357/tests/dromaeo/cssquery-jquery.html) 04:23:05 INFO - PROCESS | 2090 | RSS: Main: 375558144 04:23:05 INFO - PROCESS | 2090 | 04:26:07 INFO - PROCESS | 2090 | Cycle 1(1): loaded http://localhost:49357/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49357/tests/dromaeo/cssquery-mootools.html) 04:26:08 INFO - PROCESS | 2090 | RSS: Main: 372461568 04:26:08 INFO - PROCESS | 2090 | 04:29:10 INFO - PROCESS | 2090 | Cycle 1(1): loaded http://localhost:49357/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49357/tests/dromaeo/cssquery-prototype.html) 04:29:11 INFO - PROCESS | 2090 | RSS: Main: 371134464 04:29:11 INFO - PROCESS | 2090 | 04:32:14 INFO - PROCESS | 2090 | Cycle 1(1): loaded http://localhost:49357/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49357/tests/dromaeo/cssquery-yui.html) 04:32:15 INFO - PROCESS | 2090 | RSS: Main: 340951040 04:32:15 INFO - PROCESS | 2090 | 04:35:18 INFO - PROCESS | 2090 | Cycle 1(1): loaded http://localhost:49357/tests/dromaeo/cssquery-yui.html (next: http://localhost:49357/tests/dromaeo/cssquery-dojo.html) 04:35:18 INFO - PROCESS | 2090 | RSS: Main: 387526656 04:35:18 INFO - PROCESS | 2090 | 04:35:18 INFO - PROCESS | 2090 | __start_tp_report 04:35:18 INFO - PROCESS | 2090 | _x_x_mozilla_page_load 04:35:18 INFO - PROCESS | 2090 | _x_x_mozilla_page_load_details 04:35:18 INFO - PROCESS | 2090 | |i|pagename|runs| 04:35:18 INFO - PROCESS | 2090 | |0;dojo.html;2541.8601976891273;3344.481605351171;3354;3382.238996225851;3409.8806541771028;5542.944570554293;5613.270274864267;5659.830205093854;5697.373288938214;5705.885882282347;5660.60375773696;5662.858428539289;5753.050746626802;5757.942420575793;5787.363390027093;5129.794808207667;5146.5110814472755;5191.428942816287;5200.375954885419;5263.499967503081;5265.973670131663;5296.3379577552805;5303.920441193385;5324.015057214408;5338.91991620126;5098.643094983334;5160.045391602565;5161.148410512261;5214.426413094576;5226.3989641191365;5134.589232861362;5158.845234642967;5159.509846564571;5203.8178663746585;5231.372235331746;5115.565176959963;5154.587632989352;5169.483051694838;5197.740112994373;5295;5537.169424586304;5690.943090569124;5762.884742305172;5780.855478613026;5803.129530570448;6119.1674507285425;6142.293636231846;6151.954167791486;6177.938220617782;6247.62514249142;6250.656213908233;6252.18721566198;6301.905471417932;6317.778877739257;6334.651594162319;5628.549716022709;5775.48020678141;5825.592208545403;5832.562557808096;5841.240638716982;6237.438630523275;6259.624422534663;6294.496440284766;6351.936480635227;6352.428281454691;6153.784617538365;6213.347598502182;6241.095041218998;6253.78111766086;6257.9687101564205;6138.294096178907;6330.113784070234;6338.778142764981;6345.175127233522;6352.428281454691;6080.14877917092;6106.339326077069;6124.938750612437;6128.961487065963;6138.059051571771;6212.347703491158;6215;6215.12988181655;6219.036049412348;6227.346128656426;4856.125897338513;4928.162212423897;4937.185364414903;4939.481354457802;4976.2038073908;4492.101579684011;4510.932336014962;4525.705829121097;4552.726836389827;4559.293309537027;5730.656160630292;5743.052396354638;5743.195952566644;5743.540516758733;5747.5114615257335;6165.506759459233;6169.413905678954;6255.468285195719;6268.216472940882;6290.21372328459;5723.77104915807;5742.626729262584;5751.252337196138;5754.683492407958;5778.537716982715;5453.045716999589;5454.372747134051;5472.534834539029;5478.808241711521;5489.066858633962;5597.488678056983;6215.06773983897;6273.466755325849;6301.212348456443;6343.55595108338;6164.291106522807;6273.341299163522;6306.306306306395;6315.242170939517;6325.588836725598;6317.96841015792;6324;6337.588056776295;6361.109444677656;6361.491080713625;6051.878962420639;6098.159935307505;6109.022556390956;6146.647737497743;6160.445559899451;6328;6328.968355158195;6336.3030066693545;6345.46063584582;6346.428821406095;6007.399260074132;6137.815865523863;6147;6174.9073763892675;6175.413335733098;5898.292204935436;5915.733791979285;5976.97011514957;6037.0642550404755;6062.605930614673;5870.442307980735;5892.7642894283745;5952.375000625046;5970.9701451492465;6033.78881739137;6124.43826824157;6126.855735137855;6142.969285153546;6145.539084568764;6155.199824023028;5756.30924289088;5820.592558520862;5915.260592425947;5960.8807823844145;5997 04:35:18 INFO - PROCESS | 2090 | |1;ext.html;426.27533193570935;460.76270720578907;472.71400802514466;517.7721802406941;535.2105644174843;30866;40559;41589.7920510397;41631.79184104079;41824.58175418245;42773.930651733644;42943.35584966228;43207.56792432075;43284;43380.132397352034;8170.714025009126;8644.610992505352;9759.072888075658;9795.314327997043;9806;8594.355423343242;8836.469811811312;8837.558122093902;8846.867296990551;11705;11689.941550292278;11963.46164422603;11986.340751258676;12013.219140755822;12023.278603283787;11885.821712674273;11961.10291728124;11974.521019159223;11976.520939162467;11996.340201288926;8623.13768623139;8808.911910880874;8815.955920220422;11786;11903.702407439796;31975.840120799247;39881;40057.79971100126;40149.397759033636;42593;3520.4543295789194;3528.153243221634;3548.532865889702;3591.4612808078737;3975.662068724162;30143.547846782316;31172.844135779404;31898.362032759447;31960.20099497508;31990;3274.8035117893;3307.603087629476;3328.617209020945;3359;3379.932401351959;411746.99999999703;415494;416382;416880;418181;58044.70977645085;58336.41663583395;58455.41544584585;58477.70761146252;58580.4141958575;60726.696366517885;60972.08541871876;61019.69490152521;61179.69410152921;61218.693906530185;14993.875459340594;16309.83690163107;16527.752083718755;16531.00813951167;16536.586585335463;11670.241434306743;11945.581904633296;11957.402129893644;11959.461824217931;11966.521339146511;13381.799273010913;16134;16483;16892.746608800877;16923.323067077425;4894.105461299805;7405.518896220778;7574.469787114849;7654.770356889191;7715.729949451855;865.8701194820701;1001.2590682894605;1033.2870319479534;1035.7255327338262;1107.5182295701395;28066.71933280682;28475.715242847305;28511.144665660064;30738.38523229523;31275.21811954674;909.7763508081752;938.5213541093955;1003.2074661017714;1032.5766435761302;1110.9777804443866;15897.761533577006;15923.681526369408;16110.597235068983;16135.331154857586;16174.066909592451;689643;691664;697442;700143;702332;59839;60401.39598604046;60622.69688651529;60714.696426517585;60883.47791305253;1243.4155946705034;1292.3473645808806;1383.7370899529058;1393.6794537256487;1421.2112277685776;28317.85841070864;32737;32846.507302390004;33005.50491742578;33971.83014084914;56637.15044274257;56873.57816054466;57491;57679.55801104838;57684.42315576791;642862;646346;647199;647537;647666;33881.49177762385;34065.48901766426;36452.45321320235;42271.365929510466;42394.7281581553;38690.4196437048;40009;40453.595464044985;41960;47212;40298.798506008454;40643.593564065166;40785;40965;41766.37350439685;39600;39616;40184.799076005605;40185;40387.798061009504;38252.426213607374;38885.6111438882;39069.41395879007;39083.609163908;39089.609103908595;34134.40696799502;34270.48594271038;34969.65030349664;35073.1231719209;35096.64903351036;24595.754042459346;24934.875325623256;25170;25610.615840762777;25694.614580781676 04:35:18 INFO - PROCESS | 2090 | |2;jquery.html;318.8294262569525;359.34599029765803;384.15294276121153;394.8420631747301;409.64974946420807;12976.675583110415;17887.910560447195;21235.68146477804;21598.81206533642;21721;17621.55946101348;21191.728496290194;21611.351659450203;21739.695618262915;21967.780322196773;5246.658967167141;5780.797672081478;6021.036634138539;6040.516758659297;6047.939520604804;5927.703614819264;5983.192269043689;6027.578069535134;6027.758889644409;6028.819135425944;5757.481826635602;5985.910211346833;5992.49063829575;6072.939270607304;6103.023516237403;6011.117270724995;6027.126066720323;6053.969730151365;6061.787837425713;6067;5662.66024038559;5822.883542329172;5823.126531020339;5823.2138661280605;5834.445727655866;11985.22096063753;12099.758004839941;12196.756064878742;13057.738845223137;13335;2167.7615462299136;2432.72023717273;2730.470936275672;2954.335274563206;3137.952930706041;9216.170544651013;10021.847487538953;10192.643257485952;10331.94834025825;10375.206520816835;1866.8413184879303;1894.7063205203078;1912.6652835753687;1935.7123970405478;1957.119296316649;78992.42015159664;87483.56258218733;121886.3905680466;123187;126350.36824815816;9813.264005199639;10852;11163.15807891705;11190.66428007161;11391.829122563167;14089.71820563583;14122.223277719619;14165.78751318731;14169.504067357593;14173.433062677377;7017.578945263301;8328.500289982621;8536;8604.5697715114;8655.17775811297;8575.1424857515;8575.356848236408;8583.313334933191;8718.302535797247;8726.476411415335;4454.866354009385;4536.206163921301;4541.364209010741;4571.22289210835;4618.076384723069;1592.7371983622804;1882.9623407531772;1929.5465565592074;1932.9903350483157;1949.9317523886596;695.600029982762;859.5401460218878;961.5576834655997;1030.6289735695143;1049.732318258839;20722.481937951372;21273.468163295733;21332;21389.46526336854;21408.78591214068;917.4311926605413;970.5389939778548;984.7193549838262;984.7641210281195;999.6001599360314;8814.559272036373;10588.15294776416;11006.614768483065;11031.503582338813;11071.501782419811;576654;602756;605436;623158;630865;148820;149355;152044;153417;157955;852.4970267542055;957.2772556719772;988.2143695761887;1000.6147633161286;1004.4174378860243;15253.466128685443;19132.61734765269;19149.61700765949;19179.94510301947;19183.616327673088;59031;59053.70473147607;59394.10908836284;59667;60205.698971506616;86238;86506;86764;87495.56252218699;88973;5411.485908838654;5723.656580605177;6122.663253521099;7992.600369981594;8169.5098294102545;10996.945015275192;12068.974137198436;12861.871381286068;12878.420471078636;13080.28058456794;10836.945815270874;10963.287386319864;11094.44527773592;11097.112231021338;11202.495887685236;10824.837627435438;10890.509927053143;11059.059979901798;11248;11322.94338528302;11174.944125279322;11330.716732081433;11339;11401.429928503707;11527.88472115268;8029.8394032120195;8057.19428057213;8060.556669383241;8086.757397278091;8152.755417337489;10165.390076595428;11150.386728729673;11249.212555121063;11301.660950171508;11326.660200194008 04:35:18 INFO - PROCESS | 2090 | |3;mootools.html;1554.712378210031;1584.9856092101054;1776.760137381454;1910.2931915191382;1914.7223652570378;8298.294644955178;8531.658733650647;8694.347923905716;8717.389782715205;8718.258947989421;7670.07959044914;7869.606519674007;8286.709965151222;8454.830903381944;8528.317734581235;2303.8156947444204;2326.9534609307802;2329.638905969569;2331.9650205246935;2339.5203983183405;2283.7145356830397;2310.0759696121518;2316.756740542244;2359.504504054142;2371.3952941999764;1675.7653928450027;2137.561799831031;2271.0802125139344;2343.7070366204225;2346.2022912209845;2319.7332306784765;2325.337278875529;2343.5078633486987;2351.259353303704;2353.4822339085545;2318.547883162784;2321.6749655048307;2326.2439707095264;2327.9883600582057;2329.77867102625;8610.354223433267;8657.523836189008;8660.740177794676;8669.739907802776;8689.087645797163;5040.59675225981;5255.86860328491;5260.842174734764;5262.394824595182;5270.2358158067245;3452.378571857065;3529.6117427083;3578.7494875358734;3620.456931460276;3632.4188129899353;902.2826852652091;1068.471106802138;1088.684281558347;1109.3842917180962;1111.5442668505955;11240.887591124067;11254.0996720263;11268.605598804003;11285.661430157108;11296.322220666703;4492.3710680504755;4590.495045544987;4721.480637129913;4721.6458765592715;4722.763861806965;9698.418094914328;9703.466309352913;9705.951470242604;9711.951440242754;9717.61129554824;5237.3453318335205;5241.947580524222;5246.186841039645;5249.658772179796;5287.735613219324;4690.319903613957;4792.065547218259;4806.7836947337455;4811.133995880705;4842.055799119138;3426.9657303427143;3468.271662950758;3508.017755028596;3525.5945566259697;3527.5766907971206;1103.172620534599;1145.9828102578467;1240.503798480597;1245.7383949370733;1267.5690265309838;719.2124623537219;757.6552668535803;843.3632607381511;890.5324704530174;926.6108234541508;5655.406182350876;5659.915101273402;5758.913616295759;5812.476877080999;5846.630565283895;891.3804905590553;910.5902343945252;920.4293338130402;924.7965447601517;951.5432592355708;3763.172102137525;3836.7825922647803;3862.9613703863165;3888.630580094943;3903.590123037097;10133.695989120339;10368;10549.20880933933;10571.418571978615;10608.787824243473;8718.346124040723;9590.520473976274;10405.011523905217;10523.421211833424;10534.157267418588;7916.960415197887;8048.195180482022;8079.192080791991;8080.595970201466;8081.151479094728;5743.827685169452;5799.94200057994;5870.324912635102;5919.171316015847;5992.221011268507;9341.346105772802;9737.707868763948;9738.318317717974;9747.561359738971;9767.023297670461;9987.101160895554;10021.99780021992;10029.648962286285;10047.799044018933;10050.949745251228;7348.1182258129365;7357.889631655423;7365.189829118685;7370.5946172961;7370.815729606803;7853.607319633881;7926.524408535276;7941.761747147595;8027.719029834161;8031.397645176518;7062.505624606229;7086.574805511686;7151.678174482265;7347.706091756484;7358.668859901424;7058.153021637437;7059.5764254144915;7111.253318401494;7192.460565457507;7198.20819709842;6850.486213533905;6901.378875901193;6905.689243983932;6979.581225126509;6988.895166572607;4762;4762.833300834454;4822.662413631152;4838.395200599925;4858;4341.45731783527;4502.684812063124;4515;4590.816367345269;4720.622350212044 04:35:18 INFO - PROCESS | 2090 | |4;prototype.html;248.0647957201352;264.362885446075;276.3671192968103;314.81425958684383;317.79184634064666;4382.5398333175;4507.346434766957;4939.604831613471;5130.127878260695;5453.863653408666;4325.32957391604;4937.370667679666;5341.679499230049;5353.250544923714;5393.919091213635;3687.225682606656;4057.6145266199665;4103.363978583325;4112.437273835938;4114.91770164598;3739.420389839579;3863.3625451800417;4139.275626765319;4158.708890377675;4192.077742896573;4060.167665628539;4092.7544347339253;4095.795210239491;4099.8770036898795;4113.794310284474;3330.900072997802;4045.3527435610163;4079.306517891936;4091.2022155679656;4103.4050062740735;3113.723373416911;3215.646278909318;3257.299680568689;3568.678818906311;3575.6781889629797;4545.954540454586;5138.871528211787;5139.486051394868;5164.8967020659375;5193.402758682762;4599.563041511051;4633.887866911917;4638.165130276549;4638.837640682594;4641.466231383401;4606.930896036562;4741.407324084489;5391.032273220286;5659.066254068074;5762.942370576325;3075.400296942097;3878.592747761501;3886.7667939923695;3890;3909.3940439231965;4818.98801251741;4952.504749525055;5929.584929054968;5948.583599148062;6000.729967151488;3661.9633803662155;3917.7845218512684;3938.586448422874;3947.5262968443394;3949.960500395017;3321.285923526453;3447.7243419934284;3957.56466788653;3965.4448377226636;3971.980140099281;3846.4614953906475;4459.554044595579;4655.813767449332;4663.813447462064;4669.486356500782;5362.463753624685;5418.810341638024;5687.317521897398;5710.857228569319;5755.5683323750045;1148.8281952408497;1278.4310981613278;1370.6093763277418;1376.7521846067805;1399.818023656918;745.6346390268837;791.7545560876032;809.0909538316151;824.9811482818714;832.0182185021612;3629.9818500907327;3912.4131380293184;3915.686745060388;3990.563397177014;4377.365282034088;3858.5176852893387;4188.5811418857875;4454.910901781946;4809.519048095232;4810.759462026885;4370.562943705604;4395.099004704028;4405.669574781904;4405.713628614129;4421.867343979686;2315.1594482386545;2373.8694371809715;2548.668673072526;2579.625954236625;2589.365605426645;4007.7394969327484;4914.198906623191;4950.603951683856;4992.450830408652;5009.520287457732;4196.79668174211;4314.71954322968;4326.610605045561;4483.377224323807;4938.950610493921;4739.702050442725;4845.238214217932;4955.900881982412;4989.900201996013;5015.144396573219;3837.501124853751;4408.140412619508;4423.996283363561;4542.7955741992355;4768.666193366431;4120.2583534964;4569.497355290981;4741.644376671832;4799.592034676952;4850.53919877611;4231.4710661167355;4488.214562451492;4823.517648235148;4999;5020.824271150492;3407.7796482324957;3525.9598418466103;3583.623719509414;3658.5061016762397;3786.1859700163495;5226.372835259641;5751.971240143773;5778.682172480554;5785.30576330843;5816.127580862904;5142.820001299937;5588.2455868458865;5615.522680572197;5676.5742569308295;5838.94161058384;5286.841394758163;5619.325680918316;5626.718664066862;5629.4652008059165;5677.842419880419;4953.529414705597;5063.050725470646;5444.809431670031;5464.087569350695;5521.337439507285;5332.33345831771;5489.090781885607;5594.328680558359;5607.859803504945;5666.320041595035;5172.9741351293005;5241.850841155108;5347.411784703601;5464.3442786865835;5471.234027236111 04:35:18 INFO - PROCESS | 2090 | |5;yui.html;728.683022885045;738.7008261654032;754.532190042174;790.9446338756289;795.7188925829417;14407.855921440783;15202.087874727496;15318.310676019579;15364.001339912911;16664;15047.623809404771;15085.472008479705;15232.69534609307;15262.694746105099;15279.388824447009;701.2356531380794;726.0452504955992;728.1189760389934;889.8264838356523;898.5013317608757;719.3202423709571;721.3219573600825;722.8734971380014;875.3609864798701;919.6183583812709;952.8380175370204;957.1863915671659;958.7028021313415;959.52023988006;962.1148543340144;956.1920177450044;956.5312996631635;957.3824882950528;958.2382006305004;959.9280053995908;545.6507834985613;683.931606839312;707.056080133023;711.0258945244996;711.9715211391538;15542.300596473186;15563.299651515708;15574.766378504331;15592.532224033297;15649.374025038986;14166.858331416657;14437.205953672545;14467.204303763288;14530.893020512072;14689.706205875822;12906.096573239876;12956.322204452279;13033.283169425678;13079.41142648583;13253;4570.954290457086;4600.217962946307;4601.976990115061;4604.424446944132;4764.7617619119255;112312;112521;117607;120498.3975080119;121014;11304.378259195822;11383.69491340433;11437.59968401102;11511.827322590168;11582.536698531965;19157.521061973563;23082.65376019361;23092.65361019586;23172.65241021386;23189.376743627785;7907.723229686933;7926.8810967835525;7943.682252709942;8114.553699546582;8173.386995975326;4371.278739008028;4549.772511374418;4641.767911604406;4666.95333046672;4687.460941991646;3549.219171782255;3661.908452288714;3663.816809159531;3665.157013886821;3668.825975687754;633.5976654824128;655.9639219842859;657.4214691071921;667.5894324990096;678.5792808458689;576.8356018534697;599.9040153575494;610.5970059760625;624.8312955501989;631.4853394483481;8533.914660853436;8761.86095807541;8820.117988201257;8892.480207811688;8960.775980600538;574.2047264538644;602.3193791016124;608.5869527324181;616.1158737212115;622.1383384013084;3783.4703141560203;4021.1650786196246;4208.22147902639;4437.800298986554;4446.133004064176;92516;121484.39257803831;130805;142924;153799.23100384427;86525.1347486517;108976.91023089825;126264;127774;130011.34994324968;94830;100421;100887;111946;114522;9318.580663870278;9416.576254068721;9424.528773561158;9467.621295148392;9472;58415.415845840995;60105.69947150236;63713.36286637074;64132;65122.67438662776;92838;94200;95661.82536309665;96311;99486;12805.487780488676;13054.477820887058;13261.204327740366;13905.791413129013;14049.016568840492;14241.786373204617;14522.49171279;14604.561863144123;14632.341544630304;14948.925255374088;12914.22514649124;13278.203307801563;13478.73042539124;13544.187348759106;13596.048276620542;13160;13205.33973301312;13219.074664773374;13849.16904985704;14122.717545649235;12874.970002399972;12905.032122590655;12973.286469243905;13079.934600326937;13243.80134297967;10699.786004280026;11232.83150752723;11260.94369528147;11302.547898083985;11361.715957101138;10955.233133680566;11009.119270458505;11073.117570012955;11126.721831954266;11483.770324593628 04:35:18 INFO - PROCESS | 2090 | __end_tp_report 04:35:18 INFO - PROCESS | 2090 | __start_cc_report 04:35:18 INFO - PROCESS | 2090 | _x_x_mozilla_cycle_collect,1400 04:35:18 INFO - PROCESS | 2090 | __end_cc_report 04:35:18 INFO - PROCESS | 2090 | __startTimestamp1468323318882__endTimestamp 04:35:18 INFO - PROCESS | 2090 | 04:35:18 INFO - PROCESS | 2090 | ------- Summary: start ------- 04:35:18 INFO - PROCESS | 2090 | Number of tests: 6 04:35:18 INFO - PROCESS | 2090 | 04:35:18 INFO - PROCESS | 2090 | [#0] dojo.html Cycles:165 Average:5747.15 Median:5915.73 stddev:654.10 (11.1%) stddev-sans-first:605.86 04:35:18 INFO - PROCESS | 2090 | Values: 2541.9 3344.5 3354.0 3382.2 3409.9 5542.9 5613.3 5659.8 5697.4 5705.9 5660.6 5662.9 5753.1 5757.9 5787.4 5129.8 5146.5 5191.4 5200.4 5263.5 5266.0 5296.3 5303.9 5324.0 5338.9 5098.6 5160.0 5161.1 5214.4 5226.4 5134.6 5158.8 5159.5 5203.8 5231.4 5115.6 5154.6 5169.5 5197.7 5295.0 5537.2 5690.9 5762.9 5780.9 5803.1 6119.2 6142.3 6152.0 6177.9 6247.6 6250.7 6252.2 6301.9 6317.8 6334.7 5628.5 5775.5 5825.6 5832.6 5841.2 6237.4 6259.6 6294.5 6351.9 6352.4 6153.8 6213.3 6241.1 6253.8 6258.0 6138.3 6330.1 6338.8 6345.2 6352.4 6080.1 6106.3 6124.9 6129.0 6138.1 6212.3 6215.0 6215.1 6219.0 6227.3 4856.1 4928.2 4937.2 4939.5 4976.2 4492.1 4510.9 4525.7 4552.7 4559.3 5730.7 5743.1 5743.2 5743.5 5747.5 6165.5 6169.4 6255.5 6268.2 6290.2 5723.8 5742.6 5751.3 5754.7 5778.5 5453.0 5454.4 5472.5 5478.8 5489.1 5597.5 6215.1 6273.5 6301.2 6343.6 6164.3 6273.3 6306.3 6315.2 6325.6 6318.0 6324.0 6337.6 6361.1 6361.5 6051.9 6098.2 6109.0 6146.6 6160.4 6328.0 6329.0 6336.3 6345.5 6346.4 6007.4 6137.8 6147.0 6174.9 6175.4 5898.3 5915.7 5977.0 6037.1 6062.6 5870.4 5892.8 5952.4 5971.0 6033.8 6124.4 6126.9 6143.0 6145.5 6155.2 5756.3 5820.6 5915.3 5960.9 5997.0 04:35:18 INFO - PROCESS | 2090 | 04:35:18 INFO - PROCESS | 2090 | [#1] ext.html Cycles:180 Average:72205.79 Median:39608.00 stddev:160390.06 (404.9%) stddev-sans-first:160749.45 04:35:18 INFO - PROCESS | 2090 | Values: 426.3 460.8 472.7 517.8 535.2 30866.0 40559.0 41589.8 41631.8 41824.6 42773.9 42943.4 43207.6 43284.0 43380.1 8170.7 8644.6 9759.1 9795.3 9806.0 8594.4 8836.5 8837.6 8846.9 11705.0 11689.9 11963.5 11986.3 12013.2 12023.3 11885.8 11961.1 11974.5 11976.5 11996.3 8623.1 8808.9 8816.0 11786.0 11903.7 31975.8 39881.0 40057.8 40149.4 42593.0 3520.5 3528.2 3548.5 3591.5 3975.7 30143.5 31172.8 31898.4 31960.2 31990.0 3274.8 3307.6 3328.6 3359.0 3379.9 411747.0 415494.0 416382.0 416880.0 418181.0 58044.7 58336.4 58455.4 58477.7 58580.4 60726.7 60972.1 61019.7 61179.7 61218.7 14993.9 16309.8 16527.8 16531.0 16536.6 11670.2 11945.6 11957.4 11959.5 11966.5 13381.8 16134.0 16483.0 16892.7 16923.3 4894.1 7405.5 7574.5 7654.8 7715.7 865.9 1001.3 1033.3 1035.7 1107.5 28066.7 28475.7 28511.1 30738.4 31275.2 909.8 938.5 1003.2 1032.6 1111.0 15897.8 15923.7 16110.6 16135.3 16174.1 689643.0 691664.0 697442.0 700143.0 702332.0 59839.0 60401.4 60622.7 60714.7 60883.5 1243.4 1292.3 1383.7 1393.7 1421.2 28317.9 32737.0 32846.5 33005.5 33971.8 56637.2 56873.6 57491.0 57679.6 57684.4 642862.0 646346.0 647199.0 647537.0 647666.0 33881.5 34065.5 36452.5 42271.4 42394.7 38690.4 40009.0 40453.6 41960.0 47212.0 40298.8 40643.6 40785.0 40965.0 41766.4 39600.0 39616.0 40184.8 40185.0 40387.8 38252.4 38885.6 39069.4 39083.6 39089.6 34134.4 34270.5 34969.7 35073.1 35096.6 24595.8 24934.9 25170.0 25610.6 25694.6 04:35:18 INFO - PROCESS | 2090 | 04:35:18 INFO - PROCESS | 2090 | [#2] jquery.html Cycles:180 Average:35675.54 Median:2581.60 stddev:102164.04 (3957.4%) stddev-sans-first:102416.14 04:35:18 INFO - PROCESS | 2090 | Values: 318.8 359.3 384.2 394.8 409.6 12976.7 17887.9 21235.7 21598.8 21721.0 17621.6 21191.7 21611.4 21739.7 21967.8 5246.7 5780.8 6021.0 6040.5 6047.9 5927.7 5983.2 6027.6 6027.8 6028.8 5757.5 5985.9 5992.5 6072.9 6103.0 6011.1 6027.1 6054.0 6061.8 6067.0 5662.7 5822.9 5823.1 5823.2 5834.4 11985.2 12099.8 12196.8 13057.7 13335.0 2167.8 2432.7 2730.5 2954.3 3138.0 9216.2 10021.8 10192.6 10331.9 10375.2 1866.8 1894.7 1912.7 1935.7 1957.1 78992.4 87483.6 121886.4 123187.0 126350.4 9813.3 10852.0 11163.2 11190.7 11391.8 14089.7 14122.2 14165.8 14169.5 14173.4 7017.6 8328.5 8536.0 8604.6 8655.2 8575.1 8575.4 8583.3 8718.3 8726.5 4454.9 4536.2 4541.4 4571.2 4618.1 1592.7 1883.0 1929.5 1933.0 1949.9 695.6 859.5 961.6 1030.6 1049.7 20722.5 21273.5 21332.0 21389.5 21408.8 917.4 970.5 984.7 984.8 999.6 8814.6 10588.2 11006.6 11031.5 11071.5 576654.0 602756.0 605436.0 623158.0 630865.0 148820.0 149355.0 152044.0 153417.0 157955.0 852.5 957.3 988.2 1000.6 1004.4 15253.5 19132.6 19149.6 19179.9 19183.6 59031.0 59053.7 59394.1 59667.0 60205.7 86238.0 86506.0 86764.0 87495.6 88973.0 5411.5 5723.7 6122.7 7992.6 8169.5 10996.9 12069.0 12861.9 12878.4 13080.3 10836.9 10963.3 11094.4 11097.1 11202.5 10824.8 10890.5 11059.1 11248.0 11322.9 11174.9 11330.7 11339.0 11401.4 11527.9 8029.8 8057.2 8060.6 8086.8 8152.8 10165.4 11150.4 11249.2 11301.7 11326.7 04:35:18 INFO - PROCESS | 2090 | 04:35:18 INFO - PROCESS | 2090 | [#3] mootools.html Cycles:180 Average:5557.96 Median:4949.30 stddev:3100.11 (62.6%) stddev-sans-first:3094.21 04:35:18 INFO - PROCESS | 2090 | Values: 1554.7 1585.0 1776.8 1910.3 1914.7 8298.3 8531.7 8694.3 8717.4 8718.3 7670.1 7869.6 8286.7 8454.8 8528.3 2303.8 2327.0 2329.6 2332.0 2339.5 2283.7 2310.1 2316.8 2359.5 2371.4 1675.8 2137.6 2271.1 2343.7 2346.2 2319.7 2325.3 2343.5 2351.3 2353.5 2318.5 2321.7 2326.2 2328.0 2329.8 8610.4 8657.5 8660.7 8669.7 8689.1 5040.6 5255.9 5260.8 5262.4 5270.2 3452.4 3529.6 3578.7 3620.5 3632.4 902.3 1068.5 1088.7 1109.4 1111.5 11240.9 11254.1 11268.6 11285.7 11296.3 4492.4 4590.5 4721.5 4721.6 4722.8 9698.4 9703.5 9706.0 9712.0 9717.6 5237.3 5241.9 5246.2 5249.7 5287.7 4690.3 4792.1 4806.8 4811.1 4842.1 3427.0 3468.3 3508.0 3525.6 3527.6 1103.2 1146.0 1240.5 1245.7 1267.6 719.2 757.7 843.4 890.5 926.6 5655.4 5659.9 5758.9 5812.5 5846.6 891.4 910.6 920.4 924.8 951.5 3763.2 3836.8 3863.0 3888.6 3903.6 10133.7 10368.0 10549.2 10571.4 10608.8 8718.3 9590.5 10405.0 10523.4 10534.2 7917.0 8048.2 8079.2 8080.6 8081.2 5743.8 5799.9 5870.3 5919.2 5992.2 9341.3 9737.7 9738.3 9747.6 9767.0 9987.1 10022.0 10029.6 10047.8 10050.9 7348.1 7357.9 7365.2 7370.6 7370.8 7853.6 7926.5 7941.8 8027.7 8031.4 7062.5 7086.6 7151.7 7347.7 7358.7 7058.2 7059.6 7111.3 7192.5 7198.2 6850.5 6901.4 6905.7 6979.6 6988.9 4762.0 4762.8 4822.7 4838.4 4858.0 4341.5 4502.7 4515.0 4590.8 4720.6 04:35:18 INFO - PROCESS | 2090 | 04:35:18 INFO - PROCESS | 2090 | [#4] prototype.html Cycles:180 Average:4257.73 Median:4557.73 stddev:1303.31 (28.6%) stddev-sans-first:1271.75 04:35:18 INFO - PROCESS | 2090 | Values: 248.1 264.4 276.4 314.8 317.8 4382.5 4507.3 4939.6 5130.1 5453.9 4325.3 4937.4 5341.7 5353.3 5393.9 3687.2 4057.6 4103.4 4112.4 4114.9 3739.4 3863.4 4139.3 4158.7 4192.1 4060.2 4092.8 4095.8 4099.9 4113.8 3330.9 4045.4 4079.3 4091.2 4103.4 3113.7 3215.6 3257.3 3568.7 3575.7 4546.0 5138.9 5139.5 5164.9 5193.4 4599.6 4633.9 4638.2 4638.8 4641.5 4606.9 4741.4 5391.0 5659.1 5762.9 3075.4 3878.6 3886.8 3890.0 3909.4 4819.0 4952.5 5929.6 5948.6 6000.7 3662.0 3917.8 3938.6 3947.5 3950.0 3321.3 3447.7 3957.6 3965.4 3972.0 3846.5 4459.6 4655.8 4663.8 4669.5 5362.5 5418.8 5687.3 5710.9 5755.6 1148.8 1278.4 1370.6 1376.8 1399.8 745.6 791.8 809.1 825.0 832.0 3630.0 3912.4 3915.7 3990.6 4377.4 3858.5 4188.6 4454.9 4809.5 4810.8 4370.6 4395.1 4405.7 4405.7 4421.9 2315.2 2373.9 2548.7 2579.6 2589.4 4007.7 4914.2 4950.6 4992.5 5009.5 4196.8 4314.7 4326.6 4483.4 4939.0 4739.7 4845.2 4955.9 4989.9 5015.1 3837.5 4408.1 4424.0 4542.8 4768.7 4120.3 4569.5 4741.6 4799.6 4850.5 4231.5 4488.2 4823.5 4999.0 5020.8 3407.8 3526.0 3583.6 3658.5 3786.2 5226.4 5752.0 5778.7 5785.3 5816.1 5142.8 5588.2 5615.5 5676.6 5838.9 5286.8 5619.3 5626.7 5629.5 5677.8 4953.5 5063.1 5444.8 5464.1 5521.3 5332.3 5489.1 5594.3 5607.9 5666.3 5173.0 5241.9 5347.4 5464.3 5471.2 04:35:18 INFO - PROCESS | 2090 | 04:35:18 INFO - PROCESS | 2090 | [#5] yui.html Cycles:180 Average:24200.73 Median:3666.99 stddev:37526.21 (1023.4%) stddev-sans-first:37590.09 04:35:18 INFO - PROCESS | 2090 | Values: 728.7 738.7 754.5 790.9 795.7 14407.9 15202.1 15318.3 15364.0 16664.0 15047.6 15085.5 15232.7 15262.7 15279.4 701.2 726.0 728.1 889.8 898.5 719.3 721.3 722.9 875.4 919.6 952.8 957.2 958.7 959.5 962.1 956.2 956.5 957.4 958.2 959.9 545.7 683.9 707.1 711.0 712.0 15542.3 15563.3 15574.8 15592.5 15649.4 14166.9 14437.2 14467.2 14530.9 14689.7 12906.1 12956.3 13033.3 13079.4 13253.0 4571.0 4600.2 4602.0 4604.4 4764.8 112312.0 112521.0 117607.0 120498.4 121014.0 11304.4 11383.7 11437.6 11511.8 11582.5 19157.5 23082.7 23092.7 23172.7 23189.4 7907.7 7926.9 7943.7 8114.6 8173.4 4371.3 4549.8 4641.8 4667.0 4687.5 3549.2 3661.9 3663.8 3665.2 3668.8 633.6 656.0 657.4 667.6 678.6 576.8 599.9 610.6 624.8 631.5 8533.9 8761.9 8820.1 8892.5 8960.8 574.2 602.3 608.6 616.1 622.1 3783.5 4021.2 4208.2 4437.8 4446.1 92516.0 121484.4 130805.0 142924.0 153799.2 86525.1 108976.9 126264.0 127774.0 130011.3 94830.0 100421.0 100887.0 111946.0 114522.0 9318.6 9416.6 9424.5 9467.6 9472.0 58415.4 60105.7 63713.4 64132.0 65122.7 92838.0 94200.0 95661.8 96311.0 99486.0 12805.5 13054.5 13261.2 13905.8 14049.0 14241.8 14522.5 14604.6 14632.3 14948.9 12914.2 13278.2 13478.7 13544.2 13596.0 13160.0 13205.3 13219.1 13849.2 14122.7 12875.0 12905.0 12973.3 13079.9 13243.8 10699.8 11232.8 11260.9 11302.5 11361.7 10955.2 11009.1 11073.1 11126.7 11483.8 04:35:18 INFO - PROCESS | 2090 | -------- Summary: end -------- 04:35:18 INFO - PROCESS | 2090 | 04:35:19 INFO - TEST-INFO | 2090: exit 0 04:35:19 INFO - TEST-OK | dromaeo_css | took 1117067ms 04:35:19 INFO - TEST-START | kraken 04:35:19 INFO - operating with platform_type : mac_ 04:35:19 INFO - Initialising browser for kraken test... 04:35:19 INFO - TEST-INFO | started process 2095 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/tmpCkYs6G/profile http://localhost:49357/getInfo.html) 04:35:21 INFO - PROCESS | 2095 | __metrics Screen width/height:1600/1200 04:35:21 INFO - PROCESS | 2095 | colorDepth:24 04:35:21 INFO - PROCESS | 2095 | Browser inner width/height: 1024/689 04:35:21 INFO - PROCESS | 2095 | __metrics 04:35:22 INFO - PROCESS | 2095 | 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" 04:35:22 INFO - PROCESS | 2095 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:35:22 INFO - TEST-INFO | 2095: exit 0 04:35:22 INFO - Browser initialized. 04:35:22 INFO - Running cycle 1/1 for kraken test... 04:35:22 INFO - TEST-INFO | started process 2096 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/T/tmpCkYs6G/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:35:34 INFO - PROCESS | 2096 | RSS: Main: 243597312 04:35:34 INFO - PROCESS | 2096 | 04:35:37 INFO - PROCESS | 2096 | Cycle 1(1): loaded http://localhost:49357/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49357/tests/kraken/driver.html?testName=audio-beat-detection) 04:35:38 INFO - PROCESS | 2096 | RSS: Main: 294199296 04:35:38 INFO - PROCESS | 2096 | 04:35:40 INFO - PROCESS | 2096 | Cycle 1(1): loaded http://localhost:49357/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49357/tests/kraken/driver.html?testName=audio-dft) 04:35:41 INFO - PROCESS | 2096 | RSS: Main: 304910336 04:35:41 INFO - PROCESS | 2096 | 04:35:45 INFO - PROCESS | 2096 | Cycle 1(1): loaded http://localhost:49357/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49357/tests/kraken/driver.html?testName=audio-fft) 04:35:45 INFO - PROCESS | 2096 | RSS: Main: 389083136 04:35:45 INFO - PROCESS | 2096 | 04:35:47 INFO - PROCESS | 2096 | Cycle 1(1): loaded http://localhost:49357/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49357/tests/kraken/driver.html?testName=audio-oscillator) 04:35:48 INFO - PROCESS | 2096 | RSS: Main: 288788480 04:35:48 INFO - PROCESS | 2096 | 04:35:50 INFO - PROCESS | 2096 | Cycle 1(1): loaded http://localhost:49357/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49357/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:35:51 INFO - PROCESS | 2096 | RSS: Main: 292806656 04:35:51 INFO - PROCESS | 2096 | 04:35:53 INFO - PROCESS | 2096 | Cycle 1(1): loaded http://localhost:49357/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49357/tests/kraken/driver.html?testName=imaging-darkroom) 04:35:54 INFO - PROCESS | 2096 | RSS: Main: 330235904 04:35:54 INFO - PROCESS | 2096 | 04:35:57 INFO - PROCESS | 2096 | Cycle 1(1): loaded http://localhost:49357/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49357/tests/kraken/driver.html?testName=imaging-desaturate) 04:35:58 INFO - PROCESS | 2096 | RSS: Main: 330928128 04:35:58 INFO - PROCESS | 2096 | 04:36:00 INFO - PROCESS | 2096 | Cycle 1(1): loaded http://localhost:49357/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49357/tests/kraken/driver.html?testName=json-parse-financial) 04:36:01 INFO - PROCESS | 2096 | RSS: Main: 330780672 04:36:01 INFO - PROCESS | 2096 | 04:36:03 INFO - PROCESS | 2096 | Cycle 1(1): loaded http://localhost:49357/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49357/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:36:03 INFO - PROCESS | 2096 | RSS: Main: 311615488 04:36:03 INFO - PROCESS | 2096 | 04:36:05 INFO - PROCESS | 2096 | Cycle 1(1): loaded http://localhost:49357/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49357/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:36:06 INFO - PROCESS | 2096 | RSS: Main: 317992960 04:36:06 INFO - PROCESS | 2096 | 04:36:08 INFO - PROCESS | 2096 | Cycle 1(1): loaded http://localhost:49357/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49357/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:36:09 INFO - PROCESS | 2096 | RSS: Main: 315760640 04:36:09 INFO - PROCESS | 2096 | 04:36:11 INFO - PROCESS | 2096 | Cycle 1(1): loaded http://localhost:49357/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49357/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:36:12 INFO - PROCESS | 2096 | RSS: Main: 324009984 04:36:12 INFO - PROCESS | 2096 | 04:36:15 INFO - PROCESS | 2096 | Cycle 1(1): loaded http://localhost:49357/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49357/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:36:16 INFO - PROCESS | 2096 | RSS: Main: 326709248 04:36:16 INFO - PROCESS | 2096 | 04:36:17 INFO - PROCESS | 2096 | Cycle 1(1): loaded http://localhost:49357/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49357/tests/kraken/driver.html?testName=ai-astar) 04:36:18 INFO - PROCESS | 2096 | RSS: Main: 370171904 04:36:18 INFO - PROCESS | 2096 | 04:36:18 INFO - PROCESS | 2096 | __start_tp_report 04:36:18 INFO - PROCESS | 2096 | _x_x_mozilla_page_load 04:36:18 INFO - PROCESS | 2096 | _x_x_mozilla_page_load_details 04:36:18 INFO - PROCESS | 2096 | |i|pagename|runs| 04:36:18 INFO - PROCESS | 2096 | |0;ai-astar;103;106;112;111;112;112;112;112;112;112 04:36:18 INFO - PROCESS | 2096 | |1;audio-beat-detection;141;105;106;162;105;107;129;107;141;107 04:36:18 INFO - PROCESS | 2096 | |2;audio-dft;185;187;184;183;192;184;180;181;185;183 04:36:18 INFO - PROCESS | 2096 | |3;audio-fft;80;74;64;130;81;48;76;74;111;80 04:36:18 INFO - PROCESS | 2096 | |4;audio-oscillator;86;87;91;70;71;68;95;70;67;99 04:36:18 INFO - PROCESS | 2096 | |5;imaging-gaussian-blur;89;85;88;87;87;84;88;86;87;84 04:36:18 INFO - PROCESS | 2096 | |6;imaging-darkroom;94;93;93;94;93;94;94;94;92;92 04:36:18 INFO - PROCESS | 2096 | |7;imaging-desaturate;87;85;84;82;85;86;83;87;84;84 04:36:18 INFO - PROCESS | 2096 | |8;json-parse-financial;56;57;54;56;55;55;57;56;54;55 04:36:18 INFO - PROCESS | 2096 | |9;json-stringify-tinderbox;68;66;77;78;72;71;69;73;71;72 04:36:18 INFO - PROCESS | 2096 | |10;stanford-crypto-aes;83;83;83;82;85;85;85;83;93;91 04:36:18 INFO - PROCESS | 2096 | |11;stanford-crypto-ccm;137;138;138;140;147;152;129;136;223;139 04:36:18 INFO - PROCESS | 2096 | |12;stanford-crypto-pbkdf2;164;162;164;165;161;165;164;161;163;162 04:36:18 INFO - PROCESS | 2096 | |13;stanford-crypto-sha256-iterative;68;67;68;66;68;70;82;86;85;76 04:36:18 INFO - PROCESS | 2096 | __end_tp_report 04:36:18 INFO - PROCESS | 2096 | __start_cc_report 04:36:18 INFO - PROCESS | 2096 | _x_x_mozilla_cycle_collect,2026 04:36:18 INFO - PROCESS | 2096 | __end_cc_report 04:36:18 INFO - PROCESS | 2096 | __startTimestamp1468323378091__endTimestamp 04:36:18 INFO - PROCESS | 2096 | 04:36:18 INFO - PROCESS | 2096 | ------- Summary: start ------- 04:36:18 INFO - PROCESS | 2096 | Number of tests: 14 04:36:18 INFO - PROCESS | 2096 | 04:36:18 INFO - PROCESS | 2096 | [#0] ai-astar Cycles:10 Average:110.40 Median:112.00 stddev:3.20 (2.9%) stddev-sans-first:1.99 04:36:18 INFO - PROCESS | 2096 | Values: 103.0 106.0 112.0 111.0 112.0 112.0 112.0 112.0 112.0 112.0 04:36:18 INFO - PROCESS | 2096 | 04:36:18 INFO - PROCESS | 2096 | [#1] audio-beat-detection Cycles:10 Average:121.00 Median:118.00 stddev:20.74 (17.6%) stddev-sans-first:20.69 04:36:18 INFO - PROCESS | 2096 | Values: 141.0 105.0 106.0 162.0 105.0 107.0 129.0 107.0 141.0 107.0 04:36:18 INFO - PROCESS | 2096 | 04:36:18 INFO - PROCESS | 2096 | [#2] audio-dft Cycles:10 Average:184.40 Median:184.50 stddev:3.34 (1.8%) stddev-sans-first:3.54 04:36:18 INFO - PROCESS | 2096 | Values: 185.0 187.0 184.0 183.0 192.0 184.0 180.0 181.0 185.0 183.0 04:36:18 INFO - PROCESS | 2096 | 04:36:18 INFO - PROCESS | 2096 | [#3] audio-fft Cycles:10 Average:81.80 Median:75.00 stddev:23.09 (30.8%) stddev-sans-first:24.48 04:36:18 INFO - PROCESS | 2096 | Values: 80.0 74.0 64.0 130.0 81.0 48.0 76.0 74.0 111.0 80.0 04:36:18 INFO - PROCESS | 2096 | 04:36:18 INFO - PROCESS | 2096 | [#4] audio-oscillator Cycles:10 Average:80.40 Median:86.50 stddev:12.40 (14.3%) stddev-sans-first:12.99 04:36:18 INFO - PROCESS | 2096 | Values: 86.0 87.0 91.0 70.0 71.0 68.0 95.0 70.0 67.0 99.0 04:36:18 INFO - PROCESS | 2096 | 04:36:18 INFO - PROCESS | 2096 | [#5] imaging-gaussian-blur Cycles:10 Average:86.50 Median:87.00 stddev:1.72 (2.0%) stddev-sans-first:1.56 04:36:18 INFO - PROCESS | 2096 | Values: 89.0 85.0 88.0 87.0 87.0 84.0 88.0 86.0 87.0 84.0 04:36:18 INFO - PROCESS | 2096 | 04:36:18 INFO - PROCESS | 2096 | [#6] imaging-darkroom Cycles:10 Average:93.30 Median:94.00 stddev:0.82 (0.9%) stddev-sans-first:0.83 04:36:18 INFO - PROCESS | 2096 | Values: 94.0 93.0 93.0 94.0 93.0 94.0 94.0 94.0 92.0 92.0 04:36:18 INFO - PROCESS | 2096 | 04:36:18 INFO - PROCESS | 2096 | [#7] imaging-desaturate Cycles:10 Average:84.70 Median:85.00 stddev:1.64 (1.9%) stddev-sans-first:1.51 04:36:18 INFO - PROCESS | 2096 | Values: 87.0 85.0 84.0 82.0 85.0 86.0 83.0 87.0 84.0 84.0 04:36:18 INFO - PROCESS | 2096 | 04:36:18 INFO - PROCESS | 2096 | [#8] json-parse-financial Cycles:10 Average:55.50 Median:56.00 stddev:1.08 (1.9%) stddev-sans-first:1.13 04:36:18 INFO - PROCESS | 2096 | Values: 56.0 57.0 54.0 56.0 55.0 55.0 57.0 56.0 54.0 55.0 04:36:18 INFO - PROCESS | 2096 | 04:36:18 INFO - PROCESS | 2096 | [#9] json-stringify-tinderbox Cycles:10 Average:71.70 Median:72.00 stddev:3.71 (5.2%) stddev-sans-first:3.69 04:36:18 INFO - PROCESS | 2096 | Values: 68.0 66.0 77.0 78.0 72.0 71.0 69.0 73.0 71.0 72.0 04:36:18 INFO - PROCESS | 2096 | 04:36:18 INFO - PROCESS | 2096 | [#10] stanford-crypto-aes Cycles:10 Average:85.30 Median:85.00 stddev:3.71 (4.4%) stddev-sans-first:3.84 04:36:18 INFO - PROCESS | 2096 | Values: 83.0 83.0 83.0 82.0 85.0 85.0 85.0 83.0 93.0 91.0 04:36:18 INFO - PROCESS | 2096 | 04:36:18 INFO - PROCESS | 2096 | [#11] stanford-crypto-ccm Cycles:10 Average:147.90 Median:139.50 stddev:27.11 (19.4%) stddev-sans-first:28.46 04:36:18 INFO - PROCESS | 2096 | Values: 137.0 138.0 138.0 140.0 147.0 152.0 129.0 136.0 223.0 139.0 04:36:18 INFO - PROCESS | 2096 | 04:36:18 INFO - PROCESS | 2096 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.10 Median:164.00 stddev:1.52 (0.9%) stddev-sans-first:1.58 04:36:18 INFO - PROCESS | 2096 | Values: 164.0 162.0 164.0 165.0 161.0 165.0 164.0 161.0 163.0 162.0 04:36:18 INFO - PROCESS | 2096 | 04:36:18 INFO - PROCESS | 2096 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.60 Median:73.00 stddev:7.95 (10.9%) stddev-sans-first:8.17 04:36:18 INFO - PROCESS | 2096 | Values: 68.0 67.0 68.0 66.0 68.0 70.0 82.0 86.0 85.0 76.0 04:36:18 INFO - PROCESS | 2096 | -------- Summary: end -------- 04:36:18 INFO - PROCESS | 2096 | 04:36:18 INFO - TEST-INFO | 2096: exit 0 04:36:18 INFO - TEST-OK | kraken | took 59467ms 04:36:18 INFO - SUITE-END | took 1176s 04:36:19 INFO - Completed test suite (00:19:36) 04:36:19 INFO - javascript benchmark 04:36:19 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2541.8601976891273, 3344.481605351171, 3354.0, 3382.238996225851, 3409.8806541771028, 5542.944570554293, 5613.270274864267, 5659.830205093854, 5697.373288938214, 5705.885882282347, 5660.60375773696, 5662.858428539289, 5753.050746626802, 5757.942420575793, 5787.363390027093, 5129.794808207667, 5146.5110814472755, 5191.428942816287, 5200.375954885419, 5263.499967503081, 5265.973670131663, 5296.3379577552805, 5303.920441193385, 5324.015057214408, 5338.91991620126, 5098.643094983334, 5160.045391602565, 5161.148410512261, 5214.426413094576, 5226.3989641191365, 5134.589232861362, 5158.845234642967, 5159.509846564571, 5203.8178663746585, 5231.372235331746, 5115.565176959963, 5154.587632989352, 5169.483051694838, 5197.740112994373, 5295.0, 5537.169424586304, 5690.943090569124, 5762.884742305172, 5780.855478613026, 5803.129530570448, 6119.1674507285425, 6142.293636231846, 6151.954167791486, 6177.938220617782, 6247.62514249142, 6250.656213908233, 6252.18721566198, 6301.905471417932, 6317.778877739257, 6334.651594162319, 5628.549716022709, 5775.48020678141, 5825.592208545403, 5832.562557808096, 5841.240638716982, 6237.438630523275, 6259.624422534663, 6294.496440284766, 6351.936480635227, 6352.428281454691, 6153.784617538365, 6213.347598502182, 6241.095041218998, 6253.78111766086, 6257.9687101564205, 6138.294096178907, 6330.113784070234, 6338.778142764981, 6345.175127233522, 6352.428281454691, 6080.14877917092, 6106.339326077069, 6124.938750612437, 6128.961487065963, 6138.059051571771, 6212.347703491158, 6215.0, 6215.12988181655, 6219.036049412348, 6227.346128656426, 4856.125897338513, 4928.162212423897, 4937.185364414903, 4939.481354457802, 4976.2038073908, 4492.101579684011, 4510.932336014962, 4525.705829121097, 4552.726836389827, 4559.293309537027, 5730.656160630292, 5743.052396354638, 5743.195952566644, 5743.540516758733, 5747.5114615257335, 6165.506759459233, 6169.413905678954, 6255.468285195719, 6268.216472940882, 6290.21372328459, 5723.77104915807, 5742.626729262584, 5751.252337196138, 5754.683492407958, 5778.537716982715, 5453.045716999589, 5454.372747134051, 5472.534834539029, 5478.808241711521, 5489.066858633962, 5597.488678056983, 6215.06773983897, 6273.466755325849, 6301.212348456443, 6343.55595108338, 6164.291106522807, 6273.341299163522, 6306.306306306395, 6315.242170939517, 6325.588836725598, 6317.96841015792, 6324.0, 6337.588056776295, 6361.109444677656, 6361.491080713625, 6051.878962420639, 6098.159935307505, 6109.022556390956, 6146.647737497743, 6160.445559899451, 6328.0, 6328.968355158195, 6336.3030066693545, 6345.46063584582, 6346.428821406095, 6007.399260074132, 6137.815865523863, 6147.0, 6174.9073763892675, 6175.413335733098, 5898.292204935436, 5915.733791979285, 5976.97011514957, 6037.0642550404755, 6062.605930614673, 5870.442307980735, 5892.7642894283745, 5952.375000625046, 5970.9701451492465, 6033.78881739137, 6124.43826824157, 6126.855735137855, 6142.969285153546, 6145.539084568764, 6155.199824023028, 5756.30924289088, 5820.592558520862, 5915.260592425947, 5960.8807823844145, 5997.0], "value": 5702.790522177546, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [426.27533193570935, 460.76270720578907, 472.71400802514466, 517.7721802406941, 535.2105644174843, 30866.0, 40559.0, 41589.7920510397, 41631.79184104079, 41824.58175418245, 42773.930651733644, 42943.35584966228, 43207.56792432075, 43284.0, 43380.132397352034, 8170.714025009126, 8644.610992505352, 9759.072888075658, 9795.314327997043, 9806.0, 8594.355423343242, 8836.469811811312, 8837.558122093902, 8846.867296990551, 11705.0, 11689.941550292278, 11963.46164422603, 11986.340751258676, 12013.219140755822, 12023.278603283787, 11885.821712674273, 11961.10291728124, 11974.521019159223, 11976.520939162467, 11996.340201288926, 8623.13768623139, 8808.911910880874, 8815.955920220422, 11786.0, 11903.702407439796, 31975.840120799247, 39881.0, 40057.79971100126, 40149.397759033636, 42593.0, 3520.4543295789194, 3528.153243221634, 3548.532865889702, 3591.4612808078737, 3975.662068724162, 30143.547846782316, 31172.844135779404, 31898.362032759447, 31960.20099497508, 31990.0, 3274.8035117893, 3307.603087629476, 3328.617209020945, 3359.0, 3379.932401351959, 411746.99999999703, 415494.0, 416382.0, 416880.0, 418181.0, 58044.70977645085, 58336.41663583395, 58455.41544584585, 58477.70761146252, 58580.4141958575, 60726.696366517885, 60972.08541871876, 61019.69490152521, 61179.69410152921, 61218.693906530185, 14993.875459340594, 16309.83690163107, 16527.752083718755, 16531.00813951167, 16536.586585335463, 11670.241434306743, 11945.581904633296, 11957.402129893644, 11959.461824217931, 11966.521339146511, 13381.799273010913, 16134.0, 16483.0, 16892.746608800877, 16923.323067077425, 4894.105461299805, 7405.518896220778, 7574.469787114849, 7654.770356889191, 7715.729949451855, 865.8701194820701, 1001.2590682894605, 1033.2870319479534, 1035.7255327338262, 1107.5182295701395, 28066.71933280682, 28475.715242847305, 28511.144665660064, 30738.38523229523, 31275.21811954674, 909.7763508081752, 938.5213541093955, 1003.2074661017714, 1032.5766435761302, 1110.9777804443866, 15897.761533577006, 15923.681526369408, 16110.597235068983, 16135.331154857586, 16174.066909592451, 689643.0, 691664.0, 697442.0, 700143.0, 702332.0, 59839.0, 60401.39598604046, 60622.69688651529, 60714.696426517585, 60883.47791305253, 1243.4155946705034, 1292.3473645808806, 1383.7370899529058, 1393.6794537256487, 1421.2112277685776, 28317.85841070864, 32737.0, 32846.507302390004, 33005.50491742578, 33971.83014084914, 56637.15044274257, 56873.57816054466, 57491.0, 57679.55801104838, 57684.42315576791, 642862.0, 646346.0, 647199.0, 647537.0, 647666.0, 33881.49177762385, 34065.48901766426, 36452.45321320235, 42271.365929510466, 42394.7281581553, 38690.4196437048, 40009.0, 40453.595464044985, 41960.0, 47212.0, 40298.798506008454, 40643.593564065166, 40785.0, 40965.0, 41766.37350439685, 39600.0, 39616.0, 40184.799076005605, 40185.0, 40387.798061009504, 38252.426213607374, 38885.6111438882, 39069.41395879007, 39083.609163908, 39089.609103908595, 34134.40696799502, 34270.48594271038, 34969.65030349664, 35073.1231719209, 35096.64903351036, 24595.754042459346, 24934.875325623256, 25170.0, 25610.615840762777, 25694.614580781676], "value": 20380.709363495815, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [318.8294262569525, 359.34599029765803, 384.15294276121153, 394.8420631747301, 409.64974946420807, 12976.675583110415, 17887.910560447195, 21235.68146477804, 21598.81206533642, 21721.0, 17621.55946101348, 21191.728496290194, 21611.351659450203, 21739.695618262915, 21967.780322196773, 5246.658967167141, 5780.797672081478, 6021.036634138539, 6040.516758659297, 6047.939520604804, 5927.703614819264, 5983.192269043689, 6027.578069535134, 6027.758889644409, 6028.819135425944, 5757.481826635602, 5985.910211346833, 5992.49063829575, 6072.939270607304, 6103.023516237403, 6011.117270724995, 6027.126066720323, 6053.969730151365, 6061.787837425713, 6067.0, 5662.66024038559, 5822.883542329172, 5823.126531020339, 5823.2138661280605, 5834.445727655866, 11985.22096063753, 12099.758004839941, 12196.756064878742, 13057.738845223137, 13335.0, 2167.7615462299136, 2432.72023717273, 2730.470936275672, 2954.335274563206, 3137.952930706041, 9216.170544651013, 10021.847487538953, 10192.643257485952, 10331.94834025825, 10375.206520816835, 1866.8413184879303, 1894.7063205203078, 1912.6652835753687, 1935.7123970405478, 1957.119296316649, 78992.42015159664, 87483.56258218733, 121886.3905680466, 123187.0, 126350.36824815816, 9813.264005199639, 10852.0, 11163.15807891705, 11190.66428007161, 11391.829122563167, 14089.71820563583, 14122.223277719619, 14165.78751318731, 14169.504067357593, 14173.433062677377, 7017.578945263301, 8328.500289982621, 8536.0, 8604.5697715114, 8655.17775811297, 8575.1424857515, 8575.356848236408, 8583.313334933191, 8718.302535797247, 8726.476411415335, 4454.866354009385, 4536.206163921301, 4541.364209010741, 4571.22289210835, 4618.076384723069, 1592.7371983622804, 1882.9623407531772, 1929.5465565592074, 1932.9903350483157, 1949.9317523886596, 695.600029982762, 859.5401460218878, 961.5576834655997, 1030.6289735695143, 1049.732318258839, 20722.481937951372, 21273.468163295733, 21332.0, 21389.46526336854, 21408.78591214068, 917.4311926605413, 970.5389939778548, 984.7193549838262, 984.7641210281195, 999.6001599360314, 8814.559272036373, 10588.15294776416, 11006.614768483065, 11031.503582338813, 11071.501782419811, 576654.0, 602756.0, 605436.0, 623158.0, 630865.0, 148820.0, 149355.0, 152044.0, 153417.0, 157955.0, 852.4970267542055, 957.2772556719772, 988.2143695761887, 1000.6147633161286, 1004.4174378860243, 15253.466128685443, 19132.61734765269, 19149.61700765949, 19179.94510301947, 19183.616327673088, 59031.0, 59053.70473147607, 59394.10908836284, 59667.0, 60205.698971506616, 86238.0, 86506.0, 86764.0, 87495.56252218699, 88973.0, 5411.485908838654, 5723.656580605177, 6122.663253521099, 7992.600369981594, 8169.5098294102545, 10996.945015275192, 12068.974137198436, 12861.871381286068, 12878.420471078636, 13080.28058456794, 10836.945815270874, 10963.287386319864, 11094.44527773592, 11097.112231021338, 11202.495887685236, 10824.837627435438, 10890.509927053143, 11059.059979901798, 11248.0, 11322.94338528302, 11174.944125279322, 11330.716732081433, 11339.0, 11401.429928503707, 11527.88472115268, 8029.8394032120195, 8057.19428057213, 8060.556669383241, 8086.757397278091, 8152.755417337489, 10165.390076595428, 11150.386728729673, 11249.212555121063, 11301.660950171508, 11326.660200194008], "value": 9430.439468972134, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1554.712378210031, 1584.9856092101054, 1776.760137381454, 1910.2931915191382, 1914.7223652570378, 8298.294644955178, 8531.658733650647, 8694.347923905716, 8717.389782715205, 8718.258947989421, 7670.07959044914, 7869.606519674007, 8286.709965151222, 8454.830903381944, 8528.317734581235, 2303.8156947444204, 2326.9534609307802, 2329.638905969569, 2331.9650205246935, 2339.5203983183405, 2283.7145356830397, 2310.0759696121518, 2316.756740542244, 2359.504504054142, 2371.3952941999764, 1675.7653928450027, 2137.561799831031, 2271.0802125139344, 2343.7070366204225, 2346.2022912209845, 2319.7332306784765, 2325.337278875529, 2343.5078633486987, 2351.259353303704, 2353.4822339085545, 2318.547883162784, 2321.6749655048307, 2326.2439707095264, 2327.9883600582057, 2329.77867102625, 8610.354223433267, 8657.523836189008, 8660.740177794676, 8669.739907802776, 8689.087645797163, 5040.59675225981, 5255.86860328491, 5260.842174734764, 5262.394824595182, 5270.2358158067245, 3452.378571857065, 3529.6117427083, 3578.7494875358734, 3620.456931460276, 3632.4188129899353, 902.2826852652091, 1068.471106802138, 1088.684281558347, 1109.3842917180962, 1111.5442668505955, 11240.887591124067, 11254.0996720263, 11268.605598804003, 11285.661430157108, 11296.322220666703, 4492.3710680504755, 4590.495045544987, 4721.480637129913, 4721.6458765592715, 4722.763861806965, 9698.418094914328, 9703.466309352913, 9705.951470242604, 9711.951440242754, 9717.61129554824, 5237.3453318335205, 5241.947580524222, 5246.186841039645, 5249.658772179796, 5287.735613219324, 4690.319903613957, 4792.065547218259, 4806.7836947337455, 4811.133995880705, 4842.055799119138, 3426.9657303427143, 3468.271662950758, 3508.017755028596, 3525.5945566259697, 3527.5766907971206, 1103.172620534599, 1145.9828102578467, 1240.503798480597, 1245.7383949370733, 1267.5690265309838, 719.2124623537219, 757.6552668535803, 843.3632607381511, 890.5324704530174, 926.6108234541508, 5655.406182350876, 5659.915101273402, 5758.913616295759, 5812.476877080999, 5846.630565283895, 891.3804905590553, 910.5902343945252, 920.4293338130402, 924.7965447601517, 951.5432592355708, 3763.172102137525, 3836.7825922647803, 3862.9613703863165, 3888.630580094943, 3903.590123037097, 10133.695989120339, 10368.0, 10549.20880933933, 10571.418571978615, 10608.787824243473, 8718.346124040723, 9590.520473976274, 10405.011523905217, 10523.421211833424, 10534.157267418588, 7916.960415197887, 8048.195180482022, 8079.192080791991, 8080.595970201466, 8081.151479094728, 5743.827685169452, 5799.94200057994, 5870.324912635102, 5919.171316015847, 5992.221011268507, 9341.346105772802, 9737.707868763948, 9738.318317717974, 9747.561359738971, 9767.023297670461, 9987.101160895554, 10021.99780021992, 10029.648962286285, 10047.799044018933, 10050.949745251228, 7348.1182258129365, 7357.889631655423, 7365.189829118685, 7370.5946172961, 7370.815729606803, 7853.607319633881, 7926.524408535276, 7941.761747147595, 8027.719029834161, 8031.397645176518, 7062.505624606229, 7086.574805511686, 7151.678174482265, 7347.706091756484, 7358.668859901424, 7058.153021637437, 7059.5764254144915, 7111.253318401494, 7192.460565457507, 7198.20819709842, 6850.486213533905, 6901.378875901193, 6905.689243983932, 6979.581225126509, 6988.895166572607, 4762.0, 4762.833300834454, 4822.662413631152, 4838.395200599925, 4858.0, 4341.45731783527, 4502.684812063124, 4515.0, 4590.816367345269, 4720.622350212044], "value": 4470.349202292676, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [248.0647957201352, 264.362885446075, 276.3671192968103, 314.81425958684383, 317.79184634064666, 4382.5398333175, 4507.346434766957, 4939.604831613471, 5130.127878260695, 5453.863653408666, 4325.32957391604, 4937.370667679666, 5341.679499230049, 5353.250544923714, 5393.919091213635, 3687.225682606656, 4057.6145266199665, 4103.363978583325, 4112.437273835938, 4114.91770164598, 3739.420389839579, 3863.3625451800417, 4139.275626765319, 4158.708890377675, 4192.077742896573, 4060.167665628539, 4092.7544347339253, 4095.795210239491, 4099.8770036898795, 4113.794310284474, 3330.900072997802, 4045.3527435610163, 4079.306517891936, 4091.2022155679656, 4103.4050062740735, 3113.723373416911, 3215.646278909318, 3257.299680568689, 3568.678818906311, 3575.6781889629797, 4545.954540454586, 5138.871528211787, 5139.486051394868, 5164.8967020659375, 5193.402758682762, 4599.563041511051, 4633.887866911917, 4638.165130276549, 4638.837640682594, 4641.466231383401, 4606.930896036562, 4741.407324084489, 5391.032273220286, 5659.066254068074, 5762.942370576325, 3075.400296942097, 3878.592747761501, 3886.7667939923695, 3890.0, 3909.3940439231965, 4818.98801251741, 4952.504749525055, 5929.584929054968, 5948.583599148062, 6000.729967151488, 3661.9633803662155, 3917.7845218512684, 3938.586448422874, 3947.5262968443394, 3949.960500395017, 3321.285923526453, 3447.7243419934284, 3957.56466788653, 3965.4448377226636, 3971.980140099281, 3846.4614953906475, 4459.554044595579, 4655.813767449332, 4663.813447462064, 4669.486356500782, 5362.463753624685, 5418.810341638024, 5687.317521897398, 5710.857228569319, 5755.5683323750045, 1148.8281952408497, 1278.4310981613278, 1370.6093763277418, 1376.7521846067805, 1399.818023656918, 745.6346390268837, 791.7545560876032, 809.0909538316151, 824.9811482818714, 832.0182185021612, 3629.9818500907327, 3912.4131380293184, 3915.686745060388, 3990.563397177014, 4377.365282034088, 3858.5176852893387, 4188.5811418857875, 4454.910901781946, 4809.519048095232, 4810.759462026885, 4370.562943705604, 4395.099004704028, 4405.669574781904, 4405.713628614129, 4421.867343979686, 2315.1594482386545, 2373.8694371809715, 2548.668673072526, 2579.625954236625, 2589.365605426645, 4007.7394969327484, 4914.198906623191, 4950.603951683856, 4992.450830408652, 5009.520287457732, 4196.79668174211, 4314.71954322968, 4326.610605045561, 4483.377224323807, 4938.950610493921, 4739.702050442725, 4845.238214217932, 4955.900881982412, 4989.900201996013, 5015.144396573219, 3837.501124853751, 4408.140412619508, 4423.996283363561, 4542.7955741992355, 4768.666193366431, 4120.2583534964, 4569.497355290981, 4741.644376671832, 4799.592034676952, 4850.53919877611, 4231.4710661167355, 4488.214562451492, 4823.517648235148, 4999.0, 5020.824271150492, 3407.7796482324957, 3525.9598418466103, 3583.623719509414, 3658.5061016762397, 3786.1859700163495, 5226.372835259641, 5751.971240143773, 5778.682172480554, 5785.30576330843, 5816.127580862904, 5142.820001299937, 5588.2455868458865, 5615.522680572197, 5676.5742569308295, 5838.94161058384, 5286.841394758163, 5619.325680918316, 5626.718664066862, 5629.4652008059165, 5677.842419880419, 4953.529414705597, 5063.050725470646, 5444.809431670031, 5464.087569350695, 5521.337439507285, 5332.33345831771, 5489.090781885607, 5594.328680558359, 5607.859803504945, 5666.320041595035, 5172.9741351293005, 5241.850841155108, 5347.411784703601, 5464.3442786865835, 5471.234027236111], "value": 3842.1993069400037, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [728.683022885045, 738.7008261654032, 754.532190042174, 790.9446338756289, 795.7188925829417, 14407.855921440783, 15202.087874727496, 15318.310676019579, 15364.001339912911, 16664.0, 15047.623809404771, 15085.472008479705, 15232.69534609307, 15262.694746105099, 15279.388824447009, 701.2356531380794, 726.0452504955992, 728.1189760389934, 889.8264838356523, 898.5013317608757, 719.3202423709571, 721.3219573600825, 722.8734971380014, 875.3609864798701, 919.6183583812709, 952.8380175370204, 957.1863915671659, 958.7028021313415, 959.52023988006, 962.1148543340144, 956.1920177450044, 956.5312996631635, 957.3824882950528, 958.2382006305004, 959.9280053995908, 545.6507834985613, 683.931606839312, 707.056080133023, 711.0258945244996, 711.9715211391538, 15542.300596473186, 15563.299651515708, 15574.766378504331, 15592.532224033297, 15649.374025038986, 14166.858331416657, 14437.205953672545, 14467.204303763288, 14530.893020512072, 14689.706205875822, 12906.096573239876, 12956.322204452279, 13033.283169425678, 13079.41142648583, 13253.0, 4570.954290457086, 4600.217962946307, 4601.976990115061, 4604.424446944132, 4764.7617619119255, 112312.0, 112521.0, 117607.0, 120498.3975080119, 121014.0, 11304.378259195822, 11383.69491340433, 11437.59968401102, 11511.827322590168, 11582.536698531965, 19157.521061973563, 23082.65376019361, 23092.65361019586, 23172.65241021386, 23189.376743627785, 7907.723229686933, 7926.8810967835525, 7943.682252709942, 8114.553699546582, 8173.386995975326, 4371.278739008028, 4549.772511374418, 4641.767911604406, 4666.95333046672, 4687.460941991646, 3549.219171782255, 3661.908452288714, 3663.816809159531, 3665.157013886821, 3668.825975687754, 633.5976654824128, 655.9639219842859, 657.4214691071921, 667.5894324990096, 678.5792808458689, 576.8356018534697, 599.9040153575494, 610.5970059760625, 624.8312955501989, 631.4853394483481, 8533.914660853436, 8761.86095807541, 8820.117988201257, 8892.480207811688, 8960.775980600538, 574.2047264538644, 602.3193791016124, 608.5869527324181, 616.1158737212115, 622.1383384013084, 3783.4703141560203, 4021.1650786196246, 4208.22147902639, 4437.800298986554, 4446.133004064176, 92516.0, 121484.39257803831, 130805.0, 142924.0, 153799.23100384427, 86525.1347486517, 108976.91023089825, 126264.0, 127774.0, 130011.34994324968, 94830.0, 100421.0, 100887.0, 111946.0, 114522.0, 9318.580663870278, 9416.576254068721, 9424.528773561158, 9467.621295148392, 9472.0, 58415.415845840995, 60105.69947150236, 63713.36286637074, 64132.0, 65122.67438662776, 92838.0, 94200.0, 95661.82536309665, 96311.0, 99486.0, 12805.487780488676, 13054.477820887058, 13261.204327740366, 13905.791413129013, 14049.016568840492, 14241.786373204617, 14522.49171279, 14604.561863144123, 14632.341544630304, 14948.925255374088, 12914.22514649124, 13278.203307801563, 13478.73042539124, 13544.187348759106, 13596.048276620542, 13160.0, 13205.33973301312, 13219.074664773374, 13849.16904985704, 14122.717545649235, 12874.970002399972, 12905.032122590655, 12973.286469243905, 13079.934600326937, 13243.80134297967, 10699.786004280026, 11232.83150752723, 11260.94369528147, 11302.547898083985, 11361.715957101138, 10955.233133680566, 11009.119270458505, 11073.117570012955, 11126.721831954266, 11483.770324593628], "value": 7854.060098660937, "unit": "score"}], "name": "dromaeo_css", "value": 7271.94874448452, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 106.0, 112.0, 111.0, 112.0, 112.0, 112.0, 112.0, 112.0, 112.0], "value": 110.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 105.0, 106.0, 162.0, 105.0, 107.0, 129.0, 107.0, 141.0, 107.0], "value": 121.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 187.0, 184.0, 183.0, 192.0, 184.0, 180.0, 181.0, 185.0, 183.0], "value": 184.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 74.0, 64.0, 130.0, 81.0, 48.0, 76.0, 74.0, 111.0, 80.0], "value": 81.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 91.0, 70.0, 71.0, 68.0, 95.0, 70.0, 67.0, 99.0], "value": 80.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 85.0, 88.0, 87.0, 87.0, 84.0, 88.0, 86.0, 87.0, 84.0], "value": 86.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 93.0, 94.0, 93.0, 94.0, 94.0, 94.0, 92.0, 92.0], "value": 93.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 84.0, 82.0, 85.0, 86.0, 83.0, 87.0, 84.0, 84.0], "value": 84.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 57.0, 54.0, 56.0, 55.0, 55.0, 57.0, 56.0, 54.0, 55.0], "value": 55.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 77.0, 78.0, 72.0, 71.0, 69.0, 73.0, 71.0, 72.0], "value": 71.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 83.0, 82.0, 85.0, 85.0, 85.0, 83.0, 93.0, 91.0], "value": 85.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 138.0, 138.0, 140.0, 147.0, 152.0, 129.0, 136.0, 223.0, 139.0], "value": 147.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 162.0, 164.0, 165.0, 161.0, 165.0, 164.0, 161.0, 163.0, 162.0], "value": 163.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 68.0, 66.0, 68.0, 70.0, 82.0, 86.0, 85.0, 76.0], "value": 73.6, "unit": "score"}], "name": "kraken", "value": 1439.6, "alertThreshold": 2.0}]} 04:36:19 INFO - Return code: 0 04:36:19 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:36:19 INFO - # TBPL SUCCESS # 04:36:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:36:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:36:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:36:19 INFO - 2016-07-12 04:36:19.410 screenresolution[2100:24408] starting screenresolution argv=screenresolution get 04:36:19 INFO - 2016-07-12 04:36:19.413 screenresolution[2100:24408] Display 0: 1600x1200x32@60 04:36:19 INFO - 2016-07-12 04:36:19.419 screenresolution[2101:24411] starting screenresolution argv=screenresolution list 04:36:19 INFO - Available Modes on Display 0 04:36:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:36:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:36:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:36:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:36:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:36:19 INFO - Intel Iris: 04:36:19 INFO - Chipset Model: Intel Iris 04:36:19 INFO - Type: GPU 04:36:19 INFO - Bus: Built-In 04:36:19 INFO - VRAM (Dynamic, Max): 1536 MB 04:36:19 INFO - Vendor: Intel (0x8086) 04:36:19 INFO - Device ID: 0x0a2e 04:36:19 INFO - Revision ID: 0x0009 04:36:19 INFO - Displays: 04:36:19 INFO - Display: 04:36:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:36:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:36:19 INFO - Display Serial Number: 1600x1200 60 04:36:19 INFO - Main Display: Yes 04:36:19 INFO - Mirror: Off 04:36:19 INFO - Online: Yes 04:36:19 INFO - Rotation: Supported 04:36:19 INFO - Return code: 0 04:36:19 INFO - Running post-action listener: _resource_record_post_action 04:36:19 INFO - ##### 04:36:19 INFO - ##### Finished run-tests step (success) 04:36:19 INFO - ##### 04:36:19 INFO - Running post-run listener: _resource_record_post_run 04:36:19 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 29232640; Write bytes: 703617536; Read time: 176; Write time: 1936 04:36:19 INFO - TinderboxPrint: CPU usage
53.3% 04:36:19 INFO - TinderboxPrint: I/O read bytes / time
29,232,640 / 176 04:36:19 INFO - TinderboxPrint: I/O write bytes / time
703,617,536 / 1,936 04:36:19 INFO - TinderboxPrint: CPU idle
2,262.3 (46.6%) 04:36:19 INFO - TinderboxPrint: CPU system
965.5 (19.9%) 04:36:19 INFO - TinderboxPrint: CPU user
1,621.8 (33.4%) 04:36:19 INFO - TinderboxPrint: Swap in / out
430,841,856 / 0 04:36:19 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 389505536; Write bytes: 383346176; Read time: 29402; Write time: 787 04:36:19 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 29232640; Write bytes: 295142400; Read time: 176; Write time: 1089 04:36:20 INFO - Running post-run listener: _upload_blobber_files 04:36:20 INFO - Blob upload gear active. 04:36:20 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:36:20 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 04:36:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:36:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:36:20 INFO - (blobuploader) - INFO - Open directory for files ... 04:36:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:36:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:36:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:36:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:36:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:36:21 INFO - (blobuploader) - INFO - Done attempting. 04:36:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:36:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:36:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:36:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:36:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:36:22 INFO - (blobuploader) - INFO - Done attempting. 04:36:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:36:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:36:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:36:24 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:36:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:36:24 INFO - (blobuploader) - INFO - Done attempting. 04:36:24 INFO - (blobuploader) - INFO - Iteration through files over. 04:36:24 INFO - Return code: 0 04:36:24 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:36:24 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:36:24 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9f571e5f4cd8373e45ebb330fdfae4473ef6cd1ccd627e818c582c7766b5c941e7e066cdc13ccd0ecc0f448976c6ebabc6252c8567cfc19575215154ef5b6d4e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/445fc4206a54d0d30ef3591b47166b25d705f97c3b585edc90cecd9cb2cc2f0a8c19f584cd74fee389c033eff38de8f1ec6de25c7db5ef542ac5d287b18f4a24", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d9fd79ae3f5c6f8699110c12c84ea471cdf4fa98fff5c7854cb256659b4a33a432195206a1e6c3325ece969a892b978fdb4a03e651fb656acf1f213e29dfa154"} 04:36:24 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:36:24 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:36:24 INFO - Contents: 04:36:24 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9f571e5f4cd8373e45ebb330fdfae4473ef6cd1ccd627e818c582c7766b5c941e7e066cdc13ccd0ecc0f448976c6ebabc6252c8567cfc19575215154ef5b6d4e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/445fc4206a54d0d30ef3591b47166b25d705f97c3b585edc90cecd9cb2cc2f0a8c19f584cd74fee389c033eff38de8f1ec6de25c7db5ef542ac5d287b18f4a24", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d9fd79ae3f5c6f8699110c12c84ea471cdf4fa98fff5c7854cb256659b4a33a432195206a1e6c3325ece969a892b978fdb4a03e651fb656acf1f213e29dfa154"} 04:36:24 INFO - Running post-run listener: copy_logs_to_upload_dir 04:36:24 INFO - Copying logs to upload dir... 04:36:24 INFO - mkdir: /builds/slave/test/build/upload/logs 04:36:24 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1263.945326 ========= master_lag: 2.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-07-12 04:36:26.522741) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-12 04:36:26.526058) ========= 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.0mUfHgd1p4/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.tbJZE3IPuZ/Listeners TMPDIR=/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/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/9f571e5f4cd8373e45ebb330fdfae4473ef6cd1ccd627e818c582c7766b5c941e7e066cdc13ccd0ecc0f448976c6ebabc6252c8567cfc19575215154ef5b6d4e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/445fc4206a54d0d30ef3591b47166b25d705f97c3b585edc90cecd9cb2cc2f0a8c19f584cd74fee389c033eff38de8f1ec6de25c7db5ef542ac5d287b18f4a24", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d9fd79ae3f5c6f8699110c12c84ea471cdf4fa98fff5c7854cb256659b4a33a432195206a1e6c3325ece969a892b978fdb4a03e651fb656acf1f213e29dfa154"} build_url:https://queue.taskcluster.net/v1/task/E6I7gtcPRc-B7Hf3ncDN8Q/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011110 build_url: 'https://queue.taskcluster.net/v1/task/E6I7gtcPRc-B7Hf3ncDN8Q/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9f571e5f4cd8373e45ebb330fdfae4473ef6cd1ccd627e818c582c7766b5c941e7e066cdc13ccd0ecc0f448976c6ebabc6252c8567cfc19575215154ef5b6d4e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/445fc4206a54d0d30ef3591b47166b25d705f97c3b585edc90cecd9cb2cc2f0a8c19f584cd74fee389c033eff38de8f1ec6de25c7db5ef542ac5d287b18f4a24", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d9fd79ae3f5c6f8699110c12c84ea471cdf4fa98fff5c7854cb256659b4a33a432195206a1e6c3325ece969a892b978fdb4a03e651fb656acf1f213e29dfa154"}' ========= master_lag: 0.69 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-12 04:36:27.224272) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 04:36:27.224623) ========= 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.0mUfHgd1p4/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.tbJZE3IPuZ/Listeners TMPDIR=/var/folders/0_/1mzt9_fn191_pm4dgt9z87g000000w/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.005230 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 04:36:27.251306) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-12 04:36:27.251717) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-12 04:36:27.252197) ========= ========= Total master_lag: 2.81 =========