builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0100 starttime: 1481303713.39 results: success (0) buildid: 20161209065550 builduid: 0938eb48a0f54000a086d31563987bd0 revision: f72af5a4c1327d87a9eee376ff0f77f10191ff86 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-09 09:15:13.390340) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-09 09:15:13.390806) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-09 09:15:13.391106) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-09 09:15:13.422155) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-09 09:15:13.422547) ========= 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.5flTsvcXN8/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.S0JjKvJLC9/Listeners TMPDIR=/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/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-09 09:15:13-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 171M=0s 2016-12-09 09:15:13 (171 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.113465 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-09 09:15:13.560434) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-09 09:15:13.561191) ========= 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.5flTsvcXN8/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.S0JjKvJLC9/Listeners TMPDIR=/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/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.026773 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-09 09:15:13.610123) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-09 09:15:13.610483) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev f72af5a4c1327d87a9eee376ff0f77f10191ff86 --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 f72af5a4c1327d87a9eee376ff0f77f10191ff86 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.5flTsvcXN8/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.S0JjKvJLC9/Listeners TMPDIR=/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/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-09 09:15:13,706 truncating revision to first 12 chars 2016-12-09 09:15:13,706 Setting DEBUG logging. 2016-12-09 09:15:13,706 attempt 1/10 2016-12-09 09:15:13,706 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/f72af5a4c132?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-09 09:15:14,788 unpacking tar archive at: mozilla-beta-f72af5a4c132/testing/mozharness/ program finished with exit code 0 elapsedTime=1.359300 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-09 09:15:14.995516) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-09 09:15:14.998880) ========= script_repo_revision: f72af5a4c1327d87a9eee376ff0f77f10191ff86 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-09 09:15:14.999281) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-09 09:15:14.999559) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-09 09:15:15.019606) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2016-12-09 09:15:15.020004) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.5flTsvcXN8/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.S0JjKvJLC9/Listeners TMPDIR=/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/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 09:15:15 INFO - MultiFileLogger online at 20161209 09:15:15 in /builds/slave/test 09:15:15 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 09:15:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:15:15 INFO - {'append_to_log': False, 09:15:15 INFO - 'base_work_dir': '/builds/slave/test', 09:15:15 INFO - 'blob_upload_branch': 'Mozilla-Beta', 09:15:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:15:15 INFO - 'branch': 'Mozilla-Beta', 09:15:15 INFO - 'buildbot_json_path': 'buildprops.json', 09:15:15 INFO - 'config_files': ('talos/mac_config.py',), 09:15:15 INFO - 'default_actions': ('clobber', 09:15:15 INFO - 'read-buildbot-config', 09:15:15 INFO - 'download-and-extract', 09:15:15 INFO - 'populate-webroot', 09:15:15 INFO - 'create-virtualenv', 09:15:15 INFO - 'install', 09:15:15 INFO - 'run-tests'), 09:15:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:15:15 INFO - 'download_minidump_stackwalk': True, 09:15:15 INFO - 'download_symbols': 'ondemand', 09:15:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:15:15 INFO - 'tooltool.py': '/tools/tooltool.py', 09:15:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:15:15 INFO - '/tools/misc-python/virtualenv.py')}, 09:15:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:15:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:15:15 INFO - 'installer_path': 'installer.exe', 09:15:15 INFO - 'log_level': 'info', 09:15:15 INFO - 'log_name': 'talos', 09:15:15 INFO - 'log_to_console': True, 09:15:15 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:15:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:15:15 INFO - 'opt_config_files': (), 09:15:15 INFO - 'pip_index': False, 09:15:15 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:15:15 INFO - 'cmd': ('bash', 09:15:15 INFO - '-c', 09:15:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:15:15 INFO - 'enabled': True, 09:15:15 INFO - 'halt_on_failure': False, 09:15:15 INFO - 'name': 'check_screen_resolution'},), 09:15:15 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:15:15 INFO - 'cmd': ('bash', 09:15:15 INFO - '-c', 09:15:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:15:15 INFO - 'enabled': True, 09:15:15 INFO - 'halt_on_failure': False, 09:15:15 INFO - 'name': 'check_screen_resolution'},), 09:15:15 INFO - 'run_cmd_checks_enabled': True, 09:15:15 INFO - 'sps_profile': False, 09:15:15 INFO - 'sps_profile_interval': 0, 09:15:15 INFO - 'suite': 'dromaeojs', 09:15:15 INFO - 'system_bits': '32', 09:15:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:15:15 INFO - 'title': 't-yosemite-r7-0100', 09:15:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:15:15 INFO - 'use_talos_json': True, 09:15:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:15:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:15:15 INFO - 'work_dir': 'build'} 09:15:15 INFO - [mozharness: 2016-12-09 17:15:15.214944Z] Running clobber step. 09:15:15 INFO - Running pre-action listener: _resource_record_pre_action 09:15:15 INFO - Running main action method: clobber 09:15:15 INFO - rmtree: /builds/slave/test/build 09:15:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:15:17 INFO - Running post-action listener: _resource_record_post_action 09:15:17 INFO - [mozharness: 2016-12-09 17:15:17.624238Z] Finished clobber step (success) 09:15:17 INFO - [mozharness: 2016-12-09 17:15:17.624391Z] Running read-buildbot-config step. 09:15:17 INFO - Running pre-action listener: _resource_record_pre_action 09:15:17 INFO - Running main action method: read_buildbot_config 09:15:17 INFO - Using buildbot properties: 09:15:17 INFO - { 09:15:17 INFO - "project": "", 09:15:17 INFO - "product": "firefox", 09:15:17 INFO - "script_repo_revision": "production", 09:15:17 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 09:15:17 INFO - "repository": "", 09:15:17 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 09:15:17 INFO - "buildid": "20161209065550", 09:15:17 INFO - "pgo_build": "False", 09:15:17 INFO - "basedir": "/builds/slave/test", 09:15:17 INFO - "buildnumber": 5, 09:15:17 INFO - "slavename": "t-yosemite-r7-0100", 09:15:17 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 09:15:17 INFO - "platform": "yosemite_r7", 09:15:17 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 09:15:17 INFO - "branch": "mozilla-beta", 09:15:17 INFO - "repo_path": "releases/mozilla-beta", 09:15:17 INFO - "revision": "f72af5a4c1327d87a9eee376ff0f77f10191ff86", 09:15:17 INFO - "stage_platform": "macosx64", 09:15:17 INFO - "builduid": "0938eb48a0f54000a086d31563987bd0", 09:15:17 INFO - "slavebuilddir": "test" 09:15:17 INFO - } 09:15:17 INFO - Found installer url https://queue.taskcluster.net/v1/task/Glx4_IIuQ_Gs2GHLSKEL7Q/artifacts/public/build/firefox-51.0.en-US.mac.dmg. 09:15:17 INFO - Running post-action listener: _resource_record_post_action 09:15:17 INFO - [mozharness: 2016-12-09 17:15:17.628591Z] Finished read-buildbot-config step (success) 09:15:17 INFO - [mozharness: 2016-12-09 17:15:17.628737Z] Running download-and-extract step. 09:15:17 INFO - Running pre-action listener: _resource_record_pre_action 09:15:17 INFO - Running main action method: download_and_extract 09:15:17 INFO - mkdir: /builds/slave/test/build/tests 09:15:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:15:17 INFO - trying https://queue.taskcluster.net/v1/task/Glx4_IIuQ_Gs2GHLSKEL7Q/artifacts/public/build/firefox-51.0.en-US.mac.test_packages.json 09:15:17 INFO - Downloading https://queue.taskcluster.net/v1/task/Glx4_IIuQ_Gs2GHLSKEL7Q/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 09:15:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Glx4_IIuQ_Gs2GHLSKEL7Q/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 09:15:18 INFO - Downloaded 1165 bytes. 09:15:18 INFO - Reading from file /builds/slave/test/build/firefox-51.0.en-US.mac.test_packages.json 09:15:18 INFO - Using the following test package requirements: 09:15:18 INFO - {u'common': [u'firefox-51.0.en-US.mac.common.tests.zip'], 09:15:18 INFO - u'cppunittest': [u'firefox-51.0.en-US.mac.common.tests.zip', 09:15:18 INFO - u'firefox-51.0.en-US.mac.cppunittest.tests.zip'], 09:15:18 INFO - u'gtest': [u'firefox-51.0.en-US.mac.common.tests.zip', 09:15:18 INFO - u'firefox-51.0.en-US.mac.gtest.tests.zip'], 09:15:18 INFO - u'jittest': [u'firefox-51.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 09:15:18 INFO - u'mochitest': [u'firefox-51.0.en-US.mac.common.tests.zip', 09:15:18 INFO - u'firefox-51.0.en-US.mac.mochitest.tests.zip'], 09:15:18 INFO - u'mozbase': [u'firefox-51.0.en-US.mac.common.tests.zip'], 09:15:18 INFO - u'reftest': [u'firefox-51.0.en-US.mac.common.tests.zip', 09:15:18 INFO - u'firefox-51.0.en-US.mac.reftest.tests.zip'], 09:15:18 INFO - u'talos': [u'firefox-51.0.en-US.mac.common.tests.zip', 09:15:18 INFO - u'firefox-51.0.en-US.mac.talos.tests.zip'], 09:15:18 INFO - u'web-platform': [u'firefox-51.0.en-US.mac.common.tests.zip', 09:15:18 INFO - u'firefox-51.0.en-US.mac.web-platform.tests.zip'], 09:15:18 INFO - u'xpcshell': [u'firefox-51.0.en-US.mac.common.tests.zip', 09:15:18 INFO - u'firefox-51.0.en-US.mac.xpcshell.tests.zip']} 09:15:18 INFO - Downloading packages: [u'firefox-51.0.en-US.mac.common.tests.zip'] for test suite category: common 09:15:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Glx4_IIuQ_Gs2GHLSKEL7Q/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip 09:15:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Glx4_IIuQ_Gs2GHLSKEL7Q/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip'}, attempt #1 09:15:18 INFO - Fetch https://queue.taskcluster.net/v1/task/Glx4_IIuQ_Gs2GHLSKEL7Q/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip into memory 09:15:19 INFO - Expected file size: 16232529 09:15:26 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 09:15:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Glx4_IIuQ_Gs2GHLSKEL7Q/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip 09:15:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Glx4_IIuQ_Gs2GHLSKEL7Q/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip'}, attempt #1 09:15:26 INFO - Fetch https://queue.taskcluster.net/v1/task/Glx4_IIuQ_Gs2GHLSKEL7Q/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip into memory 09:15:27 INFO - Expected file size: 16232529 09:15:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Glx4_IIuQ_Gs2GHLSKEL7Q/artifacts/public/build/firefox-51.0.en-US.mac.talos.tests.zip 09:15:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Glx4_IIuQ_Gs2GHLSKEL7Q/artifacts/public/build/firefox-51.0.en-US.mac.talos.tests.zip'}, attempt #1 09:15:33 INFO - Fetch https://queue.taskcluster.net/v1/task/Glx4_IIuQ_Gs2GHLSKEL7Q/artifacts/public/build/firefox-51.0.en-US.mac.talos.tests.zip into memory 09:15:34 INFO - Expected file size: 14414089 09:15:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:15:34 INFO - trying https://queue.taskcluster.net/v1/task/Glx4_IIuQ_Gs2GHLSKEL7Q/artifacts/public/build/firefox-51.0.en-US.mac.dmg 09:15:34 INFO - Downloading https://queue.taskcluster.net/v1/task/Glx4_IIuQ_Gs2GHLSKEL7Q/artifacts/public/build/firefox-51.0.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0.en-US.mac.dmg 09:15:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Glx4_IIuQ_Gs2GHLSKEL7Q/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 09:15:37 INFO - Downloaded 86078535 bytes. 09:15:37 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Glx4_IIuQ_Gs2GHLSKEL7Q/artifacts/public/build/firefox-51.0.en-US.mac.dmg 09:15:37 INFO - mkdir: /builds/slave/test/properties 09:15:37 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:15:37 INFO - Writing to file /builds/slave/test/properties/build_url 09:15:37 INFO - Contents: 09:15:37 INFO - build_url:https://queue.taskcluster.net/v1/task/Glx4_IIuQ_Gs2GHLSKEL7Q/artifacts/public/build/firefox-51.0.en-US.mac.dmg 09:15:37 INFO - Running post-action listener: _resource_record_post_action 09:15:37 INFO - Running post-action listener: set_extra_try_arguments 09:15:37 INFO - [mozharness: 2016-12-09 17:15:37.774496Z] Finished download-and-extract step (success) 09:15:37 INFO - [mozharness: 2016-12-09 17:15:37.774646Z] Running populate-webroot step. 09:15:37 INFO - Running pre-action listener: _resource_record_pre_action 09:15:37 INFO - Running main action method: populate_webroot 09:15:37 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:15:37 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:15:37 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:15:37 INFO - '--tppagecycles', 09:15:37 INFO - '7'], 09:15:37 INFO - 'tests': ['tsvgm']}}, 09:15:37 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:15:37 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:15:37 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:15:37 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:15:37 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:15:37 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:15:37 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:15:37 INFO - 'talos_options': ['--disable-e10s'], 09:15:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:15:37 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:15:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:15:37 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:15:37 INFO - 'talos_options': ['--disable-e10s'], 09:15:37 INFO - 'tests': ['damp', 'tps']}, 09:15:37 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:15:37 INFO - 'tests': ['damp', 'tps']}, 09:15:37 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:15:37 INFO - 'tests': ['dromaeo_dom']}, 09:15:37 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:15:37 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:15:37 INFO - 'tests': ['basic_compositor_video']}, 09:15:37 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:15:37 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:15:37 INFO - 'tests': ['a11yr', 09:15:37 INFO - 'ts_paint', 09:15:37 INFO - 'tpaint', 09:15:37 INFO - 'sessionrestore', 09:15:37 INFO - 'sessionrestore_no_auto_restore', 09:15:37 INFO - 'tabpaint']}, 09:15:37 INFO - 'other-e10s': {'tests': ['a11yr', 09:15:37 INFO - 'ts_paint', 09:15:37 INFO - 'tpaint', 09:15:37 INFO - 'sessionrestore', 09:15:37 INFO - 'sessionrestore_no_auto_restore', 09:15:37 INFO - 'tabpaint']}, 09:15:37 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:15:37 INFO - 'ts_paint', 09:15:37 INFO - 'tpaint', 09:15:37 INFO - 'sessionrestore', 09:15:37 INFO - 'sessionrestore_no_auto_restore', 09:15:37 INFO - 'tabpaint']}, 09:15:37 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:15:37 INFO - 'ts_paint', 09:15:37 INFO - 'tpaint', 09:15:37 INFO - 'sessionrestore', 09:15:37 INFO - 'sessionrestore_no_auto_restore', 09:15:37 INFO - 'tabpaint']}, 09:15:37 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:15:37 INFO - 'tests': ['a11yr', 09:15:37 INFO - 'ts_paint', 09:15:37 INFO - 'tpaint', 09:15:37 INFO - 'sessionrestore', 09:15:37 INFO - 'sessionrestore_no_auto_restore', 09:15:37 INFO - 'tabpaint']}, 09:15:37 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:15:37 INFO - 'tests': ['a11yr', 09:15:37 INFO - 'ts_paint', 09:15:37 INFO - 'tpaint', 09:15:37 INFO - 'sessionrestore', 09:15:37 INFO - 'sessionrestore_no_auto_restore', 09:15:37 INFO - 'tabpaint']}, 09:15:37 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:15:37 INFO - 'tests': ['tsvgx', 09:15:37 INFO - 'tsvgr_opacity', 09:15:37 INFO - 'tart', 09:15:37 INFO - 'tscrollx', 09:15:37 INFO - 'cart']}, 09:15:37 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:15:37 INFO - 'tsvgr_opacity', 09:15:37 INFO - 'tart', 09:15:37 INFO - 'tscrollx', 09:15:37 INFO - 'cart']}, 09:15:37 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:15:37 INFO - 'talos_options': ['--disable-e10s'], 09:15:37 INFO - 'tests': ['tp5o']}, 09:15:37 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:15:37 INFO - 'tests': ['tp5o']}, 09:15:37 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:15:37 INFO - 'talos_options': ['--disable-e10s', 09:15:37 INFO - '--xperf_path', 09:15:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:15:37 INFO - 'tests': ['tp5n']}, 09:15:37 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:15:37 INFO - 'talos_options': ['--xperf_path', 09:15:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:15:37 INFO - 'tests': ['tp5n']}}, 09:15:37 INFO - 'talos.zip': {'path': '', 09:15:37 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:15:37 INFO - Running post-action listener: _resource_record_post_action 09:15:37 INFO - [mozharness: 2016-12-09 17:15:37.793767Z] Finished populate-webroot step (success) 09:15:37 INFO - [mozharness: 2016-12-09 17:15:37.793898Z] Running create-virtualenv step. 09:15:37 INFO - Running pre-action listener: _resource_record_pre_action 09:15:37 INFO - Running main action method: create_virtualenv 09:15:37 INFO - Creating virtualenv /builds/slave/test/build/venv 09:15:37 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 09:15:37 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:15:37 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:15:37 INFO - Using real prefix '/tools/python27' 09:15:37 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:15:38 INFO - Installing distribute.............................................................................................................................................................................................done. 09:15:41 INFO - Installing pip.................done. 09:15:41 INFO - Return code: 0 09:15:41 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:15:41 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')]} 09:15:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:15:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:41 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')]} 09:15:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:15:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:41 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 0x1085d5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10854b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8424c62f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083e9d30>, '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 0x10824fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1083e1b20>, '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.5flTsvcXN8/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.S0JjKvJLC9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/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 09:15:41 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 09:15:41 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 09:15:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5flTsvcXN8/Render', 09:15:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:15:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:15:41 INFO - 'HOME': '/Users/cltbld', 09:15:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:15:41 INFO - 'LOGNAME': 'cltbld', 09:15:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:15:41 INFO - 'MOZ_NO_REMOTE': '1', 09:15:41 INFO - 'NO_EM_RESTART': '1', 09:15:41 INFO - 'PAGER': '/bin/cat', 09:15:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:15:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:15:41 INFO - 'PWD': '/builds/slave/test', 09:15:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:15:41 INFO - 'SHELL': '/bin/bash', 09:15:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S0JjKvJLC9/Listeners', 09:15:41 INFO - 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/', 09:15:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:15:41 INFO - 'USER': 'cltbld', 09:15:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:15:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:15:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:15:41 INFO - 'XPC_FLAGS': '0x0', 09:15:41 INFO - 'XPC_SERVICE_NAME': '0', 09:15:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:15:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:15:41 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 09:15:41 INFO - Cleaning up... 09:15:41 INFO - Return code: 0 09:15:41 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:15:41 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')]} 09:15:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:15:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:41 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')]} 09:15:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:15:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:41 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 0x1085d5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10854b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8424c62f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083e9d30>, '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 0x10824fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1083e1b20>, '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.5flTsvcXN8/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.S0JjKvJLC9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/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 09:15:41 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 09:15:41 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 09:15:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5flTsvcXN8/Render', 09:15:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:15:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:15:41 INFO - 'HOME': '/Users/cltbld', 09:15:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:15:41 INFO - 'LOGNAME': 'cltbld', 09:15:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:15:41 INFO - 'MOZ_NO_REMOTE': '1', 09:15:41 INFO - 'NO_EM_RESTART': '1', 09:15:41 INFO - 'PAGER': '/bin/cat', 09:15:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:15:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:15:41 INFO - 'PWD': '/builds/slave/test', 09:15:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:15:41 INFO - 'SHELL': '/bin/bash', 09:15:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S0JjKvJLC9/Listeners', 09:15:41 INFO - 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/', 09:15:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:15:41 INFO - 'USER': 'cltbld', 09:15:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:15:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:15:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:15:41 INFO - 'XPC_FLAGS': '0x0', 09:15:41 INFO - 'XPC_SERVICE_NAME': '0', 09:15:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:15:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:15:41 INFO - Downloading/unpacking psutil>=3.1.1 09:15:41 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 09:15:41 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 09:15:41 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 09:15:41 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 09:15:41 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 09:15:41 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 09:15:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:15:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:15:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:15:45 INFO - Installing collected packages: psutil 09:15:45 INFO - Running setup.py install for psutil 09:15:45 INFO - building 'psutil._psutil_osx' extension 09:15:45 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 09:15:45 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 09:15:45 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 09:15:45 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 09:15:46 INFO - building 'psutil._psutil_posix' extension 09:15:46 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 09:15:46 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:15:46 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:15:46 INFO - ^ 09:15:46 INFO - 1 warning generated. 09:15:46 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 09:15:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:15:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:15:46 INFO - Successfully installed psutil 09:15:46 INFO - Cleaning up... 09:15:46 INFO - Return code: 0 09:15:46 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:15:46 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')]} 09:15:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:15:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:46 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')]} 09:15:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:15:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:46 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 0x1085d5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10854b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8424c62f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083e9d30>, '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 0x10824fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1083e1b20>, '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.5flTsvcXN8/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.S0JjKvJLC9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/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 09:15:46 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 09:15:46 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 09:15:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5flTsvcXN8/Render', 09:15:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:15:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:15:46 INFO - 'HOME': '/Users/cltbld', 09:15:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:15:46 INFO - 'LOGNAME': 'cltbld', 09:15:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:15:46 INFO - 'MOZ_NO_REMOTE': '1', 09:15:46 INFO - 'NO_EM_RESTART': '1', 09:15:46 INFO - 'PAGER': '/bin/cat', 09:15:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:15:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:15:46 INFO - 'PWD': '/builds/slave/test', 09:15:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:15:46 INFO - 'SHELL': '/bin/bash', 09:15:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S0JjKvJLC9/Listeners', 09:15:46 INFO - 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/', 09:15:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:15:46 INFO - 'USER': 'cltbld', 09:15:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:15:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:15:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:15:46 INFO - 'XPC_FLAGS': '0x0', 09:15:46 INFO - 'XPC_SERVICE_NAME': '0', 09:15:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:15:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:15:46 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:15:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:15:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:15:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:15:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:15:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:15:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:15:49 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:15:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:15:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:15:49 INFO - Installing collected packages: mozsystemmonitor 09:15:49 INFO - Running setup.py install for mozsystemmonitor 09:15:49 INFO - Successfully installed mozsystemmonitor 09:15:49 INFO - Cleaning up... 09:15:49 INFO - Return code: 0 09:15:49 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:15:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:15:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:15:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:15:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:15:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:49 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 0x1085d5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10854b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8424c62f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083e9d30>, '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 0x10824fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1083e1b20>, '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.5flTsvcXN8/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.S0JjKvJLC9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/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 09:15:49 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 09:15:49 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 09:15:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5flTsvcXN8/Render', 09:15:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:15:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:15:49 INFO - 'HOME': '/Users/cltbld', 09:15:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:15:49 INFO - 'LOGNAME': 'cltbld', 09:15:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:15:49 INFO - 'MOZ_NO_REMOTE': '1', 09:15:49 INFO - 'NO_EM_RESTART': '1', 09:15:49 INFO - 'PAGER': '/bin/cat', 09:15:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:15:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:15:49 INFO - 'PWD': '/builds/slave/test', 09:15:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:15:49 INFO - 'SHELL': '/bin/bash', 09:15:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S0JjKvJLC9/Listeners', 09:15:49 INFO - 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/', 09:15:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:15:49 INFO - 'USER': 'cltbld', 09:15:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:15:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:15:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:15:49 INFO - 'XPC_FLAGS': '0x0', 09:15:49 INFO - 'XPC_SERVICE_NAME': '0', 09:15:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:15:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:15:49 INFO - Downloading/unpacking jsonschema==2.5.1 09:15:49 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 09:15:49 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 09:15:49 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 09:15:49 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 09:15:49 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 09:15:49 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 09:15:52 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:15:52 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:15:52 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 09:15:52 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 09:15:52 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 09:15:52 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 09:15:52 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 09:15:52 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 09:15:52 INFO - Downloading functools32-3.2.3-2.tar.gz 09:15:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:15:52 INFO - warning: no files found matching '*.txt' 09:15:52 INFO - no previously-included directories found matching 'build' 09:15:52 INFO - no previously-included directories found matching 'dist' 09:15:52 INFO - no previously-included directories found matching '.git*' 09:15:52 INFO - Installing collected packages: jsonschema, functools32 09:15:52 INFO - Running setup.py install for functools32 09:15:53 INFO - warning: no files found matching '*.txt' 09:15:53 INFO - no previously-included directories found matching 'build' 09:15:53 INFO - no previously-included directories found matching 'dist' 09:15:53 INFO - no previously-included directories found matching '.git*' 09:15:53 INFO - Successfully installed jsonschema functools32 09:15:53 INFO - Cleaning up... 09:15:53 INFO - Return code: 0 09:15:53 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:15:53 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')]} 09:15:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:15:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:53 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')]} 09:15:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:15:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:53 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 0x1085d5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10854b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8424c62f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083e9d30>, '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 0x10824fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1083e1b20>, '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.5flTsvcXN8/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.S0JjKvJLC9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/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 09:15:53 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 09:15:53 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 09:15:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5flTsvcXN8/Render', 09:15:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:15:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:15:53 INFO - 'HOME': '/Users/cltbld', 09:15:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:15:53 INFO - 'LOGNAME': 'cltbld', 09:15:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:15:53 INFO - 'MOZ_NO_REMOTE': '1', 09:15:53 INFO - 'NO_EM_RESTART': '1', 09:15:53 INFO - 'PAGER': '/bin/cat', 09:15:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:15:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:15:53 INFO - 'PWD': '/builds/slave/test', 09:15:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:15:53 INFO - 'SHELL': '/bin/bash', 09:15:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S0JjKvJLC9/Listeners', 09:15:53 INFO - 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/', 09:15:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:15:53 INFO - 'USER': 'cltbld', 09:15:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:15:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:15:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:15:53 INFO - 'XPC_FLAGS': '0x0', 09:15:53 INFO - 'XPC_SERVICE_NAME': '0', 09:15:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:15:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:15:53 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:15:53 INFO - Cleaning up... 09:15:53 INFO - Return code: 0 09:15:53 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:15:53 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')]} 09:15:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:15:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:53 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')]} 09:15:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:15:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:53 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 0x1085d5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10854b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8424c62f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083e9d30>, '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 0x10824fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1083e1b20>, '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.5flTsvcXN8/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.S0JjKvJLC9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/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 09:15:53 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 09:15:53 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 09:15:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5flTsvcXN8/Render', 09:15:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:15:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:15:53 INFO - 'HOME': '/Users/cltbld', 09:15:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:15:53 INFO - 'LOGNAME': 'cltbld', 09:15:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:15:53 INFO - 'MOZ_NO_REMOTE': '1', 09:15:53 INFO - 'NO_EM_RESTART': '1', 09:15:53 INFO - 'PAGER': '/bin/cat', 09:15:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:15:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:15:53 INFO - 'PWD': '/builds/slave/test', 09:15:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:15:53 INFO - 'SHELL': '/bin/bash', 09:15:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S0JjKvJLC9/Listeners', 09:15:53 INFO - 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/', 09:15:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:15:53 INFO - 'USER': 'cltbld', 09:15:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:15:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:15:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:15:53 INFO - 'XPC_FLAGS': '0x0', 09:15:53 INFO - 'XPC_SERVICE_NAME': '0', 09:15:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:15:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:15:53 INFO - Downloading/unpacking blobuploader==1.2.4 09:15:53 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 09:15:53 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 09:15:53 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 09:15:53 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 09:15:53 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 09:15:53 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 09:15:56 INFO - Downloading blobuploader-1.2.4.tar.gz 09:15:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:15:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:15:56 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 09:15:56 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 09:15:56 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 09:15:56 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 09:15:56 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 09:15:56 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 09:15:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:15:56 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:15:56 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 09:15:56 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 09:15:56 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 09:15:56 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 09:15:56 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 09:15:56 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 09:15:57 INFO - Downloading docopt-0.6.1.tar.gz 09:15:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:15:57 INFO - Installing collected packages: blobuploader, requests, docopt 09:15:57 INFO - Running setup.py install for blobuploader 09:15:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:15:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:15:57 INFO - Running setup.py install for requests 09:15:58 INFO - Running setup.py install for docopt 09:15:58 INFO - Successfully installed blobuploader requests docopt 09:15:58 INFO - Cleaning up... 09:15:58 INFO - Return code: 0 09:15:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:15:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:15:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:15:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:15:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:15:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:58 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 0x1085d5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10854b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8424c62f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083e9d30>, '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 0x10824fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1083e1b20>, '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.5flTsvcXN8/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.S0JjKvJLC9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/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 09:15:58 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 09:15:58 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 09:15:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5flTsvcXN8/Render', 09:15:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:15:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:15:58 INFO - 'HOME': '/Users/cltbld', 09:15:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:15:58 INFO - 'LOGNAME': 'cltbld', 09:15:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:15:58 INFO - 'MOZ_NO_REMOTE': '1', 09:15:58 INFO - 'NO_EM_RESTART': '1', 09:15:58 INFO - 'PAGER': '/bin/cat', 09:15:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:15:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:15:58 INFO - 'PWD': '/builds/slave/test', 09:15:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:15:58 INFO - 'SHELL': '/bin/bash', 09:15:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S0JjKvJLC9/Listeners', 09:15:58 INFO - 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/', 09:15:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:15:58 INFO - 'USER': 'cltbld', 09:15:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:15:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:15:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:15:58 INFO - 'XPC_FLAGS': '0x0', 09:15:58 INFO - 'XPC_SERVICE_NAME': '0', 09:15:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:15:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:15:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:15:58 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-UAcvOF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:15:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:15:58 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-V34pMT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:15:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:15:58 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-HWQUeF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:15:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:15:58 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-hg9KcJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:15:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:15:59 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-1EJQxE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:15:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:15:59 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-N8NcpC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:15:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:15:59 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-tDkLyP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:15:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:15:59 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-xFVKEX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:15:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:15:59 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-0wKfZh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:15:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:15:59 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-S913BH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:15:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:15:59 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-KqCI0J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:15:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:15:59 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-6x_Z_9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:15:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:15:59 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-83iPiZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:16:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:16:00 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-_iEEZL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:16:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:16:00 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-qhwxQQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:16:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:16:00 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-ZaYqqw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:16:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:16:00 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-CA1Npr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:16:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:16:00 INFO - Running setup.py install for manifestparser 09:16:00 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:16:00 INFO - Running setup.py install for mozcrash 09:16:00 INFO - Running setup.py install for mozdebug 09:16:00 INFO - Running setup.py install for mozdevice 09:16:01 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:16:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:16:01 INFO - Running setup.py install for mozfile 09:16:01 INFO - Running setup.py install for mozhttpd 09:16:01 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:16:01 INFO - Running setup.py install for mozinfo 09:16:01 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:16:01 INFO - Running setup.py install for mozInstall 09:16:01 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:16:01 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:16:01 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:16:01 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:16:01 INFO - Running setup.py install for mozleak 09:16:01 INFO - Running setup.py install for mozlog 09:16:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:16:01 INFO - Running setup.py install for moznetwork 09:16:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:16:02 INFO - Running setup.py install for mozprocess 09:16:02 INFO - Running setup.py install for mozprofile 09:16:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:16:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:16:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:16:02 INFO - Running setup.py install for mozrunner 09:16:02 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:16:02 INFO - Running setup.py install for mozscreenshot 09:16:02 INFO - Running setup.py install for moztest 09:16:02 INFO - Running setup.py install for mozversion 09:16:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:16:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:16:03 INFO - Cleaning up... 09:16:03 INFO - Return code: 0 09:16:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:16:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:03 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 0x1085d5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10854b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8424c62f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083e9d30>, '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 0x10824fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1083e1b20>, '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.5flTsvcXN8/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.S0JjKvJLC9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/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 09:16:03 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 09:16:03 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 09:16:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5flTsvcXN8/Render', 09:16:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:16:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:16:03 INFO - 'HOME': '/Users/cltbld', 09:16:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:16:03 INFO - 'LOGNAME': 'cltbld', 09:16:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:03 INFO - 'MOZ_NO_REMOTE': '1', 09:16:03 INFO - 'NO_EM_RESTART': '1', 09:16:03 INFO - 'PAGER': '/bin/cat', 09:16:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:16:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:03 INFO - 'PWD': '/builds/slave/test', 09:16:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:16:03 INFO - 'SHELL': '/bin/bash', 09:16:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S0JjKvJLC9/Listeners', 09:16:03 INFO - 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/', 09:16:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:16:03 INFO - 'USER': 'cltbld', 09:16:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:16:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:16:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:03 INFO - 'XPC_FLAGS': '0x0', 09:16:03 INFO - 'XPC_SERVICE_NAME': '0', 09:16:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:16:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:16:03 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-O4j4nT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:16:03 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)) 09:16:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:16:03 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-TEK961-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:16:03 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)) 09:16:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:16:03 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-1YvEd_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:16:03 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)) 09:16:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:16:03 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-tMPdkh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:16:03 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)) 09:16:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:16:03 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-OZFidP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:16:03 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)) 09:16:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:16:03 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-F9GEgC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:16:03 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)) 09:16:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:16:03 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-5rAMdp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:16:04 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)) 09:16:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:16:04 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-R2zHBQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:16:04 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)) 09:16:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:16:04 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-1nVTYU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:16:04 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)) 09:16:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:16:04 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-rzLBQC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:16:04 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)) 09:16:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:16:04 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-USJ2e9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:16:04 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)) 09:16:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:16:04 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-FKmXhu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:16:04 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)) 09:16:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:16:04 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-ScbGYJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:16:04 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)) 09:16:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:16:04 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-84weSb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:16:04 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)) 09:16:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:16:04 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-Q2N0Bm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:16:04 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)) 09:16:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:16:04 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-bUV6eq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:16:05 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)) 09:16:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:16:05 INFO - Running setup.py (path:/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/pip-IotNZk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:16:05 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)) 09:16:05 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)) 09:16:05 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)) 09:16:05 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)) 09:16:05 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)) 09:16:05 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)) 09:16:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:16: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 09:16: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 09:16: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 09:16: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 09:16: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 09:16: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 09:16:07 INFO - Downloading blessings-1.6.tar.gz 09:16:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:16:07 INFO - Installing collected packages: blessings 09:16:07 INFO - Running setup.py install for blessings 09:16:07 INFO - Successfully installed blessings 09:16:07 INFO - Cleaning up... 09:16:08 INFO - Return code: 0 09:16:08 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:16:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:16:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:16:08 INFO - Reading from file tmpfile_stdout 09:16:08 INFO - Current package versions: 09:16:08 INFO - blessings == 1.6 09:16:08 INFO - blobuploader == 1.2.4 09:16:08 INFO - docopt == 0.6.1 09:16:08 INFO - functools32 == 3.2.3-2 09:16:08 INFO - jsonschema == 2.5.1 09:16:08 INFO - manifestparser == 1.1 09:16:08 INFO - mozInstall == 1.12 09:16:08 INFO - mozcrash == 0.17 09:16:08 INFO - mozdebug == 0.1 09:16:08 INFO - mozdevice == 0.48 09:16:08 INFO - mozfile == 1.2 09:16:08 INFO - mozhttpd == 0.7 09:16:08 INFO - mozinfo == 0.9 09:16:08 INFO - mozleak == 0.1 09:16:08 INFO - mozlog == 3.4 09:16:08 INFO - moznetwork == 0.27 09:16:08 INFO - mozprocess == 0.23 09:16:08 INFO - mozprofile == 0.28 09:16:08 INFO - mozrunner == 6.12 09:16:08 INFO - mozscreenshot == 0.1 09:16:08 INFO - mozsystemmonitor == 0.3 09:16:08 INFO - moztest == 0.7 09:16:08 INFO - mozversion == 1.4 09:16:08 INFO - psutil == 3.1.1 09:16:08 INFO - requests == 1.2.3 09:16:08 INFO - wsgiref == 0.1.2 09:16:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:16:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:08 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 0x1085d5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10854b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8424c62f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083e9d30>, '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 0x10824fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1083e1b20>, '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.5flTsvcXN8/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.S0JjKvJLC9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/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 09:16:08 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 09:16:08 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 09:16:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5flTsvcXN8/Render', 09:16:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:16:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:16:08 INFO - 'HOME': '/Users/cltbld', 09:16:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:16:08 INFO - 'LOGNAME': 'cltbld', 09:16:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:08 INFO - 'MOZ_NO_REMOTE': '1', 09:16:08 INFO - 'NO_EM_RESTART': '1', 09:16:08 INFO - 'PAGER': '/bin/cat', 09:16:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:16:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:08 INFO - 'PWD': '/builds/slave/test', 09:16:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:16:08 INFO - 'SHELL': '/bin/bash', 09:16:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S0JjKvJLC9/Listeners', 09:16:08 INFO - 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/', 09:16:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:16:08 INFO - 'USER': 'cltbld', 09:16:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:16:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:16:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:08 INFO - 'XPC_FLAGS': '0x0', 09:16:08 INFO - 'XPC_SERVICE_NAME': '0', 09:16:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:16:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:08 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)) 09:16:08 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)) 09:16:08 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)) 09:16:08 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)) 09:16:08 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)) 09:16:08 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)) 09:16:08 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)) 09:16:08 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)) 09:16:08 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)) 09:16:08 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)) 09:16:08 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)) 09:16:08 INFO - Cleaning up... 09:16:08 INFO - Return code: 0 09:16:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:16:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:08 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 0x1085d5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10854b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc8424c62f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083e9d30>, '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 0x10824fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1083e1b20>, '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.5flTsvcXN8/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.S0JjKvJLC9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/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 09:16:08 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 09:16:08 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 09:16:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5flTsvcXN8/Render', 09:16:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:16:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:16:08 INFO - 'HOME': '/Users/cltbld', 09:16:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:16:08 INFO - 'LOGNAME': 'cltbld', 09:16:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:08 INFO - 'MOZ_NO_REMOTE': '1', 09:16:08 INFO - 'NO_EM_RESTART': '1', 09:16:08 INFO - 'PAGER': '/bin/cat', 09:16:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:16:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:08 INFO - 'PWD': '/builds/slave/test', 09:16:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:16:08 INFO - 'SHELL': '/bin/bash', 09:16:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S0JjKvJLC9/Listeners', 09:16:08 INFO - 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/', 09:16:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:16:08 INFO - 'USER': 'cltbld', 09:16:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:16:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:16:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:08 INFO - 'XPC_FLAGS': '0x0', 09:16:08 INFO - 'XPC_SERVICE_NAME': '0', 09:16:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:16:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:09 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:16:09 INFO - Cleaning up... 09:16:09 INFO - Return code: 0 09:16:09 INFO - Running post-action listener: _resource_record_post_action 09:16:09 INFO - Running post-action listener: _start_resource_monitoring 09:16:09 INFO - Starting resource monitoring. 09:16:09 INFO - [mozharness: 2016-12-09 17:16:09.221271Z] Finished create-virtualenv step (success) 09:16:09 INFO - [mozharness: 2016-12-09 17:16:09.222181Z] Running install step. 09:16:09 INFO - Running pre-action listener: _resource_record_pre_action 09:16:09 INFO - Running main action method: install 09:16:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:16:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:16:09 INFO - Reading from file tmpfile_stdout 09:16:09 INFO - Detecting whether we're running mozinstall >=1.0... 09:16:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:16:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:16:09 INFO - Reading from file tmpfile_stdout 09:16:09 INFO - Output received: 09:16:09 INFO - Usage: mozinstall [options] installer 09:16:09 INFO - Options: 09:16:09 INFO - -h, --help show this help message and exit 09:16:09 INFO - -d DEST, --destination=DEST 09:16:09 INFO - Directory to install application into. [default: 09:16:09 INFO - "/builds/slave/test"] 09:16:09 INFO - --app=APP Application being installed. [default: firefox] 09:16:09 INFO - mkdir: /builds/slave/test/build/application 09:16:09 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'] 09:16:09 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 09:16:31 INFO - Reading from file tmpfile_stdout 09:16:31 INFO - Output received: 09:16:31 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 09:16:31 INFO - Running post-action listener: _resource_record_post_action 09:16:31 INFO - [mozharness: 2016-12-09 17:16:31.282080Z] Finished install step (success) 09:16:31 INFO - [mozharness: 2016-12-09 17:16:31.282239Z] Running run-tests step. 09:16:31 INFO - Running pre-action listener: _resource_record_pre_action 09:16:31 INFO - Running main action method: run_tests 09:16:31 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:16:31 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:16:31 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:16:31 INFO - 2016-12-09 09:16:31.296 screenresolution[1628:7481] starting screenresolution argv=screenresolution get 09:16:31 INFO - 2016-12-09 09:16:31.299 screenresolution[1628:7481] Display 0: 1600x1200x32@60 09:16:31 INFO - 2016-12-09 09:16:31.305 screenresolution[1629:7484] starting screenresolution argv=screenresolution list 09:16:31 INFO - Available Modes on Display 0 09:16:31 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:16:31 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:16:31 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:16:31 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:16:31 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:16:31 INFO - Intel Iris: 09:16:31 INFO - Chipset Model: Intel Iris 09:16:31 INFO - Type: GPU 09:16:31 INFO - Bus: Built-In 09:16:31 INFO - VRAM (Dynamic, Max): 1536 MB 09:16:31 INFO - Vendor: Intel (0x8086) 09:16:31 INFO - Device ID: 0x0a2e 09:16:31 INFO - Revision ID: 0x0009 09:16:31 INFO - Displays: 09:16:31 INFO - Display: 09:16:31 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:16:31 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:16:31 INFO - Display Serial Number: 1600x1200 60 09:16:31 INFO - Main Display: Yes 09:16:31 INFO - Mirror: Off 09:16:31 INFO - Online: Yes 09:16:31 INFO - Rotation: Supported 09:16:31 INFO - Return code: 0 09:16:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:16:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:16:31 INFO - Python 2.7.3 09:16:31 INFO - Return code: 0 09:16:31 INFO - grabbing minidump binary from tooltool 09:16: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')]} 09:16:31 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 0x1083e9d30>, '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 0x10824fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1083e1b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:16:31 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 09:16:31 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 09:16:31 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 09:16:31 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:16:31 INFO - Return code: 0 09:16:31 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:16:31 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:16:31 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:16:31 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:16:31 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:16:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Glx4_IIuQ_Gs2GHLSKEL7Q/artifacts/public/build/firefox-51.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0100', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:16:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Glx4_IIuQ_Gs2GHLSKEL7Q/artifacts/public/build/firefox-51.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0100 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:16:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5flTsvcXN8/Render', 09:16:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:16:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:16:31 INFO - 'HOME': '/Users/cltbld', 09:16:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:16:31 INFO - 'LOGNAME': 'cltbld', 09:16:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:16:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:16:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:31 INFO - 'MOZ_NO_REMOTE': '1', 09:16:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:16:31 INFO - 'NO_EM_RESTART': '1', 09:16:31 INFO - 'PAGER': '/bin/cat', 09:16:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:16:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:31 INFO - 'PWD': '/builds/slave/test', 09:16:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:16:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:16:31 INFO - 'SHELL': '/bin/bash', 09:16:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S0JjKvJLC9/Listeners', 09:16:31 INFO - 'TMPDIR': '/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/', 09:16:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:16:31 INFO - 'USER': 'cltbld', 09:16:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:16:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:16:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:31 INFO - 'XPC_FLAGS': '0x0', 09:16:31 INFO - 'XPC_SERVICE_NAME': '0', 09:16:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:16:31 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Glx4_IIuQ_Gs2GHLSKEL7Q/artifacts/public/build/firefox-51.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0100', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:16:31 INFO - mozversion application_buildid: 20161209065550 09:16:31 INFO - mozversion application_changeset: f72af5a4c1327d87a9eee376ff0f77f10191ff86 09:16:31 INFO - mozversion application_display_name: Firefox 09:16:31 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:16:31 INFO - mozversion application_name: Firefox 09:16:31 INFO - mozversion application_remotingname: firefox 09:16:31 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 09:16:31 INFO - mozversion application_vendor: Mozilla 09:16:31 INFO - mozversion application_version: 51.0 09:16:31 INFO - mozversion platform_buildid: 20161209065550 09:16:31 INFO - mozversion platform_changeset: f72af5a4c1327d87a9eee376ff0f77f10191ff86 09:16:31 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 09:16:31 INFO - mozversion platform_version: 51.0 09:16:31 INFO - using testdate: 1481303791 09:16:31 INFO - actual date: 1481303791 09:16:31 INFO - starting webserver on 'localhost:49242' 09:16:31 INFO - SUITE-START | Running 2 tests 09:16:31 INFO - TEST-START | dromaeo_css 09:16:31 INFO - operating with platform_type : mac_ 09:16:31 INFO - Initialising browser for dromaeo_css test... 09:16:31 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/tmpXbZkVV/profile http://localhost:49242/getInfo.html) 09:16:34 INFO - PROCESS | 1639 | __metrics Screen width/height:1600/1200 09:16:34 INFO - PROCESS | 1639 | colorDepth:24 09:16:34 INFO - PROCESS | 1639 | Browser inner width/height: 1024/689 09:16:34 INFO - PROCESS | 1639 | __metrics 09:16:34 INFO - PROCESS | 1639 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:16:34 INFO - PROCESS | 1639 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:16:35 INFO - TEST-INFO | 1639: exit 0 09:16:35 INFO - Browser initialized. 09:16:35 INFO - Running cycle 1/1 for dromaeo_css test... 09:16:35 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/tmpXbZkVV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:16:46 INFO - PROCESS | 1642 | RSS: Main: 169144320 09:16:46 INFO - PROCESS | 1642 | 09:17:38 INFO - PROCESS | 1642 | 1481303858459 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] 09:19:48 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49242/tests/dromaeo/cssquery-ext.html) 09:19:50 INFO - PROCESS | 1642 | RSS: Main: 324243456 09:19:50 INFO - PROCESS | 1642 | 09:22:53 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-ext.html (next: http://localhost:49242/tests/dromaeo/cssquery-jquery.html) 09:22:53 INFO - PROCESS | 1642 | RSS: Main: 301854720 09:22:53 INFO - PROCESS | 1642 | 09:25:56 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49242/tests/dromaeo/cssquery-mootools.html) 09:25:56 INFO - PROCESS | 1642 | RSS: Main: 270311424 09:25:56 INFO - PROCESS | 1642 | 09:28:58 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49242/tests/dromaeo/cssquery-prototype.html) 09:28:59 INFO - PROCESS | 1642 | RSS: Main: 271360000 09:28:59 INFO - PROCESS | 1642 | 09:32:02 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49242/tests/dromaeo/cssquery-yui.html) 09:32:03 INFO - PROCESS | 1642 | RSS: Main: 259387392 09:32:03 INFO - PROCESS | 1642 | 09:35:06 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-yui.html (next: http://localhost:49242/tests/dromaeo/cssquery-dojo.html) 09:35:06 INFO - PROCESS | 1642 | RSS: Main: 376037376 09:35:06 INFO - PROCESS | 1642 | 09:35:06 INFO - PROCESS | 1642 | __start_tp_report 09:35:06 INFO - PROCESS | 1642 | _x_x_mozilla_page_load 09:35:06 INFO - PROCESS | 1642 | _x_x_mozilla_page_load_details 09:35:06 INFO - PROCESS | 1642 | |i|pagename|runs| 09:35:06 INFO - PROCESS | 1642 | |0;dojo.html;3063.1576316512965;3489.738269629777;3521.1197200699808;3523.995661236548;3572.1962558424357;5782.624129431584;5865.794697185599;5882.764689412429;5933.110033494978;5990.520758339333;4771.737554434513;5616.129499927508;5670.6957399798075;5840.3575606683235;5901.704914754267;4401.845935392262;5336.786528538853;5344;5346.625736198468;5390.380106287768;5287.762050707727;5292.708901010443;5423.620346575742;5437.9939711153565;5466.480684334982;5283.28675628789;5347.545458636001;5385.919211211835;5412.891742165154;5429.212764149196;5367.651102678314;5376.91934620981;5380.488853558905;5434.049041417776;5460.590455715797;5311.123664595337;5355.25026496287;5368.382635996872;5370.5435038021815;5392.487713667196;4761;5722.8268205018285;5724.8855022899725;5777.162311464814;5806.82579522615;6184.969075154595;6253.218347706536;6316.873662526724;6325.715342809539;6349.904751428732;4894.1679914414635;5389.566530255462;5717.256756621613;6287.1198032275515;6389.8402539936405;5798.188253644493;5848.122781582797;5858.238429004202;5869.148973398833;5907.438793314628;6310.716017779211;6393.104965304861;6394.488440924715;6415.775447859302;6416.133821934072;6298.653574053379;6306.1171435998995;6319.24169099711;6322.36776322364;6343.397377249154;6211.937880621136;6270.561060725706;6279.654618995908;6331.018692102732;6361.077643741632;6289.0880822280515;6339.936600633934;6365.013422919455;6369.458596019297;6384.457321127663;6302.1177035215105;6347.270063942706;6360.491160707133;6372.58578192416;6380.553361264667;5034.11902916988;5092.083424983464;5130.30740849988;5133.845984620468;5177.171652535576;4547.204239258117;4551.431071116111;4566.26939689648;4577.267637178102;4661.720296782136;6016;6060.181875446847;6061.030235162353;6074.210352654215;6090.969545152246;6314.71583778731;6333.303336632966;6337;6341.460975817107;6344.460720838688;5802.100674395476;5928.436798504135;5937.851553711192;5942.970285148546;5958.970205148947;5552.167174923794;5568.053430916673;5580.972095139579;5605.962896864094;5628.549716022709;6473.514486413538;6473.870522589522;6477.060826180177;6479.254885688206;6487.3836985486305;6210.5652604319075;6255.499560035095;6270.404311590574;6311.211098612674;6336.651484168415;6288.182536270256;6300.180976473029;6326.2092238470195;6347.619142851444;6455.870882582416;6350.777722779865;6353.52348573868;6354.93645063562;6354.968225158845;6361.045843123568;6273.62358258506;6349.841253968688;6359.141515895295;6367.52243581724;6377.139086223486;6137.8060289514;6183.350748171557;6192.411720886508;6195.31851496326;6277.12120303167;6295.811125666238;6309.148264984168;6317.084022816574;6317.399847014526;6319.399657032574;6172.753089876354;6205.720742566505;6215.378462153749;6227;6230;6113.841195312599;6166.321704612579;6207.534434917309;6230.158928544589;6268.341824108586;6163.322034576283;6219.067139929047;6235.781747638811;6242.157308763441;6244.968775156095 09:35:06 INFO - PROCESS | 1642 | |1;ext.html;479.12798706354437;494.3523983581508;540.6782964136338;554.3015800091883;556.6075916478878;32259.838700806493;42218.15563688724;43238.783806081;43458;45437.77281113598;44981;45025.09949801002;45093.774531127296;45455.09089818202;45528.77235613817;9905.306628535987;10094.646687365943;10097.899021009805;10100.191984641211;10100.494975251244;9271.072892710708;9524.380915240487;12216.450259738333;12512;12518.687032824162;12404.13171078025;12502.749945001095;12517.812232816515;12518.81221781674;12525.997920166365;12463;12550.874491255108;12555.874441255562;12556.74886502274;12560.874391256062;12210.694732631755;12298.938505307417;12308;12317.69205769854;12327.260364378077;42101.78949105265;42221.577784222376;42379.57620423818;42383.57616423827;42448.787756061334;3401.693847553732;3473.496343030272;3498.597661268961;3540.309639620275;3578.481120237577;31554;31625.84187079073;31874;32062.358752825046;32193;3287.3638538905193;3339.983300083484;3398.4562470004926;3423.7945723256685;3504.7196224301993;531684;533989;534045;534045.3297733525;534494;34803.477947830805;60339.09491357633;60856;60890.69554652287;60975.39024609786;35012.29975400478;36225.73209937639;36252.63747362545;36303.63696363055;36310.8184459076;15620.218989050729;15826.9208653956;15832.841671583366;15838.208089595475;15843.049417035014;12370.938145309216;12593.559225427249;13302.401391937192;13400.128991615515;13438.731225375634;7850.8429831403055;8078.35373170145;8104.351651867836;8133.024037115466;8202.794930126794;3007.77441691874;3381.425157723193;3415.7438192135687;3422.6235114137426;3423.6918677318663;1019.8827134879584;1041.7187359412917;1053.2416660004785;1066.3825635000585;1085.2132204152085;26308.605370919453;27991.580126298122;28668.139955801358;30898.227544311572;31545.684543154734;1055.390529442606;1127.3912087472672;1139.8518192634904;1174.5477990973536;1221.590767093016;16006.119663418624;16027.634196818566;16076.75288547507;16264.674706505804;16432.75350869738;205910;208027.95986019974;208040;208209.9589502073;209531;60871.08693369603;61532.38467615356;61560.38439615636;61635.383646163864;61693.691531542056;1314.6581888708813;1347.4946894914408;1442.2716528153426;1450.3111022264552;1463.1367493179075;31096.22259443532;31120.53319200259;32060.877869274464;32085.35829283448;32647.34705305833;49839.50160498349;50440.495595045046;50456.49543504518;50462.74768626133;50562.46062775717;572850;576830;577599;662352;677743.6112819405;26657;26914.730852692006;27234.863825680746;27487.725122748518;27792.72207277901;48508.272375913686;49072;49257;49560;49670.50329496659;39325.01687457837;40133.79933100316;40587;41480.170396592504;42325.15349692927;39357.60642393654;39907.80046099751;40201.798991004856;41008.384874226314;41363;39545.80227098846;39782.602173979045;39818;41248.1750364985;42009.36985945152;32915.670843291264;35106.64893351034;35815;35944;36366.63633363633;22578.548429031656;26025.21924342273;26314.86842565775;26373;26886.59670104989 09:35:06 INFO - PROCESS | 1642 | |2;jquery.html;376.7306375941203;380.3875760026358;391.2175648702595;397.8269452788036;411.45893150507084;19741.914194719302;21896.781032189712;22384;22680.319590412295;22922.083116675334;18818.717719234188;19153.904230478827;20664;22193.223237186703;22453.775462245412;5888.558358123137;6259.749610015594;6319.526035547329;6330;6333.55665103443;4499.482559505666;4537.3647689323525;4545.863624091283;6261.749530018794;6755.695993680271;6824.522283440161;6992.755253566126;7129.750458733945;7151.070360853082;7171.713131474735;7045.048918396003;7102.857942841166;7114.288571142896;7137.250588688191;7145.178304494998;6156.78451254204;6172.969135154341;6224.62652240867;6245.094461303085;6247.2815626202655;27873.721262787316;27885;27894.860525697444;27929.301767455774;28156.014539491225;2044.366246463601;2347;2365.503244318695;2372.0511795282027;2624.8818803153904;13790.034697571175;15091.773623395658;15546.844531554652;16704.376688229542;17158.227879745442;1942.5143714071482;1960.6176795524877;1976.090998140857;1989.7413336266338;2016.6571682813958;122140;125098.37450812779;129988.35005825006;134083;134452;9852.556634951445;10234;10547.683569492927;10604.76554650839;11591;11461.598844040418;11867.525298987945;12637.17858339205;12648.873511264954;12656.6835829105;7850.050941926209;7976.763601641755;8587.055423903366;8726.825463490695;8826.029136794947;9243.815123697488;9266.490343031062;9277.257819374434;9395.013523580063;9407.90592094084;4031.5162180538523;4033.2336855997855;4036.4550785643564;4043.5956404359918;4096.692748043849;1690.8140104588485;1741.651669666072;1809.276289484217;1841.8542398106056;1941.5631482916513;818.995905020483;915.6520522201521;941.6845356805546;944.4003058058182;976.9609215631438;16052.839471605135;23267.650985235236;23304;23380.76619233786;23471.53056938886;946.4794363100267;953.5137080089204;983.7098056073476;994.8457989011715;1045.0490054050777;11395.658130255939;12008.979236764799;12219.961303289143;12240.204386714835;12280.570180043656;536949;577821;609902;642689;646648;123061;156637;157415;160432.78355342784;161522.38477615308;974.201155052864;994.164901482758;1004.9949750251349;1045.6549338718205;1045.738565358645;15508.767368489707;19999.10004049792;20646.690299645215;21049.789502104784;21203.787962120183;57520.849583008945;59379;60661.393386065574;60669;61467.463313417524;79632.80550791859;81211.59394202991;81326.59336703515;82592;83604;8205.671773129008;8297.128801476;8426.957865210634;8445.704400345958;8539;12954.481820727065;13567.389467474184;13664.31678416095;13685.726285474433;14259.500917467838;11699.29804211716;11716.765664686489;11878.584249551224;11943.462544185706;12024.75950480968;11606.573069559614;11720.70698232551;11814.586489472827;11948.522059117538;12102.818457722966;9630.807383852423;11839.76320473603;12127.57553485659;12203.511859525875;12315.69210769738;7859.8035049121945;8321.251087402163;8569.442986205877;8581.184787445183;8652.307815374881;9782.657606983721;11725.296482211095;12131.75736485283;12173;12194.817077743663 09:35:06 INFO - PROCESS | 1642 | |3;mootools.html;1385.4596707284159;1514.0310201471057;1664.5505713457367;1849.4729002234367;1857.544901499133;8685.088065753092;9213.03263157369;9214.723558293254;9220.815583688322;9224.677136300214;8186.754397368074;8465.66137354505;8475.618597163128;8807.515586642732;9032.954835225832;2416.951660966784;2445.3886528367907;2448.51029794041;2449.09383528094;2449.1672831237374;2413.3001429585397;2424.696912885889;2458.7172475165316;2461.0770960889668;2463.1255904153945;2030.512676957527;2432.2581612608146;2442.7190873049562;2451.595486744685;2460.409501719583;1980.554375265568;2417.3231495181376;2425.7816796488314;2426.696662917135;2432.1365915100064;1962.470133064079;2414.082648593541;2419.903203871843;2420.5279970405777;2421.346236516131;9091.545422728837;9158.404703694305;9222.44665320083;9223.169914707709;9233.307501937381;5235.58115350771;5390.056740070472;5392.946070539284;5397.352317721859;5427.972860135714;3652.7077833773233;3677.17263615687;3707.4068149096283;3756.098536351283;3761.755485893408;1124.1624989382963;1126.9718257043558;1129.2603344809163;1141.6346769033912;1152.6542037388833;11940.164188506722;12006.639800806077;12010.33943133136;12034.699132521757;12043.217190882564;4769.260764581495;4804.111239420718;4835.661503694778;4961.156603377434;4971.552560269593;10228.795424091477;10229.130523905551;10230;10233.027862353065;10240.385576865412;5523.475269849358;5530.778768849201;5532.5020748132865;5535.529479994165;5536.695481748462;4449.977750111293;4594.33382159585;4728;4999.725015124131;5061.72160531167;3972.781497017634;3999.6200360965663;4077.286474866946;4100.0569868930315;4110.116324990082;1139.8259792413662;1256.7674980128706;1285.6078895936641;1294.8316718826682;1305.2951406240525;822.3092602214049;876.3120950054305;910.003546117014;939.4973689076311;942.4675058591873;5749.3963133871175;5904.350521442638;5958.344582095966;5962.582619216613;6239.563230573907;447.30667465428456;461.63069544364373;468.20171607409964;471.40132032319445;472.74235541629895;3510.7191424685966;3842.538895332578;3904.590018048121;3993.3610622300876;4044.988752811797;10740.570377184826;11024.393658348868;11129;11144.832827507595;11193.048590869867;10714.25000249975;10865.641794775653;10921.39932303715;11075.390853503135;11085.778284434266;7323.670434830446;8433.831323373499;8548.273396761346;8557;8584.184502472255;5827.125931110368;5835.649861008353;5896.292444906639;5927.673977931083;6031.276246850406;10001.799964000826;10193.23550733683;10221.131203847755;10285.691429257135;10320.380777153396;10508.264421490423;10562.260641755003;10591.5233814477;10610.310329828537;10616.575336986434;6859.622720750406;7067.681954312171;7750.92249077502;7855.135935047026;8258.215469530385;8364.6235919385;8367.539785311867;8668.263197628272;8674.696385626472;8690.608922598625;7620.1998790128455;7718.691252349843;7720.961395192988;7741.806454838674;7849;7473.663685134291;7482.139553951365;7560.017197764256;7594.974678418343;7764.301212890641;6981.650917454209;7147.785566433015;7149.034880291094;7152.284771522806;7183.173934997542;5049.091163590442;5051.419086804917;5101.974490127525;5185.299984502044;5363.570914326767;5072.137736584863;5110.259012443101;5125.974370128126;5256.053910296183;5319.4414586467865 09:35:06 INFO - PROCESS | 1642 | |4;prototype.html;279.9146260390581;312.9655737868833;317.8935056755986;323.81380706094;325.6287831871665;5159.432462429129;5360.410354860972;5388.919166212505;5440.292761940952;5448.45515448455;4220.125754753257;4383.583559561845;5147.202183661539;5287.39194992576;5330.706811125387;3513.3149035938004;4087.651075145195;4093.447384603078;4180.24755544002;4208.97895510522;3246.772725909187;4056.371262454324;4182.6197354872975;4226.978865105671;4238.830446782125;3887.9782513093173;3982.6060878692515;4156.628312656816;4200.991761977134;4218.860907554958;4172.018291311602;4172.4784401949755;4184.372344148372;4194.4337514435465;4203.201391735576;3070.6775788542327;3682.834272457746;3695.1685870679153;3706.6478684524927;3707.7404581679293;5014;5260.8684782880355;5272.156454967187;5272.894542109137;5352.197170424429;4189.664826813848;4218.620324170809;4889.997550502138;4907.214845624683;4918.163912134945;4342;4707.764611769432;5693.007447393421;5718.911787634673;5724.252358867725;3193.552902593639;3873.3996230584303;4034.031832360242;4061;4094.5086589609436;4962.379702537219;5950.9702451487465;5952.851178720523;5997.640141591475;6011.909821352683;3832.616738326201;4160.729552579072;4171.97914010428;4182.996080940552;4192.553569018683;3674.430463278196;3845.5577608575372;4061.8926521211297;4222.0289333453475;4250.617444430016;4757.952420475751;4804.42346918372;4810.11012962603;4819.349387832668;4831.73425461603;5714.428557144335;5793.391693872083;5796.5072968797285;5797.333306669702;5801.158831969341;1301.139370699908;1328.7509740843577;1338.380559522988;1412.7779470757926;1424.7364237616075;765;790.517784151667;877.5787621941377;884.3853521802414;888.8000199954959;4453.704258848429;4454.7549884756;4478.462584489882;4494.910101798011;4502.639788816887;4402.779861006937;4432.711873728197;4845.806167753321;4870.342503762017;4896.069746748107;3767.830447629863;3980.800959951991;4458.331250312479;4464.531224221475;4472.239719247736;2585.5346037713393;2810;3106.4718997770433;3134.5925029745986;3135.278885856266;5846.94153058464;6211.546439156508;6214.782482613087;6234.690714949911;6241.500679945593;4977.352944117314;5918.822435326947;6049.697515124226;6131.4977830430935;6155.96922015396;6117.938820611826;6180.042093475428;6181.752729890754;6197.25632924043;6246.843828904313;5336.759845807026;5980.5215582754145;6025.270162922086;6056.031035034373;6073.908891366721;4735.608517698678;5009.974950125226;5425.755840987086;5452.473307473913;5946.554008449297;6208.4722798562625;6215.81352559424;6257.186565746424;6264.404881536247;6319.368063193644;3834.386498160281;3929.7249192556246;4016.196760647824;4026.9731218539087;4079.6940229482316;5182.870428239325;5671.079394112118;5929.495992840657;5954.821355359346;5961.344252132175;5417.620766546304;5431.945680543144;5609.569559762398;5723.484886360247;5863.384344643751;5125.589952803842;5801.158831969426;5806.2451881255165;5819.854503637443;5843.357230704704;4614.60120333607;5141.305923700253;5471.781128754806;5524.530414914777;5692.288463942007;5078.796848126033;5777.104548794943;5789.247397838254;5796.04365279741;5800.651960882361;4969.55274025325;5632.493075623213;5706.429357064427;5718.571107166896;5739.741711623071 09:35:06 INFO - PROCESS | 1642 | |5;yui.html;751.1924680967961;760.1866003376387;786.3551887452288;803.3693550562809;872.3893274707705;15575.454859079935;16038;16852.747208791847;16863.747043794323;16936.322547098116;13594.320283985811;13950.720985580283;14742.63143421415;15157.469488567902;15165.393384264644;726.5096060159372;745.9179490256067;885.9246964008067;911.8905731312252;915.2083447817623;885.9867101993475;906.148220672569;911.9863202051974;915.4644532948233;918.7289749523873;964.1130160252584;966.5408930757869;967.7677357434236;967.840306349455;972.6692924405701;953.4803532075035;954.5179684259405;956.0057540158227;956.8995255498143;957.4638202606562;714.5855403865745;715.8031541326125;716.3946465236867;716.505611128325;717.4941666125369;14961.700765984728;15115.924420377938;15141.924290378587;15155.393784248618;15161.924190379088;12305.384730763515;14675.853241467663;14996.100233985886;15027.774583381259;15145.924270378688;12043.759124817541;12227.449764760608;12278.877211227862;12325.075619328496;12406.88886135547;3765.6046115157787;3881.9805900970596;3902.6682731967817;3927.528696556403;4029.738067025634;97960.51019744927;100769;100988.99011009869;115712;121125;11334.206605537698;11426.08591312693;11434.656960291204;11474.94262528682;11711.76576468483;22759.431014224778;22774.65838012431;22779.88610056939;22824.543509129726;22829.086836526352;7897.802554936057;7918.089419716806;7958.482698624571;8226.341892648574;8270.007599088149;4189.413482112506;4295.78521073945;4337.240982827992;4474.7762611869275;4503.729776213438;3552.348158106493;3658.213484100931;3731.440283957428;3734.495843061152;3734.6825519830577;549.2557584473061;560.6636018389015;563.5181919458886;570.090705325006;574.7643466178847;617.7903861931272;632.2318383164477;638.8147437243148;648.6302807399737;669.2905520148561;8949.955250223707;9353.298502612332;9422.246220302359;9433.528323583794;9641.517924103768;568.7355379748354;637.7130291368902;658.7595527632379;665.4809248785955;674.8582797612459;3955.861544845917;4276.593723596254;4553.886152846206;4554.453465584085;4555.33947577606;166638;175092;175221.12389438227;178811;182103;151855;153604.23197883938;155137;155928;156792.4320756778;100184;118154;122121;124044.9999999982;134703;8566.657333706582;8922.55387230623;8939.418937769024;8967.306385448122;9150.862737058816;43515.38128572977;67593.66203168953;70084.29915700777;71256.93114603181;75083.62458187673;80486.19513804787;85323;88516;93144;99973.50013249887;13135.34323283813;13312.866871331551;13526.59420217395;14069.52270011634;14082.366293516612;14413.351399186851;14760.557183284518;15883.52349429519;16034.037957722574;16526.256318465457;13821.596618948159;14539.854601454274;14715.60201780829;15145.091294522364;15377.231138442809;13797.862021379658;13891.305434728425;14917.104973701613;15370.846291536942;15493.07041577509;13171.341432928124;13741.587752367444;13933.233672147724;14364.35360408805;14633.41466341377;11378.886211138113;11619.302841829518;11821.320844578546;11952.76094478123;12556;10122.038406351385;10610.151187905103;11351;11937.52249909994;11993.820092698443 09:35:06 INFO - PROCESS | 1642 | __end_tp_report 09:35:06 INFO - PROCESS | 1642 | __start_cc_report 09:35:06 INFO - PROCESS | 1642 | _x_x_mozilla_cycle_collect,1409 09:35:06 INFO - PROCESS | 1642 | __end_cc_report 09:35:06 INFO - PROCESS | 1642 | __startTimestamp1481304906458__endTimestamp 09:35:06 INFO - PROCESS | 1642 | 09:35:06 INFO - PROCESS | 1642 | ------- Summary: start ------- 09:35:06 INFO - PROCESS | 1642 | Number of tests: 6 09:35:06 INFO - PROCESS | 1642 | 09:35:06 INFO - PROCESS | 1642 | [#0] dojo.html Cycles:165 Average:5855.34 Median:6172.75 stddev:657.66 (10.7%) stddev-sans-first:622.14 09:35:06 INFO - PROCESS | 1642 | Values: 3063.2 3489.7 3521.1 3524.0 3572.2 5782.6 5865.8 5882.8 5933.1 5990.5 4771.7 5616.1 5670.7 5840.4 5901.7 4401.8 5336.8 5344.0 5346.6 5390.4 5287.8 5292.7 5423.6 5438.0 5466.5 5283.3 5347.5 5385.9 5412.9 5429.2 5367.7 5376.9 5380.5 5434.0 5460.6 5311.1 5355.3 5368.4 5370.5 5392.5 4761.0 5722.8 5724.9 5777.2 5806.8 6185.0 6253.2 6316.9 6325.7 6349.9 4894.2 5389.6 5717.3 6287.1 6389.8 5798.2 5848.1 5858.2 5869.1 5907.4 6310.7 6393.1 6394.5 6415.8 6416.1 6298.7 6306.1 6319.2 6322.4 6343.4 6211.9 6270.6 6279.7 6331.0 6361.1 6289.1 6339.9 6365.0 6369.5 6384.5 6302.1 6347.3 6360.5 6372.6 6380.6 5034.1 5092.1 5130.3 5133.8 5177.2 4547.2 4551.4 4566.3 4577.3 4661.7 6016.0 6060.2 6061.0 6074.2 6091.0 6314.7 6333.3 6337.0 6341.5 6344.5 5802.1 5928.4 5937.9 5943.0 5959.0 5552.2 5568.1 5581.0 5606.0 5628.5 6473.5 6473.9 6477.1 6479.3 6487.4 6210.6 6255.5 6270.4 6311.2 6336.7 6288.2 6300.2 6326.2 6347.6 6455.9 6350.8 6353.5 6354.9 6355.0 6361.0 6273.6 6349.8 6359.1 6367.5 6377.1 6137.8 6183.4 6192.4 6195.3 6277.1 6295.8 6309.1 6317.1 6317.4 6319.4 6172.8 6205.7 6215.4 6227.0 6230.0 6113.8 6166.3 6207.5 6230.2 6268.3 6163.3 6219.1 6235.8 6242.2 6245.0 09:35:06 INFO - PROCESS | 1642 | 09:35:06 INFO - PROCESS | 1642 | [#1] ext.html Cycles:180 Average:60138.03 Median:3419.18 stddev:130219.52 (3808.5%) stddev-sans-first:130507.78 09:35:06 INFO - PROCESS | 1642 | Values: 479.1 494.4 540.7 554.3 556.6 32259.8 42218.2 43238.8 43458.0 45437.8 44981.0 45025.1 45093.8 45455.1 45528.8 9905.3 10094.6 10097.9 10100.2 10100.5 9271.1 9524.4 12216.5 12512.0 12518.7 12404.1 12502.7 12517.8 12518.8 12526.0 12463.0 12550.9 12555.9 12556.7 12560.9 12210.7 12298.9 12308.0 12317.7 12327.3 42101.8 42221.6 42379.6 42383.6 42448.8 3401.7 3473.5 3498.6 3540.3 3578.5 31554.0 31625.8 31874.0 32062.4 32193.0 3287.4 3340.0 3398.5 3423.8 3504.7 531684.0 533989.0 534045.0 534045.3 534494.0 34803.5 60339.1 60856.0 60890.7 60975.4 35012.3 36225.7 36252.6 36303.6 36310.8 15620.2 15826.9 15832.8 15838.2 15843.0 12370.9 12593.6 13302.4 13400.1 13438.7 7850.8 8078.4 8104.4 8133.0 8202.8 3007.8 3381.4 3415.7 3422.6 3423.7 1019.9 1041.7 1053.2 1066.4 1085.2 26308.6 27991.6 28668.1 30898.2 31545.7 1055.4 1127.4 1139.9 1174.5 1221.6 16006.1 16027.6 16076.8 16264.7 16432.8 205910.0 208028.0 208040.0 208210.0 209531.0 60871.1 61532.4 61560.4 61635.4 61693.7 1314.7 1347.5 1442.3 1450.3 1463.1 31096.2 31120.5 32060.9 32085.4 32647.3 49839.5 50440.5 50456.5 50462.7 50562.5 572850.0 576830.0 577599.0 662352.0 677743.6 26657.0 26914.7 27234.9 27487.7 27792.7 48508.3 49072.0 49257.0 49560.0 49670.5 39325.0 40133.8 40587.0 41480.2 42325.2 39357.6 39907.8 40201.8 41008.4 41363.0 39545.8 39782.6 39818.0 41248.2 42009.4 32915.7 35106.6 35815.0 35944.0 36366.6 22578.5 26025.2 26314.9 26373.0 26886.6 09:35:06 INFO - PROCESS | 1642 | 09:35:06 INFO - PROCESS | 1642 | [#2] jquery.html Cycles:180 Average:36901.03 Median:15249.30 stddev:101769.79 (667.4%) stddev-sans-first:102018.33 09:35:06 INFO - PROCESS | 1642 | Values: 376.7 380.4 391.2 397.8 411.5 19741.9 21896.8 22384.0 22680.3 22922.1 18818.7 19153.9 20664.0 22193.2 22453.8 5888.6 6259.7 6319.5 6330.0 6333.6 4499.5 4537.4 4545.9 6261.7 6755.7 6824.5 6992.8 7129.8 7151.1 7171.7 7045.0 7102.9 7114.3 7137.3 7145.2 6156.8 6173.0 6224.6 6245.1 6247.3 27873.7 27885.0 27894.9 27929.3 28156.0 2044.4 2347.0 2365.5 2372.1 2624.9 13790.0 15091.8 15546.8 16704.4 17158.2 1942.5 1960.6 1976.1 1989.7 2016.7 122140.0 125098.4 129988.4 134083.0 134452.0 9852.6 10234.0 10547.7 10604.8 11591.0 11461.6 11867.5 12637.2 12648.9 12656.7 7850.1 7976.8 8587.1 8726.8 8826.0 9243.8 9266.5 9277.3 9395.0 9407.9 4031.5 4033.2 4036.5 4043.6 4096.7 1690.8 1741.7 1809.3 1841.9 1941.6 819.0 915.7 941.7 944.4 977.0 16052.8 23267.7 23304.0 23380.8 23471.5 946.5 953.5 983.7 994.8 1045.0 11395.7 12009.0 12220.0 12240.2 12280.6 536949.0 577821.0 609902.0 642689.0 646648.0 123061.0 156637.0 157415.0 160432.8 161522.4 974.2 994.2 1005.0 1045.7 1045.7 15508.8 19999.1 20646.7 21049.8 21203.8 57520.8 59379.0 60661.4 60669.0 61467.5 79632.8 81211.6 81326.6 82592.0 83604.0 8205.7 8297.1 8427.0 8445.7 8539.0 12954.5 13567.4 13664.3 13685.7 14259.5 11699.3 11716.8 11878.6 11943.5 12024.8 11606.6 11720.7 11814.6 11948.5 12102.8 9630.8 11839.8 12127.6 12203.5 12315.7 7859.8 8321.3 8569.4 8581.2 8652.3 9782.7 11725.3 12131.8 12173.0 12194.8 09:35:06 INFO - PROCESS | 1642 | 09:35:06 INFO - PROCESS | 1642 | [#3] mootools.html Cycles:180 Average:5844.26 Median:472.07 stddev:3324.55 (704.2%) stddev-sans-first:3316.99 09:35:06 INFO - PROCESS | 1642 | Values: 1385.5 1514.0 1664.6 1849.5 1857.5 8685.1 9213.0 9214.7 9220.8 9224.7 8186.8 8465.7 8475.6 8807.5 9033.0 2417.0 2445.4 2448.5 2449.1 2449.2 2413.3 2424.7 2458.7 2461.1 2463.1 2030.5 2432.3 2442.7 2451.6 2460.4 1980.6 2417.3 2425.8 2426.7 2432.1 1962.5 2414.1 2419.9 2420.5 2421.3 9091.5 9158.4 9222.4 9223.2 9233.3 5235.6 5390.1 5392.9 5397.4 5428.0 3652.7 3677.2 3707.4 3756.1 3761.8 1124.2 1127.0 1129.3 1141.6 1152.7 11940.2 12006.6 12010.3 12034.7 12043.2 4769.3 4804.1 4835.7 4961.2 4971.6 10228.8 10229.1 10230.0 10233.0 10240.4 5523.5 5530.8 5532.5 5535.5 5536.7 4450.0 4594.3 4728.0 4999.7 5061.7 3972.8 3999.6 4077.3 4100.1 4110.1 1139.8 1256.8 1285.6 1294.8 1305.3 822.3 876.3 910.0 939.5 942.5 5749.4 5904.4 5958.3 5962.6 6239.6 447.3 461.6 468.2 471.4 472.7 3510.7 3842.5 3904.6 3993.4 4045.0 10740.6 11024.4 11129.0 11144.8 11193.0 10714.3 10865.6 10921.4 11075.4 11085.8 7323.7 8433.8 8548.3 8557.0 8584.2 5827.1 5835.6 5896.3 5927.7 6031.3 10001.8 10193.2 10221.1 10285.7 10320.4 10508.3 10562.3 10591.5 10610.3 10616.6 6859.6 7067.7 7750.9 7855.1 8258.2 8364.6 8367.5 8668.3 8674.7 8690.6 7620.2 7718.7 7721.0 7741.8 7849.0 7473.7 7482.1 7560.0 7595.0 7764.3 6981.7 7147.8 7149.0 7152.3 7183.2 5049.1 5051.4 5102.0 5185.3 5363.6 5072.1 5110.3 5126.0 5256.1 5319.4 09:35:06 INFO - PROCESS | 1642 | 09:35:06 INFO - PROCESS | 1642 | [#4] prototype.html Cycles:180 Average:4597.73 Median:4940.27 stddev:1435.56 (29.1%) stddev-sans-first:1402.53 09:35:06 INFO - PROCESS | 1642 | Values: 279.9 313.0 317.9 323.8 325.6 5159.4 5360.4 5388.9 5440.3 5448.5 4220.1 4383.6 5147.2 5287.4 5330.7 3513.3 4087.7 4093.4 4180.2 4209.0 3246.8 4056.4 4182.6 4227.0 4238.8 3888.0 3982.6 4156.6 4201.0 4218.9 4172.0 4172.5 4184.4 4194.4 4203.2 3070.7 3682.8 3695.2 3706.6 3707.7 5014.0 5260.9 5272.2 5272.9 5352.2 4189.7 4218.6 4890.0 4907.2 4918.2 4342.0 4707.8 5693.0 5718.9 5724.3 3193.6 3873.4 4034.0 4061.0 4094.5 4962.4 5951.0 5952.9 5997.6 6011.9 3832.6 4160.7 4172.0 4183.0 4192.6 3674.4 3845.6 4061.9 4222.0 4250.6 4758.0 4804.4 4810.1 4819.3 4831.7 5714.4 5793.4 5796.5 5797.3 5801.2 1301.1 1328.8 1338.4 1412.8 1424.7 765.0 790.5 877.6 884.4 888.8 4453.7 4454.8 4478.5 4494.9 4502.6 4402.8 4432.7 4845.8 4870.3 4896.1 3767.8 3980.8 4458.3 4464.5 4472.2 2585.5 2810.0 3106.5 3134.6 3135.3 5846.9 6211.5 6214.8 6234.7 6241.5 4977.4 5918.8 6049.7 6131.5 6156.0 6117.9 6180.0 6181.8 6197.3 6246.8 5336.8 5980.5 6025.3 6056.0 6073.9 4735.6 5010.0 5425.8 5452.5 5946.6 6208.5 6215.8 6257.2 6264.4 6319.4 3834.4 3929.7 4016.2 4027.0 4079.7 5182.9 5671.1 5929.5 5954.8 5961.3 5417.6 5431.9 5609.6 5723.5 5863.4 5125.6 5801.2 5806.2 5819.9 5843.4 4614.6 5141.3 5471.8 5524.5 5692.3 5078.8 5777.1 5789.2 5796.0 5800.7 4969.6 5632.5 5706.4 5718.6 5739.7 09:35:06 INFO - PROCESS | 1642 | 09:35:06 INFO - PROCESS | 1642 | [#5] yui.html Cycles:180 Average:26797.10 Median:3694.83 stddev:44482.07 (1203.9%) stddev-sans-first:44563.86 09:35:06 INFO - PROCESS | 1642 | Values: 751.2 760.2 786.4 803.4 872.4 15575.5 16038.0 16852.7 16863.7 16936.3 13594.3 13950.7 14742.6 15157.5 15165.4 726.5 745.9 885.9 911.9 915.2 886.0 906.1 912.0 915.5 918.7 964.1 966.5 967.8 967.8 972.7 953.5 954.5 956.0 956.9 957.5 714.6 715.8 716.4 716.5 717.5 14961.7 15115.9 15141.9 15155.4 15161.9 12305.4 14675.9 14996.1 15027.8 15145.9 12043.8 12227.4 12278.9 12325.1 12406.9 3765.6 3882.0 3902.7 3927.5 4029.7 97960.5 100769.0 100989.0 115712.0 121125.0 11334.2 11426.1 11434.7 11474.9 11711.8 22759.4 22774.7 22779.9 22824.5 22829.1 7897.8 7918.1 7958.5 8226.3 8270.0 4189.4 4295.8 4337.2 4474.8 4503.7 3552.3 3658.2 3731.4 3734.5 3734.7 549.3 560.7 563.5 570.1 574.8 617.8 632.2 638.8 648.6 669.3 8950.0 9353.3 9422.2 9433.5 9641.5 568.7 637.7 658.8 665.5 674.9 3955.9 4276.6 4553.9 4554.5 4555.3 166638.0 175092.0 175221.1 178811.0 182103.0 151855.0 153604.2 155137.0 155928.0 156792.4 100184.0 118154.0 122121.0 124045.0 134703.0 8566.7 8922.6 8939.4 8967.3 9150.9 43515.4 67593.7 70084.3 71256.9 75083.6 80486.2 85323.0 88516.0 93144.0 99973.5 13135.3 13312.9 13526.6 14069.5 14082.4 14413.4 14760.6 15883.5 16034.0 16526.3 13821.6 14539.9 14715.6 15145.1 15377.2 13797.9 13891.3 14917.1 15370.8 15493.1 13171.3 13741.6 13933.2 14364.4 14633.4 11378.9 11619.3 11821.3 11952.8 12556.0 10122.0 10610.2 11351.0 11937.5 11993.8 09:35:06 INFO - PROCESS | 1642 | -------- Summary: end -------- 09:35:06 INFO - PROCESS | 1642 | 09:35:07 INFO - TEST-INFO | 1642: exit 0 09:35:07 INFO - TEST-OK | dromaeo_css | took 1115227ms 09:35:07 INFO - TEST-START | kraken 09:35:07 INFO - operating with platform_type : mac_ 09:35:07 INFO - Initialising browser for kraken test... 09:35:07 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/tmpDGT4fL/profile http://localhost:49242/getInfo.html) 09:35:09 INFO - PROCESS | 1653 | __metrics Screen width/height:1600/1200 09:35:09 INFO - PROCESS | 1653 | colorDepth:24 09:35:09 INFO - PROCESS | 1653 | Browser inner width/height: 1024/689 09:35:09 INFO - PROCESS | 1653 | __metrics 09:35:09 INFO - PROCESS | 1653 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:35:09 INFO - PROCESS | 1653 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:35:10 INFO - TEST-INFO | 1653: exit 0 09:35:10 INFO - Browser initialized. 09:35:10 INFO - Running cycle 1/1 for kraken test... 09:35:10 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/tmpDGT4fL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:35:21 INFO - PROCESS | 1654 | RSS: Main: 168583168 09:35:21 INFO - PROCESS | 1654 | 09:35:24 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection) 09:35:25 INFO - PROCESS | 1654 | RSS: Main: 219373568 09:35:25 INFO - PROCESS | 1654 | 09:35:28 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-dft) 09:35:28 INFO - PROCESS | 1654 | RSS: Main: 233676800 09:35:28 INFO - PROCESS | 1654 | 09:35:31 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-fft) 09:35:32 INFO - PROCESS | 1654 | RSS: Main: 329355264 09:35:32 INFO - PROCESS | 1654 | 09:35:34 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator) 09:35:35 INFO - PROCESS | 1654 | RSS: Main: 227082240 09:35:35 INFO - PROCESS | 1654 | 09:35:37 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:35:37 INFO - PROCESS | 1654 | RSS: Main: 228868096 09:35:37 INFO - PROCESS | 1654 | 09:35:40 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom) 09:35:41 INFO - PROCESS | 1654 | RSS: Main: 266006528 09:35:41 INFO - PROCESS | 1654 | 09:35:43 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate) 09:35:44 INFO - PROCESS | 1654 | RSS: Main: 265871360 09:35:44 INFO - PROCESS | 1654 | 09:35:47 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial) 09:35:48 INFO - PROCESS | 1654 | RSS: Main: 265158656 09:35:48 INFO - PROCESS | 1654 | 09:35:49 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:35:50 INFO - PROCESS | 1654 | RSS: Main: 239853568 09:35:50 INFO - PROCESS | 1654 | 09:35:52 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:35:52 INFO - PROCESS | 1654 | RSS: Main: 244715520 09:35:52 INFO - PROCESS | 1654 | 09:35:54 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:35:55 INFO - PROCESS | 1654 | RSS: Main: 252145664 09:35:55 INFO - PROCESS | 1654 | 09:35:58 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:35:59 INFO - PROCESS | 1654 | RSS: Main: 259301376 09:35:59 INFO - PROCESS | 1654 | 09:36:01 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:36:02 INFO - PROCESS | 1654 | RSS: Main: 259121152 09:36:02 INFO - PROCESS | 1654 | 09:36:04 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49242/tests/kraken/driver.html?testName=ai-astar) 09:36:04 INFO - PROCESS | 1654 | RSS: Main: 302944256 09:36:04 INFO - PROCESS | 1654 | 09:36:04 INFO - PROCESS | 1654 | __start_tp_report 09:36:04 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 09:36:04 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 09:36:04 INFO - PROCESS | 1654 | |i|pagename|runs| 09:36:04 INFO - PROCESS | 1654 | |0;ai-astar;114;115;115;113;113;115;113;114;113;114 09:36:04 INFO - PROCESS | 1654 | |1;audio-beat-detection;168;96;132;176;140;121;105;107;138;108 09:36:04 INFO - PROCESS | 1654 | |2;audio-dft;182;187;183;182;182;182;182;188;182;180 09:36:04 INFO - PROCESS | 1654 | |3;audio-fft;79;60;58;88;61;57;59;57;82;58 09:36:04 INFO - PROCESS | 1654 | |4;audio-oscillator;87;70;94;68;66;70;116;98;101;98 09:36:04 INFO - PROCESS | 1654 | |5;imaging-gaussian-blur;89;87;88;91;88;84;90;87;95;84 09:36:04 INFO - PROCESS | 1654 | |6;imaging-darkroom;96;108;96;101;95;94;97;95;95;95 09:36:04 INFO - PROCESS | 1654 | |7;imaging-desaturate;87;107;131;138;84;85;84;86;84;85 09:36:04 INFO - PROCESS | 1654 | |8;json-parse-financial;54;52;54;53;57;56;57;56;56;56 09:36:04 INFO - PROCESS | 1654 | |9;json-stringify-tinderbox;62;70;71;74;71;68;67;67;69;68 09:36:04 INFO - PROCESS | 1654 | |10;stanford-crypto-aes;82;81;80;81;84;81;80;84;88;90 09:36:04 INFO - PROCESS | 1654 | |11;stanford-crypto-ccm;156;143;134;141;154;148;145;140;177;174 09:36:04 INFO - PROCESS | 1654 | |12;stanford-crypto-pbkdf2;163;157;159;161;160;156;162;161;160;161 09:36:04 INFO - PROCESS | 1654 | |13;stanford-crypto-sha256-iterative;65;67;66;65;65;66;65;65;65;64 09:36:04 INFO - PROCESS | 1654 | __end_tp_report 09:36:04 INFO - PROCESS | 1654 | __start_cc_report 09:36:04 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,1888 09:36:04 INFO - PROCESS | 1654 | __end_cc_report 09:36:04 INFO - PROCESS | 1654 | __startTimestamp1481304964375__endTimestamp 09:36:04 INFO - PROCESS | 1654 | 09:36:04 INFO - PROCESS | 1654 | ------- Summary: start ------- 09:36:04 INFO - PROCESS | 1654 | Number of tests: 14 09:36:04 INFO - PROCESS | 1654 | 09:36:04 INFO - PROCESS | 1654 | [#0] ai-astar Cycles:10 Average:113.90 Median:114.00 stddev:0.88 (0.8%) stddev-sans-first:0.93 09:36:04 INFO - PROCESS | 1654 | Values: 114.0 115.0 115.0 113.0 113.0 115.0 113.0 114.0 113.0 114.0 09:36:04 INFO - PROCESS | 1654 | 09:36:04 INFO - PROCESS | 1654 | [#1] audio-beat-detection Cycles:10 Average:129.10 Median:139.00 stddev:27.07 (19.5%) stddev-sans-first:24.78 09:36:04 INFO - PROCESS | 1654 | Values: 168.0 96.0 132.0 176.0 140.0 121.0 105.0 107.0 138.0 108.0 09:36:04 INFO - PROCESS | 1654 | 09:36:04 INFO - PROCESS | 1654 | [#2] audio-dft Cycles:10 Average:183.00 Median:182.00 stddev:2.49 (1.4%) stddev-sans-first:2.62 09:36:04 INFO - PROCESS | 1654 | Values: 182.0 187.0 183.0 182.0 182.0 182.0 182.0 188.0 182.0 180.0 09:36:04 INFO - PROCESS | 1654 | 09:36:04 INFO - PROCESS | 1654 | [#3] audio-fft Cycles:10 Average:65.90 Median:60.50 stddev:12.06 (19.9%) stddev-sans-first:11.82 09:36:04 INFO - PROCESS | 1654 | Values: 79.0 60.0 58.0 88.0 61.0 57.0 59.0 57.0 82.0 58.0 09:36:04 INFO - PROCESS | 1654 | 09:36:04 INFO - PROCESS | 1654 | [#4] audio-oscillator Cycles:10 Average:86.80 Median:78.50 stddev:17.34 (22.1%) stddev-sans-first:18.40 09:36:04 INFO - PROCESS | 1654 | Values: 87.0 70.0 94.0 68.0 66.0 70.0 116.0 98.0 101.0 98.0 09:36:04 INFO - PROCESS | 1654 | 09:36:04 INFO - PROCESS | 1654 | [#5] imaging-gaussian-blur Cycles:10 Average:88.30 Median:88.50 stddev:3.27 (3.7%) stddev-sans-first:3.46 09:36:04 INFO - PROCESS | 1654 | Values: 89.0 87.0 88.0 91.0 88.0 84.0 90.0 87.0 95.0 84.0 09:36:04 INFO - PROCESS | 1654 | 09:36:04 INFO - PROCESS | 1654 | [#6] imaging-darkroom Cycles:10 Average:97.20 Median:95.00 stddev:4.26 (4.5%) stddev-sans-first:4.50 09:36:04 INFO - PROCESS | 1654 | Values: 96.0 108.0 96.0 101.0 95.0 94.0 97.0 95.0 95.0 95.0 09:36:04 INFO - PROCESS | 1654 | 09:36:04 INFO - PROCESS | 1654 | [#7] imaging-desaturate Cycles:10 Average:97.10 Median:84.50 stddev:20.96 (24.8%) stddev-sans-first:21.91 09:36:04 INFO - PROCESS | 1654 | Values: 87.0 107.0 131.0 138.0 84.0 85.0 84.0 86.0 84.0 85.0 09:36:04 INFO - PROCESS | 1654 | 09:36:04 INFO - PROCESS | 1654 | [#8] json-parse-financial Cycles:10 Average:55.10 Median:56.00 stddev:1.73 (3.1%) stddev-sans-first:1.79 09:36:04 INFO - PROCESS | 1654 | Values: 54.0 52.0 54.0 53.0 57.0 56.0 57.0 56.0 56.0 56.0 09:36:04 INFO - PROCESS | 1654 | 09:36:04 INFO - PROCESS | 1654 | [#9] json-stringify-tinderbox Cycles:10 Average:68.70 Median:69.50 stddev:3.20 (4.6%) stddev-sans-first:2.30 09:36:04 INFO - PROCESS | 1654 | Values: 62.0 70.0 71.0 74.0 71.0 68.0 67.0 67.0 69.0 68.0 09:36:04 INFO - PROCESS | 1654 | 09:36:04 INFO - PROCESS | 1654 | [#10] stanford-crypto-aes Cycles:10 Average:83.10 Median:83.00 stddev:3.45 (4.2%) stddev-sans-first:3.63 09:36:04 INFO - PROCESS | 1654 | Values: 82.0 81.0 80.0 81.0 84.0 81.0 80.0 84.0 88.0 90.0 09:36:04 INFO - PROCESS | 1654 | 09:36:04 INFO - PROCESS | 1654 | [#11] stanford-crypto-ccm Cycles:10 Average:151.20 Median:151.00 stddev:14.37 (9.5%) stddev-sans-first:15.13 09:36:04 INFO - PROCESS | 1654 | Values: 156.0 143.0 134.0 141.0 154.0 148.0 145.0 140.0 177.0 174.0 09:36:04 INFO - PROCESS | 1654 | 09:36:04 INFO - PROCESS | 1654 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:160.00 Median:161.00 stddev:2.16 (1.3%) stddev-sans-first:2.00 09:36:04 INFO - PROCESS | 1654 | Values: 163.0 157.0 159.0 161.0 160.0 156.0 162.0 161.0 160.0 161.0 09:36:04 INFO - PROCESS | 1654 | 09:36:04 INFO - PROCESS | 1654 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.30 Median:65.00 stddev:0.82 (1.3%) stddev-sans-first:0.87 09:36:04 INFO - PROCESS | 1654 | Values: 65.0 67.0 66.0 65.0 65.0 66.0 65.0 65.0 65.0 64.0 09:36:04 INFO - PROCESS | 1654 | -------- Summary: end -------- 09:36:04 INFO - PROCESS | 1654 | 09:36:05 INFO - TEST-INFO | 1654: exit 0 09:36:05 INFO - TEST-OK | kraken | took 58031ms 09:36:05 INFO - SUITE-END | took 1173s 09:36:05 INFO - Completed test suite (00:19:33) 09:36:05 INFO - javascript benchmark 09:36:05 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3063.1576316512965, 3489.738269629777, 3521.1197200699808, 3523.995661236548, 3572.1962558424357, 5782.624129431584, 5865.794697185599, 5882.764689412429, 5933.110033494978, 5990.520758339333, 4771.737554434513, 5616.129499927508, 5670.6957399798075, 5840.3575606683235, 5901.704914754267, 4401.845935392262, 5336.786528538853, 5344.0, 5346.625736198468, 5390.380106287768, 5287.762050707727, 5292.708901010443, 5423.620346575742, 5437.9939711153565, 5466.480684334982, 5283.28675628789, 5347.545458636001, 5385.919211211835, 5412.891742165154, 5429.212764149196, 5367.651102678314, 5376.91934620981, 5380.488853558905, 5434.049041417776, 5460.590455715797, 5311.123664595337, 5355.25026496287, 5368.382635996872, 5370.5435038021815, 5392.487713667196, 4761.0, 5722.8268205018285, 5724.8855022899725, 5777.162311464814, 5806.82579522615, 6184.969075154595, 6253.218347706536, 6316.873662526724, 6325.715342809539, 6349.904751428732, 4894.1679914414635, 5389.566530255462, 5717.256756621613, 6287.1198032275515, 6389.8402539936405, 5798.188253644493, 5848.122781582797, 5858.238429004202, 5869.148973398833, 5907.438793314628, 6310.716017779211, 6393.104965304861, 6394.488440924715, 6415.775447859302, 6416.133821934072, 6298.653574053379, 6306.1171435998995, 6319.24169099711, 6322.36776322364, 6343.397377249154, 6211.937880621136, 6270.561060725706, 6279.654618995908, 6331.018692102732, 6361.077643741632, 6289.0880822280515, 6339.936600633934, 6365.013422919455, 6369.458596019297, 6384.457321127663, 6302.1177035215105, 6347.270063942706, 6360.491160707133, 6372.58578192416, 6380.553361264667, 5034.11902916988, 5092.083424983464, 5130.30740849988, 5133.845984620468, 5177.171652535576, 4547.204239258117, 4551.431071116111, 4566.26939689648, 4577.267637178102, 4661.720296782136, 6016.0, 6060.181875446847, 6061.030235162353, 6074.210352654215, 6090.969545152246, 6314.71583778731, 6333.303336632966, 6337.0, 6341.460975817107, 6344.460720838688, 5802.100674395476, 5928.436798504135, 5937.851553711192, 5942.970285148546, 5958.970205148947, 5552.167174923794, 5568.053430916673, 5580.972095139579, 5605.962896864094, 5628.549716022709, 6473.514486413538, 6473.870522589522, 6477.060826180177, 6479.254885688206, 6487.3836985486305, 6210.5652604319075, 6255.499560035095, 6270.404311590574, 6311.211098612674, 6336.651484168415, 6288.182536270256, 6300.180976473029, 6326.2092238470195, 6347.619142851444, 6455.870882582416, 6350.777722779865, 6353.52348573868, 6354.93645063562, 6354.968225158845, 6361.045843123568, 6273.62358258506, 6349.841253968688, 6359.141515895295, 6367.52243581724, 6377.139086223486, 6137.8060289514, 6183.350748171557, 6192.411720886508, 6195.31851496326, 6277.12120303167, 6295.811125666238, 6309.148264984168, 6317.084022816574, 6317.399847014526, 6319.399657032574, 6172.753089876354, 6205.720742566505, 6215.378462153749, 6227.0, 6230.0, 6113.841195312599, 6166.321704612579, 6207.534434917309, 6230.158928544589, 6268.341824108586, 6163.322034576283, 6219.067139929047, 6235.781747638811, 6242.157308763441, 6244.968775156095], "value": 5814.24201194136, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [479.12798706354437, 494.3523983581508, 540.6782964136338, 554.3015800091883, 556.6075916478878, 32259.838700806493, 42218.15563688724, 43238.783806081, 43458.0, 45437.77281113598, 44981.0, 45025.09949801002, 45093.774531127296, 45455.09089818202, 45528.77235613817, 9905.306628535987, 10094.646687365943, 10097.899021009805, 10100.191984641211, 10100.494975251244, 9271.072892710708, 9524.380915240487, 12216.450259738333, 12512.0, 12518.687032824162, 12404.13171078025, 12502.749945001095, 12517.812232816515, 12518.81221781674, 12525.997920166365, 12463.0, 12550.874491255108, 12555.874441255562, 12556.74886502274, 12560.874391256062, 12210.694732631755, 12298.938505307417, 12308.0, 12317.69205769854, 12327.260364378077, 42101.78949105265, 42221.577784222376, 42379.57620423818, 42383.57616423827, 42448.787756061334, 3401.693847553732, 3473.496343030272, 3498.597661268961, 3540.309639620275, 3578.481120237577, 31554.0, 31625.84187079073, 31874.0, 32062.358752825046, 32193.0, 3287.3638538905193, 3339.983300083484, 3398.4562470004926, 3423.7945723256685, 3504.7196224301993, 531684.0, 533989.0, 534045.0, 534045.3297733525, 534494.0, 34803.477947830805, 60339.09491357633, 60856.0, 60890.69554652287, 60975.39024609786, 35012.29975400478, 36225.73209937639, 36252.63747362545, 36303.63696363055, 36310.8184459076, 15620.218989050729, 15826.9208653956, 15832.841671583366, 15838.208089595475, 15843.049417035014, 12370.938145309216, 12593.559225427249, 13302.401391937192, 13400.128991615515, 13438.731225375634, 7850.8429831403055, 8078.35373170145, 8104.351651867836, 8133.024037115466, 8202.794930126794, 3007.77441691874, 3381.425157723193, 3415.7438192135687, 3422.6235114137426, 3423.6918677318663, 1019.8827134879584, 1041.7187359412917, 1053.2416660004785, 1066.3825635000585, 1085.2132204152085, 26308.605370919453, 27991.580126298122, 28668.139955801358, 30898.227544311572, 31545.684543154734, 1055.390529442606, 1127.3912087472672, 1139.8518192634904, 1174.5477990973536, 1221.590767093016, 16006.119663418624, 16027.634196818566, 16076.75288547507, 16264.674706505804, 16432.75350869738, 205910.0, 208027.95986019974, 208040.0, 208209.9589502073, 209531.0, 60871.08693369603, 61532.38467615356, 61560.38439615636, 61635.383646163864, 61693.691531542056, 1314.6581888708813, 1347.4946894914408, 1442.2716528153426, 1450.3111022264552, 1463.1367493179075, 31096.22259443532, 31120.53319200259, 32060.877869274464, 32085.35829283448, 32647.34705305833, 49839.50160498349, 50440.495595045046, 50456.49543504518, 50462.74768626133, 50562.46062775717, 572850.0, 576830.0, 577599.0, 662352.0, 677743.6112819405, 26657.0, 26914.730852692006, 27234.863825680746, 27487.725122748518, 27792.72207277901, 48508.272375913686, 49072.0, 49257.0, 49560.0, 49670.50329496659, 39325.01687457837, 40133.79933100316, 40587.0, 41480.170396592504, 42325.15349692927, 39357.60642393654, 39907.80046099751, 40201.798991004856, 41008.384874226314, 41363.0, 39545.80227098846, 39782.602173979045, 39818.0, 41248.1750364985, 42009.36985945152, 32915.670843291264, 35106.64893351034, 35815.0, 35944.0, 36366.63633363633, 22578.548429031656, 26025.21924342273, 26314.86842565775, 26373.0, 26886.59670104989], "value": 19214.821884330573, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [376.7306375941203, 380.3875760026358, 391.2175648702595, 397.8269452788036, 411.45893150507084, 19741.914194719302, 21896.781032189712, 22384.0, 22680.319590412295, 22922.083116675334, 18818.717719234188, 19153.904230478827, 20664.0, 22193.223237186703, 22453.775462245412, 5888.558358123137, 6259.749610015594, 6319.526035547329, 6330.0, 6333.55665103443, 4499.482559505666, 4537.3647689323525, 4545.863624091283, 6261.749530018794, 6755.695993680271, 6824.522283440161, 6992.755253566126, 7129.750458733945, 7151.070360853082, 7171.713131474735, 7045.048918396003, 7102.857942841166, 7114.288571142896, 7137.250588688191, 7145.178304494998, 6156.78451254204, 6172.969135154341, 6224.62652240867, 6245.094461303085, 6247.2815626202655, 27873.721262787316, 27885.0, 27894.860525697444, 27929.301767455774, 28156.014539491225, 2044.366246463601, 2347.0, 2365.503244318695, 2372.0511795282027, 2624.8818803153904, 13790.034697571175, 15091.773623395658, 15546.844531554652, 16704.376688229542, 17158.227879745442, 1942.5143714071482, 1960.6176795524877, 1976.090998140857, 1989.7413336266338, 2016.6571682813958, 122140.0, 125098.37450812779, 129988.35005825006, 134083.0, 134452.0, 9852.556634951445, 10234.0, 10547.683569492927, 10604.76554650839, 11591.0, 11461.598844040418, 11867.525298987945, 12637.17858339205, 12648.873511264954, 12656.6835829105, 7850.050941926209, 7976.763601641755, 8587.055423903366, 8726.825463490695, 8826.029136794947, 9243.815123697488, 9266.490343031062, 9277.257819374434, 9395.013523580063, 9407.90592094084, 4031.5162180538523, 4033.2336855997855, 4036.4550785643564, 4043.5956404359918, 4096.692748043849, 1690.8140104588485, 1741.651669666072, 1809.276289484217, 1841.8542398106056, 1941.5631482916513, 818.995905020483, 915.6520522201521, 941.6845356805546, 944.4003058058182, 976.9609215631438, 16052.839471605135, 23267.650985235236, 23304.0, 23380.76619233786, 23471.53056938886, 946.4794363100267, 953.5137080089204, 983.7098056073476, 994.8457989011715, 1045.0490054050777, 11395.658130255939, 12008.979236764799, 12219.961303289143, 12240.204386714835, 12280.570180043656, 536949.0, 577821.0, 609902.0, 642689.0, 646648.0, 123061.0, 156637.0, 157415.0, 160432.78355342784, 161522.38477615308, 974.201155052864, 994.164901482758, 1004.9949750251349, 1045.6549338718205, 1045.738565358645, 15508.767368489707, 19999.10004049792, 20646.690299645215, 21049.789502104784, 21203.787962120183, 57520.849583008945, 59379.0, 60661.393386065574, 60669.0, 61467.463313417524, 79632.80550791859, 81211.59394202991, 81326.59336703515, 82592.0, 83604.0, 8205.671773129008, 8297.128801476, 8426.957865210634, 8445.704400345958, 8539.0, 12954.481820727065, 13567.389467474184, 13664.31678416095, 13685.726285474433, 14259.500917467838, 11699.29804211716, 11716.765664686489, 11878.584249551224, 11943.462544185706, 12024.75950480968, 11606.573069559614, 11720.70698232551, 11814.586489472827, 11948.522059117538, 12102.818457722966, 9630.807383852423, 11839.76320473603, 12127.57553485659, 12203.511859525875, 12315.69210769738, 7859.8035049121945, 8321.251087402163, 8569.442986205877, 8581.184787445183, 8652.307815374881, 9782.657606983721, 11725.296482211095, 12131.75736485283, 12173.0, 12194.817077743663], "value": 10090.414378902777, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1385.4596707284159, 1514.0310201471057, 1664.5505713457367, 1849.4729002234367, 1857.544901499133, 8685.088065753092, 9213.03263157369, 9214.723558293254, 9220.815583688322, 9224.677136300214, 8186.754397368074, 8465.66137354505, 8475.618597163128, 8807.515586642732, 9032.954835225832, 2416.951660966784, 2445.3886528367907, 2448.51029794041, 2449.09383528094, 2449.1672831237374, 2413.3001429585397, 2424.696912885889, 2458.7172475165316, 2461.0770960889668, 2463.1255904153945, 2030.512676957527, 2432.2581612608146, 2442.7190873049562, 2451.595486744685, 2460.409501719583, 1980.554375265568, 2417.3231495181376, 2425.7816796488314, 2426.696662917135, 2432.1365915100064, 1962.470133064079, 2414.082648593541, 2419.903203871843, 2420.5279970405777, 2421.346236516131, 9091.545422728837, 9158.404703694305, 9222.44665320083, 9223.169914707709, 9233.307501937381, 5235.58115350771, 5390.056740070472, 5392.946070539284, 5397.352317721859, 5427.972860135714, 3652.7077833773233, 3677.17263615687, 3707.4068149096283, 3756.098536351283, 3761.755485893408, 1124.1624989382963, 1126.9718257043558, 1129.2603344809163, 1141.6346769033912, 1152.6542037388833, 11940.164188506722, 12006.639800806077, 12010.33943133136, 12034.699132521757, 12043.217190882564, 4769.260764581495, 4804.111239420718, 4835.661503694778, 4961.156603377434, 4971.552560269593, 10228.795424091477, 10229.130523905551, 10230.0, 10233.027862353065, 10240.385576865412, 5523.475269849358, 5530.778768849201, 5532.5020748132865, 5535.529479994165, 5536.695481748462, 4449.977750111293, 4594.33382159585, 4728.0, 4999.725015124131, 5061.72160531167, 3972.781497017634, 3999.6200360965663, 4077.286474866946, 4100.0569868930315, 4110.116324990082, 1139.8259792413662, 1256.7674980128706, 1285.6078895936641, 1294.8316718826682, 1305.2951406240525, 822.3092602214049, 876.3120950054305, 910.003546117014, 939.4973689076311, 942.4675058591873, 5749.3963133871175, 5904.350521442638, 5958.344582095966, 5962.582619216613, 6239.563230573907, 447.30667465428456, 461.63069544364373, 468.20171607409964, 471.40132032319445, 472.74235541629895, 3510.7191424685966, 3842.538895332578, 3904.590018048121, 3993.3610622300876, 4044.988752811797, 10740.570377184826, 11024.393658348868, 11129.0, 11144.832827507595, 11193.048590869867, 10714.25000249975, 10865.641794775653, 10921.39932303715, 11075.390853503135, 11085.778284434266, 7323.670434830446, 8433.831323373499, 8548.273396761346, 8557.0, 8584.184502472255, 5827.125931110368, 5835.649861008353, 5896.292444906639, 5927.673977931083, 6031.276246850406, 10001.799964000826, 10193.23550733683, 10221.131203847755, 10285.691429257135, 10320.380777153396, 10508.264421490423, 10562.260641755003, 10591.5233814477, 10610.310329828537, 10616.575336986434, 6859.622720750406, 7067.681954312171, 7750.92249077502, 7855.135935047026, 8258.215469530385, 8364.6235919385, 8367.539785311867, 8668.263197628272, 8674.696385626472, 8690.608922598625, 7620.1998790128455, 7718.691252349843, 7720.961395192988, 7741.806454838674, 7849.0, 7473.663685134291, 7482.139553951365, 7560.017197764256, 7594.974678418343, 7764.301212890641, 6981.650917454209, 7147.785566433015, 7149.034880291094, 7152.284771522806, 7183.173934997542, 5049.091163590442, 5051.419086804917, 5101.974490127525, 5185.299984502044, 5363.570914326767, 5072.137736584863, 5110.259012443101, 5125.974370128126, 5256.053910296183, 5319.4414586467865], "value": 4601.54025238039, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [279.9146260390581, 312.9655737868833, 317.8935056755986, 323.81380706094, 325.6287831871665, 5159.432462429129, 5360.410354860972, 5388.919166212505, 5440.292761940952, 5448.45515448455, 4220.125754753257, 4383.583559561845, 5147.202183661539, 5287.39194992576, 5330.706811125387, 3513.3149035938004, 4087.651075145195, 4093.447384603078, 4180.24755544002, 4208.97895510522, 3246.772725909187, 4056.371262454324, 4182.6197354872975, 4226.978865105671, 4238.830446782125, 3887.9782513093173, 3982.6060878692515, 4156.628312656816, 4200.991761977134, 4218.860907554958, 4172.018291311602, 4172.4784401949755, 4184.372344148372, 4194.4337514435465, 4203.201391735576, 3070.6775788542327, 3682.834272457746, 3695.1685870679153, 3706.6478684524927, 3707.7404581679293, 5014.0, 5260.8684782880355, 5272.156454967187, 5272.894542109137, 5352.197170424429, 4189.664826813848, 4218.620324170809, 4889.997550502138, 4907.214845624683, 4918.163912134945, 4342.0, 4707.764611769432, 5693.007447393421, 5718.911787634673, 5724.252358867725, 3193.552902593639, 3873.3996230584303, 4034.031832360242, 4061.0, 4094.5086589609436, 4962.379702537219, 5950.9702451487465, 5952.851178720523, 5997.640141591475, 6011.909821352683, 3832.616738326201, 4160.729552579072, 4171.97914010428, 4182.996080940552, 4192.553569018683, 3674.430463278196, 3845.5577608575372, 4061.8926521211297, 4222.0289333453475, 4250.617444430016, 4757.952420475751, 4804.42346918372, 4810.11012962603, 4819.349387832668, 4831.73425461603, 5714.428557144335, 5793.391693872083, 5796.5072968797285, 5797.333306669702, 5801.158831969341, 1301.139370699908, 1328.7509740843577, 1338.380559522988, 1412.7779470757926, 1424.7364237616075, 765.0, 790.517784151667, 877.5787621941377, 884.3853521802414, 888.8000199954959, 4453.704258848429, 4454.7549884756, 4478.462584489882, 4494.910101798011, 4502.639788816887, 4402.779861006937, 4432.711873728197, 4845.806167753321, 4870.342503762017, 4896.069746748107, 3767.830447629863, 3980.800959951991, 4458.331250312479, 4464.531224221475, 4472.239719247736, 2585.5346037713393, 2810.0, 3106.4718997770433, 3134.5925029745986, 3135.278885856266, 5846.94153058464, 6211.546439156508, 6214.782482613087, 6234.690714949911, 6241.500679945593, 4977.352944117314, 5918.822435326947, 6049.697515124226, 6131.4977830430935, 6155.96922015396, 6117.938820611826, 6180.042093475428, 6181.752729890754, 6197.25632924043, 6246.843828904313, 5336.759845807026, 5980.5215582754145, 6025.270162922086, 6056.031035034373, 6073.908891366721, 4735.608517698678, 5009.974950125226, 5425.755840987086, 5452.473307473913, 5946.554008449297, 6208.4722798562625, 6215.81352559424, 6257.186565746424, 6264.404881536247, 6319.368063193644, 3834.386498160281, 3929.7249192556246, 4016.196760647824, 4026.9731218539087, 4079.6940229482316, 5182.870428239325, 5671.079394112118, 5929.495992840657, 5954.821355359346, 5961.344252132175, 5417.620766546304, 5431.945680543144, 5609.569559762398, 5723.484886360247, 5863.384344643751, 5125.589952803842, 5801.158831969426, 5806.2451881255165, 5819.854503637443, 5843.357230704704, 4614.60120333607, 5141.305923700253, 5471.781128754806, 5524.530414914777, 5692.288463942007, 5078.796848126033, 5777.104548794943, 5789.247397838254, 5796.04365279741, 5800.651960882361, 4969.55274025325, 5632.493075623213, 5706.429357064427, 5718.571107166896, 5739.741711623071], "value": 4140.232421123591, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [751.1924680967961, 760.1866003376387, 786.3551887452288, 803.3693550562809, 872.3893274707705, 15575.454859079935, 16038.0, 16852.747208791847, 16863.747043794323, 16936.322547098116, 13594.320283985811, 13950.720985580283, 14742.63143421415, 15157.469488567902, 15165.393384264644, 726.5096060159372, 745.9179490256067, 885.9246964008067, 911.8905731312252, 915.2083447817623, 885.9867101993475, 906.148220672569, 911.9863202051974, 915.4644532948233, 918.7289749523873, 964.1130160252584, 966.5408930757869, 967.7677357434236, 967.840306349455, 972.6692924405701, 953.4803532075035, 954.5179684259405, 956.0057540158227, 956.8995255498143, 957.4638202606562, 714.5855403865745, 715.8031541326125, 716.3946465236867, 716.505611128325, 717.4941666125369, 14961.700765984728, 15115.924420377938, 15141.924290378587, 15155.393784248618, 15161.924190379088, 12305.384730763515, 14675.853241467663, 14996.100233985886, 15027.774583381259, 15145.924270378688, 12043.759124817541, 12227.449764760608, 12278.877211227862, 12325.075619328496, 12406.88886135547, 3765.6046115157787, 3881.9805900970596, 3902.6682731967817, 3927.528696556403, 4029.738067025634, 97960.51019744927, 100769.0, 100988.99011009869, 115712.0, 121125.0, 11334.206605537698, 11426.08591312693, 11434.656960291204, 11474.94262528682, 11711.76576468483, 22759.431014224778, 22774.65838012431, 22779.88610056939, 22824.543509129726, 22829.086836526352, 7897.802554936057, 7918.089419716806, 7958.482698624571, 8226.341892648574, 8270.007599088149, 4189.413482112506, 4295.78521073945, 4337.240982827992, 4474.7762611869275, 4503.729776213438, 3552.348158106493, 3658.213484100931, 3731.440283957428, 3734.495843061152, 3734.6825519830577, 549.2557584473061, 560.6636018389015, 563.5181919458886, 570.090705325006, 574.7643466178847, 617.7903861931272, 632.2318383164477, 638.8147437243148, 648.6302807399737, 669.2905520148561, 8949.955250223707, 9353.298502612332, 9422.246220302359, 9433.528323583794, 9641.517924103768, 568.7355379748354, 637.7130291368902, 658.7595527632379, 665.4809248785955, 674.8582797612459, 3955.861544845917, 4276.593723596254, 4553.886152846206, 4554.453465584085, 4555.33947577606, 166638.0, 175092.0, 175221.12389438227, 178811.0, 182103.0, 151855.0, 153604.23197883938, 155137.0, 155928.0, 156792.4320756778, 100184.0, 118154.0, 122121.0, 124044.9999999982, 134703.0, 8566.657333706582, 8922.55387230623, 8939.418937769024, 8967.306385448122, 9150.862737058816, 43515.38128572977, 67593.66203168953, 70084.29915700777, 71256.93114603181, 75083.62458187673, 80486.19513804787, 85323.0, 88516.0, 93144.0, 99973.50013249887, 13135.34323283813, 13312.866871331551, 13526.59420217395, 14069.52270011634, 14082.366293516612, 14413.351399186851, 14760.557183284518, 15883.52349429519, 16034.037957722574, 16526.256318465457, 13821.596618948159, 14539.854601454274, 14715.60201780829, 15145.091294522364, 15377.231138442809, 13797.862021379658, 13891.305434728425, 14917.104973701613, 15370.846291536942, 15493.07041577509, 13171.341432928124, 13741.587752367444, 13933.233672147724, 14364.35360408805, 14633.41466341377, 11378.886211138113, 11619.302841829518, 11821.320844578546, 11952.76094478123, 12556.0, 10122.038406351385, 10610.151187905103, 11351.0, 11937.52249909994, 11993.820092698443], "value": 8097.040988540308, "unit": "score"}], "name": "dromaeo_css", "value": 7471.18578602467, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 115.0, 115.0, 113.0, 113.0, 115.0, 113.0, 114.0, 113.0, 114.0], "value": 113.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 96.0, 132.0, 176.0, 140.0, 121.0, 105.0, 107.0, 138.0, 108.0], "value": 129.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 187.0, 183.0, 182.0, 182.0, 182.0, 182.0, 188.0, 182.0, 180.0], "value": 183.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 60.0, 58.0, 88.0, 61.0, 57.0, 59.0, 57.0, 82.0, 58.0], "value": 65.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 70.0, 94.0, 68.0, 66.0, 70.0, 116.0, 98.0, 101.0, 98.0], "value": 86.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 88.0, 91.0, 88.0, 84.0, 90.0, 87.0, 95.0, 84.0], "value": 88.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 108.0, 96.0, 101.0, 95.0, 94.0, 97.0, 95.0, 95.0, 95.0], "value": 97.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 107.0, 131.0, 138.0, 84.0, 85.0, 84.0, 86.0, 84.0, 85.0], "value": 97.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 52.0, 54.0, 53.0, 57.0, 56.0, 57.0, 56.0, 56.0, 56.0], "value": 55.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 70.0, 71.0, 74.0, 71.0, 68.0, 67.0, 67.0, 69.0, 68.0], "value": 68.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 80.0, 81.0, 84.0, 81.0, 80.0, 84.0, 88.0, 90.0], "value": 83.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 143.0, 134.0, 141.0, 154.0, 148.0, 145.0, 140.0, 177.0, 174.0], "value": 151.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 157.0, 159.0, 161.0, 160.0, 156.0, 162.0, 161.0, 160.0, 161.0], "value": 160.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 67.0, 66.0, 65.0, 65.0, 66.0, 65.0, 65.0, 65.0, 64.0], "value": 65.3, "unit": "score"}], "name": "kraken", "value": 1444.7, "alertThreshold": 2.0}]} 09:36:05 INFO - Return code: 0 09:36:05 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:36:05 INFO - # TBPL SUCCESS # 09:36:05 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:36:05 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:36:05 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:36:05 INFO - 2016-12-09 09:36:05.672 screenresolution[1658:9908] starting screenresolution argv=screenresolution get 09:36:05 INFO - 2016-12-09 09:36:05.675 screenresolution[1658:9908] Display 0: 1600x1200x32@60 09:36:05 INFO - 2016-12-09 09:36:05.681 screenresolution[1659:9911] starting screenresolution argv=screenresolution list 09:36:05 INFO - Available Modes on Display 0 09:36:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:36:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:36:05 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:36:05 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:36:05 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:36:05 INFO - Intel Iris: 09:36:05 INFO - Chipset Model: Intel Iris 09:36:05 INFO - Type: GPU 09:36:05 INFO - Bus: Built-In 09:36:05 INFO - VRAM (Dynamic, Max): 1536 MB 09:36:05 INFO - Vendor: Intel (0x8086) 09:36:05 INFO - Device ID: 0x0a2e 09:36:05 INFO - Revision ID: 0x0009 09:36:05 INFO - Displays: 09:36:05 INFO - Display: 09:36:05 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:36:05 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:36:05 INFO - Display Serial Number: 1600x1200 60 09:36:05 INFO - Main Display: Yes 09:36:05 INFO - Mirror: Off 09:36:05 INFO - Online: Yes 09:36:05 INFO - Rotation: Supported 09:36:05 INFO - Return code: 0 09:36:05 INFO - Running post-action listener: _resource_record_post_action 09:36:05 INFO - [mozharness: 2016-12-09 17:36:05.918424Z] Finished run-tests step (success) 09:36:05 INFO - Running post-run listener: _resource_record_post_run 09:36:05 INFO - Total resource usage - Wall time: 1196s; CPU: 53.0%; Read bytes: 38827008; Write bytes: 492782592; Read time: 381; Write time: 1874 09:36:05 INFO - TinderboxPrint: CPU usage
53.4% 09:36:05 INFO - TinderboxPrint: I/O read bytes / time
38,827,008 / 381 09:36:05 INFO - TinderboxPrint: I/O write bytes / time
492,782,592 / 1,874 09:36:05 INFO - TinderboxPrint: CPU idle
2,232.2 (46.6%) 09:36:05 INFO - TinderboxPrint: CPU system
956.3 (20.0%) 09:36:05 INFO - TinderboxPrint: CPU user
1,597.3 (33.4%) 09:36:05 INFO - TinderboxPrint: Swap in / out
234,393,600 / 0 09:36:05 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 196221440; Write bytes: 195923968; Read time: 17788; Write time: 363 09:36:05 INFO - run-tests - Wall time: 1175s; CPU: 53.0%; Read bytes: 34575360; Write bytes: 295498752; Read time: 347; Write time: 1504 09:36:06 INFO - Running post-run listener: _upload_blobber_files 09:36:06 INFO - Blob upload gear active. 09:36:06 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:36:06 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 09:36:06 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'] 09:36:06 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 09:36:06 INFO - (blobuploader) - INFO - Open directory for files ... 09:36:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:36:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:36:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:36:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:36:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:36:07 INFO - (blobuploader) - INFO - Done attempting. 09:36:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:36:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:36:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:36:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:36:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:36:08 INFO - (blobuploader) - INFO - Done attempting. 09:36:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:36:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:36:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:36:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:36:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:36:10 INFO - (blobuploader) - INFO - Done attempting. 09:36:10 INFO - (blobuploader) - INFO - Iteration through files over. 09:36:10 INFO - Return code: 0 09:36:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:36:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:36:10 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2d4490df5e9485f6575bcfedb2a203fbf7f28c8e64f571ba5bec211251a1fd17490727296e86495407f11ce5dbad77cd7d147c8a3442df4e24f1e9c8b803b503", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4c62241cb95eeb128fdf29793b6fba60920a65ba0cca05c6f8cef3b37cf5cd8a0d4992ac26eaf4b2c5d68aa4437583bee28ea204f33b045a4e47aa190f9cb6fb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/691ec3b48b26e5cb5462078e5268f5c3416f7230afd104b1d53be72a6f28682672e310b344f10ac1a0e7887fddd981eeef8405209dccdedd0e87c200da4d4de1"} 09:36:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:36:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:36:10 INFO - Contents: 09:36:10 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2d4490df5e9485f6575bcfedb2a203fbf7f28c8e64f571ba5bec211251a1fd17490727296e86495407f11ce5dbad77cd7d147c8a3442df4e24f1e9c8b803b503", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4c62241cb95eeb128fdf29793b6fba60920a65ba0cca05c6f8cef3b37cf5cd8a0d4992ac26eaf4b2c5d68aa4437583bee28ea204f33b045a4e47aa190f9cb6fb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/691ec3b48b26e5cb5462078e5268f5c3416f7230afd104b1d53be72a6f28682672e310b344f10ac1a0e7887fddd981eeef8405209dccdedd0e87c200da4d4de1"} 09:36:10 INFO - Running post-run listener: copy_logs_to_upload_dir 09:36:10 INFO - Copying logs to upload dir... 09:36:10 INFO - mkdir: /builds/slave/test/build/upload/logs 09:36:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1255.907280 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2016-12-09 09:36:10.971433) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-09 09:36:10.975543) ========= 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.5flTsvcXN8/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.S0JjKvJLC9/Listeners TMPDIR=/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2d4490df5e9485f6575bcfedb2a203fbf7f28c8e64f571ba5bec211251a1fd17490727296e86495407f11ce5dbad77cd7d147c8a3442df4e24f1e9c8b803b503", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4c62241cb95eeb128fdf29793b6fba60920a65ba0cca05c6f8cef3b37cf5cd8a0d4992ac26eaf4b2c5d68aa4437583bee28ea204f33b045a4e47aa190f9cb6fb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/691ec3b48b26e5cb5462078e5268f5c3416f7230afd104b1d53be72a6f28682672e310b344f10ac1a0e7887fddd981eeef8405209dccdedd0e87c200da4d4de1"} build_url:https://queue.taskcluster.net/v1/task/Glx4_IIuQ_Gs2GHLSKEL7Q/artifacts/public/build/firefox-51.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011257 build_url: 'https://queue.taskcluster.net/v1/task/Glx4_IIuQ_Gs2GHLSKEL7Q/artifacts/public/build/firefox-51.0.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2d4490df5e9485f6575bcfedb2a203fbf7f28c8e64f571ba5bec211251a1fd17490727296e86495407f11ce5dbad77cd7d147c8a3442df4e24f1e9c8b803b503", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4c62241cb95eeb128fdf29793b6fba60920a65ba0cca05c6f8cef3b37cf5cd8a0d4992ac26eaf4b2c5d68aa4437583bee28ea204f33b045a4e47aa190f9cb6fb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/691ec3b48b26e5cb5462078e5268f5c3416f7230afd104b1d53be72a6f28682672e310b344f10ac1a0e7887fddd981eeef8405209dccdedd0e87c200da4d4de1"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-09 09:36:11.018112) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-09 09:36:11.018471) ========= 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.5flTsvcXN8/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.S0JjKvJLC9/Listeners TMPDIR=/var/folders/h6/mf44gd353f7dv2g4t9vfyz5w00000w/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.005054 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-09 09:36:11.068141) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-09 09:36:11.068744) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-09 09:36:11.069336) ========= ========= Total master_lag: 0.19 =========