builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0119 starttime: 1469450358.39 results: success (0) buildid: 20160725032124 builduid: 92a4c734636546e3b1ca4699167f0107 revision: 1bb05a19ff9d70ed1d858d526fdbe27a8408f19f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-25 05:39:18.393057) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-25 05:39:18.393657) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-25 05:39:18.393970) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-25 05:39:18.440568) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-25 05:39:18.440862) ========= 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.K0ACA1f4dH/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.R5uuEFbPzI/Listeners TMPDIR=/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-07-25 05:39:18-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 196M=0s 2016-07-25 05:39:19 (196 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.585010 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-25 05:39:19.049256) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-25 05:39:19.049576) ========= 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.K0ACA1f4dH/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.R5uuEFbPzI/Listeners TMPDIR=/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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.034155 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-25 05:39:19.108234) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-25 05:39:19.108908) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 1bb05a19ff9d70ed1d858d526fdbe27a8408f19f --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 1bb05a19ff9d70ed1d858d526fdbe27a8408f19f --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.K0ACA1f4dH/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.R5uuEFbPzI/Listeners TMPDIR=/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-07-25 05:39:19,183 truncating revision to first 12 chars 2016-07-25 05:39:19,183 Setting DEBUG logging. 2016-07-25 05:39:19,183 attempt 1/10 2016-07-25 05:39:19,183 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/1bb05a19ff9d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-25 05:39:20,274 unpacking tar archive at: fx-team-1bb05a19ff9d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.349178 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-25 05:39:20.482075) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-25 05:39:20.482403) ========= script_repo_revision: 1bb05a19ff9d70ed1d858d526fdbe27a8408f19f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-25 05:39:20.486929) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-25 05:39:20.487329) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-25 05:39:20.508501) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 40 secs) (at 2016-07-25 05:39:20.508918) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.K0ACA1f4dH/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.R5uuEFbPzI/Listeners TMPDIR=/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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 05:39:20 INFO - MultiFileLogger online at 20160725 05:39:20 in /builds/slave/test 05:39:20 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 05:39:20 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:39:20 INFO - {'append_to_log': False, 05:39:20 INFO - 'base_work_dir': '/builds/slave/test', 05:39:20 INFO - 'blob_upload_branch': 'Fx-Team', 05:39:20 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:39:20 INFO - 'branch': 'Fx-Team', 05:39:20 INFO - 'buildbot_json_path': 'buildprops.json', 05:39:20 INFO - 'config_files': ('talos/mac_config.py',), 05:39:20 INFO - 'default_actions': ('clobber', 05:39:20 INFO - 'read-buildbot-config', 05:39:20 INFO - 'download-and-extract', 05:39:20 INFO - 'populate-webroot', 05:39:20 INFO - 'create-virtualenv', 05:39:20 INFO - 'install', 05:39:20 INFO - 'run-tests'), 05:39:20 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:39:20 INFO - 'download_minidump_stackwalk': True, 05:39:20 INFO - 'download_symbols': 'ondemand', 05:39:20 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:39:20 INFO - 'tooltool.py': '/tools/tooltool.py', 05:39:20 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:39:20 INFO - '/tools/misc-python/virtualenv.py')}, 05:39:20 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:39:20 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:39:20 INFO - 'installer_path': 'installer.exe', 05:39:20 INFO - 'log_level': 'info', 05:39:20 INFO - 'log_name': 'talos', 05:39:20 INFO - 'log_to_console': True, 05:39:20 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:39:20 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:39:20 INFO - 'opt_config_files': (), 05:39:20 INFO - 'pip_index': False, 05:39:20 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:39:20 INFO - 'cmd': ('bash', 05:39:20 INFO - '-c', 05:39:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:39:20 INFO - 'enabled': True, 05:39:20 INFO - 'halt_on_failure': False, 05:39:20 INFO - 'name': 'check_screen_resolution'},), 05:39:20 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:39:20 INFO - 'cmd': ('bash', 05:39:20 INFO - '-c', 05:39:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:39:20 INFO - 'enabled': True, 05:39:20 INFO - 'halt_on_failure': False, 05:39:20 INFO - 'name': 'check_screen_resolution'},), 05:39:20 INFO - 'run_cmd_checks_enabled': True, 05:39:20 INFO - 'sps_profile': False, 05:39:20 INFO - 'sps_profile_interval': 0, 05:39:20 INFO - 'suite': 'dromaeojs-e10s', 05:39:20 INFO - 'system_bits': '32', 05:39:20 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:39:20 INFO - 'title': 't-yosemite-r7-0119', 05:39:20 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:39:20 INFO - 'use_talos_json': True, 05:39:20 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:39:20 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:39:20 INFO - 'work_dir': 'build'} 05:39:20 INFO - [mozharness: 2016-07-25 12:39:20.706709Z] Running clobber step. 05:39:20 INFO - Running pre-action listener: _resource_record_pre_action 05:39:20 INFO - Running main action method: clobber 05:39:20 INFO - rmtree: /builds/slave/test/build 05:39:20 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:39:22 INFO - Running post-action listener: _resource_record_post_action 05:39:22 INFO - [mozharness: 2016-07-25 12:39:22.879243Z] Finished clobber step (success) 05:39:22 INFO - [mozharness: 2016-07-25 12:39:22.879407Z] Running read-buildbot-config step. 05:39:22 INFO - Running pre-action listener: _resource_record_pre_action 05:39:22 INFO - Running main action method: read_buildbot_config 05:39:22 INFO - Using buildbot properties: 05:39:22 INFO - { 05:39:22 INFO - "project": "", 05:39:22 INFO - "product": "firefox", 05:39:22 INFO - "script_repo_revision": "production", 05:39:22 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 05:39:22 INFO - "repository": "", 05:39:22 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 05:39:22 INFO - "buildid": "20160725032124", 05:39:22 INFO - "pgo_build": "False", 05:39:22 INFO - "basedir": "/builds/slave/test", 05:39:22 INFO - "buildnumber": 57, 05:39:22 INFO - "slavename": "t-yosemite-r7-0119", 05:39:22 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 05:39:22 INFO - "platform": "yosemite_r7", 05:39:22 INFO - "scheduler": "tests-fx-team-macosx64-talos", 05:39:22 INFO - "branch": "fx-team", 05:39:22 INFO - "repo_path": "integration/fx-team", 05:39:22 INFO - "revision": "1bb05a19ff9d70ed1d858d526fdbe27a8408f19f", 05:39:22 INFO - "stage_platform": "macosx64", 05:39:22 INFO - "builduid": "92a4c734636546e3b1ca4699167f0107", 05:39:22 INFO - "slavebuilddir": "test" 05:39:22 INFO - } 05:39:22 INFO - Found installer url https://queue.taskcluster.net/v1/task/ZwzSrdUhRFy0zUlDHpekQA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 05:39:22 INFO - Running post-action listener: _resource_record_post_action 05:39:22 INFO - [mozharness: 2016-07-25 12:39:22.882824Z] Finished read-buildbot-config step (success) 05:39:22 INFO - [mozharness: 2016-07-25 12:39:22.882931Z] Running download-and-extract step. 05:39:22 INFO - Running pre-action listener: _resource_record_pre_action 05:39:22 INFO - Running main action method: download_and_extract 05:39:22 INFO - mkdir: /builds/slave/test/build/tests 05:39:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:39:22 INFO - https://queue.taskcluster.net/v1/task/ZwzSrdUhRFy0zUlDHpekQA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 05:39:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZwzSrdUhRFy0zUlDHpekQA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 05:39:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZwzSrdUhRFy0zUlDHpekQA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 05:39:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZwzSrdUhRFy0zUlDHpekQA/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 05:39:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZwzSrdUhRFy0zUlDHpekQA/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 05:39:49 INFO - Downloaded 1199 bytes. 05:39:49 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 05:39:49 INFO - Using the following test package requirements: 05:39:49 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 05:39:49 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:39:49 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 05:39:49 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:39:49 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 05:39:49 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:39:49 INFO - u'jsshell-mac.zip'], 05:39:49 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:39:49 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 05:39:49 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 05:39:49 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:39:49 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 05:39:49 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:39:49 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 05:39:49 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:39:49 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 05:39:49 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:39:49 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 05:39:49 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:39: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')]} 05:39:49 INFO - https://queue.taskcluster.net/v1/task/ZwzSrdUhRFy0zUlDHpekQA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:39:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZwzSrdUhRFy0zUlDHpekQA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 05:39:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZwzSrdUhRFy0zUlDHpekQA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 05:39:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZwzSrdUhRFy0zUlDHpekQA/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 05:39:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZwzSrdUhRFy0zUlDHpekQA/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 05:39:54 INFO - Downloaded 18808669 bytes. 05:39:54 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'] 05:39:54 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 05:39:57 INFO - Return code: 0 05:39:57 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 05:39:57 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')]} 05:39:57 INFO - https://queue.taskcluster.net/v1/task/ZwzSrdUhRFy0zUlDHpekQA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:39:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZwzSrdUhRFy0zUlDHpekQA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 05:39:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZwzSrdUhRFy0zUlDHpekQA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 05:39:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZwzSrdUhRFy0zUlDHpekQA/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 05:39:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZwzSrdUhRFy0zUlDHpekQA/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 05:40:00 INFO - Downloaded 18808669 bytes. 05:40:00 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:40:00 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:40:03 INFO - Return code: 0 05:40: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')]} 05:40:03 INFO - https://queue.taskcluster.net/v1/task/ZwzSrdUhRFy0zUlDHpekQA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:40:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZwzSrdUhRFy0zUlDHpekQA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 05:40:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZwzSrdUhRFy0zUlDHpekQA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 05:40:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZwzSrdUhRFy0zUlDHpekQA/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 05:40:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZwzSrdUhRFy0zUlDHpekQA/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 05:40:04 INFO - Downloaded 14403289 bytes. 05:40:04 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'] 05:40:04 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 05:40:05 INFO - Return code: 0 05:40:05 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')]} 05:40:05 INFO - https://queue.taskcluster.net/v1/task/ZwzSrdUhRFy0zUlDHpekQA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 05:40:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZwzSrdUhRFy0zUlDHpekQA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 05:40:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZwzSrdUhRFy0zUlDHpekQA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 05:40:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZwzSrdUhRFy0zUlDHpekQA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 05:40:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZwzSrdUhRFy0zUlDHpekQA/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 05:40:13 INFO - Downloaded 112903911 bytes. 05:40:13 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ZwzSrdUhRFy0zUlDHpekQA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 05:40:13 INFO - mkdir: /builds/slave/test/properties 05:40:13 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:40:13 INFO - Writing to file /builds/slave/test/properties/build_url 05:40:13 INFO - Contents: 05:40:13 INFO - build_url:https://queue.taskcluster.net/v1/task/ZwzSrdUhRFy0zUlDHpekQA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 05:40:14 INFO - Running post-action listener: _resource_record_post_action 05:40:14 INFO - Running post-action listener: set_extra_try_arguments 05:40:14 INFO - [mozharness: 2016-07-25 12:40:14.064754Z] Finished download-and-extract step (success) 05:40:14 INFO - [mozharness: 2016-07-25 12:40:14.064921Z] Running populate-webroot step. 05:40:14 INFO - Running pre-action listener: _resource_record_pre_action 05:40:14 INFO - Running main action method: populate_webroot 05:40:14 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:40:14 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:40:14 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:40:14 INFO - '--tppagecycles', 05:40:14 INFO - '7'], 05:40:14 INFO - 'tests': ['tsvgm']}}, 05:40:14 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:40:14 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:40:14 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:40:14 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:40:14 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:40:14 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:40:14 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:40:14 INFO - 'talos_options': ['--disable-e10s'], 05:40:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:40:14 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:40:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:40:14 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:40:14 INFO - 'talos_options': ['--disable-e10s'], 05:40:14 INFO - 'tests': ['damp', 'tps']}, 05:40:14 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:40:14 INFO - 'tests': ['damp', 'tps']}, 05:40:14 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:40:14 INFO - 'tests': ['dromaeo_dom']}, 05:40:14 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:40:14 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:40:14 INFO - 'tests': ['basic_compositor_video']}, 05:40:14 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:40:14 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:40:14 INFO - 'tests': ['a11yr', 05:40:14 INFO - 'ts_paint', 05:40:14 INFO - 'tpaint', 05:40:14 INFO - 'sessionrestore', 05:40:14 INFO - 'sessionrestore_no_auto_restore', 05:40:14 INFO - 'tabpaint']}, 05:40:14 INFO - 'other-e10s': {'tests': ['a11yr', 05:40:14 INFO - 'ts_paint', 05:40:14 INFO - 'tpaint', 05:40:14 INFO - 'sessionrestore', 05:40:14 INFO - 'sessionrestore_no_auto_restore', 05:40:14 INFO - 'tabpaint']}, 05:40:14 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:40:14 INFO - 'ts_paint', 05:40:14 INFO - 'tpaint', 05:40:14 INFO - 'sessionrestore', 05:40:14 INFO - 'sessionrestore_no_auto_restore', 05:40:14 INFO - 'tabpaint']}, 05:40:14 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:40:14 INFO - 'ts_paint', 05:40:14 INFO - 'tpaint', 05:40:14 INFO - 'sessionrestore', 05:40:14 INFO - 'sessionrestore_no_auto_restore', 05:40:14 INFO - 'tabpaint']}, 05:40:14 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:40:14 INFO - 'tests': ['a11yr', 05:40:14 INFO - 'ts_paint', 05:40:14 INFO - 'tpaint', 05:40:14 INFO - 'sessionrestore', 05:40:14 INFO - 'sessionrestore_no_auto_restore', 05:40:14 INFO - 'tabpaint']}, 05:40:14 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:40:14 INFO - 'tests': ['a11yr', 05:40:14 INFO - 'ts_paint', 05:40:14 INFO - 'tpaint', 05:40:14 INFO - 'sessionrestore', 05:40:14 INFO - 'sessionrestore_no_auto_restore', 05:40:14 INFO - 'tabpaint']}, 05:40:14 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:40:14 INFO - 'tests': ['tsvgx', 05:40:14 INFO - 'tsvgr_opacity', 05:40:14 INFO - 'tart', 05:40:14 INFO - 'tscrollx', 05:40:14 INFO - 'cart']}, 05:40:14 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:40:14 INFO - 'tsvgr_opacity', 05:40:14 INFO - 'tart', 05:40:14 INFO - 'tscrollx', 05:40:14 INFO - 'cart']}, 05:40:14 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:40:14 INFO - 'talos_options': ['--disable-e10s'], 05:40:14 INFO - 'tests': ['tp5o']}, 05:40:14 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:40:14 INFO - 'tests': ['tp5o']}, 05:40:14 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:40:14 INFO - 'talos_options': ['--disable-e10s', 05:40:14 INFO - '--xperf_path', 05:40:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:40:14 INFO - 'tests': ['tp5n']}, 05:40:14 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:40:14 INFO - 'talos_options': ['--xperf_path', 05:40:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:40:14 INFO - 'tests': ['tp5n']}}, 05:40:14 INFO - 'talos.zip': {'path': '', 05:40:14 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:40:14 INFO - Running post-action listener: _resource_record_post_action 05:40:14 INFO - [mozharness: 2016-07-25 12:40:14.084196Z] Finished populate-webroot step (success) 05:40:14 INFO - [mozharness: 2016-07-25 12:40:14.084327Z] Running create-virtualenv step. 05:40:14 INFO - Running pre-action listener: _resource_record_pre_action 05:40:14 INFO - Running main action method: create_virtualenv 05:40:14 INFO - Creating virtualenv /builds/slave/test/build/venv 05:40:14 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 05:40:14 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:40:14 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:40:14 INFO - Using real prefix '/tools/python27' 05:40:14 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:40:14 INFO - Installing distribute.............................................................................................................................................................................................done. 05:40:17 INFO - Installing pip.................done. 05:40:17 INFO - Return code: 0 05:40:17 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:40:17 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')]} 05:40:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:40:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:17 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')]} 05:40:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:40:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:17 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 0x10dbf7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc8c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd99cb6380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db5bd30>, '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 0x10d8c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10db53b20>, '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.K0ACA1f4dH/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.R5uuEFbPzI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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 05:40:17 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 05:40:17 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 05:40:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K0ACA1f4dH/Render', 05:40:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:40:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:40:17 INFO - 'HOME': '/Users/cltbld', 05:40:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:40:17 INFO - 'LOGNAME': 'cltbld', 05:40:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:40:17 INFO - 'MOZ_NO_REMOTE': '1', 05:40:17 INFO - 'NO_EM_RESTART': '1', 05:40:17 INFO - 'PAGER': '/bin/cat', 05:40:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:40:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:40:17 INFO - 'PWD': '/builds/slave/test', 05:40:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:40:17 INFO - 'SHELL': '/bin/bash', 05:40:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.R5uuEFbPzI/Listeners', 05:40:17 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 05:40:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:40:17 INFO - 'USER': 'cltbld', 05:40:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:40:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:40:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:40:17 INFO - 'XPC_FLAGS': '0x0', 05:40:17 INFO - 'XPC_SERVICE_NAME': '0', 05:40:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:40:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:40:18 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 05:40:18 INFO - Cleaning up... 05:40:18 INFO - Return code: 0 05:40:18 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:40:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:40:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:40:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:40:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:40:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10dbf7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc8c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd99cb6380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db5bd30>, '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 0x10d8c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10db53b20>, '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.K0ACA1f4dH/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.R5uuEFbPzI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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 05:40:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:40:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:40:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K0ACA1f4dH/Render', 05:40:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:40:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:40:18 INFO - 'HOME': '/Users/cltbld', 05:40:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:40:18 INFO - 'LOGNAME': 'cltbld', 05:40:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:40:18 INFO - 'MOZ_NO_REMOTE': '1', 05:40:18 INFO - 'NO_EM_RESTART': '1', 05:40:18 INFO - 'PAGER': '/bin/cat', 05:40:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:40:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:40:18 INFO - 'PWD': '/builds/slave/test', 05:40:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:40:18 INFO - 'SHELL': '/bin/bash', 05:40:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.R5uuEFbPzI/Listeners', 05:40:18 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 05:40:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:40:18 INFO - 'USER': 'cltbld', 05:40:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:40:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:40:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:40:18 INFO - 'XPC_FLAGS': '0x0', 05:40:18 INFO - 'XPC_SERVICE_NAME': '0', 05:40:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:40:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:40:18 INFO - Downloading/unpacking psutil>=3.1.1 05:40:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:40:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:40:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:40:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:40:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:40:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:40:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:40:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:40:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:40:21 INFO - Installing collected packages: psutil 05:40:21 INFO - Running setup.py install for psutil 05:40:21 INFO - building 'psutil._psutil_osx' extension 05:40:21 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 05:40:22 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 05:40:22 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 05:40:22 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 05:40:22 INFO - building 'psutil._psutil_posix' extension 05:40:22 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 05:40:22 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:40:22 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:40:22 INFO - ^ 05:40:22 INFO - 1 warning generated. 05:40:22 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 05:40:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:40:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:40:22 INFO - Successfully installed psutil 05:40:22 INFO - Cleaning up... 05:40:22 INFO - Return code: 0 05:40:22 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:40:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:40:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:40:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:40:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:40:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10dbf7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc8c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd99cb6380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db5bd30>, '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 0x10d8c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10db53b20>, '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.K0ACA1f4dH/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.R5uuEFbPzI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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 05:40:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:40:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:40:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K0ACA1f4dH/Render', 05:40:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:40:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:40:22 INFO - 'HOME': '/Users/cltbld', 05:40:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:40:22 INFO - 'LOGNAME': 'cltbld', 05:40:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:40:22 INFO - 'MOZ_NO_REMOTE': '1', 05:40:22 INFO - 'NO_EM_RESTART': '1', 05:40:22 INFO - 'PAGER': '/bin/cat', 05:40:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:40:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:40:22 INFO - 'PWD': '/builds/slave/test', 05:40:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:40:22 INFO - 'SHELL': '/bin/bash', 05:40:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.R5uuEFbPzI/Listeners', 05:40:22 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 05:40:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:40:22 INFO - 'USER': 'cltbld', 05:40:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:40:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:40:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:40:22 INFO - 'XPC_FLAGS': '0x0', 05:40:22 INFO - 'XPC_SERVICE_NAME': '0', 05:40:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:40:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:40:23 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:40:23 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 05:40:23 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 05:40:23 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 05:40:23 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 05:40:23 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 05:40:23 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 05:40:25 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:40:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:40:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:40:25 INFO - Installing collected packages: mozsystemmonitor 05:40:25 INFO - Running setup.py install for mozsystemmonitor 05:40:25 INFO - Successfully installed mozsystemmonitor 05:40:25 INFO - Cleaning up... 05:40:25 INFO - Return code: 0 05:40:25 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:40:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:40:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:40:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:40:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:40:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10dbf7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc8c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd99cb6380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db5bd30>, '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 0x10d8c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10db53b20>, '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.K0ACA1f4dH/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.R5uuEFbPzI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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 05:40:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:40:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 05:40:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K0ACA1f4dH/Render', 05:40:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:40:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:40:25 INFO - 'HOME': '/Users/cltbld', 05:40:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:40:25 INFO - 'LOGNAME': 'cltbld', 05:40:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:40:25 INFO - 'MOZ_NO_REMOTE': '1', 05:40:25 INFO - 'NO_EM_RESTART': '1', 05:40:25 INFO - 'PAGER': '/bin/cat', 05:40:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:40:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:40:25 INFO - 'PWD': '/builds/slave/test', 05:40:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:40:25 INFO - 'SHELL': '/bin/bash', 05:40:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.R5uuEFbPzI/Listeners', 05:40:25 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 05:40:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:40:25 INFO - 'USER': 'cltbld', 05:40:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:40:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:40:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:40:25 INFO - 'XPC_FLAGS': '0x0', 05:40:25 INFO - 'XPC_SERVICE_NAME': '0', 05:40:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:40:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:40:26 INFO - Downloading/unpacking jsonschema==2.5.1 05:40:26 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 05:40:26 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 05:40:26 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 05:40:26 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 05:40:26 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 05:40:26 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 05:40:28 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:40:28 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:40:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:40:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:40:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:40:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:40:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:40:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:40:28 INFO - Downloading functools32-3.2.3-2.tar.gz 05:40:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:40:29 INFO - warning: no files found matching '*.txt' 05:40:29 INFO - no previously-included directories found matching 'build' 05:40:29 INFO - no previously-included directories found matching 'dist' 05:40:29 INFO - no previously-included directories found matching '.git*' 05:40:29 INFO - Installing collected packages: jsonschema, functools32 05:40:29 INFO - Running setup.py install for functools32 05:40:29 INFO - warning: no files found matching '*.txt' 05:40:29 INFO - no previously-included directories found matching 'build' 05:40:29 INFO - no previously-included directories found matching 'dist' 05:40:29 INFO - no previously-included directories found matching '.git*' 05:40:29 INFO - Successfully installed jsonschema functools32 05:40:29 INFO - Cleaning up... 05:40:29 INFO - Return code: 0 05:40:29 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:40:29 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')]} 05:40:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:40:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:29 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')]} 05:40:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:40:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:29 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 0x10dbf7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc8c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd99cb6380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db5bd30>, '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 0x10d8c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10db53b20>, '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.K0ACA1f4dH/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.R5uuEFbPzI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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 05:40:29 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 05:40:29 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 05:40:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K0ACA1f4dH/Render', 05:40:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:40:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:40:29 INFO - 'HOME': '/Users/cltbld', 05:40:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:40:29 INFO - 'LOGNAME': 'cltbld', 05:40:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:40:29 INFO - 'MOZ_NO_REMOTE': '1', 05:40:29 INFO - 'NO_EM_RESTART': '1', 05:40:29 INFO - 'PAGER': '/bin/cat', 05:40:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:40:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:40:29 INFO - 'PWD': '/builds/slave/test', 05:40:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:40:29 INFO - 'SHELL': '/bin/bash', 05:40:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.R5uuEFbPzI/Listeners', 05:40:29 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 05:40:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:40:29 INFO - 'USER': 'cltbld', 05:40:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:40:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:40:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:40:29 INFO - 'XPC_FLAGS': '0x0', 05:40:29 INFO - 'XPC_SERVICE_NAME': '0', 05:40:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:40:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:40:29 INFO - Downloading/unpacking blobuploader==1.2.4 05:40:29 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 05:40:29 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 05:40:29 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 05:40:29 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 05:40:29 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 05:40:29 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 05:40:32 INFO - Downloading blobuploader-1.2.4.tar.gz 05:40:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:40:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:40:32 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 05:40:32 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 05:40:32 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 05:40:32 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 05:40:32 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 05:40:32 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 05:40:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:40:32 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:40:32 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 05:40:32 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 05:40:32 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 05:40:32 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 05:40:32 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 05:40:32 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 05:40:33 INFO - Downloading docopt-0.6.1.tar.gz 05:40:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:40:33 INFO - Installing collected packages: blobuploader, requests, docopt 05:40:33 INFO - Running setup.py install for blobuploader 05:40:33 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:40:33 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:40:33 INFO - Running setup.py install for requests 05:40:33 INFO - Running setup.py install for docopt 05:40:34 INFO - Successfully installed blobuploader requests docopt 05:40:34 INFO - Cleaning up... 05:40:34 INFO - Return code: 0 05:40:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:40:34 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')]} 05:40:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:40:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:34 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')]} 05:40:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:40:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:34 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 0x10dbf7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc8c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd99cb6380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db5bd30>, '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 0x10d8c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10db53b20>, '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.K0ACA1f4dH/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.R5uuEFbPzI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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 05:40:34 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 05:40:34 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 05:40:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K0ACA1f4dH/Render', 05:40:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:40:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:40:34 INFO - 'HOME': '/Users/cltbld', 05:40:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:40:34 INFO - 'LOGNAME': 'cltbld', 05:40:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:40:34 INFO - 'MOZ_NO_REMOTE': '1', 05:40:34 INFO - 'NO_EM_RESTART': '1', 05:40:34 INFO - 'PAGER': '/bin/cat', 05:40:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:40:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:40:34 INFO - 'PWD': '/builds/slave/test', 05:40:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:40:34 INFO - 'SHELL': '/bin/bash', 05:40:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.R5uuEFbPzI/Listeners', 05:40:34 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 05:40:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:40:34 INFO - 'USER': 'cltbld', 05:40:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:40:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:40:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:40:34 INFO - 'XPC_FLAGS': '0x0', 05:40:34 INFO - 'XPC_SERVICE_NAME': '0', 05:40:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:40:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:40:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:40:34 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-iwU9Yh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:40:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:40:34 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-7XNEN4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:40:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:40:34 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-fALXRy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:40:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:40:34 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-H6ebFO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:40:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:40:34 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-7om_fE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:40:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:40:34 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-4U649L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:40:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:40:35 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-p1RY78-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:40:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:40:35 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-VI0HNd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:40:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:40:35 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-0eaeFr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:40:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:40:35 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-zvbX3h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:40:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:40:35 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-eNfzBM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:40:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:40:35 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-Gav_Bx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:40:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:40:35 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-F_JCaQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:40:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:40:35 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-xn9oQq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:40:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:40:35 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-Hl_8GH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:40:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:40:36 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-clkkQQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:40:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:40:36 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-wfvw_n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:40:36 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:40:36 INFO - Running setup.py install for manifestparser 05:40:36 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:40:36 INFO - Running setup.py install for mozcrash 05:40:36 INFO - Running setup.py install for mozdebug 05:40:36 INFO - Running setup.py install for mozdevice 05:40:36 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:40:36 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:40:36 INFO - Running setup.py install for mozfile 05:40:36 INFO - Running setup.py install for mozhttpd 05:40:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:40:37 INFO - Running setup.py install for mozinfo 05:40:37 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:40:37 INFO - Running setup.py install for mozInstall 05:40:37 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:40:37 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:40:37 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:40:37 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:40:37 INFO - Running setup.py install for mozleak 05:40:37 INFO - Running setup.py install for mozlog 05:40:37 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:40:37 INFO - Running setup.py install for moznetwork 05:40:37 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:40:37 INFO - Running setup.py install for mozprocess 05:40:38 INFO - Running setup.py install for mozprofile 05:40:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:40:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:40:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:40:38 INFO - Running setup.py install for mozrunner 05:40:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:40:38 INFO - Running setup.py install for mozscreenshot 05:40:38 INFO - Running setup.py install for moztest 05:40:38 INFO - Running setup.py install for mozversion 05:40:38 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:40:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:40:38 INFO - Cleaning up... 05:40:38 INFO - Return code: 0 05:40:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:40: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')]} 05:40:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:40:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40: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')]} 05:40:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:40:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:38 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 0x10dbf7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc8c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd99cb6380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db5bd30>, '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 0x10d8c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10db53b20>, '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.K0ACA1f4dH/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.R5uuEFbPzI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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 05:40:38 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 05:40:38 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 05:40:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K0ACA1f4dH/Render', 05:40:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:40:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:40:38 INFO - 'HOME': '/Users/cltbld', 05:40:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:40:38 INFO - 'LOGNAME': 'cltbld', 05:40:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:40:38 INFO - 'MOZ_NO_REMOTE': '1', 05:40:38 INFO - 'NO_EM_RESTART': '1', 05:40:38 INFO - 'PAGER': '/bin/cat', 05:40:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:40:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:40:38 INFO - 'PWD': '/builds/slave/test', 05:40:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:40:38 INFO - 'SHELL': '/bin/bash', 05:40:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.R5uuEFbPzI/Listeners', 05:40:38 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 05:40:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:40:38 INFO - 'USER': 'cltbld', 05:40:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:40:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:40:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:40:38 INFO - 'XPC_FLAGS': '0x0', 05:40:38 INFO - 'XPC_SERVICE_NAME': '0', 05:40:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:40:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:40:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:40:39 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-G9EIoM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:40:39 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)) 05:40:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:40:39 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-LQT92X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:40:39 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)) 05:40:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:40:39 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-_yoOTo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:40:39 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)) 05:40:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:40:39 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-M5b5Ho-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:40:39 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)) 05:40:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:40:39 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-afzA31-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:40:39 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)) 05:40:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:40:39 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-b3kAZn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:40:39 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)) 05:40:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:40:39 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-LNSej7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:40:39 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)) 05:40:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:40:39 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-GwsvHB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:40:39 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)) 05:40:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:40:39 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-vMuP_j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:40:40 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)) 05:40:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:40:40 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-p1wvLy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:40:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 05:40:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:40:40 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-Uml6xI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:40:40 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)) 05:40:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:40:40 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-BvZFyh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:40:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:40:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:40:40 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-x_XlqX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:40:40 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)) 05:40:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:40:40 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-PeZyxl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:40:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:40:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:40:40 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-iP7brc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:40:40 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)) 05:40:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:40:40 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-Gihvgi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:40:40 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)) 05:40:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:40:40 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-1flAd5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:40:40 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)) 05:40:40 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)) 05:40:40 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)) 05:40:40 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)) 05:40:40 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)) 05:40:40 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)) 05:40:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:40:40 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 05:40:40 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 05:40:40 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 05:40:40 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 05:40:40 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 05:40:40 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 05:40:43 INFO - Downloading blessings-1.6.tar.gz 05:40:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:40:43 INFO - Installing collected packages: blessings 05:40:43 INFO - Running setup.py install for blessings 05:40:43 INFO - Successfully installed blessings 05:40:43 INFO - Cleaning up... 05:40:43 INFO - Return code: 0 05:40:43 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:40:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:40:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:40:44 INFO - Reading from file tmpfile_stdout 05:40:44 INFO - Current package versions: 05:40:44 INFO - blessings == 1.6 05:40:44 INFO - blobuploader == 1.2.4 05:40:44 INFO - docopt == 0.6.1 05:40:44 INFO - functools32 == 3.2.3-2 05:40:44 INFO - jsonschema == 2.5.1 05:40:44 INFO - manifestparser == 1.1 05:40:44 INFO - mozInstall == 1.12 05:40:44 INFO - mozcrash == 0.17 05:40:44 INFO - mozdebug == 0.1 05:40:44 INFO - mozdevice == 0.48 05:40:44 INFO - mozfile == 1.2 05:40:44 INFO - mozhttpd == 0.7 05:40:44 INFO - mozinfo == 0.9 05:40:44 INFO - mozleak == 0.1 05:40:44 INFO - mozlog == 3.3 05:40:44 INFO - moznetwork == 0.27 05:40:44 INFO - mozprocess == 0.23 05:40:44 INFO - mozprofile == 0.28 05:40:44 INFO - mozrunner == 6.12 05:40:44 INFO - mozscreenshot == 0.1 05:40:44 INFO - mozsystemmonitor == 0.3 05:40:44 INFO - moztest == 0.7 05:40:44 INFO - mozversion == 1.4 05:40:44 INFO - psutil == 3.1.1 05:40:44 INFO - requests == 1.2.3 05:40:44 INFO - wsgiref == 0.1.2 05:40:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:40:44 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')]} 05:40:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:40:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:44 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')]} 05:40:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:40:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:44 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 0x10dbf7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc8c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd99cb6380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db5bd30>, '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 0x10d8c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10db53b20>, '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.K0ACA1f4dH/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.R5uuEFbPzI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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 05:40:44 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 05:40:44 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 05:40:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K0ACA1f4dH/Render', 05:40:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:40:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:40:44 INFO - 'HOME': '/Users/cltbld', 05:40:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:40:44 INFO - 'LOGNAME': 'cltbld', 05:40:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:40:44 INFO - 'MOZ_NO_REMOTE': '1', 05:40:44 INFO - 'NO_EM_RESTART': '1', 05:40:44 INFO - 'PAGER': '/bin/cat', 05:40:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:40:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:40:44 INFO - 'PWD': '/builds/slave/test', 05:40:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:40:44 INFO - 'SHELL': '/bin/bash', 05:40:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.R5uuEFbPzI/Listeners', 05:40:44 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 05:40:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:40:44 INFO - 'USER': 'cltbld', 05:40:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:40:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:40:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:40:44 INFO - 'XPC_FLAGS': '0x0', 05:40:44 INFO - 'XPC_SERVICE_NAME': '0', 05:40:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:40:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:40:44 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)) 05:40:44 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)) 05:40:44 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)) 05:40:44 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)) 05:40:44 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)) 05:40:44 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)) 05:40:44 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)) 05:40:44 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)) 05:40:44 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)) 05:40:44 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)) 05:40:44 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)) 05:40:44 INFO - Cleaning up... 05:40:44 INFO - Return code: 0 05:40:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:40:44 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')]} 05:40:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:40:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:44 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')]} 05:40:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:40:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:44 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 0x10dbf7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc8c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd99cb6380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db5bd30>, '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 0x10d8c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10db53b20>, '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.K0ACA1f4dH/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.R5uuEFbPzI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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 05:40:44 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 05:40:44 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 05:40:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K0ACA1f4dH/Render', 05:40:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:40:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:40:44 INFO - 'HOME': '/Users/cltbld', 05:40:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:40:44 INFO - 'LOGNAME': 'cltbld', 05:40:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:40:44 INFO - 'MOZ_NO_REMOTE': '1', 05:40:44 INFO - 'NO_EM_RESTART': '1', 05:40:44 INFO - 'PAGER': '/bin/cat', 05:40:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:40:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:40:44 INFO - 'PWD': '/builds/slave/test', 05:40:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:40:44 INFO - 'SHELL': '/bin/bash', 05:40:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.R5uuEFbPzI/Listeners', 05:40:44 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 05:40:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:40:44 INFO - 'USER': 'cltbld', 05:40:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:40:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:40:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:40:44 INFO - 'XPC_FLAGS': '0x0', 05:40:44 INFO - 'XPC_SERVICE_NAME': '0', 05:40:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:40:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:40:44 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:40:44 INFO - Cleaning up... 05:40:44 INFO - Return code: 0 05:40:44 INFO - Running post-action listener: _resource_record_post_action 05:40:44 INFO - Running post-action listener: _start_resource_monitoring 05:40:44 INFO - Starting resource monitoring. 05:40:44 INFO - [mozharness: 2016-07-25 12:40:44.582214Z] Finished create-virtualenv step (success) 05:40:44 INFO - [mozharness: 2016-07-25 12:40:44.583167Z] Running install step. 05:40:44 INFO - Running pre-action listener: _resource_record_pre_action 05:40:44 INFO - Running main action method: install 05:40:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:40:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:40:44 INFO - Reading from file tmpfile_stdout 05:40:44 INFO - Detecting whether we're running mozinstall >=1.0... 05:40:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:40:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:40:44 INFO - Reading from file tmpfile_stdout 05:40:44 INFO - Output received: 05:40:44 INFO - Usage: mozinstall [options] installer 05:40:44 INFO - Options: 05:40:44 INFO - -h, --help show this help message and exit 05:40:44 INFO - -d DEST, --destination=DEST 05:40:44 INFO - Directory to install application into. [default: 05:40:44 INFO - "/builds/slave/test"] 05:40:44 INFO - --app=APP Application being installed. [default: firefox] 05:40:44 INFO - mkdir: /builds/slave/test/build/application 05:40:44 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'] 05:40:44 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 05:41:19 INFO - Reading from file tmpfile_stdout 05:41:19 INFO - Output received: 05:41:19 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:41:19 INFO - Running post-action listener: _resource_record_post_action 05:41:19 INFO - [mozharness: 2016-07-25 12:41:19.569396Z] Finished install step (success) 05:41:19 INFO - [mozharness: 2016-07-25 12:41:19.569555Z] Running run-tests step. 05:41:19 INFO - Running pre-action listener: _resource_record_pre_action 05:41:19 INFO - Running main action method: run_tests 05:41:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:41:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:41:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:41:19 INFO - 2016-07-25 05:41:19.584 screenresolution[1645:8388] starting screenresolution argv=screenresolution get 05:41:19 INFO - 2016-07-25 05:41:19.586 screenresolution[1645:8388] Display 0: 1600x1200x32@60 05:41:19 INFO - 2016-07-25 05:41:19.592 screenresolution[1646:8390] starting screenresolution argv=screenresolution list 05:41:19 INFO - Available Modes on Display 0 05:41:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:41:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:41:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:41:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:41:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:41:19 INFO - Intel Iris: 05:41:19 INFO - Chipset Model: Intel Iris 05:41:19 INFO - Type: GPU 05:41:19 INFO - Bus: Built-In 05:41:19 INFO - VRAM (Dynamic, Max): 1536 MB 05:41:19 INFO - Vendor: Intel (0x8086) 05:41:19 INFO - Device ID: 0x0a2e 05:41:19 INFO - Revision ID: 0x0009 05:41:19 INFO - Displays: 05:41:19 INFO - Display: 05:41:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:41:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:41:19 INFO - Display Serial Number: 1600x1200 60 05:41:19 INFO - Main Display: Yes 05:41:19 INFO - Mirror: Off 05:41:19 INFO - Online: Yes 05:41:19 INFO - Rotation: Supported 05:41:19 INFO - Return code: 0 05:41:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:41:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:41:19 INFO - Python 2.7.3 05:41:19 INFO - Return code: 0 05:41:19 INFO - grabbing minidump binary from tooltool 05:41:19 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')]} 05:41:19 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 0x10db5bd30>, '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 0x10d8c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10db53b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:41:19 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 05:41:19 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 05:41:19 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 05:41:19 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:41:19 INFO - Return code: 0 05:41:19 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:41:19 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:41:19 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:41:19 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:41:19 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:41:19 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-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZwzSrdUhRFy0zUlDHpekQA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0119', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 05:41:19 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-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ZwzSrdUhRFy0zUlDHpekQA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0119 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 05:41:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K0ACA1f4dH/Render', 05:41:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:41:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:41:19 INFO - 'HOME': '/Users/cltbld', 05:41:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:41:19 INFO - 'LOGNAME': 'cltbld', 05:41:19 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:41:19 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:41:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:41:19 INFO - 'MOZ_NO_REMOTE': '1', 05:41:19 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:41:19 INFO - 'NO_EM_RESTART': '1', 05:41:19 INFO - 'PAGER': '/bin/cat', 05:41:19 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:41:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:41:19 INFO - 'PWD': '/builds/slave/test', 05:41:19 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:41:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:41:19 INFO - 'SHELL': '/bin/bash', 05:41:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.R5uuEFbPzI/Listeners', 05:41:19 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 05:41:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:41:19 INFO - 'USER': 'cltbld', 05:41:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:41:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:41:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:41:19 INFO - 'XPC_FLAGS': '0x0', 05:41:19 INFO - 'XPC_SERVICE_NAME': '0', 05:41:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:41:19 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZwzSrdUhRFy0zUlDHpekQA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0119', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 05:41:20 INFO - mozversion application_buildid: 20160725032124 05:41:20 INFO - mozversion application_changeset: 1bb05a19ff9d70ed1d858d526fdbe27a8408f19f 05:41:20 INFO - mozversion application_display_name: Nightly 05:41:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:41:20 INFO - mozversion application_name: Firefox 05:41:20 INFO - mozversion application_remotingname: firefox 05:41:20 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 05:41:20 INFO - mozversion application_vendor: Mozilla 05:41:20 INFO - mozversion application_version: 50.0a1 05:41:20 INFO - mozversion platform_buildid: 20160725032124 05:41:20 INFO - mozversion platform_changeset: 1bb05a19ff9d70ed1d858d526fdbe27a8408f19f 05:41:20 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 05:41:20 INFO - mozversion platform_version: 50.0a1 05:41:20 INFO - using testdate: 1469450480 05:41:20 INFO - actual date: 1469450480 05:41:20 INFO - starting webserver on 'localhost:49273' 05:41:20 INFO - SUITE-START | Running 2 tests 05:41:20 INFO - TEST-START | dromaeo_css 05:41:20 INFO - operating with platform_type : mac_ 05:41:20 INFO - Initialising browser for dromaeo_css test... 05:41:20 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/tmptOARPm/profile http://localhost:49273/getInfo.html) 05:41:22 INFO - PROCESS | 1656 | JavaScript error: , line 0: uncaught exception: undefined 05:41:23 INFO - PROCESS | 1656 | __metrics Screen width/height:1600/1200 05:41:23 INFO - PROCESS | 1656 | colorDepth:24 05:41:23 INFO - PROCESS | 1656 | Browser inner width/height: 1024/689 05:41:23 INFO - PROCESS | 1656 | __metrics 05:41:23 ERROR - PROCESS | 1656 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2968: TypeError: this.tabs is undefined 05:41:24 INFO - TEST-INFO | 1656: exit 0 05:41:24 INFO - Browser initialized. 05:41:24 INFO - Running cycle 1/1 for dromaeo_css test... 05:41:24 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/tmptOARPm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:41:35 INFO - PROCESS | 1660 | RSS: Main: 256167936 05:41:35 INFO - PROCESS | 1660 | 05:42:25 INFO - PROCESS | 1660 | 1469450545651 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] 05:44:37 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49273/tests/dromaeo/cssquery-ext.html) 05:44:38 INFO - PROCESS | 1660 | RSS: Main: 271482880 05:44:38 INFO - PROCESS | 1660 | 05:47:40 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-ext.html (next: http://localhost:49273/tests/dromaeo/cssquery-jquery.html) 05:47:41 INFO - PROCESS | 1660 | RSS: Main: 282161152 05:47:41 INFO - PROCESS | 1660 | 05:50:43 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49273/tests/dromaeo/cssquery-mootools.html) 05:50:44 INFO - PROCESS | 1660 | RSS: Main: 289325056 05:50:44 INFO - PROCESS | 1660 | 05:53:45 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49273/tests/dromaeo/cssquery-prototype.html) 05:53:46 INFO - PROCESS | 1660 | RSS: Main: 289173504 05:53:46 INFO - PROCESS | 1660 | 05:56:49 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49273/tests/dromaeo/cssquery-yui.html) 05:56:49 INFO - PROCESS | 1660 | RSS: Main: 293199872 05:56:49 INFO - PROCESS | 1660 | 05:59:52 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-yui.html (next: http://localhost:49273/tests/dromaeo/cssquery-dojo.html) 05:59:53 INFO - PROCESS | 1660 | RSS: Main: 294199296 05:59:53 INFO - PROCESS | 1660 | 05:59:53 INFO - PROCESS | 1660 | __start_tp_report 05:59:53 INFO - PROCESS | 1660 | _x_x_mozilla_page_load 05:59:53 INFO - PROCESS | 1660 | _x_x_mozilla_page_load_details 05:59:53 INFO - PROCESS | 1660 | |i|pagename|runs| 05:59:53 INFO - PROCESS | 1660 | |0;dojo.html;3407.8807241746545;3432.605250396205;3440.965590344099;3445.7243420526343;3445.844936977836;5838.474537291643;5844.415558444148;5892.499137573312;5893.145493903381;5921.23024006879;4801.423829140499;5687.544996400288;5807.332156801969;5808.1578171165265;5836.6789826559525;5226.111561034624;5259.342582177228;5340.706261155635;5366.490183432567;5396.622236443451;5307.2569840222395;5471.917921231185;5481.725913704318;5485.259489968843;5485.4788795064405;4302.505211900609;4771;4899.608031357483;5305;5368.275282836806;5183.403908550527;5244.396894357122;5258.07983602868;5267.104592219332;5310.92033619496;5228.581713462914;5256.842294731164;5287.296728715708;5295.073362161606;5305.151175811851;5763.394843541451;5815.15680226369;5905.6751878646655;5911.645301281937;5940.9108863366655;6174.691265436711;6191.938080619181;6234.937650623481;6249.218847644044;6250.624962502219;5286.5242128208265;6262.968685156545;6286.119943207955;6306.87386252277;6307.621542707452;5395.73021348931;5611.354694210137;5837.357890632027;6004.1984618904735;6048.818535443944;6391.936080639227;6397.840053998687;6408.58344207625;6410.358964103552;6418.711157997901;6278.937210627927;6291.559590828598;6307.526935479766;6316.9368306317265;6341.524385671093;6188.9690551546955;6225;6228.688565571794;6285.497160227263;6287.465565426898;6124.228368151176;6162;6222.782202622887;6269.96865015672;6282.780102696475;6236.371348020205;6286.088517164985;6290.528210384148;6290.779822706183;6293.118963345135;4957.281194226817;5038.949610503848;5075.264086707481;5092.10888094582;5098.133317336062;4240.724352917051;4498.415206023196;4518.548145185455;4585.679002469795;4616.422947131608;5879.2944846618675;5995.670238136944;6057.545684073712;6066.181065556182;6079;6205.6586887720705;6219.657918814509;6292.905606415908;6299.370062993756;6305.117283580303;5951.880962380815;6008.549358798107;6061.3029501607;6082.057281121433;6102.206713127265;5534.833954981276;5555.611107222537;5565.777368905198;5585.497305242548;5601.5238704709745;6185.41238582334;6292.811215663537;6438.227412710505;6444.581102228341;6444.709988050549;6203.410675985774;6245.718942647501;6296.307406185224;6300.464460520907;6309.684515774285;6387.936120638734;6428.967855160694;6446.387593178641;6447.323031081669;6492.772752953624;6143.082036033027;6147.538934580014;6300.653464059521;6300.71646775887;6320.462760665395;6212.81361559154;6248.68756562161;6276.309605943434;6289.874202516015;6291.213598300213;5919.31927828288;6153.3538978408415;6162.907556386568;6179.536534760001;6205.379462053758;6089.2997305310455;6146.893559159215;6163;6163.876722465615;6190;6039.607425517327;6057.121717350871;6111.205543279345;6134.650895842656;6161.784337548164;6163.106349579196;6217.678713294217;6229.813105606839;6267.780627678009;6292.653904035322;5990.311114221746;6041.577089603861;6131.448169664752;6135.509159267337;6158.538109641705 05:59:53 INFO - PROCESS | 1660 | |1;ext.html;498.42929845327103;521.746952727927;524.5174439515645;543.5542854859016;574.8218052403757;36856.26287474249;38158.42762358563;41036.58963410372;41088.97277568059;41168.38247426287;38291.80854095726;42017;42285;42409.15181696364;42524;9509.904900950989;9559.856602150974;9651.372660777028;9704.514774261293;9723.513824308791;8178.836423271531;9572.521373931275;9650.276229282797;9693.806123877519;9708.61165553377;9655.806883862319;9693.321467497279;9716.271279654018;9717.076877696607;9754.902450975505;9663.033696630317;9676.854847177263;9706.563204655808;9708.708738737778;9717.854232186522;8606.88110545625;9158.313126515539;9533.570989305497;9594.13652771247;9615.134637882555;39791.80624581267;39932.600673993176;40061.59938400608;40241.195176096604;40562.79718601388;3123.875044998197;3285.2772390074147;3329.3840639481773;3456.688897999167;3530.75284730069;28868.989585364623;30273.848630756926;30367.848160759277;31011.06966791;31519.212019699462;3118.70372314632;3132.295233572451;3147.244661281276;3149.2756665966954;3231.5152727091127;458053;458581;460625;460918;464268;32690.019299421056;33007.3398532028;33038.50442243368;33046.50430243548;33114.50328245078;34094.65905340965;34226.82886585551;34352.82823585916;35333.29333413317;35527.11182220465;16495.927764695258;16877.32490700383;16877.746833797504;16943.98336099838;17052.976821390756;12895.48418063267;12983.675408114872;13087.607371778862;13089.607311780663;13091.672708182181;16104.919475402548;19434.319798807257;19442.319518817058;19499.220031198878;19521.31675391383;6716.697748601325;8186.590670466453;8192.624138999623;8220.917790822015;8269.62786674601;750.6284389227368;967.8790151231096;980.8332583460686;1055.883852776194;1057.952626899364;30275.697243027727;30732.692673072983;30909.227269318446;31148;31684.20789480235;1082.6697857153645;1095.367901826273;1120.1486869978876;1150.3328069719544;1176.8823117688162;15545;16027.278772455034;16084.43704470338;16196.352145914032;16464.588385290223;673053;682569;685515;685648;688209;58992;59404;60058.69970650206;60125.398746012856;60160;1293.6442478318538;1392.4569417927016;1395.7278330725412;1432.4556668466125;1433.33349992252;29714;30702.925397610976;31273.37453250968;31912.840435797672;32392.83803580967;52597;57631.71184144053;57875;58266.7086664564;58505;596243;596485;599818;600671;603007;38232.853014409615;43566.34650480182;44233.11533769242;44368.33447498226;45222.86942826456;44747.55252447434;48210.758946205046;49012.754936225094;49261.261081083096;49644.25533617071;37196;37462.8126859364;40537;40796.79601601973;42204;40494.1901161981;40769;41352.79323603363;41797.79101104475;42199.57800422079;33554.16114597155;38965.610343896195;39585.2082958345;39633.40549891812;39730.205395891346;33741.66258337385;35833.64166358407;35977;36369.454458183674;37168.628313716516;23030.769692302863;25998.870005650606;26028.86985565136;26560.601590975763;26576.936922523655 05:59:53 INFO - PROCESS | 1660 | |2;jquery.html;354.6276409769742;370.08773373634006;383.46506623260564;394.6763653803882;404.01017507107576;15686;19281.035948202585;19982.400527984184;21013.789862101356;21289.68065479019;17969.82030179698;21190;21197.576048479023;21847.126114955383;21952;5942.6137301075505;5985.34161242263;6008.8497787555225;6066;6134.325224225332;5965.373635768247;6005.099235114724;6016.8495787605225;6023.126646636235;6080;5874.97062514689;5976.462118409342;5979.581429299951;6032.426919442646;6045;5846.473817356458;5909.08409196573;5916.497097746696;5986.401359864022;6029.517638588903;5155.896882062375;5201.063808514466;5253;5263.31576895005;5269.473052694738;26605.201843944713;26841.865790671116;26947.730522694717;27030.053948111723;27209.591856122173;2375.5248950210025;2462.4213309872166;2490.713567939692;2681.6379788728664;3005.2787331040395;12789;16102.677946441005;16257.268422920997;16283.837161628351;16479.423220187236;1808.2586139682799;1868.6449574580786;1887.584731359099;1909.6944488881852;1915.4062240705287;122695.77304226933;123547.382263089;124877;126336.36831815874;127116.99999999907;10686.786264274671;10722.249442539103;11261.155413344031;11268.774624507463;11396.316221026598;11360.886391136148;12016.759664806656;12145.271283723005;12743.745125097632;13754.174749514861;7406.333429991327;8529.232369086812;8675.783105422288;8865.68970086044;8983.338082454768;7505.949167116608;8958.283337332998;8988.235999939947;9009.324300677476;9199.678011269574;5680.460356266148;6653.63405012729;6668.499862510234;6710.597364158166;6768.357006084414;1560.8595226429675;1901.5531350132708;1903.6763750162506;1905.3902751119786;1907.7329173915664;908.9547020925855;918.3938600523622;978.2858513285199;1007.2244371833647;1013.3463915774431;21824.345269641937;22923.31230063101;23054.538909222058;23108.88445557795;23139.958701858457;983.695054533097;1012.711377257478;1016.11090295991;1028.922830787694;1058.4284486377423;11512.712182195344;12054.638360849189;12064.758704825854;12186.08604354677;12204.267743935392;583050;590555;591118;594911;596664;91877;120577;120586.79413205931;129366.35316823356;132634.33682831717;967.67099186277;980.9901900981041;1012.8025035118228;1047.4762618690654;1051.138066785244;16916.069616170735;17116.65766684684;20162.697559536326;20208.79791202069;20523.076461559565;39450.80274598609;52437;53487.73256133695;53879.461205387444;58575.53561160835;65504.34495654982;77087;84012.15987840043;84865;85023.14976850152;7958;8107.986501687289;8274.544900030556;8313.709020184264;8319.334453243606;10735.516901739284;11386.658400248005;12950.093493455744;13277.136986095873;13408.597742067754;11045.282056666612;11444.828327574927;11518.769624607628;11703.356315402734;11825.586104486301;9849.310548261554;11221.326720396803;11538.538458461568;11552.826707599224;11925.76148477043;10004.749881252736;11519.30884146954;11700.531978721096;11988.280703157838;12213;7206.6396680164735;7407.666655000646;7610.847783044197;7646.808829779077;8095.83808323842;10215.182785377301;11674.416279185836;11825.822612660644;11903.761924761284;11927.105467090176 05:59:53 INFO - PROCESS | 1660 | |3;mootools.html;1580.1072394097337;1610.8630766384856;1693.5766058485378;1840.7514985476957;1903.5621806984402;6669.666516674171;8482.872756908644;8494.150584941519;8887.111288871118;8937.240334571561;8631.00743414506;8784.692535761249;8795.78010549735;8811.383203175781;8811.86782198266;2363.314638754759;2395.688560487134;2404.230646193219;2416.1905761569897;2426.502566973766;2375.061850569024;2377.4294169399304;2413.806895448369;2420.745821688724;2431.659567660529;2372.7627237276306;2385.153270588942;2397.7961873240797;2398.880055997202;2419.1653879411574;2382.1424287256573;2390.529824158139;2401.987990060047;2403.4712363280137;2408.9397765055837;2364.692589963294;2378.131981826638;2388.796952259043;2402.5915594349003;2407.2898494944034;6691.230508491489;8205.95897020511;8938.910610893938;9003.369764116514;9003.684871029547;4072.5723799000975;4608.953910460886;5329.800794821134;5408.513233808976;5414.83755487336;3012.5631783391505;3508.4737289406794;3641.8543258269638;3705.3330400527893;3707.925841483155;941.7457286532598;967.7532229281559;978.7993461340479;985.6895078050463;986.4623780039896;11420.71448213793;11443.771124577463;11539;11552.191346605741;11579.015783658399;4754.833580824724;4760.237666724221;4785.856424307276;4841.830535931225;4870.707757534559;9697.224222062217;9866.80266394668;9883.209343252522;9894.60421583143;9900;5337.786488540415;5377.139657654757;5396.217548455452;5398.4871437213405;5406.729663516809;4022.577629348935;4329.393884856123;4429.75636339998;4692.272697731857;4916.778744956486;3946.3685810270795;3962.980185099056;4003;4055.7769322687536;4113.9794301028305;1290.5612091888802;1305.3081866610712;1308.1693124865778;1316.427354100969;1317.9209247445183;955.9378640388353;961.5288508630721;965.4931161140457;968.7965527239359;1000.3047881722279;5965.6718880461995;5989.401059893976;6132.233470816148;6152.446279834837;6174.691265436711;447.4116536754158;447.75597299471866;452.2447512653876;455.8883073646911;458.49794475049765;4032.1734044520804;4044.676425885922;4088;4167.645750111214;4179.289520781414;10398.58405663765;10690.62582809598;10813.18901082422;10828.72928176786;10833.7541182765;10704.78590428187;10712.46437678113;10751.139908807276;10783.568657253621;10859.185561082792;7343.192248852622;7706.344960678293;7707.922920770833;8270.131636178236;8369.246767790928;5963.314218864886;6085.756569737161;6126.938730612636;6200.689965501797;6216.658083805434;10000.799984000425;10242.334248273839;10254.436006019741;10266.024727650862;10279.537420815932;8256.380771442045;8357.707480238163;9662.71011869645;10140.847887281549;10171.694849154537;7010.158780946319;7332.266773322838;7509.6245187739305;7582.203868593939;7705.807354816175;8407.621657025325;8495.235428811437;8528.59278219087;8545.444546104483;8569.914300856912;7496.812579685551;7578.393728501596;7624.466287359832;7682.7311044113185;7739.883901741366;7387.1135463744695;7508.58702771331;7522.398208143226;7628.54228746277;7690.461667683208;7052.682629281592;7163;7173.748918787817;7175.8564828704175;7200.423966082805;4972.403311602631;5001.224810154432;5106.948930510647;5118.181091025418;5133.717645529532;5057.595392368528;5073.923891141562;5180.430152683127;5195.74021298926;5269.420363759907 05:59:53 INFO - PROCESS | 1660 | |4;prototype.html;308.82705684816506;314.10636738479025;320.7930884579446;323.95140728890647;325.29086591231123;5329.147336426163;5507.311586051744;5589.413111623282;5648.373506319561;5671.602987790851;4439.866803995886;4516.345129956155;5126.487351264872;5507.366652834925;5557.805476808313;3673.963260367389;4116.9090191099385;4261.936070958938;4267.396887898395;4274.29474136767;3441.2773317603333;3589.4436362363745;4298.290782020963;4304.548022457644;4312.870613881589;3291.901242962703;4144.48193975753;4157.397177409274;4174.20690068886;4273.273543497613;3824.368979118442;3952.664023558001;4154.709170358061;4165.27107756143;4195.538490766014;3183.4110689522513;3595.694365978895;3607.4769158472136;3622.1487950331652;3629.566321303097;4699.365585645963;5035.395752509686;5362.088444964365;5400.21696853954;5424.810131645413;3952.940705889414;4801.399825021872;4805.783739731721;4811.566958973674;4811.759412029384;5838.222468881308;5885.5585831062845;5915.940840591582;5965.583938504869;5988.101784732325;3207.182168547029;3832.6358995895343;3853.402722578005;3902.9462045247624;3961.524617045944;5607.803726869581;6019.789307374264;6215;6216.699821066234;6225.501959843247;3050.496668049747;3323.2522682396752;4010;4016.658584020391;4032.274190645653;3646.744727869024;3912.6478616924046;4038;4044.656204222674;4055.249778790874;4331.578053337827;4450.908073205588;4734.95265047352;4757.26262429324;4807.831725889577;5579.358373787067;5920.703964801743;5950.970245148833;5987.341392446828;6005.918934591816;1120.6862078618;1306.2162702378496;1361.3057340756088;1367.9056754596481;1387.3756809435592;899.9244262360293;908.14180599333;933.6778811310087;933.7245512573807;934.3879758758025;4285.46431696038;4949.896294075075;5429.203959960623;5482.972585137049;5538.667679939216;4605.424321959755;4905.975470122627;4946.629002824802;5988.461038506555;6094.451499365078;5162.767675454614;5417.670401727295;5477.863053423696;5517.889642207214;5574.470425309589;2240.7759224077786;2686.7775162301105;2690.5829596412586;2723.9319017024336;2732.142143342546;4081.245159517314;4416.779161041935;4892.315075889307;5125.000624878113;5361;4779.904401912012;4856.538628830255;4959.3467494890065;5198.532132108128;5320;4335.7181783184;4732.929006064981;4834.323194752737;5248.501392367719;5276.625518516759;4227.619514243733;4762.3570817939135;5095.464976177448;5152.1405619881325;5215.243789650403;4197.439561867198;4286.635635970977;4350.108227813227;4617.122746678255;4991.627302491844;4394.318880573516;5140.640155189101;5156.793728250828;5213.244079608511;5275.472452754694;3231.854566544464;3511.613722490575;3541.5395998520025;3541.840617172285;3554.928901422009;4536;4834.661573689808;4841.564259216688;5155.313950525392;5178.430372658929;3878;4619.769011549476;4996.528107343979;5047.394312682502;5055.317532133115;4330.328799036154;4717.834875779331;4785.282207668738;4791.47293797689;4999.350084488993;4607.917139472288;4629.837955671535;4894.213612031654;4895.755212239445;4910.8281210157475;3864.845406183721;4012.819423125908;4315.956840431555;4333.544977777288;4962.429320628174;4049.0687141958106;4311.49933038843;4483.775811209492;4834.993477706853;4891.046245981999 05:59:53 INFO - PROCESS | 1660 | |5;yui.html;704.2851505721693;731.7841236835133;789.992100078999;801.2988634944426;820.2371794231362;15140;15535.99016063955;15684.215789210552;15882.761758573617;15962.600934976632;14095.295235238249;14331.92834035831;14429.927850360733;14458.277086145703;14474.2762861857;702.3257672634252;853.5646820121736;861.6165806216236;881.1188811188811;885.1723638398119;860.3547339495378;870.0559892516595;874.5845723281454;875.5840975536601;877.9473231606092;906.6282824042144;926.3654396738256;928.5310917986408;929.7350255177275;930.9534523273843;893.7408151636017;894.6287290774353;903.2818908967354;919.9908000919972;923.0031565908803;663.1081195791653;664.7706541243264;685.5578152091873;687.4500399680286;687.7077242172057;14329.35517901697;14918.925405373013;14949.476768313165;14959.252037398195;14994.62513437162;14749.557513274618;14781.187034713086;14853.48012819546;14912.328945197492;14922.70154596902;10165.698599618054;11968;12091.21407108544;12376.443060062318;12536.498540058477;3911.471951286568;3957.604239576019;3979.6597376026593;4100.01599616093;4109.116539943926;119192.21211681832;120478.39760801228;120689;120914.39542802317;123122.76877231203;11268.267562608404;11296.6046188383;11319.490622921989;11344.376059316652;11483.36841473727;23010.884945575166;23228.187013454448;23251.651225231635;23324.183653572043;23341.18305859287;7266.745663901844;8152.572254907825;8266.380021498411;8392.790180245544;8584.527850968258;4538.9546104539195;4558.133954548625;4571.519990401027;4593.494715581283;4599.241125214302;3138.952915706266;3553.07620018797;3666.1201311685004;3670.339338918967;3670.504481894963;615.5260449453984;659.3538332434241;663.0750103605443;669.2604671837647;672.9461643068543;674.4925092418441;676.2358534855582;712.2521352579771;722.8301349182938;728.5191773429617;7585.658645360972;7667.424943129287;8316.87722157513;9559.569819358145;9812.852807207897;601.6901295832649;661.995315415451;662.2020465339255;668.7859884836903;708.4615692074061;4459.197344478025;4703.176944034849;4742.074620420578;4779.187538118528;4867.95132048675;124562;147804.26097869658;149883;151643;157648;117159;133058;134572;134586;137277;104868.47565762122;112958.43520782508;114648;115505;115896.42051789687;8679.218870301704;9197.540122993958;9294.163525282756;9324.487153206368;9449.574769135268;51046.744766277414;63094.369056308846;63483;64119.038214427754;65053.67473162604;83558.74661880198;88114;91368;91595.54202228946;96642.51678741562;13521.59435216945;14079.0848594841;14153.292335383396;14180.716385672435;14501.854981450473;15773.211339433212;15832.28754706064;15915.363385464452;16120.274587643818;16295.51113466598;14196.36116374745;14716;14827.258637068318;15266.923665381602;15388.692226155637;13825.377857996615;14576.416943322149;14683.706325873209;14976.775348369565;15320.38718451294;14505;14690.485832995793;14811.25943702832;14854.257287135384;14962.700745985238;10932.672019839418;11499.137564682514;11681.474333655176;11722.94138529302;12285.75428491443;9226.769330766785;10437.425941573287;10978.341299522055;11761.294322340687;11830.290182589073 05:59:53 INFO - PROCESS | 1660 | __end_tp_report 05:59:53 INFO - PROCESS | 1660 | __start_cc_report 05:59:53 INFO - PROCESS | 1660 | _x_x_mozilla_cycle_collect,445 05:59:53 INFO - PROCESS | 1660 | __end_cc_report 05:59:53 INFO - PROCESS | 1660 | __startTimestamp1469451593159__endTimestamp 05:59:53 INFO - PROCESS | 1660 | 05:59:53 INFO - PROCESS | 1660 | ------- Summary: start ------- 05:59:53 INFO - PROCESS | 1660 | Number of tests: 6 05:59:53 INFO - PROCESS | 1660 | 05:59:53 INFO - PROCESS | 1660 | [#0] dojo.html Cycles:165 Average:5825.49 Median:6111.21 stddev:653.85 (10.7%) stddev-sans-first:627.74 05:59:53 INFO - PROCESS | 1660 | Values: 3407.9 3432.6 3441.0 3445.7 3445.8 5838.5 5844.4 5892.5 5893.1 5921.2 4801.4 5687.5 5807.3 5808.2 5836.7 5226.1 5259.3 5340.7 5366.5 5396.6 5307.3 5471.9 5481.7 5485.3 5485.5 4302.5 4771.0 4899.6 5305.0 5368.3 5183.4 5244.4 5258.1 5267.1 5310.9 5228.6 5256.8 5287.3 5295.1 5305.2 5763.4 5815.2 5905.7 5911.6 5940.9 6174.7 6191.9 6234.9 6249.2 6250.6 5286.5 6263.0 6286.1 6306.9 6307.6 5395.7 5611.4 5837.4 6004.2 6048.8 6391.9 6397.8 6408.6 6410.4 6418.7 6278.9 6291.6 6307.5 6316.9 6341.5 6189.0 6225.0 6228.7 6285.5 6287.5 6124.2 6162.0 6222.8 6270.0 6282.8 6236.4 6286.1 6290.5 6290.8 6293.1 4957.3 5038.9 5075.3 5092.1 5098.1 4240.7 4498.4 4518.5 4585.7 4616.4 5879.3 5995.7 6057.5 6066.2 6079.0 6205.7 6219.7 6292.9 6299.4 6305.1 5951.9 6008.5 6061.3 6082.1 6102.2 5534.8 5555.6 5565.8 5585.5 5601.5 6185.4 6292.8 6438.2 6444.6 6444.7 6203.4 6245.7 6296.3 6300.5 6309.7 6387.9 6429.0 6446.4 6447.3 6492.8 6143.1 6147.5 6300.7 6300.7 6320.5 6212.8 6248.7 6276.3 6289.9 6291.2 5919.3 6153.4 6162.9 6179.5 6205.4 6089.3 6146.9 6163.0 6163.9 6190.0 6039.6 6057.1 6111.2 6134.7 6161.8 6163.1 6217.7 6229.8 6267.8 6292.7 5990.3 6041.6 6131.4 6135.5 6158.5 05:59:53 INFO - PROCESS | 1660 | 05:59:53 INFO - PROCESS | 1660 | [#1] ext.html Cycles:180 Average:70662.87 Median:40151.40 stddev:157432.01 (392.1%) stddev-sans-first:157785.50 05:59:53 INFO - PROCESS | 1660 | Values: 498.4 521.7 524.5 543.6 574.8 36856.3 38158.4 41036.6 41089.0 41168.4 38291.8 42017.0 42285.0 42409.2 42524.0 9509.9 9559.9 9651.4 9704.5 9723.5 8178.8 9572.5 9650.3 9693.8 9708.6 9655.8 9693.3 9716.3 9717.1 9754.9 9663.0 9676.9 9706.6 9708.7 9717.9 8606.9 9158.3 9533.6 9594.1 9615.1 39791.8 39932.6 40061.6 40241.2 40562.8 3123.9 3285.3 3329.4 3456.7 3530.8 28869.0 30273.8 30367.8 31011.1 31519.2 3118.7 3132.3 3147.2 3149.3 3231.5 458053.0 458581.0 460625.0 460918.0 464268.0 32690.0 33007.3 33038.5 33046.5 33114.5 34094.7 34226.8 34352.8 35333.3 35527.1 16495.9 16877.3 16877.7 16944.0 17053.0 12895.5 12983.7 13087.6 13089.6 13091.7 16104.9 19434.3 19442.3 19499.2 19521.3 6716.7 8186.6 8192.6 8220.9 8269.6 750.6 967.9 980.8 1055.9 1058.0 30275.7 30732.7 30909.2 31148.0 31684.2 1082.7 1095.4 1120.1 1150.3 1176.9 15545.0 16027.3 16084.4 16196.4 16464.6 673053.0 682569.0 685515.0 685648.0 688209.0 58992.0 59404.0 60058.7 60125.4 60160.0 1293.6 1392.5 1395.7 1432.5 1433.3 29714.0 30702.9 31273.4 31912.8 32392.8 52597.0 57631.7 57875.0 58266.7 58505.0 596243.0 596485.0 599818.0 600671.0 603007.0 38232.9 43566.3 44233.1 44368.3 45222.9 44747.6 48210.8 49012.8 49261.3 49644.3 37196.0 37462.8 40537.0 40796.8 42204.0 40494.2 40769.0 41352.8 41797.8 42199.6 33554.2 38965.6 39585.2 39633.4 39730.2 33741.7 35833.6 35977.0 36369.5 37168.6 23030.8 25998.9 26028.9 26560.6 26576.9 05:59:53 INFO - PROCESS | 1660 | 05:59:53 INFO - PROCESS | 1660 | [#2] jquery.html Cycles:180 Average:35071.63 Median:23124.42 stddev:98682.04 (426.7%) stddev-sans-first:98924.44 05:59:53 INFO - PROCESS | 1660 | Values: 354.6 370.1 383.5 394.7 404.0 15686.0 19281.0 19982.4 21013.8 21289.7 17969.8 21190.0 21197.6 21847.1 21952.0 5942.6 5985.3 6008.8 6066.0 6134.3 5965.4 6005.1 6016.8 6023.1 6080.0 5875.0 5976.5 5979.6 6032.4 6045.0 5846.5 5909.1 5916.5 5986.4 6029.5 5155.9 5201.1 5253.0 5263.3 5269.5 26605.2 26841.9 26947.7 27030.1 27209.6 2375.5 2462.4 2490.7 2681.6 3005.3 12789.0 16102.7 16257.3 16283.8 16479.4 1808.3 1868.6 1887.6 1909.7 1915.4 122695.8 123547.4 124877.0 126336.4 127117.0 10686.8 10722.2 11261.2 11268.8 11396.3 11360.9 12016.8 12145.3 12743.7 13754.2 7406.3 8529.2 8675.8 8865.7 8983.3 7505.9 8958.3 8988.2 9009.3 9199.7 5680.5 6653.6 6668.5 6710.6 6768.4 1560.9 1901.6 1903.7 1905.4 1907.7 909.0 918.4 978.3 1007.2 1013.3 21824.3 22923.3 23054.5 23108.9 23140.0 983.7 1012.7 1016.1 1028.9 1058.4 11512.7 12054.6 12064.8 12186.1 12204.3 583050.0 590555.0 591118.0 594911.0 596664.0 91877.0 120577.0 120586.8 129366.4 132634.3 967.7 981.0 1012.8 1047.5 1051.1 16916.1 17116.7 20162.7 20208.8 20523.1 39450.8 52437.0 53487.7 53879.5 58575.5 65504.3 77087.0 84012.2 84865.0 85023.1 7958.0 8108.0 8274.5 8313.7 8319.3 10735.5 11386.7 12950.1 13277.1 13408.6 11045.3 11444.8 11518.8 11703.4 11825.6 9849.3 11221.3 11538.5 11552.8 11925.8 10004.7 11519.3 11700.5 11988.3 12213.0 7206.6 7407.7 7610.8 7646.8 8095.8 10215.2 11674.4 11825.8 11903.8 11927.1 05:59:53 INFO - PROCESS | 1660 | 05:59:53 INFO - PROCESS | 1660 | [#3] mootools.html Cycles:180 Average:5695.30 Median:4986.81 stddev:3180.88 (63.8%) stddev-sans-first:3174.77 05:59:53 INFO - PROCESS | 1660 | Values: 1580.1 1610.9 1693.6 1840.8 1903.6 6669.7 8482.9 8494.2 8887.1 8937.2 8631.0 8784.7 8795.8 8811.4 8811.9 2363.3 2395.7 2404.2 2416.2 2426.5 2375.1 2377.4 2413.8 2420.7 2431.7 2372.8 2385.2 2397.8 2398.9 2419.2 2382.1 2390.5 2402.0 2403.5 2408.9 2364.7 2378.1 2388.8 2402.6 2407.3 6691.2 8206.0 8938.9 9003.4 9003.7 4072.6 4609.0 5329.8 5408.5 5414.8 3012.6 3508.5 3641.9 3705.3 3707.9 941.7 967.8 978.8 985.7 986.5 11420.7 11443.8 11539.0 11552.2 11579.0 4754.8 4760.2 4785.9 4841.8 4870.7 9697.2 9866.8 9883.2 9894.6 9900.0 5337.8 5377.1 5396.2 5398.5 5406.7 4022.6 4329.4 4429.8 4692.3 4916.8 3946.4 3963.0 4003.0 4055.8 4114.0 1290.6 1305.3 1308.2 1316.4 1317.9 955.9 961.5 965.5 968.8 1000.3 5965.7 5989.4 6132.2 6152.4 6174.7 447.4 447.8 452.2 455.9 458.5 4032.2 4044.7 4088.0 4167.6 4179.3 10398.6 10690.6 10813.2 10828.7 10833.8 10704.8 10712.5 10751.1 10783.6 10859.2 7343.2 7706.3 7707.9 8270.1 8369.2 5963.3 6085.8 6126.9 6200.7 6216.7 10000.8 10242.3 10254.4 10266.0 10279.5 8256.4 8357.7 9662.7 10140.8 10171.7 7010.2 7332.3 7509.6 7582.2 7705.8 8407.6 8495.2 8528.6 8545.4 8569.9 7496.8 7578.4 7624.5 7682.7 7739.9 7387.1 7508.6 7522.4 7628.5 7690.5 7052.7 7163.0 7173.7 7175.9 7200.4 4972.4 5001.2 5106.9 5118.2 5133.7 5057.6 5073.9 5180.4 5195.7 5269.4 05:59:53 INFO - PROCESS | 1660 | 05:59:53 INFO - PROCESS | 1660 | [#4] prototype.html Cycles:180 Average:4320.25 Median:4725.38 stddev:1314.98 (27.8%) stddev-sans-first:1283.73 05:59:53 INFO - PROCESS | 1660 | Values: 308.8 314.1 320.8 324.0 325.3 5329.1 5507.3 5589.4 5648.4 5671.6 4439.9 4516.3 5126.5 5507.4 5557.8 3674.0 4116.9 4261.9 4267.4 4274.3 3441.3 3589.4 4298.3 4304.5 4312.9 3291.9 4144.5 4157.4 4174.2 4273.3 3824.4 3952.7 4154.7 4165.3 4195.5 3183.4 3595.7 3607.5 3622.1 3629.6 4699.4 5035.4 5362.1 5400.2 5424.8 3952.9 4801.4 4805.8 4811.6 4811.8 5838.2 5885.6 5915.9 5965.6 5988.1 3207.2 3832.6 3853.4 3902.9 3961.5 5607.8 6019.8 6215.0 6216.7 6225.5 3050.5 3323.3 4010.0 4016.7 4032.3 3646.7 3912.6 4038.0 4044.7 4055.2 4331.6 4450.9 4735.0 4757.3 4807.8 5579.4 5920.7 5951.0 5987.3 6005.9 1120.7 1306.2 1361.3 1367.9 1387.4 899.9 908.1 933.7 933.7 934.4 4285.5 4949.9 5429.2 5483.0 5538.7 4605.4 4906.0 4946.6 5988.5 6094.5 5162.8 5417.7 5477.9 5517.9 5574.5 2240.8 2686.8 2690.6 2723.9 2732.1 4081.2 4416.8 4892.3 5125.0 5361.0 4779.9 4856.5 4959.3 5198.5 5320.0 4335.7 4732.9 4834.3 5248.5 5276.6 4227.6 4762.4 5095.5 5152.1 5215.2 4197.4 4286.6 4350.1 4617.1 4991.6 4394.3 5140.6 5156.8 5213.2 5275.5 3231.9 3511.6 3541.5 3541.8 3554.9 4536.0 4834.7 4841.6 5155.3 5178.4 3878.0 4619.8 4996.5 5047.4 5055.3 4330.3 4717.8 4785.3 4791.5 4999.4 4607.9 4629.8 4894.2 4895.8 4910.8 3864.8 4012.8 4316.0 4333.5 4962.4 4049.1 4311.5 4483.8 4835.0 4891.0 05:59:53 INFO - PROCESS | 1660 | 05:59:53 INFO - PROCESS | 1660 | [#5] yui.html Cycles:180 Average:25433.32 Median:3609.60 stddev:40326.71 (1117.2%) stddev-sans-first:40397.09 05:59:53 INFO - PROCESS | 1660 | Values: 704.3 731.8 790.0 801.3 820.2 15140.0 15536.0 15684.2 15882.8 15962.6 14095.3 14331.9 14429.9 14458.3 14474.3 702.3 853.6 861.6 881.1 885.2 860.4 870.1 874.6 875.6 877.9 906.6 926.4 928.5 929.7 931.0 893.7 894.6 903.3 920.0 923.0 663.1 664.8 685.6 687.5 687.7 14329.4 14918.9 14949.5 14959.3 14994.6 14749.6 14781.2 14853.5 14912.3 14922.7 10165.7 11968.0 12091.2 12376.4 12536.5 3911.5 3957.6 3979.7 4100.0 4109.1 119192.2 120478.4 120689.0 120914.4 123122.8 11268.3 11296.6 11319.5 11344.4 11483.4 23010.9 23228.2 23251.7 23324.2 23341.2 7266.7 8152.6 8266.4 8392.8 8584.5 4539.0 4558.1 4571.5 4593.5 4599.2 3139.0 3553.1 3666.1 3670.3 3670.5 615.5 659.4 663.1 669.3 672.9 674.5 676.2 712.3 722.8 728.5 7585.7 7667.4 8316.9 9559.6 9812.9 601.7 662.0 662.2 668.8 708.5 4459.2 4703.2 4742.1 4779.2 4868.0 124562.0 147804.3 149883.0 151643.0 157648.0 117159.0 133058.0 134572.0 134586.0 137277.0 104868.5 112958.4 114648.0 115505.0 115896.4 8679.2 9197.5 9294.2 9324.5 9449.6 51046.7 63094.4 63483.0 64119.0 65053.7 83558.7 88114.0 91368.0 91595.5 96642.5 13521.6 14079.1 14153.3 14180.7 14501.9 15773.2 15832.3 15915.4 16120.3 16295.5 14196.4 14716.0 14827.3 15266.9 15388.7 13825.4 14576.4 14683.7 14976.8 15320.4 14505.0 14690.5 14811.3 14854.3 14962.7 10932.7 11499.1 11681.5 11722.9 12285.8 9226.8 10437.4 10978.3 11761.3 11830.3 05:59:53 INFO - PROCESS | 1660 | -------- Summary: end -------- 05:59:53 INFO - PROCESS | 1660 | 05:59:53 INFO - TEST-INFO | 1660: exit 0 05:59:53 INFO - TEST-OK | dromaeo_css | took 1113605ms 05:59:53 INFO - TEST-START | kraken 05:59:53 INFO - operating with platform_type : mac_ 05:59:53 INFO - Initialising browser for kraken test... 05:59:53 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/tmpCNkgLB/profile http://localhost:49273/getInfo.html) 05:59:55 INFO - PROCESS | 1670 | JavaScript error: , line 0: uncaught exception: undefined 05:59:56 INFO - PROCESS | 1670 | __metrics Screen width/height:1600/1200 05:59:56 INFO - PROCESS | 1670 | colorDepth:24 05:59:56 INFO - PROCESS | 1670 | Browser inner width/height: 1024/689 05:59:56 INFO - PROCESS | 1670 | __metrics 05:59:56 ERROR - PROCESS | 1670 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2968: TypeError: this.tabs is undefined 05:59:57 INFO - TEST-INFO | 1670: exit 0 05:59:57 INFO - Browser initialized. 05:59:57 INFO - Running cycle 1/1 for kraken test... 05:59:57 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/tmpCNkgLB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:00:09 INFO - PROCESS | 1672 | RSS: Main: 255844352 06:00:09 INFO - PROCESS | 1672 | 06:00:12 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection) 06:00:13 INFO - PROCESS | 1672 | RSS: Main: 268992512 06:00:13 INFO - PROCESS | 1672 | 06:00:15 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-dft) 06:00:16 INFO - PROCESS | 1672 | RSS: Main: 276783104 06:00:16 INFO - PROCESS | 1672 | 06:00:19 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-fft) 06:00:20 INFO - PROCESS | 1672 | RSS: Main: 280408064 06:00:20 INFO - PROCESS | 1672 | 06:00:22 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator) 06:00:22 INFO - PROCESS | 1672 | RSS: Main: 281784320 06:00:22 INFO - PROCESS | 1672 | 06:00:24 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:00:25 INFO - PROCESS | 1672 | RSS: Main: 282533888 06:00:25 INFO - PROCESS | 1672 | 06:00:28 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom) 06:00:29 INFO - PROCESS | 1672 | RSS: Main: 281657344 06:00:29 INFO - PROCESS | 1672 | 06:00:31 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate) 06:00:32 INFO - PROCESS | 1672 | RSS: Main: 283844608 06:00:32 INFO - PROCESS | 1672 | 06:00:35 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial) 06:00:36 INFO - PROCESS | 1672 | RSS: Main: 284303360 06:00:36 INFO - PROCESS | 1672 | 06:00:37 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:00:38 INFO - PROCESS | 1672 | RSS: Main: 283762688 06:00:38 INFO - PROCESS | 1672 | 06:00:40 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:00:41 INFO - PROCESS | 1672 | RSS: Main: 283926528 06:00:41 INFO - PROCESS | 1672 | 06:00:43 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:00:43 INFO - PROCESS | 1672 | RSS: Main: 284274688 06:00:43 INFO - PROCESS | 1672 | 06:00:46 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:00:47 INFO - PROCESS | 1672 | RSS: Main: 284536832 06:00:47 INFO - PROCESS | 1672 | 06:00:50 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:00:50 INFO - PROCESS | 1672 | RSS: Main: 284536832 06:00:50 INFO - PROCESS | 1672 | 06:00:52 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49273/tests/kraken/driver.html?testName=ai-astar) 06:00:52 INFO - PROCESS | 1672 | RSS: Main: 285683712 06:00:52 INFO - PROCESS | 1672 | 06:00:52 INFO - PROCESS | 1672 | __start_tp_report 06:00:52 INFO - PROCESS | 1672 | _x_x_mozilla_page_load 06:00:52 INFO - PROCESS | 1672 | _x_x_mozilla_page_load_details 06:00:52 INFO - PROCESS | 1672 | |i|pagename|runs| 06:00:52 INFO - PROCESS | 1672 | |0;ai-astar;103;117;107;118;117;113;107;115;117;116 06:00:52 INFO - PROCESS | 1672 | |1;audio-beat-detection;145;108;133;154;109;129;130;112;156;108 06:00:52 INFO - PROCESS | 1672 | |2;audio-dft;192;196;197;197;206;238;269;195;195;200 06:00:52 INFO - PROCESS | 1672 | |3;audio-fft;70;76;70;108;61;71;84;86;148;60 06:00:52 INFO - PROCESS | 1672 | |4;audio-oscillator;69;74;134;100;73;71;142;102;60;132 06:00:52 INFO - PROCESS | 1672 | |5;imaging-gaussian-blur;91;87;89;89;88;86;89;86;89;85 06:00:52 INFO - PROCESS | 1672 | |6;imaging-darkroom;95;96;95;97;97;95;106;98;95;105 06:00:52 INFO - PROCESS | 1672 | |7;imaging-desaturate;152;77;151;141;92;95;96;91;92;89 06:00:52 INFO - PROCESS | 1672 | |8;json-parse-financial;56;56;57;56;64;51;54;57;56;57 06:00:52 INFO - PROCESS | 1672 | |9;json-stringify-tinderbox;81;102;71;77;73;75;83;71;70;70 06:00:52 INFO - PROCESS | 1672 | |10;stanford-crypto-aes;82;87;88;87;84;83;79;75;75;72 06:00:52 INFO - PROCESS | 1672 | |11;stanford-crypto-ccm;135;137;124;155;166;157;132;168;194;144 06:00:52 INFO - PROCESS | 1672 | |12;stanford-crypto-pbkdf2;167;168;166;186;178;168;169;168;164;164 06:00:52 INFO - PROCESS | 1672 | |13;stanford-crypto-sha256-iterative;67;68;67;67;74;77;87;87;70;58 06:00:52 INFO - PROCESS | 1672 | __end_tp_report 06:00:52 INFO - PROCESS | 1672 | __start_cc_report 06:00:52 INFO - PROCESS | 1672 | _x_x_mozilla_cycle_collect,1140 06:00:52 INFO - PROCESS | 1672 | __end_cc_report 06:00:52 INFO - PROCESS | 1672 | __startTimestamp1469451652959__endTimestamp 06:00:52 INFO - PROCESS | 1672 | 06:00:52 INFO - PROCESS | 1672 | ------- Summary: start ------- 06:00:52 INFO - PROCESS | 1672 | Number of tests: 14 06:00:52 INFO - PROCESS | 1672 | 06:00:52 INFO - PROCESS | 1672 | [#0] ai-astar Cycles:10 Average:113.00 Median:116.50 stddev:5.35 (4.6%) stddev-sans-first:4.28 06:00:52 INFO - PROCESS | 1672 | Values: 103.0 117.0 107.0 118.0 117.0 113.0 107.0 115.0 117.0 116.0 06:00:52 INFO - PROCESS | 1672 | 06:00:52 INFO - PROCESS | 1672 | [#1] audio-beat-detection Cycles:10 Average:128.40 Median:131.50 stddev:18.84 (14.3%) stddev-sans-first:19.00 06:00:52 INFO - PROCESS | 1672 | Values: 145.0 108.0 133.0 154.0 109.0 129.0 130.0 112.0 156.0 108.0 06:00:52 INFO - PROCESS | 1672 | 06:00:52 INFO - PROCESS | 1672 | [#2] audio-dft Cycles:10 Average:208.50 Median:198.50 stddev:25.09 (12.6%) stddev-sans-first:25.89 06:00:52 INFO - PROCESS | 1672 | Values: 192.0 196.0 197.0 197.0 206.0 238.0 269.0 195.0 195.0 200.0 06:00:52 INFO - PROCESS | 1672 | 06:00:52 INFO - PROCESS | 1672 | [#3] audio-fft Cycles:10 Average:83.40 Median:70.50 stddev:26.67 (37.8%) stddev-sans-first:27.85 06:00:52 INFO - PROCESS | 1672 | Values: 70.0 76.0 70.0 108.0 61.0 71.0 84.0 86.0 148.0 60.0 06:00:52 INFO - PROCESS | 1672 | 06:00:52 INFO - PROCESS | 1672 | [#4] audio-oscillator Cycles:10 Average:95.70 Median:64.50 stddev:30.86 (47.8%) stddev-sans-first:31.18 06:00:52 INFO - PROCESS | 1672 | Values: 69.0 74.0 134.0 100.0 73.0 71.0 142.0 102.0 60.0 132.0 06:00:52 INFO - PROCESS | 1672 | 06:00:52 INFO - PROCESS | 1672 | [#5] imaging-gaussian-blur Cycles:10 Average:87.90 Median:89.00 stddev:1.85 (2.1%) stddev-sans-first:1.59 06:00:52 INFO - PROCESS | 1672 | Values: 91.0 87.0 89.0 89.0 88.0 86.0 89.0 86.0 89.0 85.0 06:00:52 INFO - PROCESS | 1672 | 06:00:52 INFO - PROCESS | 1672 | [#6] imaging-darkroom Cycles:10 Average:97.90 Median:95.50 stddev:4.15 (4.3%) stddev-sans-first:4.27 06:00:52 INFO - PROCESS | 1672 | Values: 95.0 96.0 95.0 97.0 97.0 95.0 106.0 98.0 95.0 105.0 06:00:52 INFO - PROCESS | 1672 | 06:00:52 INFO - PROCESS | 1672 | [#7] imaging-desaturate Cycles:10 Average:107.60 Median:91.50 stddev:28.50 (31.1%) stddev-sans-first:25.29 06:00:52 INFO - PROCESS | 1672 | Values: 152.0 77.0 151.0 141.0 92.0 95.0 96.0 91.0 92.0 89.0 06:00:52 INFO - PROCESS | 1672 | 06:00:52 INFO - PROCESS | 1672 | [#8] json-parse-financial Cycles:10 Average:56.40 Median:56.50 stddev:3.24 (5.7%) stddev-sans-first:3.43 06:00:52 INFO - PROCESS | 1672 | Values: 56.0 56.0 57.0 56.0 64.0 51.0 54.0 57.0 56.0 57.0 06:00:52 INFO - PROCESS | 1672 | 06:00:52 INFO - PROCESS | 1672 | [#9] json-stringify-tinderbox Cycles:10 Average:77.30 Median:74.00 stddev:9.81 (13.3%) stddev-sans-first:10.31 06:00:52 INFO - PROCESS | 1672 | Values: 81.0 102.0 71.0 77.0 73.0 75.0 83.0 71.0 70.0 70.0 06:00:52 INFO - PROCESS | 1672 | 06:00:52 INFO - PROCESS | 1672 | [#10] stanford-crypto-aes Cycles:10 Average:81.20 Median:83.50 stddev:5.69 (6.8%) stddev-sans-first:6.03 06:00:52 INFO - PROCESS | 1672 | Values: 82.0 87.0 88.0 87.0 84.0 83.0 79.0 75.0 75.0 72.0 06:00:52 INFO - PROCESS | 1672 | 06:00:52 INFO - PROCESS | 1672 | [#11] stanford-crypto-ccm Cycles:10 Average:151.20 Median:156.00 stddev:21.10 (13.5%) stddev-sans-first:21.55 06:00:52 INFO - PROCESS | 1672 | Values: 135.0 137.0 124.0 155.0 166.0 157.0 132.0 168.0 194.0 144.0 06:00:52 INFO - PROCESS | 1672 | 06:00:52 INFO - PROCESS | 1672 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.80 Median:168.00 stddev:6.91 (4.1%) stddev-sans-first:7.25 06:00:52 INFO - PROCESS | 1672 | Values: 167.0 168.0 166.0 186.0 178.0 168.0 169.0 168.0 164.0 164.0 06:00:52 INFO - PROCESS | 1672 | 06:00:52 INFO - PROCESS | 1672 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.20 Median:72.00 stddev:9.25 (12.8%) stddev-sans-first:9.61 06:00:52 INFO - PROCESS | 1672 | Values: 67.0 68.0 67.0 67.0 74.0 77.0 87.0 87.0 70.0 58.0 06:00:52 INFO - PROCESS | 1672 | -------- Summary: end -------- 06:00:52 INFO - PROCESS | 1672 | 06:00:53 INFO - TEST-INFO | 1672: exit 0 06:00:53 INFO - TEST-OK | kraken | took 60009ms 06:00:53 INFO - SUITE-END | took 1173s 06:00:54 INFO - Completed test suite (00:19:33) 06:00:54 INFO - javascript benchmark 06:00:54 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7385.084016487181, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3407.8807241746545, 3432.605250396205, 3440.965590344099, 3445.7243420526343, 3445.844936977836, 5838.474537291643, 5844.415558444148, 5892.499137573312, 5893.145493903381, 5921.23024006879, 4801.423829140499, 5687.544996400288, 5807.332156801969, 5808.1578171165265, 5836.6789826559525, 5226.111561034624, 5259.342582177228, 5340.706261155635, 5366.490183432567, 5396.622236443451, 5307.2569840222395, 5471.917921231185, 5481.725913704318, 5485.259489968843, 5485.4788795064405, 4302.505211900609, 4771.0, 4899.608031357483, 5305.0, 5368.275282836806, 5183.403908550527, 5244.396894357122, 5258.07983602868, 5267.104592219332, 5310.92033619496, 5228.581713462914, 5256.842294731164, 5287.296728715708, 5295.073362161606, 5305.151175811851, 5763.394843541451, 5815.15680226369, 5905.6751878646655, 5911.645301281937, 5940.9108863366655, 6174.691265436711, 6191.938080619181, 6234.937650623481, 6249.218847644044, 6250.624962502219, 5286.5242128208265, 6262.968685156545, 6286.119943207955, 6306.87386252277, 6307.621542707452, 5395.73021348931, 5611.354694210137, 5837.357890632027, 6004.1984618904735, 6048.818535443944, 6391.936080639227, 6397.840053998687, 6408.58344207625, 6410.358964103552, 6418.711157997901, 6278.937210627927, 6291.559590828598, 6307.526935479766, 6316.9368306317265, 6341.524385671093, 6188.9690551546955, 6225.0, 6228.688565571794, 6285.497160227263, 6287.465565426898, 6124.228368151176, 6162.0, 6222.782202622887, 6269.96865015672, 6282.780102696475, 6236.371348020205, 6286.088517164985, 6290.528210384148, 6290.779822706183, 6293.118963345135, 4957.281194226817, 5038.949610503848, 5075.264086707481, 5092.10888094582, 5098.133317336062, 4240.724352917051, 4498.415206023196, 4518.548145185455, 4585.679002469795, 4616.422947131608, 5879.2944846618675, 5995.670238136944, 6057.545684073712, 6066.181065556182, 6079.0, 6205.6586887720705, 6219.657918814509, 6292.905606415908, 6299.370062993756, 6305.117283580303, 5951.880962380815, 6008.549358798107, 6061.3029501607, 6082.057281121433, 6102.206713127265, 5534.833954981276, 5555.611107222537, 5565.777368905198, 5585.497305242548, 5601.5238704709745, 6185.41238582334, 6292.811215663537, 6438.227412710505, 6444.581102228341, 6444.709988050549, 6203.410675985774, 6245.718942647501, 6296.307406185224, 6300.464460520907, 6309.684515774285, 6387.936120638734, 6428.967855160694, 6446.387593178641, 6447.323031081669, 6492.772752953624, 6143.082036033027, 6147.538934580014, 6300.653464059521, 6300.71646775887, 6320.462760665395, 6212.81361559154, 6248.68756562161, 6276.309605943434, 6289.874202516015, 6291.213598300213, 5919.31927828288, 6153.3538978408415, 6162.907556386568, 6179.536534760001, 6205.379462053758, 6089.2997305310455, 6146.893559159215, 6163.0, 6163.876722465615, 6190.0, 6039.607425517327, 6057.121717350871, 6111.205543279345, 6134.650895842656, 6161.784337548164, 6163.106349579196, 6217.678713294217, 6229.813105606839, 6267.780627678009, 6292.653904035322, 5990.311114221746, 6041.577089603861, 6131.448169664752, 6135.509159267337, 6158.538109641705], "value": 5783.849238384693, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [498.42929845327103, 521.746952727927, 524.5174439515645, 543.5542854859016, 574.8218052403757, 36856.26287474249, 38158.42762358563, 41036.58963410372, 41088.97277568059, 41168.38247426287, 38291.80854095726, 42017.0, 42285.0, 42409.15181696364, 42524.0, 9509.904900950989, 9559.856602150974, 9651.372660777028, 9704.514774261293, 9723.513824308791, 8178.836423271531, 9572.521373931275, 9650.276229282797, 9693.806123877519, 9708.61165553377, 9655.806883862319, 9693.321467497279, 9716.271279654018, 9717.076877696607, 9754.902450975505, 9663.033696630317, 9676.854847177263, 9706.563204655808, 9708.708738737778, 9717.854232186522, 8606.88110545625, 9158.313126515539, 9533.570989305497, 9594.13652771247, 9615.134637882555, 39791.80624581267, 39932.600673993176, 40061.59938400608, 40241.195176096604, 40562.79718601388, 3123.875044998197, 3285.2772390074147, 3329.3840639481773, 3456.688897999167, 3530.75284730069, 28868.989585364623, 30273.848630756926, 30367.848160759277, 31011.06966791, 31519.212019699462, 3118.70372314632, 3132.295233572451, 3147.244661281276, 3149.2756665966954, 3231.5152727091127, 458053.0, 458581.0, 460625.0, 460918.0, 464268.0, 32690.019299421056, 33007.3398532028, 33038.50442243368, 33046.50430243548, 33114.50328245078, 34094.65905340965, 34226.82886585551, 34352.82823585916, 35333.29333413317, 35527.11182220465, 16495.927764695258, 16877.32490700383, 16877.746833797504, 16943.98336099838, 17052.976821390756, 12895.48418063267, 12983.675408114872, 13087.607371778862, 13089.607311780663, 13091.672708182181, 16104.919475402548, 19434.319798807257, 19442.319518817058, 19499.220031198878, 19521.31675391383, 6716.697748601325, 8186.590670466453, 8192.624138999623, 8220.917790822015, 8269.62786674601, 750.6284389227368, 967.8790151231096, 980.8332583460686, 1055.883852776194, 1057.952626899364, 30275.697243027727, 30732.692673072983, 30909.227269318446, 31148.0, 31684.20789480235, 1082.6697857153645, 1095.367901826273, 1120.1486869978876, 1150.3328069719544, 1176.8823117688162, 15545.0, 16027.278772455034, 16084.43704470338, 16196.352145914032, 16464.588385290223, 673053.0, 682569.0, 685515.0, 685648.0, 688209.0, 58992.0, 59404.0, 60058.69970650206, 60125.398746012856, 60160.0, 1293.6442478318538, 1392.4569417927016, 1395.7278330725412, 1432.4556668466125, 1433.33349992252, 29714.0, 30702.925397610976, 31273.37453250968, 31912.840435797672, 32392.83803580967, 52597.0, 57631.71184144053, 57875.0, 58266.7086664564, 58505.0, 596243.0, 596485.0, 599818.0, 600671.0, 603007.0, 38232.853014409615, 43566.34650480182, 44233.11533769242, 44368.33447498226, 45222.86942826456, 44747.55252447434, 48210.758946205046, 49012.754936225094, 49261.261081083096, 49644.25533617071, 37196.0, 37462.8126859364, 40537.0, 40796.79601601973, 42204.0, 40494.1901161981, 40769.0, 41352.79323603363, 41797.79101104475, 42199.57800422079, 33554.16114597155, 38965.610343896195, 39585.2082958345, 39633.40549891812, 39730.205395891346, 33741.66258337385, 35833.64166358407, 35977.0, 36369.454458183674, 37168.628313716516, 23030.769692302863, 25998.870005650606, 26028.86985565136, 26560.601590975763, 26576.936922523655], "value": 19924.879158356172, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [354.6276409769742, 370.08773373634006, 383.46506623260564, 394.6763653803882, 404.01017507107576, 15686.0, 19281.035948202585, 19982.400527984184, 21013.789862101356, 21289.68065479019, 17969.82030179698, 21190.0, 21197.576048479023, 21847.126114955383, 21952.0, 5942.6137301075505, 5985.34161242263, 6008.8497787555225, 6066.0, 6134.325224225332, 5965.373635768247, 6005.099235114724, 6016.8495787605225, 6023.126646636235, 6080.0, 5874.97062514689, 5976.462118409342, 5979.581429299951, 6032.426919442646, 6045.0, 5846.473817356458, 5909.08409196573, 5916.497097746696, 5986.401359864022, 6029.517638588903, 5155.896882062375, 5201.063808514466, 5253.0, 5263.31576895005, 5269.473052694738, 26605.201843944713, 26841.865790671116, 26947.730522694717, 27030.053948111723, 27209.591856122173, 2375.5248950210025, 2462.4213309872166, 2490.713567939692, 2681.6379788728664, 3005.2787331040395, 12789.0, 16102.677946441005, 16257.268422920997, 16283.837161628351, 16479.423220187236, 1808.2586139682799, 1868.6449574580786, 1887.584731359099, 1909.6944488881852, 1915.4062240705287, 122695.77304226933, 123547.382263089, 124877.0, 126336.36831815874, 127116.99999999907, 10686.786264274671, 10722.249442539103, 11261.155413344031, 11268.774624507463, 11396.316221026598, 11360.886391136148, 12016.759664806656, 12145.271283723005, 12743.745125097632, 13754.174749514861, 7406.333429991327, 8529.232369086812, 8675.783105422288, 8865.68970086044, 8983.338082454768, 7505.949167116608, 8958.283337332998, 8988.235999939947, 9009.324300677476, 9199.678011269574, 5680.460356266148, 6653.63405012729, 6668.499862510234, 6710.597364158166, 6768.357006084414, 1560.8595226429675, 1901.5531350132708, 1903.6763750162506, 1905.3902751119786, 1907.7329173915664, 908.9547020925855, 918.3938600523622, 978.2858513285199, 1007.2244371833647, 1013.3463915774431, 21824.345269641937, 22923.31230063101, 23054.538909222058, 23108.88445557795, 23139.958701858457, 983.695054533097, 1012.711377257478, 1016.11090295991, 1028.922830787694, 1058.4284486377423, 11512.712182195344, 12054.638360849189, 12064.758704825854, 12186.08604354677, 12204.267743935392, 583050.0, 590555.0, 591118.0, 594911.0, 596664.0, 91877.0, 120577.0, 120586.79413205931, 129366.35316823356, 132634.33682831717, 967.67099186277, 980.9901900981041, 1012.8025035118228, 1047.4762618690654, 1051.138066785244, 16916.069616170735, 17116.65766684684, 20162.697559536326, 20208.79791202069, 20523.076461559565, 39450.80274598609, 52437.0, 53487.73256133695, 53879.461205387444, 58575.53561160835, 65504.34495654982, 77087.0, 84012.15987840043, 84865.0, 85023.14976850152, 7958.0, 8107.986501687289, 8274.544900030556, 8313.709020184264, 8319.334453243606, 10735.516901739284, 11386.658400248005, 12950.093493455744, 13277.136986095873, 13408.597742067754, 11045.282056666612, 11444.828327574927, 11518.769624607628, 11703.356315402734, 11825.586104486301, 9849.310548261554, 11221.326720396803, 11538.538458461568, 11552.826707599224, 11925.76148477043, 10004.749881252736, 11519.30884146954, 11700.531978721096, 11988.280703157838, 12213.0, 7206.6396680164735, 7407.666655000646, 7610.847783044197, 7646.808829779077, 8095.83808323842, 10215.182785377301, 11674.416279185836, 11825.822612660644, 11903.761924761284, 11927.105467090176], "value": 9875.360212190355, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1580.1072394097337, 1610.8630766384856, 1693.5766058485378, 1840.7514985476957, 1903.5621806984402, 6669.666516674171, 8482.872756908644, 8494.150584941519, 8887.111288871118, 8937.240334571561, 8631.00743414506, 8784.692535761249, 8795.78010549735, 8811.383203175781, 8811.86782198266, 2363.314638754759, 2395.688560487134, 2404.230646193219, 2416.1905761569897, 2426.502566973766, 2375.061850569024, 2377.4294169399304, 2413.806895448369, 2420.745821688724, 2431.659567660529, 2372.7627237276306, 2385.153270588942, 2397.7961873240797, 2398.880055997202, 2419.1653879411574, 2382.1424287256573, 2390.529824158139, 2401.987990060047, 2403.4712363280137, 2408.9397765055837, 2364.692589963294, 2378.131981826638, 2388.796952259043, 2402.5915594349003, 2407.2898494944034, 6691.230508491489, 8205.95897020511, 8938.910610893938, 9003.369764116514, 9003.684871029547, 4072.5723799000975, 4608.953910460886, 5329.800794821134, 5408.513233808976, 5414.83755487336, 3012.5631783391505, 3508.4737289406794, 3641.8543258269638, 3705.3330400527893, 3707.925841483155, 941.7457286532598, 967.7532229281559, 978.7993461340479, 985.6895078050463, 986.4623780039896, 11420.71448213793, 11443.771124577463, 11539.0, 11552.191346605741, 11579.015783658399, 4754.833580824724, 4760.237666724221, 4785.856424307276, 4841.830535931225, 4870.707757534559, 9697.224222062217, 9866.80266394668, 9883.209343252522, 9894.60421583143, 9900.0, 5337.786488540415, 5377.139657654757, 5396.217548455452, 5398.4871437213405, 5406.729663516809, 4022.577629348935, 4329.393884856123, 4429.75636339998, 4692.272697731857, 4916.778744956486, 3946.3685810270795, 3962.980185099056, 4003.0, 4055.7769322687536, 4113.9794301028305, 1290.5612091888802, 1305.3081866610712, 1308.1693124865778, 1316.427354100969, 1317.9209247445183, 955.9378640388353, 961.5288508630721, 965.4931161140457, 968.7965527239359, 1000.3047881722279, 5965.6718880461995, 5989.401059893976, 6132.233470816148, 6152.446279834837, 6174.691265436711, 447.4116536754158, 447.75597299471866, 452.2447512653876, 455.8883073646911, 458.49794475049765, 4032.1734044520804, 4044.676425885922, 4088.0, 4167.645750111214, 4179.289520781414, 10398.58405663765, 10690.62582809598, 10813.18901082422, 10828.72928176786, 10833.7541182765, 10704.78590428187, 10712.46437678113, 10751.139908807276, 10783.568657253621, 10859.185561082792, 7343.192248852622, 7706.344960678293, 7707.922920770833, 8270.131636178236, 8369.246767790928, 5963.314218864886, 6085.756569737161, 6126.938730612636, 6200.689965501797, 6216.658083805434, 10000.799984000425, 10242.334248273839, 10254.436006019741, 10266.024727650862, 10279.537420815932, 8256.380771442045, 8357.707480238163, 9662.71011869645, 10140.847887281549, 10171.694849154537, 7010.158780946319, 7332.266773322838, 7509.6245187739305, 7582.203868593939, 7705.807354816175, 8407.621657025325, 8495.235428811437, 8528.59278219087, 8545.444546104483, 8569.914300856912, 7496.812579685551, 7578.393728501596, 7624.466287359832, 7682.7311044113185, 7739.883901741366, 7387.1135463744695, 7508.58702771331, 7522.398208143226, 7628.54228746277, 7690.461667683208, 7052.682629281592, 7163.0, 7173.748918787817, 7175.8564828704175, 7200.423966082805, 4972.403311602631, 5001.224810154432, 5106.948930510647, 5118.181091025418, 5133.717645529532, 5057.595392368528, 5073.923891141562, 5180.430152683127, 5195.74021298926, 5269.420363759907], "value": 4519.836556279833, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [308.82705684816506, 314.10636738479025, 320.7930884579446, 323.95140728890647, 325.29086591231123, 5329.147336426163, 5507.311586051744, 5589.413111623282, 5648.373506319561, 5671.602987790851, 4439.866803995886, 4516.345129956155, 5126.487351264872, 5507.366652834925, 5557.805476808313, 3673.963260367389, 4116.9090191099385, 4261.936070958938, 4267.396887898395, 4274.29474136767, 3441.2773317603333, 3589.4436362363745, 4298.290782020963, 4304.548022457644, 4312.870613881589, 3291.901242962703, 4144.48193975753, 4157.397177409274, 4174.20690068886, 4273.273543497613, 3824.368979118442, 3952.664023558001, 4154.709170358061, 4165.27107756143, 4195.538490766014, 3183.4110689522513, 3595.694365978895, 3607.4769158472136, 3622.1487950331652, 3629.566321303097, 4699.365585645963, 5035.395752509686, 5362.088444964365, 5400.21696853954, 5424.810131645413, 3952.940705889414, 4801.399825021872, 4805.783739731721, 4811.566958973674, 4811.759412029384, 5838.222468881308, 5885.5585831062845, 5915.940840591582, 5965.583938504869, 5988.101784732325, 3207.182168547029, 3832.6358995895343, 3853.402722578005, 3902.9462045247624, 3961.524617045944, 5607.803726869581, 6019.789307374264, 6215.0, 6216.699821066234, 6225.501959843247, 3050.496668049747, 3323.2522682396752, 4010.0, 4016.658584020391, 4032.274190645653, 3646.744727869024, 3912.6478616924046, 4038.0, 4044.656204222674, 4055.249778790874, 4331.578053337827, 4450.908073205588, 4734.95265047352, 4757.26262429324, 4807.831725889577, 5579.358373787067, 5920.703964801743, 5950.970245148833, 5987.341392446828, 6005.918934591816, 1120.6862078618, 1306.2162702378496, 1361.3057340756088, 1367.9056754596481, 1387.3756809435592, 899.9244262360293, 908.14180599333, 933.6778811310087, 933.7245512573807, 934.3879758758025, 4285.46431696038, 4949.896294075075, 5429.203959960623, 5482.972585137049, 5538.667679939216, 4605.424321959755, 4905.975470122627, 4946.629002824802, 5988.461038506555, 6094.451499365078, 5162.767675454614, 5417.670401727295, 5477.863053423696, 5517.889642207214, 5574.470425309589, 2240.7759224077786, 2686.7775162301105, 2690.5829596412586, 2723.9319017024336, 2732.142143342546, 4081.245159517314, 4416.779161041935, 4892.315075889307, 5125.000624878113, 5361.0, 4779.904401912012, 4856.538628830255, 4959.3467494890065, 5198.532132108128, 5320.0, 4335.7181783184, 4732.929006064981, 4834.323194752737, 5248.501392367719, 5276.625518516759, 4227.619514243733, 4762.3570817939135, 5095.464976177448, 5152.1405619881325, 5215.243789650403, 4197.439561867198, 4286.635635970977, 4350.108227813227, 4617.122746678255, 4991.627302491844, 4394.318880573516, 5140.640155189101, 5156.793728250828, 5213.244079608511, 5275.472452754694, 3231.854566544464, 3511.613722490575, 3541.5395998520025, 3541.840617172285, 3554.928901422009, 4536.0, 4834.661573689808, 4841.564259216688, 5155.313950525392, 5178.430372658929, 3878.0, 4619.769011549476, 4996.528107343979, 5047.394312682502, 5055.317532133115, 4330.328799036154, 4717.834875779331, 4785.282207668738, 4791.47293797689, 4999.350084488993, 4607.917139472288, 4629.837955671535, 4894.213612031654, 4895.755212239445, 4910.8281210157475, 3864.845406183721, 4012.819423125908, 4315.956840431555, 4333.544977777288, 4962.429320628174, 4049.0687141958106, 4311.49933038843, 4483.775811209492, 4834.993477706853, 4891.046245981999], "value": 3920.7082428886397, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [704.2851505721693, 731.7841236835133, 789.992100078999, 801.2988634944426, 820.2371794231362, 15140.0, 15535.99016063955, 15684.215789210552, 15882.761758573617, 15962.600934976632, 14095.295235238249, 14331.92834035831, 14429.927850360733, 14458.277086145703, 14474.2762861857, 702.3257672634252, 853.5646820121736, 861.6165806216236, 881.1188811188811, 885.1723638398119, 860.3547339495378, 870.0559892516595, 874.5845723281454, 875.5840975536601, 877.9473231606092, 906.6282824042144, 926.3654396738256, 928.5310917986408, 929.7350255177275, 930.9534523273843, 893.7408151636017, 894.6287290774353, 903.2818908967354, 919.9908000919972, 923.0031565908803, 663.1081195791653, 664.7706541243264, 685.5578152091873, 687.4500399680286, 687.7077242172057, 14329.35517901697, 14918.925405373013, 14949.476768313165, 14959.252037398195, 14994.62513437162, 14749.557513274618, 14781.187034713086, 14853.48012819546, 14912.328945197492, 14922.70154596902, 10165.698599618054, 11968.0, 12091.21407108544, 12376.443060062318, 12536.498540058477, 3911.471951286568, 3957.604239576019, 3979.6597376026593, 4100.01599616093, 4109.116539943926, 119192.21211681832, 120478.39760801228, 120689.0, 120914.39542802317, 123122.76877231203, 11268.267562608404, 11296.6046188383, 11319.490622921989, 11344.376059316652, 11483.36841473727, 23010.884945575166, 23228.187013454448, 23251.651225231635, 23324.183653572043, 23341.18305859287, 7266.745663901844, 8152.572254907825, 8266.380021498411, 8392.790180245544, 8584.527850968258, 4538.9546104539195, 4558.133954548625, 4571.519990401027, 4593.494715581283, 4599.241125214302, 3138.952915706266, 3553.07620018797, 3666.1201311685004, 3670.339338918967, 3670.504481894963, 615.5260449453984, 659.3538332434241, 663.0750103605443, 669.2604671837647, 672.9461643068543, 674.4925092418441, 676.2358534855582, 712.2521352579771, 722.8301349182938, 728.5191773429617, 7585.658645360972, 7667.424943129287, 8316.87722157513, 9559.569819358145, 9812.852807207897, 601.6901295832649, 661.995315415451, 662.2020465339255, 668.7859884836903, 708.4615692074061, 4459.197344478025, 4703.176944034849, 4742.074620420578, 4779.187538118528, 4867.95132048675, 124562.0, 147804.26097869658, 149883.0, 151643.0, 157648.0, 117159.0, 133058.0, 134572.0, 134586.0, 137277.0, 104868.47565762122, 112958.43520782508, 114648.0, 115505.0, 115896.42051789687, 8679.218870301704, 9197.540122993958, 9294.163525282756, 9324.487153206368, 9449.574769135268, 51046.744766277414, 63094.369056308846, 63483.0, 64119.038214427754, 65053.67473162604, 83558.74661880198, 88114.0, 91368.0, 91595.54202228946, 96642.51678741562, 13521.59435216945, 14079.0848594841, 14153.292335383396, 14180.716385672435, 14501.854981450473, 15773.211339433212, 15832.28754706064, 15915.363385464452, 16120.274587643818, 16295.51113466598, 14196.36116374745, 14716.0, 14827.258637068318, 15266.923665381602, 15388.692226155637, 13825.377857996615, 14576.416943322149, 14683.706325873209, 14976.775348369565, 15320.38718451294, 14505.0, 14690.485832995793, 14811.25943702832, 14854.257287135384, 14962.700745985238, 10932.672019839418, 11499.137564682514, 11681.474333655176, 11722.94138529302, 12285.75428491443, 9226.769330766785, 10437.425941573287, 10978.341299522055, 11761.294322340687, 11830.290182589073], "value": 8043.238068090099, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1530.5, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 117.0, 107.0, 118.0, 117.0, 113.0, 107.0, 115.0, 117.0, 116.0], "value": 113.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 108.0, 133.0, 154.0, 109.0, 129.0, 130.0, 112.0, 156.0, 108.0], "value": 128.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [192.0, 196.0, 197.0, 197.0, 206.0, 238.0, 269.0, 195.0, 195.0, 200.0], "value": 208.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 76.0, 70.0, 108.0, 61.0, 71.0, 84.0, 86.0, 148.0, 60.0], "value": 83.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 74.0, 134.0, 100.0, 73.0, 71.0, 142.0, 102.0, 60.0, 132.0], "value": 95.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 87.0, 89.0, 89.0, 88.0, 86.0, 89.0, 86.0, 89.0, 85.0], "value": 87.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 97.0, 97.0, 95.0, 106.0, 98.0, 95.0, 105.0], "value": 97.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 77.0, 151.0, 141.0, 92.0, 95.0, 96.0, 91.0, 92.0, 89.0], "value": 107.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 57.0, 56.0, 64.0, 51.0, 54.0, 57.0, 56.0, 57.0], "value": 56.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 102.0, 71.0, 77.0, 73.0, 75.0, 83.0, 71.0, 70.0, 70.0], "value": 77.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 87.0, 88.0, 87.0, 84.0, 83.0, 79.0, 75.0, 75.0, 72.0], "value": 81.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 137.0, 124.0, 155.0, 166.0, 157.0, 132.0, 168.0, 194.0, 144.0], "value": 151.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 168.0, 166.0, 186.0, 178.0, 168.0, 169.0, 168.0, 164.0, 164.0], "value": 169.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 67.0, 67.0, 74.0, 77.0, 87.0, 87.0, 70.0, 58.0], "value": 72.2, "unit": "score"}]}]} 06:00:54 INFO - Return code: 0 06:00:54 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:00:54 ERROR - # TBPL SUCCESS # 06:00:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:00:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:00:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:00:54 INFO - 2016-07-25 06:00:54.293 screenresolution[1677:11126] starting screenresolution argv=screenresolution get 06:00:54 INFO - 2016-07-25 06:00:54.295 screenresolution[1677:11126] Display 0: 1600x1200x32@60 06:00:54 INFO - 2016-07-25 06:00:54.301 screenresolution[1678:11130] starting screenresolution argv=screenresolution list 06:00:54 INFO - Available Modes on Display 0 06:00:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:00:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:00:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:00:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:00:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:00:54 INFO - Intel Iris: 06:00:54 INFO - Chipset Model: Intel Iris 06:00:54 INFO - Type: GPU 06:00:54 INFO - Bus: Built-In 06:00:54 INFO - VRAM (Dynamic, Max): 1536 MB 06:00:54 INFO - Vendor: Intel (0x8086) 06:00:54 INFO - Device ID: 0x0a2e 06:00:54 INFO - Revision ID: 0x0009 06:00:54 INFO - Displays: 06:00:54 INFO - Display: 06:00:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:00:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:00:54 INFO - Display Serial Number: 1600x1200 60 06:00:54 INFO - Main Display: Yes 06:00:54 INFO - Mirror: Off 06:00:54 INFO - Online: Yes 06:00:54 INFO - Rotation: Supported 06:00:54 INFO - Return code: 0 06:00:54 INFO - Running post-action listener: _resource_record_post_action 06:00:54 INFO - [mozharness: 2016-07-25 13:00:54.536700Z] Finished run-tests step (success) 06:00:54 INFO - Running post-run listener: _resource_record_post_run 06:00:54 INFO - Total resource usage - Wall time: 1209s; CPU: 54.0%; Read bytes: 32934912; Write bytes: 710420992; Read time: 321; Write time: 1915 06:00:54 INFO - TinderboxPrint: CPU usage
54.5% 06:00:54 INFO - TinderboxPrint: I/O read bytes / time
32,934,912 / 321 06:00:54 INFO - TinderboxPrint: I/O write bytes / time
710,420,992 / 1,915 06:00:54 INFO - TinderboxPrint: CPU idle
2,202.3 (45.5%) 06:00:54 INFO - TinderboxPrint: CPU system
974.9 (20.2%) 06:00:54 INFO - TinderboxPrint: CPU user
1,660.5 (34.3%) 06:00:54 INFO - TinderboxPrint: Swap in / out
437,706,752 / 0 06:00:54 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 389192192; Write bytes: 382936576; Read time: 29459; Write time: 702 06:00:54 INFO - run-tests - Wall time: 1175s; CPU: 54.0%; Read bytes: 32005120; Write bytes: 299189248; Read time: 306; Write time: 1159 06:00:54 INFO - Running post-run listener: _upload_blobber_files 06:00:54 INFO - Blob upload gear active. 06:00:55 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:00:55 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:00:55 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:00:55 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:00:55 INFO - (blobuploader) - INFO - Open directory for files ... 06:00:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 06:00:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:00:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:00:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 06:00:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:00:56 INFO - (blobuploader) - INFO - Done attempting. 06:00:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 06:00:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:00:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:00:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 06:00:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:00:57 INFO - (blobuploader) - INFO - Done attempting. 06:00:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:00:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:00:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:01:00 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:01:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:01:00 INFO - (blobuploader) - INFO - Done attempting. 06:01:00 INFO - (blobuploader) - INFO - Iteration through files over. 06:01:00 INFO - Return code: 0 06:01:00 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:01:00 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:01:00 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/49fe0eb8ee0cb9230ed19725a34a1ce5a2f4fbfc5b4e101af3c592e572ec29283981ca989d716b830ffcfb1193092193cddf58f02a8553d10278279e12166ed1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c306539af0faad956e460d87ae09a6f4767b2eb0bf4c49442896a5cdf75e593b13c41526763e51100e3ea96b293c597afff7b7d0f7ec1629dc3d671ac5cb4a1d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4862ddf51930ede6c9be3951430e15e0e2c32df580d0d261db71b87ebbb71649ccf15ae369c5981e5dc80847733e26b34a3343f0e74bd25071274afe82324d1b"} 06:01:00 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:01:00 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:01:00 INFO - Contents: 06:01:00 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/49fe0eb8ee0cb9230ed19725a34a1ce5a2f4fbfc5b4e101af3c592e572ec29283981ca989d716b830ffcfb1193092193cddf58f02a8553d10278279e12166ed1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c306539af0faad956e460d87ae09a6f4767b2eb0bf4c49442896a5cdf75e593b13c41526763e51100e3ea96b293c597afff7b7d0f7ec1629dc3d671ac5cb4a1d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4862ddf51930ede6c9be3951430e15e0e2c32df580d0d261db71b87ebbb71649ccf15ae369c5981e5dc80847733e26b34a3343f0e74bd25071274afe82324d1b"} 06:01:00 INFO - Running post-run listener: copy_logs_to_upload_dir 06:01:00 INFO - Copying logs to upload dir... 06:01:00 INFO - mkdir: /builds/slave/test/build/upload/logs 06:01:00 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1300.320225 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 40 secs) (at 2016-07-25 06:01:00.867077) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-25 06:01:00.871114) ========= 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.K0ACA1f4dH/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.R5uuEFbPzI/Listeners TMPDIR=/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/49fe0eb8ee0cb9230ed19725a34a1ce5a2f4fbfc5b4e101af3c592e572ec29283981ca989d716b830ffcfb1193092193cddf58f02a8553d10278279e12166ed1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c306539af0faad956e460d87ae09a6f4767b2eb0bf4c49442896a5cdf75e593b13c41526763e51100e3ea96b293c597afff7b7d0f7ec1629dc3d671ac5cb4a1d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4862ddf51930ede6c9be3951430e15e0e2c32df580d0d261db71b87ebbb71649ccf15ae369c5981e5dc80847733e26b34a3343f0e74bd25071274afe82324d1b"} build_url:https://queue.taskcluster.net/v1/task/ZwzSrdUhRFy0zUlDHpekQA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011010 build_url: 'https://queue.taskcluster.net/v1/task/ZwzSrdUhRFy0zUlDHpekQA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/49fe0eb8ee0cb9230ed19725a34a1ce5a2f4fbfc5b4e101af3c592e572ec29283981ca989d716b830ffcfb1193092193cddf58f02a8553d10278279e12166ed1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c306539af0faad956e460d87ae09a6f4767b2eb0bf4c49442896a5cdf75e593b13c41526763e51100e3ea96b293c597afff7b7d0f7ec1629dc3d671ac5cb4a1d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4862ddf51930ede6c9be3951430e15e0e2c32df580d0d261db71b87ebbb71649ccf15ae369c5981e5dc80847733e26b34a3343f0e74bd25071274afe82324d1b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-25 06:01:00.903242) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-25 06:01:00.903608) ========= 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.K0ACA1f4dH/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.R5uuEFbPzI/Listeners TMPDIR=/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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.004885 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-25 06:01:00.952877) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-25 06:01:00.953206) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-25 06:01:00.953539) ========= ========= Total master_lag: 0.18 =========