builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0025 starttime: 1480771803.58 results: success (0) buildid: 20161203030204 builduid: 163e9a46b1db4a5cbc4274a2cb8cc76a revision: 557548714db55136b51e1129d649e2599797985f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-03 05:30:03.580551) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-03 05:30:03.581246) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-03 05:30:03.581562) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-03 05:30:03.832739) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-03 05:30:03.833076) ========= 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.lbbGar9cYE/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.kO3k6xlrR5/Listeners TMPDIR=/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-12-03 05:30:03-- 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% 151M=0s 2016-12-03 05:30:03 (151 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.148950 ========= master_lag: 0.08 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-03 05:30:04.063195) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-03 05:30:04.063513) ========= 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.lbbGar9cYE/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.kO3k6xlrR5/Listeners TMPDIR=/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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.028373 ========= master_lag: 0.08 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-03 05:30:04.170902) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-12-03 05:30:04.171297) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 557548714db55136b51e1129d649e2599797985f --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 557548714db55136b51e1129d649e2599797985f --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.lbbGar9cYE/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.kO3k6xlrR5/Listeners TMPDIR=/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-12-03 05:30:04,280 truncating revision to first 12 chars 2016-12-03 05:30:04,280 Setting DEBUG logging. 2016-12-03 05:30:04,280 attempt 1/10 2016-12-03 05:30:04,280 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/557548714db5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-03 05:30:06,003 unpacking tar archive at: mozilla-central-557548714db5/testing/mozharness/ program finished with exit code 0 elapsedTime=1.995282 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-12-03 05:30:06.202109) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-03 05:30:06.202416) ========= script_repo_revision: 557548714db55136b51e1129d649e2599797985f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-03 05:30:06.202794) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-03 05:30:06.203074) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-03 05:30:06.224634) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-12-03 05:30:06.225054) ========= /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.lbbGar9cYE/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.kO3k6xlrR5/Listeners TMPDIR=/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 05:30:06 INFO - MultiFileLogger online at 20161203 05:30:06 in /builds/slave/test 05:30:06 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 05:30:06 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:30:06 INFO - {'append_to_log': False, 05:30:06 INFO - 'base_work_dir': '/builds/slave/test', 05:30:06 INFO - 'blob_upload_branch': 'Firefox', 05:30:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:30:06 INFO - 'branch': 'Firefox', 05:30:06 INFO - 'buildbot_json_path': 'buildprops.json', 05:30:06 INFO - 'config_files': ('talos/mac_config.py',), 05:30:06 INFO - 'default_actions': ('clobber', 05:30:06 INFO - 'read-buildbot-config', 05:30:06 INFO - 'download-and-extract', 05:30:06 INFO - 'populate-webroot', 05:30:06 INFO - 'create-virtualenv', 05:30:06 INFO - 'install', 05:30:06 INFO - 'run-tests'), 05:30:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:30:06 INFO - 'download_minidump_stackwalk': True, 05:30:06 INFO - 'download_symbols': 'ondemand', 05:30:06 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:30:06 INFO - 'tooltool.py': '/tools/tooltool.py', 05:30:06 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:30:06 INFO - '/tools/misc-python/virtualenv.py')}, 05:30:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:30:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:30:06 INFO - 'installer_path': 'installer.exe', 05:30:06 INFO - 'log_level': 'info', 05:30:06 INFO - 'log_name': 'talos', 05:30:06 INFO - 'log_to_console': True, 05:30:06 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:30:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:30:06 INFO - 'opt_config_files': (), 05:30:06 INFO - 'pip_index': False, 05:30:06 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:30:06 INFO - 'cmd': ('bash', 05:30:06 INFO - '-c', 05:30:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:30:06 INFO - 'enabled': True, 05:30:06 INFO - 'halt_on_failure': False, 05:30:06 INFO - 'name': 'check_screen_resolution'},), 05:30:06 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:30:06 INFO - 'cmd': ('bash', 05:30:06 INFO - '-c', 05:30:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:30:06 INFO - 'enabled': True, 05:30:06 INFO - 'halt_on_failure': False, 05:30:06 INFO - 'name': 'check_screen_resolution'},), 05:30:06 INFO - 'run_cmd_checks_enabled': True, 05:30:06 INFO - 'sps_profile': False, 05:30:06 INFO - 'sps_profile_interval': 0, 05:30:06 INFO - 'suite': 'dromaeojs-e10s', 05:30:06 INFO - 'system_bits': '32', 05:30:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:30:06 INFO - 'title': 't-yosemite-r7-0025', 05:30:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:30:06 INFO - 'use_talos_json': True, 05:30:06 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:30:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:30:06 INFO - 'work_dir': 'build'} 05:30:06 INFO - [mozharness: 2016-12-03 13:30:06.430422Z] Running clobber step. 05:30:06 INFO - Running pre-action listener: _resource_record_pre_action 05:30:06 INFO - Running main action method: clobber 05:30:06 INFO - rmtree: /builds/slave/test/build 05:30:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:30:08 INFO - Running post-action listener: _resource_record_post_action 05:30:08 INFO - [mozharness: 2016-12-03 13:30:08.846549Z] Finished clobber step (success) 05:30:08 INFO - [mozharness: 2016-12-03 13:30:08.846662Z] Running read-buildbot-config step. 05:30:08 INFO - Running pre-action listener: _resource_record_pre_action 05:30:08 INFO - Running main action method: read_buildbot_config 05:30:08 INFO - Using buildbot properties: 05:30:08 INFO - { 05:30:08 INFO - "project": "", 05:30:08 INFO - "product": "firefox", 05:30:08 INFO - "script_repo_revision": "production", 05:30:08 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 05:30:08 INFO - "repository": "", 05:30:08 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 05:30:08 INFO - "buildid": "20161203030204", 05:30:08 INFO - "stage_platform": "macosx64", 05:30:08 INFO - "basedir": "/builds/slave/test", 05:30:08 INFO - "nightly_build": "True", 05:30:08 INFO - "buildnumber": 103, 05:30:08 INFO - "slavename": "t-yosemite-r7-0025", 05:30:08 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 05:30:08 INFO - "platform": "yosemite_r7", 05:30:08 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:30:08 INFO - "branch": "mozilla-central", 05:30:08 INFO - "repo_path": "mozilla-central", 05:30:08 INFO - "revision": "557548714db55136b51e1129d649e2599797985f", 05:30:08 INFO - "pgo_build": "False", 05:30:08 INFO - "builduid": "163e9a46b1db4a5cbc4274a2cb8cc76a", 05:30:08 INFO - "slavebuilddir": "test" 05:30:08 INFO - } 05:30:08 INFO - Found installer url https://queue.taskcluster.net/v1/task/LlY0JE9IR5KIjdeEStiCtQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 05:30:08 INFO - Running post-action listener: _resource_record_post_action 05:30:08 INFO - [mozharness: 2016-12-03 13:30:08.850036Z] Finished read-buildbot-config step (success) 05:30:08 INFO - [mozharness: 2016-12-03 13:30:08.850155Z] Running download-and-extract step. 05:30:08 INFO - Running pre-action listener: _resource_record_pre_action 05:30:08 INFO - Running main action method: download_and_extract 05:30:08 INFO - mkdir: /builds/slave/test/build/tests 05:30:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:30:08 INFO - trying https://queue.taskcluster.net/v1/task/LlY0JE9IR5KIjdeEStiCtQ/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 05:30:08 INFO - Downloading https://queue.taskcluster.net/v1/task/LlY0JE9IR5KIjdeEStiCtQ/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 05:30:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LlY0JE9IR5KIjdeEStiCtQ/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 05:30:10 INFO - Downloaded 1199 bytes. 05:30:10 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 05:30:10 INFO - Using the following test package requirements: 05:30:10 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 05:30:10 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:30:10 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 05:30:10 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:30:10 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 05:30:10 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:30:10 INFO - u'jsshell-mac.zip'], 05:30:10 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:30:10 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 05:30:10 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 05:30:10 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:30:10 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 05:30:10 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:30:10 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 05:30:10 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:30:10 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 05:30:10 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:30:10 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 05:30:10 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:30:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LlY0JE9IR5KIjdeEStiCtQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 05:30:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LlY0JE9IR5KIjdeEStiCtQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:30:10 INFO - Fetch https://queue.taskcluster.net/v1/task/LlY0JE9IR5KIjdeEStiCtQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 05:30:11 INFO - Expected file size: 16283867 05:30:11 INFO - Obtained file size: 16283867 05:30:18 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:30:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LlY0JE9IR5KIjdeEStiCtQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 05:30:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LlY0JE9IR5KIjdeEStiCtQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:30:18 INFO - Fetch https://queue.taskcluster.net/v1/task/LlY0JE9IR5KIjdeEStiCtQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 05:30:19 INFO - Expected file size: 16283867 05:30:19 INFO - Obtained file size: 16283867 05:30:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LlY0JE9IR5KIjdeEStiCtQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 05:30:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LlY0JE9IR5KIjdeEStiCtQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:30:25 INFO - Fetch https://queue.taskcluster.net/v1/task/LlY0JE9IR5KIjdeEStiCtQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 05:30:27 INFO - Expected file size: 14409734 05:30:27 INFO - Obtained file size: 14409734 05:30:27 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')]} 05:30:27 INFO - trying https://queue.taskcluster.net/v1/task/LlY0JE9IR5KIjdeEStiCtQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 05:30:27 INFO - Downloading https://queue.taskcluster.net/v1/task/LlY0JE9IR5KIjdeEStiCtQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 05:30:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LlY0JE9IR5KIjdeEStiCtQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 05:30:30 INFO - Downloaded 107741519 bytes. 05:30:30 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LlY0JE9IR5KIjdeEStiCtQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 05:30:30 INFO - mkdir: /builds/slave/test/properties 05:30:30 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:30:30 INFO - Writing to file /builds/slave/test/properties/build_url 05:30:30 INFO - Contents: 05:30:30 INFO - build_url:https://queue.taskcluster.net/v1/task/LlY0JE9IR5KIjdeEStiCtQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 05:30:30 INFO - Running post-action listener: _resource_record_post_action 05:30:30 INFO - Running post-action listener: set_extra_try_arguments 05:30:30 INFO - [mozharness: 2016-12-03 13:30:30.895842Z] Finished download-and-extract step (success) 05:30:30 INFO - [mozharness: 2016-12-03 13:30:30.895970Z] Running populate-webroot step. 05:30:30 INFO - Running pre-action listener: _resource_record_pre_action 05:30:30 INFO - Running main action method: populate_webroot 05:30:30 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:30:30 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:30:30 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:30:30 INFO - '--tppagecycles', 05:30:30 INFO - '7'], 05:30:30 INFO - 'tests': ['tsvgm']}}, 05:30:30 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:30:30 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:30:30 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:30:30 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:30:30 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:30:30 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:30:30 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 05:30:30 INFO - 'talos_options': ['--disable-e10s'], 05:30:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:30:30 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:30:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:30:30 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 05:30:30 INFO - 'talos_options': ['--disable-e10s'], 05:30:30 INFO - 'tests': ['damp', 'tps']}, 05:30:30 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:30:30 INFO - 'tests': ['damp', 'tps']}, 05:30:30 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:30:30 INFO - 'tests': ['dromaeo_dom']}, 05:30:30 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:30:30 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:30:30 INFO - 'tests': ['basic_compositor_video']}, 05:30:30 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:30:30 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:30:30 INFO - 'tests': ['a11yr', 05:30:30 INFO - 'ts_paint', 05:30:30 INFO - 'tpaint', 05:30:30 INFO - 'sessionrestore', 05:30:30 INFO - 'sessionrestore_no_auto_restore', 05:30:30 INFO - 'tabpaint']}, 05:30:30 INFO - 'other-e10s': {'tests': ['a11yr', 05:30:30 INFO - 'ts_paint', 05:30:30 INFO - 'tpaint', 05:30:30 INFO - 'sessionrestore', 05:30:30 INFO - 'sessionrestore_no_auto_restore', 05:30:30 INFO - 'tabpaint']}, 05:30:30 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:30:30 INFO - 'ts_paint', 05:30:30 INFO - 'tpaint', 05:30:30 INFO - 'sessionrestore', 05:30:30 INFO - 'sessionrestore_no_auto_restore', 05:30:30 INFO - 'tabpaint']}, 05:30:30 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:30:30 INFO - 'ts_paint', 05:30:30 INFO - 'tpaint', 05:30:30 INFO - 'sessionrestore', 05:30:30 INFO - 'sessionrestore_no_auto_restore', 05:30:30 INFO - 'tabpaint']}, 05:30:30 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:30:30 INFO - 'tests': ['a11yr', 05:30:30 INFO - 'ts_paint', 05:30:30 INFO - 'tpaint', 05:30:30 INFO - 'sessionrestore', 05:30:30 INFO - 'sessionrestore_no_auto_restore', 05:30:30 INFO - 'tabpaint']}, 05:30:30 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:30:30 INFO - 'tests': ['a11yr', 05:30:30 INFO - 'ts_paint', 05:30:30 INFO - 'tpaint', 05:30:30 INFO - 'sessionrestore', 05:30:30 INFO - 'sessionrestore_no_auto_restore', 05:30:30 INFO - 'tabpaint']}, 05:30:30 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:30:30 INFO - 'tests': ['tsvgx', 05:30:30 INFO - 'tsvgr_opacity', 05:30:30 INFO - 'tart', 05:30:30 INFO - 'tscrollx', 05:30:30 INFO - 'cart', 05:30:30 INFO - 'tsvg_static']}, 05:30:30 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:30:30 INFO - 'tsvgr_opacity', 05:30:30 INFO - 'tart', 05:30:30 INFO - 'tscrollx', 05:30:30 INFO - 'cart', 05:30:30 INFO - 'tsvg_static']}, 05:30:30 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 05:30:30 INFO - 'talos_options': ['--disable-e10s'], 05:30:30 INFO - 'tests': ['tp5o']}, 05:30:30 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:30:30 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 05:30:30 INFO - 'talos_options': ['--disable-e10s', 05:30:30 INFO - '--xperf_path', 05:30:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:30:30 INFO - 'tests': ['tp5n']}, 05:30:30 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:30:30 INFO - 'talos_options': ['--xperf_path', 05:30:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:30:30 INFO - 'tests': ['tp5n']}}, 05:30:30 INFO - 'talos.zip': {'path': '', 05:30:30 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:30:30 INFO - Running post-action listener: _resource_record_post_action 05:30:30 INFO - [mozharness: 2016-12-03 13:30:30.914701Z] Finished populate-webroot step (success) 05:30:30 INFO - [mozharness: 2016-12-03 13:30:30.914827Z] Running create-virtualenv step. 05:30:30 INFO - Running pre-action listener: _resource_record_pre_action 05:30:30 INFO - Running main action method: create_virtualenv 05:30:30 INFO - Creating virtualenv /builds/slave/test/build/venv 05:30:30 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:30:30 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:30:31 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:30:31 INFO - Using real prefix '/tools/python27' 05:30:31 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:30:31 INFO - Installing distribute.............................................................................................................................................................................................done. 05:30:34 INFO - Installing pip.................done. 05:30:34 INFO - Return code: 0 05:30:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:30:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:30:34 INFO - Reading from file tmpfile_stdout 05:30:34 INFO - Output received: 05:30:34 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) 05:30:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:30:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:30:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:30:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:30:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:30:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:34 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 0x10e67b3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5f1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc07a57b5d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e490d30>, '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 0x10e2f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e488b20>, '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.lbbGar9cYE/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.kO3k6xlrR5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:30:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:30:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:30:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lbbGar9cYE/Render', 05:30:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:30:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:30:34 INFO - 'HOME': '/Users/cltbld', 05:30:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:30:34 INFO - 'LOGNAME': 'cltbld', 05:30:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:30:34 INFO - 'MOZ_NO_REMOTE': '1', 05:30:34 INFO - 'NO_EM_RESTART': '1', 05:30:34 INFO - 'PAGER': '/bin/cat', 05:30:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:30:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:30:34 INFO - 'PWD': '/builds/slave/test', 05:30:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:30:34 INFO - 'SHELL': '/bin/bash', 05:30:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kO3k6xlrR5/Listeners', 05:30:34 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 05:30:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:30:34 INFO - 'USER': 'cltbld', 05:30:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:30:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:30:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:30:34 INFO - 'XPC_FLAGS': '0x0', 05:30:34 INFO - 'XPC_SERVICE_NAME': '0', 05:30:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:30:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:30:35 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:30:35 INFO - Cleaning up... 05:30:35 INFO - Return code: 0 05:30:35 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:30: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')]} 05:30:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:30:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30: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')]} 05:30:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:30:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30: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', '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 0x10e67b3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5f1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc07a57b5d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e490d30>, '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 0x10e2f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e488b20>, '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.lbbGar9cYE/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.kO3k6xlrR5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:30: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', 'psutil>=3.1.1'] in /builds/slave/test/build 05:30: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 psutil>=3.1.1 05:30:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lbbGar9cYE/Render', 05:30:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:30:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:30:35 INFO - 'HOME': '/Users/cltbld', 05:30:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:30:35 INFO - 'LOGNAME': 'cltbld', 05:30:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:30:35 INFO - 'MOZ_NO_REMOTE': '1', 05:30:35 INFO - 'NO_EM_RESTART': '1', 05:30:35 INFO - 'PAGER': '/bin/cat', 05:30:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:30:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:30:35 INFO - 'PWD': '/builds/slave/test', 05:30:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:30:35 INFO - 'SHELL': '/bin/bash', 05:30:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kO3k6xlrR5/Listeners', 05:30:35 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 05:30:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:30:35 INFO - 'USER': 'cltbld', 05:30:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:30:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:30:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:30:35 INFO - 'XPC_FLAGS': '0x0', 05:30:35 INFO - 'XPC_SERVICE_NAME': '0', 05:30:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:30:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:30:35 INFO - Downloading/unpacking psutil>=3.1.1 05:30:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:30:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:30:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:30:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:30:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:30:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:30:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:30:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:30:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:30:39 INFO - Installing collected packages: psutil 05:30:39 INFO - Running setup.py install for psutil 05:30:39 INFO - building 'psutil._psutil_osx' extension 05:30:39 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 05:30:39 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 05:30:39 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 05:30:40 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 05:30:40 INFO - building 'psutil._psutil_posix' extension 05:30:40 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 05:30:40 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:30:40 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:30:40 INFO - ^ 05:30:40 INFO - 1 warning generated. 05:30:40 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 05:30:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:30:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:30:40 INFO - Successfully installed psutil 05:30:40 INFO - Cleaning up... 05:30:40 INFO - Return code: 0 05:30:40 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:30: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')]} 05:30:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:30:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30: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')]} 05:30:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:30:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e67b3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5f1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc07a57b5d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e490d30>, '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 0x10e2f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e488b20>, '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.lbbGar9cYE/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.kO3k6xlrR5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:30:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:30:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:30:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lbbGar9cYE/Render', 05:30:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:30:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:30:40 INFO - 'HOME': '/Users/cltbld', 05:30:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:30:40 INFO - 'LOGNAME': 'cltbld', 05:30:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:30:40 INFO - 'MOZ_NO_REMOTE': '1', 05:30:40 INFO - 'NO_EM_RESTART': '1', 05:30:40 INFO - 'PAGER': '/bin/cat', 05:30:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:30:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:30:40 INFO - 'PWD': '/builds/slave/test', 05:30:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:30:40 INFO - 'SHELL': '/bin/bash', 05:30:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kO3k6xlrR5/Listeners', 05:30:40 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 05:30:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:30:40 INFO - 'USER': 'cltbld', 05:30:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:30:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:30:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:30:40 INFO - 'XPC_FLAGS': '0x0', 05:30:40 INFO - 'XPC_SERVICE_NAME': '0', 05:30:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:30:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:30:40 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:30:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:30:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:30:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:30:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:30:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:30:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:30:43 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:30:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:30:43 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:30:43 INFO - Installing collected packages: mozsystemmonitor 05:30:43 INFO - Running setup.py install for mozsystemmonitor 05:30:43 INFO - Successfully installed mozsystemmonitor 05:30:43 INFO - Cleaning up... 05:30:43 INFO - Return code: 0 05:30:43 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:30:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:30:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:30:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:30:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:30:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:43 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 0x10e67b3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5f1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc07a57b5d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e490d30>, '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 0x10e2f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e488b20>, '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.lbbGar9cYE/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.kO3k6xlrR5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:30:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:30:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 05:30:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lbbGar9cYE/Render', 05:30:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:30:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:30:43 INFO - 'HOME': '/Users/cltbld', 05:30:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:30:43 INFO - 'LOGNAME': 'cltbld', 05:30:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:30:43 INFO - 'MOZ_NO_REMOTE': '1', 05:30:43 INFO - 'NO_EM_RESTART': '1', 05:30:43 INFO - 'PAGER': '/bin/cat', 05:30:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:30:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:30:43 INFO - 'PWD': '/builds/slave/test', 05:30:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:30:43 INFO - 'SHELL': '/bin/bash', 05:30:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kO3k6xlrR5/Listeners', 05:30:43 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 05:30:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:30:43 INFO - 'USER': 'cltbld', 05:30:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:30:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:30:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:30:43 INFO - 'XPC_FLAGS': '0x0', 05:30:43 INFO - 'XPC_SERVICE_NAME': '0', 05:30:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:30:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:30:43 INFO - Downloading/unpacking jsonschema==2.5.1 05:30:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:30:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:30:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:30:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:30:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:30:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:30:46 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:30:46 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:30:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:30:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:30:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:30:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:30:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:30:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:30:46 INFO - Downloading functools32-3.2.3-2.tar.gz 05:30:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:30:46 INFO - warning: no files found matching '*.txt' 05:30:46 INFO - no previously-included directories found matching 'build' 05:30:46 INFO - no previously-included directories found matching 'dist' 05:30:46 INFO - no previously-included directories found matching '.git*' 05:30:46 INFO - Installing collected packages: jsonschema, functools32 05:30:47 INFO - Running setup.py install for functools32 05:30:47 INFO - warning: no files found matching '*.txt' 05:30:47 INFO - no previously-included directories found matching 'build' 05:30:47 INFO - no previously-included directories found matching 'dist' 05:30:47 INFO - no previously-included directories found matching '.git*' 05:30:47 INFO - Successfully installed jsonschema functools32 05:30:47 INFO - Cleaning up... 05:30:47 INFO - Return code: 0 05:30:47 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:30:47 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')]} 05:30:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:30:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:47 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')]} 05:30:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:30:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:47 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 0x10e67b3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5f1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc07a57b5d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e490d30>, '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 0x10e2f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e488b20>, '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.lbbGar9cYE/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.kO3k6xlrR5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:30:47 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 05:30:47 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 05:30:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lbbGar9cYE/Render', 05:30:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:30:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:30:47 INFO - 'HOME': '/Users/cltbld', 05:30:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:30:47 INFO - 'LOGNAME': 'cltbld', 05:30:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:30:47 INFO - 'MOZ_NO_REMOTE': '1', 05:30:47 INFO - 'NO_EM_RESTART': '1', 05:30:47 INFO - 'PAGER': '/bin/cat', 05:30:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:30:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:30:47 INFO - 'PWD': '/builds/slave/test', 05:30:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:30:47 INFO - 'SHELL': '/bin/bash', 05:30:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kO3k6xlrR5/Listeners', 05:30:47 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 05:30:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:30:47 INFO - 'USER': 'cltbld', 05:30:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:30:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:30:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:30:47 INFO - 'XPC_FLAGS': '0x0', 05:30:47 INFO - 'XPC_SERVICE_NAME': '0', 05:30:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:30:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:30:47 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:30:47 INFO - Cleaning up... 05:30:47 INFO - Return code: 0 05:30:47 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:30:47 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')]} 05:30:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:30:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:47 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')]} 05:30:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:30:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:47 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 0x10e67b3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5f1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc07a57b5d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e490d30>, '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 0x10e2f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e488b20>, '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.lbbGar9cYE/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.kO3k6xlrR5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:30:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:30:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:30:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lbbGar9cYE/Render', 05:30:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:30:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:30:47 INFO - 'HOME': '/Users/cltbld', 05:30:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:30:47 INFO - 'LOGNAME': 'cltbld', 05:30:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:30:47 INFO - 'MOZ_NO_REMOTE': '1', 05:30:47 INFO - 'NO_EM_RESTART': '1', 05:30:47 INFO - 'PAGER': '/bin/cat', 05:30:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:30:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:30:47 INFO - 'PWD': '/builds/slave/test', 05:30:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:30:47 INFO - 'SHELL': '/bin/bash', 05:30:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kO3k6xlrR5/Listeners', 05:30:47 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 05:30:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:30:47 INFO - 'USER': 'cltbld', 05:30:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:30:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:30:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:30:47 INFO - 'XPC_FLAGS': '0x0', 05:30:47 INFO - 'XPC_SERVICE_NAME': '0', 05:30:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:30:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:30:47 INFO - Downloading/unpacking blobuploader==1.2.4 05:30: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 05:30: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 05:30: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 05:30: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 05:30: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 05:30: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 05:30:50 INFO - Downloading blobuploader-1.2.4.tar.gz 05:30:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:30:50 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:30:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:30:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:30:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:30:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:30:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:30:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:30:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:30:51 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:30:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:30:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:30:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:30:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:30:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:30:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:30:51 INFO - Downloading docopt-0.6.1.tar.gz 05:30:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:30:51 INFO - Installing collected packages: blobuploader, requests, docopt 05:30:51 INFO - Running setup.py install for blobuploader 05:30:51 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:30:51 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:30:51 INFO - Running setup.py install for requests 05:30:52 INFO - Running setup.py install for docopt 05:30:52 INFO - Successfully installed blobuploader requests docopt 05:30:52 INFO - Cleaning up... 05:30:52 INFO - Return code: 0 05:30:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:30:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:30:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:30:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:30:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:30:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:52 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 0x10e67b3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5f1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc07a57b5d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e490d30>, '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 0x10e2f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e488b20>, '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.lbbGar9cYE/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.kO3k6xlrR5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:30:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:30:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:30:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lbbGar9cYE/Render', 05:30:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:30:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:30:52 INFO - 'HOME': '/Users/cltbld', 05:30:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:30:52 INFO - 'LOGNAME': 'cltbld', 05:30:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:30:52 INFO - 'MOZ_NO_REMOTE': '1', 05:30:52 INFO - 'NO_EM_RESTART': '1', 05:30:52 INFO - 'PAGER': '/bin/cat', 05:30:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:30:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:30:52 INFO - 'PWD': '/builds/slave/test', 05:30:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:30:52 INFO - 'SHELL': '/bin/bash', 05:30:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kO3k6xlrR5/Listeners', 05:30:52 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 05:30:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:30:52 INFO - 'USER': 'cltbld', 05:30:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:30:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:30:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:30:52 INFO - 'XPC_FLAGS': '0x0', 05:30:52 INFO - 'XPC_SERVICE_NAME': '0', 05:30:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:30:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:30:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:30:52 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-mOXN3a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:30:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:30:52 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-Uu9c0m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:30:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:30:52 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-ldtuwB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:30:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:30:53 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-ANccA5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:30:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:30:53 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-qw9vsI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:30:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:30:53 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-JBZ3fF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:30:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:30:53 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-fjk1mr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:30:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:30:53 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-RuqNcf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:30:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:30:53 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-n2MhNz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:30:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:30:53 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-mkUOvf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:30:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:30:53 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-Rd7RLg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:30:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:30:53 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-HvTDmv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:30:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:30:53 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-6sYDFf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:30:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:30:54 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-cXqcvA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:30:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:30:54 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-8dAmfp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:30:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:30:54 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-yQYvoF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:30:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:30:54 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-LNna3Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:30:54 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:30:54 INFO - Running setup.py install for manifestparser 05:30:54 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:30:54 INFO - Running setup.py install for mozcrash 05:30:54 INFO - Running setup.py install for mozdebug 05:30:54 INFO - Running setup.py install for mozdevice 05:30:55 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:30:55 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:30:55 INFO - Running setup.py install for mozfile 05:30:55 INFO - Running setup.py install for mozhttpd 05:30:55 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:30:55 INFO - Running setup.py install for mozinfo 05:30:55 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:30:55 INFO - Running setup.py install for mozInstall 05:30:55 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:30:55 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:30:55 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:30:55 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:30:55 INFO - Running setup.py install for mozleak 05:30:55 INFO - Running setup.py install for mozlog 05:30:55 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:30:56 INFO - Running setup.py install for moznetwork 05:30:56 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:30:56 INFO - Running setup.py install for mozprocess 05:30:56 INFO - Running setup.py install for mozprofile 05:30:56 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:30:56 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:30:56 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:30:56 INFO - Running setup.py install for mozrunner 05:30:56 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:30:56 INFO - Running setup.py install for mozscreenshot 05:30:56 INFO - Running setup.py install for moztest 05:30:56 INFO - Running setup.py install for mozversion 05:30:57 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:30:57 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:30:57 INFO - Cleaning up... 05:30:57 INFO - Return code: 0 05:30:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:30:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:30:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:30:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:30:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:30:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:57 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 0x10e67b3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5f1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc07a57b5d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e490d30>, '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 0x10e2f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e488b20>, '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.lbbGar9cYE/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.kO3k6xlrR5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:30:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:30:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:30:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lbbGar9cYE/Render', 05:30:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:30:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:30:57 INFO - 'HOME': '/Users/cltbld', 05:30:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:30:57 INFO - 'LOGNAME': 'cltbld', 05:30:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:30:57 INFO - 'MOZ_NO_REMOTE': '1', 05:30:57 INFO - 'NO_EM_RESTART': '1', 05:30:57 INFO - 'PAGER': '/bin/cat', 05:30:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:30:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:30:57 INFO - 'PWD': '/builds/slave/test', 05:30:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:30:57 INFO - 'SHELL': '/bin/bash', 05:30:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kO3k6xlrR5/Listeners', 05:30:57 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 05:30:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:30:57 INFO - 'USER': 'cltbld', 05:30:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:30:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:30:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:30:57 INFO - 'XPC_FLAGS': '0x0', 05:30:57 INFO - 'XPC_SERVICE_NAME': '0', 05:30:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:30:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:30:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:30:57 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-_oKPMt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:30:57 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:30:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:30:57 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-lgl4Pp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:30:57 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)) 05:30:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:30:57 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-eQkAe_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:30:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:30:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:30:57 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-HKfeq0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:30:57 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)) 05:30:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:30:57 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-D6sLbp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:30:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:30:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:30:57 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-io7XkH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:30:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:30:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:30:57 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-QWyEMk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:30:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:30:58 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-1Pd_Lx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:30:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:30:58 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-RvP9o3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:30:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:30:58 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-2zuzn9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:30:58 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)) 05:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:30:58 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-b8ayqB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:30:58 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:30:58 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-j6VhxH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:30:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:30:58 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-3nCKkf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:30:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:30:58 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-pAIBxi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:30:58 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)) 05:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:30:58 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-5fd59M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:30:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:30:58 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-zSVUK_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:30:59 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:30:59 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-K9EiTp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:30:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:30:59 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)) 05:30:59 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)) 05:30:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:30:59 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)) 05:30:59 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)) 05:30:59 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:30:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:30:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:30:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:30:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:30:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:30:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:31:01 INFO - Downloading blessings-1.6.tar.gz 05:31:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:31:01 INFO - Installing collected packages: blessings 05:31:01 INFO - Running setup.py install for blessings 05:31:01 INFO - Successfully installed blessings 05:31:01 INFO - Cleaning up... 05:31:02 INFO - Return code: 0 05:31:02 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:31:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:31:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:31:02 INFO - Reading from file tmpfile_stdout 05:31:02 INFO - Current package versions: 05:31:02 INFO - blessings == 1.6 05:31:02 INFO - blobuploader == 1.2.4 05:31:02 INFO - docopt == 0.6.1 05:31:02 INFO - functools32 == 3.2.3-2 05:31:02 INFO - jsonschema == 2.5.1 05:31:02 INFO - manifestparser == 1.1 05:31:02 INFO - mozInstall == 1.12 05:31:02 INFO - mozcrash == 1.0 05:31:02 INFO - mozdebug == 0.1 05:31:02 INFO - mozdevice == 0.49 05:31:02 INFO - mozfile == 1.2 05:31:02 INFO - mozhttpd == 0.7 05:31:02 INFO - mozinfo == 0.9 05:31:02 INFO - mozleak == 0.1 05:31:02 INFO - mozlog == 3.4 05:31:02 INFO - moznetwork == 0.27 05:31:02 INFO - mozprocess == 0.23 05:31:02 INFO - mozprofile == 0.28 05:31:02 INFO - mozrunner == 6.13 05:31:02 INFO - mozscreenshot == 0.1 05:31:02 INFO - mozsystemmonitor == 0.3 05:31:02 INFO - moztest == 0.7 05:31:02 INFO - mozversion == 1.4 05:31:02 INFO - psutil == 3.1.1 05:31:02 INFO - requests == 1.2.3 05:31:02 INFO - wsgiref == 0.1.2 05:31:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:31:02 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')]} 05:31:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:02 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')]} 05:31:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:02 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 0x10e67b3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5f1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc07a57b5d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e490d30>, '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 0x10e2f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e488b20>, '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.lbbGar9cYE/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.kO3k6xlrR5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:31:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:31:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:31:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lbbGar9cYE/Render', 05:31:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:31:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:31:02 INFO - 'HOME': '/Users/cltbld', 05:31:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:31:02 INFO - 'LOGNAME': 'cltbld', 05:31:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:02 INFO - 'MOZ_NO_REMOTE': '1', 05:31:02 INFO - 'NO_EM_RESTART': '1', 05:31:02 INFO - 'PAGER': '/bin/cat', 05:31:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:31:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:31:02 INFO - 'PWD': '/builds/slave/test', 05:31:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:31:02 INFO - 'SHELL': '/bin/bash', 05:31:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kO3k6xlrR5/Listeners', 05:31:02 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 05:31:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:31:02 INFO - 'USER': 'cltbld', 05:31:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:31:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:31:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:02 INFO - 'XPC_FLAGS': '0x0', 05:31:02 INFO - 'XPC_SERVICE_NAME': '0', 05:31:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:31:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:31:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:31:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:31:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:31:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:31:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:31:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:31:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:31:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:31:02 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:31:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:31:02 INFO - Cleaning up... 05:31:02 INFO - Return code: 0 05:31:02 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:31:02 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')]} 05:31:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:02 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')]} 05:31:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:02 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 0x10e67b3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5f1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc07a57b5d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e490d30>, '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 0x10e2f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e488b20>, '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.lbbGar9cYE/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.kO3k6xlrR5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:31:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:31:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:31:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lbbGar9cYE/Render', 05:31:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:31:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:31:02 INFO - 'HOME': '/Users/cltbld', 05:31:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:31:02 INFO - 'LOGNAME': 'cltbld', 05:31:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:02 INFO - 'MOZ_NO_REMOTE': '1', 05:31:02 INFO - 'NO_EM_RESTART': '1', 05:31:02 INFO - 'PAGER': '/bin/cat', 05:31:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:31:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:31:02 INFO - 'PWD': '/builds/slave/test', 05:31:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:31:02 INFO - 'SHELL': '/bin/bash', 05:31:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kO3k6xlrR5/Listeners', 05:31:02 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 05:31:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:31:02 INFO - 'USER': 'cltbld', 05:31:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:31:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:31:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:02 INFO - 'XPC_FLAGS': '0x0', 05:31:02 INFO - 'XPC_SERVICE_NAME': '0', 05:31:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:31:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:02 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:31:02 INFO - Cleaning up... 05:31:02 INFO - Return code: 0 05:31:02 INFO - Running post-action listener: _resource_record_post_action 05:31:02 INFO - Running post-action listener: _start_resource_monitoring 05:31:02 INFO - Starting resource monitoring. 05:31:02 INFO - [mozharness: 2016-12-03 13:31:02.895961Z] Finished create-virtualenv step (success) 05:31:02 INFO - [mozharness: 2016-12-03 13:31:02.896940Z] Running install step. 05:31:02 INFO - Running pre-action listener: _resource_record_pre_action 05:31:02 INFO - Running main action method: install 05:31:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:31:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:31:03 INFO - Reading from file tmpfile_stdout 05:31:03 INFO - Detecting whether we're running mozinstall >=1.0... 05:31:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:31:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:31:03 INFO - Reading from file tmpfile_stdout 05:31:03 INFO - Output received: 05:31:03 INFO - Usage: mozinstall [options] installer 05:31:03 INFO - Options: 05:31:03 INFO - -h, --help show this help message and exit 05:31:03 INFO - -d DEST, --destination=DEST 05:31:03 INFO - Directory to install application into. [default: 05:31:03 INFO - "/builds/slave/test"] 05:31:03 INFO - --app=APP Application being installed. [default: firefox] 05:31:03 INFO - mkdir: /builds/slave/test/build/application 05:31:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:31:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:31:35 INFO - Reading from file tmpfile_stdout 05:31:35 INFO - Output received: 05:31:35 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 05:31:35 INFO - Running post-action listener: _resource_record_post_action 05:31:35 INFO - [mozharness: 2016-12-03 13:31:35.487499Z] Finished install step (success) 05:31:35 INFO - [mozharness: 2016-12-03 13:31:35.487666Z] Running run-tests step. 05:31:35 INFO - Running pre-action listener: _resource_record_pre_action 05:31:35 INFO - Running main action method: run_tests 05:31:35 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:31:35 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:31:35 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:31:35 INFO - 2016-12-03 05:31:35.501 screenresolution[1636:8413] starting screenresolution argv=screenresolution get 05:31:35 INFO - 2016-12-03 05:31:35.504 screenresolution[1636:8413] Display 0: 1600x1200x32@60 05:31:35 INFO - 2016-12-03 05:31:35.510 screenresolution[1637:8417] starting screenresolution argv=screenresolution list 05:31:35 INFO - Available Modes on Display 0 05:31:35 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:31:35 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:31:35 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:31:35 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:31:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:31:35 INFO - Intel Iris: 05:31:35 INFO - Chipset Model: Intel Iris 05:31:35 INFO - Type: GPU 05:31:35 INFO - Bus: Built-In 05:31:35 INFO - VRAM (Dynamic, Max): 1536 MB 05:31:35 INFO - Vendor: Intel (0x8086) 05:31:35 INFO - Device ID: 0x0a2e 05:31:35 INFO - Revision ID: 0x0009 05:31:35 INFO - Displays: 05:31:35 INFO - Display: 05:31:35 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:31:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:31:35 INFO - Display Serial Number: 1600x1200 60 05:31:35 INFO - Main Display: Yes 05:31:35 INFO - Mirror: Off 05:31:35 INFO - Online: Yes 05:31:35 INFO - Rotation: Supported 05:31:35 INFO - Return code: 0 05:31:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:31:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:31:35 INFO - Python 2.7.3 05:31:35 INFO - Return code: 0 05:31:35 INFO - grabbing minidump binary from tooltool 05:31: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')]} 05:31:35 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 0x10e490d30>, '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 0x10e2f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e488b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:31:35 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:31:35 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 05:31:35 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 05:31:35 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:31:35 INFO - Return code: 0 05:31:35 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:31:35 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:31:35 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:31:35 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:31:35 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:31:35 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/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LlY0JE9IR5KIjdeEStiCtQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0025', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 05:31:35 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/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/LlY0JE9IR5KIjdeEStiCtQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0025 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 05:31:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lbbGar9cYE/Render', 05:31:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:31:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:31:35 INFO - 'HOME': '/Users/cltbld', 05:31:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:31:35 INFO - 'LOGNAME': 'cltbld', 05:31:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:31:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:31:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:35 INFO - 'MOZ_NO_REMOTE': '1', 05:31:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:31:35 INFO - 'NO_EM_RESTART': '1', 05:31:35 INFO - 'PAGER': '/bin/cat', 05:31:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:31:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:31:35 INFO - 'PWD': '/builds/slave/test', 05:31:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:31:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:31:35 INFO - 'SHELL': '/bin/bash', 05:31:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kO3k6xlrR5/Listeners', 05:31:35 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 05:31:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:31:35 INFO - 'USER': 'cltbld', 05:31:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:31:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:31:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:35 INFO - 'XPC_FLAGS': '0x0', 05:31:35 INFO - 'XPC_SERVICE_NAME': '0', 05:31:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:31:35 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/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LlY0JE9IR5KIjdeEStiCtQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0025', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 05:31:36 INFO - mozversion application_buildid: 20161203030204 05:31:36 INFO - mozversion application_changeset: 557548714db55136b51e1129d649e2599797985f 05:31:36 INFO - mozversion application_display_name: Nightly 05:31:36 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:31:36 INFO - mozversion application_name: Firefox 05:31:36 INFO - mozversion application_remotingname: firefox 05:31:36 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:31:36 INFO - mozversion application_vendor: Mozilla 05:31:36 INFO - mozversion application_version: 53.0a1 05:31:36 INFO - mozversion platform_buildid: 20161203030204 05:31:36 INFO - mozversion platform_changeset: 557548714db55136b51e1129d649e2599797985f 05:31:36 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:31:36 INFO - mozversion platform_version: 53.0a1 05:31:36 INFO - using testdate: 1480771896 05:31:36 INFO - actual date: 1480771896 05:31:36 INFO - starting webserver on 'localhost:49258' 05:31:36 INFO - SUITE-START | Running 2 tests 05:31:36 INFO - TEST-START | dromaeo_css 05:31:36 INFO - operating with platform_type : mac_ 05:31:36 INFO - Initialising browser for dromaeo_css test... 05:31:36 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/tmpeCsicK/profile http://localhost:49258/getInfo.html) 05:31:39 INFO - PROCESS | 1647 | __metrics Screen width/height:1600/1200 05:31:39 INFO - PROCESS | 1647 | colorDepth:24 05:31:39 INFO - PROCESS | 1647 | Browser inner width/height: 1024/689 05:31:39 INFO - PROCESS | 1647 | __metrics 05:31:39 INFO - PROCESS | 1647 | 2016-12-03 05:31:39.599 plugin-container[1651:8599] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4e4f, name = 'com.apple.tsm.portname' 05:31:39 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:31:39 INFO - PROCESS | 1647 | 2016-12-03 05:31:39.600 plugin-container[1651:8599] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x960b, name = 'com.apple.CFPasteboardClient' 05:31:39 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:31:39 INFO - PROCESS | 1647 | 2016-12-03 05:31:39.600 plugin-container[1651:8599] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:31:39 ERROR - PROCESS | 1647 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3220: TypeError: this.tabs is undefined 05:31:40 INFO - TEST-INFO | 1647: exit 0 05:31:40 INFO - Browser initialized. 05:31:40 INFO - Running cycle 1/1 for dromaeo_css test... 05:31:40 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/tmpeCsicK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:31:43 INFO - PROCESS | 1652 | 2016-12-03 05:31:43.258 plugin-container[1653:8725] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x434b, name = 'com.apple.tsm.portname' 05:31:43 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:31:43 INFO - PROCESS | 1652 | 2016-12-03 05:31:43.259 plugin-container[1653:8725] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 05:31:43 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:31:43 INFO - PROCESS | 1652 | 2016-12-03 05:31:43.259 plugin-container[1653:8725] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:31:52 INFO - PROCESS | 1652 | RSS: Main: 241754112 05:31:52 INFO - PROCESS | 1652 | 05:32:42 INFO - PROCESS | 1652 | 1480771962475 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 05:34:55 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49258/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49258/tests/dromaeo/cssquery-ext.html) 05:34:57 INFO - PROCESS | 1652 | RSS: Main: 250437632 05:34:57 INFO - PROCESS | 1652 | 05:38:00 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49258/tests/dromaeo/cssquery-ext.html (next: http://localhost:49258/tests/dromaeo/cssquery-jquery.html) 05:38:00 INFO - PROCESS | 1652 | RSS: Main: 261885952 05:38:00 INFO - PROCESS | 1652 | 05:41:02 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49258/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49258/tests/dromaeo/cssquery-mootools.html) 05:41:03 INFO - PROCESS | 1652 | RSS: Main: 267186176 05:41:03 INFO - PROCESS | 1652 | 05:44:05 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49258/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49258/tests/dromaeo/cssquery-prototype.html) 05:44:05 INFO - PROCESS | 1652 | RSS: Main: 266571776 05:44:05 INFO - PROCESS | 1652 | 05:47:08 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49258/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49258/tests/dromaeo/cssquery-yui.html) 05:47:09 INFO - PROCESS | 1652 | RSS: Main: 271056896 05:47:09 INFO - PROCESS | 1652 | 05:50:12 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49258/tests/dromaeo/cssquery-yui.html (next: http://localhost:49258/tests/dromaeo/cssquery-dojo.html) 05:50:12 INFO - PROCESS | 1652 | RSS: Main: 273231872 05:50:12 INFO - PROCESS | 1652 | 05:50:12 INFO - PROCESS | 1652 | __start_tp_report 05:50:12 INFO - PROCESS | 1652 | _x_x_mozilla_page_load 05:50:12 INFO - PROCESS | 1652 | _x_x_mozilla_page_load_details 05:50:12 INFO - PROCESS | 1652 | |i|pagename|runs| 05:50:12 INFO - PROCESS | 1652 | |0;dojo.html;2787.4007088475987;3347.782394144382;3350.09547422196;3418.897433077008;3425.469052296895;5603;5624.100143976964;5648.576356773238;5658.8019419320335;5718.313802343714;5657.32112146543;5704.48659620634;5736.942630573682;5784.942150578493;5880.088586269125;5132.640715149941;5153.613478989091;5215.1395019821875;5301.20481927712;5315.893682126355;5266.499682530153;5304.602154838383;5308.92036619451;5319.601029922752;5335.1197052486295;5103.744812759347;5172.922406163911;5246;5246.186841039606;5285.392179899303;4216.241076606209;5130.615203859688;5207.062728708831;5209.60927930403;5233.790648374061;5117.385913690381;5135.12702840518;5155.200943853672;5205;5214.739263036833;4748.691335063209;5294.92057619136;5654.293213348332;5659.830205093813;5696.971515142439;6072.908906366408;6099.512039036867;6105.297890742532;6208.689565521751;6230.5638605297645;6110.801781737181;6121.908171377433;6146.170267013986;6146.985747351681;6241.843953901143;5783.913241301384;5847.356790753014;5857;5867.677277749722;5869.06095024794;6088.391160883876;6182.505399568024;6232.594881332699;6244.469220116341;6246.593971391845;5996.1005849122985;6115.088229002436;6116.541259405473;6126.326104128542;6163;6196.256449226122;6208.565400421927;6211.3478084801345;6212.347703491068;6253.1245625612455;4834.226523756252;5816.738246778905;5851.034579294369;5886.529077673776;5900.704964751745;6061.242344706911;6089.878202436015;6119.84700382494;6128.264608247039;6130.172426722335;4798.088363210979;4811.133995880775;4818.180909245437;4826.444958829779;4863.148948933923;4370.344448332712;4377.146456441027;4405.030893203554;4427.40230068943;4427.69006169565;4857.271409288635;5541.584381171348;5748.540116790647;5749.080147176432;5821;6271.874562508724;6273.62358258506;6284.371562843679;6285.497160227263;6290.654014029181;5821.825345239649;5834.67909264986;5843.737031833578;5848.5321174305955;5892.734826932799;5303.416624171338;5335.866603334947;5353.38436079848;5377.059014672417;5399.865003374868;5499.807506737325;5753.820466804379;6241.656708881055;6246.031865060922;6250.0937364082865;6246.437820596168;6254.937450625435;6264.874702506016;6283.654399008098;6307.117003619405;5338.893222135458;6266.68666566679;6292.119103325446;6307.053941908567;6312.779052733133;6115.113308570143;6218.53360997936;6229.283632382334;6242.875142497034;6253.062040693932;6103.206583144163;6188.628682279078;6215.160953271251;6235.532335074797;6291.591046582141;6100.633961962297;6101.664408457577;6148.508119350352;6157.384261573807;6192.102145188832;6129.295131059986;6147.415995480422;6152.692365381624;6153;6180.072989051679;5649.519790817662;5667.574931880208;5814.680192589448;5877.764889404376;5891.234139562001;6073.878522429438;6081.27024757032;6100.6949652516305;6145.324014358506;6145.463634091477;5981.551383646331;5991.400859913973;5997.280326360865;6001.759929602942;6061.909071363845 05:50:12 INFO - PROCESS | 1652 | |1;ext.html;468.71642656193006;502.98491045268634;504.36701939066467;513.7302915969116;528.6722232216026;29037;30879.845600771994;38459.80770096152;39159.80420097903;39311.21377572447;39591.80204098982;39962.600373996254;39979;40079.59920400795;40084;9282.628694852214;9452.81094378112;9468.24254059677;9472.052794720541;9503.47730874805;8352.958235208815;8554.700585479479;9279.860802087975;11033.889661103407;11310.377929213932;11342.43287835609;11387.943060284688;11390.202685811997;11392.943035284812;11417.200795944287;11067.446627668583;11101.167412444058;11161.051310638606;11176.385298808562;11184.44077796107;11239.752049590115;11307.660770176826;11338.659840204808;11340.603078892282;11350.60272890453;38064.42903356452;38362.61637383646;38366;38408;38430.61569384326;2757.8483183424905;3229.2249860033658;3353.3460975109865;3490.0896225473703;3563.4476656118345;28453.57319640207;28930.132096037152;29616;29701.554476682868;29911.850440747658;3147.7009684079976;3208.727258183034;3258.3483303339426;3270.4767237241927;3289.605247370307;400434;404135;404449;405288;406149.99999999703;56790;57246.85506289851;57735;57897.13154302689;58005;59153.70423147943;60121.39878601158;60207.09689354663;60214.09678854821;60297;11717.88282117185;11993.64019079429;12618.738126187307;15073.547793566211;15258.542243732705;10820.513076911559;11130.499127539118;11557.711057223469;12176.57381991626;12215.694607634703;11812.940935295268;13477.730445391036;13950.511732089532;14127.576172714835;14941.327640256215;5441.966026454961;5493.2034854946605;5651.547876169897;6076.756929722762;6078.1186727924205;771.5255118102326;885.6191837509804;939.5818860606964;989.5794287427815;1023.97440063999;27236.04673836406;28335.539608444047;28652.71347286542;29136.980205693122;29142.979995700473;888.7467071884482;1048.564845589072;1051.5898799468214;1064.9414282214552;1174.1663418972626;12166.330851803235;13075.476980920657;13452;13713.588592342247;13787.655308617164;185079;185610.0719496394;185678;186457;187049;54402.183967240526;54462.910741784945;54608.72695636496;54697.45302547003;54925.90148197013;1291.3607764156616;1327.8008298755071;1334.3061607963805;1345.6904911870129;1348.312360696052;26907.865460672572;28448.43103137967;28513.42973140484;29215.123546293646;29275;47632;47856;48104.27843582279;48205;48271.758641207976;557918;567123.3287667071;570217;575997;576046;29635;33399;38133;38561.03597410087;42880.14239715251;38704.80647596744;39358.803205983786;44627;46567;46869;34362.656373435944;39211.80394098011;39450.60549394584;39984.40023399593;40073.799631002825;37837.24325513529;38122.0469488265;38715.03212419712;39555.80222098871;39644.603553964094;33696.663033370336;38163.42754858624;38700.22599547937;39108;39410;36526;37351.813240933625;38636.420453693776;39392.60607394004;40715.592844072366;29332.853335733183;30634.693653063183;30943.91696290619;31171.37657246797;31264.062078137693 05:50:12 INFO - PROCESS | 1652 | |2;jquery.html;364.42239051103996;370.88687950175205;381.42024123332527;385.3218335729116;401.5923837305136;18389;20032.899835500837;20684.06921688524;20692.275770348042;20872.37382878516;19248.903755481202;19548.804511954877;19849.90075049623;20040.49898752532;20171.495712607193;5016.3980322361185;5686.1755045518585;5817;5990.580659353847;6032.909506357408;5887.293524777011;5994.280686317648;5999.1301261317085;6004.669743164124;6028.186194863703;5086.567641750456;5823.213866128082;5826.047859319587;5942.732577034022;5978.581499295051;4934.432540257881;5044.520770526794;5180.896382072357;5905.793297234576;5917.082852157922;5011.774470148852;5139.820106296262;5174.094533456668;5184.688918664892;5199.428062913076;20033.398998030083;24503.509929801483;24551.508969820505;25045.12342068037;25301.240962771146;1988.5724569217543;2197.505561248722;2214.081156320125;2241.0587553227683;2259.0173274625427;14366.425342986267;15238.619034524225;15297.541073767805;15360;15543.689126217525;1563.9687206255926;1647.6622292430143;1820.827021432975;1837.319814680705;1848.3161230344724;115112;117706;117971;118471.81528184694;118632.40683796526;9049.692328456202;9445.291603129794;10614.73463163427;11028.172887033505;11066.845948904524;11304.547818087349;13677.726445471233;13759.243241621607;13769.31153442324;13798.965077619017;7971.60141992898;8217.917820821835;8284.295834853985;8413.831723365498;8439.746807595782;6974;8128.552929588811;8182.959085204655;8225.547594882339;8268.2558569729;6562.704678289489;6715.2613212546585;6743.494237932076;6773.9322606774285;6868.965655171693;1589.5787616281696;1862.348178137641;1872.2043131668986;1875.399872040961;1886.528367908023;807.7253733730442;811.7848770075934;836.60679480644;863.2230992107028;956.5456408206046;19962.70055949162;21851.78148218529;21891.781082189293;21912.780872191393;22006.229781957554;801.5471258738794;885.5262434597588;892.5894088719132;895.8387490251818;922.3635691372931;10250.58997640101;10258.743531411774;10652.62715804943;11357.261777984404;11621.76756464866;587170;590132;591752;592052;604049;113815.86184138218;124601;127044;131871.34064329616;139525.30237348747;935.6070450410843;938.5307346326837;1011.4386515483977;1015.8780946286493;1021.3310281765456;19403.514912126746;19645.607087857876;19789.30737424183;19872.50318742043;19889.502762430475;49694.75152624214;54655.72672136614;56288;56384.436155637915;56968.715156423954;74936.62531687306;77552.61223693845;78816;82048;84468.57765711378;7116.181639111362;7300.744473943387;7421.962890185515;7984.041914970241;8027.598620068857;10825.350478971286;12837.87162128404;12838;13001.544945926848;13510.662233444242;11039.613613523488;11065.059469944823;11285.153613478858;11367.43162841838;11388.032017278623;10639.734006649896;10839.67480975572;10853.891461085288;11326.207165498336;11418.086553075567;11304.434778261219;11367.602133925273;11627;11740.471678774305;11889.929906308475;7893.881591776013;8088.838223235384;8299.336053115858;8302.916970830456;8415.368847336353;8106.878396823934;9452.007539208284;10570.418626975283;10620.256582039183;11668.124890633406 05:50:12 INFO - PROCESS | 1652 | |3;mootools.html;1449.46369843158;1496.4388354367113;1644.1203955883605;1778.3597904754295;1793.1213705284415;7314.049173607437;7588.20323865993;7839.0985036720795;8282.627281772313;8288.129746376633;7832.099308579515;8089.716859909904;8124.47190932589;8225.670973161066;8252.83494330113;2194.36363454598;2240.820734341257;2240.8991595378247;2243.069126312578;2249.6175650139435;2300.735415427222;2317.7798109179603;2344.120954642009;2344.824138189634;2346.3664810501155;2234.0951914474663;2244.5847518209102;2262.321303608919;2313.618252988255;2316.5251123519724;1872.2604571194438;2269.704938358011;2286.3826766772877;2299.0688771047753;2300.907963681442;2129.2866889592005;2279.87460689662;2303.055747143663;2304.4699719064542;2314.6296592545114;8302.750917472486;8359.414840961135;8407.495550267004;8416.789580260482;8431.030431500394;5030.597552195853;5192.247124166975;5195.974020129913;5196.480351964811;5205.375354957391;2953.7489313408387;3227.951580726291;3546.9113272168142;3562;3563.982180089083;1038.6001389465034;1065.9413732244723;1072.2065671403184;1088.5700148441401;1095.6439157273837;10803.459827008619;10817.675469735841;10861.18541109412;10951.561937522489;10968.616098436516;4487.842925497592;4562.657800664897;4654.2785868190485;4664.790084446208;4743.383360163157;7371.004914336603;7830.7470804670975;9491.477968711788;9508.239340852853;9538.046195380546;4482.5741554552915;5204;5204.0892843752945;5207.765650545734;5219.791208351623;4241.151769646083;4481.35020422037;4709.693869898446;4772.880677983078;4795.520447955247;3773.3585290500682;3799.639034291738;3858.6141385861188;3901.08324543732;3907.199024200032;1286.2539726958341;1290.6386211860695;1291.6577107066635;1295.5400832704443;1300.1289136278715;685.6400389795398;889.9599518021705;897.2059727141396;920.6455514626917;932.5011119051275;5588.636738611977;5622.8313150605545;5767.826965191051;5859.853503662443;5899.528037756969;956.6938579654444;963.1524258652481;972.6820158685022;980.6175591519313;991.177322822056;3835.9041023974623;3847.442120892455;3858.1126340941178;3970.9602903971168;3975.8807235782974;9902.158316543175;10167.23745719074;10217.978202179722;10233.1301839343;10241.385516869012;10160.441175735405;10196.745081373025;10200.0819926207;10225.590976360862;10237.74405639865;7635.930969664251;7723.150453450116;7745.496542724705;7819.960900195462;7875.054993400829;5218.869528261824;5531.421519598369;5620.662760234399;5658.519025882846;5694.772209111589;9226.723198303793;9245.260379169786;9290.62837486493;9294.163525282756;9304.488253146143;8947.955260223658;9378.296627752808;9463.479508626817;9485.288603354638;9541.093596108358;6621.735130594915;6816.863662726817;6966.442684585119;7290.343869051599;7621.35218506411;7747.57388343624;7908.723194688158;8022.237887400687;8032.63853126599;8049.87925181111;7020.684069217;7070.540414873017;7095.893561596684;7100.751473698396;7116.323949224816;6830.8633827324165;6971.163460384583;6984.92186603749;7006.369426751617;7145.356917877208;6468.967655161694;6488.545801793829;6540;6540;6569.80290591283;4772.093302272557;4779;4849.830255941025;4858.416989961086;4995.675281106716;3952.822123004414;4338.414314067561;4875.561199492063;4928.581070609039;5059.342285502861 05:50:12 INFO - PROCESS | 1652 | |4;prototype.html;249.8413507422787;255.1745104586662;278.6266403019953;301.27994094115064;312.1883103929782;5172.2328554223495;5250.4487028861995;5316.867078323044;5431.293931788867;5507.889842203153;5180.6373553851245;5415.969011620642;5416.945830541694;5423.593230507708;5426.484483974026;3777.2058272320633;3829.617038296162;3832.559255685604;3950.407438884176;4137.193247316774;3633.745637805355;4054.493188351456;4139.917201655965;4187.392828039932;4220.388043733672;3741.9064523386996;4072.0023594219456;4153.501579810412;4157.921039480259;4159.9376009359885;4091.713580049398;4124.834461907492;4133.772642504661;4143.067809742814;4160.45914031177;3604.6575575320303;3607.206414588786;3630.6732394084397;3641.6904563112166;3644.416893297086;4153.522344930311;5070.61970352225;5225.242339860737;5323.96182744365;5432.565394768409;3898;4037.515498140242;4673.252279635242;4770.61835053195;4777.665563410563;5431.2396264522995;5775.5957083004205;5779.33537643168;5786.508146807527;5817.7091145442555;3620.8913732587803;3817.541894972593;3891.435741817449;3913.2173565287057;3983.940240896389;5011.5239052290335;5419.861829015911;6046.909296360558;6078.483328917047;6315.684215789192;3335.1651096567725;3434.5363375944426;3966.5438474575217;3997.620226078518;4033.253848038122;3901.648851603369;3974.582668819732;4003.939940900889;4005.8597949072227;4024.7724444044848;4152.584741525884;4636.35091087248;4664.790084446208;4690.437147542317;4705.764711764398;5088.98984900962;5643.633163844337;5894.616849904743;5912.822615321547;5966.373530779292;1244.800831866897;1258.8858859909046;1273.9108262421541;1297.2670441200692;1299.6880748620451;793.8489190673547;816.432579357341;859.4370687199894;873.9868901966476;887.1439061305871;4260.6378457830815;4815.494373090845;4840.661153719277;5176.559992400688;5187.948120518822;4973.577245934064;5548.0013597552825;5584.385717571064;5638.323401191801;5661.471402721242;4157.962598326798;4406.889827754268;4758.405199350082;4817.470078291385;5125.97523978034;2570.8714564271713;2589.805764567627;2634.2229042432145;2733;2995.9400811983637;5141;5692.975264452355;5884.735186916599;5907;5940.910886336708;4989.675671081368;5011.369031258546;5106.463821298785;5190.650430887921;5437.755301011464;5044.445111037783;5428.5385742211565;5853.209816674779;5881.029854998624;5891.646501209855;5486.1222204447095;5671.319441667027;5674.943250567441;5691.345495267931;5705.543556515552;4823.710577365369;5110.386753589593;5312.707801070978;6000.639961602318;6054.00108982013;6315.5894866833505;6316.210473690789;6336;6339.90490142639;6343.492520598434;3588.138846676831;3661.2860492203763;3682.7846810552987;3990.74060186087;4036.959630403776;5400.648957817729;5999.730012149551;6001.669908154919;6019.969900150471;6041.395860413923;4384.4519435070615;5173.741312934413;5191;5393.379761327341;5599.832005039855;4910.9263361050325;5552.194931734956;5846.064629659234;5879.441453061952;5889.057750759858;4558.544145585415;5039.420466646236;5554.111342185231;5746.13807928814;5754.2597311747695;4366.995591014148;5221.451747566451;5256.763445645031;5728.770849165987;5851.473367396955;4317.84887528935;5369.899170669925;5491.505764481216;5685.668875760918;5865.853353666193 05:50:12 INFO - PROCESS | 1652 | |5;yui.html;671.5097978475712;698.9650517474126;699.6501749125438;734.8420089680718;764.9902129189469;14862.554123376303;15176.39294428223;15448.227588620566;15527.844721552781;15754;12309.446074926627;12382;12469.37653117345;12481.875181248184;13006.93496532516;809.1948511231318;833.1293797981094;836.6736972580668;837.2172019162103;837.6398148796015;645.0324513230155;666.0342503370133;803.8995125609299;835.1773503099442;837.1670188162786;870.9825803483926;873.2926329672986;873.628707799186;876.5266755951778;876.6230520876021;829.3489610655637;862.5730263519536;864.127231496187;867.5055218525443;874.0822136756412;643.3020173112179;644.561698045329;645.2934037229227;646.4860435953428;647.5240698086902;14411.92794036023;14438.422463101464;14441.711165776625;14462.783058254134;14566.781498277534;14058.367373468116;14409.423623055065;14556;14694.265286735726;14724.042937209048;10982.286151400134;11410;11617.419129043514;11624;11791.410429478577;3669.3395188865993;3683.7053035757076;3697.4155819656585;3753.0992561785247;3808.276427478798;110007;111383;113648;114160.4291978543;114980;10968.341899486057;11179.273347232403;11198.160137989684;11230.887691122984;11537.88462115368;21667.241646542294;22557.66163507549;22854.8857255716;22866.771332286466;22882.199123030947;7302.3181646036055;7995.720149794845;8086.919130808734;8135.145809690016;8135.593220339078;4154.231467178582;4352.281873490839;4370.628496577763;4372;4427.69006169565;3519.3665140274484;3589;3617.5839778425816;3633.418653015505;3646.6717995380545;637.6652257564723;652.4160876015939;653.9836504087436;658.134553062721;666.037575703114;601.4977493792645;631.8673078653443;633.3191818794814;662.4137638190236;678.9015592739025;7381.446391520657;8451.788705282417;8673.52295623734;9096.8635470468;9876.950615246878;615.1787363869195;636.6848410037061;644.0950464597248;648.9837754056186;686.584616307137;4354.869353919387;4495.685302028826;4497.64018878489;4500.4149460569915;4512.774361281923;112872;113859;117293.82706172828;119142;119333.80666193401;100363;101431.49284253531;102506;102507;104341.43487847688;84606.15393846105;84974;85948.42154735368;86033.13966860376;88323.23353532894;8776.868346974928;8796.252318552995;8836;8898.199162075185;9067.957184923818;49495;50312.496875032244;50402.495975041245;51694.707632309495;53682.73158634182;66672.33327666855;71362.64318678374;73050;73876.26123738909;77642.6117869407;12696;12987.35063246815;13183.14309569875;13347.66630834237;13474.730505390033;14596.270186490845;14793.778093328823;14800.62998425048;14980.982535794703;15497.690046198788;13544.59366219015;13624.727505450033;13680.726385472433;13972.510962116277;14121.717565648834;13700.45198192061;13711.382987765774;13928.930355348159;13958.302084895919;14043.016988810685;13432.597022089352;13616.207113033126;13650.249236292098;13912.304384780518;14031;9492.477913714813;11077.66766996959;11258.549658013588;11631.941840291083;11806.704832379259;9757.902420975983;10208.540615672164;11398.658040258806;11614;11644.475998580254 05:50:12 INFO - PROCESS | 1652 | __end_tp_report 05:50:12 INFO - PROCESS | 1652 | __start_cc_report 05:50:12 INFO - PROCESS | 1652 | _x_x_mozilla_cycle_collect,365 05:50:12 INFO - PROCESS | 1652 | __end_cc_report 05:50:12 INFO - PROCESS | 1652 | __startTimestamp1480773012578__endTimestamp 05:50:12 INFO - PROCESS | 1652 | 05:50:12 INFO - PROCESS | 1652 | ------- Summary: start ------- 05:50:12 INFO - PROCESS | 1652 | Number of tests: 6 05:50:12 INFO - PROCESS | 1652 | 05:50:12 INFO - PROCESS | 1652 | [#0] dojo.html Cycles:165 Average:5686.16 Median:5877.76 stddev:661.15 (11.2%) stddev-sans-first:622.84 05:50:12 INFO - PROCESS | 1652 | Values: 2787.4 3347.8 3350.1 3418.9 3425.5 5603.0 5624.1 5648.6 5658.8 5718.3 5657.3 5704.5 5736.9 5784.9 5880.1 5132.6 5153.6 5215.1 5301.2 5315.9 5266.5 5304.6 5308.9 5319.6 5335.1 5103.7 5172.9 5246.0 5246.2 5285.4 4216.2 5130.6 5207.1 5209.6 5233.8 5117.4 5135.1 5155.2 5205.0 5214.7 4748.7 5294.9 5654.3 5659.8 5697.0 6072.9 6099.5 6105.3 6208.7 6230.6 6110.8 6121.9 6146.2 6147.0 6241.8 5783.9 5847.4 5857.0 5867.7 5869.1 6088.4 6182.5 6232.6 6244.5 6246.6 5996.1 6115.1 6116.5 6126.3 6163.0 6196.3 6208.6 6211.3 6212.3 6253.1 4834.2 5816.7 5851.0 5886.5 5900.7 6061.2 6089.9 6119.8 6128.3 6130.2 4798.1 4811.1 4818.2 4826.4 4863.1 4370.3 4377.1 4405.0 4427.4 4427.7 4857.3 5541.6 5748.5 5749.1 5821.0 6271.9 6273.6 6284.4 6285.5 6290.7 5821.8 5834.7 5843.7 5848.5 5892.7 5303.4 5335.9 5353.4 5377.1 5399.9 5499.8 5753.8 6241.7 6246.0 6250.1 6246.4 6254.9 6264.9 6283.7 6307.1 5338.9 6266.7 6292.1 6307.1 6312.8 6115.1 6218.5 6229.3 6242.9 6253.1 6103.2 6188.6 6215.2 6235.5 6291.6 6100.6 6101.7 6148.5 6157.4 6192.1 6129.3 6147.4 6152.7 6153.0 6180.1 5649.5 5667.6 5814.7 5877.8 5891.2 6073.9 6081.3 6100.7 6145.3 6145.5 5981.6 5991.4 5997.3 6001.8 6061.9 05:50:12 INFO - PROCESS | 1652 | 05:50:12 INFO - PROCESS | 1652 | [#1] ext.html Cycles:180 Average:54573.06 Median:37950.84 stddev:111542.43 (293.9%) stddev-sans-first:111781.36 05:50:12 INFO - PROCESS | 1652 | Values: 468.7 503.0 504.4 513.7 528.7 29037.0 30879.8 38459.8 39159.8 39311.2 39591.8 39962.6 39979.0 40079.6 40084.0 9282.6 9452.8 9468.2 9472.1 9503.5 8353.0 8554.7 9279.9 11033.9 11310.4 11342.4 11387.9 11390.2 11392.9 11417.2 11067.4 11101.2 11161.1 11176.4 11184.4 11239.8 11307.7 11338.7 11340.6 11350.6 38064.4 38362.6 38366.0 38408.0 38430.6 2757.8 3229.2 3353.3 3490.1 3563.4 28453.6 28930.1 29616.0 29701.6 29911.9 3147.7 3208.7 3258.3 3270.5 3289.6 400434.0 404135.0 404449.0 405288.0 406150.0 56790.0 57246.9 57735.0 57897.1 58005.0 59153.7 60121.4 60207.1 60214.1 60297.0 11717.9 11993.6 12618.7 15073.5 15258.5 10820.5 11130.5 11557.7 12176.6 12215.7 11812.9 13477.7 13950.5 14127.6 14941.3 5442.0 5493.2 5651.5 6076.8 6078.1 771.5 885.6 939.6 989.6 1024.0 27236.0 28335.5 28652.7 29137.0 29143.0 888.7 1048.6 1051.6 1064.9 1174.2 12166.3 13075.5 13452.0 13713.6 13787.7 185079.0 185610.1 185678.0 186457.0 187049.0 54402.2 54462.9 54608.7 54697.5 54925.9 1291.4 1327.8 1334.3 1345.7 1348.3 26907.9 28448.4 28513.4 29215.1 29275.0 47632.0 47856.0 48104.3 48205.0 48271.8 557918.0 567123.3 570217.0 575997.0 576046.0 29635.0 33399.0 38133.0 38561.0 42880.1 38704.8 39358.8 44627.0 46567.0 46869.0 34362.7 39211.8 39450.6 39984.4 40073.8 37837.2 38122.0 38715.0 39555.8 39644.6 33696.7 38163.4 38700.2 39108.0 39410.0 36526.0 37351.8 38636.4 39392.6 40715.6 29332.9 30634.7 30943.9 31171.4 31264.1 05:50:12 INFO - PROCESS | 1652 | 05:50:12 INFO - PROCESS | 1652 | [#2] jquery.html Cycles:180 Average:34969.93 Median:24527.51 stddev:99037.33 (403.8%) stddev-sans-first:99281.06 05:50:12 INFO - PROCESS | 1652 | Values: 364.4 370.9 381.4 385.3 401.6 18389.0 20032.9 20684.1 20692.3 20872.4 19248.9 19548.8 19849.9 20040.5 20171.5 5016.4 5686.2 5817.0 5990.6 6032.9 5887.3 5994.3 5999.1 6004.7 6028.2 5086.6 5823.2 5826.0 5942.7 5978.6 4934.4 5044.5 5180.9 5905.8 5917.1 5011.8 5139.8 5174.1 5184.7 5199.4 20033.4 24503.5 24551.5 25045.1 25301.2 1988.6 2197.5 2214.1 2241.1 2259.0 14366.4 15238.6 15297.5 15360.0 15543.7 1564.0 1647.7 1820.8 1837.3 1848.3 115112.0 117706.0 117971.0 118471.8 118632.4 9049.7 9445.3 10614.7 11028.2 11066.8 11304.5 13677.7 13759.2 13769.3 13799.0 7971.6 8217.9 8284.3 8413.8 8439.7 6974.0 8128.6 8183.0 8225.5 8268.3 6562.7 6715.3 6743.5 6773.9 6869.0 1589.6 1862.3 1872.2 1875.4 1886.5 807.7 811.8 836.6 863.2 956.5 19962.7 21851.8 21891.8 21912.8 22006.2 801.5 885.5 892.6 895.8 922.4 10250.6 10258.7 10652.6 11357.3 11621.8 587170.0 590132.0 591752.0 592052.0 604049.0 113815.9 124601.0 127044.0 131871.3 139525.3 935.6 938.5 1011.4 1015.9 1021.3 19403.5 19645.6 19789.3 19872.5 19889.5 49694.8 54655.7 56288.0 56384.4 56968.7 74936.6 77552.6 78816.0 82048.0 84468.6 7116.2 7300.7 7422.0 7984.0 8027.6 10825.4 12837.9 12838.0 13001.5 13510.7 11039.6 11065.1 11285.2 11367.4 11388.0 10639.7 10839.7 10853.9 11326.2 11418.1 11304.4 11367.6 11627.0 11740.5 11889.9 7893.9 8088.8 8299.3 8302.9 8415.4 8106.9 9452.0 10570.4 10620.3 11668.1 05:50:12 INFO - PROCESS | 1652 | 05:50:12 INFO - PROCESS | 1652 | [#3] mootools.html Cycles:180 Average:5411.79 Median:5125.79 stddev:2959.52 (57.7%) stddev-sans-first:2952.84 05:50:12 INFO - PROCESS | 1652 | Values: 1449.5 1496.4 1644.1 1778.4 1793.1 7314.0 7588.2 7839.1 8282.6 8288.1 7832.1 8089.7 8124.5 8225.7 8252.8 2194.4 2240.8 2240.9 2243.1 2249.6 2300.7 2317.8 2344.1 2344.8 2346.4 2234.1 2244.6 2262.3 2313.6 2316.5 1872.3 2269.7 2286.4 2299.1 2300.9 2129.3 2279.9 2303.1 2304.5 2314.6 8302.8 8359.4 8407.5 8416.8 8431.0 5030.6 5192.2 5196.0 5196.5 5205.4 2953.7 3228.0 3546.9 3562.0 3564.0 1038.6 1065.9 1072.2 1088.6 1095.6 10803.5 10817.7 10861.2 10951.6 10968.6 4487.8 4562.7 4654.3 4664.8 4743.4 7371.0 7830.7 9491.5 9508.2 9538.0 4482.6 5204.0 5204.1 5207.8 5219.8 4241.2 4481.4 4709.7 4772.9 4795.5 3773.4 3799.6 3858.6 3901.1 3907.2 1286.3 1290.6 1291.7 1295.5 1300.1 685.6 890.0 897.2 920.6 932.5 5588.6 5622.8 5767.8 5859.9 5899.5 956.7 963.2 972.7 980.6 991.2 3835.9 3847.4 3858.1 3971.0 3975.9 9902.2 10167.2 10218.0 10233.1 10241.4 10160.4 10196.7 10200.1 10225.6 10237.7 7635.9 7723.2 7745.5 7820.0 7875.1 5218.9 5531.4 5620.7 5658.5 5694.8 9226.7 9245.3 9290.6 9294.2 9304.5 8948.0 9378.3 9463.5 9485.3 9541.1 6621.7 6816.9 6966.4 7290.3 7621.4 7747.6 7908.7 8022.2 8032.6 8049.9 7020.7 7070.5 7095.9 7100.8 7116.3 6830.9 6971.2 6984.9 7006.4 7145.4 6469.0 6488.5 6540.0 6540.0 6569.8 4772.1 4779.0 4849.8 4858.4 4995.7 3952.8 4338.4 4875.6 4928.6 5059.3 05:50:12 INFO - PROCESS | 1652 | 05:50:12 INFO - PROCESS | 1652 | [#4] prototype.html Cycles:180 Average:4547.64 Median:5079.80 stddev:1426.66 (28.1%) stddev-sans-first:1393.71 05:50:12 INFO - PROCESS | 1652 | Values: 249.8 255.2 278.6 301.3 312.2 5172.2 5250.4 5316.9 5431.3 5507.9 5180.6 5416.0 5416.9 5423.6 5426.5 3777.2 3829.6 3832.6 3950.4 4137.2 3633.7 4054.5 4139.9 4187.4 4220.4 3741.9 4072.0 4153.5 4157.9 4159.9 4091.7 4124.8 4133.8 4143.1 4160.5 3604.7 3607.2 3630.7 3641.7 3644.4 4153.5 5070.6 5225.2 5324.0 5432.6 3898.0 4037.5 4673.3 4770.6 4777.7 5431.2 5775.6 5779.3 5786.5 5817.7 3620.9 3817.5 3891.4 3913.2 3983.9 5011.5 5419.9 6046.9 6078.5 6315.7 3335.2 3434.5 3966.5 3997.6 4033.3 3901.6 3974.6 4003.9 4005.9 4024.8 4152.6 4636.4 4664.8 4690.4 4705.8 5089.0 5643.6 5894.6 5912.8 5966.4 1244.8 1258.9 1273.9 1297.3 1299.7 793.8 816.4 859.4 874.0 887.1 4260.6 4815.5 4840.7 5176.6 5187.9 4973.6 5548.0 5584.4 5638.3 5661.5 4158.0 4406.9 4758.4 4817.5 5126.0 2570.9 2589.8 2634.2 2733.0 2995.9 5141.0 5693.0 5884.7 5907.0 5940.9 4989.7 5011.4 5106.5 5190.7 5437.8 5044.4 5428.5 5853.2 5881.0 5891.6 5486.1 5671.3 5674.9 5691.3 5705.5 4823.7 5110.4 5312.7 6000.6 6054.0 6315.6 6316.2 6336.0 6339.9 6343.5 3588.1 3661.3 3682.8 3990.7 4037.0 5400.6 5999.7 6001.7 6020.0 6041.4 4384.5 5173.7 5191.0 5393.4 5599.8 4910.9 5552.2 5846.1 5879.4 5889.1 4558.5 5039.4 5554.1 5746.1 5754.3 4367.0 5221.5 5256.8 5728.8 5851.5 4317.8 5369.9 5491.5 5685.7 5865.9 05:50:12 INFO - PROCESS | 1652 | 05:50:12 INFO - PROCESS | 1652 | [#5] yui.html Cycles:180 Average:21749.53 Median:3725.26 stddev:32621.34 (875.7%) stddev-sans-first:32674.46 05:50:12 INFO - PROCESS | 1652 | Values: 671.5 699.0 699.7 734.8 765.0 14862.6 15176.4 15448.2 15527.8 15754.0 12309.4 12382.0 12469.4 12481.9 13006.9 809.2 833.1 836.7 837.2 837.6 645.0 666.0 803.9 835.2 837.2 871.0 873.3 873.6 876.5 876.6 829.3 862.6 864.1 867.5 874.1 643.3 644.6 645.3 646.5 647.5 14411.9 14438.4 14441.7 14462.8 14566.8 14058.4 14409.4 14556.0 14694.3 14724.0 10982.3 11410.0 11617.4 11624.0 11791.4 3669.3 3683.7 3697.4 3753.1 3808.3 110007.0 111383.0 113648.0 114160.4 114980.0 10968.3 11179.3 11198.2 11230.9 11537.9 21667.2 22557.7 22854.9 22866.8 22882.2 7302.3 7995.7 8086.9 8135.1 8135.6 4154.2 4352.3 4370.6 4372.0 4427.7 3519.4 3589.0 3617.6 3633.4 3646.7 637.7 652.4 654.0 658.1 666.0 601.5 631.9 633.3 662.4 678.9 7381.4 8451.8 8673.5 9096.9 9877.0 615.2 636.7 644.1 649.0 686.6 4354.9 4495.7 4497.6 4500.4 4512.8 112872.0 113859.0 117293.8 119142.0 119333.8 100363.0 101431.5 102506.0 102507.0 104341.4 84606.2 84974.0 85948.4 86033.1 88323.2 8776.9 8796.3 8836.0 8898.2 9068.0 49495.0 50312.5 50402.5 51694.7 53682.7 66672.3 71362.6 73050.0 73876.3 77642.6 12696.0 12987.4 13183.1 13347.7 13474.7 14596.3 14793.8 14800.6 14981.0 15497.7 13544.6 13624.7 13680.7 13972.5 14121.7 13700.5 13711.4 13928.9 13958.3 14043.0 13432.6 13616.2 13650.2 13912.3 14031.0 9492.5 11077.7 11258.5 11631.9 11806.7 9757.9 10208.5 11398.7 11614.0 11644.5 05:50:12 INFO - PROCESS | 1652 | -------- Summary: end -------- 05:50:12 INFO - PROCESS | 1652 | 05:50:13 INFO - TEST-INFO | 1652: exit 0 05:50:13 INFO - TEST-OK | dromaeo_css | took 1117081ms 05:50:13 INFO - TEST-START | kraken 05:50:13 INFO - operating with platform_type : mac_ 05:50:13 INFO - Initialising browser for kraken test... 05:50:13 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/tmpzsh016/profile http://localhost:49258/getInfo.html) 05:50:16 INFO - PROCESS | 1659 | __metrics Screen width/height:1600/1200 05:50:16 INFO - PROCESS | 1659 | colorDepth:24 05:50:16 INFO - PROCESS | 1659 | Browser inner width/height: 1024/689 05:50:16 INFO - PROCESS | 1659 | __metrics 05:50:16 INFO - PROCESS | 1659 | 2016-12-03 05:50:16.249 plugin-container[1661:11000] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 05:50:16 INFO - PROCESS | 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:50:16 INFO - PROCESS | 1659 | 2016-12-03 05:50:16.249 plugin-container[1661:11000] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 05:50:16 INFO - PROCESS | 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:50:16 INFO - PROCESS | 1659 | 2016-12-03 05:50:16.249 plugin-container[1661:11000] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:50:16 ERROR - PROCESS | 1659 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3220: TypeError: this.tabs is undefined 05:50:17 INFO - TEST-INFO | 1659: exit 0 05:50:17 INFO - Browser initialized. 05:50:17 INFO - Running cycle 1/1 for kraken test... 05:50:17 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/tmpzsh016/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:50:20 INFO - PROCESS | 1662 | 2016-12-03 05:50:20.086 plugin-container[1663:11130] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 05:50:20 INFO - PROCESS | 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:50:20 INFO - PROCESS | 1662 | 2016-12-03 05:50:20.087 plugin-container[1663:11130] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0f, name = 'com.apple.CFPasteboardClient' 05:50:20 INFO - PROCESS | 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:50:20 INFO - PROCESS | 1662 | 2016-12-03 05:50:20.087 plugin-container[1663:11130] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:50:29 INFO - PROCESS | 1662 | RSS: Main: 241762304 05:50:29 INFO - PROCESS | 1662 | 05:50:32 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49258/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49258/tests/kraken/driver.html?testName=audio-beat-detection) 05:50:33 INFO - PROCESS | 1662 | RSS: Main: 253837312 05:50:33 INFO - PROCESS | 1662 | 05:50:36 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49258/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49258/tests/kraken/driver.html?testName=audio-dft) 05:50:36 INFO - PROCESS | 1662 | RSS: Main: 261574656 05:50:36 INFO - PROCESS | 1662 | 05:50:40 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49258/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49258/tests/kraken/driver.html?testName=audio-fft) 05:50:40 INFO - PROCESS | 1662 | RSS: Main: 264196096 05:50:40 INFO - PROCESS | 1662 | 05:50:42 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49258/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49258/tests/kraken/driver.html?testName=audio-oscillator) 05:50:43 INFO - PROCESS | 1662 | RSS: Main: 265404416 05:50:43 INFO - PROCESS | 1662 | 05:50:45 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49258/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49258/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:50:45 INFO - PROCESS | 1662 | RSS: Main: 267108352 05:50:45 INFO - PROCESS | 1662 | 05:50:48 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49258/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49258/tests/kraken/driver.html?testName=imaging-darkroom) 05:50:49 INFO - PROCESS | 1662 | RSS: Main: 272551936 05:50:49 INFO - PROCESS | 1662 | 05:50:52 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49258/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49258/tests/kraken/driver.html?testName=imaging-desaturate) 05:50:53 INFO - PROCESS | 1662 | RSS: Main: 271933440 05:50:53 INFO - PROCESS | 1662 | 05:50:56 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49258/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49258/tests/kraken/driver.html?testName=json-parse-financial) 05:50:56 INFO - PROCESS | 1662 | RSS: Main: 272154624 05:50:56 INFO - PROCESS | 1662 | 05:50:58 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49258/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49258/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:50:59 INFO - PROCESS | 1662 | RSS: Main: 271044608 05:50:59 INFO - PROCESS | 1662 | 05:51:00 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49258/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49258/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:51:01 INFO - PROCESS | 1662 | RSS: Main: 271147008 05:51:01 INFO - PROCESS | 1662 | 05:51:03 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49258/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49258/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:51:04 INFO - PROCESS | 1662 | RSS: Main: 271458304 05:51:04 INFO - PROCESS | 1662 | 05:51:07 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49258/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49258/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:51:08 INFO - PROCESS | 1662 | RSS: Main: 271765504 05:51:08 INFO - PROCESS | 1662 | 05:51:11 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49258/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49258/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:51:11 INFO - PROCESS | 1662 | RSS: Main: 271978496 05:51:11 INFO - PROCESS | 1662 | 05:51:13 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49258/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49258/tests/kraken/driver.html?testName=ai-astar) 05:51:13 INFO - PROCESS | 1662 | RSS: Main: 272515072 05:51:13 INFO - PROCESS | 1662 | 05:51:13 INFO - PROCESS | 1662 | __start_tp_report 05:51:13 INFO - PROCESS | 1662 | _x_x_mozilla_page_load 05:51:13 INFO - PROCESS | 1662 | _x_x_mozilla_page_load_details 05:51:13 INFO - PROCESS | 1662 | |i|pagename|runs| 05:51:13 INFO - PROCESS | 1662 | |0;ai-astar;127;170;155;120;116;117;117;123;117;99 05:51:13 INFO - PROCESS | 1662 | |1;audio-beat-detection;183;120;123;185;153;121;120;121;134;118 05:51:13 INFO - PROCESS | 1662 | |2;audio-dft;202;232;196;194;196;198;196;194;200;190 05:51:13 INFO - PROCESS | 1662 | |3;audio-fft;89;59;56;77;61;78;84;53;85;75 05:51:13 INFO - PROCESS | 1662 | |4;audio-oscillator;89;71;82;71;70;71;82;70;72;81 05:51:13 INFO - PROCESS | 1662 | |5;imaging-gaussian-blur;93;87;88;86;88;103;89;77;134;130 05:51:13 INFO - PROCESS | 1662 | |6;imaging-darkroom;100;174;99;99;112;100;98;105;99;100 05:51:13 INFO - PROCESS | 1662 | |7;imaging-desaturate;117;95;92;91;92;92;92;102;141;125 05:51:13 INFO - PROCESS | 1662 | |8;json-parse-financial;58;58;57;55;50;56;61;66;76;90 05:51:13 INFO - PROCESS | 1662 | |9;json-stringify-tinderbox;59;106;98;79;76;71;70;72;72;73 05:51:13 INFO - PROCESS | 1662 | |10;stanford-crypto-aes;93;89;91;93;91;109;91;89;88;92 05:51:13 INFO - PROCESS | 1662 | |11;stanford-crypto-ccm;149;151;146;150;151;167;175;162;220;167 05:51:13 INFO - PROCESS | 1662 | |12;stanford-crypto-pbkdf2;177;178;177;178;175;237;198;177;174;227 05:51:13 INFO - PROCESS | 1662 | |13;stanford-crypto-sha256-iterative;67;72;72;72;73;67;71;70;69;73 05:51:13 INFO - PROCESS | 1662 | __end_tp_report 05:51:13 INFO - PROCESS | 1662 | __start_cc_report 05:51:13 INFO - PROCESS | 1662 | _x_x_mozilla_cycle_collect,925 05:51:13 INFO - PROCESS | 1662 | __end_cc_report 05:51:13 INFO - PROCESS | 1662 | __startTimestamp1480773073948__endTimestamp 05:51:13 INFO - PROCESS | 1662 | 05:51:13 INFO - PROCESS | 1662 | ------- Summary: start ------- 05:51:13 INFO - PROCESS | 1662 | Number of tests: 14 05:51:13 INFO - PROCESS | 1662 | 05:51:13 INFO - PROCESS | 1662 | [#0] ai-astar Cycles:10 Average:126.10 Median:125.00 stddev:20.80 (16.6%) stddev-sans-first:22.06 05:51:13 INFO - PROCESS | 1662 | Values: 127.0 170.0 155.0 120.0 116.0 117.0 117.0 123.0 117.0 99.0 05:51:13 INFO - PROCESS | 1662 | 05:51:13 INFO - PROCESS | 1662 | [#1] audio-beat-detection Cycles:10 Average:137.80 Median:128.50 stddev:26.51 (20.6%) stddev-sans-first:22.52 05:51:13 INFO - PROCESS | 1662 | Values: 183.0 120.0 123.0 185.0 153.0 121.0 120.0 121.0 134.0 118.0 05:51:13 INFO - PROCESS | 1662 | 05:51:13 INFO - PROCESS | 1662 | [#2] audio-dft Cycles:10 Average:199.80 Median:197.00 stddev:11.79 (6.0%) stddev-sans-first:12.48 05:51:13 INFO - PROCESS | 1662 | Values: 202.0 232.0 196.0 194.0 196.0 198.0 196.0 194.0 200.0 190.0 05:51:13 INFO - PROCESS | 1662 | 05:51:13 INFO - PROCESS | 1662 | [#3] audio-fft Cycles:10 Average:71.70 Median:77.50 stddev:13.24 (17.1%) stddev-sans-first:12.48 05:51:13 INFO - PROCESS | 1662 | Values: 89.0 59.0 56.0 77.0 61.0 78.0 84.0 53.0 85.0 75.0 05:51:13 INFO - PROCESS | 1662 | 05:51:13 INFO - PROCESS | 1662 | [#4] audio-oscillator Cycles:10 Average:75.90 Median:76.50 stddev:6.90 (9.0%) stddev-sans-first:5.46 05:51:13 INFO - PROCESS | 1662 | Values: 89.0 71.0 82.0 71.0 70.0 71.0 82.0 70.0 72.0 81.0 05:51:13 INFO - PROCESS | 1662 | 05:51:13 INFO - PROCESS | 1662 | [#5] imaging-gaussian-blur Cycles:10 Average:97.50 Median:87.50 stddev:19.31 (22.1%) stddev-sans-first:20.41 05:51:13 INFO - PROCESS | 1662 | Values: 93.0 87.0 88.0 86.0 88.0 103.0 89.0 77.0 134.0 130.0 05:51:13 INFO - PROCESS | 1662 | 05:51:13 INFO - PROCESS | 1662 | [#6] imaging-darkroom Cycles:10 Average:108.60 Median:136.00 stddev:23.36 (17.2%) stddev-sans-first:24.57 05:51:13 INFO - PROCESS | 1662 | Values: 100.0 174.0 99.0 99.0 112.0 100.0 98.0 105.0 99.0 100.0 05:51:13 INFO - PROCESS | 1662 | 05:51:13 INFO - PROCESS | 1662 | [#7] imaging-desaturate Cycles:10 Average:103.90 Median:92.00 stddev:17.67 (19.2%) stddev-sans-first:18.09 05:51:13 INFO - PROCESS | 1662 | Values: 117.0 95.0 92.0 91.0 92.0 92.0 92.0 102.0 141.0 125.0 05:51:13 INFO - PROCESS | 1662 | 05:51:13 INFO - PROCESS | 1662 | [#8] json-parse-financial Cycles:10 Average:62.70 Median:59.50 stddev:11.92 (20.0%) stddev-sans-first:12.52 05:51:13 INFO - PROCESS | 1662 | Values: 58.0 58.0 57.0 55.0 50.0 56.0 61.0 66.0 76.0 90.0 05:51:13 INFO - PROCESS | 1662 | 05:51:13 INFO - PROCESS | 1662 | [#9] json-stringify-tinderbox Cycles:10 Average:77.60 Median:72.50 stddev:13.98 (19.3%) stddev-sans-first:13.11 05:51:13 INFO - PROCESS | 1662 | Values: 59.0 106.0 98.0 79.0 76.0 71.0 70.0 72.0 72.0 73.0 05:51:13 INFO - PROCESS | 1662 | 05:51:13 INFO - PROCESS | 1662 | [#10] stanford-crypto-aes Cycles:10 Average:92.60 Median:91.00 stddev:6.00 (6.6%) stddev-sans-first:6.37 05:51:13 INFO - PROCESS | 1662 | Values: 93.0 89.0 91.0 93.0 91.0 109.0 91.0 89.0 88.0 92.0 05:51:13 INFO - PROCESS | 1662 | 05:51:13 INFO - PROCESS | 1662 | [#11] stanford-crypto-ccm Cycles:10 Average:163.80 Median:164.50 stddev:22.01 (13.4%) stddev-sans-first:22.69 05:51:13 INFO - PROCESS | 1662 | Values: 149.0 151.0 146.0 150.0 151.0 167.0 175.0 162.0 220.0 167.0 05:51:13 INFO - PROCESS | 1662 | 05:51:13 INFO - PROCESS | 1662 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:189.80 Median:178.00 stddev:23.38 (13.1%) stddev-sans-first:24.33 05:51:13 INFO - PROCESS | 1662 | Values: 177.0 178.0 177.0 178.0 175.0 237.0 198.0 177.0 174.0 227.0 05:51:13 INFO - PROCESS | 1662 | 05:51:13 INFO - PROCESS | 1662 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.60 Median:72.00 stddev:2.27 (3.2%) stddev-sans-first:2.00 05:51:13 INFO - PROCESS | 1662 | Values: 67.0 72.0 72.0 72.0 73.0 67.0 71.0 70.0 69.0 73.0 05:51:13 INFO - PROCESS | 1662 | -------- Summary: end -------- 05:51:13 INFO - PROCESS | 1662 | 05:51:14 INFO - TEST-INFO | 1662: exit 0 05:51:15 INFO - TEST-OK | kraken | took 61781ms 05:51:15 INFO - SUITE-END | took 1178s 05:51:15 INFO - Completed test suite (00:19:38) 05:51:15 INFO - javascript benchmark 05:51:15 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7173.078529292501, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2787.4007088475987, 3347.782394144382, 3350.09547422196, 3418.897433077008, 3425.469052296895, 5603.0, 5624.100143976964, 5648.576356773238, 5658.8019419320335, 5718.313802343714, 5657.32112146543, 5704.48659620634, 5736.942630573682, 5784.942150578493, 5880.088586269125, 5132.640715149941, 5153.613478989091, 5215.1395019821875, 5301.20481927712, 5315.893682126355, 5266.499682530153, 5304.602154838383, 5308.92036619451, 5319.601029922752, 5335.1197052486295, 5103.744812759347, 5172.922406163911, 5246.0, 5246.186841039606, 5285.392179899303, 4216.241076606209, 5130.615203859688, 5207.062728708831, 5209.60927930403, 5233.790648374061, 5117.385913690381, 5135.12702840518, 5155.200943853672, 5205.0, 5214.739263036833, 4748.691335063209, 5294.92057619136, 5654.293213348332, 5659.830205093813, 5696.971515142439, 6072.908906366408, 6099.512039036867, 6105.297890742532, 6208.689565521751, 6230.5638605297645, 6110.801781737181, 6121.908171377433, 6146.170267013986, 6146.985747351681, 6241.843953901143, 5783.913241301384, 5847.356790753014, 5857.0, 5867.677277749722, 5869.06095024794, 6088.391160883876, 6182.505399568024, 6232.594881332699, 6244.469220116341, 6246.593971391845, 5996.1005849122985, 6115.088229002436, 6116.541259405473, 6126.326104128542, 6163.0, 6196.256449226122, 6208.565400421927, 6211.3478084801345, 6212.347703491068, 6253.1245625612455, 4834.226523756252, 5816.738246778905, 5851.034579294369, 5886.529077673776, 5900.704964751745, 6061.242344706911, 6089.878202436015, 6119.84700382494, 6128.264608247039, 6130.172426722335, 4798.088363210979, 4811.133995880775, 4818.180909245437, 4826.444958829779, 4863.148948933923, 4370.344448332712, 4377.146456441027, 4405.030893203554, 4427.40230068943, 4427.69006169565, 4857.271409288635, 5541.584381171348, 5748.540116790647, 5749.080147176432, 5821.0, 6271.874562508724, 6273.62358258506, 6284.371562843679, 6285.497160227263, 6290.654014029181, 5821.825345239649, 5834.67909264986, 5843.737031833578, 5848.5321174305955, 5892.734826932799, 5303.416624171338, 5335.866603334947, 5353.38436079848, 5377.059014672417, 5399.865003374868, 5499.807506737325, 5753.820466804379, 6241.656708881055, 6246.031865060922, 6250.0937364082865, 6246.437820596168, 6254.937450625435, 6264.874702506016, 6283.654399008098, 6307.117003619405, 5338.893222135458, 6266.68666566679, 6292.119103325446, 6307.053941908567, 6312.779052733133, 6115.113308570143, 6218.53360997936, 6229.283632382334, 6242.875142497034, 6253.062040693932, 6103.206583144163, 6188.628682279078, 6215.160953271251, 6235.532335074797, 6291.591046582141, 6100.633961962297, 6101.664408457577, 6148.508119350352, 6157.384261573807, 6192.102145188832, 6129.295131059986, 6147.415995480422, 6152.692365381624, 6153.0, 6180.072989051679, 5649.519790817662, 5667.574931880208, 5814.680192589448, 5877.764889404376, 5891.234139562001, 6073.878522429438, 6081.27024757032, 6100.6949652516305, 6145.324014358506, 6145.463634091477, 5981.551383646331, 5991.400859913973, 5997.280326360865, 6001.759929602942, 6061.909071363845], "value": 5643.269349806298, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [468.71642656193006, 502.98491045268634, 504.36701939066467, 513.7302915969116, 528.6722232216026, 29037.0, 30879.845600771994, 38459.80770096152, 39159.80420097903, 39311.21377572447, 39591.80204098982, 39962.600373996254, 39979.0, 40079.59920400795, 40084.0, 9282.628694852214, 9452.81094378112, 9468.24254059677, 9472.052794720541, 9503.47730874805, 8352.958235208815, 8554.700585479479, 9279.860802087975, 11033.889661103407, 11310.377929213932, 11342.43287835609, 11387.943060284688, 11390.202685811997, 11392.943035284812, 11417.200795944287, 11067.446627668583, 11101.167412444058, 11161.051310638606, 11176.385298808562, 11184.44077796107, 11239.752049590115, 11307.660770176826, 11338.659840204808, 11340.603078892282, 11350.60272890453, 38064.42903356452, 38362.61637383646, 38366.0, 38408.0, 38430.61569384326, 2757.8483183424905, 3229.2249860033658, 3353.3460975109865, 3490.0896225473703, 3563.4476656118345, 28453.57319640207, 28930.132096037152, 29616.0, 29701.554476682868, 29911.850440747658, 3147.7009684079976, 3208.727258183034, 3258.3483303339426, 3270.4767237241927, 3289.605247370307, 400434.0, 404135.0, 404449.0, 405288.0, 406149.99999999703, 56790.0, 57246.85506289851, 57735.0, 57897.13154302689, 58005.0, 59153.70423147943, 60121.39878601158, 60207.09689354663, 60214.09678854821, 60297.0, 11717.88282117185, 11993.64019079429, 12618.738126187307, 15073.547793566211, 15258.542243732705, 10820.513076911559, 11130.499127539118, 11557.711057223469, 12176.57381991626, 12215.694607634703, 11812.940935295268, 13477.730445391036, 13950.511732089532, 14127.576172714835, 14941.327640256215, 5441.966026454961, 5493.2034854946605, 5651.547876169897, 6076.756929722762, 6078.1186727924205, 771.5255118102326, 885.6191837509804, 939.5818860606964, 989.5794287427815, 1023.97440063999, 27236.04673836406, 28335.539608444047, 28652.71347286542, 29136.980205693122, 29142.979995700473, 888.7467071884482, 1048.564845589072, 1051.5898799468214, 1064.9414282214552, 1174.1663418972626, 12166.330851803235, 13075.476980920657, 13452.0, 13713.588592342247, 13787.655308617164, 185079.0, 185610.0719496394, 185678.0, 186457.0, 187049.0, 54402.183967240526, 54462.910741784945, 54608.72695636496, 54697.45302547003, 54925.90148197013, 1291.3607764156616, 1327.8008298755071, 1334.3061607963805, 1345.6904911870129, 1348.312360696052, 26907.865460672572, 28448.43103137967, 28513.42973140484, 29215.123546293646, 29275.0, 47632.0, 47856.0, 48104.27843582279, 48205.0, 48271.758641207976, 557918.0, 567123.3287667071, 570217.0, 575997.0, 576046.0, 29635.0, 33399.0, 38133.0, 38561.03597410087, 42880.14239715251, 38704.80647596744, 39358.803205983786, 44627.0, 46567.0, 46869.0, 34362.656373435944, 39211.80394098011, 39450.60549394584, 39984.40023399593, 40073.799631002825, 37837.24325513529, 38122.0469488265, 38715.03212419712, 39555.80222098871, 39644.603553964094, 33696.663033370336, 38163.42754858624, 38700.22599547937, 39108.0, 39410.0, 36526.0, 37351.813240933625, 38636.420453693776, 39392.60607394004, 40715.592844072366, 29332.853335733183, 30634.693653063183, 30943.91696290619, 31171.37657246797, 31264.062078137693], "value": 18817.025611796398, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [364.42239051103996, 370.88687950175205, 381.42024123332527, 385.3218335729116, 401.5923837305136, 18389.0, 20032.899835500837, 20684.06921688524, 20692.275770348042, 20872.37382878516, 19248.903755481202, 19548.804511954877, 19849.90075049623, 20040.49898752532, 20171.495712607193, 5016.3980322361185, 5686.1755045518585, 5817.0, 5990.580659353847, 6032.909506357408, 5887.293524777011, 5994.280686317648, 5999.1301261317085, 6004.669743164124, 6028.186194863703, 5086.567641750456, 5823.213866128082, 5826.047859319587, 5942.732577034022, 5978.581499295051, 4934.432540257881, 5044.520770526794, 5180.896382072357, 5905.793297234576, 5917.082852157922, 5011.774470148852, 5139.820106296262, 5174.094533456668, 5184.688918664892, 5199.428062913076, 20033.398998030083, 24503.509929801483, 24551.508969820505, 25045.12342068037, 25301.240962771146, 1988.5724569217543, 2197.505561248722, 2214.081156320125, 2241.0587553227683, 2259.0173274625427, 14366.425342986267, 15238.619034524225, 15297.541073767805, 15360.0, 15543.689126217525, 1563.9687206255926, 1647.6622292430143, 1820.827021432975, 1837.319814680705, 1848.3161230344724, 115112.0, 117706.0, 117971.0, 118471.81528184694, 118632.40683796526, 9049.692328456202, 9445.291603129794, 10614.73463163427, 11028.172887033505, 11066.845948904524, 11304.547818087349, 13677.726445471233, 13759.243241621607, 13769.31153442324, 13798.965077619017, 7971.60141992898, 8217.917820821835, 8284.295834853985, 8413.831723365498, 8439.746807595782, 6974.0, 8128.552929588811, 8182.959085204655, 8225.547594882339, 8268.2558569729, 6562.704678289489, 6715.2613212546585, 6743.494237932076, 6773.9322606774285, 6868.965655171693, 1589.5787616281696, 1862.348178137641, 1872.2043131668986, 1875.399872040961, 1886.528367908023, 807.7253733730442, 811.7848770075934, 836.60679480644, 863.2230992107028, 956.5456408206046, 19962.70055949162, 21851.78148218529, 21891.781082189293, 21912.780872191393, 22006.229781957554, 801.5471258738794, 885.5262434597588, 892.5894088719132, 895.8387490251818, 922.3635691372931, 10250.58997640101, 10258.743531411774, 10652.62715804943, 11357.261777984404, 11621.76756464866, 587170.0, 590132.0, 591752.0, 592052.0, 604049.0, 113815.86184138218, 124601.0, 127044.0, 131871.34064329616, 139525.30237348747, 935.6070450410843, 938.5307346326837, 1011.4386515483977, 1015.8780946286493, 1021.3310281765456, 19403.514912126746, 19645.607087857876, 19789.30737424183, 19872.50318742043, 19889.502762430475, 49694.75152624214, 54655.72672136614, 56288.0, 56384.436155637915, 56968.715156423954, 74936.62531687306, 77552.61223693845, 78816.0, 82048.0, 84468.57765711378, 7116.181639111362, 7300.744473943387, 7421.962890185515, 7984.041914970241, 8027.598620068857, 10825.350478971286, 12837.87162128404, 12838.0, 13001.544945926848, 13510.662233444242, 11039.613613523488, 11065.059469944823, 11285.153613478858, 11367.43162841838, 11388.032017278623, 10639.734006649896, 10839.67480975572, 10853.891461085288, 11326.207165498336, 11418.086553075567, 11304.434778261219, 11367.602133925273, 11627.0, 11740.471678774305, 11889.929906308475, 7893.881591776013, 8088.838223235384, 8299.336053115858, 8302.916970830456, 8415.368847336353, 8106.878396823934, 9452.007539208284, 10570.418626975283, 10620.256582039183, 11668.124890633406], "value": 9543.10817481607, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1449.46369843158, 1496.4388354367113, 1644.1203955883605, 1778.3597904754295, 1793.1213705284415, 7314.049173607437, 7588.20323865993, 7839.0985036720795, 8282.627281772313, 8288.129746376633, 7832.099308579515, 8089.716859909904, 8124.47190932589, 8225.670973161066, 8252.83494330113, 2194.36363454598, 2240.820734341257, 2240.8991595378247, 2243.069126312578, 2249.6175650139435, 2300.735415427222, 2317.7798109179603, 2344.120954642009, 2344.824138189634, 2346.3664810501155, 2234.0951914474663, 2244.5847518209102, 2262.321303608919, 2313.618252988255, 2316.5251123519724, 1872.2604571194438, 2269.704938358011, 2286.3826766772877, 2299.0688771047753, 2300.907963681442, 2129.2866889592005, 2279.87460689662, 2303.055747143663, 2304.4699719064542, 2314.6296592545114, 8302.750917472486, 8359.414840961135, 8407.495550267004, 8416.789580260482, 8431.030431500394, 5030.597552195853, 5192.247124166975, 5195.974020129913, 5196.480351964811, 5205.375354957391, 2953.7489313408387, 3227.951580726291, 3546.9113272168142, 3562.0, 3563.982180089083, 1038.6001389465034, 1065.9413732244723, 1072.2065671403184, 1088.5700148441401, 1095.6439157273837, 10803.459827008619, 10817.675469735841, 10861.18541109412, 10951.561937522489, 10968.616098436516, 4487.842925497592, 4562.657800664897, 4654.2785868190485, 4664.790084446208, 4743.383360163157, 7371.004914336603, 7830.7470804670975, 9491.477968711788, 9508.239340852853, 9538.046195380546, 4482.5741554552915, 5204.0, 5204.0892843752945, 5207.765650545734, 5219.791208351623, 4241.151769646083, 4481.35020422037, 4709.693869898446, 4772.880677983078, 4795.520447955247, 3773.3585290500682, 3799.639034291738, 3858.6141385861188, 3901.08324543732, 3907.199024200032, 1286.2539726958341, 1290.6386211860695, 1291.6577107066635, 1295.5400832704443, 1300.1289136278715, 685.6400389795398, 889.9599518021705, 897.2059727141396, 920.6455514626917, 932.5011119051275, 5588.636738611977, 5622.8313150605545, 5767.826965191051, 5859.853503662443, 5899.528037756969, 956.6938579654444, 963.1524258652481, 972.6820158685022, 980.6175591519313, 991.177322822056, 3835.9041023974623, 3847.442120892455, 3858.1126340941178, 3970.9602903971168, 3975.8807235782974, 9902.158316543175, 10167.23745719074, 10217.978202179722, 10233.1301839343, 10241.385516869012, 10160.441175735405, 10196.745081373025, 10200.0819926207, 10225.590976360862, 10237.74405639865, 7635.930969664251, 7723.150453450116, 7745.496542724705, 7819.960900195462, 7875.054993400829, 5218.869528261824, 5531.421519598369, 5620.662760234399, 5658.519025882846, 5694.772209111589, 9226.723198303793, 9245.260379169786, 9290.62837486493, 9294.163525282756, 9304.488253146143, 8947.955260223658, 9378.296627752808, 9463.479508626817, 9485.288603354638, 9541.093596108358, 6621.735130594915, 6816.863662726817, 6966.442684585119, 7290.343869051599, 7621.35218506411, 7747.57388343624, 7908.723194688158, 8022.237887400687, 8032.63853126599, 8049.87925181111, 7020.684069217, 7070.540414873017, 7095.893561596684, 7100.751473698396, 7116.323949224816, 6830.8633827324165, 6971.163460384583, 6984.92186603749, 7006.369426751617, 7145.356917877208, 6468.967655161694, 6488.545801793829, 6540.0, 6540.0, 6569.80290591283, 4772.093302272557, 4779.0, 4849.830255941025, 4858.416989961086, 4995.675281106716, 3952.822123004414, 4338.414314067561, 4875.561199492063, 4928.581070609039, 5059.342285502861], "value": 4400.29680813663, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [249.8413507422787, 255.1745104586662, 278.6266403019953, 301.27994094115064, 312.1883103929782, 5172.2328554223495, 5250.4487028861995, 5316.867078323044, 5431.293931788867, 5507.889842203153, 5180.6373553851245, 5415.969011620642, 5416.945830541694, 5423.593230507708, 5426.484483974026, 3777.2058272320633, 3829.617038296162, 3832.559255685604, 3950.407438884176, 4137.193247316774, 3633.745637805355, 4054.493188351456, 4139.917201655965, 4187.392828039932, 4220.388043733672, 3741.9064523386996, 4072.0023594219456, 4153.501579810412, 4157.921039480259, 4159.9376009359885, 4091.713580049398, 4124.834461907492, 4133.772642504661, 4143.067809742814, 4160.45914031177, 3604.6575575320303, 3607.206414588786, 3630.6732394084397, 3641.6904563112166, 3644.416893297086, 4153.522344930311, 5070.61970352225, 5225.242339860737, 5323.96182744365, 5432.565394768409, 3898.0, 4037.515498140242, 4673.252279635242, 4770.61835053195, 4777.665563410563, 5431.2396264522995, 5775.5957083004205, 5779.33537643168, 5786.508146807527, 5817.7091145442555, 3620.8913732587803, 3817.541894972593, 3891.435741817449, 3913.2173565287057, 3983.940240896389, 5011.5239052290335, 5419.861829015911, 6046.909296360558, 6078.483328917047, 6315.684215789192, 3335.1651096567725, 3434.5363375944426, 3966.5438474575217, 3997.620226078518, 4033.253848038122, 3901.648851603369, 3974.582668819732, 4003.939940900889, 4005.8597949072227, 4024.7724444044848, 4152.584741525884, 4636.35091087248, 4664.790084446208, 4690.437147542317, 4705.764711764398, 5088.98984900962, 5643.633163844337, 5894.616849904743, 5912.822615321547, 5966.373530779292, 1244.800831866897, 1258.8858859909046, 1273.9108262421541, 1297.2670441200692, 1299.6880748620451, 793.8489190673547, 816.432579357341, 859.4370687199894, 873.9868901966476, 887.1439061305871, 4260.6378457830815, 4815.494373090845, 4840.661153719277, 5176.559992400688, 5187.948120518822, 4973.577245934064, 5548.0013597552825, 5584.385717571064, 5638.323401191801, 5661.471402721242, 4157.962598326798, 4406.889827754268, 4758.405199350082, 4817.470078291385, 5125.97523978034, 2570.8714564271713, 2589.805764567627, 2634.2229042432145, 2733.0, 2995.9400811983637, 5141.0, 5692.975264452355, 5884.735186916599, 5907.0, 5940.910886336708, 4989.675671081368, 5011.369031258546, 5106.463821298785, 5190.650430887921, 5437.755301011464, 5044.445111037783, 5428.5385742211565, 5853.209816674779, 5881.029854998624, 5891.646501209855, 5486.1222204447095, 5671.319441667027, 5674.943250567441, 5691.345495267931, 5705.543556515552, 4823.710577365369, 5110.386753589593, 5312.707801070978, 6000.639961602318, 6054.00108982013, 6315.5894866833505, 6316.210473690789, 6336.0, 6339.90490142639, 6343.492520598434, 3588.138846676831, 3661.2860492203763, 3682.7846810552987, 3990.74060186087, 4036.959630403776, 5400.648957817729, 5999.730012149551, 6001.669908154919, 6019.969900150471, 6041.395860413923, 4384.4519435070615, 5173.741312934413, 5191.0, 5393.379761327341, 5599.832005039855, 4910.9263361050325, 5552.194931734956, 5846.064629659234, 5879.441453061952, 5889.057750759858, 4558.544145585415, 5039.420466646236, 5554.111342185231, 5746.13807928814, 5754.2597311747695, 4366.995591014148, 5221.451747566451, 5256.763445645031, 5728.770849165987, 5851.473367396955, 4317.84887528935, 5369.899170669925, 5491.505764481216, 5685.668875760918, 5865.853353666193], "value": 4080.4763906777766, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [671.5097978475712, 698.9650517474126, 699.6501749125438, 734.8420089680718, 764.9902129189469, 14862.554123376303, 15176.39294428223, 15448.227588620566, 15527.844721552781, 15754.0, 12309.446074926627, 12382.0, 12469.37653117345, 12481.875181248184, 13006.93496532516, 809.1948511231318, 833.1293797981094, 836.6736972580668, 837.2172019162103, 837.6398148796015, 645.0324513230155, 666.0342503370133, 803.8995125609299, 835.1773503099442, 837.1670188162786, 870.9825803483926, 873.2926329672986, 873.628707799186, 876.5266755951778, 876.6230520876021, 829.3489610655637, 862.5730263519536, 864.127231496187, 867.5055218525443, 874.0822136756412, 643.3020173112179, 644.561698045329, 645.2934037229227, 646.4860435953428, 647.5240698086902, 14411.92794036023, 14438.422463101464, 14441.711165776625, 14462.783058254134, 14566.781498277534, 14058.367373468116, 14409.423623055065, 14556.0, 14694.265286735726, 14724.042937209048, 10982.286151400134, 11410.0, 11617.419129043514, 11624.0, 11791.410429478577, 3669.3395188865993, 3683.7053035757076, 3697.4155819656585, 3753.0992561785247, 3808.276427478798, 110007.0, 111383.0, 113648.0, 114160.4291978543, 114980.0, 10968.341899486057, 11179.273347232403, 11198.160137989684, 11230.887691122984, 11537.88462115368, 21667.241646542294, 22557.66163507549, 22854.8857255716, 22866.771332286466, 22882.199123030947, 7302.3181646036055, 7995.720149794845, 8086.919130808734, 8135.145809690016, 8135.593220339078, 4154.231467178582, 4352.281873490839, 4370.628496577763, 4372.0, 4427.69006169565, 3519.3665140274484, 3589.0, 3617.5839778425816, 3633.418653015505, 3646.6717995380545, 637.6652257564723, 652.4160876015939, 653.9836504087436, 658.134553062721, 666.037575703114, 601.4977493792645, 631.8673078653443, 633.3191818794814, 662.4137638190236, 678.9015592739025, 7381.446391520657, 8451.788705282417, 8673.52295623734, 9096.8635470468, 9876.950615246878, 615.1787363869195, 636.6848410037061, 644.0950464597248, 648.9837754056186, 686.584616307137, 4354.869353919387, 4495.685302028826, 4497.64018878489, 4500.4149460569915, 4512.774361281923, 112872.0, 113859.0, 117293.82706172828, 119142.0, 119333.80666193401, 100363.0, 101431.49284253531, 102506.0, 102507.0, 104341.43487847688, 84606.15393846105, 84974.0, 85948.42154735368, 86033.13966860376, 88323.23353532894, 8776.868346974928, 8796.252318552995, 8836.0, 8898.199162075185, 9067.957184923818, 49495.0, 50312.496875032244, 50402.495975041245, 51694.707632309495, 53682.73158634182, 66672.33327666855, 71362.64318678374, 73050.0, 73876.26123738909, 77642.6117869407, 12696.0, 12987.35063246815, 13183.14309569875, 13347.66630834237, 13474.730505390033, 14596.270186490845, 14793.778093328823, 14800.62998425048, 14980.982535794703, 15497.690046198788, 13544.59366219015, 13624.727505450033, 13680.726385472433, 13972.510962116277, 14121.717565648834, 13700.45198192061, 13711.382987765774, 13928.930355348159, 13958.302084895919, 14043.016988810685, 13432.597022089352, 13616.207113033126, 13650.249236292098, 13912.304384780518, 14031.0, 9492.477913714813, 11077.66766996959, 11258.549658013588, 11631.941840291083, 11806.704832379259, 9757.902420975983, 10208.540615672164, 11398.658040258806, 11614.0, 11644.475998580254], "value": 7485.54903059216, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1578.3999999999996, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 170.0, 155.0, 120.0, 116.0, 117.0, 117.0, 123.0, 117.0, 99.0], "value": 126.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 120.0, 123.0, 185.0, 153.0, 121.0, 120.0, 121.0, 134.0, 118.0], "value": 137.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [202.0, 232.0, 196.0, 194.0, 196.0, 198.0, 196.0, 194.0, 200.0, 190.0], "value": 199.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 59.0, 56.0, 77.0, 61.0, 78.0, 84.0, 53.0, 85.0, 75.0], "value": 71.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 71.0, 82.0, 71.0, 70.0, 71.0, 82.0, 70.0, 72.0, 81.0], "value": 75.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 87.0, 88.0, 86.0, 88.0, 103.0, 89.0, 77.0, 134.0, 130.0], "value": 97.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 174.0, 99.0, 99.0, 112.0, 100.0, 98.0, 105.0, 99.0, 100.0], "value": 108.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 95.0, 92.0, 91.0, 92.0, 92.0, 92.0, 102.0, 141.0, 125.0], "value": 103.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 58.0, 57.0, 55.0, 50.0, 56.0, 61.0, 66.0, 76.0, 90.0], "value": 62.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 106.0, 98.0, 79.0, 76.0, 71.0, 70.0, 72.0, 72.0, 73.0], "value": 77.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 89.0, 91.0, 93.0, 91.0, 109.0, 91.0, 89.0, 88.0, 92.0], "value": 92.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 151.0, 146.0, 150.0, 151.0, 167.0, 175.0, 162.0, 220.0, 167.0], "value": 163.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 178.0, 177.0, 178.0, 175.0, 237.0, 198.0, 177.0, 174.0, 227.0], "value": 189.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 72.0, 72.0, 72.0, 73.0, 67.0, 71.0, 70.0, 69.0, 73.0], "value": 70.6, "unit": "score"}]}]} 05:51:15 INFO - Return code: 0 05:51:15 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:51:15 ERROR - # TBPL SUCCESS # 05:51:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:51:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:51:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:51:15 INFO - 2016-12-03 05:51:15.298 screenresolution[1668:11566] starting screenresolution argv=screenresolution get 05:51:15 INFO - 2016-12-03 05:51:15.300 screenresolution[1668:11566] Display 0: 1600x1200x32@60 05:51:15 INFO - 2016-12-03 05:51:15.306 screenresolution[1669:11569] starting screenresolution argv=screenresolution list 05:51:15 INFO - Available Modes on Display 0 05:51:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:51:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:51:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:51:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:51:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:51:15 INFO - Intel Iris: 05:51:15 INFO - Chipset Model: Intel Iris 05:51:15 INFO - Type: GPU 05:51:15 INFO - Bus: Built-In 05:51:15 INFO - VRAM (Dynamic, Max): 1536 MB 05:51:15 INFO - Vendor: Intel (0x8086) 05:51:15 INFO - Device ID: 0x0a2e 05:51:15 INFO - Revision ID: 0x0009 05:51:15 INFO - Displays: 05:51:15 INFO - Display: 05:51:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:51:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:51:15 INFO - Display Serial Number: 1600x1200 60 05:51:15 INFO - Main Display: Yes 05:51:15 INFO - Mirror: Off 05:51:15 INFO - Online: Yes 05:51:15 INFO - Rotation: Supported 05:51:15 INFO - Return code: 0 05:51:15 INFO - Running post-action listener: _resource_record_post_action 05:51:15 INFO - [mozharness: 2016-12-03 13:51:15.555060Z] Finished run-tests step (success) 05:51:15 INFO - Running post-run listener: _resource_record_post_run 05:51:15 INFO - Total resource usage - Wall time: 1212s; CPU: 55.0%; Read bytes: 34689024; Write bytes: 699708416; Read time: 389; Write time: 2086 05:51:15 INFO - TinderboxPrint: CPU usage
54.6% 05:51:15 INFO - TinderboxPrint: I/O read bytes / time
34,689,024 / 389 05:51:15 INFO - TinderboxPrint: I/O write bytes / time
699,708,416 / 2,086 05:51:15 INFO - TinderboxPrint: CPU idle
2,201.1 (45.4%) 05:51:15 INFO - TinderboxPrint: CPU system
978.6 (20.2%) 05:51:15 INFO - TinderboxPrint: CPU user
1,670.0 (34.4%) 05:51:15 INFO - TinderboxPrint: Swap in / out
415,174,656 / 0 05:51:15 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 370665984; Write bytes: 367339008; Read time: 27698; Write time: 672 05:51:15 INFO - run-tests - Wall time: 1180s; CPU: 55.0%; Read bytes: 32903168; Write bytes: 313024000; Read time: 307; Write time: 1367 05:51:16 INFO - Running post-run listener: _upload_blobber_files 05:51:16 INFO - Blob upload gear active. 05:51:16 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:51:16 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 05:51:16 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'] 05:51:16 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 05:51:16 INFO - (blobuploader) - INFO - Open directory for files ... 05:51:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:51:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:51:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:51:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:51:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:51:17 INFO - (blobuploader) - INFO - Done attempting. 05:51:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:51:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:51:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:51:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:51:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:51:18 INFO - (blobuploader) - INFO - Done attempting. 05:51:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:51:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:51:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:51:21 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:51:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:51:21 INFO - (blobuploader) - INFO - Done attempting. 05:51:21 INFO - (blobuploader) - INFO - Iteration through files over. 05:51:21 INFO - Return code: 0 05:51:21 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:51:21 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:51:21 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4564365ffddebf2498ebc719030d2c52c0e981ca3da3f9f95c308ba859538172e6183d8115a1934e43048a8affd00856d7f27727e35d1da89d399451f46ce46b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/01390aa43117b53b53e74766f17bc486d85621a473c956ce085e971f9921b00f7708e22220fba8b85cb5e0ff18dcf0f70d1671c7b807de7b2a40c71c1e243868", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ec098741b984c351d79363ba030d5f8f9e8597c4feafee234c94f98e3b99b6476b554eedc2f9a0330d5bbb5cbd71782a54970173d91690140af761a69d6a80c1"} 05:51:21 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:51:21 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:51:21 INFO - Contents: 05:51:21 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4564365ffddebf2498ebc719030d2c52c0e981ca3da3f9f95c308ba859538172e6183d8115a1934e43048a8affd00856d7f27727e35d1da89d399451f46ce46b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/01390aa43117b53b53e74766f17bc486d85621a473c956ce085e971f9921b00f7708e22220fba8b85cb5e0ff18dcf0f70d1671c7b807de7b2a40c71c1e243868", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ec098741b984c351d79363ba030d5f8f9e8597c4feafee234c94f98e3b99b6476b554eedc2f9a0330d5bbb5cbd71782a54970173d91690140af761a69d6a80c1"} 05:51:21 INFO - Running post-run listener: copy_logs_to_upload_dir 05:51:21 INFO - Copying logs to upload dir... 05:51:21 INFO - mkdir: /builds/slave/test/build/upload/logs 05:51:21 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.586526 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-12-03 05:51:21.865289) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-03 05:51:21.872385) ========= 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.lbbGar9cYE/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.kO3k6xlrR5/Listeners TMPDIR=/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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/4564365ffddebf2498ebc719030d2c52c0e981ca3da3f9f95c308ba859538172e6183d8115a1934e43048a8affd00856d7f27727e35d1da89d399451f46ce46b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/01390aa43117b53b53e74766f17bc486d85621a473c956ce085e971f9921b00f7708e22220fba8b85cb5e0ff18dcf0f70d1671c7b807de7b2a40c71c1e243868", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ec098741b984c351d79363ba030d5f8f9e8597c4feafee234c94f98e3b99b6476b554eedc2f9a0330d5bbb5cbd71782a54970173d91690140af761a69d6a80c1"} build_url:https://queue.taskcluster.net/v1/task/LlY0JE9IR5KIjdeEStiCtQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010999 build_url: 'https://queue.taskcluster.net/v1/task/LlY0JE9IR5KIjdeEStiCtQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4564365ffddebf2498ebc719030d2c52c0e981ca3da3f9f95c308ba859538172e6183d8115a1934e43048a8affd00856d7f27727e35d1da89d399451f46ce46b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/01390aa43117b53b53e74766f17bc486d85621a473c956ce085e971f9921b00f7708e22220fba8b85cb5e0ff18dcf0f70d1671c7b807de7b2a40c71c1e243868", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ec098741b984c351d79363ba030d5f8f9e8597c4feafee234c94f98e3b99b6476b554eedc2f9a0330d5bbb5cbd71782a54970173d91690140af761a69d6a80c1"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-03 05:51:21.909815) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-03 05:51:21.910192) ========= 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.lbbGar9cYE/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.kO3k6xlrR5/Listeners TMPDIR=/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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.004942 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-03 05:51:21.959234) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-03 05:51:21.959539) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-03 05:51:21.959873) ========= ========= Total master_lag: 0.32 =========