builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0325 starttime: 1467205799.74 results: success (0) buildid: 20160629050409 builduid: 3e7ca8ebf16746d3b8cfe73bae014375 revision: cda8c83f6a5727cad1d971a6ccf0660aa4ea0fb7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-29 06:09:59.746560) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-29 06:09:59.747028) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 3 secs) (at 2016-06-29 06:09:59.747409) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 3 secs) (at 2016-06-29 06:10:03.559118) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 06:10:03.559393) ========= 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.4DmA64TmzH/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.5hqQ0xgbtg/Listeners TMPDIR=/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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-06-29 06:10:03-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 21.3M=0.001s 2016-06-29 06:10:03 (21.3 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.116055 ========= master_lag: 0.13 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 06:10:03.800786) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 06:10:03.801122) ========= 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.4DmA64TmzH/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.5hqQ0xgbtg/Listeners TMPDIR=/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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.027504 ========= master_lag: 0.07 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 06:10:03.895248) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 06:10:03.895605) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev cda8c83f6a5727cad1d971a6ccf0660aa4ea0fb7 --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 cda8c83f6a5727cad1d971a6ccf0660aa4ea0fb7 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.4DmA64TmzH/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.5hqQ0xgbtg/Listeners TMPDIR=/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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-06-29 06:10:03,979 truncating revision to first 12 chars 2016-06-29 06:10:03,979 Setting DEBUG logging. 2016-06-29 06:10:03,979 attempt 1/10 2016-06-29 06:10:03,979 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/cda8c83f6a57?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-29 06:10:04,608 unpacking tar archive at: fx-team-cda8c83f6a57/testing/mozharness/ program finished with exit code 0 elapsedTime=0.908764 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 06:10:04.828858) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-29 06:10:04.829238) ========= script_repo_revision: cda8c83f6a5727cad1d971a6ccf0660aa4ea0fb7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-29 06:10:04.829646) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-29 06:10:04.829958) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-29 06:10:04.849132) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2016-06-29 06:10:04.849545) ========= /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.4DmA64TmzH/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.5hqQ0xgbtg/Listeners TMPDIR=/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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 06:10:05 INFO - MultiFileLogger online at 20160629 06:10:05 in /builds/slave/test 06:10:05 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 06:10:05 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:10:05 INFO - {'append_to_log': False, 06:10:05 INFO - 'base_work_dir': '/builds/slave/test', 06:10:05 INFO - 'blob_upload_branch': 'Fx-Team', 06:10:05 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:10:05 INFO - 'branch': 'Fx-Team', 06:10:05 INFO - 'buildbot_json_path': 'buildprops.json', 06:10:05 INFO - 'config_files': ('talos/mac_config.py',), 06:10:05 INFO - 'default_actions': ('clobber', 06:10:05 INFO - 'read-buildbot-config', 06:10:05 INFO - 'download-and-extract', 06:10:05 INFO - 'populate-webroot', 06:10:05 INFO - 'create-virtualenv', 06:10:05 INFO - 'install', 06:10:05 INFO - 'run-tests'), 06:10:05 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:10:05 INFO - 'download_minidump_stackwalk': True, 06:10:05 INFO - 'download_symbols': 'ondemand', 06:10:05 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:10:05 INFO - 'tooltool.py': '/tools/tooltool.py', 06:10:05 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:10:05 INFO - '/tools/misc-python/virtualenv.py')}, 06:10:05 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:10:05 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:10:05 INFO - 'installer_path': 'installer.exe', 06:10:05 INFO - 'log_level': 'info', 06:10:05 INFO - 'log_name': 'talos', 06:10:05 INFO - 'log_to_console': True, 06:10:05 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 06:10:05 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 06:10:05 INFO - 'opt_config_files': (), 06:10:05 INFO - 'pip_index': False, 06:10:05 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:10:05 INFO - 'cmd': ('bash', 06:10:05 INFO - '-c', 06:10:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:10:05 INFO - 'enabled': True, 06:10:05 INFO - 'halt_on_failure': False, 06:10:05 INFO - 'name': 'check_screen_resolution'},), 06:10:05 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:10:05 INFO - 'cmd': ('bash', 06:10:05 INFO - '-c', 06:10:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:10:05 INFO - 'enabled': True, 06:10:05 INFO - 'halt_on_failure': False, 06:10:05 INFO - 'name': 'check_screen_resolution'},), 06:10:05 INFO - 'run_cmd_checks_enabled': True, 06:10:05 INFO - 'sps_profile': False, 06:10:05 INFO - 'sps_profile_interval': 0, 06:10:05 INFO - 'suite': 'dromaeojs', 06:10:05 INFO - 'system_bits': '32', 06:10:05 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:10:05 INFO - 'title': 't-yosemite-r7-0325', 06:10:05 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:10:05 INFO - 'use_talos_json': True, 06:10:05 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:10:05 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:10:05 INFO - 'work_dir': 'build'} 06:10:05 INFO - ##### 06:10:05 INFO - ##### Running clobber step. 06:10:05 INFO - ##### 06:10:05 INFO - Running pre-action listener: _resource_record_pre_action 06:10:05 INFO - Running main action method: clobber 06:10:05 INFO - rmtree: /builds/slave/test/build 06:10:05 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:10:06 INFO - Running post-action listener: _resource_record_post_action 06:10:06 INFO - ##### 06:10:06 INFO - ##### Finished clobber step (success) 06:10:06 INFO - ##### 06:10:06 INFO - ##### 06:10:06 INFO - ##### Running read-buildbot-config step. 06:10:06 INFO - ##### 06:10:06 INFO - Running pre-action listener: _resource_record_pre_action 06:10:06 INFO - Running main action method: read_buildbot_config 06:10:06 INFO - Using buildbot properties: 06:10:06 INFO - { 06:10:06 INFO - "project": "", 06:10:06 INFO - "product": "firefox", 06:10:06 INFO - "script_repo_revision": "production", 06:10:06 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 06:10:06 INFO - "repository": "", 06:10:06 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 06:10:06 INFO - "buildid": "20160629050409", 06:10:06 INFO - "pgo_build": "False", 06:10:06 INFO - "basedir": "/builds/slave/test", 06:10:06 INFO - "buildnumber": 64, 06:10:06 INFO - "slavename": "t-yosemite-r7-0325", 06:10:06 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 06:10:06 INFO - "platform": "yosemite_r7", 06:10:06 INFO - "scheduler": "tests-fx-team-macosx64-talos", 06:10:06 INFO - "branch": "fx-team", 06:10:06 INFO - "repo_path": "integration/fx-team", 06:10:06 INFO - "revision": "cda8c83f6a5727cad1d971a6ccf0660aa4ea0fb7", 06:10:06 INFO - "stage_platform": "macosx64", 06:10:06 INFO - "builduid": "3e7ca8ebf16746d3b8cfe73bae014375", 06:10:06 INFO - "slavebuilddir": "test" 06:10:06 INFO - } 06:10:06 INFO - Found installer url https://queue.taskcluster.net/v1/task/T67JNPS_Qm6m7IbMAigkLA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 06:10:06 INFO - Running post-action listener: _resource_record_post_action 06:10:06 INFO - ##### 06:10:06 INFO - ##### Finished read-buildbot-config step (success) 06:10:06 INFO - ##### 06:10:06 INFO - ##### 06:10:06 INFO - ##### Running download-and-extract step. 06:10:06 INFO - ##### 06:10:06 INFO - Running pre-action listener: _resource_record_pre_action 06:10:06 INFO - Running main action method: download_and_extract 06:10:06 INFO - mkdir: /builds/slave/test/build/tests 06:10: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')]} 06:10:06 INFO - https://queue.taskcluster.net/v1/task/T67JNPS_Qm6m7IbMAigkLA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 06:10:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T67JNPS_Qm6m7IbMAigkLA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 06:10:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T67JNPS_Qm6m7IbMAigkLA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 06:10:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T67JNPS_Qm6m7IbMAigkLA/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 06:10:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T67JNPS_Qm6m7IbMAigkLA/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 06:10:24 INFO - Downloaded 1199 bytes. 06:10:24 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 06:10:24 INFO - Using the following test package requirements: 06:10:24 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 06:10:24 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 06:10:24 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 06:10:24 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 06:10:24 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 06:10:24 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 06:10:24 INFO - u'jsshell-mac.zip'], 06:10:24 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 06:10:24 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 06:10:24 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 06:10:24 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 06:10:24 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 06:10:24 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 06:10:24 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 06:10:24 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 06:10:24 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 06:10:24 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 06:10:24 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 06:10:24 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 06:10:24 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')]} 06:10:24 INFO - https://queue.taskcluster.net/v1/task/T67JNPS_Qm6m7IbMAigkLA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 06:10:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T67JNPS_Qm6m7IbMAigkLA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 06:10:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T67JNPS_Qm6m7IbMAigkLA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 06:10:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T67JNPS_Qm6m7IbMAigkLA/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 06:10:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T67JNPS_Qm6m7IbMAigkLA/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 06:10:30 INFO - Downloaded 18787794 bytes. 06:10:30 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'] 06:10:30 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 06:10:33 INFO - Return code: 0 06:10:33 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 06:10:33 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')]} 06:10:33 INFO - https://queue.taskcluster.net/v1/task/T67JNPS_Qm6m7IbMAigkLA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 06:10:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T67JNPS_Qm6m7IbMAigkLA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 06:10:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T67JNPS_Qm6m7IbMAigkLA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 06:10:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T67JNPS_Qm6m7IbMAigkLA/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 06:10:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T67JNPS_Qm6m7IbMAigkLA/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 06:10:35 INFO - Downloaded 18787794 bytes. 06:10:35 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'] 06:10:35 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 06:10:38 INFO - Return code: 0 06:10:38 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')]} 06:10:38 INFO - https://queue.taskcluster.net/v1/task/T67JNPS_Qm6m7IbMAigkLA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 06:10:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T67JNPS_Qm6m7IbMAigkLA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 06:10:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T67JNPS_Qm6m7IbMAigkLA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 06:10:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T67JNPS_Qm6m7IbMAigkLA/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 06:10:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T67JNPS_Qm6m7IbMAigkLA/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 06:10:39 INFO - Downloaded 14405245 bytes. 06:10:39 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'] 06:10:39 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 06:10:40 INFO - Return code: 0 06:10: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')]} 06:10:40 INFO - https://queue.taskcluster.net/v1/task/T67JNPS_Qm6m7IbMAigkLA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 06:10:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T67JNPS_Qm6m7IbMAigkLA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 06:10:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T67JNPS_Qm6m7IbMAigkLA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 06:10:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T67JNPS_Qm6m7IbMAigkLA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 06:10:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T67JNPS_Qm6m7IbMAigkLA/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 06:10:42 INFO - Downloaded 112384366 bytes. 06:10:42 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/T67JNPS_Qm6m7IbMAigkLA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 06:10:42 INFO - mkdir: /builds/slave/test/properties 06:10:42 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:10:42 INFO - Writing to file /builds/slave/test/properties/build_url 06:10:42 INFO - Contents: 06:10:42 INFO - build_url:https://queue.taskcluster.net/v1/task/T67JNPS_Qm6m7IbMAigkLA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 06:10:44 INFO - Running post-action listener: _resource_record_post_action 06:10:44 INFO - Running post-action listener: set_extra_try_arguments 06:10:44 INFO - ##### 06:10:44 INFO - ##### Finished download-and-extract step (success) 06:10:44 INFO - ##### 06:10:44 INFO - ##### 06:10:44 INFO - ##### Running populate-webroot step. 06:10:44 INFO - ##### 06:10:44 INFO - Running pre-action listener: _resource_record_pre_action 06:10:44 INFO - Running main action method: populate_webroot 06:10:44 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:10:44 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:10:44 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:10:44 INFO - '--tppagecycles', 06:10:44 INFO - '7'], 06:10:44 INFO - 'tests': ['tsvgm']}}, 06:10:44 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:10:44 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:10:44 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:10:44 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:10:44 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:10:44 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:10:44 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:10:44 INFO - 'talos_options': ['--disable-e10s'], 06:10:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:10:44 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:10:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:10:44 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:10:44 INFO - 'talos_options': ['--disable-e10s'], 06:10:44 INFO - 'tests': ['damp', 'tps']}, 06:10:44 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:10:44 INFO - 'tests': ['damp', 'tps']}, 06:10:44 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:10:44 INFO - 'tests': ['dromaeo_dom']}, 06:10:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:10:44 INFO - 'g4': {'talos_options': ['--disable-e10s'], 06:10:44 INFO - 'tests': ['basic_compositor_video']}, 06:10:44 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 06:10:44 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:10:44 INFO - 'tests': ['a11yr', 06:10:44 INFO - 'ts_paint', 06:10:44 INFO - 'tpaint', 06:10:44 INFO - 'sessionrestore', 06:10:44 INFO - 'sessionrestore_no_auto_restore', 06:10:44 INFO - 'tabpaint']}, 06:10:44 INFO - 'other-e10s': {'tests': ['a11yr', 06:10:44 INFO - 'ts_paint', 06:10:44 INFO - 'tpaint', 06:10:44 INFO - 'sessionrestore', 06:10:44 INFO - 'sessionrestore_no_auto_restore', 06:10:44 INFO - 'tabpaint']}, 06:10:44 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:10:44 INFO - 'ts_paint', 06:10:44 INFO - 'tpaint', 06:10:44 INFO - 'sessionrestore', 06:10:44 INFO - 'sessionrestore_no_auto_restore', 06:10:44 INFO - 'tabpaint']}, 06:10:44 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:10:44 INFO - 'ts_paint', 06:10:44 INFO - 'tpaint', 06:10:44 INFO - 'sessionrestore', 06:10:44 INFO - 'sessionrestore_no_auto_restore', 06:10:44 INFO - 'tabpaint']}, 06:10:44 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:10:44 INFO - 'tests': ['a11yr', 06:10:44 INFO - 'ts_paint', 06:10:44 INFO - 'tpaint', 06:10:44 INFO - 'sessionrestore', 06:10:44 INFO - 'sessionrestore_no_auto_restore', 06:10:44 INFO - 'tabpaint']}, 06:10:44 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:10:44 INFO - 'tests': ['a11yr', 06:10:44 INFO - 'ts_paint', 06:10:44 INFO - 'tpaint', 06:10:44 INFO - 'sessionrestore', 06:10:44 INFO - 'sessionrestore_no_auto_restore', 06:10:44 INFO - 'tabpaint']}, 06:10:44 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:10:44 INFO - 'tests': ['tsvgx', 06:10:44 INFO - 'tsvgr_opacity', 06:10:44 INFO - 'tart', 06:10:44 INFO - 'tscrollx', 06:10:44 INFO - 'cart']}, 06:10:44 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:10:44 INFO - 'tsvgr_opacity', 06:10:44 INFO - 'tart', 06:10:44 INFO - 'tscrollx', 06:10:44 INFO - 'cart']}, 06:10:44 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:10:44 INFO - 'talos_options': ['--disable-e10s'], 06:10:44 INFO - 'tests': ['tp5o']}, 06:10:44 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:10:44 INFO - 'tests': ['tp5o']}, 06:10:44 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:10:44 INFO - 'talos_options': ['--disable-e10s', 06:10:44 INFO - '--xperf_path', 06:10:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:10:44 INFO - 'tests': ['tp5n']}, 06:10:44 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:10:44 INFO - 'talos_options': ['--xperf_path', 06:10:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:10:44 INFO - 'tests': ['tp5n']}}, 06:10:44 INFO - 'talos.zip': {'path': '', 06:10:44 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:10:44 INFO - Running post-action listener: _resource_record_post_action 06:10:44 INFO - ##### 06:10:44 INFO - ##### Finished populate-webroot step (success) 06:10:44 INFO - ##### 06:10:44 INFO - ##### 06:10:44 INFO - ##### Running create-virtualenv step. 06:10:44 INFO - ##### 06:10:44 INFO - Running pre-action listener: _resource_record_pre_action 06:10:44 INFO - Running main action method: create_virtualenv 06:10:44 INFO - Creating virtualenv /builds/slave/test/build/venv 06:10:44 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 06:10:44 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:10:44 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:10:44 INFO - Using real prefix '/tools/python27' 06:10:44 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:10:45 INFO - Installing distribute.............................................................................................................................................................................................done. 06:10:48 INFO - Installing pip.................done. 06:10:48 INFO - Return code: 0 06:10:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:10: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')]} 06:10:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:10:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:10: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')]} 06:10:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:10:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:10: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106d49a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106dde618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc28ac8b6a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106cadd30>, '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 0x106a12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ca5b20>, '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.4DmA64TmzH/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.5hqQ0xgbtg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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 06:10: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', 'pip>=1.5'] in /builds/slave/test/build 06:10: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 pip>=1.5 06:10:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4DmA64TmzH/Render', 06:10:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:10:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:10:48 INFO - 'HOME': '/Users/cltbld', 06:10:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:10:48 INFO - 'LOGNAME': 'cltbld', 06:10:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:10:48 INFO - 'MOZ_NO_REMOTE': '1', 06:10:48 INFO - 'NO_EM_RESTART': '1', 06:10:48 INFO - 'PAGER': '/bin/cat', 06:10:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:10:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:10:48 INFO - 'PWD': '/builds/slave/test', 06:10:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:10:48 INFO - 'SHELL': '/bin/bash', 06:10:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5hqQ0xgbtg/Listeners', 06:10:48 INFO - 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/', 06:10:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:10:48 INFO - 'USER': 'cltbld', 06:10:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:10:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:10:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:10:48 INFO - 'XPC_FLAGS': '0x0', 06:10:48 INFO - 'XPC_SERVICE_NAME': '0', 06:10:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:10:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:10:48 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 06:10:48 INFO - Cleaning up... 06:10:48 INFO - Return code: 0 06:10:48 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:10: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')]} 06:10:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:10:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:10: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')]} 06:10:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:10:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:10: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', '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 0x106d49a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106dde618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc28ac8b6a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106cadd30>, '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 0x106a12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ca5b20>, '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.4DmA64TmzH/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.5hqQ0xgbtg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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 06:10: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', 'psutil>=3.1.1'] in /builds/slave/test/build 06:10: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 psutil>=3.1.1 06:10:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4DmA64TmzH/Render', 06:10:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:10:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:10:48 INFO - 'HOME': '/Users/cltbld', 06:10:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:10:48 INFO - 'LOGNAME': 'cltbld', 06:10:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:10:48 INFO - 'MOZ_NO_REMOTE': '1', 06:10:48 INFO - 'NO_EM_RESTART': '1', 06:10:48 INFO - 'PAGER': '/bin/cat', 06:10:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:10:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:10:48 INFO - 'PWD': '/builds/slave/test', 06:10:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:10:48 INFO - 'SHELL': '/bin/bash', 06:10:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5hqQ0xgbtg/Listeners', 06:10:48 INFO - 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/', 06:10:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:10:48 INFO - 'USER': 'cltbld', 06:10:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:10:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:10:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:10:48 INFO - 'XPC_FLAGS': '0x0', 06:10:48 INFO - 'XPC_SERVICE_NAME': '0', 06:10:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:10:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:10:48 INFO - Downloading/unpacking psutil>=3.1.1 06:10: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 06:10: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 06:10: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 06:10: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 06:10: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 06:10: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 06:10:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:10:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:10:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:10:51 INFO - Installing collected packages: psutil 06:10:51 INFO - Running setup.py install for psutil 06:10:51 INFO - building 'psutil._psutil_osx' extension 06:10:51 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 06:10:52 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 06:10:52 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 06:10:52 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 06:10:52 INFO - building 'psutil._psutil_posix' extension 06:10:52 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 06:10:52 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 06:10:52 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 06:10:52 INFO - ^ 06:10:52 INFO - 1 warning generated. 06:10:52 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 06:10:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:10:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:10:52 INFO - Successfully installed psutil 06:10:52 INFO - Cleaning up... 06:10:52 INFO - Return code: 0 06:10:52 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:10:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:10:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:10:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:10:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:10:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:10:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:10:52 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 0x106d49a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106dde618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc28ac8b6a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106cadd30>, '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 0x106a12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ca5b20>, '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.4DmA64TmzH/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.5hqQ0xgbtg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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 06:10:52 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 06:10:52 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 06:10:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4DmA64TmzH/Render', 06:10:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:10:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:10:52 INFO - 'HOME': '/Users/cltbld', 06:10:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:10:52 INFO - 'LOGNAME': 'cltbld', 06:10:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:10:52 INFO - 'MOZ_NO_REMOTE': '1', 06:10:52 INFO - 'NO_EM_RESTART': '1', 06:10:52 INFO - 'PAGER': '/bin/cat', 06:10:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:10:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:10:52 INFO - 'PWD': '/builds/slave/test', 06:10:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:10:52 INFO - 'SHELL': '/bin/bash', 06:10:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5hqQ0xgbtg/Listeners', 06:10:52 INFO - 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/', 06:10:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:10:52 INFO - 'USER': 'cltbld', 06:10:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:10:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:10:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:10:52 INFO - 'XPC_FLAGS': '0x0', 06:10:52 INFO - 'XPC_SERVICE_NAME': '0', 06:10:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:10:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:10:52 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:10:52 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 06:10:52 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 06:10:52 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 06:10:52 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 06:10:52 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 06:10:52 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 06:10:55 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:10:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:10:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:10:55 INFO - Installing collected packages: mozsystemmonitor 06:10:55 INFO - Running setup.py install for mozsystemmonitor 06:10:55 INFO - Successfully installed mozsystemmonitor 06:10:55 INFO - Cleaning up... 06:10:55 INFO - Return code: 0 06:10:55 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 06:10:55 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')]} 06:10:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:10:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:10:55 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')]} 06:10:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:10:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:10:55 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 0x106d49a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106dde618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc28ac8b6a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106cadd30>, '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 0x106a12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ca5b20>, '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.4DmA64TmzH/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.5hqQ0xgbtg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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 06:10:55 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 06:10:55 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 06:10:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4DmA64TmzH/Render', 06:10:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:10:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:10:55 INFO - 'HOME': '/Users/cltbld', 06:10:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:10:55 INFO - 'LOGNAME': 'cltbld', 06:10:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:10:55 INFO - 'MOZ_NO_REMOTE': '1', 06:10:55 INFO - 'NO_EM_RESTART': '1', 06:10:55 INFO - 'PAGER': '/bin/cat', 06:10:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:10:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:10:55 INFO - 'PWD': '/builds/slave/test', 06:10:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:10:55 INFO - 'SHELL': '/bin/bash', 06:10:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5hqQ0xgbtg/Listeners', 06:10:55 INFO - 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/', 06:10:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:10:55 INFO - 'USER': 'cltbld', 06:10:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:10:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:10:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:10:55 INFO - 'XPC_FLAGS': '0x0', 06:10:55 INFO - 'XPC_SERVICE_NAME': '0', 06:10:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:10:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:10:55 INFO - Downloading/unpacking jsonschema==2.5.1 06:10:55 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 06:10:55 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 06:10:55 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 06:10:55 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 06:10:55 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 06:10:55 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 06:10:58 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:10:58 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:10:58 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 06:10:58 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 06:10:58 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 06:10:58 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 06:10:58 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 06:10:58 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 06:10:58 INFO - Downloading functools32-3.2.3-2.tar.gz 06:10:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:10:58 INFO - warning: no files found matching '*.txt' 06:10:58 INFO - no previously-included directories found matching 'build' 06:10:58 INFO - no previously-included directories found matching 'dist' 06:10:58 INFO - no previously-included directories found matching '.git*' 06:10:58 INFO - Installing collected packages: jsonschema, functools32 06:10:58 INFO - Running setup.py install for functools32 06:10:58 INFO - warning: no files found matching '*.txt' 06:10:58 INFO - no previously-included directories found matching 'build' 06:10:58 INFO - no previously-included directories found matching 'dist' 06:10:58 INFO - no previously-included directories found matching '.git*' 06:10:58 INFO - Successfully installed jsonschema functools32 06:10:58 INFO - Cleaning up... 06:10:59 INFO - Return code: 0 06:10:59 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:10:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:10:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:10:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:10:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:10:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:10:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:10:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x106d49a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106dde618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc28ac8b6a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106cadd30>, '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 0x106a12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ca5b20>, '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.4DmA64TmzH/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.5hqQ0xgbtg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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 06:10:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 06:10:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 06:10:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4DmA64TmzH/Render', 06:10:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:10:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:10:59 INFO - 'HOME': '/Users/cltbld', 06:10:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:10:59 INFO - 'LOGNAME': 'cltbld', 06:10:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:10:59 INFO - 'MOZ_NO_REMOTE': '1', 06:10:59 INFO - 'NO_EM_RESTART': '1', 06:10:59 INFO - 'PAGER': '/bin/cat', 06:10:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:10:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:10:59 INFO - 'PWD': '/builds/slave/test', 06:10:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:10:59 INFO - 'SHELL': '/bin/bash', 06:10:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5hqQ0xgbtg/Listeners', 06:10:59 INFO - 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/', 06:10:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:10:59 INFO - 'USER': 'cltbld', 06:10:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:10:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:10:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:10:59 INFO - 'XPC_FLAGS': '0x0', 06:10:59 INFO - 'XPC_SERVICE_NAME': '0', 06:10:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:10:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:10:59 INFO - Downloading/unpacking blobuploader==1.2.4 06:10:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:10:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:10:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:10:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:10:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:10:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:11:01 INFO - Downloading blobuploader-1.2.4.tar.gz 06:11:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:11:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:11:01 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 06:11:01 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 06:11:01 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 06:11:01 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 06:11:01 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 06:11:01 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 06:11:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:11:02 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:11:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:11:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:11:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:11:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:11:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:11:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:11:02 INFO - Downloading docopt-0.6.1.tar.gz 06:11:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:11:02 INFO - Installing collected packages: blobuploader, requests, docopt 06:11:02 INFO - Running setup.py install for blobuploader 06:11:03 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:11:03 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:11:03 INFO - Running setup.py install for requests 06:11:03 INFO - Running setup.py install for docopt 06:11:03 INFO - Successfully installed blobuploader requests docopt 06:11:03 INFO - Cleaning up... 06:11:03 INFO - Return code: 0 06:11:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:11:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:11:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:11:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:11:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:11:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:11:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:11:03 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 0x106d49a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106dde618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc28ac8b6a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106cadd30>, '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 0x106a12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ca5b20>, '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.4DmA64TmzH/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.5hqQ0xgbtg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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 06:11:03 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 06:11:03 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 06:11:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4DmA64TmzH/Render', 06:11:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:11:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:11:03 INFO - 'HOME': '/Users/cltbld', 06:11:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:11:03 INFO - 'LOGNAME': 'cltbld', 06:11:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:11:03 INFO - 'MOZ_NO_REMOTE': '1', 06:11:03 INFO - 'NO_EM_RESTART': '1', 06:11:03 INFO - 'PAGER': '/bin/cat', 06:11:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:11:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:11:03 INFO - 'PWD': '/builds/slave/test', 06:11:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:11:03 INFO - 'SHELL': '/bin/bash', 06:11:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5hqQ0xgbtg/Listeners', 06:11:03 INFO - 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/', 06:11:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:11:03 INFO - 'USER': 'cltbld', 06:11:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:11:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:11:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:11:03 INFO - 'XPC_FLAGS': '0x0', 06:11:03 INFO - 'XPC_SERVICE_NAME': '0', 06:11:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:11:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:11:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:11:04 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-xmzTTQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:11:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:11:04 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-p_zUZr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:11:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:11:04 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-Q7BmEc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:11:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:11:04 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-X58uIx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:11:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:11:04 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-_U552r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:11:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:11:04 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-NBNANY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:11:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:11:04 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-NPyAi5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:11:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:11:04 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-YxjdVt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:11:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:11:04 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-NPd9S0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:11:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:11:05 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-MW5n7i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:11:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:11:05 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-UbDW3I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:11:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:11:05 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-cZHoYM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:11:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:11:05 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-6KDJq2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:11:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:11:05 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-dMystf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:11:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:11:05 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-QkVmn2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:11:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:11:05 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-jtT2hT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:11:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:11:05 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-wydKTP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:11:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:11:05 INFO - Running setup.py install for manifestparser 06:11:06 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:11:06 INFO - Running setup.py install for mozcrash 06:11:06 INFO - Running setup.py install for mozdebug 06:11:06 INFO - Running setup.py install for mozdevice 06:11:06 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:11:06 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:11:06 INFO - Running setup.py install for mozfile 06:11:06 INFO - Running setup.py install for mozhttpd 06:11:06 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:11:06 INFO - Running setup.py install for mozinfo 06:11:06 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:11:06 INFO - Running setup.py install for mozInstall 06:11:07 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:11:07 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:11:07 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:11:07 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:11:07 INFO - Running setup.py install for mozleak 06:11:07 INFO - Running setup.py install for mozlog 06:11:07 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:11:07 INFO - Running setup.py install for moznetwork 06:11:07 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:11:07 INFO - Running setup.py install for mozprocess 06:11:07 INFO - Running setup.py install for mozprofile 06:11:07 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:11:07 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:11:07 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:11:07 INFO - Running setup.py install for mozrunner 06:11:07 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:11:08 INFO - Running setup.py install for mozscreenshot 06:11:08 INFO - Running setup.py install for moztest 06:11:08 INFO - Running setup.py install for mozversion 06:11:08 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:11:08 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:11:08 INFO - Cleaning up... 06:11:08 INFO - Return code: 0 06:11:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:11:08 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')]} 06:11:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:11:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:11:08 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')]} 06:11:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:11:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:11:08 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 0x106d49a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106dde618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc28ac8b6a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106cadd30>, '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 0x106a12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ca5b20>, '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.4DmA64TmzH/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.5hqQ0xgbtg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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 06:11:08 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 06:11:08 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 06:11:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4DmA64TmzH/Render', 06:11:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:11:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:11:08 INFO - 'HOME': '/Users/cltbld', 06:11:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:11:08 INFO - 'LOGNAME': 'cltbld', 06:11:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:11:08 INFO - 'MOZ_NO_REMOTE': '1', 06:11:08 INFO - 'NO_EM_RESTART': '1', 06:11:08 INFO - 'PAGER': '/bin/cat', 06:11:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:11:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:11:08 INFO - 'PWD': '/builds/slave/test', 06:11:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:11:08 INFO - 'SHELL': '/bin/bash', 06:11:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5hqQ0xgbtg/Listeners', 06:11:08 INFO - 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/', 06:11:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:11:08 INFO - 'USER': 'cltbld', 06:11:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:11:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:11:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:11:08 INFO - 'XPC_FLAGS': '0x0', 06:11:08 INFO - 'XPC_SERVICE_NAME': '0', 06:11:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:11:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:11:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:11:09 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-5RfRep-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:11:09 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)) 06:11:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:11:09 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-CdPNfR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:11:09 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)) 06:11:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:11:09 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-68Y5sF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:11:09 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)) 06:11:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:11:09 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-NUUwzq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:11:09 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)) 06:11:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:11:09 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-fLThvj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:11:09 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)) 06:11:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:11:09 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-GzPbj5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:11:09 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)) 06:11:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:11:09 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-WdNzWG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:11:09 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)) 06:11:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:11:09 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-bB6BZf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:11:09 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)) 06:11:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:11:09 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-fD5LcZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:11:10 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)) 06:11:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:11:10 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-lOB7c7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:11:10 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)) 06:11:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:11:10 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-pG3OD6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:11:10 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)) 06:11:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:11:10 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-tK9eyd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:11:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 06:11:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:11:10 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-ciDF3M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:11:10 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)) 06:11:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:11:10 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-9WBXSK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:11:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 06:11:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:11:10 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-QhJYrQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:11:10 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)) 06:11:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:11:10 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-P22FgN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:11:10 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)) 06:11:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:11:10 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-a6SPUT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:11:10 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)) 06:11:10 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)) 06:11:10 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)) 06:11:10 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)) 06:11:10 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)) 06:11:10 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)) 06:11:10 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:11:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:11:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:11:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:11:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:11:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:11:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:11:13 INFO - Downloading blessings-1.6.tar.gz 06:11:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:11:13 INFO - Installing collected packages: blessings 06:11:13 INFO - Running setup.py install for blessings 06:11:13 INFO - Successfully installed blessings 06:11:13 INFO - Cleaning up... 06:11:13 INFO - Return code: 0 06:11:13 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:11:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:11:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:11:14 INFO - Reading from file tmpfile_stdout 06:11:14 INFO - Current package versions: 06:11:14 INFO - blessings == 1.6 06:11:14 INFO - blobuploader == 1.2.4 06:11:14 INFO - docopt == 0.6.1 06:11:14 INFO - functools32 == 3.2.3-2 06:11:14 INFO - jsonschema == 2.5.1 06:11:14 INFO - manifestparser == 1.1 06:11:14 INFO - mozInstall == 1.12 06:11:14 INFO - mozcrash == 0.17 06:11:14 INFO - mozdebug == 0.1 06:11:14 INFO - mozdevice == 0.48 06:11:14 INFO - mozfile == 1.2 06:11:14 INFO - mozhttpd == 0.7 06:11:14 INFO - mozinfo == 0.9 06:11:14 INFO - mozleak == 0.1 06:11:14 INFO - mozlog == 3.2 06:11:14 INFO - moznetwork == 0.27 06:11:14 INFO - mozprocess == 0.22 06:11:14 INFO - mozprofile == 0.28 06:11:14 INFO - mozrunner == 6.11 06:11:14 INFO - mozscreenshot == 0.1 06:11:14 INFO - mozsystemmonitor == 0.3 06:11:14 INFO - moztest == 0.7 06:11:14 INFO - mozversion == 1.4 06:11:14 INFO - psutil == 3.1.1 06:11:14 INFO - requests == 1.2.3 06:11:14 INFO - wsgiref == 0.1.2 06:11:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:11:14 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')]} 06:11:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:11:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:11:14 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')]} 06:11:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:11:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:11:14 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 0x106d49a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106dde618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc28ac8b6a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106cadd30>, '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 0x106a12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ca5b20>, '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.4DmA64TmzH/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.5hqQ0xgbtg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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 06:11:14 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 06:11:14 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 06:11:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4DmA64TmzH/Render', 06:11:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:11:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:11:14 INFO - 'HOME': '/Users/cltbld', 06:11:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:11:14 INFO - 'LOGNAME': 'cltbld', 06:11:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:11:14 INFO - 'MOZ_NO_REMOTE': '1', 06:11:14 INFO - 'NO_EM_RESTART': '1', 06:11:14 INFO - 'PAGER': '/bin/cat', 06:11:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:11:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:11:14 INFO - 'PWD': '/builds/slave/test', 06:11:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:11:14 INFO - 'SHELL': '/bin/bash', 06:11:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5hqQ0xgbtg/Listeners', 06:11:14 INFO - 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/', 06:11:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:11:14 INFO - 'USER': 'cltbld', 06:11:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:11:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:11:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:11:14 INFO - 'XPC_FLAGS': '0x0', 06:11:14 INFO - 'XPC_SERVICE_NAME': '0', 06:11:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:11:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:11:14 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)) 06:11:14 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)) 06:11:14 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)) 06:11:14 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)) 06:11:14 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)) 06:11:14 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)) 06:11:14 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)) 06:11:14 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)) 06:11:14 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)) 06:11:14 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)) 06:11:14 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)) 06:11:14 INFO - Cleaning up... 06:11:14 INFO - Return code: 0 06:11:14 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:11:14 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')]} 06:11:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:11:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:11:14 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')]} 06:11:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:11:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:11:14 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 0x106d49a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106dde618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc28ac8b6a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106cadd30>, '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 0x106a12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ca5b20>, '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.4DmA64TmzH/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.5hqQ0xgbtg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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 06:11:14 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 06:11:14 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 06:11:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4DmA64TmzH/Render', 06:11:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:11:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:11:14 INFO - 'HOME': '/Users/cltbld', 06:11:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:11:14 INFO - 'LOGNAME': 'cltbld', 06:11:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:11:14 INFO - 'MOZ_NO_REMOTE': '1', 06:11:14 INFO - 'NO_EM_RESTART': '1', 06:11:14 INFO - 'PAGER': '/bin/cat', 06:11:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:11:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:11:14 INFO - 'PWD': '/builds/slave/test', 06:11:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:11:14 INFO - 'SHELL': '/bin/bash', 06:11:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5hqQ0xgbtg/Listeners', 06:11:14 INFO - 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/', 06:11:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:11:14 INFO - 'USER': 'cltbld', 06:11:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:11:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:11:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:11:14 INFO - 'XPC_FLAGS': '0x0', 06:11:14 INFO - 'XPC_SERVICE_NAME': '0', 06:11:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:11:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:11:14 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 06:11:14 INFO - Cleaning up... 06:11:14 INFO - Return code: 0 06:11:14 INFO - Running post-action listener: _resource_record_post_action 06:11:14 INFO - Running post-action listener: _start_resource_monitoring 06:11:14 INFO - Starting resource monitoring. 06:11:14 INFO - ##### 06:11:14 INFO - ##### Finished create-virtualenv step (success) 06:11:14 INFO - ##### 06:11:14 INFO - ##### 06:11:14 INFO - ##### Running install step. 06:11:14 INFO - ##### 06:11:14 INFO - Running pre-action listener: _resource_record_pre_action 06:11:14 INFO - Running main action method: install 06:11:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:11:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:11:14 INFO - Reading from file tmpfile_stdout 06:11:14 INFO - Detecting whether we're running mozinstall >=1.0... 06:11:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:11:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:11:14 INFO - Reading from file tmpfile_stdout 06:11:14 INFO - Output received: 06:11:14 INFO - Usage: mozinstall [options] installer 06:11:14 INFO - Options: 06:11:14 INFO - -h, --help show this help message and exit 06:11:14 INFO - -d DEST, --destination=DEST 06:11:14 INFO - Directory to install application into. [default: 06:11:14 INFO - "/builds/slave/test"] 06:11:14 INFO - --app=APP Application being installed. [default: firefox] 06:11:14 INFO - mkdir: /builds/slave/test/build/application 06:11:14 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'] 06:11:14 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 06:11:49 INFO - Reading from file tmpfile_stdout 06:11:49 INFO - Output received: 06:11:49 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 06:11:49 INFO - Running post-action listener: _resource_record_post_action 06:11:49 INFO - ##### 06:11:49 INFO - ##### Finished install step (success) 06:11:49 INFO - ##### 06:11:49 INFO - ##### 06:11:49 INFO - ##### Running run-tests step. 06:11:49 INFO - ##### 06:11:49 INFO - Running pre-action listener: _resource_record_pre_action 06:11:49 INFO - Running main action method: run_tests 06:11:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:11:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:11:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:11:49 INFO - 2016-06-29 06:11:49.374 screenresolution[1644:7981] starting screenresolution argv=screenresolution get 06:11:49 INFO - 2016-06-29 06:11:49.377 screenresolution[1644:7981] Display 0: 1600x1200x32@60 06:11:49 INFO - 2016-06-29 06:11:49.382 screenresolution[1645:7983] starting screenresolution argv=screenresolution list 06:11:49 INFO - Available Modes on Display 0 06:11:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:11:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:11:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:11:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:11:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:11:49 INFO - Intel Iris: 06:11:49 INFO - Chipset Model: Intel Iris 06:11:49 INFO - Type: GPU 06:11:49 INFO - Bus: Built-In 06:11:49 INFO - VRAM (Dynamic, Max): 1536 MB 06:11:49 INFO - Vendor: Intel (0x8086) 06:11:49 INFO - Device ID: 0x0a2e 06:11:49 INFO - Revision ID: 0x0009 06:11:49 INFO - Displays: 06:11:49 INFO - Display: 06:11:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:11:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:11:49 INFO - Display Serial Number: 1600x1200 60 06:11:49 INFO - Main Display: Yes 06:11:49 INFO - Mirror: Off 06:11:49 INFO - Online: Yes 06:11:49 INFO - Rotation: Supported 06:11:49 INFO - Return code: 0 06:11:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:11:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:11:49 INFO - Python 2.7.3 06:11:49 INFO - Return code: 0 06:11:49 INFO - grabbing minidump binary from tooltool 06:11:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:11:49 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 0x106cadd30>, '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 0x106a12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ca5b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:11:49 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 06:11:49 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 06:11:49 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 06:11:49 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:11:49 INFO - Return code: 0 06:11:49 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 06:11:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:11:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:11:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 06:11:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:11:49 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/T67JNPS_Qm6m7IbMAigkLA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0325', '--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 06:11:49 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/T67JNPS_Qm6m7IbMAigkLA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0325 --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 06:11:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4DmA64TmzH/Render', 06:11:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:11:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:11:49 INFO - 'HOME': '/Users/cltbld', 06:11:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:11:49 INFO - 'LOGNAME': 'cltbld', 06:11:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:11:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 06:11:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:11:49 INFO - 'MOZ_NO_REMOTE': '1', 06:11:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:11:49 INFO - 'NO_EM_RESTART': '1', 06:11:49 INFO - 'PAGER': '/bin/cat', 06:11:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:11:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:11:49 INFO - 'PWD': '/builds/slave/test', 06:11:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:11:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:11:49 INFO - 'SHELL': '/bin/bash', 06:11:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5hqQ0xgbtg/Listeners', 06:11:49 INFO - 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/', 06:11:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:11:49 INFO - 'USER': 'cltbld', 06:11:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:11:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:11:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:11:49 INFO - 'XPC_FLAGS': '0x0', 06:11:49 INFO - 'XPC_SERVICE_NAME': '0', 06:11:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:11:49 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/T67JNPS_Qm6m7IbMAigkLA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0325', '--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 06:11:49 INFO - mozversion application_buildid: 20160629050409 06:11:49 INFO - mozversion application_changeset: cda8c83f6a5727cad1d971a6ccf0660aa4ea0fb7 06:11:49 INFO - mozversion application_display_name: Nightly 06:11:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:11:49 INFO - mozversion application_name: Firefox 06:11:49 INFO - mozversion application_remotingname: firefox 06:11:49 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 06:11:49 INFO - mozversion application_vendor: Mozilla 06:11:49 INFO - mozversion application_version: 50.0a1 06:11:49 INFO - mozversion platform_buildid: 20160629050409 06:11:49 INFO - mozversion platform_changeset: cda8c83f6a5727cad1d971a6ccf0660aa4ea0fb7 06:11:49 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 06:11:49 INFO - mozversion platform_version: 50.0a1 06:11:49 INFO - using testdate: 1467205909 06:11:49 INFO - actual date: 1467205909 06:11:49 INFO - starting webserver on 'localhost:49272' 06:11:49 INFO - SUITE-START | Running 2 tests 06:11:49 INFO - TEST-START | dromaeo_css 06:11:49 INFO - operating with platform_type : mac_ 06:11:49 INFO - Initialising browser for dromaeo_css test... 06:11:49 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/tmpPs2LBG/profile http://localhost:49272/getInfo.html) 06:11:52 INFO - PROCESS | 1655 | __metrics Screen width/height:1600/1200 06:11:52 INFO - PROCESS | 1655 | colorDepth:24 06:11:52 INFO - PROCESS | 1655 | Browser inner width/height: 1024/689 06:11:52 INFO - PROCESS | 1655 | __metrics 06:11:52 INFO - PROCESS | 1655 | 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" 06:11:52 INFO - PROCESS | 1655 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:11:53 INFO - TEST-INFO | 1655: exit 0 06:11:53 INFO - Browser initialized. 06:11:53 INFO - Running cycle 1/1 for dromaeo_css test... 06:11:53 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/tmpPs2LBG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:12:04 INFO - PROCESS | 1658 | RSS: Main: 243421184 06:12:04 INFO - PROCESS | 1658 | 06:12:56 INFO - PROCESS | 1658 | 1467205976604 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] 06:15:07 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49272/tests/dromaeo/cssquery-ext.html) 06:15:08 INFO - PROCESS | 1658 | RSS: Main: 386613248 06:15:08 INFO - PROCESS | 1658 | 06:18:12 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-ext.html (next: http://localhost:49272/tests/dromaeo/cssquery-jquery.html) 06:18:12 INFO - PROCESS | 1658 | RSS: Main: 372711424 06:18:12 INFO - PROCESS | 1658 | 06:21:14 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49272/tests/dromaeo/cssquery-mootools.html) 06:21:15 INFO - PROCESS | 1658 | RSS: Main: 378245120 06:21:15 INFO - PROCESS | 1658 | 06:24:17 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49272/tests/dromaeo/cssquery-prototype.html) 06:24:18 INFO - PROCESS | 1658 | RSS: Main: 374874112 06:24:18 INFO - PROCESS | 1658 | 06:27:21 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49272/tests/dromaeo/cssquery-yui.html) 06:27:22 INFO - PROCESS | 1658 | RSS: Main: 341364736 06:27:22 INFO - PROCESS | 1658 | 06:30:25 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-yui.html (next: http://localhost:49272/tests/dromaeo/cssquery-dojo.html) 06:30:26 INFO - PROCESS | 1658 | RSS: Main: 379514880 06:30:26 INFO - PROCESS | 1658 | 06:30:26 INFO - PROCESS | 1658 | __start_tp_report 06:30:26 INFO - PROCESS | 1658 | _x_x_mozilla_page_load 06:30:26 INFO - PROCESS | 1658 | _x_x_mozilla_page_load_details 06:30:26 INFO - PROCESS | 1658 | |i|pagename|runs| 06:30:26 INFO - PROCESS | 1658 | |0;dojo.html;3339.5825521809775;3358.882439114631;3392.5080863274843;3406.50605662179;3421.1618153552386;5608.663480191191;5655.943440565593;5696.857578560538;5775.133729940511;5822.330432000321;5242.8165014224505;5759.68321742304;5915.526757859372;5921.85195370116;5958.106284057393;5169.1987741899875;5198.220266959948;5253.86865328366;5257.8948421031555;5363.839084827461;5342.545883599899;5366.8926621467745;5378.623496355257;5384.488473595022;5419.295491586088;5216.8174113906025;5221.765020574083;5242.056429842627;5263.157894736823;5302.549283310923;5234.214867769827;5256.500632439912;5256.7371631418655;5276.12943864262;5352.759125839347;5221.869453263699;5261.6842989420375;5302.708351040691;5325.780166954241;5340.519353258186;5676.914846277309;5743.310802703702;5889.322727886305;5915.053591425351;5943.257092863392;6157.876842463171;6231.3145553989025;6247.281562620311;6252.093446450284;6255.812325630193;6170.290416602058;6201.875962480725;6232.563720539564;6246.843828904268;6249.812505624838;5794.362620111784;5972.984592619265;5975.910361344583;5991.910121348184;6029.155918171459;6183.938160618426;6242.157308763259;6258.74965001404;6298.118263443122;6303.684815759194;6238.625682459067;6246.968765156145;6254.781082662085;6259.3427690091885;6282.4974002079725;6145.985912324506;6149.446549810538;6168.506519478431;6250.312465628777;6271.686415679198;6229.906551401733;6230.096635987757;6263.029230469285;6263.342349053375;6264.467520260738;6214.1921550198185;6222.160008398899;6259.593126446766;6268.27914789805;6270.372962703785;4946.208606622923;5001.249812528077;5038.5969122470115;5056.696598204119;5080.263361812516;4551.817927282872;4573.359729637853;4591.977040114778;4609.2394754865745;4615.423072115986;5837.357890632027;5846.356900740915;5859.355470898197;5873.030949893306;5876.353601103875;6125.052321061979;6138.020573254751;6188.4739797116845;6216;6224.875502490016;5912.793052243151;5920.378360272195;5922.615030023035;5943.286805583358;5959.374265702126;5400.594955378362;5508.118701007899;5531.778728850882;5537.612367134262;5538.584606154554;6238.937610623926;6261.96869015652;6296.811095667137;6343.207099112611;6381.393767591979;6204.317525072148;6227.501799856092;6229.657368844757;6233.5636505446;6240.313565507699;6204.5036397089025;6259.530535209969;6351.047342898602;6359.523035772429;6364.172657554674;5365.3293338332705;6130.356312587114;6263.123162757309;6276.403741644536;6286.74853005893;6174.011119778584;6201.534884883742;6222.16000839899;6236.095766113979;6241.282252541016;6039.456448919618;6103.176071230327;6127.938720612915;6205;6240.656763877849;6100.581173165739;6113.388661133851;6137.376985335611;6183.505319574513;6233.906491402723;6020.217371741645;6098.069176274567;6102.253712742212;6104.786332478342;6142.9078563820685;6027.397260274113;6201;6210.720517576629;6239.844003899939;6285.308616052139;5069.7211653357945;5783.3638299787835;6039.154518367344;6136.417040381157;6146.262448506029 06:30:26 INFO - PROCESS | 1658 | |1;ext.html;514.264601619684;516.3855012535083;532.3026834846352;543.1309904153353;568.2924758675448;25420;35026.8248658757;40720.79639601805;42099.57900420995;43026.92432689184;44946.55053449473;45288.64134075975;45385.77307113468;45394.865128371806;45500.77249613756;8887.289016878667;9595.760105997371;9773.560189791477;9831.459269740162;9843.753906152331;8963.910360896405;12051.397430128502;12315.38423078847;12322.383880805968;12448.066395020365;11184.217104802667;11290.266132701348;12145.271283722961;12151.453184606715;12218.533775946898;8939.910600893973;12062.276263424223;12229.755404891897;12291.078169137305;12323.322217278046;11829.94085029578;11903;11914.761704765942;11926.403679815974;11936.98535624473;42136.789316053226;42336;42451;42504.78747606242;42588.296468141234;3444.0528854564946;3786.678132358753;4172.415861779354;4198.706090573661;4332.241857674926;32863.014109576754;33690.49464258038;34092.97721068372;34097.488537671954;34478.655213448044;3294.0941241158635;3406.6423025582453;3418.572678415198;3449.965500345015;3453.9827300863335;456759;502087;518214;519989;521199;63745.36254637487;64150.39624009344;64176.679116604115;64233.357666423675;64312.678436607515;68858;68882;68912.27719307058;68954;68966.31033689599;16348.100854452881;16782.573481253985;16789.24448399825;16798.244079016473;16804.74792878108;11596.130290228266;11866.703332416759;12584.244945303311;12591.307478088793;12937.288449135387;18350.908245458686;18371.90814045921;18435.631287374177;18446.631067378577;18469.72295415543;6160.291566469824;7241.175941382759;7448.366888814403;7512.046867814298;7577.393808495307;941.9717408477757;1041.9843702344472;1095.6055819904827;1144.5993902134353;1192.362086283834;25520.361990950005;31316.84341578323;31568.842155789072;31815.840920795712;32125.196870078435;1052.2266134391216;1074.355386767933;1097.2319376436526;1149.597640825721;1174.2836869509592;16088.195590220676;16254.918725406298;16263.756043659354;16299.266533006043;16735.079570623733;675612;679241;679643;681774;683355;60198.699006505565;61754.0736888947;61853;61897.38102618916;61949.380506194364;1254.2537190371713;1308.4962289518414;1337.9697632822476;1412.1385954567706;1428.128841406741;26748.59877101884;27505.17484475469;32358.19104522406;32678.34643307168;32754.50868237026;52599;53290.93418131693;53831;53996;53999;512379;514729;515185;515588;515730;36841.89474315775;43623;44846.551534484235;44900.77549612231;45593;47239.055218896116;50246.49753502418;50840;51719.741401292755;51857;41240;41257.381139283534;41333.37999930064;41965.58034419617;43222.13555728931;31246.531302030035;40442;40724;40978.79510602428;41240.968975775846;39399.60600394074;39862.60137398589;40014.79992600018;40133.79933100316;40335.991600209054;34039.31921361509;34944.475832862016;35966;36116.819415902755;36133.457998129525;23370.94830732587;25939.740602594487;26258.768004719812;26321;26868.86565567238 06:30:26 INFO - PROCESS | 1658 | |2;jquery.html;386.38371796983813;387.2506699536397;391.7473229766802;402.5350719918495;406.7173314546391;15802.920985395085;19264.325748598803;20644.69032964507;21331.99999999998;21363.931803409825;20877.164913403485;21237.044333005015;21248.89375553124;21932.342029739135;21988.670169947465;5282.577393808505;5802.883942321161;6113.602615829979;6115.052166914135;6118.69406529674;5856.092305692625;5982.581219314649;6084.787032453865;6106.480949119308;6115.969420152893;5996.910046349309;6008.549358798085;6012.248468941382;6043.6675982820925;6045.3047899491685;5718.056520674084;6009.09863520471;6010.248718910115;6014.548908831834;6023.578349515536;5198.350206224222;5213.7393130343335;5214.843554693365;5220.8694782630355;5224.346956630421;26442.07452739164;26728.198154055408;26957.786899589566;27041.45917081667;27093;2276.704028476304;2430.902763889442;2473.480569080495;2474.5793215153467;2622.4658574733803;12991.675208119777;15497.22513874313;16153.596160095974;16277.267522961494;16749.66500669992;1813.4287699374786;1877.71834224866;1926.5183704073982;1992.8405727541763;2011.8390528757811;91725.33098676045;122419;125848.99999999908;126263;128219;10072.74818129538;10381.429021403896;11277.436128193558;11422.94288528552;11598.130140239517;14085;14132.152070875574;14160.291985400689;14188.716225675429;14192.787108193384;8583.356248281403;8715.477071375737;8758.2993360531;8873.378863479624;8875.378723489293;8795.692150774692;8801.339899507562;8802.427842190236;8944.060873608307;8954.552272386485;6783.389494945479;6909.585424874524;6917.89623155653;6923.134608173978;6946.270641582662;1849.306510058728;1888.6411581799414;1890.366727146394;1891.5743957609427;1891.895945722971;894.3650008494042;986.7138529826414;1038.55342202852;1063.5267306048734;1079.3631757263252;21086.578268434547;23371.7662823373;23439.296821095395;23456.413589660395;23478.882605586863;983.9840887679383;995.8705368302075;1025.348903446302;1025.8204814157493;1029.8455231715152;11498.25261358009;11996.64010079699;12191.329476878856;12196.963258122982;12269.325187114795;527428;620135.8993205095;631380;640042;648890;133147.33426332806;145771;149094.25452872884;157598;167433;966.7293157915794;982.5922242269521;1037.5434808684154;1050.9579616815242;1057.9471026448646;14701.55895323142;17697.82302176962;18213.726794097834;20064;20128.89935550313;55202;57228.42771572231;58241.4175858236;58595.70702146462;59132.11301830562;81279.59360203161;83705.74441383505;87314.563427185;87472;88582.11417885739;8259.710910118118;8359.623816928131;8444.36667249971;8445.451045682012;8533.74398768038;9262.304826820817;13601.726380631377;13649;13847;13871.791923121364;11402.771944560896;11594.246373985665;11667.124965627441;11724.355160465915;11772.52909883595;10373.325733827278;11370.658880233606;11416;11450.484728187419;11690.240134391237;11384.886151138382;11651.53393864236;11658.300501969909;11840.111991600837;11872.99079578211;8251.381146413923;8346.916530834615;8504.362172837185;8550.315974722133;8554.62822458276;9702.563384647567;11354.318740875575;11570.305781653127;11679;11693.532258709896 06:30:26 INFO - PROCESS | 1658 | |3;mootools.html;1397.6296281485402;1459.6642772162402;1501.0843385534822;1771.4774141628218;1965.8820470771755;7837.882431763521;8524.829503409936;8796;8809.163129502704;8902.910970890289;8533.274671652902;8782.297416206704;8785.341099417537;8788.956055219714;8789.296856251485;2057.9588408231825;2132.9786702132933;2252.358077947785;2331.720193576769;2367.230650038736;2047.7542694876636;2370.3600027992434;2400.5558971590226;2410.4576470294132;2418.6613874057557;2328.790408863202;2363.196513185517;2371;2371.5494056129364;2371.8221133414977;2053.414776788623;2239.1267405711624;2309.8036666883336;2314.2247347138646;2319.652052192168;2349.9060037598474;2372.79831214347;2375.394274460019;2375.6674065630827;2384.5707772600927;8739.994900586451;8801.779955501099;8815.51514666693;8821;8821.867671984925;5143.151380022292;5308.867278318035;5310.7610157543;5316.867078323035;5318.707471089088;3412.095794614429;3493.161641206092;3652.7443078984484;3657.9085522861874;3679.8528058877614;1044.9164066874707;1072.9248952573323;1073.2540884085563;1080.4219742437758;1081.935083894961;11594.246373985665;11605.071594272522;11617.361045142514;11635.301881887113;11638.592649257236;4569.223232050559;4614.861554153381;4670.322803193518;4752.809887604526;4758.904821903542;9816.852747208797;9824.90175098254;9835.45904975219;9848.409095454295;9854.162396196403;5174.09453345663;5262.210668399771;5266.1574148136115;5267.104592219332;5273.815416460406;4379.627731642782;4523.977380113079;4721.362616046789;4866;4874.073925954058;3891.9221615568094;3931.4889064422014;3991.660708839723;4039.535453422894;4042.555318914917;1146.495541961551;1289.6969212235117;1297.0272295778166;1299.1036185032485;1305.432137020399;914.5518695839124;915.1443400420628;918.6141820435433;933.8093930238999;975.8731364922514;5815.534757219412;5852.8536786579825;5978.274317670074;6023.337432882379;6133.0800379942475;448.33646203618173;449.80883124671885;450.91658043261447;454.2595569222147;458.7476887711745;3426.229098452878;4018.1160144768105;4111.71218014742;4122.216778812016;4189.560096189917;9856.950715246521;10740.946295268473;10748.247622666338;10751.462426878625;10773.569057237779;10482.68551943443;10580.735481613021;10660.360378377167;10716.624918127829;10718.196135289885;6787.898181527281;8111.229433203836;8147.959260203779;8208.343332533383;8216.34269258458;5888.3228428731245;5925.407459254213;6026.939730602813;6028.4272994065495;6187.133801267916;7856.135825059125;9840.852387214341;9877;9958.55186516594;10061.496925153859;9876.40741555509;10150.086492215441;10157;10259.948700256451;10264.384136951807;6857.862842743018;7394.149672787698;7563.810904727426;7655.387568994356;7730;8500.107488713596;8571.785705357415;8581.828363432822;8589.14108589136;8597.52713600733;7387.3148824337695;7605.277498637621;7666.041744781903;7666.19504951972;7737.767866964;6437.388448097423;7160.818464953224;7295.124585049828;7431.554106753612;7579.962100189464;6332.271788744353;7053.929460705327;7084.972678961625;7094.326039026284;7112.857742845217;4865.829695960624;4960;5064;5112.821051263188;5132.43543210254;5017.598592112549;5079.644424890223;5125.692458452356;5139.486051394831;5223.347081614798 06:30:26 INFO - PROCESS | 1658 | |4;prototype.html;293.86775950822135;298.40170458231245;305.94187104450145;310.59157208271137;313.27633167383345;4545.454545454541;5323.9467605323935;5364.168553874146;5383.5693144548395;5453.182022696597;4679.3682852814945;4994.901121753218;5051.232068775927;5207.947920520804;5302.443243459429;3384.373890830192;3713.6286371362917;4075.8981025474304;4076.531198912126;4084.4070812383193;4036.7779772112667;4037.5154981402125;4047.817848196838;4053.108316170438;4059.5128584569893;3374.2913988062537;3601.3697602919515;3735.589085200626;3908.1011367385518;4047.8785636430953;4009.298372784751;4014.8193331300163;4021.7586944783116;4030.6420743864874;4040.9897525618594;3483.878064267764;3493.7205023598053;3498.9475157872653;3501.4922836188866;3502.807345595991;4859.781309841065;5197.7920883164625;5221.295125158131;5224.973875130638;5250.317458730379;4018.6584140348104;4415.977920110411;4547.386102876136;4564.703294285894;4592.1045396147765;4957.876053098666;5558.6942718150485;5571.8607034824045;5581.706792992154;5605.831825045255;3652.817359132059;3787.8484860605545;3795.69634429245;3831.4061320495366;3840.7311488195837;4887.364642596511;5819.592628516006;5824.534037277008;5827.446392592739;5865.677387743673;3407.795532268072;3605.549306336708;3793.393057110849;3811.1805961718364;3859.749116307431;3585.354636165463;3610.754289770078;3886.7667939923695;3887.6501114899793;3913.7651740895635;4276.401303817468;4400.207962566768;4549.567791059844;4550.544945505423;4587.4036375271;4710.105080034851;4719.9292010619865;5603.683134463397;5652.2652055233375;5673.914891276634;1069.6309773128257;1189.898858597029;1193.7015746063485;1231.3166192762924;1318.6241921052454;788.6002345836072;855.2046596665036;886.025372090708;889.6041261638638;890.4568213389826;4615.215413379734;5148.304978827885;5157.045946499909;5201.323827902348;5278.81524146653;5406.945930540723;5642.1380944288285;5678.375378708339;5701.486866182064;5709.9714501427225;4543.954560454353;4646.256598944153;4928.901421971541;5054.16606259963;5098;2564.3845477085365;2665.9333516662236;2778.652668416448;2900.606999225647;2967.4065186962694;5359.035373632705;5367.665065696435;5433.402325744165;5790.449907258803;5800;4829.034193161382;5614.887702245932;5747.712614369265;5775.335836378787;5806.606414460499;5346.91979620306;5392.0294347017125;5402.297701298806;5768.336641286306;5813.389594092644;5198.324217851655;5572.749226284908;5592.804251851166;5625.774969001358;5646;4861.6596838220985;5287.524122828964;5503.229547863222;5524.309461317335;5563.860903477445;5335.253064571041;5720.227769251098;5748.637047327193;5754.741036653277;5758.798442054508;3772.679322257639;3772.717046221599;3782.2435512898082;3795.1972812913646;3806.9619303806608;4867.610591152628;5148.073346797612;5571.582131340085;5598.86002849932;5623.943760562342;4513.864584062483;4679.770958038942;4911.950880491292;5043.344365232496;5437.564994800486;5131.692098474104;5179.943833138466;5583.804566840141;5587.525060369914;5616.466435688603;4404.559544045698;4729.314249433882;5365.7048862311385;5436.293281873489;5521.1442226454965;5201.973990130176;5360.142377219626;5576.888462230814;5585.944140558705;5597.944020559742;5179.715115668524;5450.618456707993;5466.972665136808;5493.725313734377;5500.844822587302 06:30:26 INFO - PROCESS | 1658 | |5;yui.html;731.3637135691948;757.1368639750683;774.976750697479;777.1684297801355;815.881697153913;15052.021618594783;15104.697906041873;15692.764608530866;15722.056676599397;16145.43490977816;14570.417183312682;14619.195944223064;14759.852401475982;14764.335604897778;14768.261586920664;713.9821504462404;744.8212429017051;751.9135299440552;904.2539904578699;922.2667978956746;874.4359887872318;914.8993610702818;922.3128769067055;924.4268553496807;925.4724806860092;972.8346181149186;975.8292298847708;980.9215262778961;982.3909176310698;982.7592239901235;742.6769355330395;921.2767977137914;972.8248915195262;973.6300205921775;974.0794948773412;718.9065421495172;719.4496210399049;720.91709453413;721.1598487761752;723.0239177110858;15640.530784076496;15694.293756780973;15747.291371888292;15756.606084847856;15761.684766304725;14287.714245715028;14474.059186152866;14505.41978320866;14670.046446980912;14731.263436828223;12831.550895718605;13133.802992955114;13222.933885330513;13386.39761210748;13442.19346839183;4526.117407105616;4769.642276829217;4792.233242681189;4823.927641085386;4826.300186472977;120139.7986020146;121942;122667.3866630661;122804;122808;11268.267562608404;11487.770244595062;11684.766304674029;11880.702982425508;11921.642350729491;23653;23654.290371288887;23671;23702.644460333107;23792.286231413087;7845.1762564931005;8071.686579605436;8073.838523229502;8200.466969646954;8207.220314070153;4367.868963931087;4483.19302525542;4518.615917646971;4573.542645735466;4619.676622636383;3214.903552893417;3315.0054983505333;3361.638536392716;3397.694207521335;3638.3632864248652;627.5262177056293;634.7334119669749;645.296626676915;653.121551513214;655.8458762190882;630.7224821078711;646.1051443750439;653.7156336993423;657.3919124809532;658.1378394303478;8577.270931970728;8754.606042728092;9228.630854765868;9241.722748317561;9438.858417123749;601.7141857617684;611.8684482836212;630.5176539946945;644.711274973841;647.6470323673609;3743.382341913554;4207.558206388347;4484.8430304939175;4575.702579332333;4602.539746025371;107571.46214268879;128483;130604;146050;174049;130570;133700.3314983419;141739;149537;153318;71967.2803271971;72195;96581;104119.4794026025;107919.46040269906;9231.907680923105;9741.853872191781;9830.803383932138;9873.210143188671;9964.750881228027;54965.17552236639;56770.86458270894;57307.71346143409;62591.6870415645;64873.67563162154;85345;92490;96597.03402965881;98315.50842246029;99759;12819.294938778457;13248.536301229797;13287.933560332136;13319.800202997156;13571.728565428439;15007.399704011717;15493.690126197638;15640.670543003973;15724.99012751741;15738.842611574195;9403.576839042396;9910.959349268223;13842.515511957034;14610.415583376545;14795.408183672533;11077.386974548272;13967.511137110152;14132.293385330488;14411.42354305858;14441.711165776836;13896.791548126585;14016.229107399191;14033.298335083;14072.226027568582;14243.928780356031;11210.215284929976;11413.942930285295;11417.543298268309;11603.36181510025;11817.881821181678;11383.54465821358;11458.55131386208;11460.598879039193;11533.88466115328;11640.359780211831 06:30:26 INFO - PROCESS | 1658 | __end_tp_report 06:30:26 INFO - PROCESS | 1658 | __start_cc_report 06:30:26 INFO - PROCESS | 1658 | _x_x_mozilla_cycle_collect,1403 06:30:26 INFO - PROCESS | 1658 | __end_cc_report 06:30:26 INFO - PROCESS | 1658 | __startTimestamp1467207026206__endTimestamp 06:30:26 INFO - PROCESS | 1658 | 06:30:26 INFO - PROCESS | 1658 | ------- Summary: start ------- 06:30:26 INFO - PROCESS | 1658 | Number of tests: 6 06:30:26 INFO - PROCESS | 1658 | 06:30:26 INFO - PROCESS | 1658 | [#0] dojo.html Cycles:165 Average:5798.88 Median:6098.07 stddev:622.45 (10.2%) stddev-sans-first:593.71 06:30:26 INFO - PROCESS | 1658 | Values: 3339.6 3358.9 3392.5 3406.5 3421.2 5608.7 5655.9 5696.9 5775.1 5822.3 5242.8 5759.7 5915.5 5921.9 5958.1 5169.2 5198.2 5253.9 5257.9 5363.8 5342.5 5366.9 5378.6 5384.5 5419.3 5216.8 5221.8 5242.1 5263.2 5302.5 5234.2 5256.5 5256.7 5276.1 5352.8 5221.9 5261.7 5302.7 5325.8 5340.5 5676.9 5743.3 5889.3 5915.1 5943.3 6157.9 6231.3 6247.3 6252.1 6255.8 6170.3 6201.9 6232.6 6246.8 6249.8 5794.4 5973.0 5975.9 5991.9 6029.2 6183.9 6242.2 6258.7 6298.1 6303.7 6238.6 6247.0 6254.8 6259.3 6282.5 6146.0 6149.4 6168.5 6250.3 6271.7 6229.9 6230.1 6263.0 6263.3 6264.5 6214.2 6222.2 6259.6 6268.3 6270.4 4946.2 5001.2 5038.6 5056.7 5080.3 4551.8 4573.4 4592.0 4609.2 4615.4 5837.4 5846.4 5859.4 5873.0 5876.4 6125.1 6138.0 6188.5 6216.0 6224.9 5912.8 5920.4 5922.6 5943.3 5959.4 5400.6 5508.1 5531.8 5537.6 5538.6 6238.9 6262.0 6296.8 6343.2 6381.4 6204.3 6227.5 6229.7 6233.6 6240.3 6204.5 6259.5 6351.0 6359.5 6364.2 5365.3 6130.4 6263.1 6276.4 6286.7 6174.0 6201.5 6222.2 6236.1 6241.3 6039.5 6103.2 6127.9 6205.0 6240.7 6100.6 6113.4 6137.4 6183.5 6233.9 6020.2 6098.1 6102.3 6104.8 6142.9 6027.4 6201.0 6210.7 6239.8 6285.3 5069.7 5783.4 6039.2 6136.4 6146.3 06:30:26 INFO - PROCESS | 1658 | 06:30:26 INFO - PROCESS | 1658 | [#1] ext.html Cycles:180 Average:71677.89 Median:40074.30 stddev:152569.48 (380.7%) stddev-sans-first:152903.92 06:30:26 INFO - PROCESS | 1658 | Values: 514.3 516.4 532.3 543.1 568.3 25420.0 35026.8 40720.8 42099.6 43026.9 44946.6 45288.6 45385.8 45394.9 45500.8 8887.3 9595.8 9773.6 9831.5 9843.8 8963.9 12051.4 12315.4 12322.4 12448.1 11184.2 11290.3 12145.3 12151.5 12218.5 8939.9 12062.3 12229.8 12291.1 12323.3 11829.9 11903.0 11914.8 11926.4 11937.0 42136.8 42336.0 42451.0 42504.8 42588.3 3444.1 3786.7 4172.4 4198.7 4332.2 32863.0 33690.5 34093.0 34097.5 34478.7 3294.1 3406.6 3418.6 3450.0 3454.0 456759.0 502087.0 518214.0 519989.0 521199.0 63745.4 64150.4 64176.7 64233.4 64312.7 68858.0 68882.0 68912.3 68954.0 68966.3 16348.1 16782.6 16789.2 16798.2 16804.7 11596.1 11866.7 12584.2 12591.3 12937.3 18350.9 18371.9 18435.6 18446.6 18469.7 6160.3 7241.2 7448.4 7512.0 7577.4 942.0 1042.0 1095.6 1144.6 1192.4 25520.4 31316.8 31568.8 31815.8 32125.2 1052.2 1074.4 1097.2 1149.6 1174.3 16088.2 16254.9 16263.8 16299.3 16735.1 675612.0 679241.0 679643.0 681774.0 683355.0 60198.7 61754.1 61853.0 61897.4 61949.4 1254.3 1308.5 1338.0 1412.1 1428.1 26748.6 27505.2 32358.2 32678.3 32754.5 52599.0 53290.9 53831.0 53996.0 53999.0 512379.0 514729.0 515185.0 515588.0 515730.0 36841.9 43623.0 44846.6 44900.8 45593.0 47239.1 50246.5 50840.0 51719.7 51857.0 41240.0 41257.4 41333.4 41965.6 43222.1 31246.5 40442.0 40724.0 40978.8 41241.0 39399.6 39862.6 40014.8 40133.8 40336.0 34039.3 34944.5 35966.0 36116.8 36133.5 23370.9 25939.7 26258.8 26321.0 26868.9 06:30:26 INFO - PROCESS | 1658 | 06:30:26 INFO - PROCESS | 1658 | [#2] jquery.html Cycles:180 Average:36861.39 Median:23467.65 stddev:103255.50 (440.0%) stddev-sans-first:103508.83 06:30:26 INFO - PROCESS | 1658 | Values: 386.4 387.3 391.7 402.5 406.7 15802.9 19264.3 20644.7 21332.0 21363.9 20877.2 21237.0 21248.9 21932.3 21988.7 5282.6 5802.9 6113.6 6115.1 6118.7 5856.1 5982.6 6084.8 6106.5 6116.0 5996.9 6008.5 6012.2 6043.7 6045.3 5718.1 6009.1 6010.2 6014.5 6023.6 5198.4 5213.7 5214.8 5220.9 5224.3 26442.1 26728.2 26957.8 27041.5 27093.0 2276.7 2430.9 2473.5 2474.6 2622.5 12991.7 15497.2 16153.6 16277.3 16749.7 1813.4 1877.7 1926.5 1992.8 2011.8 91725.3 122419.0 125849.0 126263.0 128219.0 10072.7 10381.4 11277.4 11422.9 11598.1 14085.0 14132.2 14160.3 14188.7 14192.8 8583.4 8715.5 8758.3 8873.4 8875.4 8795.7 8801.3 8802.4 8944.1 8954.6 6783.4 6909.6 6917.9 6923.1 6946.3 1849.3 1888.6 1890.4 1891.6 1891.9 894.4 986.7 1038.6 1063.5 1079.4 21086.6 23371.8 23439.3 23456.4 23478.9 984.0 995.9 1025.3 1025.8 1029.8 11498.3 11996.6 12191.3 12197.0 12269.3 527428.0 620135.9 631380.0 640042.0 648890.0 133147.3 145771.0 149094.3 157598.0 167433.0 966.7 982.6 1037.5 1051.0 1057.9 14701.6 17697.8 18213.7 20064.0 20128.9 55202.0 57228.4 58241.4 58595.7 59132.1 81279.6 83705.7 87314.6 87472.0 88582.1 8259.7 8359.6 8444.4 8445.5 8533.7 9262.3 13601.7 13649.0 13847.0 13871.8 11402.8 11594.2 11667.1 11724.4 11772.5 10373.3 11370.7 11416.0 11450.5 11690.2 11384.9 11651.5 11658.3 11840.1 11873.0 8251.4 8346.9 8504.4 8550.3 8554.6 9702.6 11354.3 11570.3 11679.0 11693.5 06:30:26 INFO - PROCESS | 1658 | 06:30:26 INFO - PROCESS | 1658 | [#3] mootools.html Cycles:180 Average:5679.57 Median:4988.80 stddev:3199.39 (64.1%) stddev-sans-first:3192.18 06:30:26 INFO - PROCESS | 1658 | Values: 1397.6 1459.7 1501.1 1771.5 1965.9 7837.9 8524.8 8796.0 8809.2 8902.9 8533.3 8782.3 8785.3 8789.0 8789.3 2058.0 2133.0 2252.4 2331.7 2367.2 2047.8 2370.4 2400.6 2410.5 2418.7 2328.8 2363.2 2371.0 2371.5 2371.8 2053.4 2239.1 2309.8 2314.2 2319.7 2349.9 2372.8 2375.4 2375.7 2384.6 8740.0 8801.8 8815.5 8821.0 8821.9 5143.2 5308.9 5310.8 5316.9 5318.7 3412.1 3493.2 3652.7 3657.9 3679.9 1044.9 1072.9 1073.3 1080.4 1081.9 11594.2 11605.1 11617.4 11635.3 11638.6 4569.2 4614.9 4670.3 4752.8 4758.9 9816.9 9824.9 9835.5 9848.4 9854.2 5174.1 5262.2 5266.2 5267.1 5273.8 4379.6 4524.0 4721.4 4866.0 4874.1 3891.9 3931.5 3991.7 4039.5 4042.6 1146.5 1289.7 1297.0 1299.1 1305.4 914.6 915.1 918.6 933.8 975.9 5815.5 5852.9 5978.3 6023.3 6133.1 448.3 449.8 450.9 454.3 458.7 3426.2 4018.1 4111.7 4122.2 4189.6 9857.0 10740.9 10748.2 10751.5 10773.6 10482.7 10580.7 10660.4 10716.6 10718.2 6787.9 8111.2 8148.0 8208.3 8216.3 5888.3 5925.4 6026.9 6028.4 6187.1 7856.1 9840.9 9877.0 9958.6 10061.5 9876.4 10150.1 10157.0 10259.9 10264.4 6857.9 7394.1 7563.8 7655.4 7730.0 8500.1 8571.8 8581.8 8589.1 8597.5 7387.3 7605.3 7666.0 7666.2 7737.8 6437.4 7160.8 7295.1 7431.6 7580.0 6332.3 7053.9 7085.0 7094.3 7112.9 4865.8 4960.0 5064.0 5112.8 5132.4 5017.6 5079.6 5125.7 5139.5 5223.3 06:30:26 INFO - PROCESS | 1658 | 06:30:26 INFO - PROCESS | 1658 | [#4] prototype.html Cycles:180 Average:4457.17 Median:5047.29 stddev:1369.82 (27.1%) stddev-sans-first:1337.54 06:30:26 INFO - PROCESS | 1658 | Values: 293.9 298.4 305.9 310.6 313.3 4545.5 5323.9 5364.2 5383.6 5453.2 4679.4 4994.9 5051.2 5207.9 5302.4 3384.4 3713.6 4075.9 4076.5 4084.4 4036.8 4037.5 4047.8 4053.1 4059.5 3374.3 3601.4 3735.6 3908.1 4047.9 4009.3 4014.8 4021.8 4030.6 4041.0 3483.9 3493.7 3498.9 3501.5 3502.8 4859.8 5197.8 5221.3 5225.0 5250.3 4018.7 4416.0 4547.4 4564.7 4592.1 4957.9 5558.7 5571.9 5581.7 5605.8 3652.8 3787.8 3795.7 3831.4 3840.7 4887.4 5819.6 5824.5 5827.4 5865.7 3407.8 3605.5 3793.4 3811.2 3859.7 3585.4 3610.8 3886.8 3887.7 3913.8 4276.4 4400.2 4549.6 4550.5 4587.4 4710.1 4719.9 5603.7 5652.3 5673.9 1069.6 1189.9 1193.7 1231.3 1318.6 788.6 855.2 886.0 889.6 890.5 4615.2 5148.3 5157.0 5201.3 5278.8 5406.9 5642.1 5678.4 5701.5 5710.0 4544.0 4646.3 4928.9 5054.2 5098.0 2564.4 2665.9 2778.7 2900.6 2967.4 5359.0 5367.7 5433.4 5790.4 5800.0 4829.0 5614.9 5747.7 5775.3 5806.6 5346.9 5392.0 5402.3 5768.3 5813.4 5198.3 5572.7 5592.8 5625.8 5646.0 4861.7 5287.5 5503.2 5524.3 5563.9 5335.3 5720.2 5748.6 5754.7 5758.8 3772.7 3772.7 3782.2 3795.2 3807.0 4867.6 5148.1 5571.6 5598.9 5623.9 4513.9 4679.8 4912.0 5043.3 5437.6 5131.7 5179.9 5583.8 5587.5 5616.5 4404.6 4729.3 5365.7 5436.3 5521.1 5202.0 5360.1 5576.9 5585.9 5597.9 5179.7 5450.6 5467.0 5493.7 5500.8 06:30:26 INFO - PROCESS | 1658 | 06:30:26 INFO - PROCESS | 1658 | [#5] yui.html Cycles:180 Average:24927.18 Median:3975.47 stddev:39666.02 (997.8%) stddev-sans-first:39735.69 06:30:26 INFO - PROCESS | 1658 | Values: 731.4 757.1 775.0 777.2 815.9 15052.0 15104.7 15692.8 15722.1 16145.4 14570.4 14619.2 14759.9 14764.3 14768.3 714.0 744.8 751.9 904.3 922.3 874.4 914.9 922.3 924.4 925.5 972.8 975.8 980.9 982.4 982.8 742.7 921.3 972.8 973.6 974.1 718.9 719.4 720.9 721.2 723.0 15640.5 15694.3 15747.3 15756.6 15761.7 14287.7 14474.1 14505.4 14670.0 14731.3 12831.6 13133.8 13222.9 13386.4 13442.2 4526.1 4769.6 4792.2 4823.9 4826.3 120139.8 121942.0 122667.4 122804.0 122808.0 11268.3 11487.8 11684.8 11880.7 11921.6 23653.0 23654.3 23671.0 23702.6 23792.3 7845.2 8071.7 8073.8 8200.5 8207.2 4367.9 4483.2 4518.6 4573.5 4619.7 3214.9 3315.0 3361.6 3397.7 3638.4 627.5 634.7 645.3 653.1 655.8 630.7 646.1 653.7 657.4 658.1 8577.3 8754.6 9228.6 9241.7 9438.9 601.7 611.9 630.5 644.7 647.6 3743.4 4207.6 4484.8 4575.7 4602.5 107571.5 128483.0 130604.0 146050.0 174049.0 130570.0 133700.3 141739.0 149537.0 153318.0 71967.3 72195.0 96581.0 104119.5 107919.5 9231.9 9741.9 9830.8 9873.2 9964.8 54965.2 56770.9 57307.7 62591.7 64873.7 85345.0 92490.0 96597.0 98315.5 99759.0 12819.3 13248.5 13287.9 13319.8 13571.7 15007.4 15493.7 15640.7 15725.0 15738.8 9403.6 9911.0 13842.5 14610.4 14795.4 11077.4 13967.5 14132.3 14411.4 14441.7 13896.8 14016.2 14033.3 14072.2 14243.9 11210.2 11413.9 11417.5 11603.4 11817.9 11383.5 11458.6 11460.6 11533.9 11640.4 06:30:26 INFO - PROCESS | 1658 | -------- Summary: end -------- 06:30:26 INFO - PROCESS | 1658 | 06:30:26 INFO - TEST-INFO | 1658: exit 0 06:30:26 INFO - TEST-OK | dromaeo_css | took 1116821ms 06:30:26 INFO - TEST-START | kraken 06:30:26 INFO - operating with platform_type : mac_ 06:30:26 INFO - Initialising browser for kraken test... 06:30:26 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/tmpryoJJL/profile http://localhost:49272/getInfo.html) 06:30:29 INFO - PROCESS | 1664 | __metrics Screen width/height:1600/1200 06:30:29 INFO - PROCESS | 1664 | colorDepth:24 06:30:29 INFO - PROCESS | 1664 | Browser inner width/height: 1024/689 06:30:29 INFO - PROCESS | 1664 | __metrics 06:30:29 INFO - PROCESS | 1664 | 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" 06:30:29 INFO - PROCESS | 1664 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:30:29 INFO - TEST-INFO | 1664: exit 0 06:30:29 INFO - Browser initialized. 06:30:29 INFO - Running cycle 1/1 for kraken test... 06:30:29 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/tmpryoJJL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:30:41 INFO - PROCESS | 1665 | RSS: Main: 243437568 06:30:41 INFO - PROCESS | 1665 | 06:30:44 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection) 06:30:45 INFO - PROCESS | 1665 | RSS: Main: 294735872 06:30:45 INFO - PROCESS | 1665 | 06:30:48 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-dft) 06:30:48 INFO - PROCESS | 1665 | RSS: Main: 304304128 06:30:48 INFO - PROCESS | 1665 | 06:30:52 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-fft) 06:30:52 INFO - PROCESS | 1665 | RSS: Main: 388214784 06:30:52 INFO - PROCESS | 1665 | 06:30:54 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator) 06:30:55 INFO - PROCESS | 1665 | RSS: Main: 288817152 06:30:55 INFO - PROCESS | 1665 | 06:30:57 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:30:58 INFO - PROCESS | 1665 | RSS: Main: 292610048 06:30:58 INFO - PROCESS | 1665 | 06:31:01 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom) 06:31:01 INFO - PROCESS | 1665 | RSS: Main: 330227712 06:31:01 INFO - PROCESS | 1665 | 06:31:04 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate) 06:31:05 INFO - PROCESS | 1665 | RSS: Main: 331386880 06:31:05 INFO - PROCESS | 1665 | 06:31:08 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial) 06:31:08 INFO - PROCESS | 1665 | RSS: Main: 330526720 06:31:08 INFO - PROCESS | 1665 | 06:31:10 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:31:10 INFO - PROCESS | 1665 | RSS: Main: 311173120 06:31:10 INFO - PROCESS | 1665 | 06:31:12 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:31:13 INFO - PROCESS | 1665 | RSS: Main: 317636608 06:31:13 INFO - PROCESS | 1665 | 06:31:15 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:31:16 INFO - PROCESS | 1665 | RSS: Main: 320040960 06:31:16 INFO - PROCESS | 1665 | 06:31:19 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:31:19 INFO - PROCESS | 1665 | RSS: Main: 335310848 06:31:19 INFO - PROCESS | 1665 | 06:31:22 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:31:23 INFO - PROCESS | 1665 | RSS: Main: 331448320 06:31:23 INFO - PROCESS | 1665 | 06:31:25 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49272/tests/kraken/driver.html?testName=ai-astar) 06:31:25 INFO - PROCESS | 1665 | RSS: Main: 374919168 06:31:25 INFO - PROCESS | 1665 | 06:31:25 INFO - PROCESS | 1665 | __start_tp_report 06:31:25 INFO - PROCESS | 1665 | _x_x_mozilla_page_load 06:31:25 INFO - PROCESS | 1665 | _x_x_mozilla_page_load_details 06:31:25 INFO - PROCESS | 1665 | |i|pagename|runs| 06:31:25 INFO - PROCESS | 1665 | |0;ai-astar;100;111;113;111;112;112;112;112;113;113 06:31:25 INFO - PROCESS | 1665 | |1;audio-beat-detection;153;118;118;171;118;119;137;115;152;119 06:31:25 INFO - PROCESS | 1665 | |2;audio-dft;184;182;186;183;183;185;178;181;186;184 06:31:25 INFO - PROCESS | 1665 | |3;audio-fft;79;70;73;99;57;73;86;84;86;57 06:31:25 INFO - PROCESS | 1665 | |4;audio-oscillator;86;89;93;72;76;70;119;105;98;105 06:31:25 INFO - PROCESS | 1665 | |5;imaging-gaussian-blur;88;84;103;85;87;84;88;85;88;85 06:31:25 INFO - PROCESS | 1665 | |6;imaging-darkroom;94;93;94;95;94;93;95;95;93;94 06:31:25 INFO - PROCESS | 1665 | |7;imaging-desaturate;86;84;85;85;87;84;84;84;85;84 06:31:25 INFO - PROCESS | 1665 | |8;json-parse-financial;56;53;54;54;51;54;53;52;54;54 06:31:25 INFO - PROCESS | 1665 | |9;json-stringify-tinderbox;64;72;73;74;70;70;69;69;69;71 06:31:25 INFO - PROCESS | 1665 | |10;stanford-crypto-aes;83;84;85;85;85;85;85;86;100;87 06:31:25 INFO - PROCESS | 1665 | |11;stanford-crypto-ccm;138;140;136;138;136;137;134;138;138;142 06:31:25 INFO - PROCESS | 1665 | |12;stanford-crypto-pbkdf2;166;166;165;167;164;218;202;215;226;167 06:31:25 INFO - PROCESS | 1665 | |13;stanford-crypto-sha256-iterative;66;66;67;65;64;66;67;65;66;65 06:31:25 INFO - PROCESS | 1665 | __end_tp_report 06:31:25 INFO - PROCESS | 1665 | __start_cc_report 06:31:25 INFO - PROCESS | 1665 | _x_x_mozilla_cycle_collect,2039 06:31:25 INFO - PROCESS | 1665 | __end_cc_report 06:31:25 INFO - PROCESS | 1665 | __startTimestamp1467207085321__endTimestamp 06:31:25 INFO - PROCESS | 1665 | 06:31:25 INFO - PROCESS | 1665 | ------- Summary: start ------- 06:31:25 INFO - PROCESS | 1665 | Number of tests: 14 06:31:25 INFO - PROCESS | 1665 | 06:31:25 INFO - PROCESS | 1665 | [#0] ai-astar Cycles:10 Average:110.90 Median:112.00 stddev:3.90 (3.5%) stddev-sans-first:0.78 06:31:25 INFO - PROCESS | 1665 | Values: 100.0 111.0 113.0 111.0 112.0 112.0 112.0 112.0 113.0 113.0 06:31:25 INFO - PROCESS | 1665 | 06:31:25 INFO - PROCESS | 1665 | [#1] audio-beat-detection Cycles:10 Average:132.00 Median:128.00 stddev:20.01 (15.6%) stddev-sans-first:19.72 06:31:25 INFO - PROCESS | 1665 | Values: 153.0 118.0 118.0 171.0 118.0 119.0 137.0 115.0 152.0 119.0 06:31:25 INFO - PROCESS | 1665 | 06:31:25 INFO - PROCESS | 1665 | [#2] audio-dft Cycles:10 Average:183.20 Median:184.00 stddev:2.44 (1.3%) stddev-sans-first:2.57 06:31:25 INFO - PROCESS | 1665 | Values: 184.0 182.0 186.0 183.0 183.0 185.0 178.0 181.0 186.0 184.0 06:31:25 INFO - PROCESS | 1665 | 06:31:25 INFO - PROCESS | 1665 | [#3] audio-fft Cycles:10 Average:76.40 Median:81.50 stddev:13.23 (16.2%) stddev-sans-first:14.00 06:31:25 INFO - PROCESS | 1665 | Values: 79.0 70.0 73.0 99.0 57.0 73.0 86.0 84.0 86.0 57.0 06:31:25 INFO - PROCESS | 1665 | 06:31:25 INFO - PROCESS | 1665 | [#4] audio-oscillator Cycles:10 Average:91.30 Median:81.00 stddev:15.93 (19.7%) stddev-sans-first:16.78 06:31:25 INFO - PROCESS | 1665 | Values: 86.0 89.0 93.0 72.0 76.0 70.0 119.0 105.0 98.0 105.0 06:31:25 INFO - PROCESS | 1665 | 06:31:25 INFO - PROCESS | 1665 | [#5] imaging-gaussian-blur Cycles:10 Average:87.70 Median:86.00 stddev:5.62 (6.5%) stddev-sans-first:5.96 06:31:25 INFO - PROCESS | 1665 | Values: 88.0 84.0 103.0 85.0 87.0 84.0 88.0 85.0 88.0 85.0 06:31:25 INFO - PROCESS | 1665 | 06:31:25 INFO - PROCESS | 1665 | [#6] imaging-darkroom Cycles:10 Average:94.00 Median:94.00 stddev:0.82 (0.9%) stddev-sans-first:0.87 06:31:25 INFO - PROCESS | 1665 | Values: 94.0 93.0 94.0 95.0 94.0 93.0 95.0 95.0 93.0 94.0 06:31:25 INFO - PROCESS | 1665 | 06:31:25 INFO - PROCESS | 1665 | [#7] imaging-desaturate Cycles:10 Average:84.80 Median:85.00 stddev:1.03 (1.2%) stddev-sans-first:1.00 06:31:25 INFO - PROCESS | 1665 | Values: 86.0 84.0 85.0 85.0 87.0 84.0 84.0 84.0 85.0 84.0 06:31:25 INFO - PROCESS | 1665 | 06:31:25 INFO - PROCESS | 1665 | [#8] json-parse-financial Cycles:10 Average:53.50 Median:54.00 stddev:1.35 (2.5%) stddev-sans-first:1.09 06:31:25 INFO - PROCESS | 1665 | Values: 56.0 53.0 54.0 54.0 51.0 54.0 53.0 52.0 54.0 54.0 06:31:25 INFO - PROCESS | 1665 | 06:31:25 INFO - PROCESS | 1665 | [#9] json-stringify-tinderbox Cycles:10 Average:70.10 Median:70.50 stddev:2.77 (3.9%) stddev-sans-first:1.86 06:31:25 INFO - PROCESS | 1665 | Values: 64.0 72.0 73.0 74.0 70.0 70.0 69.0 69.0 69.0 71.0 06:31:25 INFO - PROCESS | 1665 | 06:31:25 INFO - PROCESS | 1665 | [#10] stanford-crypto-aes Cycles:10 Average:86.50 Median:85.00 stddev:4.86 (5.7%) stddev-sans-first:4.99 06:31:25 INFO - PROCESS | 1665 | Values: 83.0 84.0 85.0 85.0 85.0 85.0 85.0 86.0 100.0 87.0 06:31:25 INFO - PROCESS | 1665 | 06:31:25 INFO - PROCESS | 1665 | [#11] stanford-crypto-ccm Cycles:10 Average:137.70 Median:138.00 stddev:2.21 (1.6%) stddev-sans-first:2.35 06:31:25 INFO - PROCESS | 1665 | Values: 138.0 140.0 136.0 138.0 136.0 137.0 134.0 138.0 138.0 142.0 06:31:25 INFO - PROCESS | 1665 | 06:31:25 INFO - PROCESS | 1665 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:185.60 Median:184.50 stddev:26.18 (14.2%) stddev-sans-first:26.79 06:31:25 INFO - PROCESS | 1665 | Values: 166.0 166.0 165.0 167.0 164.0 218.0 202.0 215.0 226.0 167.0 06:31:25 INFO - PROCESS | 1665 | 06:31:25 INFO - PROCESS | 1665 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.70 Median:66.00 stddev:0.95 (1.4%) stddev-sans-first:1.00 06:31:25 INFO - PROCESS | 1665 | Values: 66.0 66.0 67.0 65.0 64.0 66.0 67.0 65.0 66.0 65.0 06:31:25 INFO - PROCESS | 1665 | -------- Summary: end -------- 06:31:25 INFO - PROCESS | 1665 | 06:31:26 INFO - TEST-INFO | 1665: exit 0 06:31:26 INFO - TEST-OK | kraken | took 59373ms 06:31:26 INFO - SUITE-END | took 1176s 06:31:26 INFO - Completed test suite (00:19:36) 06:31:26 INFO - javascript benchmark 06:31:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3339.5825521809775, 3358.882439114631, 3392.5080863274843, 3406.50605662179, 3421.1618153552386, 5608.663480191191, 5655.943440565593, 5696.857578560538, 5775.133729940511, 5822.330432000321, 5242.8165014224505, 5759.68321742304, 5915.526757859372, 5921.85195370116, 5958.106284057393, 5169.1987741899875, 5198.220266959948, 5253.86865328366, 5257.8948421031555, 5363.839084827461, 5342.545883599899, 5366.8926621467745, 5378.623496355257, 5384.488473595022, 5419.295491586088, 5216.8174113906025, 5221.765020574083, 5242.056429842627, 5263.157894736823, 5302.549283310923, 5234.214867769827, 5256.500632439912, 5256.7371631418655, 5276.12943864262, 5352.759125839347, 5221.869453263699, 5261.6842989420375, 5302.708351040691, 5325.780166954241, 5340.519353258186, 5676.914846277309, 5743.310802703702, 5889.322727886305, 5915.053591425351, 5943.257092863392, 6157.876842463171, 6231.3145553989025, 6247.281562620311, 6252.093446450284, 6255.812325630193, 6170.290416602058, 6201.875962480725, 6232.563720539564, 6246.843828904268, 6249.812505624838, 5794.362620111784, 5972.984592619265, 5975.910361344583, 5991.910121348184, 6029.155918171459, 6183.938160618426, 6242.157308763259, 6258.74965001404, 6298.118263443122, 6303.684815759194, 6238.625682459067, 6246.968765156145, 6254.781082662085, 6259.3427690091885, 6282.4974002079725, 6145.985912324506, 6149.446549810538, 6168.506519478431, 6250.312465628777, 6271.686415679198, 6229.906551401733, 6230.096635987757, 6263.029230469285, 6263.342349053375, 6264.467520260738, 6214.1921550198185, 6222.160008398899, 6259.593126446766, 6268.27914789805, 6270.372962703785, 4946.208606622923, 5001.249812528077, 5038.5969122470115, 5056.696598204119, 5080.263361812516, 4551.817927282872, 4573.359729637853, 4591.977040114778, 4609.2394754865745, 4615.423072115986, 5837.357890632027, 5846.356900740915, 5859.355470898197, 5873.030949893306, 5876.353601103875, 6125.052321061979, 6138.020573254751, 6188.4739797116845, 6216.0, 6224.875502490016, 5912.793052243151, 5920.378360272195, 5922.615030023035, 5943.286805583358, 5959.374265702126, 5400.594955378362, 5508.118701007899, 5531.778728850882, 5537.612367134262, 5538.584606154554, 6238.937610623926, 6261.96869015652, 6296.811095667137, 6343.207099112611, 6381.393767591979, 6204.317525072148, 6227.501799856092, 6229.657368844757, 6233.5636505446, 6240.313565507699, 6204.5036397089025, 6259.530535209969, 6351.047342898602, 6359.523035772429, 6364.172657554674, 5365.3293338332705, 6130.356312587114, 6263.123162757309, 6276.403741644536, 6286.74853005893, 6174.011119778584, 6201.534884883742, 6222.16000839899, 6236.095766113979, 6241.282252541016, 6039.456448919618, 6103.176071230327, 6127.938720612915, 6205.0, 6240.656763877849, 6100.581173165739, 6113.388661133851, 6137.376985335611, 6183.505319574513, 6233.906491402723, 6020.217371741645, 6098.069176274567, 6102.253712742212, 6104.786332478342, 6142.9078563820685, 6027.397260274113, 6201.0, 6210.720517576629, 6239.844003899939, 6285.308616052139, 5069.7211653357945, 5783.3638299787835, 6039.154518367344, 6136.417040381157, 6146.262448506029], "value": 5760.054911386279, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [514.264601619684, 516.3855012535083, 532.3026834846352, 543.1309904153353, 568.2924758675448, 25420.0, 35026.8248658757, 40720.79639601805, 42099.57900420995, 43026.92432689184, 44946.55053449473, 45288.64134075975, 45385.77307113468, 45394.865128371806, 45500.77249613756, 8887.289016878667, 9595.760105997371, 9773.560189791477, 9831.459269740162, 9843.753906152331, 8963.910360896405, 12051.397430128502, 12315.38423078847, 12322.383880805968, 12448.066395020365, 11184.217104802667, 11290.266132701348, 12145.271283722961, 12151.453184606715, 12218.533775946898, 8939.910600893973, 12062.276263424223, 12229.755404891897, 12291.078169137305, 12323.322217278046, 11829.94085029578, 11903.0, 11914.761704765942, 11926.403679815974, 11936.98535624473, 42136.789316053226, 42336.0, 42451.0, 42504.78747606242, 42588.296468141234, 3444.0528854564946, 3786.678132358753, 4172.415861779354, 4198.706090573661, 4332.241857674926, 32863.014109576754, 33690.49464258038, 34092.97721068372, 34097.488537671954, 34478.655213448044, 3294.0941241158635, 3406.6423025582453, 3418.572678415198, 3449.965500345015, 3453.9827300863335, 456759.0, 502087.0, 518214.0, 519989.0, 521199.0, 63745.36254637487, 64150.39624009344, 64176.679116604115, 64233.357666423675, 64312.678436607515, 68858.0, 68882.0, 68912.27719307058, 68954.0, 68966.31033689599, 16348.100854452881, 16782.573481253985, 16789.24448399825, 16798.244079016473, 16804.74792878108, 11596.130290228266, 11866.703332416759, 12584.244945303311, 12591.307478088793, 12937.288449135387, 18350.908245458686, 18371.90814045921, 18435.631287374177, 18446.631067378577, 18469.72295415543, 6160.291566469824, 7241.175941382759, 7448.366888814403, 7512.046867814298, 7577.393808495307, 941.9717408477757, 1041.9843702344472, 1095.6055819904827, 1144.5993902134353, 1192.362086283834, 25520.361990950005, 31316.84341578323, 31568.842155789072, 31815.840920795712, 32125.196870078435, 1052.2266134391216, 1074.355386767933, 1097.2319376436526, 1149.597640825721, 1174.2836869509592, 16088.195590220676, 16254.918725406298, 16263.756043659354, 16299.266533006043, 16735.079570623733, 675612.0, 679241.0, 679643.0, 681774.0, 683355.0, 60198.699006505565, 61754.0736888947, 61853.0, 61897.38102618916, 61949.380506194364, 1254.2537190371713, 1308.4962289518414, 1337.9697632822476, 1412.1385954567706, 1428.128841406741, 26748.59877101884, 27505.17484475469, 32358.19104522406, 32678.34643307168, 32754.50868237026, 52599.0, 53290.93418131693, 53831.0, 53996.0, 53999.0, 512379.0, 514729.0, 515185.0, 515588.0, 515730.0, 36841.89474315775, 43623.0, 44846.551534484235, 44900.77549612231, 45593.0, 47239.055218896116, 50246.49753502418, 50840.0, 51719.741401292755, 51857.0, 41240.0, 41257.381139283534, 41333.37999930064, 41965.58034419617, 43222.13555728931, 31246.531302030035, 40442.0, 40724.0, 40978.79510602428, 41240.968975775846, 39399.60600394074, 39862.60137398589, 40014.79992600018, 40133.79933100316, 40335.991600209054, 34039.31921361509, 34944.475832862016, 35966.0, 36116.819415902755, 36133.457998129525, 23370.94830732587, 25939.740602594487, 26258.768004719812, 26321.0, 26868.86565567238], "value": 21337.53895328113, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [386.38371796983813, 387.2506699536397, 391.7473229766802, 402.5350719918495, 406.7173314546391, 15802.920985395085, 19264.325748598803, 20644.69032964507, 21331.99999999998, 21363.931803409825, 20877.164913403485, 21237.044333005015, 21248.89375553124, 21932.342029739135, 21988.670169947465, 5282.577393808505, 5802.883942321161, 6113.602615829979, 6115.052166914135, 6118.69406529674, 5856.092305692625, 5982.581219314649, 6084.787032453865, 6106.480949119308, 6115.969420152893, 5996.910046349309, 6008.549358798085, 6012.248468941382, 6043.6675982820925, 6045.3047899491685, 5718.056520674084, 6009.09863520471, 6010.248718910115, 6014.548908831834, 6023.578349515536, 5198.350206224222, 5213.7393130343335, 5214.843554693365, 5220.8694782630355, 5224.346956630421, 26442.07452739164, 26728.198154055408, 26957.786899589566, 27041.45917081667, 27093.0, 2276.704028476304, 2430.902763889442, 2473.480569080495, 2474.5793215153467, 2622.4658574733803, 12991.675208119777, 15497.22513874313, 16153.596160095974, 16277.267522961494, 16749.66500669992, 1813.4287699374786, 1877.71834224866, 1926.5183704073982, 1992.8405727541763, 2011.8390528757811, 91725.33098676045, 122419.0, 125848.99999999908, 126263.0, 128219.0, 10072.74818129538, 10381.429021403896, 11277.436128193558, 11422.94288528552, 11598.130140239517, 14085.0, 14132.152070875574, 14160.291985400689, 14188.716225675429, 14192.787108193384, 8583.356248281403, 8715.477071375737, 8758.2993360531, 8873.378863479624, 8875.378723489293, 8795.692150774692, 8801.339899507562, 8802.427842190236, 8944.060873608307, 8954.552272386485, 6783.389494945479, 6909.585424874524, 6917.89623155653, 6923.134608173978, 6946.270641582662, 1849.306510058728, 1888.6411581799414, 1890.366727146394, 1891.5743957609427, 1891.895945722971, 894.3650008494042, 986.7138529826414, 1038.55342202852, 1063.5267306048734, 1079.3631757263252, 21086.578268434547, 23371.7662823373, 23439.296821095395, 23456.413589660395, 23478.882605586863, 983.9840887679383, 995.8705368302075, 1025.348903446302, 1025.8204814157493, 1029.8455231715152, 11498.25261358009, 11996.64010079699, 12191.329476878856, 12196.963258122982, 12269.325187114795, 527428.0, 620135.8993205095, 631380.0, 640042.0, 648890.0, 133147.33426332806, 145771.0, 149094.25452872884, 157598.0, 167433.0, 966.7293157915794, 982.5922242269521, 1037.5434808684154, 1050.9579616815242, 1057.9471026448646, 14701.55895323142, 17697.82302176962, 18213.726794097834, 20064.0, 20128.89935550313, 55202.0, 57228.42771572231, 58241.4175858236, 58595.70702146462, 59132.11301830562, 81279.59360203161, 83705.74441383505, 87314.563427185, 87472.0, 88582.11417885739, 8259.710910118118, 8359.623816928131, 8444.36667249971, 8445.451045682012, 8533.74398768038, 9262.304826820817, 13601.726380631377, 13649.0, 13847.0, 13871.791923121364, 11402.771944560896, 11594.246373985665, 11667.124965627441, 11724.355160465915, 11772.52909883595, 10373.325733827278, 11370.658880233606, 11416.0, 11450.484728187419, 11690.240134391237, 11384.886151138382, 11651.53393864236, 11658.300501969909, 11840.111991600837, 11872.99079578211, 8251.381146413923, 8346.916530834615, 8504.362172837185, 8550.315974722133, 8554.62822458276, 9702.563384647567, 11354.318740875575, 11570.305781653127, 11679.0, 11693.532258709896], "value": 10117.650483582773, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1397.6296281485402, 1459.6642772162402, 1501.0843385534822, 1771.4774141628218, 1965.8820470771755, 7837.882431763521, 8524.829503409936, 8796.0, 8809.163129502704, 8902.910970890289, 8533.274671652902, 8782.297416206704, 8785.341099417537, 8788.956055219714, 8789.296856251485, 2057.9588408231825, 2132.9786702132933, 2252.358077947785, 2331.720193576769, 2367.230650038736, 2047.7542694876636, 2370.3600027992434, 2400.5558971590226, 2410.4576470294132, 2418.6613874057557, 2328.790408863202, 2363.196513185517, 2371.0, 2371.5494056129364, 2371.8221133414977, 2053.414776788623, 2239.1267405711624, 2309.8036666883336, 2314.2247347138646, 2319.652052192168, 2349.9060037598474, 2372.79831214347, 2375.394274460019, 2375.6674065630827, 2384.5707772600927, 8739.994900586451, 8801.779955501099, 8815.51514666693, 8821.0, 8821.867671984925, 5143.151380022292, 5308.867278318035, 5310.7610157543, 5316.867078323035, 5318.707471089088, 3412.095794614429, 3493.161641206092, 3652.7443078984484, 3657.9085522861874, 3679.8528058877614, 1044.9164066874707, 1072.9248952573323, 1073.2540884085563, 1080.4219742437758, 1081.935083894961, 11594.246373985665, 11605.071594272522, 11617.361045142514, 11635.301881887113, 11638.592649257236, 4569.223232050559, 4614.861554153381, 4670.322803193518, 4752.809887604526, 4758.904821903542, 9816.852747208797, 9824.90175098254, 9835.45904975219, 9848.409095454295, 9854.162396196403, 5174.09453345663, 5262.210668399771, 5266.1574148136115, 5267.104592219332, 5273.815416460406, 4379.627731642782, 4523.977380113079, 4721.362616046789, 4866.0, 4874.073925954058, 3891.9221615568094, 3931.4889064422014, 3991.660708839723, 4039.535453422894, 4042.555318914917, 1146.495541961551, 1289.6969212235117, 1297.0272295778166, 1299.1036185032485, 1305.432137020399, 914.5518695839124, 915.1443400420628, 918.6141820435433, 933.8093930238999, 975.8731364922514, 5815.534757219412, 5852.8536786579825, 5978.274317670074, 6023.337432882379, 6133.0800379942475, 448.33646203618173, 449.80883124671885, 450.91658043261447, 454.2595569222147, 458.7476887711745, 3426.229098452878, 4018.1160144768105, 4111.71218014742, 4122.216778812016, 4189.560096189917, 9856.950715246521, 10740.946295268473, 10748.247622666338, 10751.462426878625, 10773.569057237779, 10482.68551943443, 10580.735481613021, 10660.360378377167, 10716.624918127829, 10718.196135289885, 6787.898181527281, 8111.229433203836, 8147.959260203779, 8208.343332533383, 8216.34269258458, 5888.3228428731245, 5925.407459254213, 6026.939730602813, 6028.4272994065495, 6187.133801267916, 7856.135825059125, 9840.852387214341, 9877.0, 9958.55186516594, 10061.496925153859, 9876.40741555509, 10150.086492215441, 10157.0, 10259.948700256451, 10264.384136951807, 6857.862842743018, 7394.149672787698, 7563.810904727426, 7655.387568994356, 7730.0, 8500.107488713596, 8571.785705357415, 8581.828363432822, 8589.14108589136, 8597.52713600733, 7387.3148824337695, 7605.277498637621, 7666.041744781903, 7666.19504951972, 7737.767866964, 6437.388448097423, 7160.818464953224, 7295.124585049828, 7431.554106753612, 7579.962100189464, 6332.271788744353, 7053.929460705327, 7084.972678961625, 7094.326039026284, 7112.857742845217, 4865.829695960624, 4960.0, 5064.0, 5112.821051263188, 5132.43543210254, 5017.598592112549, 5079.644424890223, 5125.692458452356, 5139.486051394831, 5223.347081614798], "value": 4488.534568415607, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [293.86775950822135, 298.40170458231245, 305.94187104450145, 310.59157208271137, 313.27633167383345, 4545.454545454541, 5323.9467605323935, 5364.168553874146, 5383.5693144548395, 5453.182022696597, 4679.3682852814945, 4994.901121753218, 5051.232068775927, 5207.947920520804, 5302.443243459429, 3384.373890830192, 3713.6286371362917, 4075.8981025474304, 4076.531198912126, 4084.4070812383193, 4036.7779772112667, 4037.5154981402125, 4047.817848196838, 4053.108316170438, 4059.5128584569893, 3374.2913988062537, 3601.3697602919515, 3735.589085200626, 3908.1011367385518, 4047.8785636430953, 4009.298372784751, 4014.8193331300163, 4021.7586944783116, 4030.6420743864874, 4040.9897525618594, 3483.878064267764, 3493.7205023598053, 3498.9475157872653, 3501.4922836188866, 3502.807345595991, 4859.781309841065, 5197.7920883164625, 5221.295125158131, 5224.973875130638, 5250.317458730379, 4018.6584140348104, 4415.977920110411, 4547.386102876136, 4564.703294285894, 4592.1045396147765, 4957.876053098666, 5558.6942718150485, 5571.8607034824045, 5581.706792992154, 5605.831825045255, 3652.817359132059, 3787.8484860605545, 3795.69634429245, 3831.4061320495366, 3840.7311488195837, 4887.364642596511, 5819.592628516006, 5824.534037277008, 5827.446392592739, 5865.677387743673, 3407.795532268072, 3605.549306336708, 3793.393057110849, 3811.1805961718364, 3859.749116307431, 3585.354636165463, 3610.754289770078, 3886.7667939923695, 3887.6501114899793, 3913.7651740895635, 4276.401303817468, 4400.207962566768, 4549.567791059844, 4550.544945505423, 4587.4036375271, 4710.105080034851, 4719.9292010619865, 5603.683134463397, 5652.2652055233375, 5673.914891276634, 1069.6309773128257, 1189.898858597029, 1193.7015746063485, 1231.3166192762924, 1318.6241921052454, 788.6002345836072, 855.2046596665036, 886.025372090708, 889.6041261638638, 890.4568213389826, 4615.215413379734, 5148.304978827885, 5157.045946499909, 5201.323827902348, 5278.81524146653, 5406.945930540723, 5642.1380944288285, 5678.375378708339, 5701.486866182064, 5709.9714501427225, 4543.954560454353, 4646.256598944153, 4928.901421971541, 5054.16606259963, 5098.0, 2564.3845477085365, 2665.9333516662236, 2778.652668416448, 2900.606999225647, 2967.4065186962694, 5359.035373632705, 5367.665065696435, 5433.402325744165, 5790.449907258803, 5800.0, 4829.034193161382, 5614.887702245932, 5747.712614369265, 5775.335836378787, 5806.606414460499, 5346.91979620306, 5392.0294347017125, 5402.297701298806, 5768.336641286306, 5813.389594092644, 5198.324217851655, 5572.749226284908, 5592.804251851166, 5625.774969001358, 5646.0, 4861.6596838220985, 5287.524122828964, 5503.229547863222, 5524.309461317335, 5563.860903477445, 5335.253064571041, 5720.227769251098, 5748.637047327193, 5754.741036653277, 5758.798442054508, 3772.679322257639, 3772.717046221599, 3782.2435512898082, 3795.1972812913646, 3806.9619303806608, 4867.610591152628, 5148.073346797612, 5571.582131340085, 5598.86002849932, 5623.943760562342, 4513.864584062483, 4679.770958038942, 4911.950880491292, 5043.344365232496, 5437.564994800486, 5131.692098474104, 5179.943833138466, 5583.804566840141, 5587.525060369914, 5616.466435688603, 4404.559544045698, 4729.314249433882, 5365.7048862311385, 5436.293281873489, 5521.1442226454965, 5201.973990130176, 5360.142377219626, 5576.888462230814, 5585.944140558705, 5597.944020559742, 5179.715115668524, 5450.618456707993, 5466.972665136808, 5493.725313734377, 5500.844822587302], "value": 4014.372941261129, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [731.3637135691948, 757.1368639750683, 774.976750697479, 777.1684297801355, 815.881697153913, 15052.021618594783, 15104.697906041873, 15692.764608530866, 15722.056676599397, 16145.43490977816, 14570.417183312682, 14619.195944223064, 14759.852401475982, 14764.335604897778, 14768.261586920664, 713.9821504462404, 744.8212429017051, 751.9135299440552, 904.2539904578699, 922.2667978956746, 874.4359887872318, 914.8993610702818, 922.3128769067055, 924.4268553496807, 925.4724806860092, 972.8346181149186, 975.8292298847708, 980.9215262778961, 982.3909176310698, 982.7592239901235, 742.6769355330395, 921.2767977137914, 972.8248915195262, 973.6300205921775, 974.0794948773412, 718.9065421495172, 719.4496210399049, 720.91709453413, 721.1598487761752, 723.0239177110858, 15640.530784076496, 15694.293756780973, 15747.291371888292, 15756.606084847856, 15761.684766304725, 14287.714245715028, 14474.059186152866, 14505.41978320866, 14670.046446980912, 14731.263436828223, 12831.550895718605, 13133.802992955114, 13222.933885330513, 13386.39761210748, 13442.19346839183, 4526.117407105616, 4769.642276829217, 4792.233242681189, 4823.927641085386, 4826.300186472977, 120139.7986020146, 121942.0, 122667.3866630661, 122804.0, 122808.0, 11268.267562608404, 11487.770244595062, 11684.766304674029, 11880.702982425508, 11921.642350729491, 23653.0, 23654.290371288887, 23671.0, 23702.644460333107, 23792.286231413087, 7845.1762564931005, 8071.686579605436, 8073.838523229502, 8200.466969646954, 8207.220314070153, 4367.868963931087, 4483.19302525542, 4518.615917646971, 4573.542645735466, 4619.676622636383, 3214.903552893417, 3315.0054983505333, 3361.638536392716, 3397.694207521335, 3638.3632864248652, 627.5262177056293, 634.7334119669749, 645.296626676915, 653.121551513214, 655.8458762190882, 630.7224821078711, 646.1051443750439, 653.7156336993423, 657.3919124809532, 658.1378394303478, 8577.270931970728, 8754.606042728092, 9228.630854765868, 9241.722748317561, 9438.858417123749, 601.7141857617684, 611.8684482836212, 630.5176539946945, 644.711274973841, 647.6470323673609, 3743.382341913554, 4207.558206388347, 4484.8430304939175, 4575.702579332333, 4602.539746025371, 107571.46214268879, 128483.0, 130604.0, 146050.0, 174049.0, 130570.0, 133700.3314983419, 141739.0, 149537.0, 153318.0, 71967.2803271971, 72195.0, 96581.0, 104119.4794026025, 107919.46040269906, 9231.907680923105, 9741.853872191781, 9830.803383932138, 9873.210143188671, 9964.750881228027, 54965.17552236639, 56770.86458270894, 57307.71346143409, 62591.6870415645, 64873.67563162154, 85345.0, 92490.0, 96597.03402965881, 98315.50842246029, 99759.0, 12819.294938778457, 13248.536301229797, 13287.933560332136, 13319.800202997156, 13571.728565428439, 15007.399704011717, 15493.690126197638, 15640.670543003973, 15724.99012751741, 15738.842611574195, 9403.576839042396, 9910.959349268223, 13842.515511957034, 14610.415583376545, 14795.408183672533, 11077.386974548272, 13967.511137110152, 14132.293385330488, 14411.42354305858, 14441.711165776836, 13896.791548126585, 14016.229107399191, 14033.298335083, 14072.226027568582, 14243.928780356031, 11210.215284929976, 11413.942930285295, 11417.543298268309, 11603.36181510025, 11817.881821181678, 11383.54465821358, 11458.55131386208, 11460.598879039193, 11533.88466115328, 11640.359780211831], "value": 7999.1859814127, "unit": "score"}], "name": "dromaeo_css", "value": 7508.933593705777, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 111.0, 113.0, 111.0, 112.0, 112.0, 112.0, 112.0, 113.0, 113.0], "value": 110.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 118.0, 118.0, 171.0, 118.0, 119.0, 137.0, 115.0, 152.0, 119.0], "value": 132.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 182.0, 186.0, 183.0, 183.0, 185.0, 178.0, 181.0, 186.0, 184.0], "value": 183.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 70.0, 73.0, 99.0, 57.0, 73.0, 86.0, 84.0, 86.0, 57.0], "value": 76.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 89.0, 93.0, 72.0, 76.0, 70.0, 119.0, 105.0, 98.0, 105.0], "value": 91.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 84.0, 103.0, 85.0, 87.0, 84.0, 88.0, 85.0, 88.0, 85.0], "value": 87.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 94.0, 95.0, 94.0, 93.0, 95.0, 95.0, 93.0, 94.0], "value": 94.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 85.0, 85.0, 87.0, 84.0, 84.0, 84.0, 85.0, 84.0], "value": 84.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 53.0, 54.0, 54.0, 51.0, 54.0, 53.0, 52.0, 54.0, 54.0], "value": 53.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 72.0, 73.0, 74.0, 70.0, 70.0, 69.0, 69.0, 69.0, 71.0], "value": 70.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 85.0, 85.0, 85.0, 85.0, 85.0, 86.0, 100.0, 87.0], "value": 86.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 140.0, 136.0, 138.0, 136.0, 137.0, 134.0, 138.0, 138.0, 142.0], "value": 137.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 166.0, 165.0, 167.0, 164.0, 218.0, 202.0, 215.0, 226.0, 167.0], "value": 185.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 67.0, 65.0, 64.0, 66.0, 67.0, 65.0, 66.0, 65.0], "value": 65.7, "unit": "score"}], "name": "kraken", "value": 1459.4, "alertThreshold": 2.0}]} 06:31:26 INFO - Return code: 0 06:31:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:31:26 INFO - # TBPL SUCCESS # 06:31:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:31:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:31:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:31:26 INFO - 2016-06-29 06:31:26.634 screenresolution[1669:10123] starting screenresolution argv=screenresolution get 06:31:26 INFO - 2016-06-29 06:31:26.637 screenresolution[1669:10123] Display 0: 1600x1200x32@60 06:31:26 INFO - 2016-06-29 06:31:26.643 screenresolution[1670:10126] starting screenresolution argv=screenresolution list 06:31:26 INFO - Available Modes on Display 0 06:31:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:31:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:31:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:31:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:31:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:31:26 INFO - Intel Iris: 06:31:26 INFO - Chipset Model: Intel Iris 06:31:26 INFO - Type: GPU 06:31:26 INFO - Bus: Built-In 06:31:26 INFO - VRAM (Dynamic, Max): 1536 MB 06:31:26 INFO - Vendor: Intel (0x8086) 06:31:26 INFO - Device ID: 0x0a2e 06:31:26 INFO - Revision ID: 0x0009 06:31:26 INFO - Displays: 06:31:26 INFO - Display: 06:31:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:31:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:31:26 INFO - Display Serial Number: 1600x1200 60 06:31:26 INFO - Main Display: Yes 06:31:26 INFO - Mirror: Off 06:31:26 INFO - Online: Yes 06:31:26 INFO - Rotation: Supported 06:31:26 INFO - Return code: 0 06:31:26 INFO - Running post-action listener: _resource_record_post_action 06:31:26 INFO - ##### 06:31:26 INFO - ##### Finished run-tests step (success) 06:31:26 INFO - ##### 06:31:26 INFO - Running post-run listener: _resource_record_post_run 06:31:26 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 31649280; Write bytes: 705623040; Read time: 193; Write time: 2005 06:31:26 INFO - TinderboxPrint: CPU usage
53.8% 06:31:26 INFO - TinderboxPrint: I/O read bytes / time
31,649,280 / 193 06:31:26 INFO - TinderboxPrint: I/O write bytes / time
705,623,040 / 2,005 06:31:26 INFO - TinderboxPrint: CPU idle
2,238.8 (46.2%) 06:31:26 INFO - TinderboxPrint: CPU system
967.7 (20.0%) 06:31:26 INFO - TinderboxPrint: CPU user
1,639.3 (33.8%) 06:31:26 INFO - TinderboxPrint: Swap in / out
434,257,920 / 0 06:31:26 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 392122880; Write bytes: 385730048; Read time: 29096; Write time: 860 06:31:26 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 30727680; Write bytes: 296701440; Read time: 183; Write time: 1088 06:31:27 INFO - Running post-run listener: _upload_blobber_files 06:31:27 INFO - Blob upload gear active. 06:31:27 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:31:27 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 06:31:27 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'] 06:31:27 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 06:31:27 INFO - (blobuploader) - INFO - Open directory for files ... 06:31:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:31:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:31:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:31:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:31:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:31:28 INFO - (blobuploader) - INFO - Done attempting. 06:31:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:31:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:31:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:31:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:31:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:31:29 INFO - (blobuploader) - INFO - Done attempting. 06:31:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:31:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:31:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:31:32 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:31:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:31:32 INFO - (blobuploader) - INFO - Done attempting. 06:31:32 INFO - (blobuploader) - INFO - Iteration through files over. 06:31:32 INFO - Return code: 0 06:31:32 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:31:32 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:31:32 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/08c55c7585230537641a11c40041e52704a9ad8216d3b2cdfad902c30b9fc1a9c6a0b82bd4a407224f67465cafa2051ddf774ba22e097d8ef92ef3e77cc697f6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cc762c1a4f9c5f04fb00a7f04c105db83b48e185894fc6c33a4d939311833c99616afc0831cfc3f72fb3ed028082cffee9a28586066c0783d583acb6b9739cd6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/193ee922ee80e6fcc70877dcd816a325169c368448a79d61aa50852783b087f7bf848b3b94969456e5399328199396046d3176f5a2a4753a2518d521c7fa74c7"} 06:31:32 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:31:32 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:31:32 INFO - Contents: 06:31:32 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/08c55c7585230537641a11c40041e52704a9ad8216d3b2cdfad902c30b9fc1a9c6a0b82bd4a407224f67465cafa2051ddf774ba22e097d8ef92ef3e77cc697f6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cc762c1a4f9c5f04fb00a7f04c105db83b48e185894fc6c33a4d939311833c99616afc0831cfc3f72fb3ed028082cffee9a28586066c0783d583acb6b9739cd6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/193ee922ee80e6fcc70877dcd816a325169c368448a79d61aa50852783b087f7bf848b3b94969456e5399328199396046d3176f5a2a4753a2518d521c7fa74c7"} 06:31:32 INFO - Running post-run listener: copy_logs_to_upload_dir 06:31:32 INFO - Copying logs to upload dir... 06:31:32 INFO - mkdir: /builds/slave/test/build/upload/logs 06:31:32 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1287.451781 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2016-06-29 06:31:32.339006) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-29 06:31:32.342630) ========= 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.4DmA64TmzH/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.5hqQ0xgbtg/Listeners TMPDIR=/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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/08c55c7585230537641a11c40041e52704a9ad8216d3b2cdfad902c30b9fc1a9c6a0b82bd4a407224f67465cafa2051ddf774ba22e097d8ef92ef3e77cc697f6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cc762c1a4f9c5f04fb00a7f04c105db83b48e185894fc6c33a4d939311833c99616afc0831cfc3f72fb3ed028082cffee9a28586066c0783d583acb6b9739cd6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/193ee922ee80e6fcc70877dcd816a325169c368448a79d61aa50852783b087f7bf848b3b94969456e5399328199396046d3176f5a2a4753a2518d521c7fa74c7"} build_url:https://queue.taskcluster.net/v1/task/T67JNPS_Qm6m7IbMAigkLA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011237 build_url: 'https://queue.taskcluster.net/v1/task/T67JNPS_Qm6m7IbMAigkLA/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/08c55c7585230537641a11c40041e52704a9ad8216d3b2cdfad902c30b9fc1a9c6a0b82bd4a407224f67465cafa2051ddf774ba22e097d8ef92ef3e77cc697f6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cc762c1a4f9c5f04fb00a7f04c105db83b48e185894fc6c33a4d939311833c99616afc0831cfc3f72fb3ed028082cffee9a28586066c0783d583acb6b9739cd6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/193ee922ee80e6fcc70877dcd816a325169c368448a79d61aa50852783b087f7bf848b3b94969456e5399328199396046d3176f5a2a4753a2518d521c7fa74c7"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-29 06:31:32.376796) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 06:31:32.377117) ========= 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.4DmA64TmzH/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.5hqQ0xgbtg/Listeners TMPDIR=/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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.005012 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 06:31:32.426132) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-29 06:31:32.426565) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-29 06:31:32.426928) ========= ========= Total master_lag: 0.32 =========