builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0109 starttime: 1488053193.39 results: success (0) buildid: 20170225103609 builduid: 4be7802b711a4ef7b7f1a121defeca41 revision: a08ec245fa24d573fc99e81210ecc09de734cdd3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-25 12:06:33.388350) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-25 12:06:33.388764) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-25 12:06:33.389023) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-25 12:06:33.475185) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-25 12:06:33.475495) ========= 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.Uo9Ael4neV/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.t5VkMu3fdo/Listeners TMPDIR=/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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 --2017-02-25 12:06:33-- 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: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 13.4M=0.001s 2017-02-25 12:06:33 (13.4 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.475100 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-25 12:06:33.974874) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-25 12:06:33.975169) ========= 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.Uo9Ael4neV/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.t5VkMu3fdo/Listeners TMPDIR=/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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.027303 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-25 12:06:34.024286) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-25 12:06:34.024592) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev a08ec245fa24d573fc99e81210ecc09de734cdd3 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev a08ec245fa24d573fc99e81210ecc09de734cdd3 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Uo9Ael4neV/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.t5VkMu3fdo/Listeners TMPDIR=/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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 2017-02-25 12:06:34,138 truncating revision to first 12 chars 2017-02-25 12:06:34,138 Setting DEBUG logging. 2017-02-25 12:06:34,138 attempt 1/10 2017-02-25 12:06:34,138 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/a08ec245fa24?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-25 12:06:34,760 unpacking tar archive at: mozilla-central-a08ec245fa24/testing/mozharness/ program finished with exit code 0 elapsedTime=0.907004 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-25 12:06:34.954453) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-25 12:06:34.954758) ========= script_repo_revision: a08ec245fa24d573fc99e81210ecc09de734cdd3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-25 12:06:34.955130) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-25 12:06:34.955389) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-25 12:06:34.976375) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2017-02-25 12:06:34.976683) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Uo9Ael4neV/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.t5VkMu3fdo/Listeners TMPDIR=/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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 12:06:35 INFO - MultiFileLogger online at 20170225 12:06:35 in /builds/slave/test 12:06:35 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 12:06:35 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:06:35 INFO - {'append_to_log': False, 12:06:35 INFO - 'base_work_dir': '/builds/slave/test', 12:06:35 INFO - 'blob_upload_branch': 'Firefox', 12:06:35 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:06:35 INFO - 'branch': 'Firefox', 12:06:35 INFO - 'buildbot_json_path': 'buildprops.json', 12:06:35 INFO - 'config_files': ('talos/mac_config.py',), 12:06:35 INFO - 'default_actions': ('clobber', 12:06:35 INFO - 'read-buildbot-config', 12:06:35 INFO - 'download-and-extract', 12:06:35 INFO - 'populate-webroot', 12:06:35 INFO - 'create-virtualenv', 12:06:35 INFO - 'install', 12:06:35 INFO - 'run-tests'), 12:06:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:06:35 INFO - 'download_minidump_stackwalk': True, 12:06:35 INFO - 'download_symbols': 'ondemand', 12:06:35 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:06:35 INFO - 'tooltool.py': '/tools/tooltool.py', 12:06:35 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:06:35 INFO - '/tools/misc-python/virtualenv.py')}, 12:06:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:06:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:06:35 INFO - 'gecko_profile': False, 12:06:35 INFO - 'gecko_profile_interval': 0, 12:06:35 INFO - 'installer_path': 'installer.exe', 12:06:35 INFO - 'log_level': 'info', 12:06:35 INFO - 'log_name': 'talos', 12:06:35 INFO - 'log_to_console': True, 12:06:35 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:06:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:06:35 INFO - 'opt_config_files': (), 12:06:35 INFO - 'pip_index': False, 12:06:35 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:06:35 INFO - 'cmd': ('bash', 12:06:35 INFO - '-c', 12:06:35 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:06:35 INFO - 'enabled': True, 12:06:35 INFO - 'halt_on_failure': False, 12:06:35 INFO - 'name': 'check_screen_resolution'},), 12:06:35 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:06:35 INFO - 'cmd': ('bash', 12:06:35 INFO - '-c', 12:06:35 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:06:35 INFO - 'enabled': True, 12:06:35 INFO - 'halt_on_failure': False, 12:06:35 INFO - 'name': 'check_screen_resolution'},), 12:06:35 INFO - 'run_cmd_checks_enabled': True, 12:06:35 INFO - 'suite': 'dromaeojs-e10s', 12:06:35 INFO - 'system_bits': '32', 12:06:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:06:35 INFO - 'title': 't-yosemite-r7-0109', 12:06:35 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:06:35 INFO - 'use_talos_json': True, 12:06:35 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:06:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:06:35 INFO - 'work_dir': 'build'} 12:06:35 INFO - [mozharness: 2017-02-25 20:06:35.203264Z] Running clobber step. 12:06:35 INFO - Running pre-action listener: _resource_record_pre_action 12:06:35 INFO - Running main action method: clobber 12:06:35 INFO - rmtree: /builds/slave/test/build 12:06:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:06:37 INFO - Running post-action listener: _resource_record_post_action 12:06:37 INFO - [mozharness: 2017-02-25 20:06:37.562579Z] Finished clobber step (success) 12:06:37 INFO - [mozharness: 2017-02-25 20:06:37.562710Z] Running read-buildbot-config step. 12:06:37 INFO - Running pre-action listener: _resource_record_pre_action 12:06:37 INFO - Running main action method: read_buildbot_config 12:06:37 INFO - Using buildbot properties: 12:06:37 INFO - { 12:06:37 INFO - "project": "", 12:06:37 INFO - "product": "firefox", 12:06:37 INFO - "script_repo_revision": "production", 12:06:37 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 12:06:37 INFO - "repository": "", 12:06:37 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 12:06:37 INFO - "buildid": "20170225103609", 12:06:37 INFO - "pgo_build": "False", 12:06:37 INFO - "basedir": "/builds/slave/test", 12:06:37 INFO - "buildnumber": 156, 12:06:37 INFO - "slavename": "t-yosemite-r7-0109", 12:06:37 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 12:06:37 INFO - "platform": "yosemite_r7", 12:06:37 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 12:06:37 INFO - "branch": "mozilla-central", 12:06:37 INFO - "repo_path": "mozilla-central", 12:06:37 INFO - "revision": "a08ec245fa24d573fc99e81210ecc09de734cdd3", 12:06:37 INFO - "stage_platform": "macosx64", 12:06:37 INFO - "builduid": "4be7802b711a4ef7b7f1a121defeca41", 12:06:37 INFO - "slavebuilddir": "test" 12:06:37 INFO - } 12:06:37 INFO - Found installer url https://queue.taskcluster.net/v1/task/LO__KlEoRzqtbMlmr_f0QA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg. 12:06:37 INFO - Running post-action listener: _resource_record_post_action 12:06:37 INFO - [mozharness: 2017-02-25 20:06:37.565887Z] Finished read-buildbot-config step (success) 12:06:37 INFO - [mozharness: 2017-02-25 20:06:37.565979Z] Running download-and-extract step. 12:06:37 INFO - Running pre-action listener: _resource_record_pre_action 12:06:37 INFO - Running main action method: download_and_extract 12:06:37 INFO - mkdir: /builds/slave/test/build/tests 12:06:37 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:06:37 INFO - trying https://queue.taskcluster.net/v1/task/LO__KlEoRzqtbMlmr_f0QA/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json 12:06:37 INFO - Downloading https://queue.taskcluster.net/v1/task/LO__KlEoRzqtbMlmr_f0QA/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 12:06:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LO__KlEoRzqtbMlmr_f0QA/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json'}, attempt #1 12:06:38 INFO - Downloaded 1199 bytes. 12:06:38 INFO - Reading from file /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 12:06:38 INFO - Using the following test package requirements: 12:06:38 INFO - {u'common': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 12:06:38 INFO - u'cppunittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 12:06:38 INFO - u'firefox-54.0a1.en-US.mac.cppunittest.tests.zip'], 12:06:38 INFO - u'gtest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 12:06:38 INFO - u'firefox-54.0a1.en-US.mac.gtest.tests.zip'], 12:06:38 INFO - u'jittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 12:06:38 INFO - u'jsshell-mac.zip'], 12:06:38 INFO - u'mochitest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 12:06:38 INFO - u'firefox-54.0a1.en-US.mac.mochitest.tests.zip'], 12:06:38 INFO - u'mozbase': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 12:06:38 INFO - u'reftest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 12:06:38 INFO - u'firefox-54.0a1.en-US.mac.reftest.tests.zip'], 12:06:38 INFO - u'talos': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 12:06:38 INFO - u'firefox-54.0a1.en-US.mac.talos.tests.zip'], 12:06:38 INFO - u'web-platform': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 12:06:38 INFO - u'firefox-54.0a1.en-US.mac.web-platform.tests.zip'], 12:06:38 INFO - u'xpcshell': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 12:06:38 INFO - u'firefox-54.0a1.en-US.mac.xpcshell.tests.zip']} 12:06:38 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip'] for test suite category: common 12:06:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LO__KlEoRzqtbMlmr_f0QA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 12:06:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LO__KlEoRzqtbMlmr_f0QA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:06:38 INFO - Fetch https://queue.taskcluster.net/v1/task/LO__KlEoRzqtbMlmr_f0QA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 12:06:40 INFO - Expected file size: 51745844 12:06:40 INFO - Obtained file size: 51745844 12:06:56 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip', u'firefox-54.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 12:06:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LO__KlEoRzqtbMlmr_f0QA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 12:06:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LO__KlEoRzqtbMlmr_f0QA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:06:56 INFO - Fetch https://queue.taskcluster.net/v1/task/LO__KlEoRzqtbMlmr_f0QA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 12:06:58 INFO - Expected file size: 51745844 12:06:58 INFO - Obtained file size: 51745844 12:07:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LO__KlEoRzqtbMlmr_f0QA/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip 12:07:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LO__KlEoRzqtbMlmr_f0QA/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip'}, attempt #1 12:07:14 INFO - Fetch https://queue.taskcluster.net/v1/task/LO__KlEoRzqtbMlmr_f0QA/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip into memory 12:07:15 INFO - Expected file size: 14404421 12:07:15 INFO - Obtained file size: 14404421 12:07:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:07:15 INFO - trying https://queue.taskcluster.net/v1/task/LO__KlEoRzqtbMlmr_f0QA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 12:07:15 INFO - Downloading https://queue.taskcluster.net/v1/task/LO__KlEoRzqtbMlmr_f0QA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg 12:07:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LO__KlEoRzqtbMlmr_f0QA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg'}, attempt #1 12:07:18 INFO - Downloaded 74909766 bytes. 12:07:18 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LO__KlEoRzqtbMlmr_f0QA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 12:07:18 INFO - mkdir: /builds/slave/test/properties 12:07:18 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:07:18 INFO - Writing to file /builds/slave/test/properties/build_url 12:07:18 INFO - Contents: 12:07:18 INFO - build_url:https://queue.taskcluster.net/v1/task/LO__KlEoRzqtbMlmr_f0QA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 12:07:18 INFO - Running post-action listener: _resource_record_post_action 12:07:18 INFO - Running post-action listener: set_extra_try_arguments 12:07:18 INFO - [mozharness: 2017-02-25 20:07:18.802208Z] Finished download-and-extract step (success) 12:07:18 INFO - [mozharness: 2017-02-25 20:07:18.802361Z] Running populate-webroot step. 12:07:18 INFO - Running pre-action listener: _resource_record_pre_action 12:07:18 INFO - Running main action method: populate_webroot 12:07:18 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:07:18 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:07:18 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:07:18 INFO - '--tppagecycles', 12:07:18 INFO - '7'], 12:07:18 INFO - 'tests': ['tsvgm']}}, 12:07:18 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:07:18 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:07:18 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:07:18 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:07:18 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:07:18 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:07:18 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 12:07:18 INFO - 'talos_options': ['--disable-e10s'], 12:07:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:07:18 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:07:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:07:18 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 12:07:18 INFO - 'talos_options': ['--disable-e10s'], 12:07:18 INFO - 'tests': ['damp', 'tps']}, 12:07:18 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:07:18 INFO - 'tests': ['damp', 'tps']}, 12:07:18 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:07:18 INFO - 'tests': ['dromaeo_dom']}, 12:07:18 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:07:18 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:07:18 INFO - 'tests': ['basic_compositor_video']}, 12:07:18 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:07:18 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:07:18 INFO - 'tests': ['a11yr', 12:07:18 INFO - 'ts_paint', 12:07:18 INFO - 'tpaint', 12:07:18 INFO - 'sessionrestore', 12:07:18 INFO - 'sessionrestore_no_auto_restore', 12:07:18 INFO - 'tabpaint']}, 12:07:18 INFO - 'other-e10s': {'tests': ['a11yr', 12:07:18 INFO - 'ts_paint', 12:07:18 INFO - 'tpaint', 12:07:18 INFO - 'sessionrestore', 12:07:18 INFO - 'sessionrestore_no_auto_restore', 12:07:18 INFO - 'tabpaint']}, 12:07:18 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:07:18 INFO - 'ts_paint', 12:07:18 INFO - 'tpaint', 12:07:18 INFO - 'sessionrestore', 12:07:18 INFO - 'sessionrestore_no_auto_restore', 12:07:18 INFO - 'tabpaint']}, 12:07:18 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:07:18 INFO - 'ts_paint', 12:07:18 INFO - 'tpaint', 12:07:18 INFO - 'sessionrestore', 12:07:18 INFO - 'sessionrestore_no_auto_restore', 12:07:18 INFO - 'tabpaint']}, 12:07:18 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:07:18 INFO - 'tests': ['a11yr', 12:07:18 INFO - 'ts_paint', 12:07:18 INFO - 'tpaint', 12:07:18 INFO - 'sessionrestore', 12:07:18 INFO - 'sessionrestore_no_auto_restore', 12:07:18 INFO - 'tabpaint']}, 12:07:18 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:07:18 INFO - 'tests': ['a11yr', 12:07:18 INFO - 'ts_paint', 12:07:18 INFO - 'tpaint', 12:07:18 INFO - 'sessionrestore', 12:07:18 INFO - 'sessionrestore_no_auto_restore', 12:07:18 INFO - 'tabpaint']}, 12:07:18 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:07:18 INFO - 'tests': ['tsvgx', 12:07:18 INFO - 'tsvgr_opacity', 12:07:18 INFO - 'tart', 12:07:18 INFO - 'tscrollx', 12:07:18 INFO - 'cart', 12:07:18 INFO - 'tsvg_static']}, 12:07:18 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:07:18 INFO - 'tsvgr_opacity', 12:07:18 INFO - 'tart', 12:07:18 INFO - 'tscrollx', 12:07:18 INFO - 'cart', 12:07:18 INFO - 'tsvg_static']}, 12:07:18 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 12:07:18 INFO - 'talos_options': ['--disable-e10s'], 12:07:18 INFO - 'tests': ['tp5o']}, 12:07:18 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:07:18 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 12:07:18 INFO - 'talos_options': ['--disable-e10s', 12:07:18 INFO - '--xperf_path', 12:07:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:07:18 INFO - 'tests': ['tp5n']}, 12:07:18 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:07:18 INFO - 'talos_options': ['--xperf_path', 12:07:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:07:18 INFO - 'tests': ['tp5n']}}, 12:07:18 INFO - 'talos.zip': {'path': '', 12:07:18 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:07:18 INFO - Running post-action listener: _resource_record_post_action 12:07:18 INFO - [mozharness: 2017-02-25 20:07:18.820374Z] Finished populate-webroot step (success) 12:07:18 INFO - [mozharness: 2017-02-25 20:07:18.820485Z] Running create-virtualenv step. 12:07:18 INFO - Running pre-action listener: _resource_record_pre_action 12:07:18 INFO - Running main action method: create_virtualenv 12:07:18 INFO - Creating virtualenv /builds/slave/test/build/venv 12:07:18 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 12:07:18 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:07:18 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:07:18 INFO - Using real prefix '/tools/python27' 12:07:18 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:07:19 INFO - Installing distribute.............................................................................................................................................................................................done. 12:07:22 INFO - Installing pip.................done. 12:07:22 INFO - Return code: 0 12:07:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:07:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:07:22 INFO - Reading from file tmpfile_stdout 12:07:22 INFO - Output received: 12:07:22 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 12:07:22 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:07: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:07:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:07:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:07:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:07:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b8bab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b8bd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda685656c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b698d30>, '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 0x10b5bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b68dbe8>, '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.Uo9Ael4neV/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.t5VkMu3fdo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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 12:07: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', 'pip>=1.5'] in /builds/slave/test/build 12:07: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 pip>=1.5 12:07:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Uo9Ael4neV/Render', 12:07:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:07:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:07:22 INFO - 'HOME': '/Users/cltbld', 12:07:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:07:22 INFO - 'LOGNAME': 'cltbld', 12:07:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:07:22 INFO - 'MOZ_NO_REMOTE': '1', 12:07:22 INFO - 'NO_EM_RESTART': '1', 12:07:22 INFO - 'PAGER': '/bin/cat', 12:07:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:07:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:07:22 INFO - 'PWD': '/builds/slave/test', 12:07:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:07:22 INFO - 'SHELL': '/bin/bash', 12:07:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.t5VkMu3fdo/Listeners', 12:07:22 INFO - 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', 12:07:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:07:22 INFO - 'USER': 'cltbld', 12:07:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:07:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:07:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:07:22 INFO - 'XPC_FLAGS': '0x0', 12:07:22 INFO - 'XPC_SERVICE_NAME': '0', 12:07:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:07:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:07:23 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 12:07:23 INFO - Cleaning up... 12:07:23 INFO - Return code: 0 12:07:23 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:07:23 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:07:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:07:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:23 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:07:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:07:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:23 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 0x10b8bab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b8bd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda685656c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b698d30>, '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 0x10b5bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b68dbe8>, '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.Uo9Ael4neV/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.t5VkMu3fdo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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 12:07:23 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 12:07:23 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 12:07:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Uo9Ael4neV/Render', 12:07:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:07:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:07:23 INFO - 'HOME': '/Users/cltbld', 12:07:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:07:23 INFO - 'LOGNAME': 'cltbld', 12:07:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:07:23 INFO - 'MOZ_NO_REMOTE': '1', 12:07:23 INFO - 'NO_EM_RESTART': '1', 12:07:23 INFO - 'PAGER': '/bin/cat', 12:07:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:07:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:07:23 INFO - 'PWD': '/builds/slave/test', 12:07:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:07:23 INFO - 'SHELL': '/bin/bash', 12:07:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.t5VkMu3fdo/Listeners', 12:07:23 INFO - 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', 12:07:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:07:23 INFO - 'USER': 'cltbld', 12:07:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:07:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:07:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:07:23 INFO - 'XPC_FLAGS': '0x0', 12:07:23 INFO - 'XPC_SERVICE_NAME': '0', 12:07:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:07:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:07:23 INFO - Downloading/unpacking psutil>=3.1.1 12:07: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 12:07: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 12:07: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 12:07: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 12:07: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 12:07: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 12:07:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:07:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:07:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:07:27 INFO - Installing collected packages: psutil 12:07:27 INFO - Running setup.py install for psutil 12:07:27 INFO - building 'psutil._psutil_osx' extension 12:07:27 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 12:07:28 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 12:07:28 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 12:07:28 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 12:07:28 INFO - building 'psutil._psutil_posix' extension 12:07:28 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 12:07:28 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:07:28 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:07:28 INFO - ^ 12:07:28 INFO - 1 warning generated. 12:07:28 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 12:07:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:07:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:07:28 INFO - Successfully installed psutil 12:07:28 INFO - Cleaning up... 12:07:28 INFO - Return code: 0 12:07:28 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:07:28 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:07:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:07:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:28 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:07:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:07:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:28 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 0x10b8bab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b8bd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda685656c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b698d30>, '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 0x10b5bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b68dbe8>, '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.Uo9Ael4neV/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.t5VkMu3fdo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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 12:07:28 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 12:07:28 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 12:07:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Uo9Ael4neV/Render', 12:07:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:07:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:07:28 INFO - 'HOME': '/Users/cltbld', 12:07:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:07:28 INFO - 'LOGNAME': 'cltbld', 12:07:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:07:28 INFO - 'MOZ_NO_REMOTE': '1', 12:07:28 INFO - 'NO_EM_RESTART': '1', 12:07:28 INFO - 'PAGER': '/bin/cat', 12:07:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:07:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:07:28 INFO - 'PWD': '/builds/slave/test', 12:07:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:07:28 INFO - 'SHELL': '/bin/bash', 12:07:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.t5VkMu3fdo/Listeners', 12:07:28 INFO - 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', 12:07:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:07:28 INFO - 'USER': 'cltbld', 12:07:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:07:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:07:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:07:28 INFO - 'XPC_FLAGS': '0x0', 12:07:28 INFO - 'XPC_SERVICE_NAME': '0', 12:07:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:07:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:07:28 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:07: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 12:07: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 12:07: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 12:07: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 12:07: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 12:07: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 12:07:31 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:07:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:07:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:07:31 INFO - Installing collected packages: mozsystemmonitor 12:07:31 INFO - Running setup.py install for mozsystemmonitor 12:07:31 INFO - Successfully installed mozsystemmonitor 12:07:31 INFO - Cleaning up... 12:07:31 INFO - Return code: 0 12:07:31 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:07:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:07:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:07:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:07:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:07:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:31 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 0x10b8bab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b8bd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda685656c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b698d30>, '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 0x10b5bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b68dbe8>, '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.Uo9Ael4neV/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.t5VkMu3fdo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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 12:07:31 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 12:07:31 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 12:07:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Uo9Ael4neV/Render', 12:07:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:07:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:07:31 INFO - 'HOME': '/Users/cltbld', 12:07:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:07:31 INFO - 'LOGNAME': 'cltbld', 12:07:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:07:31 INFO - 'MOZ_NO_REMOTE': '1', 12:07:31 INFO - 'NO_EM_RESTART': '1', 12:07:31 INFO - 'PAGER': '/bin/cat', 12:07:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:07:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:07:31 INFO - 'PWD': '/builds/slave/test', 12:07:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:07:31 INFO - 'SHELL': '/bin/bash', 12:07:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.t5VkMu3fdo/Listeners', 12:07:31 INFO - 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', 12:07:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:07:31 INFO - 'USER': 'cltbld', 12:07:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:07:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:07:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:07:31 INFO - 'XPC_FLAGS': '0x0', 12:07:31 INFO - 'XPC_SERVICE_NAME': '0', 12:07:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:07:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:07:32 INFO - Downloading/unpacking jsonschema==2.5.1 12:07: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 12:07: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 12:07: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 12:07: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 12:07: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 12:07: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 12:07:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:07:34 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:07:34 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 12:07:34 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 12:07:34 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 12:07:34 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 12:07:34 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 12:07:34 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 12:07:35 INFO - Downloading functools32-3.2.3-2.tar.gz 12:07:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:07:35 INFO - warning: no files found matching '*.txt' 12:07:35 INFO - no previously-included directories found matching 'build' 12:07:35 INFO - no previously-included directories found matching 'dist' 12:07:35 INFO - no previously-included directories found matching '.git*' 12:07:35 INFO - Installing collected packages: jsonschema, functools32 12:07:35 INFO - Running setup.py install for functools32 12:07:35 INFO - warning: no files found matching '*.txt' 12:07:35 INFO - no previously-included directories found matching 'build' 12:07:35 INFO - no previously-included directories found matching 'dist' 12:07:35 INFO - no previously-included directories found matching '.git*' 12:07:35 INFO - Successfully installed jsonschema functools32 12:07:35 INFO - Cleaning up... 12:07:35 INFO - Return code: 0 12:07:35 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:07:35 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:07:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:07:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:35 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:07:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:07:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:35 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', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b8bab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b8bd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda685656c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b698d30>, '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 0x10b5bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b68dbe8>, '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.Uo9Ael4neV/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.t5VkMu3fdo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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 12:07:35 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', 'functools32==3.2.3-2'] in /builds/slave/test/build 12:07:35 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 functools32==3.2.3-2 12:07:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Uo9Ael4neV/Render', 12:07:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:07:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:07:35 INFO - 'HOME': '/Users/cltbld', 12:07:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:07:35 INFO - 'LOGNAME': 'cltbld', 12:07:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:07:35 INFO - 'MOZ_NO_REMOTE': '1', 12:07:35 INFO - 'NO_EM_RESTART': '1', 12:07:35 INFO - 'PAGER': '/bin/cat', 12:07:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:07:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:07:35 INFO - 'PWD': '/builds/slave/test', 12:07:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:07:35 INFO - 'SHELL': '/bin/bash', 12:07:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.t5VkMu3fdo/Listeners', 12:07:35 INFO - 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', 12:07:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:07:35 INFO - 'USER': 'cltbld', 12:07:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:07:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:07:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:07:35 INFO - 'XPC_FLAGS': '0x0', 12:07:35 INFO - 'XPC_SERVICE_NAME': '0', 12:07:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:07:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:07:35 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:07:35 INFO - Cleaning up... 12:07:35 INFO - Return code: 0 12:07:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:07:35 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:07:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:07:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:35 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:07:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:07:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:35 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 0x10b8bab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b8bd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda685656c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b698d30>, '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 0x10b5bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b68dbe8>, '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.Uo9Ael4neV/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.t5VkMu3fdo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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 12:07:35 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 12:07:35 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 12:07:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Uo9Ael4neV/Render', 12:07:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:07:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:07:35 INFO - 'HOME': '/Users/cltbld', 12:07:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:07:35 INFO - 'LOGNAME': 'cltbld', 12:07:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:07:35 INFO - 'MOZ_NO_REMOTE': '1', 12:07:35 INFO - 'NO_EM_RESTART': '1', 12:07:35 INFO - 'PAGER': '/bin/cat', 12:07:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:07:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:07:35 INFO - 'PWD': '/builds/slave/test', 12:07:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:07:35 INFO - 'SHELL': '/bin/bash', 12:07:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.t5VkMu3fdo/Listeners', 12:07:35 INFO - 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', 12:07:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:07:35 INFO - 'USER': 'cltbld', 12:07:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:07:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:07:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:07:35 INFO - 'XPC_FLAGS': '0x0', 12:07:35 INFO - 'XPC_SERVICE_NAME': '0', 12:07:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:07:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:07:36 INFO - Downloading/unpacking blobuploader==1.2.4 12:07:36 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 12:07:36 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 12:07:36 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 12:07:36 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 12:07:36 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 12:07:36 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 12:07:38 INFO - Downloading blobuploader-1.2.4.tar.gz 12:07:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:07:38 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:07:38 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 12:07:38 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 12:07:38 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 12:07:38 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 12:07:38 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 12:07:38 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 12:07:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:07:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:07:39 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 12:07:39 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 12:07:39 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 12:07:39 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 12:07:39 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 12:07:39 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 12:07:39 INFO - Downloading docopt-0.6.1.tar.gz 12:07:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:07:40 INFO - Installing collected packages: blobuploader, requests, docopt 12:07:40 INFO - Running setup.py install for blobuploader 12:07:40 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:07:40 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:07:40 INFO - Running setup.py install for requests 12:07:40 INFO - Running setup.py install for docopt 12:07:40 INFO - Successfully installed blobuploader requests docopt 12:07:40 INFO - Cleaning up... 12:07:40 INFO - Return code: 0 12:07:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:07:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:07:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:07:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:07:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:07:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:40 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 0x10b8bab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b8bd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda685656c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b698d30>, '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 0x10b5bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b68dbe8>, '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.Uo9Ael4neV/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.t5VkMu3fdo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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 12:07:40 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 12:07:40 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 12:07:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Uo9Ael4neV/Render', 12:07:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:07:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:07:40 INFO - 'HOME': '/Users/cltbld', 12:07:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:07:40 INFO - 'LOGNAME': 'cltbld', 12:07:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:07:40 INFO - 'MOZ_NO_REMOTE': '1', 12:07:40 INFO - 'NO_EM_RESTART': '1', 12:07:40 INFO - 'PAGER': '/bin/cat', 12:07:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:07:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:07:40 INFO - 'PWD': '/builds/slave/test', 12:07:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:07:40 INFO - 'SHELL': '/bin/bash', 12:07:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.t5VkMu3fdo/Listeners', 12:07:40 INFO - 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', 12:07:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:07:40 INFO - 'USER': 'cltbld', 12:07:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:07:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:07:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:07:40 INFO - 'XPC_FLAGS': '0x0', 12:07:40 INFO - 'XPC_SERVICE_NAME': '0', 12:07:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:07:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:07:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:07:41 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-oMraXv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:07:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:07:41 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-GCVaHs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:07:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:07:41 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-HChNuc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:07:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:07:41 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-Jhxutb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:07:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:07:41 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-TduBEt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:07:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:07:41 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-HKXMwL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:07:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:07:41 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-j9nc_O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:07:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:07:41 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-BPOFyq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:07:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:07:42 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-kyvtoJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:07:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:07:42 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-xtkHC7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:07:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:07:42 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-lA07XA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:07:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:07:42 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-eQXDo6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:07:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:07:42 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-IV6B24-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:07:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:07:42 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-0T837D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:07:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:07:42 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-NWwEKP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:07:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:07:42 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-qrQLaM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:07:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:07:42 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-2ERmkF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:07:42 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:07:42 INFO - Running setup.py install for manifestparser 12:07:43 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:07:43 INFO - Running setup.py install for mozcrash 12:07:43 INFO - Running setup.py install for mozdebug 12:07:43 INFO - Running setup.py install for mozdevice 12:07:43 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:07:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:07:43 INFO - Running setup.py install for mozfile 12:07:43 INFO - Running setup.py install for mozhttpd 12:07:43 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:07:43 INFO - Running setup.py install for mozinfo 12:07:43 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:07:44 INFO - Running setup.py install for mozInstall 12:07:44 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:07:44 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:07:44 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:07:44 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:07:44 INFO - Running setup.py install for mozleak 12:07:44 INFO - Running setup.py install for mozlog 12:07:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:07:44 INFO - Running setup.py install for moznetwork 12:07:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:07:44 INFO - Running setup.py install for mozprocess 12:07:44 INFO - Running setup.py install for mozprofile 12:07:44 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:07:44 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:07:44 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:07:44 INFO - Running setup.py install for mozrunner 12:07:45 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:07:45 INFO - Running setup.py install for mozscreenshot 12:07:45 INFO - Running setup.py install for moztest 12:07:45 INFO - Running setup.py install for mozversion 12:07:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:07:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:07:45 INFO - Cleaning up... 12:07:45 INFO - Return code: 0 12:07:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:07:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:07:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:07:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:07:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:07:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:45 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 0x10b8bab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b8bd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda685656c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b698d30>, '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 0x10b5bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b68dbe8>, '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.Uo9Ael4neV/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.t5VkMu3fdo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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 12:07:45 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 12:07:45 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 12:07:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Uo9Ael4neV/Render', 12:07:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:07:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:07:45 INFO - 'HOME': '/Users/cltbld', 12:07:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:07:45 INFO - 'LOGNAME': 'cltbld', 12:07:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:07:45 INFO - 'MOZ_NO_REMOTE': '1', 12:07:45 INFO - 'NO_EM_RESTART': '1', 12:07:45 INFO - 'PAGER': '/bin/cat', 12:07:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:07:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:07:45 INFO - 'PWD': '/builds/slave/test', 12:07:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:07:45 INFO - 'SHELL': '/bin/bash', 12:07:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.t5VkMu3fdo/Listeners', 12:07:45 INFO - 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', 12:07:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:07:45 INFO - 'USER': 'cltbld', 12:07:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:07:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:07:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:07:45 INFO - 'XPC_FLAGS': '0x0', 12:07:45 INFO - 'XPC_SERVICE_NAME': '0', 12:07:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:07:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:07:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:07:45 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-34eH03-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:07:45 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)) 12:07:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:07:45 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-Z3_9qC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:07:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 12:07:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:07:46 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-EJrRGB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:07:46 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)) 12:07:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:07:46 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-UCiQYs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:07:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 12:07:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:07:46 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-Icx06Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:07:46 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)) 12:07:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:07:46 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-i4sjkL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:07:46 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)) 12:07:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:07:46 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-ZhUyGV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:07:46 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)) 12:07:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:07:46 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-3WP_XS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:07:46 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)) 12:07:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:07:46 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-sa6zhJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:07:46 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)) 12:07:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:07:46 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-xk1yui-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:07:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 12:07:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:07:46 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-PGlQG_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:07:47 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)) 12:07:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:07:47 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-BLQM6G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:07:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 12:07:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:07:47 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-x3Awof-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:07:47 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)) 12:07:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:07:47 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-EzEzyt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:07:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 12:07:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:07:47 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-qLgrcX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:07:47 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)) 12:07:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:07:47 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-3UbjGU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:07:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 12:07:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:07:47 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-go5okT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:07:47 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)) 12:07:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:07:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:07:47 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)) 12:07:47 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:07:47 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:07:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:07:47 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 12:07:47 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 12:07:47 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 12:07:47 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 12:07:47 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 12:07:47 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 12:07:50 INFO - Downloading blessings-1.6.tar.gz 12:07:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:07:50 INFO - Installing collected packages: blessings 12:07:50 INFO - Running setup.py install for blessings 12:07:50 INFO - Successfully installed blessings 12:07:50 INFO - Cleaning up... 12:07:50 INFO - Return code: 0 12:07:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:07:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:07:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:07:50 INFO - Reading from file tmpfile_stdout 12:07:50 INFO - Current package versions: 12:07:50 INFO - blessings == 1.6 12:07:50 INFO - blobuploader == 1.2.4 12:07:50 INFO - docopt == 0.6.1 12:07:50 INFO - functools32 == 3.2.3-2 12:07:50 INFO - jsonschema == 2.5.1 12:07:50 INFO - manifestparser == 1.1 12:07:50 INFO - mozInstall == 1.12 12:07:50 INFO - mozcrash == 1.0 12:07:50 INFO - mozdebug == 0.1 12:07:50 INFO - mozdevice == 0.49 12:07:50 INFO - mozfile == 1.2 12:07:50 INFO - mozhttpd == 0.7 12:07:50 INFO - mozinfo == 0.9 12:07:50 INFO - mozleak == 0.1 12:07:50 INFO - mozlog == 3.4 12:07:50 INFO - moznetwork == 0.27 12:07:50 INFO - mozprocess == 0.25 12:07:50 INFO - mozprofile == 0.28 12:07:50 INFO - mozrunner == 6.13 12:07:50 INFO - mozscreenshot == 0.1 12:07:50 INFO - mozsystemmonitor == 0.3 12:07:50 INFO - moztest == 0.8 12:07:50 INFO - mozversion == 1.4 12:07:50 INFO - psutil == 3.1.1 12:07:50 INFO - requests == 1.2.3 12:07:50 INFO - wsgiref == 0.1.2 12:07:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:07:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:07:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:07:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:07:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:07:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:50 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 0x10b8bab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b8bd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda685656c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b698d30>, '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 0x10b5bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b68dbe8>, '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.Uo9Ael4neV/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.t5VkMu3fdo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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 12:07:50 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 12:07:50 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 12:07:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Uo9Ael4neV/Render', 12:07:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:07:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:07:50 INFO - 'HOME': '/Users/cltbld', 12:07:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:07:50 INFO - 'LOGNAME': 'cltbld', 12:07:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:07:50 INFO - 'MOZ_NO_REMOTE': '1', 12:07:50 INFO - 'NO_EM_RESTART': '1', 12:07:50 INFO - 'PAGER': '/bin/cat', 12:07:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:07:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:07:50 INFO - 'PWD': '/builds/slave/test', 12:07:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:07:50 INFO - 'SHELL': '/bin/bash', 12:07:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.t5VkMu3fdo/Listeners', 12:07:50 INFO - 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', 12:07:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:07:50 INFO - 'USER': 'cltbld', 12:07:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:07:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:07:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:07:50 INFO - 'XPC_FLAGS': '0x0', 12:07:50 INFO - 'XPC_SERVICE_NAME': '0', 12:07:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:07:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:07:51 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)) 12:07:51 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)) 12:07:51 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)) 12:07:51 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)) 12:07:51 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)) 12:07:51 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)) 12:07:51 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)) 12:07:51 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)) 12:07:51 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)) 12:07:51 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)) 12:07:51 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)) 12:07:51 INFO - Cleaning up... 12:07:51 INFO - Return code: 0 12:07:51 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:07:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:07:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:07:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:07:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:07:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:07:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b8bab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b8bd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda685656c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b698d30>, '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 0x10b5bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b68dbe8>, '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.Uo9Ael4neV/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.t5VkMu3fdo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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 12:07:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 12:07:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 12:07:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Uo9Ael4neV/Render', 12:07:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:07:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:07:51 INFO - 'HOME': '/Users/cltbld', 12:07:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:07:51 INFO - 'LOGNAME': 'cltbld', 12:07:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:07:51 INFO - 'MOZ_NO_REMOTE': '1', 12:07:51 INFO - 'NO_EM_RESTART': '1', 12:07:51 INFO - 'PAGER': '/bin/cat', 12:07:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:07:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:07:51 INFO - 'PWD': '/builds/slave/test', 12:07:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:07:51 INFO - 'SHELL': '/bin/bash', 12:07:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.t5VkMu3fdo/Listeners', 12:07:51 INFO - 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', 12:07:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:07:51 INFO - 'USER': 'cltbld', 12:07:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:07:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:07:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:07:51 INFO - 'XPC_FLAGS': '0x0', 12:07:51 INFO - 'XPC_SERVICE_NAME': '0', 12:07:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:07:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:07:51 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:07:51 INFO - Cleaning up... 12:07:51 INFO - Return code: 0 12:07:51 INFO - Running post-action listener: _resource_record_post_action 12:07:51 INFO - Running post-action listener: _start_resource_monitoring 12:07:51 INFO - Starting resource monitoring. 12:07:51 INFO - [mozharness: 2017-02-25 20:07:51.525958Z] Finished create-virtualenv step (success) 12:07:51 INFO - [mozharness: 2017-02-25 20:07:51.526953Z] Running install step. 12:07:51 INFO - Running pre-action listener: _resource_record_pre_action 12:07:51 INFO - Running main action method: install 12:07:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:07:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:07:51 INFO - Reading from file tmpfile_stdout 12:07:51 INFO - Detecting whether we're running mozinstall >=1.0... 12:07:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:07:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:07:51 INFO - Reading from file tmpfile_stdout 12:07:51 INFO - Output received: 12:07:51 INFO - Usage: mozinstall [options] installer 12:07:51 INFO - Options: 12:07:51 INFO - -h, --help show this help message and exit 12:07:51 INFO - -d DEST, --destination=DEST 12:07:51 INFO - Directory to install application into. [default: 12:07:51 INFO - "/builds/slave/test"] 12:07:51 INFO - --app=APP Application being installed. [default: firefox] 12:07:51 INFO - mkdir: /builds/slave/test/build/application 12:07:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 12:07:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 12:08:11 INFO - Reading from file tmpfile_stdout 12:08:11 INFO - Output received: 12:08:11 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 12:08:11 INFO - Running post-action listener: _resource_record_post_action 12:08:11 INFO - [mozharness: 2017-02-25 20:08:11.891800Z] Finished install step (success) 12:08:11 INFO - [mozharness: 2017-02-25 20:08:11.891929Z] Running run-tests step. 12:08:11 INFO - Running pre-action listener: _resource_record_pre_action 12:08:11 INFO - Running main action method: run_tests 12:08:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:08:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:08:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:08:11 INFO - 2017-02-25 12:08:11.907 screenresolution[1635:8232] starting screenresolution argv=screenresolution get 12:08:11 INFO - 2017-02-25 12:08:11.909 screenresolution[1635:8232] Display 0: 1600x1200x32@60 12:08:11 INFO - 2017-02-25 12:08:11.915 screenresolution[1636:8235] starting screenresolution argv=screenresolution list 12:08:11 INFO - Available Modes on Display 0 12:08:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:08:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:08:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:08:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:08:12 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:08:12 INFO - Intel Iris: 12:08:12 INFO - Chipset Model: Intel Iris 12:08:12 INFO - Type: GPU 12:08:12 INFO - Bus: Built-In 12:08:12 INFO - VRAM (Dynamic, Max): 1536 MB 12:08:12 INFO - Vendor: Intel (0x8086) 12:08:12 INFO - Device ID: 0x0a2e 12:08:12 INFO - Revision ID: 0x0009 12:08:12 INFO - Displays: 12:08:12 INFO - Display: 12:08:12 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:08:12 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:08:12 INFO - Display Serial Number: 1600x1200 60 12:08:12 INFO - Main Display: Yes 12:08:12 INFO - Mirror: Off 12:08:12 INFO - Online: Yes 12:08:12 INFO - Rotation: Supported 12:08:12 INFO - Return code: 0 12:08:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:08:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:08:12 INFO - Python 2.7.3 12:08:12 INFO - Return code: 0 12:08:12 INFO - grabbing minidump binary from tooltool 12:08:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:12 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b698d30>, '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 0x10b5bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b68dbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:08:12 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 12:08:12 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 12:08:12 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 12:08:12 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:08:12 INFO - Return code: 0 12:08:12 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:08:12 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:08:12 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:08:12 INFO - ENV: RUST_BACKTRACE is now 1 12:08:12 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:08:12 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:08:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LO__KlEoRzqtbMlmr_f0QA/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0109', '--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 12:08:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/LO__KlEoRzqtbMlmr_f0QA/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0109 --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 12:08:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Uo9Ael4neV/Render', 12:08:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:08:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:08:12 INFO - 'HOME': '/Users/cltbld', 12:08:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:08:12 INFO - 'LOGNAME': 'cltbld', 12:08:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:08:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:08:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:08:12 INFO - 'MOZ_NO_REMOTE': '1', 12:08:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:08:12 INFO - 'NO_EM_RESTART': '1', 12:08:12 INFO - 'PAGER': '/bin/cat', 12:08:12 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:08:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:08:12 INFO - 'PWD': '/builds/slave/test', 12:08:12 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:08:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:08:12 INFO - 'RUST_BACKTRACE': '1', 12:08:12 INFO - 'SHELL': '/bin/bash', 12:08:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.t5VkMu3fdo/Listeners', 12:08:12 INFO - 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', 12:08:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:08:12 INFO - 'USER': 'cltbld', 12:08:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:08:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:08:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:08:12 INFO - 'XPC_FLAGS': '0x0', 12:08:12 INFO - 'XPC_SERVICE_NAME': '0', 12:08:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:08:12 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LO__KlEoRzqtbMlmr_f0QA/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0109', '--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 12:08:12 INFO - mozversion application_buildid: 20170225103609 12:08:12 INFO - mozversion application_changeset: a08ec245fa24d573fc99e81210ecc09de734cdd3 12:08:12 INFO - mozversion application_display_name: Nightly 12:08:12 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:08:12 INFO - mozversion application_name: Firefox 12:08:12 INFO - mozversion application_remotingname: firefox 12:08:12 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 12:08:12 INFO - mozversion application_vendor: Mozilla 12:08:12 INFO - mozversion application_version: 54.0a1 12:08:12 INFO - mozversion platform_buildid: 20170225103609 12:08:12 INFO - mozversion platform_changeset: a08ec245fa24d573fc99e81210ecc09de734cdd3 12:08:12 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 12:08:12 INFO - mozversion platform_version: 54.0a1 12:08:12 INFO - using testdate: 1488053292 12:08:12 INFO - actual date: 1488053292 12:08:12 INFO - starting webserver on 'localhost:49251' 12:08:12 INFO - SUITE-START | Running 2 tests 12:08:12 INFO - TEST-START | dromaeo_css 12:08:12 INFO - operating with platform_type : mac_ 12:08:12 INFO - Initialising browser for dromaeo_css test... 12:08:12 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/tmpZJ0ajo/profile http://localhost:49251/getInfo.html) 12:08:15 INFO - PROCESS | 1646 | 2017-02-25 12:08:15.432 plugin-container[1648:8421] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c47, name = 'com.apple.tsm.portname' 12:08:15 INFO - PROCESS | 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:08:15 INFO - PROCESS | 1646 | 2017-02-25 12:08:15.435 plugin-container[1648:8421] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x960b, name = 'com.apple.CFPasteboardClient' 12:08:15 INFO - PROCESS | 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:08:15 INFO - PROCESS | 1646 | 2017-02-25 12:08:15.435 plugin-container[1648:8421] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:08:15 INFO - PROCESS | 1646 | __metrics Screen width/height:1600/1200 12:08:15 INFO - PROCESS | 1646 | colorDepth:24 12:08:15 INFO - PROCESS | 1646 | Browser inner width/height: 1024/689 12:08:15 INFO - PROCESS | 1646 | __metrics 12:08:15 ERROR - PROCESS | 1646 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3354: TypeError: this.tabs is undefined 12:08:16 INFO - TEST-INFO | 1646: exit 0 12:08:16 INFO - Browser initialized. 12:08:16 INFO - Running cycle 1/1 for dromaeo_css test... 12:08:16 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/tmpZJ0ajo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:08:18 INFO - PROCESS | 1650 | 2017-02-25 12:08:18.915 plugin-container[1651:8563] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8843, name = 'com.apple.tsm.portname' 12:08:18 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:08:18 INFO - PROCESS | 1650 | 2017-02-25 12:08:18.916 plugin-container[1651:8563] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 12:08:18 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:08:18 INFO - PROCESS | 1650 | 2017-02-25 12:08:18.916 plugin-container[1651:8563] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:08:28 INFO - PROCESS | 1650 | RSS: Main: 225857536 12:08:28 INFO - PROCESS | 1650 | 12:11:31 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49251/tests/dromaeo/cssquery-ext.html) 12:11:33 INFO - PROCESS | 1650 | RSS: Main: 253599744 12:11:33 INFO - PROCESS | 1650 | 12:13:16 INFO - PROCESS | 1650 | ************************* 12:13:16 INFO - PROCESS | 1650 | A coding exception was thrown and uncaught in a Task. 12:13:16 INFO - PROCESS | 1650 | 12:13:16 ERROR - PROCESS | 1650 | Full message: TypeError: NetworkError when attempting to fetch resource. 12:13:16 INFO - PROCESS | 1650 | Full stack: 12:13:16 INFO - PROCESS | 1650 | ************************* 12:14:36 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-ext.html (next: http://localhost:49251/tests/dromaeo/cssquery-jquery.html) 12:14:36 INFO - PROCESS | 1650 | RSS: Main: 257323008 12:14:36 INFO - PROCESS | 1650 | 12:17:39 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49251/tests/dromaeo/cssquery-mootools.html) 12:17:39 INFO - PROCESS | 1650 | RSS: Main: 267759616 12:17:39 INFO - PROCESS | 1650 | 12:20:41 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49251/tests/dromaeo/cssquery-prototype.html) 12:20:42 INFO - PROCESS | 1650 | RSS: Main: 260653056 12:20:42 INFO - PROCESS | 1650 | 12:23:45 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49251/tests/dromaeo/cssquery-yui.html) 12:23:45 INFO - PROCESS | 1650 | RSS: Main: 270815232 12:23:45 INFO - PROCESS | 1650 | 12:26:48 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-yui.html (next: http://localhost:49251/tests/dromaeo/cssquery-dojo.html) 12:26:49 INFO - PROCESS | 1650 | RSS: Main: 264413184 12:26:49 INFO - PROCESS | 1650 | 12:26:49 INFO - PROCESS | 1650 | __start_tp_report 12:26:49 INFO - PROCESS | 1650 | _x_x_mozilla_page_load 12:26:49 INFO - PROCESS | 1650 | _x_x_mozilla_page_load_details 12:26:49 INFO - PROCESS | 1650 | |i|pagename|runs| 12:26:49 INFO - PROCESS | 1650 | |0;dojo.html;3033.4084853453573;3270.7219886309663;3295.91760205995;3320.020593924792;3325.102222399952;5510.889782204354;5561.77752889884;5566.359868615111;5570.941521110996;5641.887162256752;5522.447755224475;5576.302962129734;5582.441755824416;5608.859778505539;5678.119891416837;4175.665946724254;4817.301491283779;5096.79612815487;5156.407013193493;5265;4188.623023927846;5040.243963405482;5127.359080114986;5189.455107213745;5232.973835130819;5051.444341122474;5118.385793704742;5163.302954101186;5173.76718047685;5204.479552044765;5104.846854594368;5117.360329958755;5123.820666276662;5161.148410512261;5167.948320516784;4971.1549036663855;4980.477049909779;5093.617978651579;5094.61790365724;5105.489451054902;5387.4612538746205;5449.3188351456065;5491.972540137313;5599.384067752544;5665.886682266373;6094.994325936215;6138.631682099089;6142.232220972379;6150.923588372017;6163.506919446433;6000.789972350991;6204.100405441231;6217.937820621781;6220.7511699532415;6236.501079913596;5525.226468294442;5536.667799932017;5599.468050535193;5606.046972014727;5610.5231055360755;5844.590878638454;6111.591738512148;6146.938530614726;6198.938010619836;6217.471514921192;5744.684042377626;5747.741351639186;5814.767409303665;5928.170056192136;6110.7555697771595;4777.235642297216;6037.6679282639;6114.816555503342;6119.173911521976;6139.369480960552;6072.696365181723;6133.944507141707;6149.754009839646;6154.169187159766;6182.505399568114;5272.839975205448;5824.805914787458;5891.145783861316;6225.221847269091;6235.095911092866;3977.244323578511;4800.015996720664;4808.903821923542;4861.343718597944;4896.559309662148;4376.037271800199;4379.3430985351815;4397.9560204398185;4405.339199120158;4407.294832826732;4742.312364707098;5643.1535269709875;5681.147827825776;5685.914711279334;5690.5447564194765;5963.4334738199805;6038.215032045894;6077.301110372276;6129.509639228941;6229.719662615194;5587.078132108238;5605.439456054362;5614.382417934024;5620.971895140498;5702.971485142631;5317.946820531823;5352.464753524694;5374.516293533601;5387.838364849061;5388.326459192601;4698.647601429906;6251.062340648939;6254.186955695731;6256.7810126645345;6262.812115636538;5980.730867110903;6056.9697151513965;6063.605865618721;6125.479334256638;6133.233345831771;6176.104464852661;6196.318404975359;6240.781572644935;6242.188515492957;6249.843753906189;6147.200863887666;6159.29168145669;6161.353057928853;6165.475934545614;6167;5757.625754325955;6005.669688167192;6237.407446292595;6239.594426362272;6244.594101383395;5879.088741245114;5893.970530147321;5903.940960590511;5952;5953;6071.7571297147615;6073.635581865102;6079.088136779518;6085.756569737338;6106.877862442638;5781.855453613525;5785.132230165509;5891.793787217427;5903.439173278532;5925.644461332334;5741.339745929272;5772.509336706259;5813.505852002627;6003.939960600338;6086.786962456293;5703.372629010889;5723.170140329546;5746.770129194785;5750.827475175752;5806.332271788629 12:26:49 INFO - PROCESS | 1650 | |1;ext.html;786.3276898251994;793.9603019849008;823.9134890836463;856.3620103023245;942.1708896171368;35675.64324356756;38006;38157.61842381579;38159.809200953954;38253.04367390816;36565.81717091411;36731.44902826453;36762.44856327151;36777.816110919404;36904.89285321445;7428.294312040348;7536.4724469287175;7728.343090837286;7864.370850331959;11059.944700276528;10961;11259.493322800452;11265.380404077772;11280.266782659142;11306.095512359032;11219.326840389604;11281.54873805051;11282.969065568195;11287.604933827317;11290.943545282304;11201.66395008151;11229.27009744364;11255.380954047565;11271.872812718744;11279.887201128047;10934.179936504794;10995.285306455055;11012.449377531091;11015.559377624886;11048.723781905437;37943.86168414952;38060.61939380599;38110.80944595287;38176.80911595452;38186.23627527462;3679.4664773607947;3705.184859330943;3716.7769933804057;3724.3854763963914;3852.8651497197743;26636.733632663618;27357.452850943067;27493.31266718328;27791.722082779117;27853.442931141264;2820.1539538138477;2836.2767494289033;2929.3994731080293;2944.455275773989;2986.611740473746;312119.4394028038;312515.4361879243;313517;313562.4321878399;316484;41124.97187570335;41738.582614174076;50196.74901625542;51677.48322516727;52322.95354092973;45924;49660.75848103826;51820.22269665958;51869.7406512965;51952.96094078097;13563.728725425437;13580.320983950764;13661.931690341484;13765.518206862711;13807.723845523033;11653.41732913351;11695.181337306478;11761.529538818522;11799.997000254903;12301.630951071482;8834.514101724468;9978;12138.393080346124;12352.320622365676;12364.62906112818;5054.82308119214;5254.316938798008;5551.528120109836;5557.249771280906;5561.694106824163;935.0555938502162;967.5210770668564;1063.409807556798;1067.055655744656;1088.5319312695458;25081.749182508305;25250;25465.61801572978;25543.87228063848;25671.358216044748;1065.4832406282947;1102.8897110288906;1117.8486159255979;1162.3200427749691;1233.5004323249098;13057.412416441282;13188.340582970814;13303.66740831487;13344.065915386023;13379.331033448289;416502;417024.8297516986;417451;419947.3044791166;420383;52606.473935260925;52619.94760104853;52748.20877686838;52749.94500110053;53539;1358.5720498043086;1415.6390120519202;1418.184543887269;1418.7800890861727;1421.8507056758892;24095.75904240935;24154.87922560376;24203.878980604983;24513.387165320295;24771.380715482257;43441.3483797737;43586;43646.34530481982;43771.56228437802;43787.9053023677;416464;420156;421781;421800;421930;27345.453090937674;31660.366792663557;31843;32194.356112877143;32615.347693046482;39946.40080398738;40411.393829092;40644;41180;41409;30227.395452090394;30805.921792737146;31137.532937006417;31139.688603114584;31410.429478525526;33791.83104084464;33839;33851.984440466826;34824.47763283502;34854.477182842784;32784.50823237606;34684.65315346814;34722.47916281207;34799.95600132;34813.82593087018;25774.09790657318;27966;28002.719972800012;29424.705752942198;29810.552841706958;21504.56990860143;23092.307230783103;23380.76619233786;23406.946687398766;23500.059997599903 12:26:49 INFO - PROCESS | 1650 | |2;jquery.html;386.277660774352;387.59689922480624;393.63981956509804;398.14796335841294;422.86891063770236;16422.67154656906;20146.798532014676;21095.578088438222;21854;21903.561928761414;12265.816012759793;12646.430910609022;12651.62045138645;16193.514194574152;22051.11795528181;4367.2575662137515;4566.949601591636;5420.268263784398;5678.205051292823;5687.232223649796;5513.9448605513835;5534.446555344473;5551.750171242304;5573.359063707665;5582.190582365554;5329.307190065286;5530.087535556629;5583.888322233553;5595.076812325961;5607.719614019303;5530.308711411074;5536.695481748502;5583.162525621149;5588.658721906754;5595.076812325961;3992.161646054332;5146.562542183919;5302.655327403706;5477.698726570077;5477.890442191174;13773.449062037607;13817.447302107903;13893.096948698303;14283.857161428357;25858.87070564654;2575.3947822261753;2577.342777591702;2577.7551132642557;2647.2190703742444;2666.3734022504696;10109.545070471846;10115.494225288707;10501.789964200672;10533.84199237012;12545.1218414711;1705.3945849223471;1743.9302427902996;1804.224183601051;1820.59036716739;1821.927122915095;97269.513652432;117569;119134.40432797781;132120.33939830336;146104;11051.613193538187;11371.658850234506;11423.657290281304;11977.880221197851;12011.519539218509;14431.2062836545;16122.274497647633;16129.354825807071;16133.596660083356;16146.192690365435;7062.435005199572;8346.958265208636;8552.246789408156;8568.357373197035;8577.099404562556;8119.553424561587;8135.552544610104;8263.669453221804;8270.338372930151;8282.668693252203;4808.61531077513;4907.6564640475535;4912.410510738734;4919.606431485473;5024.447310795809;2055.5991581641733;2088.070808490207;2095.402810199086;2097.9370618881458;2104.2109209046607;975.3660120921458;1009.9697009089739;1015.5683834370363;1028.3726926574784;1042.8997407734912;21477.463063423227;21575.174702830147;22102.22642207515;23419.180328688413;24112.03551857941;881.4667126388571;884.4869975414244;949.9952500237592;1034.1623285139062;1040.5889673579045;10653.360798352123;11024.779504410028;11040.178370568927;11104.945030222116;11184.720381990353;576840;704803;717244.4137779382;723220;727508;122868;145133;157388;159271;164208.17895910444;881.6914080071923;1061.124572227915;1077.8383242513685;1079.7192729890128;1087.7117563845745;14111.941604379917;16838.66322673564;17163.227654755316;17946;18326.725099123258;40872.38691419572;53606.73196633992;54251.72874135604;54317.72841135927;54881.45118548923;74591;76272.23727762874;78331;81033.73280736733;83117;7755.961220193863;8464.068952415106;8494.32045436376;8622.310215182644;8676.956615216883;10843.797909234954;13408.865911341152;13625.79561306601;13678.452861885413;13678.58372804539;5583.469570390806;6120.510359171345;11177.155769297975;11270.211085223955;11307;9854.458004809805;9987.800243995225;11397.430128493708;11510;11689.766204676029;11450.541978321107;11646.855349055539;11805.173637845268;11852.348120853436;11922.52309907594;7235.746748863765;7687.423443241667;8362.163783621589;8404.789880253042;8701.260392866678;10531;11159;11348.375839328915;11399.601013964471;11537.365444900612 12:26:49 INFO - PROCESS | 1650 | |3;mootools.html;1266.4870727355421;1330.1420583723495;1414.2645824171425;1430.4421275702477;1441.963950901228;8095.514269143848;8207.056188538305;8498.532580708059;8521.360897932656;8521.446106003106;8332.541710205936;8489.023762267341;8495.872561911576;8502.829943401144;8507.404481686284;2267.9659805102897;2299.5745787029455;2300.5628930503235;2301.1485750272423;2305.124052859919;2275.6358982562792;2311.237291693737;2313.3869524576003;2316.3051084674617;2322.2104484475276;2252.977470225301;2270.3302525754943;2276.6243569810963;2287.897044632987;2295.2999335202758;2189.37602783206;2242.8654280743126;2267.9319620411416;2271.852329598579;2283.851549649276;1928.7203355513511;2002.8597998140135;2261.5363850410627;2272.874991875446;2295.97704022961;8244.216799404106;8367.623456944391;8448.957755211246;8512.616932238003;8531.530765807878;5054.418741844698;5185.818496352647;5217.2695822584865;5217.686938783647;5238.895222095537;3352.5641666583433;3372.1738174147245;3395.43479346685;3416.6754158355166;3444.51776751255;1156.2022204678829;1184.8222766584995;1185.9288442693467;1189.7798907202196;1216.6046035038648;10651.094656954168;10677.878822723658;10759.246852720235;10803.513841877135;10824.78350432987;4106.1582375612925;4364.388985542027;4445.377647129404;4451.9554804451545;4458.086092351125;8597.52713600758;8689.391742577958;8706.303495720327;8724.040355560883;8735.91264087351;4268.2743933530755;5108.923366149511;5110.054639891632;5111.565516931102;5121.974390128025;3333.599968003855;3803.2583646188727;4696.85909422711;4783.330333753277;4789.4971028042255;3078.553609726577;3558.21719221776;3558.822058897045;3622.583402908699;3623.4927110204594;988.5353883674661;1141.178351586856;1148.534843388429;1148.8851114888612;1166.7199872030822;780.7598466364519;824.4270232188571;832.342449464928;847.0809172048397;858.5835869603237;5264.15773476242;5292.5501332387175;5494;5558.666480011212;5586.385497595261;575.3872126185682;577.5985689945531;583.1806312131462;594.1414655822296;595.5325069820257;3526.1360966563357;3546.414841551167;3626.8549258029384;3721.0883333583447;3733.4026555751493;8339.499630022217;8653;9488.762780930532;9509.714708558617;9864.605415783288;9674.951625241829;9875.851862222073;9937.900620993842;9950.552225149886;9960.701178964642;6840.418564421981;7858.332041776513;7874.763757087297;7879.369650427952;7912.32745216663;5530.668159910419;5536.584756143225;5546.889062218653;5600.271964644733;5618.775248989995;8909.643614255358;8949.552522373726;8975.551222438722;9211.953940230256;9220.077992200726;8791.296696264155;9098.727038188865;9312;9336.719898403058;9391.29565282593;6317.736452709569;7085.893711594434;7167.7132914682825;7265.782026539212;7277.59973201479;6692.6988285526295;7547.338690428912;7842.372610191057;7874.921250787419;8002.639881205476;7013.438924885895;7036.050133231948;7071.222165561887;7176.677049532679;7228.277172282729;7003.824904377431;7014.894776578253;7058.200158959433;7064.152301723826;7085.114360704912;6335.334789847;6343.619382837044;6363.5545511813725;6380.553361264667;6408.102865598913;4920.827771027997;4977.328060711758;5014.373203349582;5015.695919060998;5098.97450512745;4887.315775791463;5043.596512279082;5052.72210028452;5092.8472145835685;5145.3568303962 12:26:49 INFO - PROCESS | 1650 | |4;prototype.html;296.2578740255659;305.41970256512633;368.67004031391906;373.1175769305592;379.3778203745857;5397.325334333208;5403.649412505562;5439.891202175959;5461.836144915659;5487;5057.443681195065;5362.839114826552;5376.8118115865955;5386.78452861886;5387.4612538746105;3990.68074554035;4052.7365721228175;4053.9257096869355;4064.9390259146135;4100.241455330766;3906.785126818024;4052.5947405259385;4053.4122552230056;4062.451569038172;4085.305498065336;3742.0644838790304;3849.8845034648866;4055.5538890721996;4057.8985525361954;4085.6527195188446;3386.339663765567;3862.7102967277424;4011.959880401188;4056.4726585543985;4084.97957510212;2726.5910113482937;3420.931581368359;3476.0614634048916;3479.565054368204;3490.2670439207795;5253.343332083489;5259.658122222043;5268.024490815944;5272.288241087443;5304.496072873071;4400;4638.397008388922;4640.60554852838;4645.976770116162;4679.344891715163;5212.706409179948;5230.529252367305;5629.662220266797;5658.236138121383;5700.942990570083;3250.3499300140065;3833.5016447861876;3845;3846.288436639231;3855.4987851579394;4873.537013983666;5322.361316642027;5630.679008337123;5999.580029397944;6039.78860739872;3284.8850290239725;3430.936409712997;3568.3933731265747;3568.946465803015;3726.8136593170234;3467.393206188907;3889.8055097245024;3910.8267519743963;3922.2155568885764;3931.4299426582984;4077.510698716114;4079.857639860844;4562.543745625411;4583.931241031387;4625.838095666635;4772.7136371817105;5428.75570599324;5722.856928576735;5735.139729040678;5743.655380677173;1250.4498020870788;1280.3022352817736;1286.350393051503;1287.3949243855561;1302.4855182203166;778.7002004228412;824.2499325613783;842.9873551896726;866.1165611076666;876.2026555834287;4459.866204013885;4542.341360502709;4733.077049975222;5154.304168937146;5162.6386152968935;5669.574781891375;5702.800401985911;5717.599768016199;5721.342045664718;5741.540676745851;4696.929546056812;4794.544518270759;5063.797448102035;5118.515630467123;5146.794128234904;2477.6462360757355;2826.4771017361854;2914.3005678636973;2934.310437047292;2935.163478408643;5259.763955470461;5589.329280486368;5798.275215598051;5821.76712931478;5844.649321040752;4797.784099715521;4943.2585112233455;5236.345456817898;5435.402105768362;5696.51579615729;5858.882822343615;5908.970455147697;6028.2766068072115;6039.8792024159275;6141.570090093651;5121.129408000575;5194.402643695872;5609.467100625434;5930.34766175729;5933.406659334032;5104.464031276811;5520.420355862577;5707.971460142673;5772.546667666141;5825.533957283323;5198.946416165669;6021.698915054318;6048.758049678139;6074.3925607438905;6121.693915304306;3188.473901806176;3613.95195393333;3854.383298672199;3874.3219936510436;3893.6301051400073;5155.53600175986;5594.244776955059;5742.454466825645;5847.064469685;5888.057910734262;5243.68537887728;5287.52412282881;5305.787768489217;5689.203511508309;5755;5516.751746171512;5617.522510586646;5624.550035997192;5678.403767604342;5716.456936591017;5484.2596249507405;5494.230807686847;5547.583931205257;5560.833175004756;5573.860653483695;5014.122528557561;5057.6459647824295;5138.665986710852;5221.746780772663;5267.525922666979;4839;4988.406028864897;5094.363204599425;5216.37403511581;5511.200875873056 12:26:49 INFO - PROCESS | 1650 | |5;yui.html;642.0754114075734;678.2166597579798;751.6542390500371;757.9658915348808;825.7976795685056;14644.048136871095;15333.846661533382;15385.769213461806;15385.999910005841;15411.07533547988;14140.717185656307;14261.144331340127;14289.928550357234;14330.57008289753;14351.206099237595;896.968606098785;899.1098812175932;900.6397441023611;900.9819803603924;902.1745103230536;912.7535565397338;916.138829500271;920.8250432417852;922.8477301245287;925.7315378540216;977.325645304741;979.7256768104942;980.9362391444533;981.2983716642599;982.1308142294085;967.1247520993512;970.0396607358734;972.562346943871;973.4256788494787;973.5764942250144;718.9137303523559;719.9748008819719;721.6355740351141;722.1695050691694;723.9674214660353;15403.537893863202;15614.985025973276;15657.686846263012;15674.059556426537;15876.60308492297;13630.727385452334;13897.305134743323;13980.091294065853;13998.860011399858;14196.432142714279;11385.54457821686;11414.372209528472;11421.885781142248;11513.597024104116;11855.05159587231;3278.426275401795;3636.2000359920776;3672.338978983782;3750.4374343848367;3764.548254209485;103481;105167.94832051735;105820.94179058188;107197.38494349344;107447.46276268647;10452.581896724047;10522.789544209072;10594.735131621772;10602.151827853753;10913.017828395483;21895.01472433744;22261.55476890453;22335.888320558293;22515.099396023976;22543.210987615355;7468.178500364956;7491.250874912575;7520.022397088453;7581.355584775246;7772.572508511973;4082.3468245080644;4173.207090652827;4193.454850869368;4193.685473589493;4196.958030419718;3513.4554144107706;3562.0560551453887;3601.369760291991;3616.674499295076;3621.203335266237;655.4101308822098;656.6782276684486;669.855980964095;670.9530332876749;672.8284287506752;650.4243744286341;671.412514050206;673.3703986772383;684.3327755438407;703.6953975729425;9456.290778191526;9651.372660777028;9954.203663706887;9970;9996.150327222122;629.7134803664321;672.7241830849329;683.6642822514448;684.8082536889679;686.5228666077128;4447.221736196153;4798.446331200303;4912.901741965141;5323.148296272578;5340.2256672783005;95321.0467895326;95584;96399.51800240955;97749;98766.5061674687;54047;57127.71436142793;83408.58295708604;84822;86241.56879215564;70319.24201894994;71484.64257678678;72445;72909.27090729131;74400.62799685966;10245.897541024495;10609.416482093558;10739.570417183226;10807.891921080689;10811.72970675708;46175;47058.52941470678;47700.761496192295;47797.76101119472;47849.8037549064;50822.74588627181;61933.69033154986;63224.68387658216;65496;65989;12809.56701521587;12914.773096555813;13408.597742067754;13652.931735341259;13886.305684715926;11418.257813242113;14267.143971361751;14350.641233969234;14397.352119154453;14822.110673359633;12818.102732808988;12856.292903890377;13262.602121936357;13380.330983450984;14125.858741412454;12952.740945181233;13217.603471895858;13254.403551839998;13431.597052088453;13902.51341203053;12968.870311296767;13159.144655597738;13797.931010344884;13938.790918136034;14181.220032898289;9930.553125109533;10776.515056822618;10947.726306842393;11107.555697771999;11761.529538818351;8253.546054967035;8411.411201215857;9590.568424420779;9853.802923941625;11258.211925165488 12:26:49 INFO - PROCESS | 1650 | __end_tp_report 12:26:49 INFO - PROCESS | 1650 | __start_cc_report 12:26:49 INFO - PROCESS | 1650 | _x_x_mozilla_cycle_collect,424 12:26:49 INFO - PROCESS | 1650 | __end_cc_report 12:26:49 INFO - PROCESS | 1650 | __startTimestamp1488054409158__endTimestamp 12:26:49 INFO - PROCESS | 1650 | 12:26:49 INFO - PROCESS | 1650 | ------- Summary: start ------- 12:26:49 INFO - PROCESS | 1650 | Number of tests: 6 12:26:49 INFO - PROCESS | 1650 | 12:26:49 INFO - PROCESS | 1650 | [#0] dojo.html Cycles:165 Average:5589.56 Median:5746.77 stddev:664.39 (11.6%) stddev-sans-first:635.44 12:26:49 INFO - PROCESS | 1650 | Values: 3033.4 3270.7 3295.9 3320.0 3325.1 5510.9 5561.8 5566.4 5570.9 5641.9 5522.4 5576.3 5582.4 5608.9 5678.1 4175.7 4817.3 5096.8 5156.4 5265.0 4188.6 5040.2 5127.4 5189.5 5233.0 5051.4 5118.4 5163.3 5173.8 5204.5 5104.8 5117.4 5123.8 5161.1 5167.9 4971.2 4980.5 5093.6 5094.6 5105.5 5387.5 5449.3 5492.0 5599.4 5665.9 6095.0 6138.6 6142.2 6150.9 6163.5 6000.8 6204.1 6217.9 6220.8 6236.5 5525.2 5536.7 5599.5 5606.0 5610.5 5844.6 6111.6 6146.9 6198.9 6217.5 5744.7 5747.7 5814.8 5928.2 6110.8 4777.2 6037.7 6114.8 6119.2 6139.4 6072.7 6133.9 6149.8 6154.2 6182.5 5272.8 5824.8 5891.1 6225.2 6235.1 3977.2 4800.0 4808.9 4861.3 4896.6 4376.0 4379.3 4398.0 4405.3 4407.3 4742.3 5643.2 5681.1 5685.9 5690.5 5963.4 6038.2 6077.3 6129.5 6229.7 5587.1 5605.4 5614.4 5621.0 5703.0 5317.9 5352.5 5374.5 5387.8 5388.3 4698.6 6251.1 6254.2 6256.8 6262.8 5980.7 6057.0 6063.6 6125.5 6133.2 6176.1 6196.3 6240.8 6242.2 6249.8 6147.2 6159.3 6161.4 6165.5 6167.0 5757.6 6005.7 6237.4 6239.6 6244.6 5879.1 5894.0 5903.9 5952.0 5953.0 6071.8 6073.6 6079.1 6085.8 6106.9 5781.9 5785.1 5891.8 5903.4 5925.6 5741.3 5772.5 5813.5 6003.9 6086.8 5703.4 5723.2 5746.8 5750.8 5806.3 12:26:49 INFO - PROCESS | 1650 | 12:26:49 INFO - PROCESS | 1650 | [#1] ext.html Cycles:180 Average:52170.13 Median:172486.43 stddev:102528.76 (59.4%) stddev-sans-first:102743.79 12:26:49 INFO - PROCESS | 1650 | Values: 786.3 794.0 823.9 856.4 942.2 35675.6 38006.0 38157.6 38159.8 38253.0 36565.8 36731.4 36762.4 36777.8 36904.9 7428.3 7536.5 7728.3 7864.4 11059.9 10961.0 11259.5 11265.4 11280.3 11306.1 11219.3 11281.5 11283.0 11287.6 11290.9 11201.7 11229.3 11255.4 11271.9 11279.9 10934.2 10995.3 11012.4 11015.6 11048.7 37943.9 38060.6 38110.8 38176.8 38186.2 3679.5 3705.2 3716.8 3724.4 3852.9 26636.7 27357.5 27493.3 27791.7 27853.4 2820.2 2836.3 2929.4 2944.5 2986.6 312119.4 312515.4 313517.0 313562.4 316484.0 41125.0 41738.6 50196.7 51677.5 52323.0 45924.0 49660.8 51820.2 51869.7 51953.0 13563.7 13580.3 13661.9 13765.5 13807.7 11653.4 11695.2 11761.5 11800.0 12301.6 8834.5 9978.0 12138.4 12352.3 12364.6 5054.8 5254.3 5551.5 5557.2 5561.7 935.1 967.5 1063.4 1067.1 1088.5 25081.7 25250.0 25465.6 25543.9 25671.4 1065.5 1102.9 1117.8 1162.3 1233.5 13057.4 13188.3 13303.7 13344.1 13379.3 416502.0 417024.8 417451.0 419947.3 420383.0 52606.5 52619.9 52748.2 52749.9 53539.0 1358.6 1415.6 1418.2 1418.8 1421.9 24095.8 24154.9 24203.9 24513.4 24771.4 43441.3 43586.0 43646.3 43771.6 43787.9 416464.0 420156.0 421781.0 421800.0 421930.0 27345.5 31660.4 31843.0 32194.4 32615.3 39946.4 40411.4 40644.0 41180.0 41409.0 30227.4 30805.9 31137.5 31139.7 31410.4 33791.8 33839.0 33852.0 34824.5 34854.5 32784.5 34684.7 34722.5 34800.0 34813.8 25774.1 27966.0 28002.7 29424.7 29810.6 21504.6 23092.3 23380.8 23406.9 23500.1 12:26:49 INFO - PROCESS | 1650 | 12:26:49 INFO - PROCESS | 1650 | [#2] jquery.html Cycles:180 Average:37867.86 Median:22076.67 stddev:115537.51 (523.3%) stddev-sans-first:115827.35 12:26:49 INFO - PROCESS | 1650 | Values: 386.3 387.6 393.6 398.1 422.9 16422.7 20146.8 21095.6 21854.0 21903.6 12265.8 12646.4 12651.6 16193.5 22051.1 4367.3 4566.9 5420.3 5678.2 5687.2 5513.9 5534.4 5551.8 5573.4 5582.2 5329.3 5530.1 5583.9 5595.1 5607.7 5530.3 5536.7 5583.2 5588.7 5595.1 3992.2 5146.6 5302.7 5477.7 5477.9 13773.4 13817.4 13893.1 14283.9 25858.9 2575.4 2577.3 2577.8 2647.2 2666.4 10109.5 10115.5 10501.8 10533.8 12545.1 1705.4 1743.9 1804.2 1820.6 1821.9 97269.5 117569.0 119134.4 132120.3 146104.0 11051.6 11371.7 11423.7 11977.9 12011.5 14431.2 16122.3 16129.4 16133.6 16146.2 7062.4 8347.0 8552.2 8568.4 8577.1 8119.6 8135.6 8263.7 8270.3 8282.7 4808.6 4907.7 4912.4 4919.6 5024.4 2055.6 2088.1 2095.4 2097.9 2104.2 975.4 1010.0 1015.6 1028.4 1042.9 21477.5 21575.2 22102.2 23419.2 24112.0 881.5 884.5 950.0 1034.2 1040.6 10653.4 11024.8 11040.2 11104.9 11184.7 576840.0 704803.0 717244.4 723220.0 727508.0 122868.0 145133.0 157388.0 159271.0 164208.2 881.7 1061.1 1077.8 1079.7 1087.7 14111.9 16838.7 17163.2 17946.0 18326.7 40872.4 53606.7 54251.7 54317.7 54881.5 74591.0 76272.2 78331.0 81033.7 83117.0 7756.0 8464.1 8494.3 8622.3 8677.0 10843.8 13408.9 13625.8 13678.5 13678.6 5583.5 6120.5 11177.2 11270.2 11307.0 9854.5 9987.8 11397.4 11510.0 11689.8 11450.5 11646.9 11805.2 11852.3 11922.5 7235.7 7687.4 8362.2 8404.8 8701.3 10531.0 11159.0 11348.4 11399.6 11537.4 12:26:49 INFO - PROCESS | 1650 | 12:26:49 INFO - PROCESS | 1650 | [#3] mootools.html Cycles:180 Average:5319.65 Median:5393.28 stddev:2936.41 (54.4%) stddev-sans-first:2928.84 12:26:49 INFO - PROCESS | 1650 | Values: 1266.5 1330.1 1414.3 1430.4 1442.0 8095.5 8207.1 8498.5 8521.4 8521.4 8332.5 8489.0 8495.9 8502.8 8507.4 2268.0 2299.6 2300.6 2301.1 2305.1 2275.6 2311.2 2313.4 2316.3 2322.2 2253.0 2270.3 2276.6 2287.9 2295.3 2189.4 2242.9 2267.9 2271.9 2283.9 1928.7 2002.9 2261.5 2272.9 2296.0 8244.2 8367.6 8449.0 8512.6 8531.5 5054.4 5185.8 5217.3 5217.7 5238.9 3352.6 3372.2 3395.4 3416.7 3444.5 1156.2 1184.8 1185.9 1189.8 1216.6 10651.1 10677.9 10759.2 10803.5 10824.8 4106.2 4364.4 4445.4 4452.0 4458.1 8597.5 8689.4 8706.3 8724.0 8735.9 4268.3 5108.9 5110.1 5111.6 5122.0 3333.6 3803.3 4696.9 4783.3 4789.5 3078.6 3558.2 3558.8 3622.6 3623.5 988.5 1141.2 1148.5 1148.9 1166.7 780.8 824.4 832.3 847.1 858.6 5264.2 5292.6 5494.0 5558.7 5586.4 575.4 577.6 583.2 594.1 595.5 3526.1 3546.4 3626.9 3721.1 3733.4 8339.5 8653.0 9488.8 9509.7 9864.6 9675.0 9875.9 9937.9 9950.6 9960.7 6840.4 7858.3 7874.8 7879.4 7912.3 5530.7 5536.6 5546.9 5600.3 5618.8 8909.6 8949.6 8975.6 9212.0 9220.1 8791.3 9098.7 9312.0 9336.7 9391.3 6317.7 7085.9 7167.7 7265.8 7277.6 6692.7 7547.3 7842.4 7874.9 8002.6 7013.4 7036.1 7071.2 7176.7 7228.3 7003.8 7014.9 7058.2 7064.2 7085.1 6335.3 6343.6 6363.6 6380.6 6408.1 4920.8 4977.3 5014.4 5015.7 5099.0 4887.3 5043.6 5052.7 5092.8 5145.4 12:26:49 INFO - PROCESS | 1650 | 12:26:49 INFO - PROCESS | 1650 | [#4] prototype.html Cycles:180 Average:4521.17 Median:5150.55 stddev:1412.56 (27.4%) stddev-sans-first:1380.47 12:26:49 INFO - PROCESS | 1650 | Values: 296.3 305.4 368.7 373.1 379.4 5397.3 5403.6 5439.9 5461.8 5487.0 5057.4 5362.8 5376.8 5386.8 5387.5 3990.7 4052.7 4053.9 4064.9 4100.2 3906.8 4052.6 4053.4 4062.5 4085.3 3742.1 3849.9 4055.6 4057.9 4085.7 3386.3 3862.7 4012.0 4056.5 4085.0 2726.6 3420.9 3476.1 3479.6 3490.3 5253.3 5259.7 5268.0 5272.3 5304.5 4400.0 4638.4 4640.6 4646.0 4679.3 5212.7 5230.5 5629.7 5658.2 5700.9 3250.3 3833.5 3845.0 3846.3 3855.5 4873.5 5322.4 5630.7 5999.6 6039.8 3284.9 3430.9 3568.4 3568.9 3726.8 3467.4 3889.8 3910.8 3922.2 3931.4 4077.5 4079.9 4562.5 4583.9 4625.8 4772.7 5428.8 5722.9 5735.1 5743.7 1250.4 1280.3 1286.4 1287.4 1302.5 778.7 824.2 843.0 866.1 876.2 4459.9 4542.3 4733.1 5154.3 5162.6 5669.6 5702.8 5717.6 5721.3 5741.5 4696.9 4794.5 5063.8 5118.5 5146.8 2477.6 2826.5 2914.3 2934.3 2935.2 5259.8 5589.3 5798.3 5821.8 5844.6 4797.8 4943.3 5236.3 5435.4 5696.5 5858.9 5909.0 6028.3 6039.9 6141.6 5121.1 5194.4 5609.5 5930.3 5933.4 5104.5 5520.4 5708.0 5772.5 5825.5 5198.9 6021.7 6048.8 6074.4 6121.7 3188.5 3614.0 3854.4 3874.3 3893.6 5155.5 5594.2 5742.5 5847.1 5888.1 5243.7 5287.5 5305.8 5689.2 5755.0 5516.8 5617.5 5624.6 5678.4 5716.5 5484.3 5494.2 5547.6 5560.8 5573.9 5014.1 5057.6 5138.7 5221.7 5267.5 4839.0 4988.4 5094.4 5216.4 5511.2 12:26:49 INFO - PROCESS | 1650 | 12:26:49 INFO - PROCESS | 1650 | [#5] yui.html Cycles:180 Average:19438.26 Median:25311.11 stddev:27402.16 (108.3%) stddev-sans-first:27442.68 12:26:49 INFO - PROCESS | 1650 | Values: 642.1 678.2 751.7 758.0 825.8 14644.0 15333.8 15385.8 15386.0 15411.1 14140.7 14261.1 14289.9 14330.6 14351.2 897.0 899.1 900.6 901.0 902.2 912.8 916.1 920.8 922.8 925.7 977.3 979.7 980.9 981.3 982.1 967.1 970.0 972.6 973.4 973.6 718.9 720.0 721.6 722.2 724.0 15403.5 15615.0 15657.7 15674.1 15876.6 13630.7 13897.3 13980.1 13998.9 14196.4 11385.5 11414.4 11421.9 11513.6 11855.1 3278.4 3636.2 3672.3 3750.4 3764.5 103481.0 105167.9 105820.9 107197.4 107447.5 10452.6 10522.8 10594.7 10602.2 10913.0 21895.0 22261.6 22335.9 22515.1 22543.2 7468.2 7491.3 7520.0 7581.4 7772.6 4082.3 4173.2 4193.5 4193.7 4197.0 3513.5 3562.1 3601.4 3616.7 3621.2 655.4 656.7 669.9 671.0 672.8 650.4 671.4 673.4 684.3 703.7 9456.3 9651.4 9954.2 9970.0 9996.2 629.7 672.7 683.7 684.8 686.5 4447.2 4798.4 4912.9 5323.1 5340.2 95321.0 95584.0 96399.5 97749.0 98766.5 54047.0 57127.7 83408.6 84822.0 86241.6 70319.2 71484.6 72445.0 72909.3 74400.6 10245.9 10609.4 10739.6 10807.9 10811.7 46175.0 47058.5 47700.8 47797.8 47849.8 50822.7 61933.7 63224.7 65496.0 65989.0 12809.6 12914.8 13408.6 13652.9 13886.3 11418.3 14267.1 14350.6 14397.4 14822.1 12818.1 12856.3 13262.6 13380.3 14125.9 12952.7 13217.6 13254.4 13431.6 13902.5 12968.9 13159.1 13797.9 13938.8 14181.2 9930.6 10776.5 10947.7 11107.6 11761.5 8253.5 8411.4 9590.6 9853.8 11258.2 12:26:49 INFO - PROCESS | 1650 | -------- Summary: end -------- 12:26:49 INFO - PROCESS | 1650 | 12:26:49 INFO - TEST-INFO | 1650: exit 0 12:26:49 INFO - TEST-OK | dromaeo_css | took 1117349ms 12:26:49 INFO - TEST-START | kraken 12:26:49 INFO - operating with platform_type : mac_ 12:26:49 INFO - Initialising browser for kraken test... 12:26:49 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/tmp4hworD/profile http://localhost:49251/getInfo.html) 12:26:52 INFO - PROCESS | 1658 | 2017-02-25 12:26:52.739 plugin-container[1659:10813] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893f, name = 'com.apple.tsm.portname' 12:26:52 INFO - PROCESS | 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:26:52 INFO - PROCESS | 1658 | 2017-02-25 12:26:52.742 plugin-container[1659:10813] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 12:26:52 INFO - PROCESS | 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:26:52 INFO - PROCESS | 1658 | 2017-02-25 12:26:52.742 plugin-container[1659:10813] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:26:52 INFO - PROCESS | 1658 | __metrics Screen width/height:1600/1200 12:26:52 INFO - PROCESS | 1658 | colorDepth:24 12:26:52 INFO - PROCESS | 1658 | Browser inner width/height: 1024/689 12:26:52 INFO - PROCESS | 1658 | __metrics 12:26:52 ERROR - PROCESS | 1658 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3354: TypeError: this.tabs is undefined 12:26:53 INFO - TEST-INFO | 1658: exit 0 12:26:53 INFO - Browser initialized. 12:26:53 INFO - Running cycle 1/1 for kraken test... 12:26:53 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/tmp4hworD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:26:56 INFO - PROCESS | 1660 | 2017-02-25 12:26:56.302 plugin-container[1661:10941] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893f, name = 'com.apple.tsm.portname' 12:26:56 INFO - PROCESS | 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:26:56 INFO - PROCESS | 1660 | 2017-02-25 12:26:56.303 plugin-container[1661:10941] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 12:26:56 INFO - PROCESS | 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:26:56 INFO - PROCESS | 1660 | 2017-02-25 12:26:56.303 plugin-container[1661:10941] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:27:06 INFO - PROCESS | 1660 | RSS: Main: 227213312 12:27:06 INFO - PROCESS | 1660 | 12:27:09 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49251/tests/kraken/driver.html?testName=audio-beat-detection) 12:27:10 INFO - PROCESS | 1660 | RSS: Main: 240758784 12:27:10 INFO - PROCESS | 1660 | 12:27:12 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49251/tests/kraken/driver.html?testName=audio-dft) 12:27:13 INFO - PROCESS | 1660 | RSS: Main: 248594432 12:27:13 INFO - PROCESS | 1660 | 12:27:16 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49251/tests/kraken/driver.html?testName=audio-fft) 12:27:17 INFO - PROCESS | 1660 | RSS: Main: 251060224 12:27:17 INFO - PROCESS | 1660 | 12:27:19 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49251/tests/kraken/driver.html?testName=audio-oscillator) 12:27:20 INFO - PROCESS | 1660 | RSS: Main: 253329408 12:27:20 INFO - PROCESS | 1660 | 12:27:22 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49251/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:27:22 INFO - PROCESS | 1660 | RSS: Main: 253919232 12:27:22 INFO - PROCESS | 1660 | 12:27:26 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49251/tests/kraken/driver.html?testName=imaging-darkroom) 12:27:26 INFO - PROCESS | 1660 | RSS: Main: 259473408 12:27:26 INFO - PROCESS | 1660 | 12:27:29 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49251/tests/kraken/driver.html?testName=imaging-desaturate) 12:27:30 INFO - PROCESS | 1660 | RSS: Main: 259092480 12:27:30 INFO - PROCESS | 1660 | 12:27:33 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49251/tests/kraken/driver.html?testName=json-parse-financial) 12:27:34 INFO - PROCESS | 1660 | RSS: Main: 259158016 12:27:34 INFO - PROCESS | 1660 | 12:27:36 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49251/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:27:36 INFO - PROCESS | 1660 | RSS: Main: 258600960 12:27:36 INFO - PROCESS | 1660 | 12:27:38 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:27:39 INFO - PROCESS | 1660 | RSS: Main: 258502656 12:27:39 INFO - PROCESS | 1660 | 12:27:41 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:27:42 INFO - PROCESS | 1660 | RSS: Main: 258662400 12:27:42 INFO - PROCESS | 1660 | 12:27:45 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:27:46 INFO - PROCESS | 1660 | RSS: Main: 258871296 12:27:46 INFO - PROCESS | 1660 | 12:27:49 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:27:49 INFO - PROCESS | 1660 | RSS: Main: 258678784 12:27:49 INFO - PROCESS | 1660 | 12:27:51 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49251/tests/kraken/driver.html?testName=ai-astar) 12:27:51 INFO - PROCESS | 1660 | RSS: Main: 262205440 12:27:51 INFO - PROCESS | 1660 | 12:27:51 INFO - PROCESS | 1660 | __start_tp_report 12:27:51 INFO - PROCESS | 1660 | _x_x_mozilla_page_load 12:27:51 INFO - PROCESS | 1660 | _x_x_mozilla_page_load_details 12:27:51 INFO - PROCESS | 1660 | |i|pagename|runs| 12:27:51 INFO - PROCESS | 1660 | |0;ai-astar;105;118;118;120;117;118;130;118;117;121 12:27:51 INFO - PROCESS | 1660 | |1;audio-beat-detection;152;120;129;150;119;145;96;99;173;144 12:27:51 INFO - PROCESS | 1660 | |2;audio-dft;206;217;208;211;258;286;221;195;200;198 12:27:51 INFO - PROCESS | 1660 | |3;audio-fft;81;60;61;105;85;51;52;52;71;69 12:27:51 INFO - PROCESS | 1660 | |4;audio-oscillator;87;75;85;70;74;72;83;72;70;83 12:27:51 INFO - PROCESS | 1660 | |5;imaging-gaussian-blur;123;117;97;88;113;104;97;136;88;89 12:27:51 INFO - PROCESS | 1660 | |6;imaging-darkroom;96;96;95;99;95;97;97;96;101;96 12:27:51 INFO - PROCESS | 1660 | |7;imaging-desaturate;146;115;92;94;90;91;93;92;123;86 12:27:51 INFO - PROCESS | 1660 | |8;json-parse-financial;68;68;66;68;69;68;67;70;68;67 12:27:51 INFO - PROCESS | 1660 | |9;json-stringify-tinderbox;64;62;65;75;69;64;63;64;66;65 12:27:51 INFO - PROCESS | 1660 | |10;stanford-crypto-aes;88;79;91;106;107;82;110;108;107;91 12:27:51 INFO - PROCESS | 1660 | |11;stanford-crypto-ccm;143;146;144;137;145;148;141;149;212;169 12:27:51 INFO - PROCESS | 1660 | |12;stanford-crypto-pbkdf2;174;173;185;157;210;235;240;176;176;174 12:27:51 INFO - PROCESS | 1660 | |13;stanford-crypto-sha256-iterative;71;59;82;87;91;74;73;67;70;84 12:27:51 INFO - PROCESS | 1660 | __end_tp_report 12:27:51 INFO - PROCESS | 1660 | __start_cc_report 12:27:51 INFO - PROCESS | 1660 | _x_x_mozilla_cycle_collect,1002 12:27:51 INFO - PROCESS | 1660 | __end_cc_report 12:27:51 INFO - PROCESS | 1660 | __startTimestamp1488054471942__endTimestamp 12:27:51 INFO - PROCESS | 1660 | 12:27:51 INFO - PROCESS | 1660 | ------- Summary: start ------- 12:27:51 INFO - PROCESS | 1660 | Number of tests: 14 12:27:51 INFO - PROCESS | 1660 | 12:27:51 INFO - PROCESS | 1660 | [#0] ai-astar Cycles:10 Average:118.20 Median:118.00 stddev:6.03 (5.1%) stddev-sans-first:4.09 12:27:51 INFO - PROCESS | 1660 | Values: 105.0 118.0 118.0 120.0 117.0 118.0 130.0 118.0 117.0 121.0 12:27:51 INFO - PROCESS | 1660 | 12:27:51 INFO - PROCESS | 1660 | [#1] audio-beat-detection Cycles:10 Average:132.70 Median:151.00 stddev:24.54 (16.3%) stddev-sans-first:25.02 12:27:51 INFO - PROCESS | 1660 | Values: 152.0 120.0 129.0 150.0 119.0 145.0 96.0 99.0 173.0 144.0 12:27:51 INFO - PROCESS | 1660 | 12:27:51 INFO - PROCESS | 1660 | [#2] audio-dft Cycles:10 Average:220.00 Median:214.00 stddev:29.33 (13.7%) stddev-sans-first:30.66 12:27:51 INFO - PROCESS | 1660 | Values: 206.0 217.0 208.0 211.0 258.0 286.0 221.0 195.0 200.0 198.0 12:27:51 INFO - PROCESS | 1660 | 12:27:51 INFO - PROCESS | 1660 | [#3] audio-fft Cycles:10 Average:68.70 Median:65.00 stddev:17.47 (26.9%) stddev-sans-first:17.95 12:27:51 INFO - PROCESS | 1660 | Values: 81.0 60.0 61.0 105.0 85.0 51.0 52.0 52.0 71.0 69.0 12:27:51 INFO - PROCESS | 1660 | 12:27:51 INFO - PROCESS | 1660 | [#4] audio-oscillator Cycles:10 Average:77.10 Median:79.00 stddev:6.64 (8.4%) stddev-sans-first:6.00 12:27:51 INFO - PROCESS | 1660 | Values: 87.0 75.0 85.0 70.0 74.0 72.0 83.0 72.0 70.0 83.0 12:27:51 INFO - PROCESS | 1660 | 12:27:51 INFO - PROCESS | 1660 | [#5] imaging-gaussian-blur Cycles:10 Average:105.20 Median:88.00 stddev:16.52 (18.8%) stddev-sans-first:16.22 12:27:51 INFO - PROCESS | 1660 | Values: 123.0 117.0 97.0 88.0 113.0 104.0 97.0 136.0 88.0 89.0 12:27:51 INFO - PROCESS | 1660 | 12:27:51 INFO - PROCESS | 1660 | [#6] imaging-darkroom Cycles:10 Average:96.80 Median:96.00 stddev:1.87 (2.0%) stddev-sans-first:1.96 12:27:51 INFO - PROCESS | 1660 | Values: 96.0 96.0 95.0 99.0 95.0 97.0 97.0 96.0 101.0 96.0 12:27:51 INFO - PROCESS | 1660 | 12:27:51 INFO - PROCESS | 1660 | [#7] imaging-desaturate Cycles:10 Average:102.20 Median:91.50 stddev:19.47 (21.3%) stddev-sans-first:12.65 12:27:51 INFO - PROCESS | 1660 | Values: 146.0 115.0 92.0 94.0 90.0 91.0 93.0 92.0 123.0 86.0 12:27:51 INFO - PROCESS | 1660 | 12:27:51 INFO - PROCESS | 1660 | [#8] json-parse-financial Cycles:10 Average:67.90 Median:68.00 stddev:1.10 (1.6%) stddev-sans-first:1.17 12:27:51 INFO - PROCESS | 1660 | Values: 68.0 68.0 66.0 68.0 69.0 68.0 67.0 70.0 68.0 67.0 12:27:51 INFO - PROCESS | 1660 | 12:27:51 INFO - PROCESS | 1660 | [#9] json-stringify-tinderbox Cycles:10 Average:65.70 Median:65.00 stddev:3.77 (5.8%) stddev-sans-first:3.95 12:27:51 INFO - PROCESS | 1660 | Values: 64.0 62.0 65.0 75.0 69.0 64.0 63.0 64.0 66.0 65.0 12:27:51 INFO - PROCESS | 1660 | 12:27:51 INFO - PROCESS | 1660 | [#10] stanford-crypto-aes Cycles:10 Average:96.90 Median:80.50 stddev:11.89 (14.8%) stddev-sans-first:12.17 12:27:51 INFO - PROCESS | 1660 | Values: 88.0 79.0 91.0 106.0 107.0 82.0 110.0 108.0 107.0 91.0 12:27:51 INFO - PROCESS | 1660 | 12:27:51 INFO - PROCESS | 1660 | [#11] stanford-crypto-ccm Cycles:10 Average:153.40 Median:147.00 stddev:22.29 (15.2%) stddev-sans-first:23.32 12:27:51 INFO - PROCESS | 1660 | Values: 143.0 146.0 144.0 137.0 145.0 148.0 141.0 149.0 212.0 169.0 12:27:51 INFO - PROCESS | 1660 | 12:27:51 INFO - PROCESS | 1660 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:190.00 Median:180.50 stddev:28.35 (15.7%) stddev-sans-first:29.47 12:27:51 INFO - PROCESS | 1660 | Values: 174.0 173.0 185.0 157.0 210.0 235.0 240.0 176.0 176.0 174.0 12:27:51 INFO - PROCESS | 1660 | 12:27:51 INFO - PROCESS | 1660 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.80 Median:78.00 stddev:9.94 (12.7%) stddev-sans-first:10.39 12:27:51 INFO - PROCESS | 1660 | Values: 71.0 59.0 82.0 87.0 91.0 74.0 73.0 67.0 70.0 84.0 12:27:51 INFO - PROCESS | 1660 | -------- Summary: end -------- 12:27:51 INFO - PROCESS | 1660 | 12:27:53 INFO - TEST-INFO | 1660: exit 0 12:27:53 INFO - TEST-OK | kraken | took 63205ms 12:27:53 INFO - SUITE-END | took 1180s 12:27:53 INFO - Completed test suite (00:19:40) 12:27:53 INFO - javascript benchmark 12:27:53 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7054.586020129666, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3033.4084853453573, 3270.7219886309663, 3295.91760205995, 3320.020593924792, 3325.102222399952, 5510.889782204354, 5561.77752889884, 5566.359868615111, 5570.941521110996, 5641.887162256752, 5522.447755224475, 5576.302962129734, 5582.441755824416, 5608.859778505539, 5678.119891416837, 4175.665946724254, 4817.301491283779, 5096.79612815487, 5156.407013193493, 5265.0, 4188.623023927846, 5040.243963405482, 5127.359080114986, 5189.455107213745, 5232.973835130819, 5051.444341122474, 5118.385793704742, 5163.302954101186, 5173.76718047685, 5204.479552044765, 5104.846854594368, 5117.360329958755, 5123.820666276662, 5161.148410512261, 5167.948320516784, 4971.1549036663855, 4980.477049909779, 5093.617978651579, 5094.61790365724, 5105.489451054902, 5387.4612538746205, 5449.3188351456065, 5491.972540137313, 5599.384067752544, 5665.886682266373, 6094.994325936215, 6138.631682099089, 6142.232220972379, 6150.923588372017, 6163.506919446433, 6000.789972350991, 6204.100405441231, 6217.937820621781, 6220.7511699532415, 6236.501079913596, 5525.226468294442, 5536.667799932017, 5599.468050535193, 5606.046972014727, 5610.5231055360755, 5844.590878638454, 6111.591738512148, 6146.938530614726, 6198.938010619836, 6217.471514921192, 5744.684042377626, 5747.741351639186, 5814.767409303665, 5928.170056192136, 6110.7555697771595, 4777.235642297216, 6037.6679282639, 6114.816555503342, 6119.173911521976, 6139.369480960552, 6072.696365181723, 6133.944507141707, 6149.754009839646, 6154.169187159766, 6182.505399568114, 5272.839975205448, 5824.805914787458, 5891.145783861316, 6225.221847269091, 6235.095911092866, 3977.244323578511, 4800.015996720664, 4808.903821923542, 4861.343718597944, 4896.559309662148, 4376.037271800199, 4379.3430985351815, 4397.9560204398185, 4405.339199120158, 4407.294832826732, 4742.312364707098, 5643.1535269709875, 5681.147827825776, 5685.914711279334, 5690.5447564194765, 5963.4334738199805, 6038.215032045894, 6077.301110372276, 6129.509639228941, 6229.719662615194, 5587.078132108238, 5605.439456054362, 5614.382417934024, 5620.971895140498, 5702.971485142631, 5317.946820531823, 5352.464753524694, 5374.516293533601, 5387.838364849061, 5388.326459192601, 4698.647601429906, 6251.062340648939, 6254.186955695731, 6256.7810126645345, 6262.812115636538, 5980.730867110903, 6056.9697151513965, 6063.605865618721, 6125.479334256638, 6133.233345831771, 6176.104464852661, 6196.318404975359, 6240.781572644935, 6242.188515492957, 6249.843753906189, 6147.200863887666, 6159.29168145669, 6161.353057928853, 6165.475934545614, 6167.0, 5757.625754325955, 6005.669688167192, 6237.407446292595, 6239.594426362272, 6244.594101383395, 5879.088741245114, 5893.970530147321, 5903.940960590511, 5952.0, 5953.0, 6071.7571297147615, 6073.635581865102, 6079.088136779518, 6085.756569737338, 6106.877862442638, 5781.855453613525, 5785.132230165509, 5891.793787217427, 5903.439173278532, 5925.644461332334, 5741.339745929272, 5772.509336706259, 5813.505852002627, 6003.939960600338, 6086.786962456293, 5703.372629010889, 5723.170140329546, 5746.770129194785, 5750.827475175752, 5806.332271788629], "value": 5547.737528325187, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [786.3276898251994, 793.9603019849008, 823.9134890836463, 856.3620103023245, 942.1708896171368, 35675.64324356756, 38006.0, 38157.61842381579, 38159.809200953954, 38253.04367390816, 36565.81717091411, 36731.44902826453, 36762.44856327151, 36777.816110919404, 36904.89285321445, 7428.294312040348, 7536.4724469287175, 7728.343090837286, 7864.370850331959, 11059.944700276528, 10961.0, 11259.493322800452, 11265.380404077772, 11280.266782659142, 11306.095512359032, 11219.326840389604, 11281.54873805051, 11282.969065568195, 11287.604933827317, 11290.943545282304, 11201.66395008151, 11229.27009744364, 11255.380954047565, 11271.872812718744, 11279.887201128047, 10934.179936504794, 10995.285306455055, 11012.449377531091, 11015.559377624886, 11048.723781905437, 37943.86168414952, 38060.61939380599, 38110.80944595287, 38176.80911595452, 38186.23627527462, 3679.4664773607947, 3705.184859330943, 3716.7769933804057, 3724.3854763963914, 3852.8651497197743, 26636.733632663618, 27357.452850943067, 27493.31266718328, 27791.722082779117, 27853.442931141264, 2820.1539538138477, 2836.2767494289033, 2929.3994731080293, 2944.455275773989, 2986.611740473746, 312119.4394028038, 312515.4361879243, 313517.0, 313562.4321878399, 316484.0, 41124.97187570335, 41738.582614174076, 50196.74901625542, 51677.48322516727, 52322.95354092973, 45924.0, 49660.75848103826, 51820.22269665958, 51869.7406512965, 51952.96094078097, 13563.728725425437, 13580.320983950764, 13661.931690341484, 13765.518206862711, 13807.723845523033, 11653.41732913351, 11695.181337306478, 11761.529538818522, 11799.997000254903, 12301.630951071482, 8834.514101724468, 9978.0, 12138.393080346124, 12352.320622365676, 12364.62906112818, 5054.82308119214, 5254.316938798008, 5551.528120109836, 5557.249771280906, 5561.694106824163, 935.0555938502162, 967.5210770668564, 1063.409807556798, 1067.055655744656, 1088.5319312695458, 25081.749182508305, 25250.0, 25465.61801572978, 25543.87228063848, 25671.358216044748, 1065.4832406282947, 1102.8897110288906, 1117.8486159255979, 1162.3200427749691, 1233.5004323249098, 13057.412416441282, 13188.340582970814, 13303.66740831487, 13344.065915386023, 13379.331033448289, 416502.0, 417024.8297516986, 417451.0, 419947.3044791166, 420383.0, 52606.473935260925, 52619.94760104853, 52748.20877686838, 52749.94500110053, 53539.0, 1358.5720498043086, 1415.6390120519202, 1418.184543887269, 1418.7800890861727, 1421.8507056758892, 24095.75904240935, 24154.87922560376, 24203.878980604983, 24513.387165320295, 24771.380715482257, 43441.3483797737, 43586.0, 43646.34530481982, 43771.56228437802, 43787.9053023677, 416464.0, 420156.0, 421781.0, 421800.0, 421930.0, 27345.453090937674, 31660.366792663557, 31843.0, 32194.356112877143, 32615.347693046482, 39946.40080398738, 40411.393829092, 40644.0, 41180.0, 41409.0, 30227.395452090394, 30805.921792737146, 31137.532937006417, 31139.688603114584, 31410.429478525526, 33791.83104084464, 33839.0, 33851.984440466826, 34824.47763283502, 34854.477182842784, 32784.50823237606, 34684.65315346814, 34722.47916281207, 34799.95600132, 34813.82593087018, 25774.09790657318, 27966.0, 28002.719972800012, 29424.705752942198, 29810.552841706958, 21504.56990860143, 23092.307230783103, 23380.76619233786, 23406.946687398766, 23500.059997599903], "value": 18138.03098021914, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [386.277660774352, 387.59689922480624, 393.63981956509804, 398.14796335841294, 422.86891063770236, 16422.67154656906, 20146.798532014676, 21095.578088438222, 21854.0, 21903.561928761414, 12265.816012759793, 12646.430910609022, 12651.62045138645, 16193.514194574152, 22051.11795528181, 4367.2575662137515, 4566.949601591636, 5420.268263784398, 5678.205051292823, 5687.232223649796, 5513.9448605513835, 5534.446555344473, 5551.750171242304, 5573.359063707665, 5582.190582365554, 5329.307190065286, 5530.087535556629, 5583.888322233553, 5595.076812325961, 5607.719614019303, 5530.308711411074, 5536.695481748502, 5583.162525621149, 5588.658721906754, 5595.076812325961, 3992.161646054332, 5146.562542183919, 5302.655327403706, 5477.698726570077, 5477.890442191174, 13773.449062037607, 13817.447302107903, 13893.096948698303, 14283.857161428357, 25858.87070564654, 2575.3947822261753, 2577.342777591702, 2577.7551132642557, 2647.2190703742444, 2666.3734022504696, 10109.545070471846, 10115.494225288707, 10501.789964200672, 10533.84199237012, 12545.1218414711, 1705.3945849223471, 1743.9302427902996, 1804.224183601051, 1820.59036716739, 1821.927122915095, 97269.513652432, 117569.0, 119134.40432797781, 132120.33939830336, 146104.0, 11051.613193538187, 11371.658850234506, 11423.657290281304, 11977.880221197851, 12011.519539218509, 14431.2062836545, 16122.274497647633, 16129.354825807071, 16133.596660083356, 16146.192690365435, 7062.435005199572, 8346.958265208636, 8552.246789408156, 8568.357373197035, 8577.099404562556, 8119.553424561587, 8135.552544610104, 8263.669453221804, 8270.338372930151, 8282.668693252203, 4808.61531077513, 4907.6564640475535, 4912.410510738734, 4919.606431485473, 5024.447310795809, 2055.5991581641733, 2088.070808490207, 2095.402810199086, 2097.9370618881458, 2104.2109209046607, 975.3660120921458, 1009.9697009089739, 1015.5683834370363, 1028.3726926574784, 1042.8997407734912, 21477.463063423227, 21575.174702830147, 22102.22642207515, 23419.180328688413, 24112.03551857941, 881.4667126388571, 884.4869975414244, 949.9952500237592, 1034.1623285139062, 1040.5889673579045, 10653.360798352123, 11024.779504410028, 11040.178370568927, 11104.945030222116, 11184.720381990353, 576840.0, 704803.0, 717244.4137779382, 723220.0, 727508.0, 122868.0, 145133.0, 157388.0, 159271.0, 164208.17895910444, 881.6914080071923, 1061.124572227915, 1077.8383242513685, 1079.7192729890128, 1087.7117563845745, 14111.941604379917, 16838.66322673564, 17163.227654755316, 17946.0, 18326.725099123258, 40872.38691419572, 53606.73196633992, 54251.72874135604, 54317.72841135927, 54881.45118548923, 74591.0, 76272.23727762874, 78331.0, 81033.73280736733, 83117.0, 7755.961220193863, 8464.068952415106, 8494.32045436376, 8622.310215182644, 8676.956615216883, 10843.797909234954, 13408.865911341152, 13625.79561306601, 13678.452861885413, 13678.58372804539, 5583.469570390806, 6120.510359171345, 11177.155769297975, 11270.211085223955, 11307.0, 9854.458004809805, 9987.800243995225, 11397.430128493708, 11510.0, 11689.766204676029, 11450.541978321107, 11646.855349055539, 11805.173637845268, 11852.348120853436, 11922.52309907594, 7235.746748863765, 7687.423443241667, 8362.163783621589, 8404.789880253042, 8701.260392866678, 10531.0, 11159.0, 11348.375839328915, 11399.601013964471, 11537.365444900612], "value": 9471.365469206592, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1266.4870727355421, 1330.1420583723495, 1414.2645824171425, 1430.4421275702477, 1441.963950901228, 8095.514269143848, 8207.056188538305, 8498.532580708059, 8521.360897932656, 8521.446106003106, 8332.541710205936, 8489.023762267341, 8495.872561911576, 8502.829943401144, 8507.404481686284, 2267.9659805102897, 2299.5745787029455, 2300.5628930503235, 2301.1485750272423, 2305.124052859919, 2275.6358982562792, 2311.237291693737, 2313.3869524576003, 2316.3051084674617, 2322.2104484475276, 2252.977470225301, 2270.3302525754943, 2276.6243569810963, 2287.897044632987, 2295.2999335202758, 2189.37602783206, 2242.8654280743126, 2267.9319620411416, 2271.852329598579, 2283.851549649276, 1928.7203355513511, 2002.8597998140135, 2261.5363850410627, 2272.874991875446, 2295.97704022961, 8244.216799404106, 8367.623456944391, 8448.957755211246, 8512.616932238003, 8531.530765807878, 5054.418741844698, 5185.818496352647, 5217.2695822584865, 5217.686938783647, 5238.895222095537, 3352.5641666583433, 3372.1738174147245, 3395.43479346685, 3416.6754158355166, 3444.51776751255, 1156.2022204678829, 1184.8222766584995, 1185.9288442693467, 1189.7798907202196, 1216.6046035038648, 10651.094656954168, 10677.878822723658, 10759.246852720235, 10803.513841877135, 10824.78350432987, 4106.1582375612925, 4364.388985542027, 4445.377647129404, 4451.9554804451545, 4458.086092351125, 8597.52713600758, 8689.391742577958, 8706.303495720327, 8724.040355560883, 8735.91264087351, 4268.2743933530755, 5108.923366149511, 5110.054639891632, 5111.565516931102, 5121.974390128025, 3333.599968003855, 3803.2583646188727, 4696.85909422711, 4783.330333753277, 4789.4971028042255, 3078.553609726577, 3558.21719221776, 3558.822058897045, 3622.583402908699, 3623.4927110204594, 988.5353883674661, 1141.178351586856, 1148.534843388429, 1148.8851114888612, 1166.7199872030822, 780.7598466364519, 824.4270232188571, 832.342449464928, 847.0809172048397, 858.5835869603237, 5264.15773476242, 5292.5501332387175, 5494.0, 5558.666480011212, 5586.385497595261, 575.3872126185682, 577.5985689945531, 583.1806312131462, 594.1414655822296, 595.5325069820257, 3526.1360966563357, 3546.414841551167, 3626.8549258029384, 3721.0883333583447, 3733.4026555751493, 8339.499630022217, 8653.0, 9488.762780930532, 9509.714708558617, 9864.605415783288, 9674.951625241829, 9875.851862222073, 9937.900620993842, 9950.552225149886, 9960.701178964642, 6840.418564421981, 7858.332041776513, 7874.763757087297, 7879.369650427952, 7912.32745216663, 5530.668159910419, 5536.584756143225, 5546.889062218653, 5600.271964644733, 5618.775248989995, 8909.643614255358, 8949.552522373726, 8975.551222438722, 9211.953940230256, 9220.077992200726, 8791.296696264155, 9098.727038188865, 9312.0, 9336.719898403058, 9391.29565282593, 6317.736452709569, 7085.893711594434, 7167.7132914682825, 7265.782026539212, 7277.59973201479, 6692.6988285526295, 7547.338690428912, 7842.372610191057, 7874.921250787419, 8002.639881205476, 7013.438924885895, 7036.050133231948, 7071.222165561887, 7176.677049532679, 7228.277172282729, 7003.824904377431, 7014.894776578253, 7058.200158959433, 7064.152301723826, 7085.114360704912, 6335.334789847, 6343.619382837044, 6363.5545511813725, 6380.553361264667, 6408.102865598913, 4920.827771027997, 4977.328060711758, 5014.373203349582, 5015.695919060998, 5098.97450512745, 4887.315775791463, 5043.596512279082, 5052.72210028452, 5092.8472145835685, 5145.3568303962], "value": 4257.896289796744, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [296.2578740255659, 305.41970256512633, 368.67004031391906, 373.1175769305592, 379.3778203745857, 5397.325334333208, 5403.649412505562, 5439.891202175959, 5461.836144915659, 5487.0, 5057.443681195065, 5362.839114826552, 5376.8118115865955, 5386.78452861886, 5387.4612538746105, 3990.68074554035, 4052.7365721228175, 4053.9257096869355, 4064.9390259146135, 4100.241455330766, 3906.785126818024, 4052.5947405259385, 4053.4122552230056, 4062.451569038172, 4085.305498065336, 3742.0644838790304, 3849.8845034648866, 4055.5538890721996, 4057.8985525361954, 4085.6527195188446, 3386.339663765567, 3862.7102967277424, 4011.959880401188, 4056.4726585543985, 4084.97957510212, 2726.5910113482937, 3420.931581368359, 3476.0614634048916, 3479.565054368204, 3490.2670439207795, 5253.343332083489, 5259.658122222043, 5268.024490815944, 5272.288241087443, 5304.496072873071, 4400.0, 4638.397008388922, 4640.60554852838, 4645.976770116162, 4679.344891715163, 5212.706409179948, 5230.529252367305, 5629.662220266797, 5658.236138121383, 5700.942990570083, 3250.3499300140065, 3833.5016447861876, 3845.0, 3846.288436639231, 3855.4987851579394, 4873.537013983666, 5322.361316642027, 5630.679008337123, 5999.580029397944, 6039.78860739872, 3284.8850290239725, 3430.936409712997, 3568.3933731265747, 3568.946465803015, 3726.8136593170234, 3467.393206188907, 3889.8055097245024, 3910.8267519743963, 3922.2155568885764, 3931.4299426582984, 4077.510698716114, 4079.857639860844, 4562.543745625411, 4583.931241031387, 4625.838095666635, 4772.7136371817105, 5428.75570599324, 5722.856928576735, 5735.139729040678, 5743.655380677173, 1250.4498020870788, 1280.3022352817736, 1286.350393051503, 1287.3949243855561, 1302.4855182203166, 778.7002004228412, 824.2499325613783, 842.9873551896726, 866.1165611076666, 876.2026555834287, 4459.866204013885, 4542.341360502709, 4733.077049975222, 5154.304168937146, 5162.6386152968935, 5669.574781891375, 5702.800401985911, 5717.599768016199, 5721.342045664718, 5741.540676745851, 4696.929546056812, 4794.544518270759, 5063.797448102035, 5118.515630467123, 5146.794128234904, 2477.6462360757355, 2826.4771017361854, 2914.3005678636973, 2934.310437047292, 2935.163478408643, 5259.763955470461, 5589.329280486368, 5798.275215598051, 5821.76712931478, 5844.649321040752, 4797.784099715521, 4943.2585112233455, 5236.345456817898, 5435.402105768362, 5696.51579615729, 5858.882822343615, 5908.970455147697, 6028.2766068072115, 6039.8792024159275, 6141.570090093651, 5121.129408000575, 5194.402643695872, 5609.467100625434, 5930.34766175729, 5933.406659334032, 5104.464031276811, 5520.420355862577, 5707.971460142673, 5772.546667666141, 5825.533957283323, 5198.946416165669, 6021.698915054318, 6048.758049678139, 6074.3925607438905, 6121.693915304306, 3188.473901806176, 3613.95195393333, 3854.383298672199, 3874.3219936510436, 3893.6301051400073, 5155.53600175986, 5594.244776955059, 5742.454466825645, 5847.064469685, 5888.057910734262, 5243.68537887728, 5287.52412282881, 5305.787768489217, 5689.203511508309, 5755.0, 5516.751746171512, 5617.522510586646, 5624.550035997192, 5678.403767604342, 5716.456936591017, 5484.2596249507405, 5494.230807686847, 5547.583931205257, 5560.833175004756, 5573.860653483695, 5014.122528557561, 5057.6459647824295, 5138.665986710852, 5221.746780772663, 5267.525922666979, 4839.0, 4988.406028864897, 5094.363204599425, 5216.37403511581, 5511.200875873056], "value": 4075.613333276582, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [642.0754114075734, 678.2166597579798, 751.6542390500371, 757.9658915348808, 825.7976795685056, 14644.048136871095, 15333.846661533382, 15385.769213461806, 15385.999910005841, 15411.07533547988, 14140.717185656307, 14261.144331340127, 14289.928550357234, 14330.57008289753, 14351.206099237595, 896.968606098785, 899.1098812175932, 900.6397441023611, 900.9819803603924, 902.1745103230536, 912.7535565397338, 916.138829500271, 920.8250432417852, 922.8477301245287, 925.7315378540216, 977.325645304741, 979.7256768104942, 980.9362391444533, 981.2983716642599, 982.1308142294085, 967.1247520993512, 970.0396607358734, 972.562346943871, 973.4256788494787, 973.5764942250144, 718.9137303523559, 719.9748008819719, 721.6355740351141, 722.1695050691694, 723.9674214660353, 15403.537893863202, 15614.985025973276, 15657.686846263012, 15674.059556426537, 15876.60308492297, 13630.727385452334, 13897.305134743323, 13980.091294065853, 13998.860011399858, 14196.432142714279, 11385.54457821686, 11414.372209528472, 11421.885781142248, 11513.597024104116, 11855.05159587231, 3278.426275401795, 3636.2000359920776, 3672.338978983782, 3750.4374343848367, 3764.548254209485, 103481.0, 105167.94832051735, 105820.94179058188, 107197.38494349344, 107447.46276268647, 10452.581896724047, 10522.789544209072, 10594.735131621772, 10602.151827853753, 10913.017828395483, 21895.01472433744, 22261.55476890453, 22335.888320558293, 22515.099396023976, 22543.210987615355, 7468.178500364956, 7491.250874912575, 7520.022397088453, 7581.355584775246, 7772.572508511973, 4082.3468245080644, 4173.207090652827, 4193.454850869368, 4193.685473589493, 4196.958030419718, 3513.4554144107706, 3562.0560551453887, 3601.369760291991, 3616.674499295076, 3621.203335266237, 655.4101308822098, 656.6782276684486, 669.855980964095, 670.9530332876749, 672.8284287506752, 650.4243744286341, 671.412514050206, 673.3703986772383, 684.3327755438407, 703.6953975729425, 9456.290778191526, 9651.372660777028, 9954.203663706887, 9970.0, 9996.150327222122, 629.7134803664321, 672.7241830849329, 683.6642822514448, 684.8082536889679, 686.5228666077128, 4447.221736196153, 4798.446331200303, 4912.901741965141, 5323.148296272578, 5340.2256672783005, 95321.0467895326, 95584.0, 96399.51800240955, 97749.0, 98766.5061674687, 54047.0, 57127.71436142793, 83408.58295708604, 84822.0, 86241.56879215564, 70319.24201894994, 71484.64257678678, 72445.0, 72909.27090729131, 74400.62799685966, 10245.897541024495, 10609.416482093558, 10739.570417183226, 10807.891921080689, 10811.72970675708, 46175.0, 47058.52941470678, 47700.761496192295, 47797.76101119472, 47849.8037549064, 50822.74588627181, 61933.69033154986, 63224.68387658216, 65496.0, 65989.0, 12809.56701521587, 12914.773096555813, 13408.597742067754, 13652.931735341259, 13886.305684715926, 11418.257813242113, 14267.143971361751, 14350.641233969234, 14397.352119154453, 14822.110673359633, 12818.102732808988, 12856.292903890377, 13262.602121936357, 13380.330983450984, 14125.858741412454, 12952.740945181233, 13217.603471895858, 13254.403551839998, 13431.597052088453, 13902.51341203053, 12968.870311296767, 13159.144655597738, 13797.931010344884, 13938.790918136034, 14181.220032898289, 9930.553125109533, 10776.515056822618, 10947.726306842393, 11107.555697771999, 11761.529538818351, 8253.546054967035, 8411.411201215857, 9590.568424420779, 9853.802923941625, 11258.211925165488], "value": 7452.0708691538, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1570.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 118.0, 118.0, 120.0, 117.0, 118.0, 130.0, 118.0, 117.0, 121.0], "value": 118.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 120.0, 129.0, 150.0, 119.0, 145.0, 96.0, 99.0, 173.0, 144.0], "value": 132.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [206.0, 217.0, 208.0, 211.0, 258.0, 286.0, 221.0, 195.0, 200.0, 198.0], "value": 220.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 60.0, 61.0, 105.0, 85.0, 51.0, 52.0, 52.0, 71.0, 69.0], "value": 68.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 75.0, 85.0, 70.0, 74.0, 72.0, 83.0, 72.0, 70.0, 83.0], "value": 77.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 117.0, 97.0, 88.0, 113.0, 104.0, 97.0, 136.0, 88.0, 89.0], "value": 105.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 95.0, 99.0, 95.0, 97.0, 97.0, 96.0, 101.0, 96.0], "value": 96.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 115.0, 92.0, 94.0, 90.0, 91.0, 93.0, 92.0, 123.0, 86.0], "value": 102.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 66.0, 68.0, 69.0, 68.0, 67.0, 70.0, 68.0, 67.0], "value": 67.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 62.0, 65.0, 75.0, 69.0, 64.0, 63.0, 64.0, 66.0, 65.0], "value": 65.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 79.0, 91.0, 106.0, 107.0, 82.0, 110.0, 108.0, 107.0, 91.0], "value": 96.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 146.0, 144.0, 137.0, 145.0, 148.0, 141.0, 149.0, 212.0, 169.0], "value": 153.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 173.0, 185.0, 157.0, 210.0, 235.0, 240.0, 176.0, 176.0, 174.0], "value": 190.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 59.0, 82.0, 87.0, 91.0, 74.0, 73.0, 67.0, 70.0, 84.0], "value": 75.8, "unit": "score"}]}]} 12:27:53 INFO - Return code: 0 12:27:53 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:27:53 ERROR - # TBPL SUCCESS # 12:27:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:27:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:27:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:27:53 INFO - 2017-02-25 12:27:53.280 screenresolution[1665:11326] starting screenresolution argv=screenresolution get 12:27:53 INFO - 2017-02-25 12:27:53.283 screenresolution[1665:11326] Display 0: 1600x1200x32@60 12:27:53 INFO - 2017-02-25 12:27:53.289 screenresolution[1666:11330] starting screenresolution argv=screenresolution list 12:27:53 INFO - Available Modes on Display 0 12:27:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:27:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:27:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:27:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:27:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:27:53 INFO - Intel Iris: 12:27:53 INFO - Chipset Model: Intel Iris 12:27:53 INFO - Type: GPU 12:27:53 INFO - Bus: Built-In 12:27:53 INFO - VRAM (Dynamic, Max): 1536 MB 12:27:53 INFO - Vendor: Intel (0x8086) 12:27:53 INFO - Device ID: 0x0a2e 12:27:53 INFO - Revision ID: 0x0009 12:27:53 INFO - Displays: 12:27:53 INFO - Display: 12:27:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:27:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:27:53 INFO - Display Serial Number: 1600x1200 60 12:27:53 INFO - Main Display: Yes 12:27:53 INFO - Mirror: Off 12:27:53 INFO - Online: Yes 12:27:53 INFO - Rotation: Supported 12:27:53 INFO - Return code: 0 12:27:53 INFO - Running post-action listener: _resource_record_post_action 12:27:53 INFO - [mozharness: 2017-02-25 20:27:53.536594Z] Finished run-tests step (success) 12:27:53 INFO - Running post-run listener: _resource_record_post_run 12:27:53 INFO - Total resource usage - Wall time: 1201s; CPU: 55.0%; Read bytes: 34603520; Write bytes: 516872704; Read time: 323; Write time: 2125 12:27:53 INFO - TinderboxPrint: CPU usage
54.6% 12:27:53 INFO - TinderboxPrint: I/O read bytes / time
34,603,520 / 323 12:27:53 INFO - TinderboxPrint: I/O write bytes / time
516,872,704 / 2,125 12:27:53 INFO - TinderboxPrint: CPU idle
2,180.6 (45.4%) 12:27:53 INFO - TinderboxPrint: CPU system
963.2 (20.0%) 12:27:53 INFO - TinderboxPrint: CPU user
1,661.9 (34.6%) 12:27:53 INFO - TinderboxPrint: Swap in / out
241,762,304 / 0 12:27:53 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 208357888; Write bytes: 204154880; Read time: 15774; Write time: 464 12:27:53 INFO - run-tests - Wall time: 1182s; CPU: 55.0%; Read bytes: 32469504; Write bytes: 301822464; Read time: 294; Write time: 1630 12:27:54 INFO - Running post-run listener: _upload_blobber_files 12:27:54 INFO - Blob upload gear active. 12:27:54 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:27:54 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 12:27:54 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:27:54 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:27:54 INFO - (blobuploader) - INFO - Open directory for files ... 12:27:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:27:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:27:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:27:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:27:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:27:55 INFO - (blobuploader) - INFO - Done attempting. 12:27:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:27:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:27:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:27:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:27:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:27:56 INFO - (blobuploader) - INFO - Done attempting. 12:27:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:27:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:27:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:27:57 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:27:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:27:57 INFO - (blobuploader) - INFO - Done attempting. 12:27:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:27:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:27:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:27:59 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:27:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:27:59 INFO - (blobuploader) - INFO - Done attempting. 12:27:59 INFO - (blobuploader) - INFO - Iteration through files over. 12:27:59 INFO - Return code: 0 12:27:59 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:27:59 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:27:59 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/56f862f5bb7b86efc689a45637c6c51e6b65879261e1df0f4c1cc26ea175a02ef7a83c7a8f4dfd2f479212a8ce5eb14af918f1e85b08462eff86b5be650d300e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3e465f18d5dc64a060b2080ccc2b7ca7b7d7cb128dc6d01d7df70f9b2057bd3b432a787ecc1b394c77ec056a8432a6f7ed3fe62affb6603a62226248bc85be39", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d61f1d3ef6b7a6182111f46318b1683e8b58804566ab53f6dda3245173cba6bf7d2aed4e9e36dd1e6b1fee82c4cd026ed815d6a48e18c05fd75e83c9d06179df", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eaff089c77d5e4c332f148dd6617870ffdc71105a34aca259f1db5681e1a3ecc5a9abeb407e9cec45a35848970ec85df34439591c9ddc7bb9444f0ee531573a6"} 12:27:59 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:27:59 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:27:59 INFO - Contents: 12:27:59 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/56f862f5bb7b86efc689a45637c6c51e6b65879261e1df0f4c1cc26ea175a02ef7a83c7a8f4dfd2f479212a8ce5eb14af918f1e85b08462eff86b5be650d300e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3e465f18d5dc64a060b2080ccc2b7ca7b7d7cb128dc6d01d7df70f9b2057bd3b432a787ecc1b394c77ec056a8432a6f7ed3fe62affb6603a62226248bc85be39", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d61f1d3ef6b7a6182111f46318b1683e8b58804566ab53f6dda3245173cba6bf7d2aed4e9e36dd1e6b1fee82c4cd026ed815d6a48e18c05fd75e83c9d06179df", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eaff089c77d5e4c332f148dd6617870ffdc71105a34aca259f1db5681e1a3ecc5a9abeb407e9cec45a35848970ec85df34439591c9ddc7bb9444f0ee531573a6"} 12:27:59 INFO - Running post-run listener: copy_logs_to_upload_dir 12:27:59 INFO - Copying logs to upload dir... 12:27:59 INFO - mkdir: /builds/slave/test/build/upload/logs 12:27:59 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1284.543603 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2017-02-25 12:27:59.558943) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-25 12:27:59.562103) ========= 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.Uo9Ael4neV/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.t5VkMu3fdo/Listeners TMPDIR=/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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/Firefox/sha512/56f862f5bb7b86efc689a45637c6c51e6b65879261e1df0f4c1cc26ea175a02ef7a83c7a8f4dfd2f479212a8ce5eb14af918f1e85b08462eff86b5be650d300e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3e465f18d5dc64a060b2080ccc2b7ca7b7d7cb128dc6d01d7df70f9b2057bd3b432a787ecc1b394c77ec056a8432a6f7ed3fe62affb6603a62226248bc85be39", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d61f1d3ef6b7a6182111f46318b1683e8b58804566ab53f6dda3245173cba6bf7d2aed4e9e36dd1e6b1fee82c4cd026ed815d6a48e18c05fd75e83c9d06179df", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eaff089c77d5e4c332f148dd6617870ffdc71105a34aca259f1db5681e1a3ecc5a9abeb407e9cec45a35848970ec85df34439591c9ddc7bb9444f0ee531573a6"} build_url:https://queue.taskcluster.net/v1/task/LO__KlEoRzqtbMlmr_f0QA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011584 build_url: 'https://queue.taskcluster.net/v1/task/LO__KlEoRzqtbMlmr_f0QA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/56f862f5bb7b86efc689a45637c6c51e6b65879261e1df0f4c1cc26ea175a02ef7a83c7a8f4dfd2f479212a8ce5eb14af918f1e85b08462eff86b5be650d300e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3e465f18d5dc64a060b2080ccc2b7ca7b7d7cb128dc6d01d7df70f9b2057bd3b432a787ecc1b394c77ec056a8432a6f7ed3fe62affb6603a62226248bc85be39", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d61f1d3ef6b7a6182111f46318b1683e8b58804566ab53f6dda3245173cba6bf7d2aed4e9e36dd1e6b1fee82c4cd026ed815d6a48e18c05fd75e83c9d06179df", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eaff089c77d5e4c332f148dd6617870ffdc71105a34aca259f1db5681e1a3ecc5a9abeb407e9cec45a35848970ec85df34439591c9ddc7bb9444f0ee531573a6"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-25 12:27:59.597280) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-25 12:27:59.597639) ========= 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.Uo9Ael4neV/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.t5VkMu3fdo/Listeners TMPDIR=/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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.004653 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-25 12:27:59.647548) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-25 12:27:59.647876) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-25 12:27:59.648230) ========= ========= Total master_lag: 0.18 =========