builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0238 starttime: 1479484916.15 results: success (0) buildid: 20161118040828 builduid: 1aa1d4ab7c794f94a737edcf8e37d62f revision: 11b5acda902f5ecb13ab2d451d36c7a9063f2b26 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-18 08:01:56.150422) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-18 08:01:56.158888) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-18 08:01:56.159197) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-18 08:01:56.182189) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-18 08:01:56.182765) ========= 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.Xtw0vsbFR1/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.9F2nxyEl2D/Listeners TMPDIR=/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/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-11-18 08:01:56-- 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% 149M=0s 2016-11-18 08:01:56 (149 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.200245 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-18 08:01:56.407247) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-18 08:01:56.407715) ========= 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.Xtw0vsbFR1/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.9F2nxyEl2D/Listeners TMPDIR=/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/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.019922 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-18 08:01:56.456499) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-18 08:01:56.456804) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 11b5acda902f5ecb13ab2d451d36c7a9063f2b26 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 11b5acda902f5ecb13ab2d451d36c7a9063f2b26 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Xtw0vsbFR1/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.9F2nxyEl2D/Listeners TMPDIR=/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/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-11-18 08:01:56,544 truncating revision to first 12 chars 2016-11-18 08:01:56,544 Setting DEBUG logging. 2016-11-18 08:01:56,544 attempt 1/10 2016-11-18 08:01:56,544 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/11b5acda902f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-18 08:01:57,522 unpacking tar archive at: mozilla-beta-11b5acda902f/testing/mozharness/ program finished with exit code 0 elapsedTime=1.270126 ========= master_lag: 0.10 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-18 08:01:57.823300) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-18 08:01:57.823623) ========= script_repo_revision: 11b5acda902f5ecb13ab2d451d36c7a9063f2b26 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-18 08:01:57.824021) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-18 08:01:57.824298) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-18 08:01:57.842891) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2016-11-18 08:01:57.843329) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Xtw0vsbFR1/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.9F2nxyEl2D/Listeners TMPDIR=/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/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 08:01:58 INFO - MultiFileLogger online at 20161118 08:01:58 in /builds/slave/test 08:01:58 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 08:01:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:01:58 INFO - {'append_to_log': False, 08:01:58 INFO - 'base_work_dir': '/builds/slave/test', 08:01:58 INFO - 'blob_upload_branch': 'Mozilla-Beta', 08:01:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:01:58 INFO - 'branch': 'Mozilla-Beta', 08:01:58 INFO - 'buildbot_json_path': 'buildprops.json', 08:01:58 INFO - 'config_files': ('talos/mac_config.py',), 08:01:58 INFO - 'default_actions': ('clobber', 08:01:58 INFO - 'read-buildbot-config', 08:01:58 INFO - 'download-and-extract', 08:01:58 INFO - 'populate-webroot', 08:01:58 INFO - 'create-virtualenv', 08:01:58 INFO - 'install', 08:01:58 INFO - 'run-tests'), 08:01:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:01:58 INFO - 'download_minidump_stackwalk': True, 08:01:58 INFO - 'download_symbols': 'ondemand', 08:01:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:01:58 INFO - 'tooltool.py': '/tools/tooltool.py', 08:01:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:01:58 INFO - '/tools/misc-python/virtualenv.py')}, 08:01:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:01:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:01:58 INFO - 'installer_path': 'installer.exe', 08:01:58 INFO - 'log_level': 'info', 08:01:58 INFO - 'log_name': 'talos', 08:01:58 INFO - 'log_to_console': True, 08:01:58 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:01:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:01:58 INFO - 'opt_config_files': (), 08:01:58 INFO - 'pip_index': False, 08:01:58 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:01:58 INFO - 'cmd': ('bash', 08:01:58 INFO - '-c', 08:01:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:01:58 INFO - 'enabled': True, 08:01:58 INFO - 'halt_on_failure': False, 08:01:58 INFO - 'name': 'check_screen_resolution'},), 08:01:58 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:01:58 INFO - 'cmd': ('bash', 08:01:58 INFO - '-c', 08:01:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:01:58 INFO - 'enabled': True, 08:01:58 INFO - 'halt_on_failure': False, 08:01:58 INFO - 'name': 'check_screen_resolution'},), 08:01:58 INFO - 'run_cmd_checks_enabled': True, 08:01:58 INFO - 'sps_profile': False, 08:01:58 INFO - 'sps_profile_interval': 0, 08:01:58 INFO - 'suite': 'dromaeojs', 08:01:58 INFO - 'system_bits': '32', 08:01:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:01:58 INFO - 'title': 't-yosemite-r7-0238', 08:01:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:01:58 INFO - 'use_talos_json': True, 08:01:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:01:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:01:58 INFO - 'work_dir': 'build'} 08:01:58 INFO - [mozharness: 2016-11-18 16:01:58.039650Z] Running clobber step. 08:01:58 INFO - Running pre-action listener: _resource_record_pre_action 08:01:58 INFO - Running main action method: clobber 08:01:58 INFO - rmtree: /builds/slave/test/build 08:01:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:01:58 INFO - Running post-action listener: _resource_record_post_action 08:01:58 INFO - [mozharness: 2016-11-18 16:01:58.957532Z] Finished clobber step (success) 08:01:58 INFO - [mozharness: 2016-11-18 16:01:58.957747Z] Running read-buildbot-config step. 08:01:58 INFO - Running pre-action listener: _resource_record_pre_action 08:01:58 INFO - Running main action method: read_buildbot_config 08:01:58 INFO - Using buildbot properties: 08:01:58 INFO - { 08:01:58 INFO - "project": "", 08:01:58 INFO - "product": "firefox", 08:01:58 INFO - "script_repo_revision": "production", 08:01:58 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 08:01:58 INFO - "repository": "", 08:01:58 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 08:01:58 INFO - "buildid": "20161118040828", 08:01:58 INFO - "pgo_build": "False", 08:01:58 INFO - "basedir": "/builds/slave/test", 08:01:58 INFO - "buildnumber": 69, 08:01:58 INFO - "slavename": "t-yosemite-r7-0238", 08:01:58 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 08:01:58 INFO - "platform": "yosemite_r7", 08:01:58 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 08:01:58 INFO - "branch": "mozilla-beta", 08:01:58 INFO - "repo_path": "releases/mozilla-beta", 08:01:58 INFO - "revision": "11b5acda902f5ecb13ab2d451d36c7a9063f2b26", 08:01:58 INFO - "stage_platform": "macosx64", 08:01:58 INFO - "builduid": "1aa1d4ab7c794f94a737edcf8e37d62f", 08:01:58 INFO - "slavebuilddir": "test" 08:01:58 INFO - } 08:01:58 INFO - Found installer url https://queue.taskcluster.net/v1/task/FmRmLTisR-qnEO4mwOEHDg/artifacts/public/build/firefox-51.0.en-US.mac.dmg. 08:01:58 INFO - Running post-action listener: _resource_record_post_action 08:01:58 INFO - [mozharness: 2016-11-18 16:01:58.963467Z] Finished read-buildbot-config step (success) 08:01:58 INFO - [mozharness: 2016-11-18 16:01:58.963624Z] Running download-and-extract step. 08:01:58 INFO - Running pre-action listener: _resource_record_pre_action 08:01:58 INFO - Running main action method: download_and_extract 08:01:58 INFO - mkdir: /builds/slave/test/build/tests 08:01:58 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')]} 08:01:58 INFO - trying https://queue.taskcluster.net/v1/task/FmRmLTisR-qnEO4mwOEHDg/artifacts/public/build/firefox-51.0.en-US.mac.test_packages.json 08:01:58 INFO - Downloading https://queue.taskcluster.net/v1/task/FmRmLTisR-qnEO4mwOEHDg/artifacts/public/build/firefox-51.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0.en-US.mac.test_packages.json 08:01:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FmRmLTisR-qnEO4mwOEHDg/artifacts/public/build/firefox-51.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0.en-US.mac.test_packages.json'}, attempt #1 08:02:00 INFO - Downloaded 1165 bytes. 08:02:00 INFO - Reading from file /builds/slave/test/build/firefox-51.0.en-US.mac.test_packages.json 08:02:00 INFO - Using the following test package requirements: 08:02:00 INFO - {u'common': [u'firefox-51.0.en-US.mac.common.tests.zip'], 08:02:00 INFO - u'cppunittest': [u'firefox-51.0.en-US.mac.common.tests.zip', 08:02:00 INFO - u'firefox-51.0.en-US.mac.cppunittest.tests.zip'], 08:02:00 INFO - u'gtest': [u'firefox-51.0.en-US.mac.common.tests.zip', 08:02:00 INFO - u'firefox-51.0.en-US.mac.gtest.tests.zip'], 08:02:00 INFO - u'jittest': [u'firefox-51.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 08:02:00 INFO - u'mochitest': [u'firefox-51.0.en-US.mac.common.tests.zip', 08:02:00 INFO - u'firefox-51.0.en-US.mac.mochitest.tests.zip'], 08:02:00 INFO - u'mozbase': [u'firefox-51.0.en-US.mac.common.tests.zip'], 08:02:00 INFO - u'reftest': [u'firefox-51.0.en-US.mac.common.tests.zip', 08:02:00 INFO - u'firefox-51.0.en-US.mac.reftest.tests.zip'], 08:02:00 INFO - u'talos': [u'firefox-51.0.en-US.mac.common.tests.zip', 08:02:00 INFO - u'firefox-51.0.en-US.mac.talos.tests.zip'], 08:02:00 INFO - u'web-platform': [u'firefox-51.0.en-US.mac.common.tests.zip', 08:02:00 INFO - u'firefox-51.0.en-US.mac.web-platform.tests.zip'], 08:02:00 INFO - u'xpcshell': [u'firefox-51.0.en-US.mac.common.tests.zip', 08:02:00 INFO - u'firefox-51.0.en-US.mac.xpcshell.tests.zip']} 08:02:00 INFO - Downloading packages: [u'firefox-51.0.en-US.mac.common.tests.zip'] for test suite category: common 08:02:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FmRmLTisR-qnEO4mwOEHDg/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip 08:02:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FmRmLTisR-qnEO4mwOEHDg/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip'}, attempt #1 08:02:00 INFO - Fetch https://queue.taskcluster.net/v1/task/FmRmLTisR-qnEO4mwOEHDg/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip into memory 08:02:00 INFO - Expected file size: 16231139 08:02:08 INFO - Downloading packages: [u'firefox-51.0.en-US.mac.common.tests.zip', u'firefox-51.0.en-US.mac.talos.tests.zip'] for test suite category: talos 08:02:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FmRmLTisR-qnEO4mwOEHDg/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip 08:02:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FmRmLTisR-qnEO4mwOEHDg/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip'}, attempt #1 08:02:08 INFO - Fetch https://queue.taskcluster.net/v1/task/FmRmLTisR-qnEO4mwOEHDg/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip into memory 08:02:08 INFO - Expected file size: 16231139 08:02:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FmRmLTisR-qnEO4mwOEHDg/artifacts/public/build/firefox-51.0.en-US.mac.talos.tests.zip 08:02:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FmRmLTisR-qnEO4mwOEHDg/artifacts/public/build/firefox-51.0.en-US.mac.talos.tests.zip'}, attempt #1 08:02:15 INFO - Fetch https://queue.taskcluster.net/v1/task/FmRmLTisR-qnEO4mwOEHDg/artifacts/public/build/firefox-51.0.en-US.mac.talos.tests.zip into memory 08:02:16 INFO - Expected file size: 14414089 08:02:16 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')]} 08:02:16 INFO - trying https://queue.taskcluster.net/v1/task/FmRmLTisR-qnEO4mwOEHDg/artifacts/public/build/firefox-51.0.en-US.mac.dmg 08:02:16 INFO - Downloading https://queue.taskcluster.net/v1/task/FmRmLTisR-qnEO4mwOEHDg/artifacts/public/build/firefox-51.0.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0.en-US.mac.dmg 08:02:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FmRmLTisR-qnEO4mwOEHDg/artifacts/public/build/firefox-51.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0.en-US.mac.dmg'}, attempt #1 08:02:19 INFO - Downloaded 86009518 bytes. 08:02:19 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FmRmLTisR-qnEO4mwOEHDg/artifacts/public/build/firefox-51.0.en-US.mac.dmg 08:02:19 INFO - mkdir: /builds/slave/test/properties 08:02:19 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:02:19 INFO - Writing to file /builds/slave/test/properties/build_url 08:02:19 INFO - Contents: 08:02:19 INFO - build_url:https://queue.taskcluster.net/v1/task/FmRmLTisR-qnEO4mwOEHDg/artifacts/public/build/firefox-51.0.en-US.mac.dmg 08:02:19 INFO - Running post-action listener: _resource_record_post_action 08:02:19 INFO - Running post-action listener: set_extra_try_arguments 08:02:19 INFO - [mozharness: 2016-11-18 16:02:19.671936Z] Finished download-and-extract step (success) 08:02:19 INFO - [mozharness: 2016-11-18 16:02:19.672077Z] Running populate-webroot step. 08:02:19 INFO - Running pre-action listener: _resource_record_pre_action 08:02:19 INFO - Running main action method: populate_webroot 08:02:19 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:02:19 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:02:19 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:02:19 INFO - '--tppagecycles', 08:02:19 INFO - '7'], 08:02:19 INFO - 'tests': ['tsvgm']}}, 08:02:19 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:02:19 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:02:19 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:02:19 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:02:19 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:02:19 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:02:19 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:02:19 INFO - 'talos_options': ['--disable-e10s'], 08:02:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:02:19 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:02:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:02:19 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:02:19 INFO - 'talos_options': ['--disable-e10s'], 08:02:19 INFO - 'tests': ['damp', 'tps']}, 08:02:19 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:02:19 INFO - 'tests': ['damp', 'tps']}, 08:02:19 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:02:19 INFO - 'tests': ['dromaeo_dom']}, 08:02:19 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:02:19 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:02:19 INFO - 'tests': ['basic_compositor_video']}, 08:02:19 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:02:19 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:02:19 INFO - 'tests': ['a11yr', 08:02:19 INFO - 'ts_paint', 08:02:19 INFO - 'tpaint', 08:02:19 INFO - 'sessionrestore', 08:02:19 INFO - 'sessionrestore_no_auto_restore', 08:02:19 INFO - 'tabpaint']}, 08:02:19 INFO - 'other-e10s': {'tests': ['a11yr', 08:02:19 INFO - 'ts_paint', 08:02:19 INFO - 'tpaint', 08:02:19 INFO - 'sessionrestore', 08:02:19 INFO - 'sessionrestore_no_auto_restore', 08:02:19 INFO - 'tabpaint']}, 08:02:19 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:02:19 INFO - 'ts_paint', 08:02:19 INFO - 'tpaint', 08:02:19 INFO - 'sessionrestore', 08:02:19 INFO - 'sessionrestore_no_auto_restore', 08:02:19 INFO - 'tabpaint']}, 08:02:19 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:02:19 INFO - 'ts_paint', 08:02:19 INFO - 'tpaint', 08:02:19 INFO - 'sessionrestore', 08:02:19 INFO - 'sessionrestore_no_auto_restore', 08:02:19 INFO - 'tabpaint']}, 08:02:19 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:02:19 INFO - 'tests': ['a11yr', 08:02:19 INFO - 'ts_paint', 08:02:19 INFO - 'tpaint', 08:02:19 INFO - 'sessionrestore', 08:02:19 INFO - 'sessionrestore_no_auto_restore', 08:02:19 INFO - 'tabpaint']}, 08:02:19 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:02:19 INFO - 'tests': ['a11yr', 08:02:19 INFO - 'ts_paint', 08:02:19 INFO - 'tpaint', 08:02:19 INFO - 'sessionrestore', 08:02:19 INFO - 'sessionrestore_no_auto_restore', 08:02:19 INFO - 'tabpaint']}, 08:02:19 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:02:19 INFO - 'tests': ['tsvgx', 08:02:19 INFO - 'tsvgr_opacity', 08:02:19 INFO - 'tart', 08:02:19 INFO - 'tscrollx', 08:02:19 INFO - 'cart']}, 08:02:19 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:02:19 INFO - 'tsvgr_opacity', 08:02:19 INFO - 'tart', 08:02:19 INFO - 'tscrollx', 08:02:19 INFO - 'cart']}, 08:02:19 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:02:19 INFO - 'talos_options': ['--disable-e10s'], 08:02:19 INFO - 'tests': ['tp5o']}, 08:02:19 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:02:19 INFO - 'tests': ['tp5o']}, 08:02:19 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:02:19 INFO - 'talos_options': ['--disable-e10s', 08:02:19 INFO - '--xperf_path', 08:02:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:02:19 INFO - 'tests': ['tp5n']}, 08:02:19 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:02:19 INFO - 'talos_options': ['--xperf_path', 08:02:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:02:19 INFO - 'tests': ['tp5n']}}, 08:02:19 INFO - 'talos.zip': {'path': '', 08:02:19 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:02:19 INFO - Running post-action listener: _resource_record_post_action 08:02:19 INFO - [mozharness: 2016-11-18 16:02:19.690675Z] Finished populate-webroot step (success) 08:02:19 INFO - [mozharness: 2016-11-18 16:02:19.690807Z] Running create-virtualenv step. 08:02:19 INFO - Running pre-action listener: _resource_record_pre_action 08:02:19 INFO - Running main action method: create_virtualenv 08:02:19 INFO - Creating virtualenv /builds/slave/test/build/venv 08:02:19 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 08:02:19 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:02:19 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:02:19 INFO - Using real prefix '/tools/python27' 08:02:19 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:02:20 INFO - Installing distribute.............................................................................................................................................................................................done. 08:02:23 INFO - Installing pip.................done. 08:02:23 INFO - Return code: 0 08:02:23 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:02:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:02:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:02:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:02:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:02:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100af1490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a67570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f971ac83940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100905d30>, '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 0x10076bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1008fdb20>, '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.Xtw0vsbFR1/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.9F2nxyEl2D/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/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 08:02:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:02:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:02:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xtw0vsbFR1/Render', 08:02:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:02:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:02:23 INFO - 'HOME': '/Users/cltbld', 08:02:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:02:23 INFO - 'LOGNAME': 'cltbld', 08:02:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:02:23 INFO - 'MOZ_NO_REMOTE': '1', 08:02:23 INFO - 'NO_EM_RESTART': '1', 08:02:23 INFO - 'PAGER': '/bin/cat', 08:02:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:02:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:02:23 INFO - 'PWD': '/builds/slave/test', 08:02:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:02:23 INFO - 'SHELL': '/bin/bash', 08:02:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9F2nxyEl2D/Listeners', 08:02:23 INFO - 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/', 08:02:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:02:23 INFO - 'USER': 'cltbld', 08:02:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:02:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:02:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:02:23 INFO - 'XPC_FLAGS': '0x0', 08:02:23 INFO - 'XPC_SERVICE_NAME': '0', 08:02:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:02:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:02:23 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 08:02:23 INFO - Cleaning up... 08:02:23 INFO - Return code: 0 08:02:23 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:02:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:02:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:02:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:02:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:02:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100af1490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a67570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f971ac83940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100905d30>, '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 0x10076bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1008fdb20>, '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.Xtw0vsbFR1/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.9F2nxyEl2D/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/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 08:02:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:02:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:02:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xtw0vsbFR1/Render', 08:02:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:02:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:02:23 INFO - 'HOME': '/Users/cltbld', 08:02:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:02:23 INFO - 'LOGNAME': 'cltbld', 08:02:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:02:23 INFO - 'MOZ_NO_REMOTE': '1', 08:02:23 INFO - 'NO_EM_RESTART': '1', 08:02:23 INFO - 'PAGER': '/bin/cat', 08:02:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:02:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:02:23 INFO - 'PWD': '/builds/slave/test', 08:02:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:02:23 INFO - 'SHELL': '/bin/bash', 08:02:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9F2nxyEl2D/Listeners', 08:02:23 INFO - 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/', 08:02:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:02:23 INFO - 'USER': 'cltbld', 08:02:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:02:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:02:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:02:23 INFO - 'XPC_FLAGS': '0x0', 08:02:23 INFO - 'XPC_SERVICE_NAME': '0', 08:02:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:02:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:02:24 INFO - Downloading/unpacking psutil>=3.1.1 08:02:24 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 08:02:24 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 08:02:24 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 08:02:24 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 08:02:24 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 08:02:24 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 08:02:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:02:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:02:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:02:27 INFO - Installing collected packages: psutil 08:02:27 INFO - Running setup.py install for psutil 08:02:27 INFO - building 'psutil._psutil_osx' extension 08:02:27 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 08:02:27 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 08:02:27 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 08:02:28 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 08:02:28 INFO - building 'psutil._psutil_posix' extension 08:02:28 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 08:02:28 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:02:28 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:02:28 INFO - ^ 08:02:28 INFO - 1 warning generated. 08:02:28 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 08:02:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:02:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:02:28 INFO - Successfully installed psutil 08:02:28 INFO - Cleaning up... 08:02:28 INFO - Return code: 0 08:02:28 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:02:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:02:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:02:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:02:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:02:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100af1490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a67570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f971ac83940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100905d30>, '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 0x10076bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1008fdb20>, '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.Xtw0vsbFR1/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.9F2nxyEl2D/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/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 08:02:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:02:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:02:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xtw0vsbFR1/Render', 08:02:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:02:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:02:28 INFO - 'HOME': '/Users/cltbld', 08:02:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:02:28 INFO - 'LOGNAME': 'cltbld', 08:02:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:02:28 INFO - 'MOZ_NO_REMOTE': '1', 08:02:28 INFO - 'NO_EM_RESTART': '1', 08:02:28 INFO - 'PAGER': '/bin/cat', 08:02:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:02:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:02:28 INFO - 'PWD': '/builds/slave/test', 08:02:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:02:28 INFO - 'SHELL': '/bin/bash', 08:02:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9F2nxyEl2D/Listeners', 08:02:28 INFO - 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/', 08:02:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:02:28 INFO - 'USER': 'cltbld', 08:02:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:02:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:02:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:02:28 INFO - 'XPC_FLAGS': '0x0', 08:02:28 INFO - 'XPC_SERVICE_NAME': '0', 08:02:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:02:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:02:28 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:02:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:02:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:02:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:02:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:02:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:02:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:02:31 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:02:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:02:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:02:31 INFO - Installing collected packages: mozsystemmonitor 08:02:31 INFO - Running setup.py install for mozsystemmonitor 08:02:31 INFO - Successfully installed mozsystemmonitor 08:02:31 INFO - Cleaning up... 08:02:31 INFO - Return code: 0 08:02:31 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:02:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:02:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:02:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:02:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:02:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100af1490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a67570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f971ac83940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100905d30>, '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 0x10076bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1008fdb20>, '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.Xtw0vsbFR1/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.9F2nxyEl2D/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/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 08:02:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:02:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:02:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xtw0vsbFR1/Render', 08:02:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:02:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:02:31 INFO - 'HOME': '/Users/cltbld', 08:02:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:02:31 INFO - 'LOGNAME': 'cltbld', 08:02:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:02:31 INFO - 'MOZ_NO_REMOTE': '1', 08:02:31 INFO - 'NO_EM_RESTART': '1', 08:02:31 INFO - 'PAGER': '/bin/cat', 08:02:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:02:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:02:31 INFO - 'PWD': '/builds/slave/test', 08:02:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:02:31 INFO - 'SHELL': '/bin/bash', 08:02:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9F2nxyEl2D/Listeners', 08:02:31 INFO - 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/', 08:02:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:02:31 INFO - 'USER': 'cltbld', 08:02:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:02:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:02:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:02:31 INFO - 'XPC_FLAGS': '0x0', 08:02:31 INFO - 'XPC_SERVICE_NAME': '0', 08:02:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:02:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:02:31 INFO - Downloading/unpacking jsonschema==2.5.1 08:02:31 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 08:02:31 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 08:02:31 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 08:02:31 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 08:02:31 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 08:02:31 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 08:02:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:02:34 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:02:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:02:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:02:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:02:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:02:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:02:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:02:34 INFO - Downloading functools32-3.2.3-2.tar.gz 08:02:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:02:34 INFO - warning: no files found matching '*.txt' 08:02:34 INFO - no previously-included directories found matching 'build' 08:02:34 INFO - no previously-included directories found matching 'dist' 08:02:34 INFO - no previously-included directories found matching '.git*' 08:02:34 INFO - Installing collected packages: jsonschema, functools32 08:02:34 INFO - Running setup.py install for functools32 08:02:34 INFO - warning: no files found matching '*.txt' 08:02:34 INFO - no previously-included directories found matching 'build' 08:02:34 INFO - no previously-included directories found matching 'dist' 08:02:34 INFO - no previously-included directories found matching '.git*' 08:02:34 INFO - Successfully installed jsonschema functools32 08:02:34 INFO - Cleaning up... 08:02:35 INFO - Return code: 0 08:02:35 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:02: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')]} 08:02:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:02:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02: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')]} 08:02:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:02:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100af1490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a67570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f971ac83940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100905d30>, '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 0x10076bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1008fdb20>, '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.Xtw0vsbFR1/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.9F2nxyEl2D/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/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 08:02:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:02:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 08:02:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xtw0vsbFR1/Render', 08:02:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:02:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:02:35 INFO - 'HOME': '/Users/cltbld', 08:02:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:02:35 INFO - 'LOGNAME': 'cltbld', 08:02:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:02:35 INFO - 'MOZ_NO_REMOTE': '1', 08:02:35 INFO - 'NO_EM_RESTART': '1', 08:02:35 INFO - 'PAGER': '/bin/cat', 08:02:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:02:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:02:35 INFO - 'PWD': '/builds/slave/test', 08:02:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:02:35 INFO - 'SHELL': '/bin/bash', 08:02:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9F2nxyEl2D/Listeners', 08:02:35 INFO - 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/', 08:02:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:02:35 INFO - 'USER': 'cltbld', 08:02:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:02:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:02:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:02:35 INFO - 'XPC_FLAGS': '0x0', 08:02:35 INFO - 'XPC_SERVICE_NAME': '0', 08:02:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:02:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:02:35 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:02:35 INFO - Cleaning up... 08:02:35 INFO - Return code: 0 08:02:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:02: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')]} 08:02:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:02:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02: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')]} 08:02:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:02:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100af1490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a67570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f971ac83940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100905d30>, '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 0x10076bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1008fdb20>, '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.Xtw0vsbFR1/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.9F2nxyEl2D/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/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 08:02:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:02:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:02:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xtw0vsbFR1/Render', 08:02:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:02:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:02:35 INFO - 'HOME': '/Users/cltbld', 08:02:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:02:35 INFO - 'LOGNAME': 'cltbld', 08:02:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:02:35 INFO - 'MOZ_NO_REMOTE': '1', 08:02:35 INFO - 'NO_EM_RESTART': '1', 08:02:35 INFO - 'PAGER': '/bin/cat', 08:02:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:02:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:02:35 INFO - 'PWD': '/builds/slave/test', 08:02:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:02:35 INFO - 'SHELL': '/bin/bash', 08:02:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9F2nxyEl2D/Listeners', 08:02:35 INFO - 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/', 08:02:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:02:35 INFO - 'USER': 'cltbld', 08:02:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:02:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:02:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:02:35 INFO - 'XPC_FLAGS': '0x0', 08:02:35 INFO - 'XPC_SERVICE_NAME': '0', 08:02:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:02:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:02:35 INFO - Downloading/unpacking blobuploader==1.2.4 08:02: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 08:02: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 08:02: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 08:02: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 08:02: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 08:02: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 08:02:38 INFO - Downloading blobuploader-1.2.4.tar.gz 08:02:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:02:38 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:02:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:02:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:02:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:02:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:02:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:02:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:02:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:02:38 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:02:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:02:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:02:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:02:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:02:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:02:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:02:39 INFO - Downloading docopt-0.6.1.tar.gz 08:02:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:02:39 INFO - Installing collected packages: blobuploader, requests, docopt 08:02:39 INFO - Running setup.py install for blobuploader 08:02:39 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:02:39 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:02:39 INFO - Running setup.py install for requests 08:02:40 INFO - Running setup.py install for docopt 08:02:40 INFO - Successfully installed blobuploader requests docopt 08:02:40 INFO - Cleaning up... 08:02:40 INFO - Return code: 0 08:02:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:02: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')]} 08:02:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:02:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02: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')]} 08:02:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:02:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100af1490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a67570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f971ac83940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100905d30>, '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 0x10076bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1008fdb20>, '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.Xtw0vsbFR1/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.9F2nxyEl2D/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/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 08:02:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:02:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:02:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xtw0vsbFR1/Render', 08:02:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:02:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:02:40 INFO - 'HOME': '/Users/cltbld', 08:02:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:02:40 INFO - 'LOGNAME': 'cltbld', 08:02:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:02:40 INFO - 'MOZ_NO_REMOTE': '1', 08:02:40 INFO - 'NO_EM_RESTART': '1', 08:02:40 INFO - 'PAGER': '/bin/cat', 08:02:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:02:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:02:40 INFO - 'PWD': '/builds/slave/test', 08:02:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:02:40 INFO - 'SHELL': '/bin/bash', 08:02:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9F2nxyEl2D/Listeners', 08:02:40 INFO - 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/', 08:02:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:02:40 INFO - 'USER': 'cltbld', 08:02:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:02:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:02:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:02:40 INFO - 'XPC_FLAGS': '0x0', 08:02:40 INFO - 'XPC_SERVICE_NAME': '0', 08:02:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:02:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:02:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:02:40 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-ZZDIxW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:02:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:02:40 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-AgptLK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:02:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:02:40 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-PkueoO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:02:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:02:41 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-2H9Nrv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:02:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:02:41 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-F2V_cV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:02:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:02:41 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-rts6xB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:02:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:02:41 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-FOmCvI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:02:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:02:41 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-NqQHZY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:02:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:02:41 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-8s47sc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:02:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:02:41 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-cwjJzs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:02:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:02:41 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-aqk4KD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:02:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:02:41 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-IDI00_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:02:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:02:42 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-bY6l61-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:02:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:02:42 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-HLi83A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:02:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:02:42 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-0ipccs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:02:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:02:42 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-bjhVrz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:02:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:02:42 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-WNnUhU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:02:42 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:02:42 INFO - Running setup.py install for manifestparser 08:02:42 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:02:42 INFO - Running setup.py install for mozcrash 08:02:42 INFO - Running setup.py install for mozdebug 08:02:42 INFO - Running setup.py install for mozdevice 08:02:43 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:02:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:02:43 INFO - Running setup.py install for mozfile 08:02:43 INFO - Running setup.py install for mozhttpd 08:02:43 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:02:43 INFO - Running setup.py install for mozinfo 08:02:43 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:02:43 INFO - Running setup.py install for mozInstall 08:02:43 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:02:43 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:02:43 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:02:43 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:02:43 INFO - Running setup.py install for mozleak 08:02:43 INFO - Running setup.py install for mozlog 08:02:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:02:44 INFO - Running setup.py install for moznetwork 08:02:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:02:44 INFO - Running setup.py install for mozprocess 08:02:44 INFO - Running setup.py install for mozprofile 08:02:44 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:02:44 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:02:44 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:02:44 INFO - Running setup.py install for mozrunner 08:02:44 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:02:44 INFO - Running setup.py install for mozscreenshot 08:02:44 INFO - Running setup.py install for moztest 08:02:44 INFO - Running setup.py install for mozversion 08:02:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:02:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:02:45 INFO - Cleaning up... 08:02:45 INFO - Return code: 0 08:02:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:02:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:02:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:02:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:02:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:02:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100af1490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a67570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f971ac83940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100905d30>, '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 0x10076bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1008fdb20>, '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.Xtw0vsbFR1/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.9F2nxyEl2D/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/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 08:02:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:02:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:02:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xtw0vsbFR1/Render', 08:02:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:02:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:02:45 INFO - 'HOME': '/Users/cltbld', 08:02:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:02:45 INFO - 'LOGNAME': 'cltbld', 08:02:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:02:45 INFO - 'MOZ_NO_REMOTE': '1', 08:02:45 INFO - 'NO_EM_RESTART': '1', 08:02:45 INFO - 'PAGER': '/bin/cat', 08:02:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:02:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:02:45 INFO - 'PWD': '/builds/slave/test', 08:02:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:02:45 INFO - 'SHELL': '/bin/bash', 08:02:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9F2nxyEl2D/Listeners', 08:02:45 INFO - 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/', 08:02:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:02:45 INFO - 'USER': 'cltbld', 08:02:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:02:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:02:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:02:45 INFO - 'XPC_FLAGS': '0x0', 08:02:45 INFO - 'XPC_SERVICE_NAME': '0', 08:02:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:02:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:02:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:02:45 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-L1q63x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:02:45 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 08:02:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:02:45 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-xjWz__-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:02:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:02:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:02:45 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-JVzcwi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:02:45 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)) 08:02:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:02:45 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-o0f7bO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:02:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:02:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:02:45 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-0PUkVH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:02:45 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)) 08:02:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:02:45 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-N_CQKe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:02:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 08:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:02:46 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-O_9Rkr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:02:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 08:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:02:46 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-PxvyzD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:02:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 08:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:02:46 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-MwscSN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:02:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 08:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:02:46 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-Nq2sPG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:02:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:02:46 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-zbQOPV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:02:46 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)) 08:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:02:46 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-C0CMKq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:02:46 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)) 08:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:02:46 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-0C1hnp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:02:46 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)) 08:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:02:46 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-XVkoY5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:02:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 08:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:02:46 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-I7RzfD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:02:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 08:02:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:02:47 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-BUfYCj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:02:47 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)) 08:02:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:02:47 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-1oyojI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:02:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 08:02:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:02:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:02:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:02:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:02:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:02:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:02: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 08:02: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 08:02: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 08:02: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 08:02: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 08:02: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 08:02:50 INFO - Downloading blessings-1.6.tar.gz 08:02:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:02:50 INFO - Installing collected packages: blessings 08:02:50 INFO - Running setup.py install for blessings 08:02:50 INFO - Successfully installed blessings 08:02:50 INFO - Cleaning up... 08:02:50 INFO - Return code: 0 08:02:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:02:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:02:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:02:50 INFO - Reading from file tmpfile_stdout 08:02:50 INFO - Current package versions: 08:02:50 INFO - blessings == 1.6 08:02:50 INFO - blobuploader == 1.2.4 08:02:50 INFO - docopt == 0.6.1 08:02:50 INFO - functools32 == 3.2.3-2 08:02:50 INFO - jsonschema == 2.5.1 08:02:50 INFO - manifestparser == 1.1 08:02:50 INFO - mozInstall == 1.12 08:02:50 INFO - mozcrash == 0.17 08:02:50 INFO - mozdebug == 0.1 08:02:50 INFO - mozdevice == 0.48 08:02:50 INFO - mozfile == 1.2 08:02:50 INFO - mozhttpd == 0.7 08:02:50 INFO - mozinfo == 0.9 08:02:50 INFO - mozleak == 0.1 08:02:50 INFO - mozlog == 3.4 08:02:50 INFO - moznetwork == 0.27 08:02:50 INFO - mozprocess == 0.23 08:02:50 INFO - mozprofile == 0.28 08:02:50 INFO - mozrunner == 6.12 08:02:50 INFO - mozscreenshot == 0.1 08:02:50 INFO - mozsystemmonitor == 0.3 08:02:50 INFO - moztest == 0.7 08:02:50 INFO - mozversion == 1.4 08:02:50 INFO - psutil == 3.1.1 08:02:50 INFO - requests == 1.2.3 08:02:50 INFO - wsgiref == 0.1.2 08:02:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:02:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:02:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:02:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:02:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:02:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:51 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 0x100af1490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a67570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f971ac83940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100905d30>, '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 0x10076bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1008fdb20>, '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.Xtw0vsbFR1/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.9F2nxyEl2D/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/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 08:02:51 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 08:02:51 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 08:02:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xtw0vsbFR1/Render', 08:02:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:02:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:02:51 INFO - 'HOME': '/Users/cltbld', 08:02:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:02:51 INFO - 'LOGNAME': 'cltbld', 08:02:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:02:51 INFO - 'MOZ_NO_REMOTE': '1', 08:02:51 INFO - 'NO_EM_RESTART': '1', 08:02:51 INFO - 'PAGER': '/bin/cat', 08:02:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:02:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:02:51 INFO - 'PWD': '/builds/slave/test', 08:02:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:02:51 INFO - 'SHELL': '/bin/bash', 08:02:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9F2nxyEl2D/Listeners', 08:02:51 INFO - 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/', 08:02:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:02:51 INFO - 'USER': 'cltbld', 08:02:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:02:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:02:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:02:51 INFO - 'XPC_FLAGS': '0x0', 08:02:51 INFO - 'XPC_SERVICE_NAME': '0', 08:02:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:02:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:02:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:02:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 08:02:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 08:02:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:02:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 08:02:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 08:02:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 08:02:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 08:02:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 08:02:51 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:02:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:02:51 INFO - Cleaning up... 08:02:51 INFO - Return code: 0 08:02:51 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:02:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:02:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:02:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:02:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:02:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100af1490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a67570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f971ac83940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100905d30>, '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 0x10076bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1008fdb20>, '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.Xtw0vsbFR1/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.9F2nxyEl2D/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/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 08:02:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:02:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:02:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xtw0vsbFR1/Render', 08:02:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:02:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:02:51 INFO - 'HOME': '/Users/cltbld', 08:02:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:02:51 INFO - 'LOGNAME': 'cltbld', 08:02:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:02:51 INFO - 'MOZ_NO_REMOTE': '1', 08:02:51 INFO - 'NO_EM_RESTART': '1', 08:02:51 INFO - 'PAGER': '/bin/cat', 08:02:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:02:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:02:51 INFO - 'PWD': '/builds/slave/test', 08:02:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:02:51 INFO - 'SHELL': '/bin/bash', 08:02:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9F2nxyEl2D/Listeners', 08:02:51 INFO - 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/', 08:02:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:02:51 INFO - 'USER': 'cltbld', 08:02:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:02:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:02:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:02:51 INFO - 'XPC_FLAGS': '0x0', 08:02:51 INFO - 'XPC_SERVICE_NAME': '0', 08:02:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:02:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:02:51 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:02:51 INFO - Cleaning up... 08:02:51 INFO - Return code: 0 08:02:51 INFO - Running post-action listener: _resource_record_post_action 08:02:51 INFO - Running post-action listener: _start_resource_monitoring 08:02:51 INFO - Starting resource monitoring. 08:02:51 INFO - [mozharness: 2016-11-18 16:02:51.879476Z] Finished create-virtualenv step (success) 08:02:51 INFO - [mozharness: 2016-11-18 16:02:51.880489Z] Running install step. 08:02:51 INFO - Running pre-action listener: _resource_record_pre_action 08:02:51 INFO - Running main action method: install 08:02:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:02:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:02:52 INFO - Reading from file tmpfile_stdout 08:02:52 INFO - Detecting whether we're running mozinstall >=1.0... 08:02:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:02:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:02:52 INFO - Reading from file tmpfile_stdout 08:02:52 INFO - Output received: 08:02:52 INFO - Usage: mozinstall [options] installer 08:02:52 INFO - Options: 08:02:52 INFO - -h, --help show this help message and exit 08:02:52 INFO - -d DEST, --destination=DEST 08:02:52 INFO - Directory to install application into. [default: 08:02:52 INFO - "/builds/slave/test"] 08:02:52 INFO - --app=APP Application being installed. [default: firefox] 08:02:52 INFO - mkdir: /builds/slave/test/build/application 08:02:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:02:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0.en-US.mac.dmg --destination /builds/slave/test/build/application 08:03:14 INFO - Reading from file tmpfile_stdout 08:03:14 INFO - Output received: 08:03:14 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 08:03:14 INFO - Running post-action listener: _resource_record_post_action 08:03:14 INFO - [mozharness: 2016-11-18 16:03:14.772031Z] Finished install step (success) 08:03:14 INFO - [mozharness: 2016-11-18 16:03:14.772164Z] Running run-tests step. 08:03:14 INFO - Running pre-action listener: _resource_record_pre_action 08:03:14 INFO - Running main action method: run_tests 08:03:14 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:03:14 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:03:14 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:03:14 INFO - 2016-11-18 08:03:14.786 screenresolution[2524:31618] starting screenresolution argv=screenresolution get 08:03:14 INFO - 2016-11-18 08:03:14.789 screenresolution[2524:31618] Display 0: 1600x1200x32@60 08:03:14 INFO - 2016-11-18 08:03:14.795 screenresolution[2525:31621] starting screenresolution argv=screenresolution list 08:03:14 INFO - Available Modes on Display 0 08:03:14 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:03:14 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:03:14 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:03:14 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:03:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:03:15 INFO - Intel Iris: 08:03:15 INFO - Chipset Model: Intel Iris 08:03:15 INFO - Type: GPU 08:03:15 INFO - Bus: Built-In 08:03:15 INFO - VRAM (Dynamic, Max): 1536 MB 08:03:15 INFO - Vendor: Intel (0x8086) 08:03:15 INFO - Device ID: 0x0a2e 08:03:15 INFO - Revision ID: 0x0009 08:03:15 INFO - Displays: 08:03:15 INFO - Display: 08:03:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:03:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:03:15 INFO - Display Serial Number: 1600x1200 60 08:03:15 INFO - Main Display: Yes 08:03:15 INFO - Mirror: Off 08:03:15 INFO - Online: Yes 08:03:15 INFO - Rotation: Supported 08:03:15 INFO - Return code: 0 08:03:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:03:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:03:15 INFO - Python 2.7.3 08:03:15 INFO - Return code: 0 08:03:15 INFO - grabbing minidump binary from tooltool 08:03:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:03:15 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 0x100905d30>, '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 0x10076bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1008fdb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:03:15 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 08:03:15 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 08:03:15 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 08:03:15 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:03:15 INFO - Return code: 0 08:03:15 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:03:15 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:03:15 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:03:15 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:03:15 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:03:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FmRmLTisR-qnEO4mwOEHDg/artifacts/public/build/firefox-51.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0238', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 08:03:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/FmRmLTisR-qnEO4mwOEHDg/artifacts/public/build/firefox-51.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0238 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 08:03:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Xtw0vsbFR1/Render', 08:03:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:03:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:03:15 INFO - 'HOME': '/Users/cltbld', 08:03:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:03:15 INFO - 'LOGNAME': 'cltbld', 08:03:15 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:03:15 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:03:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:03:15 INFO - 'MOZ_NO_REMOTE': '1', 08:03:15 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:03:15 INFO - 'NO_EM_RESTART': '1', 08:03:15 INFO - 'PAGER': '/bin/cat', 08:03:15 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:03:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:03:15 INFO - 'PWD': '/builds/slave/test', 08:03:15 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:03:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:03:15 INFO - 'SHELL': '/bin/bash', 08:03:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9F2nxyEl2D/Listeners', 08:03:15 INFO - 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/', 08:03:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:03:15 INFO - 'USER': 'cltbld', 08:03:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:03:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:03:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:03:15 INFO - 'XPC_FLAGS': '0x0', 08:03:15 INFO - 'XPC_SERVICE_NAME': '0', 08:03:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:03:15 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FmRmLTisR-qnEO4mwOEHDg/artifacts/public/build/firefox-51.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0238', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 08:03:15 INFO - mozversion application_buildid: 20161118040828 08:03:15 INFO - mozversion application_changeset: 11b5acda902f5ecb13ab2d451d36c7a9063f2b26 08:03:15 INFO - mozversion application_display_name: Firefox 08:03:15 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:03:15 INFO - mozversion application_name: Firefox 08:03:15 INFO - mozversion application_remotingname: firefox 08:03:15 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 08:03:15 INFO - mozversion application_vendor: Mozilla 08:03:15 INFO - mozversion application_version: 51.0 08:03:15 INFO - mozversion platform_buildid: 20161118040828 08:03:15 INFO - mozversion platform_changeset: 11b5acda902f5ecb13ab2d451d36c7a9063f2b26 08:03:15 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 08:03:15 INFO - mozversion platform_version: 51.0 08:03:15 INFO - using testdate: 1479484995 08:03:15 INFO - actual date: 1479484995 08:03:15 INFO - starting webserver on 'localhost:56068' 08:03:15 INFO - SUITE-START | Running 2 tests 08:03:15 INFO - TEST-START | dromaeo_css 08:03:15 INFO - operating with platform_type : mac_ 08:03:15 INFO - Initialising browser for dromaeo_css test... 08:03:15 INFO - TEST-INFO | started process 2535 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/tmpol8Mly/profile http://localhost:56068/getInfo.html) 08:03:17 INFO - PROCESS | 2535 | __metrics Screen width/height:1600/1200 08:03:17 INFO - PROCESS | 2535 | colorDepth:24 08:03:17 INFO - PROCESS | 2535 | Browser inner width/height: 1024/689 08:03:17 INFO - PROCESS | 2535 | __metrics 08:03:17 INFO - PROCESS | 2535 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:03:17 INFO - PROCESS | 2535 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:03:18 INFO - TEST-INFO | 2535: exit 0 08:03:18 INFO - Browser initialized. 08:03:18 INFO - Running cycle 1/1 for dromaeo_css test... 08:03:18 INFO - TEST-INFO | started process 2537 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/tmpol8Mly/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:03:30 INFO - PROCESS | 2537 | RSS: Main: 168669184 08:03:30 INFO - PROCESS | 2537 | 08:04:21 INFO - PROCESS | 2537 | 1479485061902 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] 08:06:32 INFO - PROCESS | 2537 | Cycle 1(1): loaded http://localhost:56068/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56068/tests/dromaeo/cssquery-ext.html) 08:06:33 INFO - PROCESS | 2537 | RSS: Main: 276029440 08:06:33 INFO - PROCESS | 2537 | 08:09:36 INFO - PROCESS | 2537 | Cycle 1(1): loaded http://localhost:56068/tests/dromaeo/cssquery-ext.html (next: http://localhost:56068/tests/dromaeo/cssquery-jquery.html) 08:09:36 INFO - PROCESS | 2537 | RSS: Main: 299364352 08:09:36 INFO - PROCESS | 2537 | 08:12:39 INFO - PROCESS | 2537 | Cycle 1(1): loaded http://localhost:56068/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56068/tests/dromaeo/cssquery-mootools.html) 08:12:39 INFO - PROCESS | 2537 | RSS: Main: 280195072 08:12:39 INFO - PROCESS | 2537 | 08:15:42 INFO - PROCESS | 2537 | Cycle 1(1): loaded http://localhost:56068/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56068/tests/dromaeo/cssquery-prototype.html) 08:15:42 INFO - PROCESS | 2537 | RSS: Main: 278478848 08:15:42 INFO - PROCESS | 2537 | 08:18:45 INFO - PROCESS | 2537 | Cycle 1(1): loaded http://localhost:56068/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56068/tests/dromaeo/cssquery-yui.html) 08:18:46 INFO - PROCESS | 2537 | RSS: Main: 263528448 08:18:46 INFO - PROCESS | 2537 | 08:21:49 INFO - PROCESS | 2537 | Cycle 1(1): loaded http://localhost:56068/tests/dromaeo/cssquery-yui.html (next: http://localhost:56068/tests/dromaeo/cssquery-dojo.html) 08:21:49 INFO - PROCESS | 2537 | RSS: Main: 361738240 08:21:49 INFO - PROCESS | 2537 | 08:21:49 INFO - PROCESS | 2537 | __start_tp_report 08:21:49 INFO - PROCESS | 2537 | _x_x_mozilla_page_load 08:21:49 INFO - PROCESS | 2537 | _x_x_mozilla_page_load_details 08:21:49 INFO - PROCESS | 2537 | |i|pagename|runs| 08:21:49 INFO - PROCESS | 2537 | |0;dojo.html;2717.497263006344;3422.6748458896404;3457.619661837199;3461.3942560051983;3516.156122530592;5701.657900525966;5802.39074897136;5823.417658234174;5852.444017818311;5871.500922421594;5896.911546326798;5982.670953097578;5990.6106103103375;5995.490383317412;6001.66990815505;5266.947330526694;5302.151655735083;5331.946680533203;5358.732063396815;5381.81163659272;5231;5248.212768084799;5378.704171270579;5381.515663590296;5395.190721391803;5276.657017293864;5292.947070529284;5312.548433383147;5312.973435132838;5334.733263336818;5317.787288508455;5326.36083669962;5331.786728530854;5382.4886635769535;5400.567954563626;5206.531412172884;5306.495882891119;5311.282976798121;5337.2794672719465;5343.064963631349;5768.567357448208;5785.739641716133;5791.102379131242;5796.565257605655;5809.3609702932645;6188.597741146811;6214.471769899564;6223.346548612376;6225.28409232935;6234.563580549363;6209.192804935329;6253.656048917308;6271.749130034793;6274.341194174592;6305.621662700252;5812.622179558315;5821.621594596338;5861.941380586225;5885.411458854166;5984.311804142536;6229.688515574203;6261.935470969946;6267.811965641038;6279.120923070774;6288.622682639057;6207.410296021871;6232.065190221412;6234.908890944067;6252.718627661766;6254.6247225166635;6161.8459538511;6173.9073913891325;6223.439890409885;6230.314665386803;6240.687965601793;6228.4705800007505;6233.781817636361;6247.062940558862;6257.217847769029;6270.341614130451;6178.320384757673;6181.134641150243;6231.594946328474;6263.31103578606;6271.623702577769;5018.648694591344;5057.848264552069;5065.088284108896;5123.359580052494;5124.077666020152;3812.866549670748;4489.438820147481;4566.520515345841;4582.610478109332;4600.194965880957;5970.0746384310505;5990;5991.281046274475;6001.309849367379;6005.969970150121;6151.96924015377;6225.564210505221;6240.781572644935;6248.218972628421;6260.46786023184;5879.147523609053;5958.851028724316;5968;6023.3073196582955;6030.969845150747;5507.669539827623;5549.361823390362;5550.139728342114;5553;5573.60984731073;6420.711068001951;6421.004744264646;6440.388163124496;6443.871122577616;6453.838654033687;6145.279321789906;6156.969215153896;6188.566800323934;6199.938000620116;6255.906161407492;6463;6473.805785826433;6479.4816414685765;6483.2544257411;6483.578567393104;6208.348123446973;6236.532260080421;6240.188775459162;6253.812385628438;6264.655443950626;6150.231221097363;6154.076888466766;6166.167567378347;6218.81343559694;6260;5002.699838009731;6271.310155882758;6272.059191121369;6276.717547710456;6278.654673992792;6066.817995460143;6273.96863015682;6281.780137695158;6293.276273228455;6296.496280297657;5008.098542262428;5895.616784908967;6049.969750151221;6189.473894718999;6209.410106039919;6151.50787936955;6261.186045814015;6261.467775239155;6266.342034086355;6271.4669253114;6108.5723999319625;6127.632342059491;6155.2921414037955;6167.136600875791;6186.226721659793 08:21:49 INFO - PROCESS | 2537 | |1;ext.html;474.7412660100246;479.9448063472702;529.8622358186873;532.1273112096166;550.5100460590075;31653.841730791344;41827.16345673085;43438.262469501184;43547;45558;44572;44829;44843.55156448435;44864.878378040485;44971;9999.150072243869;10107.54516046776;10130.898691013106;10135.290529662927;10151.187904967623;9283.721488355326;9516.80966380672;9522.80954380912;9627.663031793889;9637.421754694706;11007.899210078967;12125.454354554022;12166.756664866698;12451.315177665225;12481.438335274888;12328.568500102498;12438.253704777651;12438.875611243953;12439.129260951737;12439.93780031103;12248.938755306255;12352.567660131943;12362.505499779998;12369.876301237053;12371.505139794397;42056;42313.365299520534;42338.364924526155;42430.93922651927;42568;3383.729301655886;3521.3133438979407;3540.805255710935;3588.820558972041;3610.7472476926628;31900.521492177402;32135.19662008445;32193.83903080493;32256.677433225836;32600.184995375304;3044.1932887784756;3185.8725650973934;3257.9348413031844;3386.4243078676686;3419.6238413774463;513935;545021;545498;545826;547239.2638036824;60291.39708602946;60626;60654.78690426255;60661;60873;35339;36064.81967590145;36118.458223126676;37039.629603704154;37151.25697486035;17853.553661158574;18072.638547229246;18136.274549017893;18151.8201316914;18188.63622727538;13643.044986850826;13854.792178117335;13861.792073118912;13868.306584670727;13898;17119.91440042792;17392;19153.079575148764;19492.902535487232;19751.901240493706;6874.518783685194;7551.660175292125;7614.657340419694;8185.386096042821;8245.711400100967;886.8802711633847;1028.8713910761155;1032.8140934631838;1034.374203606822;1052.2007367401675;25644;28727.850885964745;28830.567541486893;29434.411311774074;29537.556936645968;1091.2252300866473;1100.834874768791;1114.6934592986831;1160.7620437810228;1261.344101067458;15576.92211538935;15605.531834044998;15623.531294061197;15807.683846323009;16035.877488575921;628267;633064;634672;635464;639678;60428.09357859636;61115.69442152761;61150.77698446095;61154;61269;1403.305363844883;1423.1247782613618;1453.4985430026625;1454.040333379964;1459.569427019032;29472.410551788416;30247.941322054503;30275.848620756755;30603.387932241676;30927.536086959164;50981.490185097675;51765;51898.22152667789;51907.74046129896;51935.48064519458;623458;624318;624778;625988;628788;23919.641205382282;24505.50988980246;24623.50752984966;27093.72906270912;27499.45001100007;47375;48961.75519122381;49449.505504945926;51683.224751628004;52118.47881521288;39500.40749388819;40176;40968.79515602403;41830.49017058986;42430.75692430856;33028.33943321072;40090.599094009856;41448.37827432651;41758.37362439626;42426.575734242266;39709.80145099256;40059.399109012804;40375;40647.796761016005;40664;34084;35326.11684707903;35528.64471355253;36148.09629759277;36233;25101.49797004013;26047.869760651833;26420.735792642598;26513.469730605666;26721.8663906687 08:21:49 INFO - PROCESS | 2537 | |2;jquery.html;333.23522515826176;371.8902923637527;378.7102866307275;381.50213970768135;403.9979800100999;20738.481537961557;21406.929653517338;22025.449363765892;22201.778902160375;22218;21066.36800895972;21464.785352146475;22072.8896355518;22196.556068878614;22432.214872479468;5187.818426355078;5920.615160014585;6088.3302836688035;6284.937150628503;6338.936610633881;4101.815418306169;4187.246295666779;6260.937390626081;6297.716602752887;6310.368963103676;5909.527237820964;6156;6205.069239614071;6254.093156492306;6261.592996455239;6004.279486461631;6221.035739460391;6228.657423841687;6284.402981716729;6316.115743795872;5272.657277276965;5978.222831031938;6026.879462410728;6088.817335479898;6140.017597184474;25704.357391065187;26639.866800666066;26776.73223267762;27411.31471713203;27884;1781.536800431897;2241.6189247827747;2247.5280191159877;2251.1445650652813;2869.741723244897;9754.512274386323;10294.794104117951;10475.790484190273;10625.46872656364;10734.624288149955;1894.4695485264149;1983.5041239690077;1995.9700604490945;2011.9597608047757;2034.5727397246596;128450;128834;139161.3041934794;143308.99999999895;145631;11491.482883270273;11666.241694290014;11695.830416958235;11768.470418831002;11945.581904633296;10995.505202265917;15141.545753627408;15216.315265813066;15217.239138043053;15222.086674799548;8880.822383552293;9157.358984871129;9185.448873067637;9239.861402078974;9266.490343031199;9183.770405739777;9197.862032069525;9207.493587852598;9356.532173391439;9370.437773733598;3943.132510847609;4415.1611193873705;4611.400517932714;4611.631069514431;4690.577847993697;1655.6357601327688;1773.379317238956;1834.0958822359366;1882.164889232987;1957.9510512237023;402.58332625732226;936.6674830435234;938.8685584018243;953.713885834247;957.3633533700012;11379.487923043484;11858.16992810495;11989.22070065443;12105.878941210474;12303.876961230451;394.16043826649627;422.51410877491134;430.32009425108254;433.3607928305724;459.53127809634657;5597.664140151523;6109.786157484556;6160.322364539897;6267.122602835607;6298.779542715983;685945;688000;694814.5259273672;695284;698559;143853;153004;153707;154760;157414;841.6423020216383;1016.8118898003893;1039.781645854364;1039.927205095636;1041.432419331451;16825.158742063093;19637.98440014857;20901.686474703194;21398.144074236858;21920.89039554792;57657.711711441174;58879;59024;59115.70442147762;60015.39984600273;75465.62267188629;89265;90185.54907225422;90466.54766726124;91227.6315855276;7989.960050199712;8144.104148543537;8144.918550814416;8448.23965843077;8681.522516261413;9227.35408521424;13368.059027014042;13447.663808404868;13505.189688618715;13603;9968.700938971842;11390.724540041558;11405.258658187191;11491.138164637518;11613.5354585819;11154.16343774204;11281.266717663326;11319.773604527698;11548.48031838586;11729.061675066145;9669.694591230094;10799.820152869997;10878.40168790724;11257.93049660281;11763.294202347886;7628.618569071636;7833.92166078332;8092.150324215872;8141.959290203511;8422.494650321001;8472.957635211784;11378.374189419661;11432.771344573228;11794.233374830608;11898.821517677416 08:21:49 INFO - PROCESS | 2537 | |3;mootools.html;1395.1838174667819;1554.82119556251;1575.9290831912563;1779.864163004395;1912.5314297996983;8340.999080110387;8875.334349923749;9179.082091790817;9456.338056336042;9458.38520496169;9250.12123848235;9379.859302110473;9384.577694003769;9386.48374339411;9390.436573805577;2436.0621160853066;2451.8651474168914;2463.519613675334;2465;2466.3464181991785;2389.8730130184063;2438.7317395086525;2442.584760590695;2447.5349683560157;2456.545539075277;2408.1932552594903;2414.9034038638433;2430.562498750224;2435.0625009371342;2444.559979203743;2376.857388556692;2378.4529558201625;2382.4401265702545;2383.261189031397;2414.9034038638433;2362.5156842847173;2394.616861302183;2398.5922393193023;2400.063975049732;2402.4354276744953;9153.725388238363;9253.444793312423;9316.487593182372;9339.76650583734;9451.716448506488;5500.064988951888;5741.138829175616;5746.080627099644;5753.539716822645;5761.53907687384;3729.3287208302495;3735.6077611850637;3791.905202369935;3827.5215598050245;3833.195029043904;942.2603256443728;1026.9948650256702;1092.9234953553255;1095.0801326885376;1122.6688127002471;12307.20003199789;12374.948129409011;12437.440315185839;12469.812952805714;12498.937505312506;4596.5863072323655;4628.3288923105965;4819.566239038503;4821.2045012572535;4822.541858523435;9617.56720947559;9653.372530785477;9666.806663866824;9667.951660241653;9673.854892176623;5501.119820828648;5502.257195278667;5512.834614961557;5513.61404701675;5519.31008623922;4473.261911784584;4654.6741728078705;4661.743604101807;5133.281340612317;5151.7681704323395;3915.4909861718365;3960.683145348365;4017.0961533654695;4059.3910913363234;4064.776437295918;1146.1060372909144;1160.785254727878;1177.770334784726;1180.3095189314176;1194.7311854832594;915.0131781789275;922.6032805893396;932.216937772261;942.8161508505775;988.4365911430559;5833.79581714638;5887.081874878282;6007.36922623127;6228.844278893064;6283.246010478681;383.61255132316006;435.5231022030871;438.48478038304864;443.0872402850139;444.45776153093175;4011.157656892027;4061.147159096564;4065.2682517146604;4074.3684728867074;4132.772697501606;11155.944220278847;11438.714032149262;11577.189596728149;11591.362475063952;11593;11501.022413094814;11501.712457188638;11508;11510.827337589943;11529.48117334722;8665.090165532529;8679.913200868037;8706.738797836075;8712.95643521791;8766.649334026695;5724.227229323988;5921.970390148022;5985.850353741192;6101.359357267423;6122.142899993915;8225.218604232588;10532.52603632823;10535.525901334306;10653.840192396965;10700;10963.50664220096;11100.389478578756;11110.055645270242;11116.61091861781;11118.054965328038;7216.819579510344;7263.491555591269;7596.202398748273;7979.202079791974;8153.79615509617;8639.092895245909;9040.321975851706;9054.139856713602;9060.773480662772;9074.047225041275;7783.766487005399;7864.134945156143;7895;7961.72133975308;8142.633581488965;7520.962395187989;7648.273414025659;7648.885266720892;7651.502652327581;7696.7306144284685;7390.706626340261;7544.170141284564;7546.584937828472;7548.358389536951;7549.7357592483995;5081.517255860687;5091.821786237464;5110.770015349244;5241.9737901310245;5362.008028514581;4480.260756975063;5123.538881500683;5173.275741396283;5272.815451459181;5401.783928642967 08:21:49 INFO - PROCESS | 2537 | |4;prototype.html;244.95713250181214;295.52273078977447;306.61213564840466;313.2622673603663;320.7434052757793;4560.931586026212;5611.523020543254;5613.267177327072;5638.548916086714;5643.61731375813;5153.613478989072;5166.819161329354;5307.558523079379;5454.618176727621;5478.616496845212;4114.341705327133;4146.668266538685;4175.3736939459095;4194.5805419457965;4238.97880510597;4179.456670632813;4210.6841986851105;4213.705040647141;4232.555581663927;4266.658667306623;3485.860565577374;3938.6652134568453;4119.691023173259;4128.174365126972;4166.729162604436;3917.902987163599;4162.271602469556;4164.916701665949;4184.765494179209;4197.349410841324;3426.623071462137;3551.2897420516;3628.727845411605;3637.199816040467;3639.290338384016;4327.317063420699;4796.04079184161;5066.442691303953;5286.444923283077;5382.81995791705;4470.094438614894;4694.530546945312;4712.929306060412;4723.385959825235;4819.2530157825595;4771.37972063633;4793.137235297645;5706.71466426677;5714;5805.332386775491;3804.619538046201;3916.295066887959;3920.0395903003946;3927.253821773854;3929.25344184604;4460.799264033094;5151.613628977804;6071;6104.229773365719;6110.403078691538;3941.2117576484816;3984.302747019318;3986.202759448122;3987.840486380512;4014.0366312085475;4067.572904845008;4069.7994091743008;4079.2453396121814;4081.8163182656854;4090.734102283342;4109.185208132439;4626.653001024936;4663.953360466352;4693.812247510062;4756.262779269219;5561.63849349791;5739.253896993448;5746.827595172152;5766;5831.300243970751;1288.0983311681673;1333.8266025416633;1344.3076815440056;1353.9661508462368;1358.9536057235873;866.5190819095336;880.6565439478609;890.6838072484304;964.1033838530094;979.8628192053118;5123.899201327438;5279.8880447821175;5371.650842695212;5391.514763671288;5517.5585953123655;4998.8257456515375;5996.13056106862;5996.820095397058;6013.9699301503215;6036.879262414815;5251.133562962068;5625.1999360204345;5625.971870140705;5649.745761440663;5662.292213473316;3139.5290706393766;3153.8265395403473;3165.936681266408;3177.601855183712;3180.316232010083;5187.974060129675;5826.475617194473;6179.3202747697715;6249.562530622904;6298.811035668938;5788.971055144697;6054.212952316171;6097.146399504073;6108.938910610926;6139.693015349215;5137.6660517066275;5799.681017543991;5817.389174136824;6156;6245.125682404467;5085.949140508547;5929.776333874797;5939;6013.226098300925;6026.969865150646;5037.496250374933;5195.454477279983;5533.087040638249;5547.667139971614;5886.411358864079;5254.238135470259;5564.693941833238;5678.034734095297;5764.452377024176;6148.139260503623;3332.450145725928;3947.605239476144;3968.8214030368126;3972.3842804365368;3985.103351745834;5150;5165.638405311743;5976.760929562943;5993.790217342546;6004.958314173202;5581.776728930798;5599;5791.8552036199435;5830.125481177857;5854.648721076749;5473.534749546333;5776.19133321327;5782.884342313214;5785.987452195933;5807.419258074327;4927.211646136601;4964.982178653295;5245.587029966258;5286.744170942275;5687.914681279867;4453.020335526179;4847.80608775645;5528.806491772769;5648.881669373985;5833.387494313036;4462.9916537558875;5674.631148975303;5682.37493875665;5692.601517893873;5703.914441283301 08:21:49 INFO - PROCESS | 2537 | |5;yui.html;692.8233300508373;745.1319213116719;770.7803276066322;799.292626025967;845.3533047218882;16605.501834944956;17362.045087520182;17370.739438908397;17379.391721289758;17439.825601743978;15342.693146137071;15392.846071539281;15414.768778468331;15444.073355598672;15455.381784728597;712.3553184368155;727.9963600182018;730.0618704964122;876;903.2909166304453;893.0221407558745;912.5847739278612;912.8950170730351;913.2420091324185;914.0539541574472;972.6838777397339;974.814785190815;975.1954637424153;976.7606936300616;977.1547611316196;966.1787480641469;966.7534778631474;967.6371360739687;968.2254196642693;969.4910172159603;719.7804669575777;720.1034711783863;721.3219573600799;721.9747308844218;723.6707298179316;15522.844771552365;15660.295286712126;15662.765058524132;15668.843311566852;15692.13693246871;13560.796588051187;13834.515791947233;13892.93053534736;13908.4436622536;14105.64735881601;13393.263370514618;13634.464232414704;13935;13997.790033149511;14348.92825535876;4735.644826638016;4739.099571081483;4788.257820037899;4844.927326090112;4946.802127914879;119513;121836.99999999911;122083.3895830524;122343;123453.38273308666;11254.381009044417;11320.6037788677;11486.19596628228;11620.244684095507;11669.183157179086;21663.45841353979;21836.89081554614;21886.671699924515;21907.014184361742;22403.21588744386;8201.30288925448;8249;8264.256216940385;8273.627686754111;8295.12901145371;4508.143452743968;4568.361738854369;4695.366125573072;4781.091592597395;4842.685225460334;3056.724894759482;3500.5274287970797;3563.465480177942;3592.3354179476883;3614.475900994341;667.0576890576765;668.8495088605025;671.8051764988196;672.8889733193968;673.1417442760519;607.9848003799852;609.9024156134997;617.8856911471393;630.5271046714964;639.859230969186;8857.778555536035;9218.53907304632;9466.526673666289;9489.762755931157;9621.567029483691;622.2564035976964;630.8769789891019;633.6736580660963;647.9190101237346;662.364130434788;3884.6698030667144;4336.6530677545725;4570.908581828345;4578.725276483422;4581.335706322631;96900.0309996891;129314;140929;153736;164425;90797;92673;128326.35836820756;144508;150187.249063754;101477;119683;124435;126287;129674;8623.741287761126;8800.691975780817;8830.249428798625;8831.823363532565;9019.549022548978;61136.38863611307;63026.05460918182;67871.66064169648;68116.6594167026;68885;68238;70213.64893175705;75583.24416755982;85616.57191714001;96899.03100969183;13402.865971340161;13539;13858.65353366174;14008.369623366769;14178.291085445893;14214.431422743388;15074.472393466176;15136.167510786572;15226.619334516725;16336.509904702878;14461.710765784415;14550.85449145495;14741.631459213606;14759.852401475848;14852;13332.733345333232;13628.909687225196;14501.564953051426;14565.41738330455;15112.319945602254;13761.931190344385;14018.859811401755;14772.33524491379;15035.323410446776;15185.164815934788;11441.42792860337;11593.82609260845;11717.76564468723;11926.463309150935;12021.21862078997;11681.357525335849;11785.395818884512;11796.94101529487;12023.75952480963;12037.217580857216 08:21:49 INFO - PROCESS | 2537 | __end_tp_report 08:21:49 INFO - PROCESS | 2537 | __start_cc_report 08:21:49 INFO - PROCESS | 2537 | _x_x_mozilla_cycle_collect,1082 08:21:49 INFO - PROCESS | 2537 | __end_cc_report 08:21:49 INFO - PROCESS | 2537 | __startTimestamp1479486109745__endTimestamp 08:21:49 INFO - PROCESS | 2537 | 08:21:49 INFO - PROCESS | 2537 | ------- Summary: start ------- 08:21:49 INFO - PROCESS | 2537 | Number of tests: 6 08:21:49 INFO - PROCESS | 2537 | 08:21:49 INFO - PROCESS | 2537 | [#0] dojo.html Cycles:165 Average:5827.82 Median:6145.28 stddev:654.29 (10.6%) stddev-sans-first:609.11 08:21:49 INFO - PROCESS | 2537 | Values: 2717.5 3422.7 3457.6 3461.4 3516.2 5701.7 5802.4 5823.4 5852.4 5871.5 5896.9 5982.7 5990.6 5995.5 6001.7 5266.9 5302.2 5331.9 5358.7 5381.8 5231.0 5248.2 5378.7 5381.5 5395.2 5276.7 5292.9 5312.5 5313.0 5334.7 5317.8 5326.4 5331.8 5382.5 5400.6 5206.5 5306.5 5311.3 5337.3 5343.1 5768.6 5785.7 5791.1 5796.6 5809.4 6188.6 6214.5 6223.3 6225.3 6234.6 6209.2 6253.7 6271.7 6274.3 6305.6 5812.6 5821.6 5861.9 5885.4 5984.3 6229.7 6261.9 6267.8 6279.1 6288.6 6207.4 6232.1 6234.9 6252.7 6254.6 6161.8 6173.9 6223.4 6230.3 6240.7 6228.5 6233.8 6247.1 6257.2 6270.3 6178.3 6181.1 6231.6 6263.3 6271.6 5018.6 5057.8 5065.1 5123.4 5124.1 3812.9 4489.4 4566.5 4582.6 4600.2 5970.1 5990.0 5991.3 6001.3 6006.0 6152.0 6225.6 6240.8 6248.2 6260.5 5879.1 5958.9 5968.0 6023.3 6031.0 5507.7 5549.4 5550.1 5553.0 5573.6 6420.7 6421.0 6440.4 6443.9 6453.8 6145.3 6157.0 6188.6 6199.9 6255.9 6463.0 6473.8 6479.5 6483.3 6483.6 6208.3 6236.5 6240.2 6253.8 6264.7 6150.2 6154.1 6166.2 6218.8 6260.0 5002.7 6271.3 6272.1 6276.7 6278.7 6066.8 6274.0 6281.8 6293.3 6296.5 5008.1 5895.6 6050.0 6189.5 6209.4 6151.5 6261.2 6261.5 6266.3 6271.5 6108.6 6127.6 6155.3 6167.1 6186.2 08:21:49 INFO - PROCESS | 2537 | 08:21:49 INFO - PROCESS | 2537 | [#1] ext.html Cycles:180 Average:72986.93 Median:19534.72 stddev:160693.29 (822.6%) stddev-sans-first:161051.85 08:21:49 INFO - PROCESS | 2537 | Values: 474.7 479.9 529.9 532.1 550.5 31653.8 41827.2 43438.3 43547.0 45558.0 44572.0 44829.0 44843.6 44864.9 44971.0 9999.2 10107.5 10130.9 10135.3 10151.2 9283.7 9516.8 9522.8 9627.7 9637.4 11007.9 12125.5 12166.8 12451.3 12481.4 12328.6 12438.3 12438.9 12439.1 12439.9 12248.9 12352.6 12362.5 12369.9 12371.5 42056.0 42313.4 42338.4 42430.9 42568.0 3383.7 3521.3 3540.8 3588.8 3610.7 31900.5 32135.2 32193.8 32256.7 32600.2 3044.2 3185.9 3257.9 3386.4 3419.6 513935.0 545021.0 545498.0 545826.0 547239.3 60291.4 60626.0 60654.8 60661.0 60873.0 35339.0 36064.8 36118.5 37039.6 37151.3 17853.6 18072.6 18136.3 18151.8 18188.6 13643.0 13854.8 13861.8 13868.3 13898.0 17119.9 17392.0 19153.1 19492.9 19751.9 6874.5 7551.7 7614.7 8185.4 8245.7 886.9 1028.9 1032.8 1034.4 1052.2 25644.0 28727.9 28830.6 29434.4 29537.6 1091.2 1100.8 1114.7 1160.8 1261.3 15576.9 15605.5 15623.5 15807.7 16035.9 628267.0 633064.0 634672.0 635464.0 639678.0 60428.1 61115.7 61150.8 61154.0 61269.0 1403.3 1423.1 1453.5 1454.0 1459.6 29472.4 30247.9 30275.8 30603.4 30927.5 50981.5 51765.0 51898.2 51907.7 51935.5 623458.0 624318.0 624778.0 625988.0 628788.0 23919.6 24505.5 24623.5 27093.7 27499.5 47375.0 48961.8 49449.5 51683.2 52118.5 39500.4 40176.0 40968.8 41830.5 42430.8 33028.3 40090.6 41448.4 41758.4 42426.6 39709.8 40059.4 40375.0 40647.8 40664.0 34084.0 35326.1 35528.6 36148.1 36233.0 25101.5 26047.9 26420.7 26513.5 26721.9 08:21:49 INFO - PROCESS | 2537 | 08:21:49 INFO - PROCESS | 2537 | [#2] jquery.html Cycles:180 Average:39113.51 Median:375.30 stddev:115995.80 (30907.5%) stddev-sans-first:116284.65 08:21:49 INFO - PROCESS | 2537 | Values: 333.2 371.9 378.7 381.5 404.0 20738.5 21406.9 22025.4 22201.8 22218.0 21066.4 21464.8 22072.9 22196.6 22432.2 5187.8 5920.6 6088.3 6284.9 6338.9 4101.8 4187.2 6260.9 6297.7 6310.4 5909.5 6156.0 6205.1 6254.1 6261.6 6004.3 6221.0 6228.7 6284.4 6316.1 5272.7 5978.2 6026.9 6088.8 6140.0 25704.4 26639.9 26776.7 27411.3 27884.0 1781.5 2241.6 2247.5 2251.1 2869.7 9754.5 10294.8 10475.8 10625.5 10734.6 1894.5 1983.5 1996.0 2012.0 2034.6 128450.0 128834.0 139161.3 143309.0 145631.0 11491.5 11666.2 11695.8 11768.5 11945.6 10995.5 15141.5 15216.3 15217.2 15222.1 8880.8 9157.4 9185.4 9239.9 9266.5 9183.8 9197.9 9207.5 9356.5 9370.4 3943.1 4415.2 4611.4 4611.6 4690.6 1655.6 1773.4 1834.1 1882.2 1958.0 402.6 936.7 938.9 953.7 957.4 11379.5 11858.2 11989.2 12105.9 12303.9 394.2 422.5 430.3 433.4 459.5 5597.7 6109.8 6160.3 6267.1 6298.8 685945.0 688000.0 694814.5 695284.0 698559.0 143853.0 153004.0 153707.0 154760.0 157414.0 841.6 1016.8 1039.8 1039.9 1041.4 16825.2 19638.0 20901.7 21398.1 21920.9 57657.7 58879.0 59024.0 59115.7 60015.4 75465.6 89265.0 90185.5 90466.5 91227.6 7990.0 8144.1 8144.9 8448.2 8681.5 9227.4 13368.1 13447.7 13505.2 13603.0 9968.7 11390.7 11405.3 11491.1 11613.5 11154.2 11281.3 11319.8 11548.5 11729.1 9669.7 10799.8 10878.4 11257.9 11763.3 7628.6 7833.9 8092.2 8142.0 8422.5 8473.0 11378.4 11432.8 11794.2 11898.8 08:21:49 INFO - PROCESS | 2537 | 08:21:49 INFO - PROCESS | 2537 | [#3] mootools.html Cycles:180 Average:5962.05 Median:4821.87 stddev:3438.87 (71.3%) stddev-sans-first:3431.39 08:21:49 INFO - PROCESS | 2537 | Values: 1395.2 1554.8 1575.9 1779.9 1912.5 8341.0 8875.3 9179.1 9456.3 9458.4 9250.1 9379.9 9384.6 9386.5 9390.4 2436.1 2451.9 2463.5 2465.0 2466.3 2389.9 2438.7 2442.6 2447.5 2456.5 2408.2 2414.9 2430.6 2435.1 2444.6 2376.9 2378.5 2382.4 2383.3 2414.9 2362.5 2394.6 2398.6 2400.1 2402.4 9153.7 9253.4 9316.5 9339.8 9451.7 5500.1 5741.1 5746.1 5753.5 5761.5 3729.3 3735.6 3791.9 3827.5 3833.2 942.3 1027.0 1092.9 1095.1 1122.7 12307.2 12374.9 12437.4 12469.8 12498.9 4596.6 4628.3 4819.6 4821.2 4822.5 9617.6 9653.4 9666.8 9668.0 9673.9 5501.1 5502.3 5512.8 5513.6 5519.3 4473.3 4654.7 4661.7 5133.3 5151.8 3915.5 3960.7 4017.1 4059.4 4064.8 1146.1 1160.8 1177.8 1180.3 1194.7 915.0 922.6 932.2 942.8 988.4 5833.8 5887.1 6007.4 6228.8 6283.2 383.6 435.5 438.5 443.1 444.5 4011.2 4061.1 4065.3 4074.4 4132.8 11155.9 11438.7 11577.2 11591.4 11593.0 11501.0 11501.7 11508.0 11510.8 11529.5 8665.1 8679.9 8706.7 8713.0 8766.6 5724.2 5922.0 5985.9 6101.4 6122.1 8225.2 10532.5 10535.5 10653.8 10700.0 10963.5 11100.4 11110.1 11116.6 11118.1 7216.8 7263.5 7596.2 7979.2 8153.8 8639.1 9040.3 9054.1 9060.8 9074.0 7783.8 7864.1 7895.0 7961.7 8142.6 7521.0 7648.3 7648.9 7651.5 7696.7 7390.7 7544.2 7546.6 7548.4 7549.7 5081.5 5091.8 5110.8 5242.0 5362.0 4480.3 5123.5 5173.3 5272.8 5401.8 08:21:49 INFO - PROCESS | 2537 | 08:21:49 INFO - PROCESS | 2537 | [#4] prototype.html Cycles:180 Average:4657.94 Median:5166.23 stddev:1420.72 (27.5%) stddev-sans-first:1385.56 08:21:49 INFO - PROCESS | 2537 | Values: 245.0 295.5 306.6 313.3 320.7 4560.9 5611.5 5613.3 5638.5 5643.6 5153.6 5166.8 5307.6 5454.6 5478.6 4114.3 4146.7 4175.4 4194.6 4239.0 4179.5 4210.7 4213.7 4232.6 4266.7 3485.9 3938.7 4119.7 4128.2 4166.7 3917.9 4162.3 4164.9 4184.8 4197.3 3426.6 3551.3 3628.7 3637.2 3639.3 4327.3 4796.0 5066.4 5286.4 5382.8 4470.1 4694.5 4712.9 4723.4 4819.3 4771.4 4793.1 5706.7 5714.0 5805.3 3804.6 3916.3 3920.0 3927.3 3929.3 4460.8 5151.6 6071.0 6104.2 6110.4 3941.2 3984.3 3986.2 3987.8 4014.0 4067.6 4069.8 4079.2 4081.8 4090.7 4109.2 4626.7 4664.0 4693.8 4756.3 5561.6 5739.3 5746.8 5766.0 5831.3 1288.1 1333.8 1344.3 1354.0 1359.0 866.5 880.7 890.7 964.1 979.9 5123.9 5279.9 5371.7 5391.5 5517.6 4998.8 5996.1 5996.8 6014.0 6036.9 5251.1 5625.2 5626.0 5649.7 5662.3 3139.5 3153.8 3165.9 3177.6 3180.3 5188.0 5826.5 6179.3 6249.6 6298.8 5789.0 6054.2 6097.1 6108.9 6139.7 5137.7 5799.7 5817.4 6156.0 6245.1 5085.9 5929.8 5939.0 6013.2 6027.0 5037.5 5195.5 5533.1 5547.7 5886.4 5254.2 5564.7 5678.0 5764.5 6148.1 3332.5 3947.6 3968.8 3972.4 3985.1 5150.0 5165.6 5976.8 5993.8 6005.0 5581.8 5599.0 5791.9 5830.1 5854.6 5473.5 5776.2 5782.9 5786.0 5807.4 4927.2 4965.0 5245.6 5286.7 5687.9 4453.0 4847.8 5528.8 5648.9 5833.4 4463.0 5674.6 5682.4 5692.6 5703.9 08:21:49 INFO - PROCESS | 2537 | 08:21:49 INFO - PROCESS | 2537 | [#5] yui.html Cycles:180 Average:25053.47 Median:3603.41 stddev:39433.43 (1094.3%) stddev-sans-first:39501.63 08:21:49 INFO - PROCESS | 2537 | Values: 692.8 745.1 770.8 799.3 845.4 16605.5 17362.0 17370.7 17379.4 17439.8 15342.7 15392.8 15414.8 15444.1 15455.4 712.4 728.0 730.1 876.0 903.3 893.0 912.6 912.9 913.2 914.1 972.7 974.8 975.2 976.8 977.2 966.2 966.8 967.6 968.2 969.5 719.8 720.1 721.3 722.0 723.7 15522.8 15660.3 15662.8 15668.8 15692.1 13560.8 13834.5 13892.9 13908.4 14105.6 13393.3 13634.5 13935.0 13997.8 14348.9 4735.6 4739.1 4788.3 4844.9 4946.8 119513.0 121837.0 122083.4 122343.0 123453.4 11254.4 11320.6 11486.2 11620.2 11669.2 21663.5 21836.9 21886.7 21907.0 22403.2 8201.3 8249.0 8264.3 8273.6 8295.1 4508.1 4568.4 4695.4 4781.1 4842.7 3056.7 3500.5 3563.5 3592.3 3614.5 667.1 668.8 671.8 672.9 673.1 608.0 609.9 617.9 630.5 639.9 8857.8 9218.5 9466.5 9489.8 9621.6 622.3 630.9 633.7 647.9 662.4 3884.7 4336.7 4570.9 4578.7 4581.3 96900.0 129314.0 140929.0 153736.0 164425.0 90797.0 92673.0 128326.4 144508.0 150187.2 101477.0 119683.0 124435.0 126287.0 129674.0 8623.7 8800.7 8830.2 8831.8 9019.5 61136.4 63026.1 67871.7 68116.7 68885.0 68238.0 70213.6 75583.2 85616.6 96899.0 13402.9 13539.0 13858.7 14008.4 14178.3 14214.4 15074.5 15136.2 15226.6 16336.5 14461.7 14550.9 14741.6 14759.9 14852.0 13332.7 13628.9 14501.6 14565.4 15112.3 13761.9 14018.9 14772.3 15035.3 15185.2 11441.4 11593.8 11717.8 11926.5 12021.2 11681.4 11785.4 11796.9 12023.8 12037.2 08:21:49 INFO - PROCESS | 2537 | -------- Summary: end -------- 08:21:49 INFO - PROCESS | 2537 | 08:21:50 INFO - TEST-INFO | 2537: exit 0 08:21:50 INFO - TEST-OK | dromaeo_css | took 1115047ms 08:21:50 INFO - TEST-START | kraken 08:21:50 INFO - operating with platform_type : mac_ 08:21:50 INFO - Initialising browser for kraken test... 08:21:50 INFO - TEST-INFO | started process 2539 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/tmpeEZugl/profile http://localhost:56068/getInfo.html) 08:21:52 INFO - PROCESS | 2539 | __metrics Screen width/height:1600/1200 08:21:52 INFO - PROCESS | 2539 | colorDepth:24 08:21:52 INFO - PROCESS | 2539 | Browser inner width/height: 1024/689 08:21:52 INFO - PROCESS | 2539 | __metrics 08:21:52 INFO - PROCESS | 2539 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:21:52 INFO - PROCESS | 2539 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:21:53 INFO - TEST-INFO | 2539: exit 0 08:21:53 INFO - Browser initialized. 08:21:53 INFO - Running cycle 1/1 for kraken test... 08:21:53 INFO - TEST-INFO | started process 2541 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/tmpeEZugl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:22:05 INFO - PROCESS | 2541 | RSS: Main: 168378368 08:22:05 INFO - PROCESS | 2541 | 08:22:08 INFO - PROCESS | 2541 | Cycle 1(1): loaded http://localhost:56068/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56068/tests/kraken/driver.html?testName=audio-beat-detection) 08:22:09 INFO - PROCESS | 2541 | RSS: Main: 218243072 08:22:09 INFO - PROCESS | 2541 | 08:22:11 INFO - PROCESS | 2541 | Cycle 1(1): loaded http://localhost:56068/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56068/tests/kraken/driver.html?testName=audio-dft) 08:22:12 INFO - PROCESS | 2541 | RSS: Main: 228794368 08:22:12 INFO - PROCESS | 2541 | 08:22:15 INFO - PROCESS | 2541 | Cycle 1(1): loaded http://localhost:56068/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56068/tests/kraken/driver.html?testName=audio-fft) 08:22:16 INFO - PROCESS | 2541 | RSS: Main: 326414336 08:22:16 INFO - PROCESS | 2541 | 08:22:18 INFO - PROCESS | 2541 | Cycle 1(1): loaded http://localhost:56068/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56068/tests/kraken/driver.html?testName=audio-oscillator) 08:22:18 INFO - PROCESS | 2541 | RSS: Main: 227696640 08:22:18 INFO - PROCESS | 2541 | 08:22:20 INFO - PROCESS | 2541 | Cycle 1(1): loaded http://localhost:56068/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56068/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:22:21 INFO - PROCESS | 2541 | RSS: Main: 229404672 08:22:21 INFO - PROCESS | 2541 | 08:22:24 INFO - PROCESS | 2541 | Cycle 1(1): loaded http://localhost:56068/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56068/tests/kraken/driver.html?testName=imaging-darkroom) 08:22:24 INFO - PROCESS | 2541 | RSS: Main: 267202560 08:22:24 INFO - PROCESS | 2541 | 08:22:27 INFO - PROCESS | 2541 | Cycle 1(1): loaded http://localhost:56068/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56068/tests/kraken/driver.html?testName=imaging-desaturate) 08:22:28 INFO - PROCESS | 2541 | RSS: Main: 267042816 08:22:28 INFO - PROCESS | 2541 | 08:22:30 INFO - PROCESS | 2541 | Cycle 1(1): loaded http://localhost:56068/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56068/tests/kraken/driver.html?testName=json-parse-financial) 08:22:31 INFO - PROCESS | 2541 | RSS: Main: 264781824 08:22:31 INFO - PROCESS | 2541 | 08:22:32 INFO - PROCESS | 2541 | Cycle 1(1): loaded http://localhost:56068/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56068/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:22:33 INFO - PROCESS | 2541 | RSS: Main: 242950144 08:22:33 INFO - PROCESS | 2541 | 08:22:35 INFO - PROCESS | 2541 | Cycle 1(1): loaded http://localhost:56068/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56068/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:22:36 INFO - PROCESS | 2541 | RSS: Main: 244322304 08:22:36 INFO - PROCESS | 2541 | 08:22:38 INFO - PROCESS | 2541 | Cycle 1(1): loaded http://localhost:56068/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56068/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:22:38 INFO - PROCESS | 2541 | RSS: Main: 251953152 08:22:38 INFO - PROCESS | 2541 | 08:22:41 INFO - PROCESS | 2541 | Cycle 1(1): loaded http://localhost:56068/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56068/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:22:42 INFO - PROCESS | 2541 | RSS: Main: 265691136 08:22:42 INFO - PROCESS | 2541 | 08:22:45 INFO - PROCESS | 2541 | Cycle 1(1): loaded http://localhost:56068/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56068/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:22:45 INFO - PROCESS | 2541 | RSS: Main: 259313664 08:22:45 INFO - PROCESS | 2541 | 08:22:47 INFO - PROCESS | 2541 | Cycle 1(1): loaded http://localhost:56068/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56068/tests/kraken/driver.html?testName=ai-astar) 08:22:47 INFO - PROCESS | 2541 | RSS: Main: 303050752 08:22:47 INFO - PROCESS | 2541 | 08:22:47 INFO - PROCESS | 2541 | __start_tp_report 08:22:47 INFO - PROCESS | 2541 | _x_x_mozilla_page_load 08:22:47 INFO - PROCESS | 2541 | _x_x_mozilla_page_load_details 08:22:47 INFO - PROCESS | 2541 | |i|pagename|runs| 08:22:47 INFO - PROCESS | 2541 | |0;ai-astar;115;114;113;111;114;114;111;113;114;113 08:22:47 INFO - PROCESS | 2541 | |1;audio-beat-detection;144;106;107;139;127;137;108;107;140;106 08:22:47 INFO - PROCESS | 2541 | |2;audio-dft;186;186;185;185;185;184;181;184;184;201 08:22:47 INFO - PROCESS | 2541 | |3;audio-fft;78;84;58;90;60;59;58;58;79;57 08:22:47 INFO - PROCESS | 2541 | |4;audio-oscillator;84;69;94;69;69;71;122;98;72;90 08:22:47 INFO - PROCESS | 2541 | |5;imaging-gaussian-blur;91;88;91;89;92;88;91;88;91;88 08:22:47 INFO - PROCESS | 2541 | |6;imaging-darkroom;95;95;95;96;95;95;95;96;94;94 08:22:47 INFO - PROCESS | 2541 | |7;imaging-desaturate;86;83;86;87;84;85;83;85;86;84 08:22:47 INFO - PROCESS | 2541 | |8;json-parse-financial;54;52;53;52;57;55;56;56;55;55 08:22:47 INFO - PROCESS | 2541 | |9;json-stringify-tinderbox;71;65;78;72;72;71;68;70;72;72 08:22:47 INFO - PROCESS | 2541 | |10;stanford-crypto-aes;87;101;96;102;75;83;81;84;82;88 08:22:47 INFO - PROCESS | 2541 | |11;stanford-crypto-ccm;137;144;144;132;132;135;140;128;199;148 08:22:47 INFO - PROCESS | 2541 | |12;stanford-crypto-pbkdf2;160;161;160;157;161;161;156;161;160;160 08:22:47 INFO - PROCESS | 2541 | |13;stanford-crypto-sha256-iterative;63;64;64;65;65;65;65;65;67;76 08:22:47 INFO - PROCESS | 2541 | __end_tp_report 08:22:47 INFO - PROCESS | 2541 | __start_cc_report 08:22:47 INFO - PROCESS | 2541 | _x_x_mozilla_cycle_collect,1967 08:22:47 INFO - PROCESS | 2541 | __end_cc_report 08:22:47 INFO - PROCESS | 2541 | __startTimestamp1479486167720__endTimestamp 08:22:47 INFO - PROCESS | 2541 | 08:22:47 INFO - PROCESS | 2541 | ------- Summary: start ------- 08:22:47 INFO - PROCESS | 2541 | Number of tests: 14 08:22:47 INFO - PROCESS | 2541 | 08:22:47 INFO - PROCESS | 2541 | [#0] ai-astar Cycles:10 Average:113.20 Median:114.00 stddev:1.32 (1.2%) stddev-sans-first:1.22 08:22:47 INFO - PROCESS | 2541 | Values: 115.0 114.0 113.0 111.0 114.0 114.0 111.0 113.0 114.0 113.0 08:22:47 INFO - PROCESS | 2541 | 08:22:47 INFO - PROCESS | 2541 | [#1] audio-beat-detection Cycles:10 Average:122.10 Median:132.00 stddev:16.68 (12.6%) stddev-sans-first:15.70 08:22:47 INFO - PROCESS | 2541 | Values: 144.0 106.0 107.0 139.0 127.0 137.0 108.0 107.0 140.0 106.0 08:22:47 INFO - PROCESS | 2541 | 08:22:47 INFO - PROCESS | 2541 | [#2] audio-dft Cycles:10 Average:186.10 Median:185.00 stddev:5.43 (2.9%) stddev-sans-first:5.75 08:22:47 INFO - PROCESS | 2541 | Values: 186.0 186.0 185.0 185.0 185.0 184.0 181.0 184.0 184.0 201.0 08:22:47 INFO - PROCESS | 2541 | 08:22:47 INFO - PROCESS | 2541 | [#3] audio-fft Cycles:10 Average:68.10 Median:69.00 stddev:13.03 (18.9%) stddev-sans-first:13.31 08:22:47 INFO - PROCESS | 2541 | Values: 78.0 84.0 58.0 90.0 60.0 59.0 58.0 58.0 79.0 57.0 08:22:47 INFO - PROCESS | 2541 | 08:22:47 INFO - PROCESS | 2541 | [#4] audio-oscillator Cycles:10 Average:83.80 Median:78.00 stddev:17.52 (22.5%) stddev-sans-first:18.59 08:22:47 INFO - PROCESS | 2541 | Values: 84.0 69.0 94.0 69.0 69.0 71.0 122.0 98.0 72.0 90.0 08:22:47 INFO - PROCESS | 2541 | 08:22:47 INFO - PROCESS | 2541 | [#5] imaging-gaussian-blur Cycles:10 Average:89.70 Median:91.00 stddev:1.64 (1.8%) stddev-sans-first:1.67 08:22:47 INFO - PROCESS | 2541 | Values: 91.0 88.0 91.0 89.0 92.0 88.0 91.0 88.0 91.0 88.0 08:22:47 INFO - PROCESS | 2541 | 08:22:47 INFO - PROCESS | 2541 | [#6] imaging-darkroom Cycles:10 Average:95.00 Median:95.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 08:22:47 INFO - PROCESS | 2541 | Values: 95.0 95.0 95.0 96.0 95.0 95.0 95.0 96.0 94.0 94.0 08:22:47 INFO - PROCESS | 2541 | 08:22:47 INFO - PROCESS | 2541 | [#7] imaging-desaturate Cycles:10 Average:84.90 Median:85.50 stddev:1.37 (1.6%) stddev-sans-first:1.39 08:22:47 INFO - PROCESS | 2541 | Values: 86.0 83.0 86.0 87.0 84.0 85.0 83.0 85.0 86.0 84.0 08:22:47 INFO - PROCESS | 2541 | 08:22:47 INFO - PROCESS | 2541 | [#8] json-parse-financial Cycles:10 Average:54.50 Median:55.00 stddev:1.72 (3.1%) stddev-sans-first:1.81 08:22:47 INFO - PROCESS | 2541 | Values: 54.0 52.0 53.0 52.0 57.0 55.0 56.0 56.0 55.0 55.0 08:22:47 INFO - PROCESS | 2541 | 08:22:47 INFO - PROCESS | 2541 | [#9] json-stringify-tinderbox Cycles:10 Average:71.10 Median:72.00 stddev:3.31 (4.6%) stddev-sans-first:3.52 08:22:47 INFO - PROCESS | 2541 | Values: 71.0 65.0 78.0 72.0 72.0 71.0 68.0 70.0 72.0 72.0 08:22:47 INFO - PROCESS | 2541 | 08:22:47 INFO - PROCESS | 2541 | [#10] stanford-crypto-aes Cycles:10 Average:87.90 Median:83.50 stddev:8.97 (10.7%) stddev-sans-first:9.51 08:22:47 INFO - PROCESS | 2541 | Values: 87.0 101.0 96.0 102.0 75.0 83.0 81.0 84.0 82.0 88.0 08:22:47 INFO - PROCESS | 2541 | 08:22:47 INFO - PROCESS | 2541 | [#11] stanford-crypto-ccm Cycles:10 Average:143.90 Median:142.00 stddev:20.36 (14.3%) stddev-sans-first:21.44 08:22:47 INFO - PROCESS | 2541 | Values: 137.0 144.0 144.0 132.0 132.0 135.0 140.0 128.0 199.0 148.0 08:22:47 INFO - PROCESS | 2541 | 08:22:47 INFO - PROCESS | 2541 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:159.70 Median:160.50 stddev:1.77 (1.1%) stddev-sans-first:1.87 08:22:47 INFO - PROCESS | 2541 | Values: 160.0 161.0 160.0 157.0 161.0 161.0 156.0 161.0 160.0 160.0 08:22:47 INFO - PROCESS | 2541 | 08:22:47 INFO - PROCESS | 2541 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.90 Median:65.00 stddev:3.70 (5.7%) stddev-sans-first:3.77 08:22:47 INFO - PROCESS | 2541 | Values: 63.0 64.0 64.0 65.0 65.0 65.0 65.0 65.0 67.0 76.0 08:22:47 INFO - PROCESS | 2541 | -------- Summary: end -------- 08:22:47 INFO - PROCESS | 2541 | 08:22:48 INFO - TEST-INFO | 2541: exit 0 08:22:48 INFO - TEST-OK | kraken | took 58173ms 08:22:48 INFO - SUITE-END | took 1173s 08:22:48 INFO - Completed test suite (00:19:33) 08:22:48 INFO - javascript benchmark 08:22:48 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2717.497263006344, 3422.6748458896404, 3457.619661837199, 3461.3942560051983, 3516.156122530592, 5701.657900525966, 5802.39074897136, 5823.417658234174, 5852.444017818311, 5871.500922421594, 5896.911546326798, 5982.670953097578, 5990.6106103103375, 5995.490383317412, 6001.66990815505, 5266.947330526694, 5302.151655735083, 5331.946680533203, 5358.732063396815, 5381.81163659272, 5231.0, 5248.212768084799, 5378.704171270579, 5381.515663590296, 5395.190721391803, 5276.657017293864, 5292.947070529284, 5312.548433383147, 5312.973435132838, 5334.733263336818, 5317.787288508455, 5326.36083669962, 5331.786728530854, 5382.4886635769535, 5400.567954563626, 5206.531412172884, 5306.495882891119, 5311.282976798121, 5337.2794672719465, 5343.064963631349, 5768.567357448208, 5785.739641716133, 5791.102379131242, 5796.565257605655, 5809.3609702932645, 6188.597741146811, 6214.471769899564, 6223.346548612376, 6225.28409232935, 6234.563580549363, 6209.192804935329, 6253.656048917308, 6271.749130034793, 6274.341194174592, 6305.621662700252, 5812.622179558315, 5821.621594596338, 5861.941380586225, 5885.411458854166, 5984.311804142536, 6229.688515574203, 6261.935470969946, 6267.811965641038, 6279.120923070774, 6288.622682639057, 6207.410296021871, 6232.065190221412, 6234.908890944067, 6252.718627661766, 6254.6247225166635, 6161.8459538511, 6173.9073913891325, 6223.439890409885, 6230.314665386803, 6240.687965601793, 6228.4705800007505, 6233.781817636361, 6247.062940558862, 6257.217847769029, 6270.341614130451, 6178.320384757673, 6181.134641150243, 6231.594946328474, 6263.31103578606, 6271.623702577769, 5018.648694591344, 5057.848264552069, 5065.088284108896, 5123.359580052494, 5124.077666020152, 3812.866549670748, 4489.438820147481, 4566.520515345841, 4582.610478109332, 4600.194965880957, 5970.0746384310505, 5990.0, 5991.281046274475, 6001.309849367379, 6005.969970150121, 6151.96924015377, 6225.564210505221, 6240.781572644935, 6248.218972628421, 6260.46786023184, 5879.147523609053, 5958.851028724316, 5968.0, 6023.3073196582955, 6030.969845150747, 5507.669539827623, 5549.361823390362, 5550.139728342114, 5553.0, 5573.60984731073, 6420.711068001951, 6421.004744264646, 6440.388163124496, 6443.871122577616, 6453.838654033687, 6145.279321789906, 6156.969215153896, 6188.566800323934, 6199.938000620116, 6255.906161407492, 6463.0, 6473.805785826433, 6479.4816414685765, 6483.2544257411, 6483.578567393104, 6208.348123446973, 6236.532260080421, 6240.188775459162, 6253.812385628438, 6264.655443950626, 6150.231221097363, 6154.076888466766, 6166.167567378347, 6218.81343559694, 6260.0, 5002.699838009731, 6271.310155882758, 6272.059191121369, 6276.717547710456, 6278.654673992792, 6066.817995460143, 6273.96863015682, 6281.780137695158, 6293.276273228455, 6296.496280297657, 5008.098542262428, 5895.616784908967, 6049.969750151221, 6189.473894718999, 6209.410106039919, 6151.50787936955, 6261.186045814015, 6261.467775239155, 6266.342034086355, 6271.4669253114, 6108.5723999319625, 6127.632342059491, 6155.2921414037955, 6167.136600875791, 6186.226721659793], "value": 5785.185019965274, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [474.7412660100246, 479.9448063472702, 529.8622358186873, 532.1273112096166, 550.5100460590075, 31653.841730791344, 41827.16345673085, 43438.262469501184, 43547.0, 45558.0, 44572.0, 44829.0, 44843.55156448435, 44864.878378040485, 44971.0, 9999.150072243869, 10107.54516046776, 10130.898691013106, 10135.290529662927, 10151.187904967623, 9283.721488355326, 9516.80966380672, 9522.80954380912, 9627.663031793889, 9637.421754694706, 11007.899210078967, 12125.454354554022, 12166.756664866698, 12451.315177665225, 12481.438335274888, 12328.568500102498, 12438.253704777651, 12438.875611243953, 12439.129260951737, 12439.93780031103, 12248.938755306255, 12352.567660131943, 12362.505499779998, 12369.876301237053, 12371.505139794397, 42056.0, 42313.365299520534, 42338.364924526155, 42430.93922651927, 42568.0, 3383.729301655886, 3521.3133438979407, 3540.805255710935, 3588.820558972041, 3610.7472476926628, 31900.521492177402, 32135.19662008445, 32193.83903080493, 32256.677433225836, 32600.184995375304, 3044.1932887784756, 3185.8725650973934, 3257.9348413031844, 3386.4243078676686, 3419.6238413774463, 513935.0, 545021.0, 545498.0, 545826.0, 547239.2638036824, 60291.39708602946, 60626.0, 60654.78690426255, 60661.0, 60873.0, 35339.0, 36064.81967590145, 36118.458223126676, 37039.629603704154, 37151.25697486035, 17853.553661158574, 18072.638547229246, 18136.274549017893, 18151.8201316914, 18188.63622727538, 13643.044986850826, 13854.792178117335, 13861.792073118912, 13868.306584670727, 13898.0, 17119.91440042792, 17392.0, 19153.079575148764, 19492.902535487232, 19751.901240493706, 6874.518783685194, 7551.660175292125, 7614.657340419694, 8185.386096042821, 8245.711400100967, 886.8802711633847, 1028.8713910761155, 1032.8140934631838, 1034.374203606822, 1052.2007367401675, 25644.0, 28727.850885964745, 28830.567541486893, 29434.411311774074, 29537.556936645968, 1091.2252300866473, 1100.834874768791, 1114.6934592986831, 1160.7620437810228, 1261.344101067458, 15576.92211538935, 15605.531834044998, 15623.531294061197, 15807.683846323009, 16035.877488575921, 628267.0, 633064.0, 634672.0, 635464.0, 639678.0, 60428.09357859636, 61115.69442152761, 61150.77698446095, 61154.0, 61269.0, 1403.305363844883, 1423.1247782613618, 1453.4985430026625, 1454.040333379964, 1459.569427019032, 29472.410551788416, 30247.941322054503, 30275.848620756755, 30603.387932241676, 30927.536086959164, 50981.490185097675, 51765.0, 51898.22152667789, 51907.74046129896, 51935.48064519458, 623458.0, 624318.0, 624778.0, 625988.0, 628788.0, 23919.641205382282, 24505.50988980246, 24623.50752984966, 27093.72906270912, 27499.45001100007, 47375.0, 48961.75519122381, 49449.505504945926, 51683.224751628004, 52118.47881521288, 39500.40749388819, 40176.0, 40968.79515602403, 41830.49017058986, 42430.75692430856, 33028.33943321072, 40090.599094009856, 41448.37827432651, 41758.37362439626, 42426.575734242266, 39709.80145099256, 40059.399109012804, 40375.0, 40647.796761016005, 40664.0, 34084.0, 35326.11684707903, 35528.64471355253, 36148.09629759277, 36233.0, 25101.49797004013, 26047.869760651833, 26420.735792642598, 26513.469730605666, 26721.8663906687], "value": 20721.839236314867, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [333.23522515826176, 371.8902923637527, 378.7102866307275, 381.50213970768135, 403.9979800100999, 20738.481537961557, 21406.929653517338, 22025.449363765892, 22201.778902160375, 22218.0, 21066.36800895972, 21464.785352146475, 22072.8896355518, 22196.556068878614, 22432.214872479468, 5187.818426355078, 5920.615160014585, 6088.3302836688035, 6284.937150628503, 6338.936610633881, 4101.815418306169, 4187.246295666779, 6260.937390626081, 6297.716602752887, 6310.368963103676, 5909.527237820964, 6156.0, 6205.069239614071, 6254.093156492306, 6261.592996455239, 6004.279486461631, 6221.035739460391, 6228.657423841687, 6284.402981716729, 6316.115743795872, 5272.657277276965, 5978.222831031938, 6026.879462410728, 6088.817335479898, 6140.017597184474, 25704.357391065187, 26639.866800666066, 26776.73223267762, 27411.31471713203, 27884.0, 1781.536800431897, 2241.6189247827747, 2247.5280191159877, 2251.1445650652813, 2869.741723244897, 9754.512274386323, 10294.794104117951, 10475.790484190273, 10625.46872656364, 10734.624288149955, 1894.4695485264149, 1983.5041239690077, 1995.9700604490945, 2011.9597608047757, 2034.5727397246596, 128450.0, 128834.0, 139161.3041934794, 143308.99999999895, 145631.0, 11491.482883270273, 11666.241694290014, 11695.830416958235, 11768.470418831002, 11945.581904633296, 10995.505202265917, 15141.545753627408, 15216.315265813066, 15217.239138043053, 15222.086674799548, 8880.822383552293, 9157.358984871129, 9185.448873067637, 9239.861402078974, 9266.490343031199, 9183.770405739777, 9197.862032069525, 9207.493587852598, 9356.532173391439, 9370.437773733598, 3943.132510847609, 4415.1611193873705, 4611.400517932714, 4611.631069514431, 4690.577847993697, 1655.6357601327688, 1773.379317238956, 1834.0958822359366, 1882.164889232987, 1957.9510512237023, 402.58332625732226, 936.6674830435234, 938.8685584018243, 953.713885834247, 957.3633533700012, 11379.487923043484, 11858.16992810495, 11989.22070065443, 12105.878941210474, 12303.876961230451, 394.16043826649627, 422.51410877491134, 430.32009425108254, 433.3607928305724, 459.53127809634657, 5597.664140151523, 6109.786157484556, 6160.322364539897, 6267.122602835607, 6298.779542715983, 685945.0, 688000.0, 694814.5259273672, 695284.0, 698559.0, 143853.0, 153004.0, 153707.0, 154760.0, 157414.0, 841.6423020216383, 1016.8118898003893, 1039.781645854364, 1039.927205095636, 1041.432419331451, 16825.158742063093, 19637.98440014857, 20901.686474703194, 21398.144074236858, 21920.89039554792, 57657.711711441174, 58879.0, 59024.0, 59115.70442147762, 60015.39984600273, 75465.62267188629, 89265.0, 90185.54907225422, 90466.54766726124, 91227.6315855276, 7989.960050199712, 8144.104148543537, 8144.918550814416, 8448.23965843077, 8681.522516261413, 9227.35408521424, 13368.059027014042, 13447.663808404868, 13505.189688618715, 13603.0, 9968.700938971842, 11390.724540041558, 11405.258658187191, 11491.138164637518, 11613.5354585819, 11154.16343774204, 11281.266717663326, 11319.773604527698, 11548.48031838586, 11729.061675066145, 9669.694591230094, 10799.820152869997, 10878.40168790724, 11257.93049660281, 11763.294202347886, 7628.618569071636, 7833.92166078332, 8092.150324215872, 8141.959290203511, 8422.494650321001, 8472.957635211784, 11378.374189419661, 11432.771344573228, 11794.233374830608, 11898.821517677416], "value": 9366.399544093103, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1395.1838174667819, 1554.82119556251, 1575.9290831912563, 1779.864163004395, 1912.5314297996983, 8340.999080110387, 8875.334349923749, 9179.082091790817, 9456.338056336042, 9458.38520496169, 9250.12123848235, 9379.859302110473, 9384.577694003769, 9386.48374339411, 9390.436573805577, 2436.0621160853066, 2451.8651474168914, 2463.519613675334, 2465.0, 2466.3464181991785, 2389.8730130184063, 2438.7317395086525, 2442.584760590695, 2447.5349683560157, 2456.545539075277, 2408.1932552594903, 2414.9034038638433, 2430.562498750224, 2435.0625009371342, 2444.559979203743, 2376.857388556692, 2378.4529558201625, 2382.4401265702545, 2383.261189031397, 2414.9034038638433, 2362.5156842847173, 2394.616861302183, 2398.5922393193023, 2400.063975049732, 2402.4354276744953, 9153.725388238363, 9253.444793312423, 9316.487593182372, 9339.76650583734, 9451.716448506488, 5500.064988951888, 5741.138829175616, 5746.080627099644, 5753.539716822645, 5761.53907687384, 3729.3287208302495, 3735.6077611850637, 3791.905202369935, 3827.5215598050245, 3833.195029043904, 942.2603256443728, 1026.9948650256702, 1092.9234953553255, 1095.0801326885376, 1122.6688127002471, 12307.20003199789, 12374.948129409011, 12437.440315185839, 12469.812952805714, 12498.937505312506, 4596.5863072323655, 4628.3288923105965, 4819.566239038503, 4821.2045012572535, 4822.541858523435, 9617.56720947559, 9653.372530785477, 9666.806663866824, 9667.951660241653, 9673.854892176623, 5501.119820828648, 5502.257195278667, 5512.834614961557, 5513.61404701675, 5519.31008623922, 4473.261911784584, 4654.6741728078705, 4661.743604101807, 5133.281340612317, 5151.7681704323395, 3915.4909861718365, 3960.683145348365, 4017.0961533654695, 4059.3910913363234, 4064.776437295918, 1146.1060372909144, 1160.785254727878, 1177.770334784726, 1180.3095189314176, 1194.7311854832594, 915.0131781789275, 922.6032805893396, 932.216937772261, 942.8161508505775, 988.4365911430559, 5833.79581714638, 5887.081874878282, 6007.36922623127, 6228.844278893064, 6283.246010478681, 383.61255132316006, 435.5231022030871, 438.48478038304864, 443.0872402850139, 444.45776153093175, 4011.157656892027, 4061.147159096564, 4065.2682517146604, 4074.3684728867074, 4132.772697501606, 11155.944220278847, 11438.714032149262, 11577.189596728149, 11591.362475063952, 11593.0, 11501.022413094814, 11501.712457188638, 11508.0, 11510.827337589943, 11529.48117334722, 8665.090165532529, 8679.913200868037, 8706.738797836075, 8712.95643521791, 8766.649334026695, 5724.227229323988, 5921.970390148022, 5985.850353741192, 6101.359357267423, 6122.142899993915, 8225.218604232588, 10532.52603632823, 10535.525901334306, 10653.840192396965, 10700.0, 10963.50664220096, 11100.389478578756, 11110.055645270242, 11116.61091861781, 11118.054965328038, 7216.819579510344, 7263.491555591269, 7596.202398748273, 7979.202079791974, 8153.79615509617, 8639.092895245909, 9040.321975851706, 9054.139856713602, 9060.773480662772, 9074.047225041275, 7783.766487005399, 7864.134945156143, 7895.0, 7961.72133975308, 8142.633581488965, 7520.962395187989, 7648.273414025659, 7648.885266720892, 7651.502652327581, 7696.7306144284685, 7390.706626340261, 7544.170141284564, 7546.584937828472, 7548.358389536951, 7549.7357592483995, 5081.517255860687, 5091.821786237464, 5110.770015349244, 5241.9737901310245, 5362.008028514581, 4480.260756975063, 5123.538881500683, 5173.275741396283, 5272.815451459181, 5401.783928642967], "value": 4653.042601648843, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [244.95713250181214, 295.52273078977447, 306.61213564840466, 313.2622673603663, 320.7434052757793, 4560.931586026212, 5611.523020543254, 5613.267177327072, 5638.548916086714, 5643.61731375813, 5153.613478989072, 5166.819161329354, 5307.558523079379, 5454.618176727621, 5478.616496845212, 4114.341705327133, 4146.668266538685, 4175.3736939459095, 4194.5805419457965, 4238.97880510597, 4179.456670632813, 4210.6841986851105, 4213.705040647141, 4232.555581663927, 4266.658667306623, 3485.860565577374, 3938.6652134568453, 4119.691023173259, 4128.174365126972, 4166.729162604436, 3917.902987163599, 4162.271602469556, 4164.916701665949, 4184.765494179209, 4197.349410841324, 3426.623071462137, 3551.2897420516, 3628.727845411605, 3637.199816040467, 3639.290338384016, 4327.317063420699, 4796.04079184161, 5066.442691303953, 5286.444923283077, 5382.81995791705, 4470.094438614894, 4694.530546945312, 4712.929306060412, 4723.385959825235, 4819.2530157825595, 4771.37972063633, 4793.137235297645, 5706.71466426677, 5714.0, 5805.332386775491, 3804.619538046201, 3916.295066887959, 3920.0395903003946, 3927.253821773854, 3929.25344184604, 4460.799264033094, 5151.613628977804, 6071.0, 6104.229773365719, 6110.403078691538, 3941.2117576484816, 3984.302747019318, 3986.202759448122, 3987.840486380512, 4014.0366312085475, 4067.572904845008, 4069.7994091743008, 4079.2453396121814, 4081.8163182656854, 4090.734102283342, 4109.185208132439, 4626.653001024936, 4663.953360466352, 4693.812247510062, 4756.262779269219, 5561.63849349791, 5739.253896993448, 5746.827595172152, 5766.0, 5831.300243970751, 1288.0983311681673, 1333.8266025416633, 1344.3076815440056, 1353.9661508462368, 1358.9536057235873, 866.5190819095336, 880.6565439478609, 890.6838072484304, 964.1033838530094, 979.8628192053118, 5123.899201327438, 5279.8880447821175, 5371.650842695212, 5391.514763671288, 5517.5585953123655, 4998.8257456515375, 5996.13056106862, 5996.820095397058, 6013.9699301503215, 6036.879262414815, 5251.133562962068, 5625.1999360204345, 5625.971870140705, 5649.745761440663, 5662.292213473316, 3139.5290706393766, 3153.8265395403473, 3165.936681266408, 3177.601855183712, 3180.316232010083, 5187.974060129675, 5826.475617194473, 6179.3202747697715, 6249.562530622904, 6298.811035668938, 5788.971055144697, 6054.212952316171, 6097.146399504073, 6108.938910610926, 6139.693015349215, 5137.6660517066275, 5799.681017543991, 5817.389174136824, 6156.0, 6245.125682404467, 5085.949140508547, 5929.776333874797, 5939.0, 6013.226098300925, 6026.969865150646, 5037.496250374933, 5195.454477279983, 5533.087040638249, 5547.667139971614, 5886.411358864079, 5254.238135470259, 5564.693941833238, 5678.034734095297, 5764.452377024176, 6148.139260503623, 3332.450145725928, 3947.605239476144, 3968.8214030368126, 3972.3842804365368, 3985.103351745834, 5150.0, 5165.638405311743, 5976.760929562943, 5993.790217342546, 6004.958314173202, 5581.776728930798, 5599.0, 5791.8552036199435, 5830.125481177857, 5854.648721076749, 5473.534749546333, 5776.19133321327, 5782.884342313214, 5785.987452195933, 5807.419258074327, 4927.211646136601, 4964.982178653295, 5245.587029966258, 5286.744170942275, 5687.914681279867, 4453.020335526179, 4847.80608775645, 5528.806491772769, 5648.881669373985, 5833.387494313036, 4462.9916537558875, 5674.631148975303, 5682.37493875665, 5692.601517893873, 5703.914441283301], "value": 4199.443638873635, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [692.8233300508373, 745.1319213116719, 770.7803276066322, 799.292626025967, 845.3533047218882, 16605.501834944956, 17362.045087520182, 17370.739438908397, 17379.391721289758, 17439.825601743978, 15342.693146137071, 15392.846071539281, 15414.768778468331, 15444.073355598672, 15455.381784728597, 712.3553184368155, 727.9963600182018, 730.0618704964122, 876.0, 903.2909166304453, 893.0221407558745, 912.5847739278612, 912.8950170730351, 913.2420091324185, 914.0539541574472, 972.6838777397339, 974.814785190815, 975.1954637424153, 976.7606936300616, 977.1547611316196, 966.1787480641469, 966.7534778631474, 967.6371360739687, 968.2254196642693, 969.4910172159603, 719.7804669575777, 720.1034711783863, 721.3219573600799, 721.9747308844218, 723.6707298179316, 15522.844771552365, 15660.295286712126, 15662.765058524132, 15668.843311566852, 15692.13693246871, 13560.796588051187, 13834.515791947233, 13892.93053534736, 13908.4436622536, 14105.64735881601, 13393.263370514618, 13634.464232414704, 13935.0, 13997.790033149511, 14348.92825535876, 4735.644826638016, 4739.099571081483, 4788.257820037899, 4844.927326090112, 4946.802127914879, 119513.0, 121836.99999999911, 122083.3895830524, 122343.0, 123453.38273308666, 11254.381009044417, 11320.6037788677, 11486.19596628228, 11620.244684095507, 11669.183157179086, 21663.45841353979, 21836.89081554614, 21886.671699924515, 21907.014184361742, 22403.21588744386, 8201.30288925448, 8249.0, 8264.256216940385, 8273.627686754111, 8295.12901145371, 4508.143452743968, 4568.361738854369, 4695.366125573072, 4781.091592597395, 4842.685225460334, 3056.724894759482, 3500.5274287970797, 3563.465480177942, 3592.3354179476883, 3614.475900994341, 667.0576890576765, 668.8495088605025, 671.8051764988196, 672.8889733193968, 673.1417442760519, 607.9848003799852, 609.9024156134997, 617.8856911471393, 630.5271046714964, 639.859230969186, 8857.778555536035, 9218.53907304632, 9466.526673666289, 9489.762755931157, 9621.567029483691, 622.2564035976964, 630.8769789891019, 633.6736580660963, 647.9190101237346, 662.364130434788, 3884.6698030667144, 4336.6530677545725, 4570.908581828345, 4578.725276483422, 4581.335706322631, 96900.0309996891, 129314.0, 140929.0, 153736.0, 164425.0, 90797.0, 92673.0, 128326.35836820756, 144508.0, 150187.249063754, 101477.0, 119683.0, 124435.0, 126287.0, 129674.0, 8623.741287761126, 8800.691975780817, 8830.249428798625, 8831.823363532565, 9019.549022548978, 61136.38863611307, 63026.05460918182, 67871.66064169648, 68116.6594167026, 68885.0, 68238.0, 70213.64893175705, 75583.24416755982, 85616.57191714001, 96899.03100969183, 13402.865971340161, 13539.0, 13858.65353366174, 14008.369623366769, 14178.291085445893, 14214.431422743388, 15074.472393466176, 15136.167510786572, 15226.619334516725, 16336.509904702878, 14461.710765784415, 14550.85449145495, 14741.631459213606, 14759.852401475848, 14852.0, 13332.733345333232, 13628.909687225196, 14501.564953051426, 14565.41738330455, 15112.319945602254, 13761.931190344385, 14018.859811401755, 14772.33524491379, 15035.323410446776, 15185.164815934788, 11441.42792860337, 11593.82609260845, 11717.76564468723, 11926.463309150935, 12021.21862078997, 11681.357525335849, 11785.395818884512, 11796.94101529487, 12023.75952480963, 12037.217580857216], "value": 8111.851281752955, "unit": "score"}], "name": "dromaeo_css", "value": 7500.12855179477, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 114.0, 113.0, 111.0, 114.0, 114.0, 111.0, 113.0, 114.0, 113.0], "value": 113.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 106.0, 107.0, 139.0, 127.0, 137.0, 108.0, 107.0, 140.0, 106.0], "value": 122.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 186.0, 185.0, 185.0, 185.0, 184.0, 181.0, 184.0, 184.0, 201.0], "value": 186.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 84.0, 58.0, 90.0, 60.0, 59.0, 58.0, 58.0, 79.0, 57.0], "value": 68.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 69.0, 94.0, 69.0, 69.0, 71.0, 122.0, 98.0, 72.0, 90.0], "value": 83.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 88.0, 91.0, 89.0, 92.0, 88.0, 91.0, 88.0, 91.0, 88.0], "value": 89.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 96.0, 94.0, 94.0], "value": 95.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 83.0, 86.0, 87.0, 84.0, 85.0, 83.0, 85.0, 86.0, 84.0], "value": 84.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 52.0, 53.0, 52.0, 57.0, 55.0, 56.0, 56.0, 55.0, 55.0], "value": 54.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 65.0, 78.0, 72.0, 72.0, 71.0, 68.0, 70.0, 72.0, 72.0], "value": 71.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 101.0, 96.0, 102.0, 75.0, 83.0, 81.0, 84.0, 82.0, 88.0], "value": 87.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 144.0, 144.0, 132.0, 132.0, 135.0, 140.0, 128.0, 199.0, 148.0], "value": 143.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 160.0, 157.0, 161.0, 161.0, 156.0, 161.0, 160.0, 160.0], "value": 159.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 64.0, 65.0, 65.0, 65.0, 65.0, 65.0, 67.0, 76.0], "value": 65.9, "unit": "score"}], "name": "kraken", "value": 1425.9000000000003, "alertThreshold": 2.0}]} 08:22:48 INFO - Return code: 0 08:22:48 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:22:48 INFO - # TBPL SUCCESS # 08:22:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:22:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:22:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:22:49 INFO - 2016-11-18 08:22:49.012 screenresolution[2547:33650] starting screenresolution argv=screenresolution get 08:22:49 INFO - 2016-11-18 08:22:49.015 screenresolution[2547:33650] Display 0: 1600x1200x32@60 08:22:49 INFO - 2016-11-18 08:22:49.021 screenresolution[2548:33652] starting screenresolution argv=screenresolution list 08:22:49 INFO - Available Modes on Display 0 08:22:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:22:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:22:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:22:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:22:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:22:49 INFO - Intel Iris: 08:22:49 INFO - Chipset Model: Intel Iris 08:22:49 INFO - Type: GPU 08:22:49 INFO - Bus: Built-In 08:22:49 INFO - VRAM (Dynamic, Max): 1536 MB 08:22:49 INFO - Vendor: Intel (0x8086) 08:22:49 INFO - Device ID: 0x0a2e 08:22:49 INFO - Revision ID: 0x0009 08:22:49 INFO - Displays: 08:22:49 INFO - Display: 08:22:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:22:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:22:49 INFO - Display Serial Number: 1600x1200 60 08:22:49 INFO - Main Display: Yes 08:22:49 INFO - Mirror: Off 08:22:49 INFO - Online: Yes 08:22:49 INFO - Rotation: Supported 08:22:49 INFO - Return code: 0 08:22:49 INFO - Running post-action listener: _resource_record_post_action 08:22:49 INFO - [mozharness: 2016-11-18 16:22:49.259695Z] Finished run-tests step (success) 08:22:49 INFO - Running post-run listener: _resource_record_post_run 08:22:49 INFO - Total resource usage - Wall time: 1196s; CPU: 53.0%; Read bytes: 1531904; Write bytes: 489204736; Read time: 8; Write time: 1525 08:22:49 INFO - TinderboxPrint: CPU usage
53.4% 08:22:49 INFO - TinderboxPrint: I/O read bytes / time
1,531,904 / 8 08:22:49 INFO - TinderboxPrint: I/O write bytes / time
489,204,736 / 1,525 08:22:49 INFO - TinderboxPrint: CPU idle
2,232.5 (46.6%) 08:22:49 INFO - TinderboxPrint: CPU system
960.6 (20.1%) 08:22:49 INFO - TinderboxPrint: CPU user
1,592.6 (33.3%) 08:22:49 INFO - TinderboxPrint: Swap in / out
196,014,080 / 0 08:22:49 INFO - install - Wall time: 23s; CPU: 54.0%; Read bytes: 186077696; Write bytes: 185716736; Read time: 17854; Write time: 383 08:22:49 INFO - run-tests - Wall time: 1174s; CPU: 53.0%; Read bytes: 1531904; Write bytes: 293325824; Read time: 8; Write time: 1105 08:22:49 INFO - Running post-run listener: _upload_blobber_files 08:22:49 INFO - Blob upload gear active. 08:22:49 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:22:49 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 08:22:49 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', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:22:49 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 Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:22:50 INFO - (blobuploader) - INFO - Open directory for files ... 08:22:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:22:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:22:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:22:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:22:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:22:51 INFO - (blobuploader) - INFO - Done attempting. 08:22:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:22:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:22:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:22:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:22:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:22:52 INFO - (blobuploader) - INFO - Done attempting. 08:22:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:22:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:22:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:22:55 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:22:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:22:55 INFO - (blobuploader) - INFO - Done attempting. 08:22:55 INFO - (blobuploader) - INFO - Iteration through files over. 08:22:55 INFO - Return code: 0 08:22:55 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:22:55 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:22:55 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b2adb22770561ea341f3d1226279768e8d5146789d081114334c859daa1e92f193058aaa5d854e92588e6bf005f781f0de990aafac35f2138c6237b870f72cf1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c49298aa76636ff4c4d7f8d9c89aa3381634201a412ac30f2e73785024d5dfe728a4bac2455cb9633b2b2edd1f1080924d2e5b1e6cc0a88a6c5f2c409e9f17fc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b38dc77109673a9afd5e8d5589b03c18e4ae37435d54b64ffb14d4574cdb172721c7b08dc0888210e31273e86baf961e604cf2284eb549ebcf316c7d983cc660"} 08:22:55 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:22:55 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:22:55 INFO - Contents: 08:22:55 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b2adb22770561ea341f3d1226279768e8d5146789d081114334c859daa1e92f193058aaa5d854e92588e6bf005f781f0de990aafac35f2138c6237b870f72cf1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c49298aa76636ff4c4d7f8d9c89aa3381634201a412ac30f2e73785024d5dfe728a4bac2455cb9633b2b2edd1f1080924d2e5b1e6cc0a88a6c5f2c409e9f17fc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b38dc77109673a9afd5e8d5589b03c18e4ae37435d54b64ffb14d4574cdb172721c7b08dc0888210e31273e86baf961e604cf2284eb549ebcf316c7d983cc660"} 08:22:55 INFO - Running post-run listener: copy_logs_to_upload_dir 08:22:55 INFO - Copying logs to upload dir... 08:22:55 INFO - mkdir: /builds/slave/test/build/upload/logs 08:22:55 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1257.587253 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2016-11-18 08:22:55.466064) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-18 08:22:55.469491) ========= 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.Xtw0vsbFR1/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.9F2nxyEl2D/Listeners TMPDIR=/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b2adb22770561ea341f3d1226279768e8d5146789d081114334c859daa1e92f193058aaa5d854e92588e6bf005f781f0de990aafac35f2138c6237b870f72cf1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c49298aa76636ff4c4d7f8d9c89aa3381634201a412ac30f2e73785024d5dfe728a4bac2455cb9633b2b2edd1f1080924d2e5b1e6cc0a88a6c5f2c409e9f17fc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b38dc77109673a9afd5e8d5589b03c18e4ae37435d54b64ffb14d4574cdb172721c7b08dc0888210e31273e86baf961e604cf2284eb549ebcf316c7d983cc660"} build_url:https://queue.taskcluster.net/v1/task/FmRmLTisR-qnEO4mwOEHDg/artifacts/public/build/firefox-51.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011172 build_url: 'https://queue.taskcluster.net/v1/task/FmRmLTisR-qnEO4mwOEHDg/artifacts/public/build/firefox-51.0.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b2adb22770561ea341f3d1226279768e8d5146789d081114334c859daa1e92f193058aaa5d854e92588e6bf005f781f0de990aafac35f2138c6237b870f72cf1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c49298aa76636ff4c4d7f8d9c89aa3381634201a412ac30f2e73785024d5dfe728a4bac2455cb9633b2b2edd1f1080924d2e5b1e6cc0a88a6c5f2c409e9f17fc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b38dc77109673a9afd5e8d5589b03c18e4ae37435d54b64ffb14d4574cdb172721c7b08dc0888210e31273e86baf961e604cf2284eb549ebcf316c7d983cc660"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-18 08:22:55.502891) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-18 08:22:55.503225) ========= 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.Xtw0vsbFR1/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.9F2nxyEl2D/Listeners TMPDIR=/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/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.005338 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-18 08:22:55.552022) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-18 08:22:55.552447) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-18 08:22:55.552769) ========= ========= Total master_lag: 0.25 =========