builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0356 starttime: 1482322707.07 results: success (0) buildid: 20161221030552 builduid: 043ed76a6488460fae98b950c928f4e1 revision: b44bdfc5b686395e16bd760ccbf15e1d9093f19a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-21 04:18:27.072387) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-21 04:18:27.072919) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-21 04:18:27.073248) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-21 04:18:27.100361) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 04:18:27.100809) ========= 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.2IRsRbL2ew/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.2bRwBGBjyZ/Listeners TMPDIR=/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-12-21 04:18:27-- 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% 168M=0s 2016-12-21 04:18:27 (168 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.087715 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 04:18:27.213447) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 04:18:27.213842) ========= 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.2IRsRbL2ew/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.2bRwBGBjyZ/Listeners TMPDIR=/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/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.024296 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 04:18:27.262409) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-21 04:18:27.262803) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev b44bdfc5b686395e16bd760ccbf15e1d9093f19a --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 b44bdfc5b686395e16bd760ccbf15e1d9093f19a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.2IRsRbL2ew/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.2bRwBGBjyZ/Listeners TMPDIR=/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-12-21 04:18:27,356 truncating revision to first 12 chars 2016-12-21 04:18:27,356 Setting DEBUG logging. 2016-12-21 04:18:27,356 attempt 1/10 2016-12-21 04:18:27,356 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/b44bdfc5b686?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-21 04:18:28,594 unpacking tar archive at: mozilla-beta-b44bdfc5b686/testing/mozharness/ program finished with exit code 0 elapsedTime=1.516740 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-21 04:18:28.805883) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-21 04:18:28.806503) ========= script_repo_revision: b44bdfc5b686395e16bd760ccbf15e1d9093f19a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-21 04:18:28.807156) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-21 04:18:28.807633) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-21 04:18:28.829460) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2016-12-21 04:18:28.829957) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.2IRsRbL2ew/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.2bRwBGBjyZ/Listeners TMPDIR=/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/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 04:18:29 INFO - MultiFileLogger online at 20161221 04:18:29 in /builds/slave/test 04:18:29 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 04:18:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:18:29 INFO - {'append_to_log': False, 04:18:29 INFO - 'base_work_dir': '/builds/slave/test', 04:18:29 INFO - 'blob_upload_branch': 'Mozilla-Beta', 04:18:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:18:29 INFO - 'branch': 'Mozilla-Beta', 04:18:29 INFO - 'buildbot_json_path': 'buildprops.json', 04:18:29 INFO - 'config_files': ('talos/mac_config.py',), 04:18:29 INFO - 'default_actions': ('clobber', 04:18:29 INFO - 'read-buildbot-config', 04:18:29 INFO - 'download-and-extract', 04:18:29 INFO - 'populate-webroot', 04:18:29 INFO - 'create-virtualenv', 04:18:29 INFO - 'install', 04:18:29 INFO - 'run-tests'), 04:18:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:18:29 INFO - 'download_minidump_stackwalk': True, 04:18:29 INFO - 'download_symbols': 'ondemand', 04:18:29 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:18:29 INFO - 'tooltool.py': '/tools/tooltool.py', 04:18:29 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:18:29 INFO - '/tools/misc-python/virtualenv.py')}, 04:18:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:18:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:18:29 INFO - 'installer_path': 'installer.exe', 04:18:29 INFO - 'log_level': 'info', 04:18:29 INFO - 'log_name': 'talos', 04:18:29 INFO - 'log_to_console': True, 04:18:29 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:18:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:18:29 INFO - 'opt_config_files': (), 04:18:29 INFO - 'pip_index': False, 04:18:29 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:18:29 INFO - 'cmd': ('bash', 04:18:29 INFO - '-c', 04:18:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:18:29 INFO - 'enabled': True, 04:18:29 INFO - 'halt_on_failure': False, 04:18:29 INFO - 'name': 'check_screen_resolution'},), 04:18:29 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:18:29 INFO - 'cmd': ('bash', 04:18:29 INFO - '-c', 04:18:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:18:29 INFO - 'enabled': True, 04:18:29 INFO - 'halt_on_failure': False, 04:18:29 INFO - 'name': 'check_screen_resolution'},), 04:18:29 INFO - 'run_cmd_checks_enabled': True, 04:18:29 INFO - 'sps_profile': False, 04:18:29 INFO - 'sps_profile_interval': 0, 04:18:29 INFO - 'suite': 'dromaeojs-e10s', 04:18:29 INFO - 'system_bits': '32', 04:18:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:18:29 INFO - 'title': 't-yosemite-r7-0356', 04:18:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:18:29 INFO - 'use_talos_json': True, 04:18:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:18:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:18:29 INFO - 'work_dir': 'build'} 04:18:29 INFO - [mozharness: 2016-12-21 12:18:29.038074Z] Running clobber step. 04:18:29 INFO - Running pre-action listener: _resource_record_pre_action 04:18:29 INFO - Running main action method: clobber 04:18:29 INFO - rmtree: /builds/slave/test/build 04:18:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:18:31 INFO - Running post-action listener: _resource_record_post_action 04:18:31 INFO - [mozharness: 2016-12-21 12:18:31.583463Z] Finished clobber step (success) 04:18:31 INFO - [mozharness: 2016-12-21 12:18:31.583626Z] Running read-buildbot-config step. 04:18:31 INFO - Running pre-action listener: _resource_record_pre_action 04:18:31 INFO - Running main action method: read_buildbot_config 04:18:31 INFO - Using buildbot properties: 04:18:31 INFO - { 04:18:31 INFO - "project": "", 04:18:31 INFO - "product": "firefox", 04:18:31 INFO - "script_repo_revision": "production", 04:18:31 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 04:18:31 INFO - "repository": "", 04:18:31 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 04:18:31 INFO - "buildid": "20161221030552", 04:18:31 INFO - "pgo_build": "False", 04:18:31 INFO - "basedir": "/builds/slave/test", 04:18:31 INFO - "buildnumber": 4, 04:18:31 INFO - "slavename": "t-yosemite-r7-0356", 04:18:31 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 04:18:31 INFO - "platform": "yosemite_r7", 04:18:31 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 04:18:31 INFO - "branch": "mozilla-beta", 04:18:31 INFO - "repo_path": "releases/mozilla-beta", 04:18:31 INFO - "revision": "b44bdfc5b686395e16bd760ccbf15e1d9093f19a", 04:18:31 INFO - "stage_platform": "macosx64", 04:18:31 INFO - "builduid": "043ed76a6488460fae98b950c928f4e1", 04:18:31 INFO - "slavebuilddir": "test" 04:18:31 INFO - } 04:18:31 INFO - Found installer url https://queue.taskcluster.net/v1/task/bONzLSkdS--6b3itps_UDw/artifacts/public/build/firefox-51.0.en-US.mac.dmg. 04:18:31 INFO - Running post-action listener: _resource_record_post_action 04:18:31 INFO - [mozharness: 2016-12-21 12:18:31.589058Z] Finished read-buildbot-config step (success) 04:18:31 INFO - [mozharness: 2016-12-21 12:18:31.589253Z] Running download-and-extract step. 04:18:31 INFO - Running pre-action listener: _resource_record_pre_action 04:18:31 INFO - Running main action method: download_and_extract 04:18:31 INFO - mkdir: /builds/slave/test/build/tests 04:18: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')]} 04:18:31 INFO - trying https://queue.taskcluster.net/v1/task/bONzLSkdS--6b3itps_UDw/artifacts/public/build/firefox-51.0.en-US.mac.test_packages.json 04:18:31 INFO - Downloading https://queue.taskcluster.net/v1/task/bONzLSkdS--6b3itps_UDw/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 04:18:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bONzLSkdS--6b3itps_UDw/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 04:18:32 INFO - Downloaded 1165 bytes. 04:18:32 INFO - Reading from file /builds/slave/test/build/firefox-51.0.en-US.mac.test_packages.json 04:18:32 INFO - Using the following test package requirements: 04:18:32 INFO - {u'common': [u'firefox-51.0.en-US.mac.common.tests.zip'], 04:18:32 INFO - u'cppunittest': [u'firefox-51.0.en-US.mac.common.tests.zip', 04:18:32 INFO - u'firefox-51.0.en-US.mac.cppunittest.tests.zip'], 04:18:32 INFO - u'gtest': [u'firefox-51.0.en-US.mac.common.tests.zip', 04:18:32 INFO - u'firefox-51.0.en-US.mac.gtest.tests.zip'], 04:18:32 INFO - u'jittest': [u'firefox-51.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 04:18:32 INFO - u'mochitest': [u'firefox-51.0.en-US.mac.common.tests.zip', 04:18:32 INFO - u'firefox-51.0.en-US.mac.mochitest.tests.zip'], 04:18:32 INFO - u'mozbase': [u'firefox-51.0.en-US.mac.common.tests.zip'], 04:18:32 INFO - u'reftest': [u'firefox-51.0.en-US.mac.common.tests.zip', 04:18:32 INFO - u'firefox-51.0.en-US.mac.reftest.tests.zip'], 04:18:32 INFO - u'talos': [u'firefox-51.0.en-US.mac.common.tests.zip', 04:18:32 INFO - u'firefox-51.0.en-US.mac.talos.tests.zip'], 04:18:32 INFO - u'web-platform': [u'firefox-51.0.en-US.mac.common.tests.zip', 04:18:32 INFO - u'firefox-51.0.en-US.mac.web-platform.tests.zip'], 04:18:32 INFO - u'xpcshell': [u'firefox-51.0.en-US.mac.common.tests.zip', 04:18:32 INFO - u'firefox-51.0.en-US.mac.xpcshell.tests.zip']} 04:18:32 INFO - Downloading packages: [u'firefox-51.0.en-US.mac.common.tests.zip'] for test suite category: common 04:18:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bONzLSkdS--6b3itps_UDw/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip 04:18:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bONzLSkdS--6b3itps_UDw/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip'}, attempt #1 04:18:32 INFO - Fetch https://queue.taskcluster.net/v1/task/bONzLSkdS--6b3itps_UDw/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip into memory 04:18:33 INFO - Expected file size: 16232548 04:18:40 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 04:18:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bONzLSkdS--6b3itps_UDw/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip 04:18:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bONzLSkdS--6b3itps_UDw/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip'}, attempt #1 04:18:40 INFO - Fetch https://queue.taskcluster.net/v1/task/bONzLSkdS--6b3itps_UDw/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip into memory 04:18:41 INFO - Expected file size: 16232548 04:18:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bONzLSkdS--6b3itps_UDw/artifacts/public/build/firefox-51.0.en-US.mac.talos.tests.zip 04:18:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bONzLSkdS--6b3itps_UDw/artifacts/public/build/firefox-51.0.en-US.mac.talos.tests.zip'}, attempt #1 04:18:47 INFO - Fetch https://queue.taskcluster.net/v1/task/bONzLSkdS--6b3itps_UDw/artifacts/public/build/firefox-51.0.en-US.mac.talos.tests.zip into memory 04:18:48 INFO - Expected file size: 14414089 04:18: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')]} 04:18:50 INFO - trying https://queue.taskcluster.net/v1/task/bONzLSkdS--6b3itps_UDw/artifacts/public/build/firefox-51.0.en-US.mac.dmg 04:18:50 INFO - Downloading https://queue.taskcluster.net/v1/task/bONzLSkdS--6b3itps_UDw/artifacts/public/build/firefox-51.0.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0.en-US.mac.dmg 04:18:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bONzLSkdS--6b3itps_UDw/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 04:18:53 INFO - Downloaded 86059470 bytes. 04:18:53 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/bONzLSkdS--6b3itps_UDw/artifacts/public/build/firefox-51.0.en-US.mac.dmg 04:18:53 INFO - mkdir: /builds/slave/test/properties 04:18:53 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:18:53 INFO - Writing to file /builds/slave/test/properties/build_url 04:18:53 INFO - Contents: 04:18:53 INFO - build_url:https://queue.taskcluster.net/v1/task/bONzLSkdS--6b3itps_UDw/artifacts/public/build/firefox-51.0.en-US.mac.dmg 04:18:53 INFO - Running post-action listener: _resource_record_post_action 04:18:53 INFO - Running post-action listener: set_extra_try_arguments 04:18:53 INFO - [mozharness: 2016-12-21 12:18:53.759923Z] Finished download-and-extract step (success) 04:18:53 INFO - [mozharness: 2016-12-21 12:18:53.760069Z] Running populate-webroot step. 04:18:53 INFO - Running pre-action listener: _resource_record_pre_action 04:18:53 INFO - Running main action method: populate_webroot 04:18:53 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:18:53 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:18:53 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:18:53 INFO - '--tppagecycles', 04:18:53 INFO - '7'], 04:18:53 INFO - 'tests': ['tsvgm']}}, 04:18:53 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:18:53 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:18:53 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:18:53 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:18:53 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:18:53 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:18:53 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:18:53 INFO - 'talos_options': ['--disable-e10s'], 04:18:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:18:53 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:18:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:18:53 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:18:53 INFO - 'talos_options': ['--disable-e10s'], 04:18:53 INFO - 'tests': ['damp', 'tps']}, 04:18:53 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:18:53 INFO - 'tests': ['damp', 'tps']}, 04:18:53 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:18:53 INFO - 'tests': ['dromaeo_dom']}, 04:18:53 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:18:53 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:18:53 INFO - 'tests': ['basic_compositor_video']}, 04:18:53 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:18:53 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:18:53 INFO - 'tests': ['a11yr', 04:18:53 INFO - 'ts_paint', 04:18:53 INFO - 'tpaint', 04:18:53 INFO - 'sessionrestore', 04:18:53 INFO - 'sessionrestore_no_auto_restore', 04:18:53 INFO - 'tabpaint']}, 04:18:53 INFO - 'other-e10s': {'tests': ['a11yr', 04:18:53 INFO - 'ts_paint', 04:18:53 INFO - 'tpaint', 04:18:53 INFO - 'sessionrestore', 04:18:53 INFO - 'sessionrestore_no_auto_restore', 04:18:53 INFO - 'tabpaint']}, 04:18:53 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:18:53 INFO - 'ts_paint', 04:18:53 INFO - 'tpaint', 04:18:53 INFO - 'sessionrestore', 04:18:53 INFO - 'sessionrestore_no_auto_restore', 04:18:53 INFO - 'tabpaint']}, 04:18:53 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:18:53 INFO - 'ts_paint', 04:18:53 INFO - 'tpaint', 04:18:53 INFO - 'sessionrestore', 04:18:53 INFO - 'sessionrestore_no_auto_restore', 04:18:53 INFO - 'tabpaint']}, 04:18:53 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:18:53 INFO - 'tests': ['a11yr', 04:18:53 INFO - 'ts_paint', 04:18:53 INFO - 'tpaint', 04:18:53 INFO - 'sessionrestore', 04:18:53 INFO - 'sessionrestore_no_auto_restore', 04:18:53 INFO - 'tabpaint']}, 04:18:53 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:18:53 INFO - 'tests': ['a11yr', 04:18:53 INFO - 'ts_paint', 04:18:53 INFO - 'tpaint', 04:18:53 INFO - 'sessionrestore', 04:18:53 INFO - 'sessionrestore_no_auto_restore', 04:18:53 INFO - 'tabpaint']}, 04:18:53 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:18:53 INFO - 'tests': ['tsvgx', 04:18:53 INFO - 'tsvgr_opacity', 04:18:53 INFO - 'tart', 04:18:53 INFO - 'tscrollx', 04:18:53 INFO - 'cart']}, 04:18:53 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:18:53 INFO - 'tsvgr_opacity', 04:18:53 INFO - 'tart', 04:18:53 INFO - 'tscrollx', 04:18:53 INFO - 'cart']}, 04:18:53 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:18:53 INFO - 'talos_options': ['--disable-e10s'], 04:18:53 INFO - 'tests': ['tp5o']}, 04:18:53 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:18:53 INFO - 'tests': ['tp5o']}, 04:18:53 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:18:53 INFO - 'talos_options': ['--disable-e10s', 04:18:53 INFO - '--xperf_path', 04:18:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:18:53 INFO - 'tests': ['tp5n']}, 04:18:53 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:18:53 INFO - 'talos_options': ['--xperf_path', 04:18:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:18:53 INFO - 'tests': ['tp5n']}}, 04:18:53 INFO - 'talos.zip': {'path': '', 04:18:53 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:18:53 INFO - Running post-action listener: _resource_record_post_action 04:18:53 INFO - [mozharness: 2016-12-21 12:18:53.778926Z] Finished populate-webroot step (success) 04:18:53 INFO - [mozharness: 2016-12-21 12:18:53.779052Z] Running create-virtualenv step. 04:18:53 INFO - Running pre-action listener: _resource_record_pre_action 04:18:53 INFO - Running main action method: create_virtualenv 04:18:53 INFO - Creating virtualenv /builds/slave/test/build/venv 04:18:53 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 04:18:53 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:18:53 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:18:53 INFO - Using real prefix '/tools/python27' 04:18:53 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:18:54 INFO - Installing distribute.............................................................................................................................................................................................done. 04:18:57 INFO - Installing pip.................done. 04:18:57 INFO - Return code: 0 04:18:57 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:18:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:18:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:18:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:18:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:18:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:18:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:18:57 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 0x10b21e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b194570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e43caab50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b032d30>, '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 0x10ae98db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b02ab20>, '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.2IRsRbL2ew/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.2bRwBGBjyZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/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 04:18:57 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 04:18:57 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 04:18:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2IRsRbL2ew/Render', 04:18:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:18:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:18:57 INFO - 'HOME': '/Users/cltbld', 04:18:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:18:57 INFO - 'LOGNAME': 'cltbld', 04:18:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:18:57 INFO - 'MOZ_NO_REMOTE': '1', 04:18:57 INFO - 'NO_EM_RESTART': '1', 04:18:57 INFO - 'PAGER': '/bin/cat', 04:18:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:18:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:18:57 INFO - 'PWD': '/builds/slave/test', 04:18:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:18:57 INFO - 'SHELL': '/bin/bash', 04:18:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2bRwBGBjyZ/Listeners', 04:18:57 INFO - 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', 04:18:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:18:57 INFO - 'USER': 'cltbld', 04:18:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:18:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:18:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:18:57 INFO - 'XPC_FLAGS': '0x0', 04:18:57 INFO - 'XPC_SERVICE_NAME': '0', 04:18:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:18:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:18:57 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 04:18:57 INFO - Cleaning up... 04:18:57 INFO - Return code: 0 04:18:57 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:18:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:18:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:18:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:18:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:18:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:18:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:18:57 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 0x10b21e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b194570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e43caab50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b032d30>, '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 0x10ae98db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b02ab20>, '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.2IRsRbL2ew/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.2bRwBGBjyZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/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 04:18:57 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 04:18:57 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 04:18:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2IRsRbL2ew/Render', 04:18:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:18:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:18:57 INFO - 'HOME': '/Users/cltbld', 04:18:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:18:57 INFO - 'LOGNAME': 'cltbld', 04:18:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:18:57 INFO - 'MOZ_NO_REMOTE': '1', 04:18:57 INFO - 'NO_EM_RESTART': '1', 04:18:57 INFO - 'PAGER': '/bin/cat', 04:18:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:18:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:18:57 INFO - 'PWD': '/builds/slave/test', 04:18:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:18:57 INFO - 'SHELL': '/bin/bash', 04:18:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2bRwBGBjyZ/Listeners', 04:18:57 INFO - 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', 04:18:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:18:57 INFO - 'USER': 'cltbld', 04:18:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:18:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:18:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:18:57 INFO - 'XPC_FLAGS': '0x0', 04:18:57 INFO - 'XPC_SERVICE_NAME': '0', 04:18:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:18:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:18:58 INFO - Downloading/unpacking psutil>=3.1.1 04:18:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:18:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:18:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:18:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:18:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:18:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:19:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:19:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:19:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:19:01 INFO - Installing collected packages: psutil 04:19:01 INFO - Running setup.py install for psutil 04:19:01 INFO - building 'psutil._psutil_osx' extension 04:19:01 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 04:19:01 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 04:19:01 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 04:19:01 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 04:19:02 INFO - building 'psutil._psutil_posix' extension 04:19:02 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 04:19:02 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:19:02 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:19:02 INFO - ^ 04:19:02 INFO - 1 warning generated. 04:19:02 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 04:19:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:19:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:19:02 INFO - Successfully installed psutil 04:19:02 INFO - Cleaning up... 04:19:02 INFO - Return code: 0 04:19:02 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:19:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:19:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:19:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:19:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:19:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:19:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:19:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b21e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b194570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e43caab50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b032d30>, '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 0x10ae98db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b02ab20>, '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.2IRsRbL2ew/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.2bRwBGBjyZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/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 04:19:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:19:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 04:19:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2IRsRbL2ew/Render', 04:19:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:19:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:19:02 INFO - 'HOME': '/Users/cltbld', 04:19:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:19:02 INFO - 'LOGNAME': 'cltbld', 04:19:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:19:02 INFO - 'MOZ_NO_REMOTE': '1', 04:19:02 INFO - 'NO_EM_RESTART': '1', 04:19:02 INFO - 'PAGER': '/bin/cat', 04:19:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:19:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:19:02 INFO - 'PWD': '/builds/slave/test', 04:19:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:19:02 INFO - 'SHELL': '/bin/bash', 04:19:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2bRwBGBjyZ/Listeners', 04:19:02 INFO - 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', 04:19:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:19:02 INFO - 'USER': 'cltbld', 04:19:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:19:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:19:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:19:02 INFO - 'XPC_FLAGS': '0x0', 04:19:02 INFO - 'XPC_SERVICE_NAME': '0', 04:19:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:19:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:19:02 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:19:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:19:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:19:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:19:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:19:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:19:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:19:05 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:19:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:19:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:19:05 INFO - Installing collected packages: mozsystemmonitor 04:19:05 INFO - Running setup.py install for mozsystemmonitor 04:19:05 INFO - Successfully installed mozsystemmonitor 04:19:05 INFO - Cleaning up... 04:19:05 INFO - Return code: 0 04:19:05 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:19:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:19:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:19:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:19:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:19:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:19:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:19:05 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 0x10b21e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b194570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e43caab50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b032d30>, '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 0x10ae98db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b02ab20>, '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.2IRsRbL2ew/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.2bRwBGBjyZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/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 04:19:05 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 04:19:05 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 04:19:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2IRsRbL2ew/Render', 04:19:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:19:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:19:05 INFO - 'HOME': '/Users/cltbld', 04:19:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:19:05 INFO - 'LOGNAME': 'cltbld', 04:19:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:19:05 INFO - 'MOZ_NO_REMOTE': '1', 04:19:05 INFO - 'NO_EM_RESTART': '1', 04:19:05 INFO - 'PAGER': '/bin/cat', 04:19:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:19:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:19:05 INFO - 'PWD': '/builds/slave/test', 04:19:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:19:05 INFO - 'SHELL': '/bin/bash', 04:19:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2bRwBGBjyZ/Listeners', 04:19:05 INFO - 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', 04:19:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:19:05 INFO - 'USER': 'cltbld', 04:19:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:19:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:19:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:19:05 INFO - 'XPC_FLAGS': '0x0', 04:19:05 INFO - 'XPC_SERVICE_NAME': '0', 04:19:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:19:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:19:05 INFO - Downloading/unpacking jsonschema==2.5.1 04:19:05 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 04:19:05 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 04:19:05 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 04:19:05 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 04:19:05 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 04:19:05 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 04:19:08 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:19:08 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:19:08 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 04:19:08 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 04:19:08 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 04:19:08 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 04:19:08 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 04:19:08 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 04:19:08 INFO - Downloading functools32-3.2.3-2.tar.gz 04:19:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:19:08 INFO - warning: no files found matching '*.txt' 04:19:08 INFO - no previously-included directories found matching 'build' 04:19:08 INFO - no previously-included directories found matching 'dist' 04:19:08 INFO - no previously-included directories found matching '.git*' 04:19:08 INFO - Installing collected packages: jsonschema, functools32 04:19:08 INFO - Running setup.py install for functools32 04:19:09 INFO - warning: no files found matching '*.txt' 04:19:09 INFO - no previously-included directories found matching 'build' 04:19:09 INFO - no previously-included directories found matching 'dist' 04:19:09 INFO - no previously-included directories found matching '.git*' 04:19:09 INFO - Successfully installed jsonschema functools32 04:19:09 INFO - Cleaning up... 04:19:09 INFO - Return code: 0 04:19:09 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:19:09 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')]} 04:19:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:19:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:19:09 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')]} 04:19:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:19:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:19:09 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 0x10b21e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b194570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e43caab50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b032d30>, '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 0x10ae98db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b02ab20>, '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.2IRsRbL2ew/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.2bRwBGBjyZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/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 04:19:09 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 04:19:09 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 04:19:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2IRsRbL2ew/Render', 04:19:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:19:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:19:09 INFO - 'HOME': '/Users/cltbld', 04:19:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:19:09 INFO - 'LOGNAME': 'cltbld', 04:19:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:19:09 INFO - 'MOZ_NO_REMOTE': '1', 04:19:09 INFO - 'NO_EM_RESTART': '1', 04:19:09 INFO - 'PAGER': '/bin/cat', 04:19:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:19:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:19:09 INFO - 'PWD': '/builds/slave/test', 04:19:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:19:09 INFO - 'SHELL': '/bin/bash', 04:19:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2bRwBGBjyZ/Listeners', 04:19:09 INFO - 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', 04:19:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:19:09 INFO - 'USER': 'cltbld', 04:19:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:19:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:19:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:19:09 INFO - 'XPC_FLAGS': '0x0', 04:19:09 INFO - 'XPC_SERVICE_NAME': '0', 04:19:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:19:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:19:09 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:19:09 INFO - Cleaning up... 04:19:09 INFO - Return code: 0 04:19:09 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:19:09 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')]} 04:19:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:19:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:19:09 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')]} 04:19:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:19:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:19:09 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 0x10b21e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b194570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e43caab50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b032d30>, '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 0x10ae98db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b02ab20>, '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.2IRsRbL2ew/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.2bRwBGBjyZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/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 04:19:09 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 04:19:09 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 04:19:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2IRsRbL2ew/Render', 04:19:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:19:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:19:09 INFO - 'HOME': '/Users/cltbld', 04:19:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:19:09 INFO - 'LOGNAME': 'cltbld', 04:19:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:19:09 INFO - 'MOZ_NO_REMOTE': '1', 04:19:09 INFO - 'NO_EM_RESTART': '1', 04:19:09 INFO - 'PAGER': '/bin/cat', 04:19:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:19:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:19:09 INFO - 'PWD': '/builds/slave/test', 04:19:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:19:09 INFO - 'SHELL': '/bin/bash', 04:19:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2bRwBGBjyZ/Listeners', 04:19:09 INFO - 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', 04:19:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:19:09 INFO - 'USER': 'cltbld', 04:19:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:19:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:19:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:19:09 INFO - 'XPC_FLAGS': '0x0', 04:19:09 INFO - 'XPC_SERVICE_NAME': '0', 04:19:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:19:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:19:09 INFO - Downloading/unpacking blobuploader==1.2.4 04:19:09 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 04:19:09 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 04:19:09 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 04:19:09 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 04:19:09 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 04:19:09 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 04:19:12 INFO - Downloading blobuploader-1.2.4.tar.gz 04:19:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:19:12 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:19:12 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 04:19:12 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 04:19:12 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 04:19:12 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 04:19:12 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 04:19:12 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 04:19:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:19:13 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:19:13 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 04:19:13 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 04:19:13 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 04:19:13 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 04:19:13 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 04:19:13 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 04:19:13 INFO - Downloading docopt-0.6.1.tar.gz 04:19:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:19:13 INFO - Installing collected packages: blobuploader, requests, docopt 04:19:13 INFO - Running setup.py install for blobuploader 04:19:13 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:19:13 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:19:13 INFO - Running setup.py install for requests 04:19:14 INFO - Running setup.py install for docopt 04:19:14 INFO - Successfully installed blobuploader requests docopt 04:19:14 INFO - Cleaning up... 04:19:14 INFO - Return code: 0 04:19:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:19:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:19:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:19:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:19:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:19:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:19:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:19:14 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 0x10b21e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b194570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e43caab50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b032d30>, '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 0x10ae98db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b02ab20>, '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.2IRsRbL2ew/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.2bRwBGBjyZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/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 04:19:14 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 04:19:14 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 04:19:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2IRsRbL2ew/Render', 04:19:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:19:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:19:14 INFO - 'HOME': '/Users/cltbld', 04:19:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:19:14 INFO - 'LOGNAME': 'cltbld', 04:19:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:19:14 INFO - 'MOZ_NO_REMOTE': '1', 04:19:14 INFO - 'NO_EM_RESTART': '1', 04:19:14 INFO - 'PAGER': '/bin/cat', 04:19:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:19:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:19:14 INFO - 'PWD': '/builds/slave/test', 04:19:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:19:14 INFO - 'SHELL': '/bin/bash', 04:19:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2bRwBGBjyZ/Listeners', 04:19:14 INFO - 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', 04:19:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:19:14 INFO - 'USER': 'cltbld', 04:19:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:19:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:19:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:19:14 INFO - 'XPC_FLAGS': '0x0', 04:19:14 INFO - 'XPC_SERVICE_NAME': '0', 04:19:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:19:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:19:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:19:14 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-5ZV7v6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:19:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:19:15 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-4TOjEO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:19:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:19:15 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-7dwXil-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:19:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:19:15 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-ZMjXw7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:19:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:19:15 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-XC8CMo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:19:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:19:15 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-nnJ9CB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:19:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:19:15 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-DbeDxj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:19:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:19:15 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-b9v9Y0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:19:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:19:15 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-03pNeE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:19:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:19:15 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-WfUZ3U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:19:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:19:15 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-E16ZOX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:19:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:19:16 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-cWEI6L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:19:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:19:16 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-HbfCe2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:19:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:19:16 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-0qod5k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:19:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:19:16 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-TvPcIA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:19:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:19:16 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-0AKS8R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:19:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:19:16 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-0fRUNx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:19:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:19:16 INFO - Running setup.py install for manifestparser 04:19:16 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:19:16 INFO - Running setup.py install for mozcrash 04:19:16 INFO - Running setup.py install for mozdebug 04:19:17 INFO - Running setup.py install for mozdevice 04:19:17 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:19:17 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:19:17 INFO - Running setup.py install for mozfile 04:19:17 INFO - Running setup.py install for mozhttpd 04:19:17 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:19:17 INFO - Running setup.py install for mozinfo 04:19:17 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:19:17 INFO - Running setup.py install for mozInstall 04:19:17 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:19:17 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:19:17 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:19:17 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:19:17 INFO - Running setup.py install for mozleak 04:19:18 INFO - Running setup.py install for mozlog 04:19:18 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:19:18 INFO - Running setup.py install for moznetwork 04:19:18 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:19:18 INFO - Running setup.py install for mozprocess 04:19:18 INFO - Running setup.py install for mozprofile 04:19:18 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:19:18 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:19:18 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:19:18 INFO - Running setup.py install for mozrunner 04:19:18 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:19:18 INFO - Running setup.py install for mozscreenshot 04:19:19 INFO - Running setup.py install for moztest 04:19:19 INFO - Running setup.py install for mozversion 04:19:19 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:19:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:19:19 INFO - Cleaning up... 04:19:19 INFO - Return code: 0 04:19:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:19:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:19:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:19:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:19:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:19:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:19:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:19:19 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 0x10b21e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b194570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e43caab50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b032d30>, '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 0x10ae98db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b02ab20>, '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.2IRsRbL2ew/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.2bRwBGBjyZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/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 04:19:19 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 04:19:19 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 04:19:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2IRsRbL2ew/Render', 04:19:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:19:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:19:19 INFO - 'HOME': '/Users/cltbld', 04:19:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:19:19 INFO - 'LOGNAME': 'cltbld', 04:19:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:19:19 INFO - 'MOZ_NO_REMOTE': '1', 04:19:19 INFO - 'NO_EM_RESTART': '1', 04:19:19 INFO - 'PAGER': '/bin/cat', 04:19:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:19:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:19:19 INFO - 'PWD': '/builds/slave/test', 04:19:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:19:19 INFO - 'SHELL': '/bin/bash', 04:19:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2bRwBGBjyZ/Listeners', 04:19:19 INFO - 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', 04:19:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:19:19 INFO - 'USER': 'cltbld', 04:19:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:19:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:19:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:19:19 INFO - 'XPC_FLAGS': '0x0', 04:19:19 INFO - 'XPC_SERVICE_NAME': '0', 04:19:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:19:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:19:19 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-iOngV7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:19:19 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)) 04:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:19:19 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-f2nP2p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:19:19 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)) 04:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:19:19 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-R4yTYV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:19:19 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)) 04:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:19:19 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-L23tqW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:19:20 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)) 04:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:19:20 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-2npKC0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:19:20 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)) 04:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:19:20 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-87_tdw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:19:20 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)) 04:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:19:20 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-lkt7XM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:19:20 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)) 04:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:19:20 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-uSArni-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:19:20 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)) 04:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:19:20 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-4ObYby-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:19:20 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)) 04:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:19:20 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-wog9ki-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:19:20 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)) 04:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:19:20 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-W474AL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:19:20 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)) 04:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:19:20 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-yGnfQe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:19:20 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)) 04:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:19:20 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-DacGt5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:19:20 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)) 04:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:19:20 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-IzSDvA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:19:21 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)) 04:19:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:19:21 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-68_wy_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:19:21 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)) 04:19:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:19:21 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-ixpll_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:19:21 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)) 04:19:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:19:21 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-i3MmMh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:19:21 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)) 04:19:21 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)) 04:19:21 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)) 04:19:21 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)) 04:19:21 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)) 04:19:21 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)) 04:19:21 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:19:21 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 04:19:21 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 04:19:21 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 04:19:21 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 04:19:21 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 04:19:21 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 04:19:24 INFO - Downloading blessings-1.6.tar.gz 04:19:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:19:24 INFO - Installing collected packages: blessings 04:19:24 INFO - Running setup.py install for blessings 04:19:24 INFO - Successfully installed blessings 04:19:24 INFO - Cleaning up... 04:19:24 INFO - Return code: 0 04:19:24 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:19:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:19:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:19:24 INFO - Reading from file tmpfile_stdout 04:19:24 INFO - Current package versions: 04:19:24 INFO - blessings == 1.6 04:19:24 INFO - blobuploader == 1.2.4 04:19:24 INFO - docopt == 0.6.1 04:19:24 INFO - functools32 == 3.2.3-2 04:19:24 INFO - jsonschema == 2.5.1 04:19:24 INFO - manifestparser == 1.1 04:19:24 INFO - mozInstall == 1.12 04:19:24 INFO - mozcrash == 0.17 04:19:24 INFO - mozdebug == 0.1 04:19:24 INFO - mozdevice == 0.48 04:19:24 INFO - mozfile == 1.2 04:19:24 INFO - mozhttpd == 0.7 04:19:24 INFO - mozinfo == 0.9 04:19:24 INFO - mozleak == 0.1 04:19:24 INFO - mozlog == 3.4 04:19:24 INFO - moznetwork == 0.27 04:19:24 INFO - mozprocess == 0.23 04:19:24 INFO - mozprofile == 0.28 04:19:24 INFO - mozrunner == 6.12 04:19:24 INFO - mozscreenshot == 0.1 04:19:24 INFO - mozsystemmonitor == 0.3 04:19:24 INFO - moztest == 0.7 04:19:24 INFO - mozversion == 1.4 04:19:24 INFO - psutil == 3.1.1 04:19:24 INFO - requests == 1.2.3 04:19:24 INFO - wsgiref == 0.1.2 04:19:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:19:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:19:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:19:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:19:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:19:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:19:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:19:25 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 0x10b21e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b194570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e43caab50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b032d30>, '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 0x10ae98db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b02ab20>, '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.2IRsRbL2ew/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.2bRwBGBjyZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/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 04:19:25 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 04:19:25 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 04:19:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2IRsRbL2ew/Render', 04:19:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:19:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:19:25 INFO - 'HOME': '/Users/cltbld', 04:19:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:19:25 INFO - 'LOGNAME': 'cltbld', 04:19:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:19:25 INFO - 'MOZ_NO_REMOTE': '1', 04:19:25 INFO - 'NO_EM_RESTART': '1', 04:19:25 INFO - 'PAGER': '/bin/cat', 04:19:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:19:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:19:25 INFO - 'PWD': '/builds/slave/test', 04:19:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:19:25 INFO - 'SHELL': '/bin/bash', 04:19:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2bRwBGBjyZ/Listeners', 04:19:25 INFO - 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', 04:19:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:19:25 INFO - 'USER': 'cltbld', 04:19:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:19:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:19:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:19:25 INFO - 'XPC_FLAGS': '0x0', 04:19:25 INFO - 'XPC_SERVICE_NAME': '0', 04:19:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:19:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:19:25 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)) 04:19:25 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)) 04:19:25 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)) 04:19:25 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)) 04:19:25 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)) 04:19:25 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)) 04:19:25 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)) 04:19:25 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)) 04:19:25 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)) 04:19:25 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)) 04:19:25 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)) 04:19:25 INFO - Cleaning up... 04:19:25 INFO - Return code: 0 04:19:25 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:19:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:19:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:19:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:19:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:19:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:19:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:19:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b21e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b194570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e43caab50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b032d30>, '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 0x10ae98db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b02ab20>, '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.2IRsRbL2ew/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.2bRwBGBjyZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/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 04:19:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:19:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:19:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2IRsRbL2ew/Render', 04:19:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:19:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:19:25 INFO - 'HOME': '/Users/cltbld', 04:19:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:19:25 INFO - 'LOGNAME': 'cltbld', 04:19:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:19:25 INFO - 'MOZ_NO_REMOTE': '1', 04:19:25 INFO - 'NO_EM_RESTART': '1', 04:19:25 INFO - 'PAGER': '/bin/cat', 04:19:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:19:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:19:25 INFO - 'PWD': '/builds/slave/test', 04:19:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:19:25 INFO - 'SHELL': '/bin/bash', 04:19:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2bRwBGBjyZ/Listeners', 04:19:25 INFO - 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', 04:19:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:19:25 INFO - 'USER': 'cltbld', 04:19:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:19:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:19:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:19:25 INFO - 'XPC_FLAGS': '0x0', 04:19:25 INFO - 'XPC_SERVICE_NAME': '0', 04:19:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:19:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:19:25 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:19:25 INFO - Cleaning up... 04:19:25 INFO - Return code: 0 04:19:25 INFO - Running post-action listener: _resource_record_post_action 04:19:25 INFO - Running post-action listener: _start_resource_monitoring 04:19:25 INFO - Starting resource monitoring. 04:19:25 INFO - [mozharness: 2016-12-21 12:19:25.707795Z] Finished create-virtualenv step (success) 04:19:25 INFO - [mozharness: 2016-12-21 12:19:25.708783Z] Running install step. 04:19:25 INFO - Running pre-action listener: _resource_record_pre_action 04:19:25 INFO - Running main action method: install 04:19:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:19:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:19:25 INFO - Reading from file tmpfile_stdout 04:19:25 INFO - Detecting whether we're running mozinstall >=1.0... 04:19:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:19:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:19:26 INFO - Reading from file tmpfile_stdout 04:19:26 INFO - Output received: 04:19:26 INFO - Usage: mozinstall [options] installer 04:19:26 INFO - Options: 04:19:26 INFO - -h, --help show this help message and exit 04:19:26 INFO - -d DEST, --destination=DEST 04:19:26 INFO - Directory to install application into. [default: 04:19:26 INFO - "/builds/slave/test"] 04:19:26 INFO - --app=APP Application being installed. [default: firefox] 04:19:26 INFO - mkdir: /builds/slave/test/build/application 04:19:26 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'] 04:19:26 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 04:19:48 INFO - Reading from file tmpfile_stdout 04:19:48 INFO - Output received: 04:19:48 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 04:19:48 INFO - Running post-action listener: _resource_record_post_action 04:19:48 INFO - [mozharness: 2016-12-21 12:19:48.304609Z] Finished install step (success) 04:19:48 INFO - [mozharness: 2016-12-21 12:19:48.304738Z] Running run-tests step. 04:19:48 INFO - Running pre-action listener: _resource_record_pre_action 04:19:48 INFO - Running main action method: run_tests 04:19:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:19:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:19:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:19:48 INFO - 2016-12-21 04:19:48.317 screenresolution[1629:7441] starting screenresolution argv=screenresolution get 04:19:48 INFO - 2016-12-21 04:19:48.320 screenresolution[1629:7441] Display 0: 1600x1200x32@60 04:19:48 INFO - 2016-12-21 04:19:48.325 screenresolution[1630:7445] starting screenresolution argv=screenresolution list 04:19:48 INFO - Available Modes on Display 0 04:19:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:19:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:19:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:19:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:19:48 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:19:48 INFO - Intel Iris: 04:19:48 INFO - Chipset Model: Intel Iris 04:19:48 INFO - Type: GPU 04:19:48 INFO - Bus: Built-In 04:19:48 INFO - VRAM (Dynamic, Max): 1536 MB 04:19:48 INFO - Vendor: Intel (0x8086) 04:19:48 INFO - Device ID: 0x0a2e 04:19:48 INFO - Revision ID: 0x0009 04:19:48 INFO - Displays: 04:19:48 INFO - Display: 04:19:48 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:19:48 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:19:48 INFO - Display Serial Number: 1600x1200 60 04:19:48 INFO - Main Display: Yes 04:19:48 INFO - Mirror: Off 04:19:48 INFO - Online: Yes 04:19:48 INFO - Rotation: Supported 04:19:48 INFO - Return code: 0 04:19:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:19:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:19:48 INFO - Python 2.7.3 04:19:48 INFO - Return code: 0 04:19:48 INFO - grabbing minidump binary from tooltool 04:19:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:19:48 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 0x10b032d30>, '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 0x10ae98db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b02ab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:19:48 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 04:19:48 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 04:19:48 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 04:19:48 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:19:48 INFO - Return code: 0 04:19:48 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:19:48 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:19:48 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:19:48 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:19:48 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:19:48 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-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bONzLSkdS--6b3itps_UDw/artifacts/public/build/firefox-51.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0356', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 04:19:48 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-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/bONzLSkdS--6b3itps_UDw/artifacts/public/build/firefox-51.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0356 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 04:19:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2IRsRbL2ew/Render', 04:19:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:19:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:19:48 INFO - 'HOME': '/Users/cltbld', 04:19:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:19:48 INFO - 'LOGNAME': 'cltbld', 04:19:48 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:19:48 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:19:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:19:48 INFO - 'MOZ_NO_REMOTE': '1', 04:19:48 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:19:48 INFO - 'NO_EM_RESTART': '1', 04:19:48 INFO - 'PAGER': '/bin/cat', 04:19:48 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:19:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:19:48 INFO - 'PWD': '/builds/slave/test', 04:19:48 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:19:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:19:48 INFO - 'SHELL': '/bin/bash', 04:19:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2bRwBGBjyZ/Listeners', 04:19:48 INFO - 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', 04:19:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:19:48 INFO - 'USER': 'cltbld', 04:19:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:19:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:19:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:19:48 INFO - 'XPC_FLAGS': '0x0', 04:19:48 INFO - 'XPC_SERVICE_NAME': '0', 04:19:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:19:48 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bONzLSkdS--6b3itps_UDw/artifacts/public/build/firefox-51.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0356', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 04:19:48 INFO - mozversion application_buildid: 20161221030552 04:19:48 INFO - mozversion application_changeset: b44bdfc5b686395e16bd760ccbf15e1d9093f19a 04:19:48 INFO - mozversion application_display_name: Firefox 04:19:48 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:19:48 INFO - mozversion application_name: Firefox 04:19:48 INFO - mozversion application_remotingname: firefox 04:19:48 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 04:19:48 INFO - mozversion application_vendor: Mozilla 04:19:48 INFO - mozversion application_version: 51.0 04:19:48 INFO - mozversion platform_buildid: 20161221030552 04:19:48 INFO - mozversion platform_changeset: b44bdfc5b686395e16bd760ccbf15e1d9093f19a 04:19:48 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 04:19:48 INFO - mozversion platform_version: 51.0 04:19:48 INFO - using testdate: 1482322788 04:19:48 INFO - actual date: 1482322788 04:19:48 INFO - starting webserver on 'localhost:49245' 04:19:48 INFO - SUITE-START | Running 2 tests 04:19:48 INFO - TEST-START | dromaeo_css 04:19:48 INFO - operating with platform_type : mac_ 04:19:48 INFO - Initialising browser for dromaeo_css test... 04:19:48 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/tmpKCotq1/profile http://localhost:49245/getInfo.html) 04:19:51 INFO - PROCESS | 1640 | __metrics Screen width/height:1600/1200 04:19:51 INFO - PROCESS | 1640 | colorDepth:24 04:19:51 INFO - PROCESS | 1640 | Browser inner width/height: 1024/689 04:19:51 INFO - PROCESS | 1640 | __metrics 04:19:51 ERROR - PROCESS | 1640 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3015: TypeError: this.tabs is undefined 04:19:52 INFO - TEST-INFO | 1640: exit 0 04:19:52 INFO - Browser initialized. 04:19:52 INFO - Running cycle 1/1 for dromaeo_css test... 04:19:52 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/tmpKCotq1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:20:04 INFO - PROCESS | 1644 | RSS: Main: 174579712 04:20:04 INFO - PROCESS | 1644 | 04:20:54 INFO - PROCESS | 1644 | 1482322854103 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] 04:23:05 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49245/tests/dromaeo/cssquery-ext.html) 04:23:07 INFO - PROCESS | 1644 | RSS: Main: 183984128 04:23:07 INFO - PROCESS | 1644 | 04:26:09 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-ext.html (next: http://localhost:49245/tests/dromaeo/cssquery-jquery.html) 04:26:09 INFO - PROCESS | 1644 | RSS: Main: 197857280 04:26:09 INFO - PROCESS | 1644 | 04:29:11 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49245/tests/dromaeo/cssquery-mootools.html) 04:29:12 INFO - PROCESS | 1644 | RSS: Main: 200601600 04:29:12 INFO - PROCESS | 1644 | 04:32:14 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49245/tests/dromaeo/cssquery-prototype.html) 04:32:14 INFO - PROCESS | 1644 | RSS: Main: 204505088 04:32:14 INFO - PROCESS | 1644 | 04:35:17 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49245/tests/dromaeo/cssquery-yui.html) 04:35:18 INFO - PROCESS | 1644 | RSS: Main: 206557184 04:35:18 INFO - PROCESS | 1644 | 04:38:20 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-yui.html (next: http://localhost:49245/tests/dromaeo/cssquery-dojo.html) 04:38:20 INFO - PROCESS | 1644 | RSS: Main: 207126528 04:38:20 INFO - PROCESS | 1644 | 04:38:20 INFO - PROCESS | 1644 | __start_tp_report 04:38:20 INFO - PROCESS | 1644 | _x_x_mozilla_page_load 04:38:20 INFO - PROCESS | 1644 | _x_x_mozilla_page_load_details 04:38:20 INFO - PROCESS | 1644 | |i|pagename|runs| 04:38:20 INFO - PROCESS | 1644 | |0;dojo.html;2988.835614041227;3655.9086022849424;3683.797391143489;3688.612695666954;3701.8889433316995;6107.541934354925;6108.664023478706;6135.478484328826;6141.263048434194;6157.322694503607;5025.698458092517;5221.451747566508;6107.908381374283;6154.476869466101;6172.753089876411;5461.754221060052;5523.392426833045;5531.474509921551;5579.637323573975;5650.378458369576;5574.414686457904;5575.860603484925;5587.469190426903;5595.49640532352;5625.6905870177125;5442.265294185294;5472.26124473195;5529.419410961851;5591.608587398884;5595.860103497404;5492.945070549304;5536.861578460531;5538.390777014525;5543.695096769676;5585.1901474286005;5384.0847056000575;5486.753096110685;5557.638753481051;5557.944420555783;5616;4415.690901636886;5833.941660583425;5979.521638268971;6197.25632924052;6209.534284928648;6253.593516421418;6293.842653933642;6305.558610897239;6310.5898116622275;6322.304546499881;5846.62604287992;6125.877482450371;6384.170057892445;6405.455536279422;6506.707198176094;5346.358436987586;6099.725512351954;6195.721192546346;6200.038993955944;6216.689165541705;6488.383603557654;6580.276169621337;6597.571157874819;6604.735810567619;6668.83327916806;6305.905411418832;6322.399372059739;6342.841428964312;6351.491880649537;6368.10846481493;6270.968645156745;6293.716782744787;6313.179286692701;6418.422341989243;6451.580647257913;6295.213098362705;6310.558260921784;6346.11154438379;6370.299267080617;6372.1716176897635;6282.780102696383;6332.1135041094285;6343.873122537523;6347.904781428282;6458.128152699325;4099.836006559704;4478.41780568524;4657.580817726421;4684.250519916797;5150.742462876841;4569.200389931749;4597.747123908217;4605.286180641939;4627.097715945359;4648.604868586141;6244.812655620339;6272.341404152589;6278.497720182374;6340.492760579143;6346.809595712136;5287.471252874759;6252.718627661675;6464.741410343628;6480.740770369227;6492.156019717407;6113.628337950562;6232.688365581703;6236.158118654014;6247.531435142382;6319.462845658079;5675.602707810413;5680.403557626472;5683.261176047087;5700.372958974593;5726.656400615893;6612.437942774918;6638.126315999584;6669.199696036507;6700.095487109275;6704.664766761643;6236.812895613139;6242.157308763441;6353.5870168438905;6364.395382438661;6384.553081284451;6665.166854143232;6704.329567043256;6705.362990515893;6711.194656641039;6719.361660642231;6369.363063693779;6373.490120790419;6384.457321127757;6386.808395748135;6388.68056597159;6243.406876346739;6296.433321001132;6297.1813664223355;6318.494520438262;6392.9041064385;6167.290761562478;6318.115463835159;6331.2085989251345;6342.460890824332;6353.96823015882;6339.651319177489;6346.4605508532295;6347.841303967438;6357.777477788255;6378.74485020594;6115.605440958429;6118.893302669218;6198.535109866688;6295.685215739286;6348.873022539431;6215.627062376272;6282.905756413749;6343.809685709251;6349.015902535114;6356.4279214870885;4997.400311962588;6257.749690012349;6305.842353941188;6349.619022858643;6360.077788720702 04:38:20 INFO - PROCESS | 1644 | |1;ext.html;497.4080843795883;524.7874610782633;541.8509909774812;560.3052215253088;563.968981706006;34555.654443455554;42331.57668423315;42420.78789606055;42462.57537424625;42557;42825;43213;43312.56687433125;43430.348544771776;43434.34848477267;9806.460644664523;9871.802563948717;10007.24945629077;10011.299209055369;10021.34861234021;9815.312928095036;9826.263030272723;9920.553575089103;9928.900710992906;9953.900460995406;9928.156106730936;9960.800783984316;9962.850557241647;9972.3517971332;9980.101790838824;9853.211743060538;9904.801903961916;9954.303198776088;9956.950215248877;9957.75105622358;9659.227261819038;9715;9718.222542196607;9803.117719405287;9856.802863942681;41610.583894161275;42005.36991945123;42098.57901420977;42278.73163805091;42280.365794513105;3427.657234276577;3471.375152472554;3623.4927110204594;3625.5105560749225;4002.3396139636925;31084.533731994037;31853.52219716706;32184.034478965434;32265.51601725976;32484.03509034713;3316.2372654289406;3316.7844090134063;3379.9155021124425;3403.7447191460737;3406.880759173417;416016;423371.9999999969;425266;426148;427082.86458567507;50511.49488505141;52309.73845130826;62541.68729156416;62895.37104628987;63147;29183.708162918098;30980.53529197064;33418.3316333672;36079.4588081179;38120.42819357656;17267.3956411525;17786.288548458175;17931.193096310697;17965.820341796676;17966.101694915204;13542.119762215425;13651.317434128254;13662.863371366358;13761.724765504634;13778.104423212659;17184.968901865926;19489.51276218106;19708.50728731793;20162.29431969874;20236.88697121643;7631.3894888408795;7757.470350990286;8079.1516890725625;8441.831163376699;8458.87311690325;867.5358683104508;969.762408209993;984.6504490905626;1027.3168343051786;1055.9313644613076;25369;30541;30649.69350306513;31324.68675313263;31340.216494587818;887.3256325192771;1097.7530055737395;1098.2641630107687;1111.4498323329842;1130.7794979978826;16282.592935176479;16483.34066637345;16602.418915337905;16625.3349866004;16633.696914130946;193248;195272;195767;196176;705770;58888;59035.704821475614;59237.703811480664;59869.40130598725;60193.69903150456;1390.367382840805;1419.3045407750335;1438.7626041703002;1443.170177148144;1450.6881020580627;29936.101916942527;31206.531902021034;31331.68668313379;31491;31907.840460797546;44788;51656.74171629118;51908.74045629748;52126.73936630444;53353;489930;493153;497393;497906;499534;27658;28074;28096.85951570229;28137.43725125527;28211.294717632223;48624.75687621681;48871.26693099535;49083;49654.503454964986;49862;39499.01252468596;40538;40800.97997550085;41370.37944430776;41764.58235417607;32041.6795832048;40030.59969400269;40420.595794042856;41383.172336553704;41408.58591414047;39573;39714;40391;40422.39366409565;41583.96040099022;30919.2270193247;32196.517052243766;33838.81564145243;35756.82121589375;36137.096572584844;21905.890470547547;26822;26973.595396068682;26981.381117133034;26996 04:38:20 INFO - PROCESS | 1644 | |2;jquery.html;377.9527559055119;388.1751278533119;388.6223338265846;400.08979571474873;416.9409699363617;19331;20930;21436.678449823266;21482.355529334127;21725.13099476021;20444.08001639926;20986.950652467393;21192.682109768328;21347.466313342135;21396.57206855862;5136.38363396392;5449.618526703132;5466.234727138204;5466.262054622634;5481.931023450429;4023.255697695921;4241.851535196269;5343.519083282504;5428.83713488596;5820.621659592113;6047.879042419149;6144.385561443864;6146.569740118193;6153.969230153843;6156.045812899008;5898.7640494380175;6149.846253843645;6219.378062193789;6239.500839932794;6268.561200715952;5327.38735045467;6029.487493563009;6083.726232319556;6093.086037094427;6122.540809439265;19743.506412339662;20903.26838560658;24701.629475557882;24861.87569062161;25497.23508294754;2196.9890150549304;2485.527749727546;2581.5027284175135;2771.3764403009363;2813.7608303294246;9981.750456238653;10268.948655256676;10582.9470852646;10697.732556686067;10719.571217151304;1809.312461264711;1904;1904.2573396375424;2026.5035066408825;2031.817136457711;118089;120515.39742301233;124942;126657;131873.99999999904;11412.600558980395;11675.824862627067;11893.226940248856;11932.88067119335;11954;15053.623659408384;15088.24558772057;15166.545003649908;15220.543383698507;15221.391144354324;8917.197452229331;8979.326550508607;9075.228605568469;9131.634734610674;9199.402038867453;7567.735129270449;8648.783780405414;9012.549372531346;9045.366824322366;9194.862077068849;4620.97689511557;4625.306204069349;4635.837745678885;4648.535146485392;4694.084653492536;1646.3167785369178;1702.3701230544777;1977.67368384215;2021.4036859126593;2041.6018876319272;825.2737590920073;927.7448701606977;980.9166220871306;998.2912132385942;1046.1587605813822;16842.24209910557;17147.657046859244;17685.381011664787;21179.68230476544;21275.78724212769;845.8181490979346;922.9123233292827;932.077243528915;956.1967946924477;958.6740508227236;9222.847144106987;10877.619283325204;11002.009819116318;11070.059044981106;11167.944160279147;586856.2628747401;590391;593224;599162;604119;136923;139727;141560;142150;142637;934.9108288843557;983.439439519467;990.4255531791543;1023.9129673977795;1051.8632577764843;15079;18564.350247741266;20328.390148295573;20513.897430512752;20683.689744654144;54093.65310824958;54735;55433;56033;56050;77678.6116069416;78221.43557128939;80686.5965670168;81617.59191204006;83034.16965830507;6752.763653272112;7708.113566939649;7725.768226953201;8200.42597018203;8256.298214651822;9166.862497062682;12386.009119270257;12734.172278801849;12882.09825312219;13196.472141114247;9644.95177524108;10838.566457341618;11024.283421577893;11091.72270693239;11436.313821170756;10852.294600850919;10931.289466184673;10964.013238808546;11161.386123763272;11260.436978151223;11143;11229.438528073728;11359.204855660024;11400.087992960709;11506.597269095542;6273.404026617464;7107.680154392961;7438.6652600632015;7874.803129921798;8284.834303313779;9063;10292.228082893962;10589.941005899349;11110.277831940897;11659.06727461784 04:38:20 INFO - PROCESS | 1644 | |3;mootools.html;1369.952051678191;1524.2683511914277;1730.766346543217;1840.5950690848008;1916.6454205971888;8021.759347219579;8249.422540422172;8549.786255343619;9099.22656574192;9129;8700.825983480327;8946.015938246788;8946.686865959693;9043.412178208411;9057.90942090579;2393.796527295178;2407.16952651335;2435;2436.902523899042;2442.6336049592614;2145.8497905146646;2403.927882163538;2406.0496104038893;2420.8063354931564;2430.586800243954;2229.7658745831695;2328.906843726257;2341.075275266269;2366.2782851230368;2402.027178992511;2386.8209884258576;2387.6776635154292;2388.8805559721945;2394.4133687246563;2415.1184817541557;2382.0590866607595;2395.844270122454;2405.3625789165885;2406.795422389099;2407.265783935899;8913.242374398184;8980.371374003822;9001.369904106716;9085.591148398338;9102.362834601581;5412.323459567554;5554.166874968787;5568.276124103841;5575.860603484945;5588.720563971785;3595.982020089909;3678.503402040717;3679.0802299425145;3693.242885208526;3699.3711069118312;1126.4649291586513;1132.1678566253797;1139.5612689114669;1152.1474109159246;1858.8419984301352;10737;10884.727881802939;11854.881451185463;12166.635000949986;12194;4471.932921006188;4697.671163018625;4759.809607615726;4784.425868895685;4801.447833499123;9381.765455863659;9451.669191578261;9468.621255149717;9485.762855928657;9488.956214816364;5410.971915336074;5429.294191755047;5459.863503412446;5466.6993315367245;5478.233047373371;4400.185965596375;4483.237849565582;4927.630427717935;4994.575461085776;5081.0854046272025;3901.43429202764;3941.152652179795;4018.1361007383553;4035.051762835731;4050.9797451012555;1175.9176857619884;1206.05222565556;1207.897328727068;1214.4959841665786;1216.4951545108859;777.9688812447439;880.6111659907458;907.4283201582961;929.1312622697799;932.2076235200026;5813.389594092644;5841.912371314434;5861.3552509223955;5971.701414929323;5989.9101513477335;801.6873419366452;955.3121752338305;960.1262850805733;961.1925982175006;1018.4245901065874;3576.40989236773;3916.74541154824;4064.878053658395;4069.7965101744794;4138.13099249155;10513.737156570995;10696.625618103484;10740.946295268473;10769.515371808287;10940.12479001678;10558.736031599272;10700.41147736882;10727.302725322828;10751.677449676523;10873.401962892116;8303.169683031649;8323.625436855356;8467.110953349933;8471.957640211882;8504.234618884206;5736.368999410145;5828.038373668297;5926.614770039933;5973.91039134422;6014;8331.875021874797;10020.448875311928;10065.496725163566;10335.328203666737;10383.532741026824;8495.830083398421;10488.31825931312;10583.4708264588;10589.629362972568;10600.946995264974;5454;6794.796156115324;7062.293770622897;7201.171865235356;7371.778846634609;8047.195280471906;8647.351448641502;8651.394402391772;8765.956170219108;8776.692815751418;7643.273888980538;7835;7857.685692572233;7875.212478752079;7879.684812607432;7585.6965721372735;7690.615469226628;7697.923020769945;7714.807129821575;7730.574818385043;7092.716291348288;7226.132864056347;7242.3843973262865;7253.963730181315;7269.636518173965;5052.191649336089;5114.769835357492;5152.562032227303;5243.68537887728;5362.490563396471;4493.662975276933;5056.761093532108;5119.718415487184;5263.736813159403;5367.275417818701 04:38:20 INFO - PROCESS | 1644 | |4;prototype.html;297.48535034390505;313.1982125758061;316.9461191597429;323.74262461343227;324.27686259640984;4483.193025255453;4685.3206285088645;5573.6098473106895;5577.442255774426;5697.831944451388;5371.946280537193;5544.584156188282;5610.905873354697;5617.550595952324;5618.943810561893;4226.598473145046;4255.787210639463;4297.935530967038;4305.547917468668;4310.224159651277;3809.8095095245126;4095.467589213413;4175.206710724968;4268.97865510672;4299.5485474025245;3879.941800872989;4219.936700949488;4222.071144348239;4251.40572285393;4260.744355338674;3875.922481550367;3880.9029774255587;4153.7507749535125;4328.523862375137;4332.826686932519;3228.4350238708366;3548.0952357148763;3725.18046029873;3730.421784623388;3758.267137908109;4706.011737535122;5164.715940623264;5420.674759514442;5422.105352616813;5457.181422786574;4870.076319853673;4882.657269250949;4890.315355850184;4903.994681090404;4922.778474968635;4626.236670949289;4921.926171107436;5032.622553308516;5571.749271282762;5895.498882594985;3365.74756893234;3598.956302672222;4042;4061.390791381287;4062.9187416251807;5218.86867879277;5805.809808989147;6210.4100110489435;6216.471599914013;6219.595726277823;3954.7542524104815;4057.533383660858;4154.8338066477;4174.686898482626;4179.97910010448;3772.9434058489146;3831.9616803831605;4184.811683474251;4195.538490766014;4198.853040143641;4827.961988172559;4875.561199492063;4878.146324393288;4880.682755620874;4882.048000639912;5168.328117344721;5867.677277749679;5887.587868849225;5936.37668044849;5945.791897283661;1263.1473755215193;1301.947922083125;1311.884897836831;1419.4606049701047;1437.9143746471348;950.9096635819586;959.2733080455153;977.379364103785;979.6571200079915;987.8567607696988;5561.277033985556;5565.916511252334;5583.3858275589655;5593;5613.7193140342815;5291.708956007456;5497.587680923947;6004.249468816398;6018.458338749533;6034.788782392683;4892.804287828447;5239.397469291004;5559.359304330492;5564.914841605929;5566.6580067547375;2360.7403185649564;2693.73062693733;2720.014389495679;2735.6731984987264;2742.780577553831;4546.2724164817155;4863.927041094386;5276.683398996072;5276.736163191825;5279.498447647467;4117;4406.27296496081;4455.977720111378;4830.2513110467935;5196.92204616931;4546.977265113654;4555.291765587838;4595.540445955377;5138.537531622172;5270.4202537721585;4488.61109862052;4493.112892585187;5075.949240507695;5120.539151476385;5128.435872053997;3990.900227494336;4471;4669.9533004670875;4904.313396124474;5087.592992560661;4333.43665323506;4557.384753058294;5257.763400646903;5283.788648454019;5333.439988801275;3013.804102733315;3176;3186.4583020886967;3602.919124262763;3624.438212077027;4767.547083027107;4821.46963833985;4898.485659005895;5110.770015349392;6109.969450152721;5950.583459157818;5979.790707325396;5981.880362392815;6007.665745511284;6016;5234.7906083756225;5436;5946;5964.612300200473;5978.461938425563;5169.638125331341;5185.455527169593;5650.773969041193;5707.6575405475805;5817.301923769006;4962.156433406401;4969.229769385751;4979.502049794992;5909.374921896513;6012.969935150296;5187.872972081428;5324.334458192726;5824.650520968756;5844.12338149281;5851.356350801323 04:38:20 INFO - PROCESS | 1644 | |5;yui.html;712.2912701861646;734.4271468254757;789.8696715042018;790.7627711686496;859.5358506406538;12619.55831545896;14534.98255122142;16164.83835161648;16220.188990550485;16304.592385190375;12213.51145954163;14270.71458570828;14845.48040818569;14984.400623975029;15017.924910375461;810.2262339465796;847.8728190771387;864.1919804982361;868.4224990381389;872.5942436766902;718.5796309159148;844.932405407566;856.0540602634093;856.1609622569886;857.2028013947058;866.969656062038;897.219419105376;897.7082448204327;899.1098812175965;900.7207765592655;890.5769759364315;897.0267260022883;898.1826537850558;900.2168113741021;902.7427183252775;654.2704884054245;662.6421732264571;669.5781657555709;671.7346648073983;673.0678010954863;15154;15168.393264269416;15396.999195052285;15427.5371738848;15460.768088478682;14586.635334116625;14664.046836955564;14696.779548306784;14786.926065369711;14840.332185051699;11617.18679692422;12054.578089766905;12117.636470905887;12119.272843629322;12592.244465332109;4655.185342565072;4686.062787442525;4710.269908164206;4711.458182309044;4860.343853579757;79706;103182.48408757983;107324.4633776834;112472.43763781211;113910.43044784723;10563.788724225626;11002;11175.944120279346;11298.491567879466;11430.542778288942;20249.19003239854;20918;20945.790542094383;20979.685304720442;20989;7442.627868606548;7770.41721870862;8013.6794528218215;8109.635066422025;8298.253157215879;3886.7667939923695;4367.759773212504;4444.4888837784065;4497.2804351304285;4646.186917289461;3542.5926018508203;3612.3497770401054;3654.9817250914107;3668.834902429397;3677.485152078711;668.5687731413211;674.8346655069539;677.4986509982577;688.3426327856885;692.2281655953575;581.9418058194146;598.2342601470125;605.4823126227012;634.7590460649419;644.9677516124175;9307.162355387913;9308.20880225175;9574.760630984281;9604.90395096054;9795.657151999789;557.6431084106175;604.8971674815292;617.3332800575367;637.1971316141695;656.5502630697987;4563.657725670588;4757.548032936935;4778.331033655291;4872.342233798392;4900.5099490051425;108408;124091.3795431017;126247;129608;130795;112816;124432.5113497725;130019;137632;140599.29700351434;88576.11423885936;103257.48371258096;108975;109826;114869.705136572;9450.858237126586;9842.212622990286;9917.851232231378;9918.950405247928;9922.305438619516;66154.67690646257;66921.33078669346;67169;67577.66211168913;68718;96630.51684741532;97281;98698;102143.97856021345;104688.95311046792;12834;13224;13386.732265354443;13644.317784110954;13985.021048526505;14682.486112985995;15284.770728439305;15391.434262948207;15607.687846243238;15977.121258330439;14239.288035598385;14357.497487588302;14373.856261437251;14570.4900328488;14579.34392952341;13306.469755978187;13662.863371366557;14321.64195895111;14382.28088595587;14578;13701.109427887155;14003.369848357052;14187.787183192055;14251.714965700421;14332.140071595737;11041.723956901142;11063.502142403771;11290.43547822622;12237.204581702517;12308.876911230773;11247.212695111592;11557.497525321654;11951.282923024646;12025.218360806519;12063.336516491678 04:38:20 INFO - PROCESS | 1644 | __end_tp_report 04:38:20 INFO - PROCESS | 1644 | __start_cc_report 04:38:20 INFO - PROCESS | 1644 | _x_x_mozilla_cycle_collect,347 04:38:20 INFO - PROCESS | 1644 | __end_cc_report 04:38:20 INFO - PROCESS | 1644 | __startTimestamp1482323900889__endTimestamp 04:38:20 INFO - PROCESS | 1644 | 04:38:20 INFO - PROCESS | 1644 | ------- Summary: start ------- 04:38:20 INFO - PROCESS | 1644 | Number of tests: 6 04:38:20 INFO - PROCESS | 1644 | 04:38:20 INFO - PROCESS | 1644 | [#0] dojo.html Cycles:165 Average:5958.86 Median:6252.72 stddev:688.19 (11.0%) stddev-sans-first:649.67 04:38:20 INFO - PROCESS | 1644 | Values: 2988.8 3655.9 3683.8 3688.6 3701.9 6107.5 6108.7 6135.5 6141.3 6157.3 5025.7 5221.5 6107.9 6154.5 6172.8 5461.8 5523.4 5531.5 5579.6 5650.4 5574.4 5575.9 5587.5 5595.5 5625.7 5442.3 5472.3 5529.4 5591.6 5595.9 5492.9 5536.9 5538.4 5543.7 5585.2 5384.1 5486.8 5557.6 5557.9 5616.0 4415.7 5833.9 5979.5 6197.3 6209.5 6253.6 6293.8 6305.6 6310.6 6322.3 5846.6 6125.9 6384.2 6405.5 6506.7 5346.4 6099.7 6195.7 6200.0 6216.7 6488.4 6580.3 6597.6 6604.7 6668.8 6305.9 6322.4 6342.8 6351.5 6368.1 6271.0 6293.7 6313.2 6418.4 6451.6 6295.2 6310.6 6346.1 6370.3 6372.2 6282.8 6332.1 6343.9 6347.9 6458.1 4099.8 4478.4 4657.6 4684.3 5150.7 4569.2 4597.7 4605.3 4627.1 4648.6 6244.8 6272.3 6278.5 6340.5 6346.8 5287.5 6252.7 6464.7 6480.7 6492.2 6113.6 6232.7 6236.2 6247.5 6319.5 5675.6 5680.4 5683.3 5700.4 5726.7 6612.4 6638.1 6669.2 6700.1 6704.7 6236.8 6242.2 6353.6 6364.4 6384.6 6665.2 6704.3 6705.4 6711.2 6719.4 6369.4 6373.5 6384.5 6386.8 6388.7 6243.4 6296.4 6297.2 6318.5 6392.9 6167.3 6318.1 6331.2 6342.5 6354.0 6339.7 6346.5 6347.8 6357.8 6378.7 6115.6 6118.9 6198.5 6295.7 6348.9 6215.6 6282.9 6343.8 6349.0 6356.4 4997.4 6257.7 6305.8 6349.6 6360.1 04:38:20 INFO - PROCESS | 1644 | 04:38:20 INFO - PROCESS | 1644 | [#1] ext.html Cycles:180 Average:56407.12 Median:40421.49 stddev:114710.70 (283.8%) stddev-sans-first:114955.68 04:38:20 INFO - PROCESS | 1644 | Values: 497.4 524.8 541.9 560.3 564.0 34555.7 42331.6 42420.8 42462.6 42557.0 42825.0 43213.0 43312.6 43430.3 43434.3 9806.5 9871.8 10007.2 10011.3 10021.3 9815.3 9826.3 9920.6 9928.9 9953.9 9928.2 9960.8 9962.9 9972.4 9980.1 9853.2 9904.8 9954.3 9957.0 9957.8 9659.2 9715.0 9718.2 9803.1 9856.8 41610.6 42005.4 42098.6 42278.7 42280.4 3427.7 3471.4 3623.5 3625.5 4002.3 31084.5 31853.5 32184.0 32265.5 32484.0 3316.2 3316.8 3379.9 3403.7 3406.9 416016.0 423372.0 425266.0 426148.0 427082.9 50511.5 52309.7 62541.7 62895.4 63147.0 29183.7 30980.5 33418.3 36079.5 38120.4 17267.4 17786.3 17931.2 17965.8 17966.1 13542.1 13651.3 13662.9 13761.7 13778.1 17185.0 19489.5 19708.5 20162.3 20236.9 7631.4 7757.5 8079.2 8441.8 8458.9 867.5 969.8 984.7 1027.3 1055.9 25369.0 30541.0 30649.7 31324.7 31340.2 887.3 1097.8 1098.3 1111.4 1130.8 16282.6 16483.3 16602.4 16625.3 16633.7 193248.0 195272.0 195767.0 196176.0 705770.0 58888.0 59035.7 59237.7 59869.4 60193.7 1390.4 1419.3 1438.8 1443.2 1450.7 29936.1 31206.5 31331.7 31491.0 31907.8 44788.0 51656.7 51908.7 52126.7 53353.0 489930.0 493153.0 497393.0 497906.0 499534.0 27658.0 28074.0 28096.9 28137.4 28211.3 48624.8 48871.3 49083.0 49654.5 49862.0 39499.0 40538.0 40801.0 41370.4 41764.6 32041.7 40030.6 40420.6 41383.2 41408.6 39573.0 39714.0 40391.0 40422.4 41584.0 30919.2 32196.5 33838.8 35756.8 36137.1 21905.9 26822.0 26973.6 26981.4 26996.0 04:38:20 INFO - PROCESS | 1644 | 04:38:20 INFO - PROCESS | 1644 | [#2] jquery.html Cycles:180 Average:35498.05 Median:2676.44 stddev:99863.51 (3731.2%) stddev-sans-first:100108.84 04:38:20 INFO - PROCESS | 1644 | Values: 378.0 388.2 388.6 400.1 416.9 19331.0 20930.0 21436.7 21482.4 21725.1 20444.1 20987.0 21192.7 21347.5 21396.6 5136.4 5449.6 5466.2 5466.3 5481.9 4023.3 4241.9 5343.5 5428.8 5820.6 6047.9 6144.4 6146.6 6154.0 6156.0 5898.8 6149.8 6219.4 6239.5 6268.6 5327.4 6029.5 6083.7 6093.1 6122.5 19743.5 20903.3 24701.6 24861.9 25497.2 2197.0 2485.5 2581.5 2771.4 2813.8 9981.8 10268.9 10582.9 10697.7 10719.6 1809.3 1904.0 1904.3 2026.5 2031.8 118089.0 120515.4 124942.0 126657.0 131874.0 11412.6 11675.8 11893.2 11932.9 11954.0 15053.6 15088.2 15166.5 15220.5 15221.4 8917.2 8979.3 9075.2 9131.6 9199.4 7567.7 8648.8 9012.5 9045.4 9194.9 4621.0 4625.3 4635.8 4648.5 4694.1 1646.3 1702.4 1977.7 2021.4 2041.6 825.3 927.7 980.9 998.3 1046.2 16842.2 17147.7 17685.4 21179.7 21275.8 845.8 922.9 932.1 956.2 958.7 9222.8 10877.6 11002.0 11070.1 11167.9 586856.3 590391.0 593224.0 599162.0 604119.0 136923.0 139727.0 141560.0 142150.0 142637.0 934.9 983.4 990.4 1023.9 1051.9 15079.0 18564.4 20328.4 20513.9 20683.7 54093.7 54735.0 55433.0 56033.0 56050.0 77678.6 78221.4 80686.6 81617.6 83034.2 6752.8 7708.1 7725.8 8200.4 8256.3 9166.9 12386.0 12734.2 12882.1 13196.5 9645.0 10838.6 11024.3 11091.7 11436.3 10852.3 10931.3 10964.0 11161.4 11260.4 11143.0 11229.4 11359.2 11400.1 11506.6 6273.4 7107.7 7438.7 7874.8 8284.8 9063.0 10292.2 10589.9 11110.3 11659.1 04:38:20 INFO - PROCESS | 1644 | 04:38:20 INFO - PROCESS | 1644 | [#3] mootools.html Cycles:180 Average:5764.63 Median:5068.92 stddev:3196.31 (63.1%) stddev-sans-first:3188.21 04:38:20 INFO - PROCESS | 1644 | Values: 1370.0 1524.3 1730.8 1840.6 1916.6 8021.8 8249.4 8549.8 9099.2 9129.0 8700.8 8946.0 8946.7 9043.4 9057.9 2393.8 2407.2 2435.0 2436.9 2442.6 2145.8 2403.9 2406.0 2420.8 2430.6 2229.8 2328.9 2341.1 2366.3 2402.0 2386.8 2387.7 2388.9 2394.4 2415.1 2382.1 2395.8 2405.4 2406.8 2407.3 8913.2 8980.4 9001.4 9085.6 9102.4 5412.3 5554.2 5568.3 5575.9 5588.7 3596.0 3678.5 3679.1 3693.2 3699.4 1126.5 1132.2 1139.6 1152.1 1858.8 10737.0 10884.7 11854.9 12166.6 12194.0 4471.9 4697.7 4759.8 4784.4 4801.4 9381.8 9451.7 9468.6 9485.8 9489.0 5411.0 5429.3 5459.9 5466.7 5478.2 4400.2 4483.2 4927.6 4994.6 5081.1 3901.4 3941.2 4018.1 4035.1 4051.0 1175.9 1206.1 1207.9 1214.5 1216.5 778.0 880.6 907.4 929.1 932.2 5813.4 5841.9 5861.4 5971.7 5989.9 801.7 955.3 960.1 961.2 1018.4 3576.4 3916.7 4064.9 4069.8 4138.1 10513.7 10696.6 10740.9 10769.5 10940.1 10558.7 10700.4 10727.3 10751.7 10873.4 8303.2 8323.6 8467.1 8472.0 8504.2 5736.4 5828.0 5926.6 5973.9 6014.0 8331.9 10020.4 10065.5 10335.3 10383.5 8495.8 10488.3 10583.5 10589.6 10600.9 5454.0 6794.8 7062.3 7201.2 7371.8 8047.2 8647.4 8651.4 8766.0 8776.7 7643.3 7835.0 7857.7 7875.2 7879.7 7585.7 7690.6 7697.9 7714.8 7730.6 7092.7 7226.1 7242.4 7254.0 7269.6 5052.2 5114.8 5152.6 5243.7 5362.5 4493.7 5056.8 5119.7 5263.7 5367.3 04:38:20 INFO - PROCESS | 1644 | 04:38:20 INFO - PROCESS | 1644 | [#4] prototype.html Cycles:180 Average:4512.95 Median:4895.64 stddev:1373.85 (28.1%) stddev-sans-first:1340.77 04:38:20 INFO - PROCESS | 1644 | Values: 297.5 313.2 316.9 323.7 324.3 4483.2 4685.3 5573.6 5577.4 5697.8 5371.9 5544.6 5610.9 5617.6 5618.9 4226.6 4255.8 4297.9 4305.5 4310.2 3809.8 4095.5 4175.2 4269.0 4299.5 3879.9 4219.9 4222.1 4251.4 4260.7 3875.9 3880.9 4153.8 4328.5 4332.8 3228.4 3548.1 3725.2 3730.4 3758.3 4706.0 5164.7 5420.7 5422.1 5457.2 4870.1 4882.7 4890.3 4904.0 4922.8 4626.2 4921.9 5032.6 5571.7 5895.5 3365.7 3599.0 4042.0 4061.4 4062.9 5218.9 5805.8 6210.4 6216.5 6219.6 3954.8 4057.5 4154.8 4174.7 4180.0 3772.9 3832.0 4184.8 4195.5 4198.9 4828.0 4875.6 4878.1 4880.7 4882.0 5168.3 5867.7 5887.6 5936.4 5945.8 1263.1 1301.9 1311.9 1419.5 1437.9 950.9 959.3 977.4 979.7 987.9 5561.3 5565.9 5583.4 5593.0 5613.7 5291.7 5497.6 6004.2 6018.5 6034.8 4892.8 5239.4 5559.4 5564.9 5566.7 2360.7 2693.7 2720.0 2735.7 2742.8 4546.3 4863.9 5276.7 5276.7 5279.5 4117.0 4406.3 4456.0 4830.3 5196.9 4547.0 4555.3 4595.5 5138.5 5270.4 4488.6 4493.1 5075.9 5120.5 5128.4 3990.9 4471.0 4670.0 4904.3 5087.6 4333.4 4557.4 5257.8 5283.8 5333.4 3013.8 3176.0 3186.5 3602.9 3624.4 4767.5 4821.5 4898.5 5110.8 6110.0 5950.6 5979.8 5981.9 6007.7 6016.0 5234.8 5436.0 5946.0 5964.6 5978.5 5169.6 5185.5 5650.8 5707.7 5817.3 4962.2 4969.2 4979.5 5909.4 6013.0 5187.9 5324.3 5824.7 5844.1 5851.4 04:38:20 INFO - PROCESS | 1644 | 04:38:20 INFO - PROCESS | 1644 | [#5] yui.html Cycles:180 Average:24398.18 Median:3577.47 stddev:37531.39 (1049.1%) stddev-sans-first:37594.54 04:38:20 INFO - PROCESS | 1644 | Values: 712.3 734.4 789.9 790.8 859.5 12619.6 14535.0 16164.8 16220.2 16304.6 12213.5 14270.7 14845.5 14984.4 15017.9 810.2 847.9 864.2 868.4 872.6 718.6 844.9 856.1 856.2 857.2 867.0 897.2 897.7 899.1 900.7 890.6 897.0 898.2 900.2 902.7 654.3 662.6 669.6 671.7 673.1 15154.0 15168.4 15397.0 15427.5 15460.8 14586.6 14664.0 14696.8 14786.9 14840.3 11617.2 12054.6 12117.6 12119.3 12592.2 4655.2 4686.1 4710.3 4711.5 4860.3 79706.0 103182.5 107324.5 112472.4 113910.4 10563.8 11002.0 11175.9 11298.5 11430.5 20249.2 20918.0 20945.8 20979.7 20989.0 7442.6 7770.4 8013.7 8109.6 8298.3 3886.8 4367.8 4444.5 4497.3 4646.2 3542.6 3612.3 3655.0 3668.8 3677.5 668.6 674.8 677.5 688.3 692.2 581.9 598.2 605.5 634.8 645.0 9307.2 9308.2 9574.8 9604.9 9795.7 557.6 604.9 617.3 637.2 656.6 4563.7 4757.5 4778.3 4872.3 4900.5 108408.0 124091.4 126247.0 129608.0 130795.0 112816.0 124432.5 130019.0 137632.0 140599.3 88576.1 103257.5 108975.0 109826.0 114869.7 9450.9 9842.2 9917.9 9919.0 9922.3 66154.7 66921.3 67169.0 67577.7 68718.0 96630.5 97281.0 98698.0 102144.0 104689.0 12834.0 13224.0 13386.7 13644.3 13985.0 14682.5 15284.8 15391.4 15607.7 15977.1 14239.3 14357.5 14373.9 14570.5 14579.3 13306.5 13662.9 14321.6 14382.3 14578.0 13701.1 14003.4 14187.8 14251.7 14332.1 11041.7 11063.5 11290.4 12237.2 12308.9 11247.2 11557.5 11951.3 12025.2 12063.3 04:38:20 INFO - PROCESS | 1644 | -------- Summary: end -------- 04:38:20 INFO - PROCESS | 1644 | 04:38:21 INFO - TEST-INFO | 1644: exit 0 04:38:21 INFO - TEST-OK | dromaeo_css | took 1112571ms 04:38:21 INFO - TEST-START | kraken 04:38:21 INFO - operating with platform_type : mac_ 04:38:21 INFO - Initialising browser for kraken test... 04:38:21 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/tmp3IYX3l/profile http://localhost:49245/getInfo.html) 04:38:24 INFO - PROCESS | 1657 | __metrics Screen width/height:1600/1200 04:38:24 INFO - PROCESS | 1657 | colorDepth:24 04:38:24 INFO - PROCESS | 1657 | Browser inner width/height: 1024/689 04:38:24 INFO - PROCESS | 1657 | __metrics 04:38:24 ERROR - PROCESS | 1657 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3015: TypeError: this.tabs is undefined 04:38:24 INFO - TEST-INFO | 1657: exit 0 04:38:24 INFO - Browser initialized. 04:38:24 INFO - Running cycle 1/1 for kraken test... 04:38:24 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/tmp3IYX3l/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:38:36 INFO - PROCESS | 1660 | RSS: Main: 174751744 04:38:36 INFO - PROCESS | 1660 | 04:38:39 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-beat-detection) 04:38:40 INFO - PROCESS | 1660 | RSS: Main: 186736640 04:38:40 INFO - PROCESS | 1660 | 04:38:42 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-dft) 04:38:43 INFO - PROCESS | 1660 | RSS: Main: 194174976 04:38:43 INFO - PROCESS | 1660 | 04:38:46 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-fft) 04:38:47 INFO - PROCESS | 1660 | RSS: Main: 197877760 04:38:47 INFO - PROCESS | 1660 | 04:38:49 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-oscillator) 04:38:49 INFO - PROCESS | 1660 | RSS: Main: 199364608 04:38:49 INFO - PROCESS | 1660 | 04:38:51 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49245/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:38:52 INFO - PROCESS | 1660 | RSS: Main: 199618560 04:38:52 INFO - PROCESS | 1660 | 04:38:55 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49245/tests/kraken/driver.html?testName=imaging-darkroom) 04:38:55 INFO - PROCESS | 1660 | RSS: Main: 200114176 04:38:55 INFO - PROCESS | 1660 | 04:38:59 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49245/tests/kraken/driver.html?testName=imaging-desaturate) 04:38:59 INFO - PROCESS | 1660 | RSS: Main: 200441856 04:38:59 INFO - PROCESS | 1660 | 04:39:02 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49245/tests/kraken/driver.html?testName=json-parse-financial) 04:39:03 INFO - PROCESS | 1660 | RSS: Main: 200572928 04:39:03 INFO - PROCESS | 1660 | 04:39:04 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49245/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:39:05 INFO - PROCESS | 1660 | RSS: Main: 199897088 04:39:05 INFO - PROCESS | 1660 | 04:39:07 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:39:08 INFO - PROCESS | 1660 | RSS: Main: 200048640 04:39:08 INFO - PROCESS | 1660 | 04:39:10 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:39:10 INFO - PROCESS | 1660 | RSS: Main: 200003584 04:39:10 INFO - PROCESS | 1660 | 04:39:13 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:39:14 INFO - PROCESS | 1660 | RSS: Main: 200040448 04:39:14 INFO - PROCESS | 1660 | 04:39:17 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:39:17 INFO - PROCESS | 1660 | RSS: Main: 200376320 04:39:17 INFO - PROCESS | 1660 | 04:39:19 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49245/tests/kraken/driver.html?testName=ai-astar) 04:39:19 INFO - PROCESS | 1660 | RSS: Main: 201863168 04:39:19 INFO - PROCESS | 1660 | 04:39:19 INFO - PROCESS | 1660 | __start_tp_report 04:39:19 INFO - PROCESS | 1660 | _x_x_mozilla_page_load 04:39:19 INFO - PROCESS | 1660 | _x_x_mozilla_page_load_details 04:39:19 INFO - PROCESS | 1660 | |i|pagename|runs| 04:39:19 INFO - PROCESS | 1660 | |0;ai-astar;105;117;118;119;119;119;119;118;121;119 04:39:19 INFO - PROCESS | 1660 | |1;audio-beat-detection;137;109;112;145;106;113;142;142;137;113 04:39:19 INFO - PROCESS | 1660 | |2;audio-dft;224;280;197;195;194;198;198;196;193;196 04:39:19 INFO - PROCESS | 1660 | |3;audio-fft;79;59;60;85;60;59;60;59;78;59 04:39:19 INFO - PROCESS | 1660 | |4;audio-oscillator;85;70;88;72;68;70;87;68;72;84 04:39:19 INFO - PROCESS | 1660 | |5;imaging-gaussian-blur;91;89;91;89;93;89;93;89;92;108 04:39:19 INFO - PROCESS | 1660 | |6;imaging-darkroom;133;147;118;174;165;102;135;109;172;173 04:39:19 INFO - PROCESS | 1660 | |7;imaging-desaturate;95;91;91;92;116;144;120;91;123;97 04:39:19 INFO - PROCESS | 1660 | |8;json-parse-financial;55;54;55;54;59;58;58;58;59;59 04:39:19 INFO - PROCESS | 1660 | |9;json-stringify-tinderbox;71;68;68;83;86;105;96;60;76;98 04:39:19 INFO - PROCESS | 1660 | |10;stanford-crypto-aes;82;82;81;85;79;85;85;86;89;83 04:39:19 INFO - PROCESS | 1660 | |11;stanford-crypto-ccm;136;135;144;135;137;139;148;154;199;166 04:39:19 INFO - PROCESS | 1660 | |12;stanford-crypto-pbkdf2;162;163;162;164;163;162;165;168;161;204 04:39:19 INFO - PROCESS | 1660 | |13;stanford-crypto-sha256-iterative;67;67;67;66;69;80;86;65;66;66 04:39:19 INFO - PROCESS | 1660 | __end_tp_report 04:39:19 INFO - PROCESS | 1660 | __start_cc_report 04:39:19 INFO - PROCESS | 1660 | _x_x_mozilla_cycle_collect,897 04:39:19 INFO - PROCESS | 1660 | __end_cc_report 04:39:19 INFO - PROCESS | 1660 | __startTimestamp1482323959698__endTimestamp 04:39:19 INFO - PROCESS | 1660 | 04:39:19 INFO - PROCESS | 1660 | ------- Summary: start ------- 04:39:19 INFO - PROCESS | 1660 | Number of tests: 14 04:39:19 INFO - PROCESS | 1660 | 04:39:19 INFO - PROCESS | 1660 | [#0] ai-astar Cycles:10 Average:117.40 Median:119.00 stddev:4.48 (3.8%) stddev-sans-first:1.09 04:39:19 INFO - PROCESS | 1660 | Values: 105.0 117.0 118.0 119.0 119.0 119.0 119.0 118.0 121.0 119.0 04:39:19 INFO - PROCESS | 1660 | 04:39:19 INFO - PROCESS | 1660 | [#1] audio-beat-detection Cycles:10 Average:125.60 Median:137.00 stddev:16.11 (11.8%) stddev-sans-first:16.55 04:39:19 INFO - PROCESS | 1660 | Values: 137.0 109.0 112.0 145.0 106.0 113.0 142.0 142.0 137.0 113.0 04:39:19 INFO - PROCESS | 1660 | 04:39:19 INFO - PROCESS | 1660 | [#2] audio-dft Cycles:10 Average:207.10 Median:197.50 stddev:27.14 (13.7%) stddev-sans-first:28.09 04:39:19 INFO - PROCESS | 1660 | Values: 224.0 280.0 197.0 195.0 194.0 198.0 198.0 196.0 193.0 196.0 04:39:19 INFO - PROCESS | 1660 | 04:39:19 INFO - PROCESS | 1660 | [#3] audio-fft Cycles:10 Average:65.80 Median:60.00 stddev:10.42 (17.4%) stddev-sans-first:9.90 04:39:19 INFO - PROCESS | 1660 | Values: 79.0 59.0 60.0 85.0 60.0 59.0 60.0 59.0 78.0 59.0 04:39:19 INFO - PROCESS | 1660 | 04:39:19 INFO - PROCESS | 1660 | [#4] audio-oscillator Cycles:10 Average:76.40 Median:78.00 stddev:8.44 (10.8%) stddev-sans-first:8.35 04:39:19 INFO - PROCESS | 1660 | Values: 85.0 70.0 88.0 72.0 68.0 70.0 87.0 68.0 72.0 84.0 04:39:19 INFO - PROCESS | 1660 | 04:39:19 INFO - PROCESS | 1660 | [#5] imaging-gaussian-blur Cycles:10 Average:92.40 Median:91.00 stddev:5.72 (6.3%) stddev-sans-first:6.04 04:39:19 INFO - PROCESS | 1660 | Values: 91.0 89.0 91.0 89.0 93.0 89.0 93.0 89.0 92.0 108.0 04:39:19 INFO - PROCESS | 1660 | 04:39:19 INFO - PROCESS | 1660 | [#6] imaging-darkroom Cycles:10 Average:142.80 Median:156.00 stddev:27.54 (17.7%) stddev-sans-first:28.98 04:39:19 INFO - PROCESS | 1660 | Values: 133.0 147.0 118.0 174.0 165.0 102.0 135.0 109.0 172.0 173.0 04:39:19 INFO - PROCESS | 1660 | 04:39:19 INFO - PROCESS | 1660 | [#7] imaging-desaturate Cycles:10 Average:106.00 Median:91.00 stddev:18.57 (20.4%) stddev-sans-first:19.26 04:39:19 INFO - PROCESS | 1660 | Values: 95.0 91.0 91.0 92.0 116.0 144.0 120.0 91.0 123.0 97.0 04:39:19 INFO - PROCESS | 1660 | 04:39:19 INFO - PROCESS | 1660 | [#8] json-parse-financial Cycles:10 Average:56.90 Median:58.00 stddev:2.13 (3.7%) stddev-sans-first:2.15 04:39:19 INFO - PROCESS | 1660 | Values: 55.0 54.0 55.0 54.0 59.0 58.0 58.0 58.0 59.0 59.0 04:39:19 INFO - PROCESS | 1660 | 04:39:19 INFO - PROCESS | 1660 | [#9] json-stringify-tinderbox Cycles:10 Average:81.10 Median:79.50 stddev:14.99 (18.9%) stddev-sans-first:15.45 04:39:19 INFO - PROCESS | 1660 | Values: 71.0 68.0 68.0 83.0 86.0 105.0 96.0 60.0 76.0 98.0 04:39:19 INFO - PROCESS | 1660 | 04:39:19 INFO - PROCESS | 1660 | [#10] stanford-crypto-aes Cycles:10 Average:83.70 Median:85.00 stddev:2.87 (3.4%) stddev-sans-first:2.98 04:39:19 INFO - PROCESS | 1660 | Values: 82.0 82.0 81.0 85.0 79.0 85.0 85.0 86.0 89.0 83.0 04:39:19 INFO - PROCESS | 1660 | 04:39:19 INFO - PROCESS | 1660 | [#11] stanford-crypto-ccm Cycles:10 Average:149.30 Median:146.00 stddev:20.12 (13.8%) stddev-sans-first:20.76 04:39:19 INFO - PROCESS | 1660 | Values: 136.0 135.0 144.0 135.0 137.0 139.0 148.0 154.0 199.0 166.0 04:39:19 INFO - PROCESS | 1660 | 04:39:19 INFO - PROCESS | 1660 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.40 Median:163.50 stddev:13.01 (8.0%) stddev-sans-first:13.66 04:39:19 INFO - PROCESS | 1660 | Values: 162.0 163.0 162.0 164.0 163.0 162.0 165.0 168.0 161.0 204.0 04:39:19 INFO - PROCESS | 1660 | 04:39:19 INFO - PROCESS | 1660 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.90 Median:67.00 stddev:7.13 (10.6%) stddev-sans-first:7.48 04:39:19 INFO - PROCESS | 1660 | Values: 67.0 67.0 67.0 66.0 69.0 80.0 86.0 65.0 66.0 66.0 04:39:19 INFO - PROCESS | 1660 | -------- Summary: end -------- 04:39:19 INFO - PROCESS | 1660 | 04:39:20 INFO - TEST-INFO | 1660: exit 0 04:39:20 INFO - TEST-OK | kraken | took 58947ms 04:39:20 INFO - SUITE-END | took 1171s 04:39:20 INFO - Completed test suite (00:19:31) 04:39:20 INFO - javascript benchmark 04:39:20 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7434.804039046992, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2988.835614041227, 3655.9086022849424, 3683.797391143489, 3688.612695666954, 3701.8889433316995, 6107.541934354925, 6108.664023478706, 6135.478484328826, 6141.263048434194, 6157.322694503607, 5025.698458092517, 5221.451747566508, 6107.908381374283, 6154.476869466101, 6172.753089876411, 5461.754221060052, 5523.392426833045, 5531.474509921551, 5579.637323573975, 5650.378458369576, 5574.414686457904, 5575.860603484925, 5587.469190426903, 5595.49640532352, 5625.6905870177125, 5442.265294185294, 5472.26124473195, 5529.419410961851, 5591.608587398884, 5595.860103497404, 5492.945070549304, 5536.861578460531, 5538.390777014525, 5543.695096769676, 5585.1901474286005, 5384.0847056000575, 5486.753096110685, 5557.638753481051, 5557.944420555783, 5616.0, 4415.690901636886, 5833.941660583425, 5979.521638268971, 6197.25632924052, 6209.534284928648, 6253.593516421418, 6293.842653933642, 6305.558610897239, 6310.5898116622275, 6322.304546499881, 5846.62604287992, 6125.877482450371, 6384.170057892445, 6405.455536279422, 6506.707198176094, 5346.358436987586, 6099.725512351954, 6195.721192546346, 6200.038993955944, 6216.689165541705, 6488.383603557654, 6580.276169621337, 6597.571157874819, 6604.735810567619, 6668.83327916806, 6305.905411418832, 6322.399372059739, 6342.841428964312, 6351.491880649537, 6368.10846481493, 6270.968645156745, 6293.716782744787, 6313.179286692701, 6418.422341989243, 6451.580647257913, 6295.213098362705, 6310.558260921784, 6346.11154438379, 6370.299267080617, 6372.1716176897635, 6282.780102696383, 6332.1135041094285, 6343.873122537523, 6347.904781428282, 6458.128152699325, 4099.836006559704, 4478.41780568524, 4657.580817726421, 4684.250519916797, 5150.742462876841, 4569.200389931749, 4597.747123908217, 4605.286180641939, 4627.097715945359, 4648.604868586141, 6244.812655620339, 6272.341404152589, 6278.497720182374, 6340.492760579143, 6346.809595712136, 5287.471252874759, 6252.718627661675, 6464.741410343628, 6480.740770369227, 6492.156019717407, 6113.628337950562, 6232.688365581703, 6236.158118654014, 6247.531435142382, 6319.462845658079, 5675.602707810413, 5680.403557626472, 5683.261176047087, 5700.372958974593, 5726.656400615893, 6612.437942774918, 6638.126315999584, 6669.199696036507, 6700.095487109275, 6704.664766761643, 6236.812895613139, 6242.157308763441, 6353.5870168438905, 6364.395382438661, 6384.553081284451, 6665.166854143232, 6704.329567043256, 6705.362990515893, 6711.194656641039, 6719.361660642231, 6369.363063693779, 6373.490120790419, 6384.457321127757, 6386.808395748135, 6388.68056597159, 6243.406876346739, 6296.433321001132, 6297.1813664223355, 6318.494520438262, 6392.9041064385, 6167.290761562478, 6318.115463835159, 6331.2085989251345, 6342.460890824332, 6353.96823015882, 6339.651319177489, 6346.4605508532295, 6347.841303967438, 6357.777477788255, 6378.74485020594, 6115.605440958429, 6118.893302669218, 6198.535109866688, 6295.685215739286, 6348.873022539431, 6215.627062376272, 6282.905756413749, 6343.809685709251, 6349.015902535114, 6356.4279214870885, 4997.400311962588, 6257.749690012349, 6305.842353941188, 6349.619022858643, 6360.077788720702], "value": 5916.479848346611, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [497.4080843795883, 524.7874610782633, 541.8509909774812, 560.3052215253088, 563.968981706006, 34555.654443455554, 42331.57668423315, 42420.78789606055, 42462.57537424625, 42557.0, 42825.0, 43213.0, 43312.56687433125, 43430.348544771776, 43434.34848477267, 9806.460644664523, 9871.802563948717, 10007.24945629077, 10011.299209055369, 10021.34861234021, 9815.312928095036, 9826.263030272723, 9920.553575089103, 9928.900710992906, 9953.900460995406, 9928.156106730936, 9960.800783984316, 9962.850557241647, 9972.3517971332, 9980.101790838824, 9853.211743060538, 9904.801903961916, 9954.303198776088, 9956.950215248877, 9957.75105622358, 9659.227261819038, 9715.0, 9718.222542196607, 9803.117719405287, 9856.802863942681, 41610.583894161275, 42005.36991945123, 42098.57901420977, 42278.73163805091, 42280.365794513105, 3427.657234276577, 3471.375152472554, 3623.4927110204594, 3625.5105560749225, 4002.3396139636925, 31084.533731994037, 31853.52219716706, 32184.034478965434, 32265.51601725976, 32484.03509034713, 3316.2372654289406, 3316.7844090134063, 3379.9155021124425, 3403.7447191460737, 3406.880759173417, 416016.0, 423371.9999999969, 425266.0, 426148.0, 427082.86458567507, 50511.49488505141, 52309.73845130826, 62541.68729156416, 62895.37104628987, 63147.0, 29183.708162918098, 30980.53529197064, 33418.3316333672, 36079.4588081179, 38120.42819357656, 17267.3956411525, 17786.288548458175, 17931.193096310697, 17965.820341796676, 17966.101694915204, 13542.119762215425, 13651.317434128254, 13662.863371366358, 13761.724765504634, 13778.104423212659, 17184.968901865926, 19489.51276218106, 19708.50728731793, 20162.29431969874, 20236.88697121643, 7631.3894888408795, 7757.470350990286, 8079.1516890725625, 8441.831163376699, 8458.87311690325, 867.5358683104508, 969.762408209993, 984.6504490905626, 1027.3168343051786, 1055.9313644613076, 25369.0, 30541.0, 30649.69350306513, 31324.68675313263, 31340.216494587818, 887.3256325192771, 1097.7530055737395, 1098.2641630107687, 1111.4498323329842, 1130.7794979978826, 16282.592935176479, 16483.34066637345, 16602.418915337905, 16625.3349866004, 16633.696914130946, 193248.0, 195272.0, 195767.0, 196176.0, 705770.0, 58888.0, 59035.704821475614, 59237.703811480664, 59869.40130598725, 60193.69903150456, 1390.367382840805, 1419.3045407750335, 1438.7626041703002, 1443.170177148144, 1450.6881020580627, 29936.101916942527, 31206.531902021034, 31331.68668313379, 31491.0, 31907.840460797546, 44788.0, 51656.74171629118, 51908.74045629748, 52126.73936630444, 53353.0, 489930.0, 493153.0, 497393.0, 497906.0, 499534.0, 27658.0, 28074.0, 28096.85951570229, 28137.43725125527, 28211.294717632223, 48624.75687621681, 48871.26693099535, 49083.0, 49654.503454964986, 49862.0, 39499.01252468596, 40538.0, 40800.97997550085, 41370.37944430776, 41764.58235417607, 32041.6795832048, 40030.59969400269, 40420.595794042856, 41383.172336553704, 41408.58591414047, 39573.0, 39714.0, 40391.0, 40422.39366409565, 41583.96040099022, 30919.2270193247, 32196.517052243766, 33838.81564145243, 35756.82121589375, 36137.096572584844, 21905.890470547547, 26822.0, 26973.595396068682, 26981.381117133034, 26996.0], "value": 19660.254964206313, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [377.9527559055119, 388.1751278533119, 388.6223338265846, 400.08979571474873, 416.9409699363617, 19331.0, 20930.0, 21436.678449823266, 21482.355529334127, 21725.13099476021, 20444.08001639926, 20986.950652467393, 21192.682109768328, 21347.466313342135, 21396.57206855862, 5136.38363396392, 5449.618526703132, 5466.234727138204, 5466.262054622634, 5481.931023450429, 4023.255697695921, 4241.851535196269, 5343.519083282504, 5428.83713488596, 5820.621659592113, 6047.879042419149, 6144.385561443864, 6146.569740118193, 6153.969230153843, 6156.045812899008, 5898.7640494380175, 6149.846253843645, 6219.378062193789, 6239.500839932794, 6268.561200715952, 5327.38735045467, 6029.487493563009, 6083.726232319556, 6093.086037094427, 6122.540809439265, 19743.506412339662, 20903.26838560658, 24701.629475557882, 24861.87569062161, 25497.23508294754, 2196.9890150549304, 2485.527749727546, 2581.5027284175135, 2771.3764403009363, 2813.7608303294246, 9981.750456238653, 10268.948655256676, 10582.9470852646, 10697.732556686067, 10719.571217151304, 1809.312461264711, 1904.0, 1904.2573396375424, 2026.5035066408825, 2031.817136457711, 118089.0, 120515.39742301233, 124942.0, 126657.0, 131873.99999999904, 11412.600558980395, 11675.824862627067, 11893.226940248856, 11932.88067119335, 11954.0, 15053.623659408384, 15088.24558772057, 15166.545003649908, 15220.543383698507, 15221.391144354324, 8917.197452229331, 8979.326550508607, 9075.228605568469, 9131.634734610674, 9199.402038867453, 7567.735129270449, 8648.783780405414, 9012.549372531346, 9045.366824322366, 9194.862077068849, 4620.97689511557, 4625.306204069349, 4635.837745678885, 4648.535146485392, 4694.084653492536, 1646.3167785369178, 1702.3701230544777, 1977.67368384215, 2021.4036859126593, 2041.6018876319272, 825.2737590920073, 927.7448701606977, 980.9166220871306, 998.2912132385942, 1046.1587605813822, 16842.24209910557, 17147.657046859244, 17685.381011664787, 21179.68230476544, 21275.78724212769, 845.8181490979346, 922.9123233292827, 932.077243528915, 956.1967946924477, 958.6740508227236, 9222.847144106987, 10877.619283325204, 11002.009819116318, 11070.059044981106, 11167.944160279147, 586856.2628747401, 590391.0, 593224.0, 599162.0, 604119.0, 136923.0, 139727.0, 141560.0, 142150.0, 142637.0, 934.9108288843557, 983.439439519467, 990.4255531791543, 1023.9129673977795, 1051.8632577764843, 15079.0, 18564.350247741266, 20328.390148295573, 20513.897430512752, 20683.689744654144, 54093.65310824958, 54735.0, 55433.0, 56033.0, 56050.0, 77678.6116069416, 78221.43557128939, 80686.5965670168, 81617.59191204006, 83034.16965830507, 6752.763653272112, 7708.113566939649, 7725.768226953201, 8200.42597018203, 8256.298214651822, 9166.862497062682, 12386.009119270257, 12734.172278801849, 12882.09825312219, 13196.472141114247, 9644.95177524108, 10838.566457341618, 11024.283421577893, 11091.72270693239, 11436.313821170756, 10852.294600850919, 10931.289466184673, 10964.013238808546, 11161.386123763272, 11260.436978151223, 11143.0, 11229.438528073728, 11359.204855660024, 11400.087992960709, 11506.597269095542, 6273.404026617464, 7107.680154392961, 7438.6652600632015, 7874.803129921798, 8284.834303313779, 9063.0, 10292.228082893962, 10589.941005899349, 11110.277831940897, 11659.06727461784], "value": 9607.794372030503, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1369.952051678191, 1524.2683511914277, 1730.766346543217, 1840.5950690848008, 1916.6454205971888, 8021.759347219579, 8249.422540422172, 8549.786255343619, 9099.22656574192, 9129.0, 8700.825983480327, 8946.015938246788, 8946.686865959693, 9043.412178208411, 9057.90942090579, 2393.796527295178, 2407.16952651335, 2435.0, 2436.902523899042, 2442.6336049592614, 2145.8497905146646, 2403.927882163538, 2406.0496104038893, 2420.8063354931564, 2430.586800243954, 2229.7658745831695, 2328.906843726257, 2341.075275266269, 2366.2782851230368, 2402.027178992511, 2386.8209884258576, 2387.6776635154292, 2388.8805559721945, 2394.4133687246563, 2415.1184817541557, 2382.0590866607595, 2395.844270122454, 2405.3625789165885, 2406.795422389099, 2407.265783935899, 8913.242374398184, 8980.371374003822, 9001.369904106716, 9085.591148398338, 9102.362834601581, 5412.323459567554, 5554.166874968787, 5568.276124103841, 5575.860603484945, 5588.720563971785, 3595.982020089909, 3678.503402040717, 3679.0802299425145, 3693.242885208526, 3699.3711069118312, 1126.4649291586513, 1132.1678566253797, 1139.5612689114669, 1152.1474109159246, 1858.8419984301352, 10737.0, 10884.727881802939, 11854.881451185463, 12166.635000949986, 12194.0, 4471.932921006188, 4697.671163018625, 4759.809607615726, 4784.425868895685, 4801.447833499123, 9381.765455863659, 9451.669191578261, 9468.621255149717, 9485.762855928657, 9488.956214816364, 5410.971915336074, 5429.294191755047, 5459.863503412446, 5466.6993315367245, 5478.233047373371, 4400.185965596375, 4483.237849565582, 4927.630427717935, 4994.575461085776, 5081.0854046272025, 3901.43429202764, 3941.152652179795, 4018.1361007383553, 4035.051762835731, 4050.9797451012555, 1175.9176857619884, 1206.05222565556, 1207.897328727068, 1214.4959841665786, 1216.4951545108859, 777.9688812447439, 880.6111659907458, 907.4283201582961, 929.1312622697799, 932.2076235200026, 5813.389594092644, 5841.912371314434, 5861.3552509223955, 5971.701414929323, 5989.9101513477335, 801.6873419366452, 955.3121752338305, 960.1262850805733, 961.1925982175006, 1018.4245901065874, 3576.40989236773, 3916.74541154824, 4064.878053658395, 4069.7965101744794, 4138.13099249155, 10513.737156570995, 10696.625618103484, 10740.946295268473, 10769.515371808287, 10940.12479001678, 10558.736031599272, 10700.41147736882, 10727.302725322828, 10751.677449676523, 10873.401962892116, 8303.169683031649, 8323.625436855356, 8467.110953349933, 8471.957640211882, 8504.234618884206, 5736.368999410145, 5828.038373668297, 5926.614770039933, 5973.91039134422, 6014.0, 8331.875021874797, 10020.448875311928, 10065.496725163566, 10335.328203666737, 10383.532741026824, 8495.830083398421, 10488.31825931312, 10583.4708264588, 10589.629362972568, 10600.946995264974, 5454.0, 6794.796156115324, 7062.293770622897, 7201.171865235356, 7371.778846634609, 8047.195280471906, 8647.351448641502, 8651.394402391772, 8765.956170219108, 8776.692815751418, 7643.273888980538, 7835.0, 7857.685692572233, 7875.212478752079, 7879.684812607432, 7585.6965721372735, 7690.615469226628, 7697.923020769945, 7714.807129821575, 7730.574818385043, 7092.716291348288, 7226.132864056347, 7242.3843973262865, 7253.963730181315, 7269.636518173965, 5052.191649336089, 5114.769835357492, 5152.562032227303, 5243.68537887728, 5362.490563396471, 4493.662975276933, 5056.761093532108, 5119.718415487184, 5263.736813159403, 5367.275417818701], "value": 4653.322595892504, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [297.48535034390505, 313.1982125758061, 316.9461191597429, 323.74262461343227, 324.27686259640984, 4483.193025255453, 4685.3206285088645, 5573.6098473106895, 5577.442255774426, 5697.831944451388, 5371.946280537193, 5544.584156188282, 5610.905873354697, 5617.550595952324, 5618.943810561893, 4226.598473145046, 4255.787210639463, 4297.935530967038, 4305.547917468668, 4310.224159651277, 3809.8095095245126, 4095.467589213413, 4175.206710724968, 4268.97865510672, 4299.5485474025245, 3879.941800872989, 4219.936700949488, 4222.071144348239, 4251.40572285393, 4260.744355338674, 3875.922481550367, 3880.9029774255587, 4153.7507749535125, 4328.523862375137, 4332.826686932519, 3228.4350238708366, 3548.0952357148763, 3725.18046029873, 3730.421784623388, 3758.267137908109, 4706.011737535122, 5164.715940623264, 5420.674759514442, 5422.105352616813, 5457.181422786574, 4870.076319853673, 4882.657269250949, 4890.315355850184, 4903.994681090404, 4922.778474968635, 4626.236670949289, 4921.926171107436, 5032.622553308516, 5571.749271282762, 5895.498882594985, 3365.74756893234, 3598.956302672222, 4042.0, 4061.390791381287, 4062.9187416251807, 5218.86867879277, 5805.809808989147, 6210.4100110489435, 6216.471599914013, 6219.595726277823, 3954.7542524104815, 4057.533383660858, 4154.8338066477, 4174.686898482626, 4179.97910010448, 3772.9434058489146, 3831.9616803831605, 4184.811683474251, 4195.538490766014, 4198.853040143641, 4827.961988172559, 4875.561199492063, 4878.146324393288, 4880.682755620874, 4882.048000639912, 5168.328117344721, 5867.677277749679, 5887.587868849225, 5936.37668044849, 5945.791897283661, 1263.1473755215193, 1301.947922083125, 1311.884897836831, 1419.4606049701047, 1437.9143746471348, 950.9096635819586, 959.2733080455153, 977.379364103785, 979.6571200079915, 987.8567607696988, 5561.277033985556, 5565.916511252334, 5583.3858275589655, 5593.0, 5613.7193140342815, 5291.708956007456, 5497.587680923947, 6004.249468816398, 6018.458338749533, 6034.788782392683, 4892.804287828447, 5239.397469291004, 5559.359304330492, 5564.914841605929, 5566.6580067547375, 2360.7403185649564, 2693.73062693733, 2720.014389495679, 2735.6731984987264, 2742.780577553831, 4546.2724164817155, 4863.927041094386, 5276.683398996072, 5276.736163191825, 5279.498447647467, 4117.0, 4406.27296496081, 4455.977720111378, 4830.2513110467935, 5196.92204616931, 4546.977265113654, 4555.291765587838, 4595.540445955377, 5138.537531622172, 5270.4202537721585, 4488.61109862052, 4493.112892585187, 5075.949240507695, 5120.539151476385, 5128.435872053997, 3990.900227494336, 4471.0, 4669.9533004670875, 4904.313396124474, 5087.592992560661, 4333.43665323506, 4557.384753058294, 5257.763400646903, 5283.788648454019, 5333.439988801275, 3013.804102733315, 3176.0, 3186.4583020886967, 3602.919124262763, 3624.438212077027, 4767.547083027107, 4821.46963833985, 4898.485659005895, 5110.770015349392, 6109.969450152721, 5950.583459157818, 5979.790707325396, 5981.880362392815, 6007.665745511284, 6016.0, 5234.7906083756225, 5436.0, 5946.0, 5964.612300200473, 5978.461938425563, 5169.638125331341, 5185.455527169593, 5650.773969041193, 5707.6575405475805, 5817.301923769006, 4962.156433406401, 4969.229769385751, 4979.502049794992, 5909.374921896513, 6012.969935150296, 5187.872972081428, 5324.334458192726, 5824.650520968756, 5844.12338149281, 5851.356350801323], "value": 4085.756726492094, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [712.2912701861646, 734.4271468254757, 789.8696715042018, 790.7627711686496, 859.5358506406538, 12619.55831545896, 14534.98255122142, 16164.83835161648, 16220.188990550485, 16304.592385190375, 12213.51145954163, 14270.71458570828, 14845.48040818569, 14984.400623975029, 15017.924910375461, 810.2262339465796, 847.8728190771387, 864.1919804982361, 868.4224990381389, 872.5942436766902, 718.5796309159148, 844.932405407566, 856.0540602634093, 856.1609622569886, 857.2028013947058, 866.969656062038, 897.219419105376, 897.7082448204327, 899.1098812175965, 900.7207765592655, 890.5769759364315, 897.0267260022883, 898.1826537850558, 900.2168113741021, 902.7427183252775, 654.2704884054245, 662.6421732264571, 669.5781657555709, 671.7346648073983, 673.0678010954863, 15154.0, 15168.393264269416, 15396.999195052285, 15427.5371738848, 15460.768088478682, 14586.635334116625, 14664.046836955564, 14696.779548306784, 14786.926065369711, 14840.332185051699, 11617.18679692422, 12054.578089766905, 12117.636470905887, 12119.272843629322, 12592.244465332109, 4655.185342565072, 4686.062787442525, 4710.269908164206, 4711.458182309044, 4860.343853579757, 79706.0, 103182.48408757983, 107324.4633776834, 112472.43763781211, 113910.43044784723, 10563.788724225626, 11002.0, 11175.944120279346, 11298.491567879466, 11430.542778288942, 20249.19003239854, 20918.0, 20945.790542094383, 20979.685304720442, 20989.0, 7442.627868606548, 7770.41721870862, 8013.6794528218215, 8109.635066422025, 8298.253157215879, 3886.7667939923695, 4367.759773212504, 4444.4888837784065, 4497.2804351304285, 4646.186917289461, 3542.5926018508203, 3612.3497770401054, 3654.9817250914107, 3668.834902429397, 3677.485152078711, 668.5687731413211, 674.8346655069539, 677.4986509982577, 688.3426327856885, 692.2281655953575, 581.9418058194146, 598.2342601470125, 605.4823126227012, 634.7590460649419, 644.9677516124175, 9307.162355387913, 9308.20880225175, 9574.760630984281, 9604.90395096054, 9795.657151999789, 557.6431084106175, 604.8971674815292, 617.3332800575367, 637.1971316141695, 656.5502630697987, 4563.657725670588, 4757.548032936935, 4778.331033655291, 4872.342233798392, 4900.5099490051425, 108408.0, 124091.3795431017, 126247.0, 129608.0, 130795.0, 112816.0, 124432.5113497725, 130019.0, 137632.0, 140599.29700351434, 88576.11423885936, 103257.48371258096, 108975.0, 109826.0, 114869.705136572, 9450.858237126586, 9842.212622990286, 9917.851232231378, 9918.950405247928, 9922.305438619516, 66154.67690646257, 66921.33078669346, 67169.0, 67577.66211168913, 68718.0, 96630.51684741532, 97281.0, 98698.0, 102143.97856021345, 104688.95311046792, 12834.0, 13224.0, 13386.732265354443, 13644.317784110954, 13985.021048526505, 14682.486112985995, 15284.770728439305, 15391.434262948207, 15607.687846243238, 15977.121258330439, 14239.288035598385, 14357.497487588302, 14373.856261437251, 14570.4900328488, 14579.34392952341, 13306.469755978187, 13662.863371366557, 14321.64195895111, 14382.28088595587, 14578.0, 13701.109427887155, 14003.369848357052, 14187.787183192055, 14251.714965700421, 14332.140071595737, 11041.723956901142, 11063.502142403771, 11290.43547822622, 12237.204581702517, 12308.876911230773, 11247.212695111592, 11557.497525321654, 11951.282923024646, 12025.218360806519, 12063.336516491678], "value": 7948.05361207909, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1541.8000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 117.0, 118.0, 119.0, 119.0, 119.0, 119.0, 118.0, 121.0, 119.0], "value": 117.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 109.0, 112.0, 145.0, 106.0, 113.0, 142.0, 142.0, 137.0, 113.0], "value": 125.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [224.0, 280.0, 197.0, 195.0, 194.0, 198.0, 198.0, 196.0, 193.0, 196.0], "value": 207.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 59.0, 60.0, 85.0, 60.0, 59.0, 60.0, 59.0, 78.0, 59.0], "value": 65.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 70.0, 88.0, 72.0, 68.0, 70.0, 87.0, 68.0, 72.0, 84.0], "value": 76.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 89.0, 91.0, 89.0, 93.0, 89.0, 93.0, 89.0, 92.0, 108.0], "value": 92.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 147.0, 118.0, 174.0, 165.0, 102.0, 135.0, 109.0, 172.0, 173.0], "value": 142.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 91.0, 91.0, 92.0, 116.0, 144.0, 120.0, 91.0, 123.0, 97.0], "value": 106.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 55.0, 54.0, 59.0, 58.0, 58.0, 58.0, 59.0, 59.0], "value": 56.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 68.0, 68.0, 83.0, 86.0, 105.0, 96.0, 60.0, 76.0, 98.0], "value": 81.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 81.0, 85.0, 79.0, 85.0, 85.0, 86.0, 89.0, 83.0], "value": 83.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 135.0, 144.0, 135.0, 137.0, 139.0, 148.0, 154.0, 199.0, 166.0], "value": 149.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 163.0, 162.0, 164.0, 163.0, 162.0, 165.0, 168.0, 161.0, 204.0], "value": 167.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 67.0, 66.0, 69.0, 80.0, 86.0, 65.0, 66.0, 66.0], "value": 69.9, "unit": "score"}]}]} 04:39:20 INFO - Return code: 0 04:39:20 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:39:21 ERROR - # TBPL SUCCESS # 04:39:21 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:39:21 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:39:21 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:39:21 INFO - 2016-12-21 04:39:21.020 screenresolution[1665:10644] starting screenresolution argv=screenresolution get 04:39:21 INFO - 2016-12-21 04:39:21.023 screenresolution[1665:10644] Display 0: 1600x1200x32@60 04:39:21 INFO - 2016-12-21 04:39:21.029 screenresolution[1666:10647] starting screenresolution argv=screenresolution list 04:39:21 INFO - Available Modes on Display 0 04:39:21 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:39:21 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:39:21 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:39:21 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:39:21 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:39:21 INFO - Intel Iris: 04:39:21 INFO - Chipset Model: Intel Iris 04:39:21 INFO - Type: GPU 04:39:21 INFO - Bus: Built-In 04:39:21 INFO - VRAM (Dynamic, Max): 1536 MB 04:39:21 INFO - Vendor: Intel (0x8086) 04:39:21 INFO - Device ID: 0x0a2e 04:39:21 INFO - Revision ID: 0x0009 04:39:21 INFO - Displays: 04:39:21 INFO - Display: 04:39:21 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:39:21 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:39:21 INFO - Display Serial Number: 1600x1200 60 04:39:21 INFO - Main Display: Yes 04:39:21 INFO - Mirror: Off 04:39:21 INFO - Online: Yes 04:39:21 INFO - Rotation: Supported 04:39:21 INFO - Return code: 0 04:39:21 INFO - Running post-action listener: _resource_record_post_action 04:39:21 INFO - [mozharness: 2016-12-21 12:39:21.264422Z] Finished run-tests step (success) 04:39:21 INFO - Running post-run listener: _resource_record_post_run 04:39:21 INFO - Total resource usage - Wall time: 1195s; CPU: 54.0%; Read bytes: 37438464; Write bytes: 498149888; Read time: 241; Write time: 1605 04:39:21 INFO - TinderboxPrint: CPU usage
54.3% 04:39:21 INFO - TinderboxPrint: I/O read bytes / time
37,438,464 / 241 04:39:21 INFO - TinderboxPrint: I/O write bytes / time
498,149,888 / 1,605 04:39:21 INFO - TinderboxPrint: CPU idle
2,185.5 (45.7%) 04:39:21 INFO - TinderboxPrint: CPU system
972.6 (20.3%) 04:39:21 INFO - TinderboxPrint: CPU user
1,623.5 (34.0%) 04:39:21 INFO - TinderboxPrint: Swap in / out
233,140,224 / 0 04:39:21 INFO - install - Wall time: 23s; CPU: 54.0%; Read bytes: 188217856; Write bytes: 185769984; Read time: 17525; Write time: 395 04:39:21 INFO - run-tests - Wall time: 1173s; CPU: 54.0%; Read bytes: 35681280; Write bytes: 301419008; Read time: 227; Write time: 1174 04:39:21 INFO - Running post-run listener: _upload_blobber_files 04:39:21 INFO - Blob upload gear active. 04:39:21 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:39:21 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 04:39:21 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'] 04:39:21 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 04:39:22 INFO - (blobuploader) - INFO - Open directory for files ... 04:39:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:39:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:39:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:39:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:39:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:39:23 INFO - (blobuploader) - INFO - Done attempting. 04:39:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:39:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:39:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:39:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 04:39:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:39:24 INFO - (blobuploader) - INFO - Done attempting. 04:39:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:39:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:39:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:39:26 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:39:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:39:26 INFO - (blobuploader) - INFO - Done attempting. 04:39:26 INFO - (blobuploader) - INFO - Iteration through files over. 04:39:26 INFO - Return code: 0 04:39:26 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:39:26 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:39:26 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f4150f2915ea186ab6c29286ad6096385c378fe9b5b83b6926ccb29a04c38a4998a0de48a2d4860c6a7450247ba87f0a8a9ac567947fceeb3e8903c9707e8812", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/704be594859d55d432b445a89c40b87d2b2f708dd74001a49315cfc5af351ad06314d37f05997c0facba052ee36571774ae1804f17a49c876990013f0ca24341", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/83b434db598afa282cb8aa4dff57e0eb90e00473e4da7bf3142586745a356fe8796df3761d7ecc887174b59124ca3654407506042eb8c107e7e53cefa840b996"} 04:39:26 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:39:26 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:39:26 INFO - Contents: 04:39:26 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f4150f2915ea186ab6c29286ad6096385c378fe9b5b83b6926ccb29a04c38a4998a0de48a2d4860c6a7450247ba87f0a8a9ac567947fceeb3e8903c9707e8812", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/704be594859d55d432b445a89c40b87d2b2f708dd74001a49315cfc5af351ad06314d37f05997c0facba052ee36571774ae1804f17a49c876990013f0ca24341", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/83b434db598afa282cb8aa4dff57e0eb90e00473e4da7bf3142586745a356fe8796df3761d7ecc887174b59124ca3654407506042eb8c107e7e53cefa840b996"} 04:39:26 INFO - Running post-run listener: copy_logs_to_upload_dir 04:39:26 INFO - Copying logs to upload dir... 04:39:26 INFO - mkdir: /builds/slave/test/build/upload/logs 04:39:26 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1257.567103 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2016-12-21 04:39:26.436325) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-21 04:39:26.439703) ========= 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.2IRsRbL2ew/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.2bRwBGBjyZ/Listeners TMPDIR=/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f4150f2915ea186ab6c29286ad6096385c378fe9b5b83b6926ccb29a04c38a4998a0de48a2d4860c6a7450247ba87f0a8a9ac567947fceeb3e8903c9707e8812", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/704be594859d55d432b445a89c40b87d2b2f708dd74001a49315cfc5af351ad06314d37f05997c0facba052ee36571774ae1804f17a49c876990013f0ca24341", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/83b434db598afa282cb8aa4dff57e0eb90e00473e4da7bf3142586745a356fe8796df3761d7ecc887174b59124ca3654407506042eb8c107e7e53cefa840b996"} build_url:https://queue.taskcluster.net/v1/task/bONzLSkdS--6b3itps_UDw/artifacts/public/build/firefox-51.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010890 build_url: 'https://queue.taskcluster.net/v1/task/bONzLSkdS--6b3itps_UDw/artifacts/public/build/firefox-51.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f4150f2915ea186ab6c29286ad6096385c378fe9b5b83b6926ccb29a04c38a4998a0de48a2d4860c6a7450247ba87f0a8a9ac567947fceeb3e8903c9707e8812", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/704be594859d55d432b445a89c40b87d2b2f708dd74001a49315cfc5af351ad06314d37f05997c0facba052ee36571774ae1804f17a49c876990013f0ca24341", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/83b434db598afa282cb8aa4dff57e0eb90e00473e4da7bf3142586745a356fe8796df3761d7ecc887174b59124ca3654407506042eb8c107e7e53cefa840b996"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-21 04:39:26.473502) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 04:39:26.473792) ========= 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.2IRsRbL2ew/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.2bRwBGBjyZ/Listeners TMPDIR=/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/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.005017 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 04:39:26.521753) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-21 04:39:26.522023) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-21 04:39:26.522297) ========= ========= Total master_lag: 0.18 =========