builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0086 starttime: 1471623410.95 results: success (0) buildid: 20160819065522 builduid: 3de745e1cbf2478f9e106e7bf69a3fcf revision: 8dfc2fdb7ae3990b73f8207aaf62de68a4f47bf5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-19 09:16:50.949253) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-19 09:16:50.949712) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 3 secs) (at 2016-08-19 09:16:50.950002) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 3 secs) (at 2016-08-19 09:16:54.229745) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 09:16:54.230019) ========= 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.m2cxzwOVtW/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.c5y7kbil3Q/Listeners TMPDIR=/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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-08-19 09:16:54-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 74.7M=0s 2016-08-19 09:16:54 (74.7 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.083001 ========= master_lag: 0.53 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 09:16:54.847803) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 5 secs) (at 2016-08-19 09:16:54.848108) ========= 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.m2cxzwOVtW/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.c5y7kbil3Q/Listeners TMPDIR=/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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.020459 ========= master_lag: 5.80 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 5 secs) (at 2016-08-19 09:17:00.667125) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 9 secs) (at 2016-08-19 09:17:00.667583) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 8dfc2fdb7ae3990b73f8207aaf62de68a4f47bf5 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 8dfc2fdb7ae3990b73f8207aaf62de68a4f47bf5 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.m2cxzwOVtW/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.c5y7kbil3Q/Listeners TMPDIR=/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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-08-19 09:17:00,891 truncating revision to first 12 chars 2016-08-19 09:17:00,891 Setting DEBUG logging. 2016-08-19 09:17:00,891 attempt 1/10 2016-08-19 09:17:00,891 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/8dfc2fdb7ae3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-19 09:17:02,168 unpacking tar archive at: mozilla-central-8dfc2fdb7ae3/testing/mozharness/ program finished with exit code 0 elapsedTime=1.545459 ========= master_lag: 7.79 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 9 secs) (at 2016-08-19 09:17:10.000373) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-19 09:17:10.000769) ========= script_repo_revision: 8dfc2fdb7ae3990b73f8207aaf62de68a4f47bf5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-19 09:17:10.001411) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 2 secs) (at 2016-08-19 09:17:10.001786) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 2 secs) (at 2016-08-19 09:17:12.271613) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-08-19 09:17:12.272158) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.m2cxzwOVtW/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.c5y7kbil3Q/Listeners TMPDIR=/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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:17:12 INFO - MultiFileLogger online at 20160819 09:17:12 in /builds/slave/test 09:17:12 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:17:12 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:17:12 INFO - {'append_to_log': False, 09:17:12 INFO - 'base_work_dir': '/builds/slave/test', 09:17:12 INFO - 'blob_upload_branch': 'Firefox', 09:17:12 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:17:12 INFO - 'branch': 'Firefox', 09:17:12 INFO - 'buildbot_json_path': 'buildprops.json', 09:17:12 INFO - 'config_files': ('talos/mac_config.py',), 09:17:12 INFO - 'default_actions': ('clobber', 09:17:12 INFO - 'read-buildbot-config', 09:17:12 INFO - 'download-and-extract', 09:17:12 INFO - 'populate-webroot', 09:17:12 INFO - 'create-virtualenv', 09:17:12 INFO - 'install', 09:17:12 INFO - 'run-tests'), 09:17:12 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:17:12 INFO - 'download_minidump_stackwalk': True, 09:17:12 INFO - 'download_symbols': 'ondemand', 09:17:12 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:17:12 INFO - 'tooltool.py': '/tools/tooltool.py', 09:17:12 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:17:12 INFO - '/tools/misc-python/virtualenv.py')}, 09:17:12 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:17:12 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:17:12 INFO - 'installer_path': 'installer.exe', 09:17:12 INFO - 'log_level': 'info', 09:17:12 INFO - 'log_name': 'talos', 09:17:12 INFO - 'log_to_console': True, 09:17:12 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:17:12 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:17:12 INFO - 'opt_config_files': (), 09:17:12 INFO - 'pip_index': False, 09:17:12 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:17:12 INFO - 'cmd': ('bash', 09:17:12 INFO - '-c', 09:17:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:17:12 INFO - 'enabled': True, 09:17:12 INFO - 'halt_on_failure': False, 09:17:12 INFO - 'name': 'check_screen_resolution'},), 09:17:12 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:17:12 INFO - 'cmd': ('bash', 09:17:12 INFO - '-c', 09:17:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:17:12 INFO - 'enabled': True, 09:17:12 INFO - 'halt_on_failure': False, 09:17:12 INFO - 'name': 'check_screen_resolution'},), 09:17:12 INFO - 'run_cmd_checks_enabled': True, 09:17:12 INFO - 'sps_profile': False, 09:17:12 INFO - 'sps_profile_interval': 0, 09:17:12 INFO - 'suite': 'dromaeojs', 09:17:12 INFO - 'system_bits': '32', 09:17:12 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:17:12 INFO - 'title': 't-yosemite-r7-0086', 09:17:12 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:17:12 INFO - 'use_talos_json': True, 09:17:12 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:17:12 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:17:12 INFO - 'work_dir': 'build'} 09:17:12 INFO - [mozharness: 2016-08-19 16:17:12.549768Z] Running clobber step. 09:17:12 INFO - Running pre-action listener: _resource_record_pre_action 09:17:12 INFO - Running main action method: clobber 09:17:12 INFO - rmtree: /builds/slave/test/build 09:17:12 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:17:13 INFO - Running post-action listener: _resource_record_post_action 09:17:13 INFO - [mozharness: 2016-08-19 16:17:13.982223Z] Finished clobber step (success) 09:17:13 INFO - [mozharness: 2016-08-19 16:17:13.982381Z] Running read-buildbot-config step. 09:17:13 INFO - Running pre-action listener: _resource_record_pre_action 09:17:13 INFO - Running main action method: read_buildbot_config 09:17:13 INFO - Using buildbot properties: 09:17:13 INFO - { 09:17:13 INFO - "project": "", 09:17:13 INFO - "product": "firefox", 09:17:13 INFO - "script_repo_revision": "production", 09:17:13 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 09:17:13 INFO - "repository": "", 09:17:13 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 09:17:13 INFO - "buildid": "20160819065522", 09:17:13 INFO - "pgo_build": "False", 09:17:13 INFO - "basedir": "/builds/slave/test", 09:17:13 INFO - "buildnumber": 68, 09:17:13 INFO - "slavename": "t-yosemite-r7-0086", 09:17:13 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 09:17:13 INFO - "platform": "yosemite_r7", 09:17:13 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 09:17:13 INFO - "branch": "mozilla-central", 09:17:13 INFO - "repo_path": "mozilla-central", 09:17:13 INFO - "revision": "8dfc2fdb7ae3990b73f8207aaf62de68a4f47bf5", 09:17:13 INFO - "stage_platform": "macosx64", 09:17:13 INFO - "builduid": "3de745e1cbf2478f9e106e7bf69a3fcf", 09:17:13 INFO - "slavebuilddir": "test" 09:17:13 INFO - } 09:17:13 INFO - Found installer url https://queue.taskcluster.net/v1/task/RGgd55ucThm9pe3vugGg-Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 09:17:13 INFO - Running post-action listener: _resource_record_post_action 09:17:13 INFO - [mozharness: 2016-08-19 16:17:13.985595Z] Finished read-buildbot-config step (success) 09:17:13 INFO - [mozharness: 2016-08-19 16:17:13.985728Z] Running download-and-extract step. 09:17:13 INFO - Running pre-action listener: _resource_record_pre_action 09:17:13 INFO - Running main action method: download_and_extract 09:17:13 INFO - mkdir: /builds/slave/test/build/tests 09:17:13 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:17:13 INFO - trying https://queue.taskcluster.net/v1/task/RGgd55ucThm9pe3vugGg-Q/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 09:17:13 INFO - Downloading https://queue.taskcluster.net/v1/task/RGgd55ucThm9pe3vugGg-Q/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 09:17:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RGgd55ucThm9pe3vugGg-Q/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 09:17:15 INFO - Downloaded 1199 bytes. 09:17:15 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 09:17:15 INFO - Using the following test package requirements: 09:17:15 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 09:17:15 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:17:15 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 09:17:15 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:17:15 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 09:17:15 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:17:15 INFO - u'jsshell-mac.zip'], 09:17:15 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:17:15 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 09:17:15 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 09:17:15 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:17:15 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 09:17:15 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:17:15 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 09:17:15 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:17:15 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 09:17:15 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:17:15 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 09:17:15 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:17:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:15 INFO - trying https://queue.taskcluster.net/v1/task/RGgd55ucThm9pe3vugGg-Q/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 09:17:15 INFO - Downloading https://queue.taskcluster.net/v1/task/RGgd55ucThm9pe3vugGg-Q/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 09:17:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RGgd55ucThm9pe3vugGg-Q/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:17:16 INFO - Downloaded 16350123 bytes. 09:17:16 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 09:17:23 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 09:17:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:23 INFO - trying https://queue.taskcluster.net/v1/task/RGgd55ucThm9pe3vugGg-Q/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 09:17:23 INFO - Downloading https://queue.taskcluster.net/v1/task/RGgd55ucThm9pe3vugGg-Q/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 09:17:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RGgd55ucThm9pe3vugGg-Q/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:17:24 INFO - Downloaded 16350123 bytes. 09:17:24 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 09:17:30 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:17:30 INFO - trying https://queue.taskcluster.net/v1/task/RGgd55ucThm9pe3vugGg-Q/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 09:17:30 INFO - Downloading https://queue.taskcluster.net/v1/task/RGgd55ucThm9pe3vugGg-Q/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip 09:17:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RGgd55ucThm9pe3vugGg-Q/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:17:31 INFO - Downloaded 14413981 bytes. 09:17:31 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 09:17: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:17:31 INFO - trying https://queue.taskcluster.net/v1/task/RGgd55ucThm9pe3vugGg-Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 09:17:31 INFO - Downloading https://queue.taskcluster.net/v1/task/RGgd55ucThm9pe3vugGg-Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 09:17:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RGgd55ucThm9pe3vugGg-Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 09:17:34 INFO - Downloaded 111377957 bytes. 09:17:34 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RGgd55ucThm9pe3vugGg-Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 09:17:34 INFO - mkdir: /builds/slave/test/properties 09:17:34 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:17:34 INFO - Writing to file /builds/slave/test/properties/build_url 09:17:34 INFO - Contents: 09:17:34 INFO - build_url:https://queue.taskcluster.net/v1/task/RGgd55ucThm9pe3vugGg-Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 09:17:35 INFO - Running post-action listener: _resource_record_post_action 09:17:35 INFO - Running post-action listener: set_extra_try_arguments 09:17:35 INFO - [mozharness: 2016-08-19 16:17:35.879242Z] Finished download-and-extract step (success) 09:17:35 INFO - [mozharness: 2016-08-19 16:17:35.879376Z] Running populate-webroot step. 09:17:35 INFO - Running pre-action listener: _resource_record_pre_action 09:17:35 INFO - Running main action method: populate_webroot 09:17:35 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:17:35 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:17:35 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:17:35 INFO - '--tppagecycles', 09:17:35 INFO - '7'], 09:17:35 INFO - 'tests': ['tsvgm']}}, 09:17:35 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:17:35 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:17:35 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:17:35 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:17:35 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:17:35 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:17:35 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:17:35 INFO - 'talos_options': ['--disable-e10s'], 09:17:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:17:35 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:17:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:17:35 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:17:35 INFO - 'talos_options': ['--disable-e10s'], 09:17:35 INFO - 'tests': ['damp', 'tps']}, 09:17:35 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:17:35 INFO - 'tests': ['damp', 'tps']}, 09:17:35 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:17:35 INFO - 'tests': ['dromaeo_dom']}, 09:17:35 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:17:35 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:17:35 INFO - 'tests': ['basic_compositor_video']}, 09:17:35 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:17:35 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:17:35 INFO - 'tests': ['a11yr', 09:17:35 INFO - 'ts_paint', 09:17:35 INFO - 'tpaint', 09:17:35 INFO - 'sessionrestore', 09:17:35 INFO - 'sessionrestore_no_auto_restore', 09:17:35 INFO - 'tabpaint']}, 09:17:35 INFO - 'other-e10s': {'tests': ['a11yr', 09:17:35 INFO - 'ts_paint', 09:17:35 INFO - 'tpaint', 09:17:35 INFO - 'sessionrestore', 09:17:35 INFO - 'sessionrestore_no_auto_restore', 09:17:35 INFO - 'tabpaint']}, 09:17:35 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:17:35 INFO - 'ts_paint', 09:17:35 INFO - 'tpaint', 09:17:35 INFO - 'sessionrestore', 09:17:35 INFO - 'sessionrestore_no_auto_restore', 09:17:35 INFO - 'tabpaint']}, 09:17:35 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:17:35 INFO - 'ts_paint', 09:17:35 INFO - 'tpaint', 09:17:35 INFO - 'sessionrestore', 09:17:35 INFO - 'sessionrestore_no_auto_restore', 09:17:35 INFO - 'tabpaint']}, 09:17:35 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:17:35 INFO - 'tests': ['a11yr', 09:17:35 INFO - 'ts_paint', 09:17:35 INFO - 'tpaint', 09:17:35 INFO - 'sessionrestore', 09:17:35 INFO - 'sessionrestore_no_auto_restore', 09:17:35 INFO - 'tabpaint']}, 09:17:35 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:17:35 INFO - 'tests': ['a11yr', 09:17:35 INFO - 'ts_paint', 09:17:35 INFO - 'tpaint', 09:17:35 INFO - 'sessionrestore', 09:17:35 INFO - 'sessionrestore_no_auto_restore', 09:17:35 INFO - 'tabpaint']}, 09:17:35 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:17:35 INFO - 'tests': ['tsvgx', 09:17:35 INFO - 'tsvgr_opacity', 09:17:35 INFO - 'tart', 09:17:35 INFO - 'tscrollx', 09:17:35 INFO - 'cart']}, 09:17:35 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:17:35 INFO - 'tsvgr_opacity', 09:17:35 INFO - 'tart', 09:17:35 INFO - 'tscrollx', 09:17:35 INFO - 'cart']}, 09:17:35 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:17:35 INFO - 'talos_options': ['--disable-e10s'], 09:17:35 INFO - 'tests': ['tp5o']}, 09:17:35 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:17:35 INFO - 'tests': ['tp5o']}, 09:17:35 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:17:35 INFO - 'talos_options': ['--disable-e10s', 09:17:35 INFO - '--xperf_path', 09:17:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:17:35 INFO - 'tests': ['tp5n']}, 09:17:35 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:17:35 INFO - 'talos_options': ['--xperf_path', 09:17:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:17:35 INFO - 'tests': ['tp5n']}}, 09:17:35 INFO - 'talos.zip': {'path': '', 09:17:35 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:17:35 INFO - Running post-action listener: _resource_record_post_action 09:17:35 INFO - [mozharness: 2016-08-19 16:17:35.898461Z] Finished populate-webroot step (success) 09:17:35 INFO - [mozharness: 2016-08-19 16:17:35.898604Z] Running create-virtualenv step. 09:17:35 INFO - Running pre-action listener: _resource_record_pre_action 09:17:35 INFO - Running main action method: create_virtualenv 09:17:35 INFO - Creating virtualenv /builds/slave/test/build/venv 09:17:35 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:17:35 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:17:36 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:17:36 INFO - Using real prefix '/tools/python27' 09:17:36 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:17:36 INFO - Installing distribute.............................................................................................................................................................................................done. 09:17:39 INFO - Installing pip.................done. 09:17:39 INFO - Return code: 0 09:17:39 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:17:39 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:17:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:39 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:17:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:39 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 0x1025cfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102664618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6e34c14a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102533d30>, '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 0x102298db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10252bb20>, '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.m2cxzwOVtW/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.c5y7kbil3Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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:17:39 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:17:39 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:17:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m2cxzwOVtW/Render', 09:17:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:17:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:17:39 INFO - 'HOME': '/Users/cltbld', 09:17:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:17:39 INFO - 'LOGNAME': 'cltbld', 09:17:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:39 INFO - 'MOZ_NO_REMOTE': '1', 09:17:39 INFO - 'NO_EM_RESTART': '1', 09:17:39 INFO - 'PAGER': '/bin/cat', 09:17:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:17:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:39 INFO - 'PWD': '/builds/slave/test', 09:17:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:17:39 INFO - 'SHELL': '/bin/bash', 09:17:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.c5y7kbil3Q/Listeners', 09:17:39 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 09:17:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:17:39 INFO - 'USER': 'cltbld', 09:17:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:17:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:17:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:39 INFO - 'XPC_FLAGS': '0x0', 09:17:39 INFO - 'XPC_SERVICE_NAME': '0', 09:17:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:17:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:39 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:17:39 INFO - Cleaning up... 09:17:39 INFO - Return code: 0 09:17:39 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:17:39 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:17:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:39 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:17:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:39 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 0x1025cfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102664618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6e34c14a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102533d30>, '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 0x102298db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10252bb20>, '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.m2cxzwOVtW/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.c5y7kbil3Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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:17:39 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:17:39 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:17:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m2cxzwOVtW/Render', 09:17:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:17:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:17:39 INFO - 'HOME': '/Users/cltbld', 09:17:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:17:39 INFO - 'LOGNAME': 'cltbld', 09:17:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:39 INFO - 'MOZ_NO_REMOTE': '1', 09:17:39 INFO - 'NO_EM_RESTART': '1', 09:17:39 INFO - 'PAGER': '/bin/cat', 09:17:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:17:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:39 INFO - 'PWD': '/builds/slave/test', 09:17:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:17:39 INFO - 'SHELL': '/bin/bash', 09:17:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.c5y7kbil3Q/Listeners', 09:17:39 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 09:17:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:17:39 INFO - 'USER': 'cltbld', 09:17:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:17:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:17:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:39 INFO - 'XPC_FLAGS': '0x0', 09:17:39 INFO - 'XPC_SERVICE_NAME': '0', 09:17:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:17:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:40 INFO - Downloading/unpacking psutil>=3.1.1 09:17:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:17:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:17:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:17:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:17:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:17:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:17:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:17:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:17:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:17:43 INFO - Installing collected packages: psutil 09:17:43 INFO - Running setup.py install for psutil 09:17:43 INFO - building 'psutil._psutil_osx' extension 09:17:43 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:17:43 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:17:43 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:17:43 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:17:43 INFO - building 'psutil._psutil_posix' extension 09:17:43 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:17:43 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:17:43 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:17:43 INFO - ^ 09:17:43 INFO - 1 warning generated. 09:17:43 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:17:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:17:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:17:43 INFO - Successfully installed psutil 09:17:43 INFO - Cleaning up... 09:17:43 INFO - Return code: 0 09:17:43 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:17:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:44 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:17:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:44 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 0x1025cfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102664618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6e34c14a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102533d30>, '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 0x102298db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10252bb20>, '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.m2cxzwOVtW/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.c5y7kbil3Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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:17:44 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:17:44 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:17:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m2cxzwOVtW/Render', 09:17:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:17:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:17:44 INFO - 'HOME': '/Users/cltbld', 09:17:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:17:44 INFO - 'LOGNAME': 'cltbld', 09:17:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:44 INFO - 'MOZ_NO_REMOTE': '1', 09:17:44 INFO - 'NO_EM_RESTART': '1', 09:17:44 INFO - 'PAGER': '/bin/cat', 09:17:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:17:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:44 INFO - 'PWD': '/builds/slave/test', 09:17:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:17:44 INFO - 'SHELL': '/bin/bash', 09:17:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.c5y7kbil3Q/Listeners', 09:17:44 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 09:17:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:17:44 INFO - 'USER': 'cltbld', 09:17:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:17:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:17:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:44 INFO - 'XPC_FLAGS': '0x0', 09:17:44 INFO - 'XPC_SERVICE_NAME': '0', 09:17:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:17:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:44 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:17:44 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:17:44 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:17:44 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:17:44 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:17:44 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:17:44 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:17:47 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:17:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:17:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:17:47 INFO - Installing collected packages: mozsystemmonitor 09:17:47 INFO - Running setup.py install for mozsystemmonitor 09:17:47 INFO - Successfully installed mozsystemmonitor 09:17:47 INFO - Cleaning up... 09:17:47 INFO - Return code: 0 09:17:47 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:17:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1025cfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102664618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6e34c14a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102533d30>, '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 0x102298db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10252bb20>, '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.m2cxzwOVtW/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.c5y7kbil3Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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:17:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:17:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:17:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m2cxzwOVtW/Render', 09:17:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:17:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:17:47 INFO - 'HOME': '/Users/cltbld', 09:17:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:17:47 INFO - 'LOGNAME': 'cltbld', 09:17:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:47 INFO - 'MOZ_NO_REMOTE': '1', 09:17:47 INFO - 'NO_EM_RESTART': '1', 09:17:47 INFO - 'PAGER': '/bin/cat', 09:17:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:17:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:47 INFO - 'PWD': '/builds/slave/test', 09:17:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:17:47 INFO - 'SHELL': '/bin/bash', 09:17:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.c5y7kbil3Q/Listeners', 09:17:47 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 09:17:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:17:47 INFO - 'USER': 'cltbld', 09:17:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:17:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:17:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:47 INFO - 'XPC_FLAGS': '0x0', 09:17:47 INFO - 'XPC_SERVICE_NAME': '0', 09:17:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:17:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:47 INFO - Downloading/unpacking jsonschema==2.5.1 09:17:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:17:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:17:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:17:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:17:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:17:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:17:50 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:17:50 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:17:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:17:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:17:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:17:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:17:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:17:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:17:50 INFO - Downloading functools32-3.2.3-2.tar.gz 09:17:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:17:50 INFO - warning: no files found matching '*.txt' 09:17:50 INFO - no previously-included directories found matching 'build' 09:17:50 INFO - no previously-included directories found matching 'dist' 09:17:50 INFO - no previously-included directories found matching '.git*' 09:17:50 INFO - Installing collected packages: jsonschema, functools32 09:17:50 INFO - Running setup.py install for functools32 09:17:51 INFO - warning: no files found matching '*.txt' 09:17:51 INFO - no previously-included directories found matching 'build' 09:17:51 INFO - no previously-included directories found matching 'dist' 09:17:51 INFO - no previously-included directories found matching '.git*' 09:17:51 INFO - Successfully installed jsonschema functools32 09:17:51 INFO - Cleaning up... 09:17:51 INFO - Return code: 0 09:17:51 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:17:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1025cfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102664618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6e34c14a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102533d30>, '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 0x102298db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10252bb20>, '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.m2cxzwOVtW/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.c5y7kbil3Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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:17:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:17:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:17:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m2cxzwOVtW/Render', 09:17:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:17:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:17:51 INFO - 'HOME': '/Users/cltbld', 09:17:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:17:51 INFO - 'LOGNAME': 'cltbld', 09:17:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:51 INFO - 'MOZ_NO_REMOTE': '1', 09:17:51 INFO - 'NO_EM_RESTART': '1', 09:17:51 INFO - 'PAGER': '/bin/cat', 09:17:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:17:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:51 INFO - 'PWD': '/builds/slave/test', 09:17:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:17:51 INFO - 'SHELL': '/bin/bash', 09:17:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.c5y7kbil3Q/Listeners', 09:17:51 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 09:17:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:17:51 INFO - 'USER': 'cltbld', 09:17:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:17:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:17:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:51 INFO - 'XPC_FLAGS': '0x0', 09:17:51 INFO - 'XPC_SERVICE_NAME': '0', 09:17:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:17:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:51 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:17:51 INFO - Cleaning up... 09:17:51 INFO - Return code: 0 09:17:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:17:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1025cfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102664618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6e34c14a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102533d30>, '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 0x102298db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10252bb20>, '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.m2cxzwOVtW/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.c5y7kbil3Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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:17:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:17:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:17:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m2cxzwOVtW/Render', 09:17:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:17:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:17:51 INFO - 'HOME': '/Users/cltbld', 09:17:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:17:51 INFO - 'LOGNAME': 'cltbld', 09:17:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:51 INFO - 'MOZ_NO_REMOTE': '1', 09:17:51 INFO - 'NO_EM_RESTART': '1', 09:17:51 INFO - 'PAGER': '/bin/cat', 09:17:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:17:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:51 INFO - 'PWD': '/builds/slave/test', 09:17:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:17:51 INFO - 'SHELL': '/bin/bash', 09:17:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.c5y7kbil3Q/Listeners', 09:17:51 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 09:17:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:17:51 INFO - 'USER': 'cltbld', 09:17:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:17:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:17:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:51 INFO - 'XPC_FLAGS': '0x0', 09:17:51 INFO - 'XPC_SERVICE_NAME': '0', 09:17:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:17:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:51 INFO - Downloading/unpacking blobuploader==1.2.4 09:17:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:17:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:17:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:17:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:17:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:17:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:17:54 INFO - Downloading blobuploader-1.2.4.tar.gz 09:17:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:17:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:17:54 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:17:54 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:17:54 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:17:54 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:17:54 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:17:54 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:17:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:17:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:17:54 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:17:54 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:17:54 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:17:54 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:17:54 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:17:54 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:17:55 INFO - Downloading docopt-0.6.1.tar.gz 09:17:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:17:55 INFO - Installing collected packages: blobuploader, requests, docopt 09:17:55 INFO - Running setup.py install for blobuploader 09:17:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:17:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:17:55 INFO - Running setup.py install for requests 09:17:55 INFO - Running setup.py install for docopt 09:17:56 INFO - Successfully installed blobuploader requests docopt 09:17:56 INFO - Cleaning up... 09:17:56 INFO - Return code: 0 09:17:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:17:56 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:17:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:17:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:56 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:17:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:17:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:17:56 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 0x1025cfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102664618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6e34c14a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102533d30>, '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 0x102298db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10252bb20>, '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.m2cxzwOVtW/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.c5y7kbil3Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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:17:56 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:17:56 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:17:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m2cxzwOVtW/Render', 09:17:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:17:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:17:56 INFO - 'HOME': '/Users/cltbld', 09:17:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:17:56 INFO - 'LOGNAME': 'cltbld', 09:17:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:56 INFO - 'MOZ_NO_REMOTE': '1', 09:17:56 INFO - 'NO_EM_RESTART': '1', 09:17:56 INFO - 'PAGER': '/bin/cat', 09:17:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:17:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:56 INFO - 'PWD': '/builds/slave/test', 09:17:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:17:56 INFO - 'SHELL': '/bin/bash', 09:17:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.c5y7kbil3Q/Listeners', 09:17:56 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 09:17:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:17:56 INFO - 'USER': 'cltbld', 09:17:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:17:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:17:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:56 INFO - 'XPC_FLAGS': '0x0', 09:17:56 INFO - 'XPC_SERVICE_NAME': '0', 09:17:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:17:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:17:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:17:56 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-Gr9scg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:17:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:17:56 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-ycNat8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:17:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:17:56 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-YjJMwj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:17:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:17:56 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-MgtTqS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:17:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:17:56 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-trYAMh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:17:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:17:57 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-SqUu0E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:17:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:17:57 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-5tCiCO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:17:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:17:57 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-CyqzeT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:17:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:17:57 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-AGgarA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:17:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:17:57 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-VE3ZPf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:17:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:17:57 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-NLkPtj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:17:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:17:57 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-26HTu6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:17:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:17:57 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-3EHR3L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:17:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:17:57 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-RNXgqY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:17:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:17:57 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-mDAs23-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:17:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:17:58 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-xeHf0X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:17:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:17:58 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-wYIlla-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:17:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:17:58 INFO - Running setup.py install for manifestparser 09:17:58 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:17:58 INFO - Running setup.py install for mozcrash 09:17:58 INFO - Running setup.py install for mozdebug 09:17:58 INFO - Running setup.py install for mozdevice 09:17:58 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:17:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:17:58 INFO - Running setup.py install for mozfile 09:17:59 INFO - Running setup.py install for mozhttpd 09:17:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:17:59 INFO - Running setup.py install for mozinfo 09:17:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:17:59 INFO - Running setup.py install for mozInstall 09:17:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:17:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:17:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:17:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:17:59 INFO - Running setup.py install for mozleak 09:17:59 INFO - Running setup.py install for mozlog 09:17:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:17:59 INFO - Running setup.py install for moznetwork 09:17:59 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:17:59 INFO - Running setup.py install for mozprocess 09:18:00 INFO - Running setup.py install for mozprofile 09:18:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:18:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:18:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:18:00 INFO - Running setup.py install for mozrunner 09:18:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:18:00 INFO - Running setup.py install for mozscreenshot 09:18:00 INFO - Running setup.py install for moztest 09:18:00 INFO - Running setup.py install for mozversion 09:18:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:18:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:18:00 INFO - Cleaning up... 09:18:00 INFO - Return code: 0 09:18:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:18:00 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:18:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:18:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:00 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:18:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:18:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:00 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 0x1025cfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102664618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6e34c14a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102533d30>, '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 0x102298db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10252bb20>, '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.m2cxzwOVtW/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.c5y7kbil3Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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:18:00 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:18:00 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:18:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m2cxzwOVtW/Render', 09:18:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:18:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:18:00 INFO - 'HOME': '/Users/cltbld', 09:18:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:18:00 INFO - 'LOGNAME': 'cltbld', 09:18:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:00 INFO - 'MOZ_NO_REMOTE': '1', 09:18:00 INFO - 'NO_EM_RESTART': '1', 09:18:00 INFO - 'PAGER': '/bin/cat', 09:18:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:18:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:18:00 INFO - 'PWD': '/builds/slave/test', 09:18:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:18:00 INFO - 'SHELL': '/bin/bash', 09:18:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.c5y7kbil3Q/Listeners', 09:18:00 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 09:18:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:18:00 INFO - 'USER': 'cltbld', 09:18:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:18:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:18:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:00 INFO - 'XPC_FLAGS': '0x0', 09:18:00 INFO - 'XPC_SERVICE_NAME': '0', 09:18:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:18:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:18:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:18:01 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-_0KmfE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:18:01 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:18:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:18:01 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-EF7Gvm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:18:01 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:18:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:18:01 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-1kasJY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:18:01 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:18:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:18:01 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-DxgIGG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:18:01 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:18:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:18:01 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-uB4t8f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:18:01 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:18:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:18:01 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-XdPUBC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:18:01 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:18:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:18:01 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-_ZMr5G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:18:01 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:18:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:18:01 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-jAPlLw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:18:01 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:18:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:18:01 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-kualEm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:18:02 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:18:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:18:02 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-d3rHng-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:18:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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:18:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:18:02 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-_K8fEf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:18:02 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:18:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:18:02 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-qARE2T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:18:02 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:18:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:18:02 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-oIHdXy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:18:02 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:18:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:18:02 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-4llAAh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:18:02 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:18:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:18:02 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-fp1qC7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:18:02 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:18:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:18:02 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-sml8eD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:18:02 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:18:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:18:02 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-SSGbbn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:18:02 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:18:02 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:18:02 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:18:02 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:18:02 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:18:02 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:18:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:18:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:18:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:18:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:18:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:18:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:18:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:18:05 INFO - Downloading blessings-1.6.tar.gz 09:18:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:18:05 INFO - Installing collected packages: blessings 09:18:05 INFO - Running setup.py install for blessings 09:18:05 INFO - Successfully installed blessings 09:18:05 INFO - Cleaning up... 09:18:05 INFO - Return code: 0 09:18:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:18:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:18:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:18:06 INFO - Reading from file tmpfile_stdout 09:18:06 INFO - Current package versions: 09:18:06 INFO - blessings == 1.6 09:18:06 INFO - blobuploader == 1.2.4 09:18:06 INFO - docopt == 0.6.1 09:18:06 INFO - functools32 == 3.2.3-2 09:18:06 INFO - jsonschema == 2.5.1 09:18:06 INFO - manifestparser == 1.1 09:18:06 INFO - mozInstall == 1.12 09:18:06 INFO - mozcrash == 0.17 09:18:06 INFO - mozdebug == 0.1 09:18:06 INFO - mozdevice == 0.48 09:18:06 INFO - mozfile == 1.2 09:18:06 INFO - mozhttpd == 0.7 09:18:06 INFO - mozinfo == 0.9 09:18:06 INFO - mozleak == 0.1 09:18:06 INFO - mozlog == 3.3 09:18:06 INFO - moznetwork == 0.27 09:18:06 INFO - mozprocess == 0.23 09:18:06 INFO - mozprofile == 0.28 09:18:06 INFO - mozrunner == 6.12 09:18:06 INFO - mozscreenshot == 0.1 09:18:06 INFO - mozsystemmonitor == 0.3 09:18:06 INFO - moztest == 0.7 09:18:06 INFO - mozversion == 1.4 09:18:06 INFO - psutil == 3.1.1 09:18:06 INFO - requests == 1.2.3 09:18:06 INFO - wsgiref == 0.1.2 09:18:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:18:06 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:18:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:18:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:06 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:18:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:18:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:06 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 0x1025cfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102664618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6e34c14a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102533d30>, '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 0x102298db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10252bb20>, '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.m2cxzwOVtW/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.c5y7kbil3Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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:18:06 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:18:06 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:18:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m2cxzwOVtW/Render', 09:18:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:18:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:18:06 INFO - 'HOME': '/Users/cltbld', 09:18:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:18:06 INFO - 'LOGNAME': 'cltbld', 09:18:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:06 INFO - 'MOZ_NO_REMOTE': '1', 09:18:06 INFO - 'NO_EM_RESTART': '1', 09:18:06 INFO - 'PAGER': '/bin/cat', 09:18:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:18:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:18:06 INFO - 'PWD': '/builds/slave/test', 09:18:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:18:06 INFO - 'SHELL': '/bin/bash', 09:18:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.c5y7kbil3Q/Listeners', 09:18:06 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 09:18:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:18:06 INFO - 'USER': 'cltbld', 09:18:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:18:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:18:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:06 INFO - 'XPC_FLAGS': '0x0', 09:18:06 INFO - 'XPC_SERVICE_NAME': '0', 09:18:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:18:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:18:06 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:18:06 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:18:06 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:18:06 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:18:06 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:18:06 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:18:06 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:18:06 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:18:06 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:18:06 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:18:06 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:18:06 INFO - Cleaning up... 09:18:06 INFO - Return code: 0 09:18:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:18:06 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:18:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:18:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:06 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:18:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:18:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:06 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 0x1025cfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102664618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6e34c14a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102533d30>, '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 0x102298db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10252bb20>, '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.m2cxzwOVtW/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.c5y7kbil3Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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:18:06 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:18:06 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:18:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m2cxzwOVtW/Render', 09:18:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:18:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:18:06 INFO - 'HOME': '/Users/cltbld', 09:18:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:18:06 INFO - 'LOGNAME': 'cltbld', 09:18:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:06 INFO - 'MOZ_NO_REMOTE': '1', 09:18:06 INFO - 'NO_EM_RESTART': '1', 09:18:06 INFO - 'PAGER': '/bin/cat', 09:18:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:18:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:18:06 INFO - 'PWD': '/builds/slave/test', 09:18:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:18:06 INFO - 'SHELL': '/bin/bash', 09:18:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.c5y7kbil3Q/Listeners', 09:18:06 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 09:18:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:18:06 INFO - 'USER': 'cltbld', 09:18:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:18:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:18:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:06 INFO - 'XPC_FLAGS': '0x0', 09:18:06 INFO - 'XPC_SERVICE_NAME': '0', 09:18:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:18:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:18:06 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:18:06 INFO - Cleaning up... 09:18:06 INFO - Return code: 0 09:18:06 INFO - Running post-action listener: _resource_record_post_action 09:18:06 INFO - Running post-action listener: _start_resource_monitoring 09:18:06 INFO - Starting resource monitoring. 09:18:06 INFO - [mozharness: 2016-08-19 16:18:06.629792Z] Finished create-virtualenv step (success) 09:18:06 INFO - [mozharness: 2016-08-19 16:18:06.630804Z] Running install step. 09:18:06 INFO - Running pre-action listener: _resource_record_pre_action 09:18:06 INFO - Running main action method: install 09:18:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:18:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:18:06 INFO - Reading from file tmpfile_stdout 09:18:06 INFO - Detecting whether we're running mozinstall >=1.0... 09:18:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:18:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:18:06 INFO - Reading from file tmpfile_stdout 09:18:06 INFO - Output received: 09:18:06 INFO - Usage: mozinstall [options] installer 09:18:06 INFO - Options: 09:18:06 INFO - -h, --help show this help message and exit 09:18:06 INFO - -d DEST, --destination=DEST 09:18:06 INFO - Directory to install application into. [default: 09:18:06 INFO - "/builds/slave/test"] 09:18:06 INFO - --app=APP Application being installed. [default: firefox] 09:18:06 INFO - mkdir: /builds/slave/test/build/application 09:18:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:18:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 09:18:41 INFO - Reading from file tmpfile_stdout 09:18:41 INFO - Output received: 09:18:41 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:18:41 INFO - Running post-action listener: _resource_record_post_action 09:18:41 INFO - [mozharness: 2016-08-19 16:18:41.212251Z] Finished install step (success) 09:18:41 INFO - [mozharness: 2016-08-19 16:18:41.212384Z] Running run-tests step. 09:18:41 INFO - Running pre-action listener: _resource_record_pre_action 09:18:41 INFO - Running main action method: run_tests 09:18:41 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:18:41 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:18:41 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:18:41 INFO - 2016-08-19 09:18:41.227 screenresolution[2177:19720] starting screenresolution argv=screenresolution get 09:18:41 INFO - 2016-08-19 09:18:41.229 screenresolution[2177:19720] Display 0: 1600x1200x32@60 09:18:41 INFO - 2016-08-19 09:18:41.235 screenresolution[2178:19722] starting screenresolution argv=screenresolution list 09:18:41 INFO - Available Modes on Display 0 09:18:41 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:18:41 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:18:41 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:18:41 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:18:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:18:41 INFO - Intel Iris: 09:18:41 INFO - Chipset Model: Intel Iris 09:18:41 INFO - Type: GPU 09:18:41 INFO - Bus: Built-In 09:18:41 INFO - VRAM (Dynamic, Max): 1536 MB 09:18:41 INFO - Vendor: Intel (0x8086) 09:18:41 INFO - Device ID: 0x0a2e 09:18:41 INFO - Revision ID: 0x0009 09:18:41 INFO - Displays: 09:18:41 INFO - Display: 09:18:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:18:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:18:41 INFO - Display Serial Number: 1600x1200 60 09:18:41 INFO - Main Display: Yes 09:18:41 INFO - Mirror: Off 09:18:41 INFO - Online: Yes 09:18:41 INFO - Rotation: Supported 09:18:41 INFO - Return code: 0 09:18:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:18:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:18:41 INFO - Python 2.7.3 09:18:41 INFO - Return code: 0 09:18:41 INFO - grabbing minidump binary from tooltool 09:18: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:18:41 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 0x102533d30>, '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 0x102298db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10252bb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:18:41 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:18:41 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:18:41 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:18:41 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:18:41 INFO - Return code: 0 09:18:41 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:18:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:18:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:18:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:18:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:18:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RGgd55ucThm9pe3vugGg-Q/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0086', '--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:18:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/RGgd55ucThm9pe3vugGg-Q/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0086 --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:18:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m2cxzwOVtW/Render', 09:18:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:18:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:18:41 INFO - 'HOME': '/Users/cltbld', 09:18:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:18:41 INFO - 'LOGNAME': 'cltbld', 09:18:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:18:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:18:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:41 INFO - 'MOZ_NO_REMOTE': '1', 09:18:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:18:41 INFO - 'NO_EM_RESTART': '1', 09:18:41 INFO - 'PAGER': '/bin/cat', 09:18:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:18:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:18:41 INFO - 'PWD': '/builds/slave/test', 09:18:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:18:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:18:41 INFO - 'SHELL': '/bin/bash', 09:18:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.c5y7kbil3Q/Listeners', 09:18:41 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 09:18:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:18:41 INFO - 'USER': 'cltbld', 09:18:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:18:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:18:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:41 INFO - 'XPC_FLAGS': '0x0', 09:18:41 INFO - 'XPC_SERVICE_NAME': '0', 09:18:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:18:41 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RGgd55ucThm9pe3vugGg-Q/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0086', '--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:18:41 INFO - mozversion application_buildid: 20160819065522 09:18:41 INFO - mozversion application_changeset: 8dfc2fdb7ae3990b73f8207aaf62de68a4f47bf5 09:18:41 INFO - mozversion application_display_name: Nightly 09:18:41 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:18:41 INFO - mozversion application_name: Firefox 09:18:41 INFO - mozversion application_remotingname: firefox 09:18:41 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:18:41 INFO - mozversion application_vendor: Mozilla 09:18:41 INFO - mozversion application_version: 51.0a1 09:18:41 INFO - mozversion platform_buildid: 20160819065522 09:18:41 INFO - mozversion platform_changeset: 8dfc2fdb7ae3990b73f8207aaf62de68a4f47bf5 09:18:41 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:18:41 INFO - mozversion platform_version: 51.0a1 09:18:41 INFO - using testdate: 1471623521 09:18:41 INFO - actual date: 1471623521 09:18:41 INFO - starting webserver on 'localhost:53501' 09:18:41 INFO - SUITE-START | Running 2 tests 09:18:41 INFO - TEST-START | dromaeo_css 09:18:41 INFO - operating with platform_type : mac_ 09:18:41 INFO - Initialising browser for dromaeo_css test... 09:18:41 INFO - TEST-INFO | started process 2188 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/tmpW8HwPk/profile http://localhost:53501/getInfo.html) 09:18:44 INFO - PROCESS | 2188 | __metrics Screen width/height:1600/1200 09:18:44 INFO - PROCESS | 2188 | colorDepth:24 09:18:44 INFO - PROCESS | 2188 | Browser inner width/height: 1024/689 09:18:44 INFO - PROCESS | 2188 | __metrics 09:18:44 INFO - PROCESS | 2188 | 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:18:44 INFO - PROCESS | 2188 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:18:45 INFO - TEST-INFO | 2188: exit 0 09:18:45 INFO - Browser initialized. 09:18:45 INFO - Running cycle 1/1 for dromaeo_css test... 09:18:45 INFO - TEST-INFO | started process 2190 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/tmpW8HwPk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:18:57 INFO - PROCESS | 2190 | RSS: Main: 228356096 09:18:57 INFO - PROCESS | 2190 | 09:19:49 INFO - PROCESS | 2190 | 1471623589002 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:21:59 INFO - PROCESS | 2190 | Cycle 1(1): loaded http://localhost:53501/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53501/tests/dromaeo/cssquery-ext.html) 09:22:00 INFO - PROCESS | 2190 | RSS: Main: 342245376 09:22:00 INFO - PROCESS | 2190 | 09:25:03 INFO - PROCESS | 2190 | Cycle 1(1): loaded http://localhost:53501/tests/dromaeo/cssquery-ext.html (next: http://localhost:53501/tests/dromaeo/cssquery-jquery.html) 09:25:04 INFO - PROCESS | 2190 | RSS: Main: 373354496 09:25:04 INFO - PROCESS | 2190 | 09:28:06 INFO - PROCESS | 2190 | Cycle 1(1): loaded http://localhost:53501/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53501/tests/dromaeo/cssquery-mootools.html) 09:28:07 INFO - PROCESS | 2190 | RSS: Main: 342454272 09:28:07 INFO - PROCESS | 2190 | 09:31:09 INFO - PROCESS | 2190 | Cycle 1(1): loaded http://localhost:53501/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53501/tests/dromaeo/cssquery-prototype.html) 09:31:10 INFO - PROCESS | 2190 | RSS: Main: 346849280 09:31:10 INFO - PROCESS | 2190 | 09:34:13 INFO - PROCESS | 2190 | Cycle 1(1): loaded http://localhost:53501/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53501/tests/dromaeo/cssquery-yui.html) 09:34:14 INFO - PROCESS | 2190 | RSS: Main: 333176832 09:34:14 INFO - PROCESS | 2190 | 09:37:17 INFO - PROCESS | 2190 | Cycle 1(1): loaded http://localhost:53501/tests/dromaeo/cssquery-yui.html (next: http://localhost:53501/tests/dromaeo/cssquery-dojo.html) 09:37:18 INFO - PROCESS | 2190 | RSS: Main: 392757248 09:37:18 INFO - PROCESS | 2190 | 09:37:18 INFO - PROCESS | 2190 | __start_tp_report 09:37:18 INFO - PROCESS | 2190 | _x_x_mozilla_page_load 09:37:18 INFO - PROCESS | 2190 | _x_x_mozilla_page_load_details 09:37:18 INFO - PROCESS | 2190 | |i|pagename|runs| 09:37:18 INFO - PROCESS | 2190 | |0;dojo.html;3229.128135403441;3231.176050107222;3264.3144939562685;3270.869165233389;3292.209869631289;5113.872153196172;5145.845624631258;5465.453454654533;5566.916496252559;5583.07879199932;5469.917951230735;5547.77808887645;5554.611177217596;5597.776088956447;5635.267415236024;4970.353853998984;4974.850754477354;5004.824831130911;5016.724080175589;5169.56058735008;5124.461931497195;5166.147585648364;5182.274481572583;5187.533122019017;5198.610104242178;4911.115999120157;4955.900881982377;5113.309703190059;5133.922991155136;5144.279800827887;4940.209566469383;4997.775100120503;5000.799968001275;5030.698158110488;5050.84847454577;5030.84907452777;5046.495350464961;5060.1903695408555;5097.770600322994;5104.489551044866;5380.139177731544;5567.66594004361;5600.327960644708;5602.495775380195;5632.4085970972865;5865.266841644794;5908.793192238251;5922.14128951304;5988.161657367972;5992.250968628921;5983.341832398476;5999.550033747487;6057.363976782419;6066.211392518989;6082.422169893853;5482.369527504308;5523.8895222096135;5578;5585.9107474042985;5601.327840659106;6043.214382130383;6054.303755068136;6073.1497590337385;6083.057126145454;6084.239470066242;5932.110183472428;5935.139404786281;5940.316863560746;5951.196588460589;5959.28488581373;5847.795327163614;5926.851828704231;6037.516998640098;6037.63774173551;6071.878562428815;5981.162637230791;6054.364291749391;6065.181200537871;6068.271807383054;6068.423499767515;6010.3088144863905;6057.061155520902;6058.182145410401;6064.241969753781;6091.847703807441;4692.319613656001;4759.214729569651;4766.23740201573;4816.229403295456;4827.8551643450755;4244.511881133647;4302.419173411549;4323.178596066738;4330;4339.067100573392;5496.120620700669;5549.38956714761;5563.276774019351;5567;5571.526420254243;5901.1148327750325;5930.673812940329;6038.909416358758;6064.545159113084;6077.726502307319;5444.564434845203;5467.863303417446;5505.146702261156;5518.779248830002;5520.199571062173;5233.241180028875;5238.476152384807;5247.842564723064;5260.894782104337;5272.736363181825;5993.580449368676;6006.819795406145;6071.301800293023;6073.423024812636;6079.817605471843;6020.819375418744;6081.057436097412;6083.422074902877;6098.329183789869;6122.234720659918;6085.939140608537;6089.908651370144;6110.908336374869;6114.205153330038;6118.082287656887;5760.683162426107;5870.031444811559;5932.584719069624;5940.79207227745;6002.579819412599;5968.045112781934;6039.245094363205;6069.666168360606;6073.514118870568;6075.787347442818;5913.881722365614;5919.319278283053;5927.614705044159;5928.49607783326;5940.762369505172;5746.683932383759;5800.1299805029585;5930.318013428511;5934.287885453773;5935.525157987265;4780.928286075781;5786.804566689117;5805.448482394166;5821.621594596338;5823.068309070529;5907.586468947133;5920.496757775465;5931.110333450017;5933.9109913352195;5940.46535811779;5548.250986116823;5618.2134501170685;5686.857828554319;5708.914366284426;5761 09:37:18 INFO - PROCESS | 2190 | |1;ext.html;470.17249640632485;484.50580407983836;498.35214221512024;510.17097217022314;537.9865503362415;29283.70716292839;34121.82939085304;39471.60528394715;40616.18767624646;40692.59307406925;41802;42076;42184.78907605458;42190;42208.78895605518;8884.955575222115;9799.167070798972;10027.34822236556;10029.749256268615;10036.749081272954;8890.155435233642;12022.93988530056;12260.264384136935;12260.44827982743;12467.314297713621;10364.326318789292;12214.938925305405;12234.265944043385;12268.815967760445;12302.692432689164;11702.648920532398;11756.823647645293;11793.11551633631;11806.645800625995;11820.408979550988;11977.94011029948;12044.337561434118;12049.819252711217;12076.637700868989;12076.879231207664;39411;39481.40777888334;39587.8020609898;39605.20789584221;39760.40359394612;3395.8471868765964;3398.3882901077795;3417.487376893461;3904.1801221743467;3920.6863450923856;30958.0712578623;30964.8451757742;31453.528197077063;31506.52740208899;31529.684703152903;3231.9676803232137;3328.251143492719;3364.8317584120696;3427.7429192810637;3436.9312613747584;388585;389836;390224.0977590216;390504;391927;56650;56828.60128287806;56935.71532142313;56996;57069;59632;59636.701816491506;59655.403445964985;59795.701021495486;59832;16067.919660401623;16276.51170464888;16300.592485187964;16321.510354689379;16325.673486530202;11587.304761714324;11780.410979450993;11786.292822430682;12881.935590322177;12935.611931642066;13734.725305493834;13845.653858653613;13853.722925541635;15020;15351.923240383727;5307.416184219733;7104.07352830996;7229.530080544748;7239;7262.346388825031;851.6713926274907;964.6864768950119;1013.2400699475394;1014.7463134216446;1074.6346242277664;24570.754292457204;28864.855675721486;29509.557356639667;29912.25219369533;30186.698133018388;1021.8722659667542;1049.338916482611;1057.8043062033548;1189.655000049993;1201.3332600406684;12838.614841554769;15373.615659608598;15554.455594054154;15612.792423422627;15810.762838557432;694010.7695153629;701057;822313;839716;902817.4859125662;43581.782091089975;54818;56113.07208495366;56162;60562.39437605567;1389.2289779172656;1408.9647758805907;1428.778539326406;1430.2634143416358;1431.1627697796905;30112.386513618505;30642.693573064877;30693.23266918345;31467;32353;50926;51495.19766808144;51537;51619.741901290254;51624.96750065053;529357;532297;532324;532616;532787.3360633173;25650.102246421287;25804.483910321313;26087.478250435266;26193.214203573923;26227.344316391478;47238.76380618075;48128.7593562044;48207.75896120497;48404.78988025327;48472.757636211594;37129.62870371262;38628.22743545169;38935.80532097322;39194.60805392024;40211.195776084896;32611.34777304393;38522.422163667004;38977.683431849524;39621.207575847744;40324.19351612893;36979.81510092432;37099;37817.86546403502;37865.81067094647;38059.2388152241;32840.671593284715;33391.99824005284;34202.82898585591;34750.82624586861;34815.477767833;24451.51096978015;25450;25508.107216247343;25618.487630247662;25639.974401023752 09:37:18 INFO - PROCESS | 2190 | |2;jquery.html;348.846507536684;366.543653151826;368.657148851568;374.10028880542296;395.27072551143146;15603.141827199514;16478.928869623465;20625.690614640793;20747.68878466824;20755.962201889884;20138.39584812458;20718.48203794906;20873.895630521827;21256.256031038916;21331.893340533315;5225.6603320784025;5325.7869685212545;5347.973260133714;5501.092319767254;5507.972460137694;3862.4013277941963;5344.973275133619;5429.945700542983;5446.673199608036;5451.182322651594;5772.5670574706855;6042.818715438544;6070.0591408331775;6088.756449742005;6117.204763380777;5825.213596164548;5925.73334199962;6018.608790428652;6076.4834989025985;6111.663858487781;5829.562782791308;5833.445822646841;5835.183074369592;5840.503557197643;5841.970790146064;13295.401706923212;14813.851861481355;21011.474713132295;25642.615360769418;25926.87036564824;2125.4792575819024;2167.2306331252335;2181.727284089489;2273.30664147435;2927.711806804999;9759.560819763128;9870.802583948282;9967.900320996841;10353.534090965924;10388.428636424993;1803.980750875751;1896.8008359122368;1908.7232351309121;1919.0884329943306;1943.37811900192;121780.78219217782;123542;123993.38003310015;128898.35550822185;131793.34103329518;9226.492542910204;10861.1854110942;11425.771484570261;11444.02725768319;11603.825942610867;14219.85780142206;14288;14302.996370762048;14323.35544900482;14339.35472903701;8306.08633050364;8567.186117318844;8596.140385961478;8692.957155068554;8718.43330183536;8530.402871798915;8550.657973681107;8575.284943011422;8726.301895848317;8734.432261902955;4412.558744125626;4492.348609451611;4504.81980720774;4564.954350456519;4577.496475387705;1877.6057028024268;1878.7839398469077;1895.393474088306;1905.914233859452;1957.2268953763185;896.4536175098034;939.4316438554713;940.6096469965024;944.9196818270386;1006.2352612014776;19288.710669339973;20216.69674954877;21792.602813690482;21794.78205217959;21928.89035554812;839.26983524333;926.2173463423396;952.1573407534244;979.4319294808994;979.4515071559807;10272.918124562751;11052.075426768568;11086.279391839505;11169.664910052712;11301.26541774782;600259;605667;611645;619548;622522;79161.20838791538;81453.59273203714;122908;141236.5876341244;146710.26644866707;975.8877729061248;998.1814911772423;1028.9691309260734;1029.9897001029894;1030.1295405382439;15994.280257388678;16523;19405.2237910482;19962;20140.49648758734;45912.31131532963;50801.74599127129;57790.42209578019;58490.70754646199;59081.704591476766;88464.55767721377;88781.11218887729;88862.66705999286;88970.55514722643;89004.5549772247;7893.486923349964;8126.796830079295;8252.257296843312;8301.501909885426;8320.958395207985;12772.680682982627;13045.934770326467;13533.661658458617;13569.864301357255;13578.45686172542;11125.721856953642;11238.831417528907;11594.188406811441;11639.6508104757;11671.299722016705;10925.508352124332;10936.617218397636;11089.94455027752;11197;11391.088712902781;9724;11575.594854180063;11658.475368608602;11677.182597218452;11725;7963.7212697557625;7978.122406535161;8330.666773329;11954.760904781682;11958.282503049844;10375.792484150426;10677.626283080366;11851.585194518495;11906.642800715646;11926 09:37:18 INFO - PROCESS | 2190 | |3;mootools.html;1358.1918758338784;1527.9694406111878;1553.3475940105154;1755.262789628356;1916.271816709651;7756.767296981093;8471.872921906177;8703.042665306826;8704.390692651508;8847.11528847115;8632.438891472066;8731.126887311266;8745;8746.256568191695;8746.956265218681;2326.220716060122;2379.0364902214633;2381.226101517005;2382.4401265702545;2384.403899025244;2333.288347054148;2358.599038163508;2378.762123787616;2384.153625462962;2389.0682633772844;2356.7878890899815;2374.4776149247223;2381.595128828095;2391.7249516305587;2394.461246219604;2333.0434521845964;2350.5651454480976;2359.4691194481275;2360.9881950590225;2375.7743014413604;2325.9534809303887;2350.4828937633692;2352.647102934556;2361.858288502678;2362.0079566582076;8745.650173993032;8758.912410875873;8825.867611985825;8842.69050583233;8865.911340886572;5231.869203269949;5409.864753381157;5410.756515956791;5449.64577302474;5449.700266485342;3653.4154535274492;3662.523871896663;3712.6658600726064;3724.1434470072036;3728.738988270822;1110.777844431117;1115.8326251062324;1119.8936101070385;1126.5268587193398;1140.1391949078393;11526.250793698382;11632.651020469315;11660.12549058824;11664.24182428148;11691.532338706442;4377.606015458624;4554.4990051094355;4632.374629425052;4637.39713837199;4663.417072865892;9157.633694652139;9159.084091590788;9161.267098632094;9165.81668366629;9171.633134674672;5255.027819853263;5258.053550360895;5260.552853007461;5269.868253293698;5279.392869819943;4430.335449682573;4589;4747.098051370298;4866.926996095061;4934.407871055496;3710.8144592770254;3752.9437058444146;3857.5949525300002;3867.7099217558793;3897.863574774926;1168.3865970365625;1184.0350114656528;1187.7921363761307;1200.111917181297;1201.6515210589007;816.5713000674574;883.8630012348096;905.2441211588398;940.0505489455699;948.166877370421;5616.831495055155;5659.773609055592;5949.8215053548465;6019.819405417845;6318.925782616966;435.7211384713786;902.1384577728254;961.5144352102144;1014.3305418423805;1073.4364458659265;3937.7637341759587;3938.330483817758;3976.4234186042863;4057.985503624094;4058.0869304406274;10369.118624916966;10753.892461075446;10763.461826908624;10781;10814.783704326026;10652.147828173727;10667.733306667395;10685;10689.625863094754;10710.571577136983;7700.460967732205;8098.109207987117;8104.5137291762685;8194.590270486451;8194.590270486571;5814;5856.238688970407;5929.9110513341475;6019.939800601938;6173.104899789595;10072.848907266543;10180.287379883634;10198.69403917854;10217.642382516577;10227.539760710935;9843.901560984299;10028.896821349792;10120.59517619287;10368;10405.531751071068;7000.649967501707;7218.639068046682;7252.202257751748;7524.05949256343;7699.422543309386;7696.615169241628;7942.12636609984;8022.398320125897;8517.44636598619;8518.361122915681;7434.182239953709;7440.3675687567165;7450.813729656802;7511.936864501169;7660.233976602518;7279.636018199175;7319.926800731924;7366.116066072105;7397.297256760599;7540.509866858856;6936.930630693831;6947.444204463732;6961.512694111364;7002.824929376807;7019.19279282889;4899.265110233351;4981.327520784647;4989.55094041538;5051.4443411225475;5178.145605974972;4711.858644240679;4849.854504364875;5002.52476014778;5025.597952163891;5123.026624941249 09:37:18 INFO - PROCESS | 2190 | |4;prototype.html;274.8611950964763;295.60389078634626;304.9573059771631;308.9803647961727;312.47192245105754;4973.278874563195;5341.225522299269;5395.325584301962;5424.701641409726;5429.53848922841;4975.577075948549;5063.189889617662;5074.340335756356;5171.689698618076;5274.709890955995;3908.205632972424;4016.975671203846;4063.5123785145825;4066.1867626474677;4077.9184416311655;3553.614090504705;3592.317459682665;4029.5164580250266;4069.6337329640473;4074.246264441073;3168.6831316868243;3994.1412596291793;4040.4949381327333;4051.9189616207805;4082.2856000199995;3549.9112522187024;4005.499312585927;4036.9596304037022;4046.271671099201;4047.433359329696;3210.8922421764687;3490.9825450872836;3498.895033148984;3501.824908754552;3501.9824900875587;4938.8765280868265;5026.421961474441;5257.526822585947;5290.814821481268;5316.548093412064;4101.610347017028;4502.475012612336;4564.977175114136;4565.292379681155;4589.24277494213;5282.894342113174;5577.386487486373;5577.593700976224;5578.716895114147;5620.381758006657;3679.2825399047197;3764.1906989997283;3813.31360355136;3822.2164456286396;3860.575336712959;5330.067238233293;5521.404939678345;5805.216295800055;5859.267591551056;5887.941120588825;3413.0955296846355;3839.462475253467;3842.4438102568506;3857.9228415431535;3891.8637847675195;3907.8632247871187;3913.4716813229848;3922.941155882664;3940.2907476653904;3953.110550126256;3371.4268574342423;4525.7510836904285;4595.333676616939;4610.193216187221;4622.491525932145;5139.897202055938;5467.371252305956;5530.834074977757;5657.405972372924;5660.462256085665;1261.6374315739163;1319.2084749150433;1342.4965637885794;1346.6229455752405;1348.9527866524625;836.1813056601867;852.8635418333037;861.2894362151251;894.9105089490998;894.9394966964169;5175.353080864892;5181.248718935733;5197.868873761739;5215.947840521546;5232.450592687713;4947.480514545993;5021.397432308073;5401.378841433207;5681.772729090872;5736.680563470425;4363.781810909505;4712.387389639393;5150.819721309736;5182.533571978464;5207.765650545659;2970.628671416073;2993.431248062861;2994.595729576523;2999.145243605562;3002.564628128953;5561.241218962032;5875.6474611523445;5884.852878678067;5887.4701276884425;5895.970520147458;5752.4180850265675;5794.812063527052;5804.984127777622;5808.244928159313;5831.854203644943;4537.682362234678;4798.152711206141;5879.20630714848;5891.499222566044;5931.822045338647;5400.164913420485;5640.661560306395;5665.405132461032;5667.801626943037;5672.290963629546;4802.903941921212;5163.509466600667;5305.628605997543;5456.781728730806;5612.494875461065;4768.856934291976;5852.678102704393;5864.237649105589;5892.377891838091;5903.852403689771;3275.773363237257;3753.868614598476;3785.356489396754;3788.1855401088897;3803.7527560708463;4904.019196160711;5607;5617.764091899939;5642.802501912413;5714.571407144397;4904.460509343898;5156.1750119980625;5345.599080068932;5523.723813809213;5582.692951887685;4594.5864872161665;4727.692699974491;5218.373795144607;5486.753096110764;5487.890242195213;4243.490781106287;4487.68586198976;5370.898965711942;5371.973140134274;5386.649867758583;5276.81531146408;5356.7589458473685;5596.369706238062;5611.74747136388;5631.887362252814;5027.59779217669;5436.918446223231;5443.972780136074;5453.454654534515;5466.234727138124 09:37:18 INFO - PROCESS | 2190 | |5;yui.html;717.4403964907373;739.3715341959336;771.8726410142327;785.8546168958743;790.1387487638473;15289.464868729598;16563.83436165638;16607.2526736297;16617.252223649935;16631.833681663196;14726.779098313507;14898.255087245649;14900.329485173166;14915.477958271462;14956.700865982673;662.5825729790225;688.1088989758275;688.3667026335783;688.7520492622651;828.70580943765;685.5543896467311;687.6458623808718;824.2334628795218;859.7807559072428;866.0127454701645;897.2014906733012;905.7463910105148;906.6826610686273;911.5533388639585;912.4935660708304;906.3021473465395;908.5184852028435;908.695586978363;908.9864800747185;911.3256190419108;522.3157663460878;670.1187937861696;670.4904272752743;670.5909395268882;673.7204060314964;14121.646958826008;14615.050021748659;14622;14622.707545849129;14626.926865365711;14150.716985660332;14819.629509262248;14843;14926;14975.700485990219;11040.613578524793;11593.246438981523;11774.470148843237;11807.409629518575;11814.232074915102;3843.538775346948;3844.75009124406;3900.4929359183407;3947.782871942042;4019.055521952339;102547;116710.41644791722;117503.4124829379;118199;120024;11334.60328888485;11409.25839820409;11482.540698371971;11503.539858405737;11669.474873630537;23461.882690586437;23470.178543750888;23479;23485.530289394115;23519.294421167393;7637.045369328834;7789.844203115906;7937.047554293406;7958.641861116148;7969.840603187904;4467.544398344427;4522.072975040109;4643.767811609406;4673.649476289292;4764.285357196448;3609.4946707460977;3738.2523495300507;3741.812909354576;3745.8688945886765;3760.0599850037493;645.1194120026224;648.9513286503532;679.1612358736955;689.035350509291;689.1385767790263;575.8848230353946;635.1869606903172;678.1692426777158;682.6211452643731;682.9897551536731;8803.559822008874;9333.253339732804;9340.299477539076;9350.625974961062;9457.43255404678;605.4218221598363;666.5334266013809;667.7929841749074;678.6471035061838;679.1985457160499;3928.332183528807;4384.934225986613;4563.429571303587;4577.198990176772;4597.310403439444;156306.21846890694;156545.2172739152;157569;161812;165429;136785;136830;139836.30081849525;140937;141966;90167.19665606652;101787;113029.43485282522;113132;115405.99999999833;8574.884016019836;8586.871196931927;8942.920064281909;9095.272378209595;9167.77080572991;57510.42489575218;57528;58330.66677333029;66424;66814.33185668082;86966.1303386958;89639.10360896568;90231.64652530086;91345.54327228322;91650.54174729083;13206.27365494907;13301.733965320445;13572.04995650295;13679.720745011045;13700.794488082487;15154.3749503296;15395.999260048062;15446.45937392231;15610.609734756721;15907.363705451653;13987.510437134652;14222.715545689236;14281.07173033791;14542.127472351693;14684.192369419785;14279.000469967003;14343.56969290923;14417.567472975827;14444.350004250044;14905.478308259158;13634;13638.659033524242;13671.31643417845;13906.235157066458;14300.284985750879;11185.161112916401;11601;11650.359230242419;11698.071779312642;11895.405229738652;11178.496967636313;11299.412506291252;11561.653150405502;11651.184417090722;11672.88327116718 09:37:18 INFO - PROCESS | 2190 | __end_tp_report 09:37:18 INFO - PROCESS | 2190 | __start_cc_report 09:37:18 INFO - PROCESS | 2190 | _x_x_mozilla_cycle_collect,1269 09:37:18 INFO - PROCESS | 2190 | __end_cc_report 09:37:18 INFO - PROCESS | 2190 | __startTimestamp1471624638076__endTimestamp 09:37:18 INFO - PROCESS | 2190 | 09:37:18 INFO - PROCESS | 2190 | ------- Summary: start ------- 09:37:18 INFO - PROCESS | 2190 | Number of tests: 6 09:37:18 INFO - PROCESS | 2190 | 09:37:18 INFO - PROCESS | 2190 | [#0] dojo.html Cycles:165 Average:5552.75 Median:5761.00 stddev:612.92 (10.6%) stddev-sans-first:587.07 09:37:18 INFO - PROCESS | 2190 | Values: 3229.1 3231.2 3264.3 3270.9 3292.2 5113.9 5145.8 5465.5 5566.9 5583.1 5469.9 5547.8 5554.6 5597.8 5635.3 4970.4 4974.9 5004.8 5016.7 5169.6 5124.5 5166.1 5182.3 5187.5 5198.6 4911.1 4955.9 5113.3 5133.9 5144.3 4940.2 4997.8 5000.8 5030.7 5050.8 5030.8 5046.5 5060.2 5097.8 5104.5 5380.1 5567.7 5600.3 5602.5 5632.4 5865.3 5908.8 5922.1 5988.2 5992.3 5983.3 5999.6 6057.4 6066.2 6082.4 5482.4 5523.9 5578.0 5585.9 5601.3 6043.2 6054.3 6073.1 6083.1 6084.2 5932.1 5935.1 5940.3 5951.2 5959.3 5847.8 5926.9 6037.5 6037.6 6071.9 5981.2 6054.4 6065.2 6068.3 6068.4 6010.3 6057.1 6058.2 6064.2 6091.8 4692.3 4759.2 4766.2 4816.2 4827.9 4244.5 4302.4 4323.2 4330.0 4339.1 5496.1 5549.4 5563.3 5567.0 5571.5 5901.1 5930.7 6038.9 6064.5 6077.7 5444.6 5467.9 5505.1 5518.8 5520.2 5233.2 5238.5 5247.8 5260.9 5272.7 5993.6 6006.8 6071.3 6073.4 6079.8 6020.8 6081.1 6083.4 6098.3 6122.2 6085.9 6089.9 6110.9 6114.2 6118.1 5760.7 5870.0 5932.6 5940.8 6002.6 5968.0 6039.2 6069.7 6073.5 6075.8 5913.9 5919.3 5927.6 5928.5 5940.8 5746.7 5800.1 5930.3 5934.3 5935.5 4780.9 5786.8 5805.4 5821.6 5823.1 5907.6 5920.5 5931.1 5933.9 5940.5 5548.3 5618.2 5686.9 5708.9 5761.0 09:37:18 INFO - PROCESS | 2190 | 09:37:18 INFO - PROCESS | 2190 | [#1] ext.html Cycles:180 Average:70175.29 Median:18810.16 stddev:160518.44 (853.4%) stddev-sans-first:160883.42 09:37:18 INFO - PROCESS | 2190 | Values: 470.2 484.5 498.4 510.2 538.0 29283.7 34121.8 39471.6 40616.2 40692.6 41802.0 42076.0 42184.8 42190.0 42208.8 8885.0 9799.2 10027.3 10029.7 10036.7 8890.2 12022.9 12260.3 12260.4 12467.3 10364.3 12214.9 12234.3 12268.8 12302.7 11702.6 11756.8 11793.1 11806.6 11820.4 11977.9 12044.3 12049.8 12076.6 12076.9 39411.0 39481.4 39587.8 39605.2 39760.4 3395.8 3398.4 3417.5 3904.2 3920.7 30958.1 30964.8 31453.5 31506.5 31529.7 3232.0 3328.3 3364.8 3427.7 3436.9 388585.0 389836.0 390224.1 390504.0 391927.0 56650.0 56828.6 56935.7 56996.0 57069.0 59632.0 59636.7 59655.4 59795.7 59832.0 16067.9 16276.5 16300.6 16321.5 16325.7 11587.3 11780.4 11786.3 12881.9 12935.6 13734.7 13845.7 13853.7 15020.0 15351.9 5307.4 7104.1 7229.5 7239.0 7262.3 851.7 964.7 1013.2 1014.7 1074.6 24570.8 28864.9 29509.6 29912.3 30186.7 1021.9 1049.3 1057.8 1189.7 1201.3 12838.6 15373.6 15554.5 15612.8 15810.8 694010.8 701057.0 822313.0 839716.0 902817.5 43581.8 54818.0 56113.1 56162.0 60562.4 1389.2 1409.0 1428.8 1430.3 1431.2 30112.4 30642.7 30693.2 31467.0 32353.0 50926.0 51495.2 51537.0 51619.7 51625.0 529357.0 532297.0 532324.0 532616.0 532787.3 25650.1 25804.5 26087.5 26193.2 26227.3 47238.8 48128.8 48207.8 48404.8 48472.8 37129.6 38628.2 38935.8 39194.6 40211.2 32611.3 38522.4 38977.7 39621.2 40324.2 36979.8 37099.0 37817.9 37865.8 38059.2 32840.7 33392.0 34202.8 34750.8 34815.5 24451.5 25450.0 25508.1 25618.5 25640.0 09:37:18 INFO - PROCESS | 2190 | 09:37:18 INFO - PROCESS | 2190 | [#2] jquery.html Cycles:180 Average:35463.88 Median:13957.96 stddev:102164.92 (731.9%) stddev-sans-first:102417.49 09:37:18 INFO - PROCESS | 2190 | Values: 348.8 366.5 368.7 374.1 395.3 15603.1 16478.9 20625.7 20747.7 20756.0 20138.4 20718.5 20873.9 21256.3 21331.9 5225.7 5325.8 5348.0 5501.1 5508.0 3862.4 5345.0 5429.9 5446.7 5451.2 5772.6 6042.8 6070.1 6088.8 6117.2 5825.2 5925.7 6018.6 6076.5 6111.7 5829.6 5833.4 5835.2 5840.5 5842.0 13295.4 14813.9 21011.5 25642.6 25926.9 2125.5 2167.2 2181.7 2273.3 2927.7 9759.6 9870.8 9967.9 10353.5 10388.4 1804.0 1896.8 1908.7 1919.1 1943.4 121780.8 123542.0 123993.4 128898.4 131793.3 9226.5 10861.2 11425.8 11444.0 11603.8 14219.9 14288.0 14303.0 14323.4 14339.4 8306.1 8567.2 8596.1 8693.0 8718.4 8530.4 8550.7 8575.3 8726.3 8734.4 4412.6 4492.3 4504.8 4565.0 4577.5 1877.6 1878.8 1895.4 1905.9 1957.2 896.5 939.4 940.6 944.9 1006.2 19288.7 20216.7 21792.6 21794.8 21928.9 839.3 926.2 952.2 979.4 979.5 10272.9 11052.1 11086.3 11169.7 11301.3 600259.0 605667.0 611645.0 619548.0 622522.0 79161.2 81453.6 122908.0 141236.6 146710.3 975.9 998.2 1029.0 1030.0 1030.1 15994.3 16523.0 19405.2 19962.0 20140.5 45912.3 50801.7 57790.4 58490.7 59081.7 88464.6 88781.1 88862.7 88970.6 89004.6 7893.5 8126.8 8252.3 8301.5 8321.0 12772.7 13045.9 13533.7 13569.9 13578.5 11125.7 11238.8 11594.2 11639.7 11671.3 10925.5 10936.6 11089.9 11197.0 11391.1 9724.0 11575.6 11658.5 11677.2 11725.0 7963.7 7978.1 8330.7 11954.8 11958.3 10375.8 10677.6 11851.6 11906.6 11926.0 09:37:18 INFO - PROCESS | 2190 | 09:37:18 INFO - PROCESS | 2190 | [#3] mootools.html Cycles:180 Average:5687.28 Median:4798.48 stddev:3171.47 (66.1%) stddev-sans-first:3163.68 09:37:18 INFO - PROCESS | 2190 | Values: 1358.2 1528.0 1553.3 1755.3 1916.3 7756.8 8471.9 8703.0 8704.4 8847.1 8632.4 8731.1 8745.0 8746.3 8747.0 2326.2 2379.0 2381.2 2382.4 2384.4 2333.3 2358.6 2378.8 2384.2 2389.1 2356.8 2374.5 2381.6 2391.7 2394.5 2333.0 2350.6 2359.5 2361.0 2375.8 2326.0 2350.5 2352.6 2361.9 2362.0 8745.7 8758.9 8825.9 8842.7 8865.9 5231.9 5409.9 5410.8 5449.6 5449.7 3653.4 3662.5 3712.7 3724.1 3728.7 1110.8 1115.8 1119.9 1126.5 1140.1 11526.3 11632.7 11660.1 11664.2 11691.5 4377.6 4554.5 4632.4 4637.4 4663.4 9157.6 9159.1 9161.3 9165.8 9171.6 5255.0 5258.1 5260.6 5269.9 5279.4 4430.3 4589.0 4747.1 4866.9 4934.4 3710.8 3752.9 3857.6 3867.7 3897.9 1168.4 1184.0 1187.8 1200.1 1201.7 816.6 883.9 905.2 940.1 948.2 5616.8 5659.8 5949.8 6019.8 6318.9 435.7 902.1 961.5 1014.3 1073.4 3937.8 3938.3 3976.4 4058.0 4058.1 10369.1 10753.9 10763.5 10781.0 10814.8 10652.1 10667.7 10685.0 10689.6 10710.6 7700.5 8098.1 8104.5 8194.6 8194.6 5814.0 5856.2 5929.9 6019.9 6173.1 10072.8 10180.3 10198.7 10217.6 10227.5 9843.9 10028.9 10120.6 10368.0 10405.5 7000.6 7218.6 7252.2 7524.1 7699.4 7696.6 7942.1 8022.4 8517.4 8518.4 7434.2 7440.4 7450.8 7511.9 7660.2 7279.6 7319.9 7366.1 7397.3 7540.5 6936.9 6947.4 6961.5 7002.8 7019.2 4899.3 4981.3 4989.6 5051.4 5178.1 4711.9 4849.9 5002.5 5025.6 5123.0 09:37:18 INFO - PROCESS | 2190 | 09:37:18 INFO - PROCESS | 2190 | [#4] prototype.html Cycles:180 Average:4490.64 Median:5107.12 stddev:1374.73 (26.9%) stddev-sans-first:1341.67 09:37:18 INFO - PROCESS | 2190 | Values: 274.9 295.6 305.0 309.0 312.5 4973.3 5341.2 5395.3 5424.7 5429.5 4975.6 5063.2 5074.3 5171.7 5274.7 3908.2 4017.0 4063.5 4066.2 4077.9 3553.6 3592.3 4029.5 4069.6 4074.2 3168.7 3994.1 4040.5 4051.9 4082.3 3549.9 4005.5 4037.0 4046.3 4047.4 3210.9 3491.0 3498.9 3501.8 3502.0 4938.9 5026.4 5257.5 5290.8 5316.5 4101.6 4502.5 4565.0 4565.3 4589.2 5282.9 5577.4 5577.6 5578.7 5620.4 3679.3 3764.2 3813.3 3822.2 3860.6 5330.1 5521.4 5805.2 5859.3 5887.9 3413.1 3839.5 3842.4 3857.9 3891.9 3907.9 3913.5 3922.9 3940.3 3953.1 3371.4 4525.8 4595.3 4610.2 4622.5 5139.9 5467.4 5530.8 5657.4 5660.5 1261.6 1319.2 1342.5 1346.6 1349.0 836.2 852.9 861.3 894.9 894.9 5175.4 5181.2 5197.9 5215.9 5232.5 4947.5 5021.4 5401.4 5681.8 5736.7 4363.8 4712.4 5150.8 5182.5 5207.8 2970.6 2993.4 2994.6 2999.1 3002.6 5561.2 5875.6 5884.9 5887.5 5896.0 5752.4 5794.8 5805.0 5808.2 5831.9 4537.7 4798.2 5879.2 5891.5 5931.8 5400.2 5640.7 5665.4 5667.8 5672.3 4802.9 5163.5 5305.6 5456.8 5612.5 4768.9 5852.7 5864.2 5892.4 5903.9 3275.8 3753.9 3785.4 3788.2 3803.8 4904.0 5607.0 5617.8 5642.8 5714.6 4904.5 5156.2 5345.6 5523.7 5582.7 4594.6 4727.7 5218.4 5486.8 5487.9 4243.5 4487.7 5370.9 5372.0 5386.6 5276.8 5356.8 5596.4 5611.7 5631.9 5027.6 5436.9 5444.0 5453.5 5466.2 09:37:18 INFO - PROCESS | 2190 | 09:37:18 INFO - PROCESS | 2190 | [#5] yui.html Cycles:180 Average:25636.42 Median:3740.03 stddev:41330.18 (1105.1%) stddev-sans-first:41403.77 09:37:18 INFO - PROCESS | 2190 | Values: 717.4 739.4 771.9 785.9 790.1 15289.5 16563.8 16607.3 16617.3 16631.8 14726.8 14898.3 14900.3 14915.5 14956.7 662.6 688.1 688.4 688.8 828.7 685.6 687.6 824.2 859.8 866.0 897.2 905.7 906.7 911.6 912.5 906.3 908.5 908.7 909.0 911.3 522.3 670.1 670.5 670.6 673.7 14121.6 14615.1 14622.0 14622.7 14626.9 14150.7 14819.6 14843.0 14926.0 14975.7 11040.6 11593.2 11774.5 11807.4 11814.2 3843.5 3844.8 3900.5 3947.8 4019.1 102547.0 116710.4 117503.4 118199.0 120024.0 11334.6 11409.3 11482.5 11503.5 11669.5 23461.9 23470.2 23479.0 23485.5 23519.3 7637.0 7789.8 7937.0 7958.6 7969.8 4467.5 4522.1 4643.8 4673.6 4764.3 3609.5 3738.3 3741.8 3745.9 3760.1 645.1 649.0 679.2 689.0 689.1 575.9 635.2 678.2 682.6 683.0 8803.6 9333.3 9340.3 9350.6 9457.4 605.4 666.5 667.8 678.6 679.2 3928.3 4384.9 4563.4 4577.2 4597.3 156306.2 156545.2 157569.0 161812.0 165429.0 136785.0 136830.0 139836.3 140937.0 141966.0 90167.2 101787.0 113029.4 113132.0 115406.0 8574.9 8586.9 8942.9 9095.3 9167.8 57510.4 57528.0 58330.7 66424.0 66814.3 86966.1 89639.1 90231.6 91345.5 91650.5 13206.3 13301.7 13572.0 13679.7 13700.8 15154.4 15396.0 15446.5 15610.6 15907.4 13987.5 14222.7 14281.1 14542.1 14684.2 14279.0 14343.6 14417.6 14444.4 14905.5 13634.0 13638.7 13671.3 13906.2 14300.3 11185.2 11601.0 11650.4 11698.1 11895.4 11178.5 11299.4 11561.7 11651.2 11672.9 09:37:18 INFO - PROCESS | 2190 | -------- Summary: end -------- 09:37:18 INFO - PROCESS | 2190 | 09:37:18 INFO - TEST-INFO | 2190: exit 0 09:37:18 INFO - TEST-OK | dromaeo_css | took 1116888ms 09:37:18 INFO - TEST-START | kraken 09:37:18 INFO - operating with platform_type : mac_ 09:37:18 INFO - Initialising browser for kraken test... 09:37:18 INFO - TEST-INFO | started process 2194 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/tmpKil_XE/profile http://localhost:53501/getInfo.html) 09:37:21 INFO - PROCESS | 2194 | __metrics Screen width/height:1600/1200 09:37:21 INFO - PROCESS | 2194 | colorDepth:24 09:37:21 INFO - PROCESS | 2194 | Browser inner width/height: 1024/689 09:37:21 INFO - PROCESS | 2194 | __metrics 09:37:21 INFO - PROCESS | 2194 | 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:37:21 INFO - PROCESS | 2194 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:37:21 INFO - TEST-INFO | 2194: exit 0 09:37:21 INFO - Browser initialized. 09:37:21 INFO - Running cycle 1/1 for kraken test... 09:37:21 INFO - TEST-INFO | started process 2195 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/tmpKil_XE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:37:33 INFO - PROCESS | 2195 | RSS: Main: 228429824 09:37:33 INFO - PROCESS | 2195 | 09:37:36 INFO - PROCESS | 2195 | Cycle 1(1): loaded http://localhost:53501/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53501/tests/kraken/driver.html?testName=audio-beat-detection) 09:37:37 INFO - PROCESS | 2195 | RSS: Main: 292167680 09:37:37 INFO - PROCESS | 2195 | 09:37:40 INFO - PROCESS | 2195 | Cycle 1(1): loaded http://localhost:53501/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53501/tests/kraken/driver.html?testName=audio-dft) 09:37:40 INFO - PROCESS | 2195 | RSS: Main: 288346112 09:37:40 INFO - PROCESS | 2195 | 09:37:44 INFO - PROCESS | 2195 | Cycle 1(1): loaded http://localhost:53501/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53501/tests/kraken/driver.html?testName=audio-fft) 09:37:44 INFO - PROCESS | 2195 | RSS: Main: 382099456 09:37:44 INFO - PROCESS | 2195 | 09:37:47 INFO - PROCESS | 2195 | Cycle 1(1): loaded http://localhost:53501/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53501/tests/kraken/driver.html?testName=audio-oscillator) 09:37:47 INFO - PROCESS | 2195 | RSS: Main: 282132480 09:37:47 INFO - PROCESS | 2195 | 09:37:49 INFO - PROCESS | 2195 | Cycle 1(1): loaded http://localhost:53501/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53501/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:37:50 INFO - PROCESS | 2195 | RSS: Main: 286814208 09:37:50 INFO - PROCESS | 2195 | 09:37:53 INFO - PROCESS | 2195 | Cycle 1(1): loaded http://localhost:53501/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53501/tests/kraken/driver.html?testName=imaging-darkroom) 09:37:53 INFO - PROCESS | 2195 | RSS: Main: 322240512 09:37:53 INFO - PROCESS | 2195 | 09:37:56 INFO - PROCESS | 2195 | Cycle 1(1): loaded http://localhost:53501/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53501/tests/kraken/driver.html?testName=imaging-desaturate) 09:37:57 INFO - PROCESS | 2195 | RSS: Main: 322924544 09:37:57 INFO - PROCESS | 2195 | 09:38:00 INFO - PROCESS | 2195 | Cycle 1(1): loaded http://localhost:53501/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53501/tests/kraken/driver.html?testName=json-parse-financial) 09:38:00 INFO - PROCESS | 2195 | RSS: Main: 324558848 09:38:00 INFO - PROCESS | 2195 | 09:38:02 INFO - PROCESS | 2195 | Cycle 1(1): loaded http://localhost:53501/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53501/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:38:02 INFO - PROCESS | 2195 | RSS: Main: 300785664 09:38:02 INFO - PROCESS | 2195 | 09:38:04 INFO - PROCESS | 2195 | Cycle 1(1): loaded http://localhost:53501/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53501/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:38:05 INFO - PROCESS | 2195 | RSS: Main: 305639424 09:38:05 INFO - PROCESS | 2195 | 09:38:07 INFO - PROCESS | 2195 | Cycle 1(1): loaded http://localhost:53501/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53501/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:38:08 INFO - PROCESS | 2195 | RSS: Main: 303063040 09:38:08 INFO - PROCESS | 2195 | 09:38:11 INFO - PROCESS | 2195 | Cycle 1(1): loaded http://localhost:53501/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53501/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:38:11 INFO - PROCESS | 2195 | RSS: Main: 314335232 09:38:11 INFO - PROCESS | 2195 | 09:38:14 INFO - PROCESS | 2195 | Cycle 1(1): loaded http://localhost:53501/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53501/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:38:15 INFO - PROCESS | 2195 | RSS: Main: 315645952 09:38:15 INFO - PROCESS | 2195 | 09:38:17 INFO - PROCESS | 2195 | Cycle 1(1): loaded http://localhost:53501/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53501/tests/kraken/driver.html?testName=ai-astar) 09:38:17 INFO - PROCESS | 2195 | RSS: Main: 359333888 09:38:17 INFO - PROCESS | 2195 | 09:38:17 INFO - PROCESS | 2195 | __start_tp_report 09:38:17 INFO - PROCESS | 2195 | _x_x_mozilla_page_load 09:38:17 INFO - PROCESS | 2195 | _x_x_mozilla_page_load_details 09:38:17 INFO - PROCESS | 2195 | |i|pagename|runs| 09:38:17 INFO - PROCESS | 2195 | |0;ai-astar;103;118;158;165;112;113;112;112;113;113 09:38:17 INFO - PROCESS | 2195 | |1;audio-beat-detection;144;114;118;153;127;132;157;107;162;108 09:38:17 INFO - PROCESS | 2195 | |2;audio-dft;184;184;185;181;185;183;184;185;181;180 09:38:17 INFO - PROCESS | 2195 | |3;audio-fft;78;76;58;89;58;57;60;66;109;76 09:38:17 INFO - PROCESS | 2195 | |4;audio-oscillator;90;72;97;74;97;97;101;71;67;105 09:38:17 INFO - PROCESS | 2195 | |5;imaging-gaussian-blur;88;90;131;109;87;84;88;84;86;85 09:38:17 INFO - PROCESS | 2195 | |6;imaging-darkroom;93;93;93;93;93;92;94;94;93;93 09:38:17 INFO - PROCESS | 2195 | |7;imaging-desaturate;84;86;84;85;83;92;81;86;85;88 09:38:17 INFO - PROCESS | 2195 | |8;json-parse-financial;55;55;53;56;54;53;55;54;53;53 09:38:17 INFO - PROCESS | 2195 | |9;json-stringify-tinderbox;63;66;67;72;73;70;67;69;68;69 09:38:17 INFO - PROCESS | 2195 | |10;stanford-crypto-aes;83;86;85;85;85;85;85;89;85;82 09:38:17 INFO - PROCESS | 2195 | |11;stanford-crypto-ccm;142;148;150;138;138;134;138;139;156;140 09:38:17 INFO - PROCESS | 2195 | |12;stanford-crypto-pbkdf2;170;170;169;169;163;170;167;166;226;212 09:38:17 INFO - PROCESS | 2195 | |13;stanford-crypto-sha256-iterative;66;68;68;67;68;66;66;67;66;65 09:38:17 INFO - PROCESS | 2195 | __end_tp_report 09:38:17 INFO - PROCESS | 2195 | __start_cc_report 09:38:17 INFO - PROCESS | 2195 | _x_x_mozilla_cycle_collect,2067 09:38:17 INFO - PROCESS | 2195 | __end_cc_report 09:38:17 INFO - PROCESS | 2195 | __startTimestamp1471624697438__endTimestamp 09:38:17 INFO - PROCESS | 2195 | 09:38:17 INFO - PROCESS | 2195 | ------- Summary: start ------- 09:38:17 INFO - PROCESS | 2195 | Number of tests: 14 09:38:17 INFO - PROCESS | 2195 | 09:38:17 INFO - PROCESS | 2195 | [#0] ai-astar Cycles:10 Average:121.90 Median:113.00 stddev:21.25 (18.8%) stddev-sans-first:21.41 09:38:17 INFO - PROCESS | 2195 | Values: 103.0 118.0 158.0 165.0 112.0 113.0 112.0 112.0 113.0 113.0 09:38:17 INFO - PROCESS | 2195 | 09:38:17 INFO - PROCESS | 2195 | [#1] audio-beat-detection Cycles:10 Average:132.20 Median:138.00 stddev:20.70 (15.0%) stddev-sans-first:21.51 09:38:17 INFO - PROCESS | 2195 | Values: 144.0 114.0 118.0 153.0 127.0 132.0 157.0 107.0 162.0 108.0 09:38:17 INFO - PROCESS | 2195 | 09:38:17 INFO - PROCESS | 2195 | [#2] audio-dft Cycles:10 Average:183.20 Median:184.00 stddev:1.87 (1.0%) stddev-sans-first:1.96 09:38:17 INFO - PROCESS | 2195 | Values: 184.0 184.0 185.0 181.0 185.0 183.0 184.0 185.0 181.0 180.0 09:38:17 INFO - PROCESS | 2195 | 09:38:17 INFO - PROCESS | 2195 | [#3] audio-fft Cycles:10 Average:72.70 Median:71.00 stddev:16.73 (23.6%) stddev-sans-first:17.63 09:38:17 INFO - PROCESS | 2195 | Values: 78.0 76.0 58.0 89.0 58.0 57.0 60.0 66.0 109.0 76.0 09:38:17 INFO - PROCESS | 2195 | 09:38:17 INFO - PROCESS | 2195 | [#4] audio-oscillator Cycles:10 Average:87.10 Median:82.00 stddev:14.45 (17.6%) stddev-sans-first:15.29 09:38:17 INFO - PROCESS | 2195 | Values: 90.0 72.0 97.0 74.0 97.0 97.0 101.0 71.0 67.0 105.0 09:38:17 INFO - PROCESS | 2195 | 09:38:17 INFO - PROCESS | 2195 | [#5] imaging-gaussian-blur Cycles:10 Average:93.20 Median:86.50 stddev:15.16 (17.5%) stddev-sans-first:15.97 09:38:17 INFO - PROCESS | 2195 | Values: 88.0 90.0 131.0 109.0 87.0 84.0 88.0 84.0 86.0 85.0 09:38:17 INFO - PROCESS | 2195 | 09:38:17 INFO - PROCESS | 2195 | [#6] imaging-darkroom Cycles:10 Average:93.10 Median:93.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 09:38:17 INFO - PROCESS | 2195 | Values: 93.0 93.0 93.0 93.0 93.0 92.0 94.0 94.0 93.0 93.0 09:38:17 INFO - PROCESS | 2195 | 09:38:17 INFO - PROCESS | 2195 | [#7] imaging-desaturate Cycles:10 Average:85.40 Median:85.50 stddev:2.99 (3.5%) stddev-sans-first:3.13 09:38:17 INFO - PROCESS | 2195 | Values: 84.0 86.0 84.0 85.0 83.0 92.0 81.0 86.0 85.0 88.0 09:38:17 INFO - PROCESS | 2195 | 09:38:17 INFO - PROCESS | 2195 | [#8] json-parse-financial Cycles:10 Average:54.10 Median:54.50 stddev:1.10 (2.0%) stddev-sans-first:1.12 09:38:17 INFO - PROCESS | 2195 | Values: 55.0 55.0 53.0 56.0 54.0 53.0 55.0 54.0 53.0 53.0 09:38:17 INFO - PROCESS | 2195 | 09:38:17 INFO - PROCESS | 2195 | [#9] json-stringify-tinderbox Cycles:10 Average:68.40 Median:69.00 stddev:2.91 (4.2%) stddev-sans-first:2.35 09:38:17 INFO - PROCESS | 2195 | Values: 63.0 66.0 67.0 72.0 73.0 70.0 67.0 69.0 68.0 69.0 09:38:17 INFO - PROCESS | 2195 | 09:38:17 INFO - PROCESS | 2195 | [#10] stanford-crypto-aes Cycles:10 Average:85.00 Median:85.00 stddev:1.83 (2.1%) stddev-sans-first:1.79 09:38:17 INFO - PROCESS | 2195 | Values: 83.0 86.0 85.0 85.0 85.0 85.0 85.0 89.0 85.0 82.0 09:38:17 INFO - PROCESS | 2195 | 09:38:17 INFO - PROCESS | 2195 | [#11] stanford-crypto-ccm Cycles:10 Average:142.30 Median:141.00 stddev:6.83 (4.8%) stddev-sans-first:7.25 09:38:17 INFO - PROCESS | 2195 | Values: 142.0 148.0 150.0 138.0 138.0 134.0 138.0 139.0 156.0 140.0 09:38:17 INFO - PROCESS | 2195 | 09:38:17 INFO - PROCESS | 2195 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.20 Median:170.00 stddev:21.87 (12.9%) stddev-sans-first:22.99 09:38:17 INFO - PROCESS | 2195 | Values: 170.0 170.0 169.0 169.0 163.0 170.0 167.0 166.0 226.0 212.0 09:38:17 INFO - PROCESS | 2195 | 09:38:17 INFO - PROCESS | 2195 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.70 Median:67.00 stddev:1.06 (1.6%) stddev-sans-first:1.09 09:38:17 INFO - PROCESS | 2195 | Values: 66.0 68.0 68.0 67.0 68.0 66.0 66.0 67.0 66.0 65.0 09:38:17 INFO - PROCESS | 2195 | -------- Summary: end -------- 09:38:17 INFO - PROCESS | 2195 | 09:38:18 INFO - TEST-INFO | 2195: exit 0 09:38:18 INFO - TEST-OK | kraken | took 59572ms 09:38:18 INFO - SUITE-END | took 1176s 09:38:18 INFO - Completed test suite (00:19:36) 09:38:18 INFO - javascript benchmark 09:38:18 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3229.128135403441, 3231.176050107222, 3264.3144939562685, 3270.869165233389, 3292.209869631289, 5113.872153196172, 5145.845624631258, 5465.453454654533, 5566.916496252559, 5583.07879199932, 5469.917951230735, 5547.77808887645, 5554.611177217596, 5597.776088956447, 5635.267415236024, 4970.353853998984, 4974.850754477354, 5004.824831130911, 5016.724080175589, 5169.56058735008, 5124.461931497195, 5166.147585648364, 5182.274481572583, 5187.533122019017, 5198.610104242178, 4911.115999120157, 4955.900881982377, 5113.309703190059, 5133.922991155136, 5144.279800827887, 4940.209566469383, 4997.775100120503, 5000.799968001275, 5030.698158110488, 5050.84847454577, 5030.84907452777, 5046.495350464961, 5060.1903695408555, 5097.770600322994, 5104.489551044866, 5380.139177731544, 5567.66594004361, 5600.327960644708, 5602.495775380195, 5632.4085970972865, 5865.266841644794, 5908.793192238251, 5922.14128951304, 5988.161657367972, 5992.250968628921, 5983.341832398476, 5999.550033747487, 6057.363976782419, 6066.211392518989, 6082.422169893853, 5482.369527504308, 5523.8895222096135, 5578.0, 5585.9107474042985, 5601.327840659106, 6043.214382130383, 6054.303755068136, 6073.1497590337385, 6083.057126145454, 6084.239470066242, 5932.110183472428, 5935.139404786281, 5940.316863560746, 5951.196588460589, 5959.28488581373, 5847.795327163614, 5926.851828704231, 6037.516998640098, 6037.63774173551, 6071.878562428815, 5981.162637230791, 6054.364291749391, 6065.181200537871, 6068.271807383054, 6068.423499767515, 6010.3088144863905, 6057.061155520902, 6058.182145410401, 6064.241969753781, 6091.847703807441, 4692.319613656001, 4759.214729569651, 4766.23740201573, 4816.229403295456, 4827.8551643450755, 4244.511881133647, 4302.419173411549, 4323.178596066738, 4330.0, 4339.067100573392, 5496.120620700669, 5549.38956714761, 5563.276774019351, 5567.0, 5571.526420254243, 5901.1148327750325, 5930.673812940329, 6038.909416358758, 6064.545159113084, 6077.726502307319, 5444.564434845203, 5467.863303417446, 5505.146702261156, 5518.779248830002, 5520.199571062173, 5233.241180028875, 5238.476152384807, 5247.842564723064, 5260.894782104337, 5272.736363181825, 5993.580449368676, 6006.819795406145, 6071.301800293023, 6073.423024812636, 6079.817605471843, 6020.819375418744, 6081.057436097412, 6083.422074902877, 6098.329183789869, 6122.234720659918, 6085.939140608537, 6089.908651370144, 6110.908336374869, 6114.205153330038, 6118.082287656887, 5760.683162426107, 5870.031444811559, 5932.584719069624, 5940.79207227745, 6002.579819412599, 5968.045112781934, 6039.245094363205, 6069.666168360606, 6073.514118870568, 6075.787347442818, 5913.881722365614, 5919.319278283053, 5927.614705044159, 5928.49607783326, 5940.762369505172, 5746.683932383759, 5800.1299805029585, 5930.318013428511, 5934.287885453773, 5935.525157987265, 4780.928286075781, 5786.804566689117, 5805.448482394166, 5821.621594596338, 5823.068309070529, 5907.586468947133, 5920.496757775465, 5931.110333450017, 5933.9109913352195, 5940.46535811779, 5548.250986116823, 5618.2134501170685, 5686.857828554319, 5708.914366284426, 5761.0], "value": 5513.565658124968, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [470.17249640632485, 484.50580407983836, 498.35214221512024, 510.17097217022314, 537.9865503362415, 29283.70716292839, 34121.82939085304, 39471.60528394715, 40616.18767624646, 40692.59307406925, 41802.0, 42076.0, 42184.78907605458, 42190.0, 42208.78895605518, 8884.955575222115, 9799.167070798972, 10027.34822236556, 10029.749256268615, 10036.749081272954, 8890.155435233642, 12022.93988530056, 12260.264384136935, 12260.44827982743, 12467.314297713621, 10364.326318789292, 12214.938925305405, 12234.265944043385, 12268.815967760445, 12302.692432689164, 11702.648920532398, 11756.823647645293, 11793.11551633631, 11806.645800625995, 11820.408979550988, 11977.94011029948, 12044.337561434118, 12049.819252711217, 12076.637700868989, 12076.879231207664, 39411.0, 39481.40777888334, 39587.8020609898, 39605.20789584221, 39760.40359394612, 3395.8471868765964, 3398.3882901077795, 3417.487376893461, 3904.1801221743467, 3920.6863450923856, 30958.0712578623, 30964.8451757742, 31453.528197077063, 31506.52740208899, 31529.684703152903, 3231.9676803232137, 3328.251143492719, 3364.8317584120696, 3427.7429192810637, 3436.9312613747584, 388585.0, 389836.0, 390224.0977590216, 390504.0, 391927.0, 56650.0, 56828.60128287806, 56935.71532142313, 56996.0, 57069.0, 59632.0, 59636.701816491506, 59655.403445964985, 59795.701021495486, 59832.0, 16067.919660401623, 16276.51170464888, 16300.592485187964, 16321.510354689379, 16325.673486530202, 11587.304761714324, 11780.410979450993, 11786.292822430682, 12881.935590322177, 12935.611931642066, 13734.725305493834, 13845.653858653613, 13853.722925541635, 15020.0, 15351.923240383727, 5307.416184219733, 7104.07352830996, 7229.530080544748, 7239.0, 7262.346388825031, 851.6713926274907, 964.6864768950119, 1013.2400699475394, 1014.7463134216446, 1074.6346242277664, 24570.754292457204, 28864.855675721486, 29509.557356639667, 29912.25219369533, 30186.698133018388, 1021.8722659667542, 1049.338916482611, 1057.8043062033548, 1189.655000049993, 1201.3332600406684, 12838.614841554769, 15373.615659608598, 15554.455594054154, 15612.792423422627, 15810.762838557432, 694010.7695153629, 701057.0, 822313.0, 839716.0, 902817.4859125662, 43581.782091089975, 54818.0, 56113.07208495366, 56162.0, 60562.39437605567, 1389.2289779172656, 1408.9647758805907, 1428.778539326406, 1430.2634143416358, 1431.1627697796905, 30112.386513618505, 30642.693573064877, 30693.23266918345, 31467.0, 32353.0, 50926.0, 51495.19766808144, 51537.0, 51619.741901290254, 51624.96750065053, 529357.0, 532297.0, 532324.0, 532616.0, 532787.3360633173, 25650.102246421287, 25804.483910321313, 26087.478250435266, 26193.214203573923, 26227.344316391478, 47238.76380618075, 48128.7593562044, 48207.75896120497, 48404.78988025327, 48472.757636211594, 37129.62870371262, 38628.22743545169, 38935.80532097322, 39194.60805392024, 40211.195776084896, 32611.34777304393, 38522.422163667004, 38977.683431849524, 39621.207575847744, 40324.19351612893, 36979.81510092432, 37099.0, 37817.86546403502, 37865.81067094647, 38059.2388152241, 32840.671593284715, 33391.99824005284, 34202.82898585591, 34750.82624586861, 34815.477767833, 24451.51096978015, 25450.0, 25508.107216247343, 25618.487630247662, 25639.974401023752], "value": 20103.279935388517, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [348.846507536684, 366.543653151826, 368.657148851568, 374.10028880542296, 395.27072551143146, 15603.141827199514, 16478.928869623465, 20625.690614640793, 20747.68878466824, 20755.962201889884, 20138.39584812458, 20718.48203794906, 20873.895630521827, 21256.256031038916, 21331.893340533315, 5225.6603320784025, 5325.7869685212545, 5347.973260133714, 5501.092319767254, 5507.972460137694, 3862.4013277941963, 5344.973275133619, 5429.945700542983, 5446.673199608036, 5451.182322651594, 5772.5670574706855, 6042.818715438544, 6070.0591408331775, 6088.756449742005, 6117.204763380777, 5825.213596164548, 5925.73334199962, 6018.608790428652, 6076.4834989025985, 6111.663858487781, 5829.562782791308, 5833.445822646841, 5835.183074369592, 5840.503557197643, 5841.970790146064, 13295.401706923212, 14813.851861481355, 21011.474713132295, 25642.615360769418, 25926.87036564824, 2125.4792575819024, 2167.2306331252335, 2181.727284089489, 2273.30664147435, 2927.711806804999, 9759.560819763128, 9870.802583948282, 9967.900320996841, 10353.534090965924, 10388.428636424993, 1803.980750875751, 1896.8008359122368, 1908.7232351309121, 1919.0884329943306, 1943.37811900192, 121780.78219217782, 123542.0, 123993.38003310015, 128898.35550822185, 131793.34103329518, 9226.492542910204, 10861.1854110942, 11425.771484570261, 11444.02725768319, 11603.825942610867, 14219.85780142206, 14288.0, 14302.996370762048, 14323.35544900482, 14339.35472903701, 8306.08633050364, 8567.186117318844, 8596.140385961478, 8692.957155068554, 8718.43330183536, 8530.402871798915, 8550.657973681107, 8575.284943011422, 8726.301895848317, 8734.432261902955, 4412.558744125626, 4492.348609451611, 4504.81980720774, 4564.954350456519, 4577.496475387705, 1877.6057028024268, 1878.7839398469077, 1895.393474088306, 1905.914233859452, 1957.2268953763185, 896.4536175098034, 939.4316438554713, 940.6096469965024, 944.9196818270386, 1006.2352612014776, 19288.710669339973, 20216.69674954877, 21792.602813690482, 21794.78205217959, 21928.89035554812, 839.26983524333, 926.2173463423396, 952.1573407534244, 979.4319294808994, 979.4515071559807, 10272.918124562751, 11052.075426768568, 11086.279391839505, 11169.664910052712, 11301.26541774782, 600259.0, 605667.0, 611645.0, 619548.0, 622522.0, 79161.20838791538, 81453.59273203714, 122908.0, 141236.5876341244, 146710.26644866707, 975.8877729061248, 998.1814911772423, 1028.9691309260734, 1029.9897001029894, 1030.1295405382439, 15994.280257388678, 16523.0, 19405.2237910482, 19962.0, 20140.49648758734, 45912.31131532963, 50801.74599127129, 57790.42209578019, 58490.70754646199, 59081.704591476766, 88464.55767721377, 88781.11218887729, 88862.66705999286, 88970.55514722643, 89004.5549772247, 7893.486923349964, 8126.796830079295, 8252.257296843312, 8301.501909885426, 8320.958395207985, 12772.680682982627, 13045.934770326467, 13533.661658458617, 13569.864301357255, 13578.45686172542, 11125.721856953642, 11238.831417528907, 11594.188406811441, 11639.6508104757, 11671.299722016705, 10925.508352124332, 10936.617218397636, 11089.94455027752, 11197.0, 11391.088712902781, 9724.0, 11575.594854180063, 11658.475368608602, 11677.182597218452, 11725.0, 7963.7212697557625, 7978.122406535161, 8330.666773329, 11954.760904781682, 11958.282503049844, 10375.792484150426, 10677.626283080366, 11851.585194518495, 11906.642800715646, 11926.0], "value": 9573.159460625458, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1358.1918758338784, 1527.9694406111878, 1553.3475940105154, 1755.262789628356, 1916.271816709651, 7756.767296981093, 8471.872921906177, 8703.042665306826, 8704.390692651508, 8847.11528847115, 8632.438891472066, 8731.126887311266, 8745.0, 8746.256568191695, 8746.956265218681, 2326.220716060122, 2379.0364902214633, 2381.226101517005, 2382.4401265702545, 2384.403899025244, 2333.288347054148, 2358.599038163508, 2378.762123787616, 2384.153625462962, 2389.0682633772844, 2356.7878890899815, 2374.4776149247223, 2381.595128828095, 2391.7249516305587, 2394.461246219604, 2333.0434521845964, 2350.5651454480976, 2359.4691194481275, 2360.9881950590225, 2375.7743014413604, 2325.9534809303887, 2350.4828937633692, 2352.647102934556, 2361.858288502678, 2362.0079566582076, 8745.650173993032, 8758.912410875873, 8825.867611985825, 8842.69050583233, 8865.911340886572, 5231.869203269949, 5409.864753381157, 5410.756515956791, 5449.64577302474, 5449.700266485342, 3653.4154535274492, 3662.523871896663, 3712.6658600726064, 3724.1434470072036, 3728.738988270822, 1110.777844431117, 1115.8326251062324, 1119.8936101070385, 1126.5268587193398, 1140.1391949078393, 11526.250793698382, 11632.651020469315, 11660.12549058824, 11664.24182428148, 11691.532338706442, 4377.606015458624, 4554.4990051094355, 4632.374629425052, 4637.39713837199, 4663.417072865892, 9157.633694652139, 9159.084091590788, 9161.267098632094, 9165.81668366629, 9171.633134674672, 5255.027819853263, 5258.053550360895, 5260.552853007461, 5269.868253293698, 5279.392869819943, 4430.335449682573, 4589.0, 4747.098051370298, 4866.926996095061, 4934.407871055496, 3710.8144592770254, 3752.9437058444146, 3857.5949525300002, 3867.7099217558793, 3897.863574774926, 1168.3865970365625, 1184.0350114656528, 1187.7921363761307, 1200.111917181297, 1201.6515210589007, 816.5713000674574, 883.8630012348096, 905.2441211588398, 940.0505489455699, 948.166877370421, 5616.831495055155, 5659.773609055592, 5949.8215053548465, 6019.819405417845, 6318.925782616966, 435.7211384713786, 902.1384577728254, 961.5144352102144, 1014.3305418423805, 1073.4364458659265, 3937.7637341759587, 3938.330483817758, 3976.4234186042863, 4057.985503624094, 4058.0869304406274, 10369.118624916966, 10753.892461075446, 10763.461826908624, 10781.0, 10814.783704326026, 10652.147828173727, 10667.733306667395, 10685.0, 10689.625863094754, 10710.571577136983, 7700.460967732205, 8098.109207987117, 8104.5137291762685, 8194.590270486451, 8194.590270486571, 5814.0, 5856.238688970407, 5929.9110513341475, 6019.939800601938, 6173.104899789595, 10072.848907266543, 10180.287379883634, 10198.69403917854, 10217.642382516577, 10227.539760710935, 9843.901560984299, 10028.896821349792, 10120.59517619287, 10368.0, 10405.531751071068, 7000.649967501707, 7218.639068046682, 7252.202257751748, 7524.05949256343, 7699.422543309386, 7696.615169241628, 7942.12636609984, 8022.398320125897, 8517.44636598619, 8518.361122915681, 7434.182239953709, 7440.3675687567165, 7450.813729656802, 7511.936864501169, 7660.233976602518, 7279.636018199175, 7319.926800731924, 7366.116066072105, 7397.297256760599, 7540.509866858856, 6936.930630693831, 6947.444204463732, 6961.512694111364, 7002.824929376807, 7019.19279282889, 4899.265110233351, 4981.327520784647, 4989.55094041538, 5051.4443411225475, 5178.145605974972, 4711.858644240679, 4849.854504364875, 5002.52476014778, 5025.597952163891, 5123.026624941249], "value": 4572.481771384017, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [274.8611950964763, 295.60389078634626, 304.9573059771631, 308.9803647961727, 312.47192245105754, 4973.278874563195, 5341.225522299269, 5395.325584301962, 5424.701641409726, 5429.53848922841, 4975.577075948549, 5063.189889617662, 5074.340335756356, 5171.689698618076, 5274.709890955995, 3908.205632972424, 4016.975671203846, 4063.5123785145825, 4066.1867626474677, 4077.9184416311655, 3553.614090504705, 3592.317459682665, 4029.5164580250266, 4069.6337329640473, 4074.246264441073, 3168.6831316868243, 3994.1412596291793, 4040.4949381327333, 4051.9189616207805, 4082.2856000199995, 3549.9112522187024, 4005.499312585927, 4036.9596304037022, 4046.271671099201, 4047.433359329696, 3210.8922421764687, 3490.9825450872836, 3498.895033148984, 3501.824908754552, 3501.9824900875587, 4938.8765280868265, 5026.421961474441, 5257.526822585947, 5290.814821481268, 5316.548093412064, 4101.610347017028, 4502.475012612336, 4564.977175114136, 4565.292379681155, 4589.24277494213, 5282.894342113174, 5577.386487486373, 5577.593700976224, 5578.716895114147, 5620.381758006657, 3679.2825399047197, 3764.1906989997283, 3813.31360355136, 3822.2164456286396, 3860.575336712959, 5330.067238233293, 5521.404939678345, 5805.216295800055, 5859.267591551056, 5887.941120588825, 3413.0955296846355, 3839.462475253467, 3842.4438102568506, 3857.9228415431535, 3891.8637847675195, 3907.8632247871187, 3913.4716813229848, 3922.941155882664, 3940.2907476653904, 3953.110550126256, 3371.4268574342423, 4525.7510836904285, 4595.333676616939, 4610.193216187221, 4622.491525932145, 5139.897202055938, 5467.371252305956, 5530.834074977757, 5657.405972372924, 5660.462256085665, 1261.6374315739163, 1319.2084749150433, 1342.4965637885794, 1346.6229455752405, 1348.9527866524625, 836.1813056601867, 852.8635418333037, 861.2894362151251, 894.9105089490998, 894.9394966964169, 5175.353080864892, 5181.248718935733, 5197.868873761739, 5215.947840521546, 5232.450592687713, 4947.480514545993, 5021.397432308073, 5401.378841433207, 5681.772729090872, 5736.680563470425, 4363.781810909505, 4712.387389639393, 5150.819721309736, 5182.533571978464, 5207.765650545659, 2970.628671416073, 2993.431248062861, 2994.595729576523, 2999.145243605562, 3002.564628128953, 5561.241218962032, 5875.6474611523445, 5884.852878678067, 5887.4701276884425, 5895.970520147458, 5752.4180850265675, 5794.812063527052, 5804.984127777622, 5808.244928159313, 5831.854203644943, 4537.682362234678, 4798.152711206141, 5879.20630714848, 5891.499222566044, 5931.822045338647, 5400.164913420485, 5640.661560306395, 5665.405132461032, 5667.801626943037, 5672.290963629546, 4802.903941921212, 5163.509466600667, 5305.628605997543, 5456.781728730806, 5612.494875461065, 4768.856934291976, 5852.678102704393, 5864.237649105589, 5892.377891838091, 5903.852403689771, 3275.773363237257, 3753.868614598476, 3785.356489396754, 3788.1855401088897, 3803.7527560708463, 4904.019196160711, 5607.0, 5617.764091899939, 5642.802501912413, 5714.571407144397, 4904.460509343898, 5156.1750119980625, 5345.599080068932, 5523.723813809213, 5582.692951887685, 4594.5864872161665, 4727.692699974491, 5218.373795144607, 5486.753096110764, 5487.890242195213, 4243.490781106287, 4487.68586198976, 5370.898965711942, 5371.973140134274, 5386.649867758583, 5276.81531146408, 5356.7589458473685, 5596.369706238062, 5611.74747136388, 5631.887362252814, 5027.59779217669, 5436.918446223231, 5443.972780136074, 5453.454654534515, 5466.234727138124], "value": 4050.8821769514484, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [717.4403964907373, 739.3715341959336, 771.8726410142327, 785.8546168958743, 790.1387487638473, 15289.464868729598, 16563.83436165638, 16607.2526736297, 16617.252223649935, 16631.833681663196, 14726.779098313507, 14898.255087245649, 14900.329485173166, 14915.477958271462, 14956.700865982673, 662.5825729790225, 688.1088989758275, 688.3667026335783, 688.7520492622651, 828.70580943765, 685.5543896467311, 687.6458623808718, 824.2334628795218, 859.7807559072428, 866.0127454701645, 897.2014906733012, 905.7463910105148, 906.6826610686273, 911.5533388639585, 912.4935660708304, 906.3021473465395, 908.5184852028435, 908.695586978363, 908.9864800747185, 911.3256190419108, 522.3157663460878, 670.1187937861696, 670.4904272752743, 670.5909395268882, 673.7204060314964, 14121.646958826008, 14615.050021748659, 14622.0, 14622.707545849129, 14626.926865365711, 14150.716985660332, 14819.629509262248, 14843.0, 14926.0, 14975.700485990219, 11040.613578524793, 11593.246438981523, 11774.470148843237, 11807.409629518575, 11814.232074915102, 3843.538775346948, 3844.75009124406, 3900.4929359183407, 3947.782871942042, 4019.055521952339, 102547.0, 116710.41644791722, 117503.4124829379, 118199.0, 120024.0, 11334.60328888485, 11409.25839820409, 11482.540698371971, 11503.539858405737, 11669.474873630537, 23461.882690586437, 23470.178543750888, 23479.0, 23485.530289394115, 23519.294421167393, 7637.045369328834, 7789.844203115906, 7937.047554293406, 7958.641861116148, 7969.840603187904, 4467.544398344427, 4522.072975040109, 4643.767811609406, 4673.649476289292, 4764.285357196448, 3609.4946707460977, 3738.2523495300507, 3741.812909354576, 3745.8688945886765, 3760.0599850037493, 645.1194120026224, 648.9513286503532, 679.1612358736955, 689.035350509291, 689.1385767790263, 575.8848230353946, 635.1869606903172, 678.1692426777158, 682.6211452643731, 682.9897551536731, 8803.559822008874, 9333.253339732804, 9340.299477539076, 9350.625974961062, 9457.43255404678, 605.4218221598363, 666.5334266013809, 667.7929841749074, 678.6471035061838, 679.1985457160499, 3928.332183528807, 4384.934225986613, 4563.429571303587, 4577.198990176772, 4597.310403439444, 156306.21846890694, 156545.2172739152, 157569.0, 161812.0, 165429.0, 136785.0, 136830.0, 139836.30081849525, 140937.0, 141966.0, 90167.19665606652, 101787.0, 113029.43485282522, 113132.0, 115405.99999999833, 8574.884016019836, 8586.871196931927, 8942.920064281909, 9095.272378209595, 9167.77080572991, 57510.42489575218, 57528.0, 58330.66677333029, 66424.0, 66814.33185668082, 86966.1303386958, 89639.10360896568, 90231.64652530086, 91345.54327228322, 91650.54174729083, 13206.27365494907, 13301.733965320445, 13572.04995650295, 13679.720745011045, 13700.794488082487, 15154.3749503296, 15395.999260048062, 15446.45937392231, 15610.609734756721, 15907.363705451653, 13987.510437134652, 14222.715545689236, 14281.07173033791, 14542.127472351693, 14684.192369419785, 14279.000469967003, 14343.56969290923, 14417.567472975827, 14444.350004250044, 14905.478308259158, 13634.0, 13638.659033524242, 13671.31643417845, 13906.235157066458, 14300.284985750879, 11185.161112916401, 11601.0, 11650.359230242419, 11698.071779312642, 11895.405229738652, 11178.496967636313, 11299.412506291252, 11561.653150405502, 11651.184417090722, 11672.88327116718], "value": 7937.189848024574, "unit": "score"}], "name": "dromaeo_css", "value": 7337.167525419033, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 118.0, 158.0, 165.0, 112.0, 113.0, 112.0, 112.0, 113.0, 113.0], "value": 121.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 114.0, 118.0, 153.0, 127.0, 132.0, 157.0, 107.0, 162.0, 108.0], "value": 132.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 184.0, 185.0, 181.0, 185.0, 183.0, 184.0, 185.0, 181.0, 180.0], "value": 183.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 76.0, 58.0, 89.0, 58.0, 57.0, 60.0, 66.0, 109.0, 76.0], "value": 72.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 72.0, 97.0, 74.0, 97.0, 97.0, 101.0, 71.0, 67.0, 105.0], "value": 87.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 90.0, 131.0, 109.0, 87.0, 84.0, 88.0, 84.0, 86.0, 85.0], "value": 93.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 93.0, 93.0, 93.0, 92.0, 94.0, 94.0, 93.0, 93.0], "value": 93.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 86.0, 84.0, 85.0, 83.0, 92.0, 81.0, 86.0, 85.0, 88.0], "value": 85.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 53.0, 56.0, 54.0, 53.0, 55.0, 54.0, 53.0, 53.0], "value": 54.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 66.0, 67.0, 72.0, 73.0, 70.0, 67.0, 69.0, 68.0, 69.0], "value": 68.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 86.0, 85.0, 85.0, 85.0, 85.0, 85.0, 89.0, 85.0, 82.0], "value": 85.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 148.0, 150.0, 138.0, 138.0, 134.0, 138.0, 139.0, 156.0, 140.0], "value": 142.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 170.0, 169.0, 169.0, 163.0, 170.0, 167.0, 166.0, 226.0, 212.0], "value": 178.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 68.0, 67.0, 68.0, 66.0, 66.0, 67.0, 66.0, 65.0], "value": 66.7, "unit": "score"}], "name": "kraken", "value": 1463.5, "alertThreshold": 2.0}]} 09:38:18 INFO - Return code: 0 09:38:18 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:38:18 INFO - # TBPL SUCCESS # 09:38:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:38:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:38:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:38:18 INFO - 2016-08-19 09:38:18.745 screenresolution[2199:21724] starting screenresolution argv=screenresolution get 09:38:18 INFO - 2016-08-19 09:38:18.749 screenresolution[2199:21724] Display 0: 1600x1200x32@60 09:38:18 INFO - 2016-08-19 09:38:18.755 screenresolution[2200:21727] starting screenresolution argv=screenresolution list 09:38:18 INFO - Available Modes on Display 0 09:38:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:38:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:38:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:38:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:38:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:38:18 INFO - Intel Iris: 09:38:18 INFO - Chipset Model: Intel Iris 09:38:18 INFO - Type: GPU 09:38:18 INFO - Bus: Built-In 09:38:18 INFO - VRAM (Dynamic, Max): 1536 MB 09:38:18 INFO - Vendor: Intel (0x8086) 09:38:18 INFO - Device ID: 0x0a2e 09:38:18 INFO - Revision ID: 0x0009 09:38:18 INFO - Displays: 09:38:18 INFO - Display: 09:38:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:38:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:38:18 INFO - Display Serial Number: 1600x1200 60 09:38:18 INFO - Main Display: Yes 09:38:18 INFO - Mirror: Off 09:38:18 INFO - Online: Yes 09:38:18 INFO - Rotation: Supported 09:38:18 INFO - Return code: 0 09:38:18 INFO - Running post-action listener: _resource_record_post_action 09:38:18 INFO - [mozharness: 2016-08-19 16:38:18.992642Z] Finished run-tests step (success) 09:38:18 INFO - Running post-run listener: _resource_record_post_run 09:38:19 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 1028096; Write bytes: 703538176; Read time: 12; Write time: 2376 09:38:19 INFO - TinderboxPrint: CPU usage
53.6% 09:38:19 INFO - TinderboxPrint: I/O read bytes / time
1,028,096 / 12 09:38:19 INFO - TinderboxPrint: I/O write bytes / time
703,538,176 / 2,376 09:38:19 INFO - TinderboxPrint: CPU idle
2,246.8 (46.4%) 09:38:19 INFO - TinderboxPrint: CPU system
968.9 (20.0%) 09:38:19 INFO - TinderboxPrint: CPU user
1,629.9 (33.6%) 09:38:19 INFO - TinderboxPrint: Swap in / out
406,106,112 / 0 09:38:19 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 394588672; Write bytes: 389355520; Read time: 29517; Write time: 724 09:38:19 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 1019904; Write bytes: 294181888; Read time: 11; Write time: 1606 09:38:19 INFO - Running post-run listener: _upload_blobber_files 09:38:19 INFO - Blob upload gear active. 09:38:19 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:38:19 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:38:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:38:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:38:19 INFO - (blobuploader) - INFO - Open directory for files ... 09:38:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:38:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:38:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:38:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:38:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:38:20 INFO - (blobuploader) - INFO - Done attempting. 09:38:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:38:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:38:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:38:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:38:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:38:21 INFO - (blobuploader) - INFO - Done attempting. 09:38:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:38:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:38:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:38:24 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:38:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:38:24 INFO - (blobuploader) - INFO - Done attempting. 09:38:24 INFO - (blobuploader) - INFO - Iteration through files over. 09:38:24 INFO - Return code: 0 09:38:24 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:38:24 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:38:24 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ed94ef3ed206abdda81bf27c110df3860498f6995d4633ca72a2a05e547adc8bc6ce201545b3fe7b104332808940a14834c8730fdb39486d78b41b3a82155fba", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e51e53c6f7d211a2e936db2f26798f59596c2a2554a9f76fc55ff65888188994ef343f7c5fbaa703c3a8dde61747dfaf9918adf0263e51f6b018fe24760e0d1b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/610a53a173fc184831a61ec0948a9bc7a2ced5a2ebd7f4526b88122481dc6894e56d0571b2b7d5fc4c8dd6c65994227c43357f0eb9af5b7e371a9495c4b35f44"} 09:38:24 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:38:24 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:38:24 INFO - Contents: 09:38:24 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ed94ef3ed206abdda81bf27c110df3860498f6995d4633ca72a2a05e547adc8bc6ce201545b3fe7b104332808940a14834c8730fdb39486d78b41b3a82155fba", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e51e53c6f7d211a2e936db2f26798f59596c2a2554a9f76fc55ff65888188994ef343f7c5fbaa703c3a8dde61747dfaf9918adf0263e51f6b018fe24760e0d1b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/610a53a173fc184831a61ec0948a9bc7a2ced5a2ebd7f4526b88122481dc6894e56d0571b2b7d5fc4c8dd6c65994227c43357f0eb9af5b7e371a9495c4b35f44"} 09:38:24 INFO - Running post-run listener: copy_logs_to_upload_dir 09:38:24 INFO - Copying logs to upload dir... 09:38:24 INFO - mkdir: /builds/slave/test/build/upload/logs 09:38:24 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.963702 ========= master_lag: 0.27 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-08-19 09:38:24.508777) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-19 09:38:24.512724) ========= 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.m2cxzwOVtW/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.c5y7kbil3Q/Listeners TMPDIR=/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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/Firefox/sha512/ed94ef3ed206abdda81bf27c110df3860498f6995d4633ca72a2a05e547adc8bc6ce201545b3fe7b104332808940a14834c8730fdb39486d78b41b3a82155fba", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e51e53c6f7d211a2e936db2f26798f59596c2a2554a9f76fc55ff65888188994ef343f7c5fbaa703c3a8dde61747dfaf9918adf0263e51f6b018fe24760e0d1b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/610a53a173fc184831a61ec0948a9bc7a2ced5a2ebd7f4526b88122481dc6894e56d0571b2b7d5fc4c8dd6c65994227c43357f0eb9af5b7e371a9495c4b35f44"} build_url:https://queue.taskcluster.net/v1/task/RGgd55ucThm9pe3vugGg-Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011184 build_url: 'https://queue.taskcluster.net/v1/task/RGgd55ucThm9pe3vugGg-Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ed94ef3ed206abdda81bf27c110df3860498f6995d4633ca72a2a05e547adc8bc6ce201545b3fe7b104332808940a14834c8730fdb39486d78b41b3a82155fba", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e51e53c6f7d211a2e936db2f26798f59596c2a2554a9f76fc55ff65888188994ef343f7c5fbaa703c3a8dde61747dfaf9918adf0263e51f6b018fe24760e0d1b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/610a53a173fc184831a61ec0948a9bc7a2ced5a2ebd7f4526b88122481dc6894e56d0571b2b7d5fc4c8dd6c65994227c43357f0eb9af5b7e371a9495c4b35f44"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-19 09:38:24.554081) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 09:38:24.554709) ========= 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.m2cxzwOVtW/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.c5y7kbil3Q/Listeners TMPDIR=/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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.005120 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 09:38:24.605614) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-19 09:38:24.606068) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-19 09:38:24.606551) ========= ========= Total master_lag: 14.47 =========