builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0379 starttime: 1481734803.96 results: success (0) buildid: 20161214074201 builduid: d9ffc0d0c1d44bcdbf32861523aa6d6f revision: 18b5a7a5d833f09b1f1e5cc45f4c0ff6235a1d5e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-14 09:00:03.959682) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-14 09:00:03.960201) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-14 09:00:03.960642) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-14 09:00:03.987185) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-14 09:00:03.987565) ========= 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.LgiMgHN4OG/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.W1OiUomxMj/Listeners TMPDIR=/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-12-14 09:00:04-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 142M=0s 2016-12-14 09:00:05 (142 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=1.444915 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-14 09:00:05.456484) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-14 09:00:05.456810) ========= 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.LgiMgHN4OG/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.W1OiUomxMj/Listeners TMPDIR=/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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.027032 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-14 09:00:05.505753) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-14 09:00:05.506096) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 18b5a7a5d833f09b1f1e5cc45f4c0ff6235a1d5e --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 18b5a7a5d833f09b1f1e5cc45f4c0ff6235a1d5e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.LgiMgHN4OG/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.W1OiUomxMj/Listeners TMPDIR=/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-12-14 09:00:06,037 truncating revision to first 12 chars 2016-12-14 09:00:06,037 Setting DEBUG logging. 2016-12-14 09:00:06,037 attempt 1/10 2016-12-14 09:00:06,037 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/18b5a7a5d833?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-14 09:00:06,714 unpacking tar archive at: mozilla-central-18b5a7a5d833/testing/mozharness/ program finished with exit code 0 elapsedTime=0.950480 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-14 09:00:06.476854) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-14 09:00:06.477200) ========= script_repo_revision: 18b5a7a5d833f09b1f1e5cc45f4c0ff6235a1d5e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-14 09:00:06.477590) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-14 09:00:06.477854) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-14 09:00:06.496229) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2016-12-14 09:00:06.496565) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.LgiMgHN4OG/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.W1OiUomxMj/Listeners TMPDIR=/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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:00:07 INFO - MultiFileLogger online at 20161214 09:00:07 in /builds/slave/test 09:00:07 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:00:07 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:00:07 INFO - {'append_to_log': False, 09:00:07 INFO - 'base_work_dir': '/builds/slave/test', 09:00:07 INFO - 'blob_upload_branch': 'Firefox', 09:00:07 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:00:07 INFO - 'branch': 'Firefox', 09:00:07 INFO - 'buildbot_json_path': 'buildprops.json', 09:00:07 INFO - 'config_files': ('talos/mac_config.py',), 09:00:07 INFO - 'default_actions': ('clobber', 09:00:07 INFO - 'read-buildbot-config', 09:00:07 INFO - 'download-and-extract', 09:00:07 INFO - 'populate-webroot', 09:00:07 INFO - 'create-virtualenv', 09:00:07 INFO - 'install', 09:00:07 INFO - 'run-tests'), 09:00:07 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:00:07 INFO - 'download_minidump_stackwalk': True, 09:00:07 INFO - 'download_symbols': 'ondemand', 09:00:07 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:00:07 INFO - 'tooltool.py': '/tools/tooltool.py', 09:00:07 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:00:07 INFO - '/tools/misc-python/virtualenv.py')}, 09:00:07 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:00:07 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:00:07 INFO - 'installer_path': 'installer.exe', 09:00:07 INFO - 'log_level': 'info', 09:00:07 INFO - 'log_name': 'talos', 09:00:07 INFO - 'log_to_console': True, 09:00:07 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:00:07 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:00:07 INFO - 'opt_config_files': (), 09:00:07 INFO - 'pip_index': False, 09:00:07 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:00:07 INFO - 'cmd': ('bash', 09:00:07 INFO - '-c', 09:00:07 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:00:07 INFO - 'enabled': True, 09:00:07 INFO - 'halt_on_failure': False, 09:00:07 INFO - 'name': 'check_screen_resolution'},), 09:00:07 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:00:07 INFO - 'cmd': ('bash', 09:00:07 INFO - '-c', 09:00:07 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:00:07 INFO - 'enabled': True, 09:00:07 INFO - 'halt_on_failure': False, 09:00:07 INFO - 'name': 'check_screen_resolution'},), 09:00:07 INFO - 'run_cmd_checks_enabled': True, 09:00:07 INFO - 'sps_profile': False, 09:00:07 INFO - 'sps_profile_interval': 0, 09:00:07 INFO - 'suite': 'dromaeojs-e10s', 09:00:07 INFO - 'system_bits': '32', 09:00:07 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:00:07 INFO - 'title': 't-yosemite-r7-0379', 09:00:07 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:00:07 INFO - 'use_talos_json': True, 09:00:07 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:00:07 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:00:07 INFO - 'work_dir': 'build'} 09:00:07 INFO - [mozharness: 2016-12-14 17:00:07.125127Z] Running clobber step. 09:00:07 INFO - Running pre-action listener: _resource_record_pre_action 09:00:07 INFO - Running main action method: clobber 09:00:07 INFO - rmtree: /builds/slave/test/build 09:00:07 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:00:09 INFO - Running post-action listener: _resource_record_post_action 09:00:09 INFO - [mozharness: 2016-12-14 17:00:09.344816Z] Finished clobber step (success) 09:00:09 INFO - [mozharness: 2016-12-14 17:00:09.344983Z] Running read-buildbot-config step. 09:00:09 INFO - Running pre-action listener: _resource_record_pre_action 09:00:09 INFO - Running main action method: read_buildbot_config 09:00:09 INFO - Using buildbot properties: 09:00:09 INFO - { 09:00:09 INFO - "project": "", 09:00:09 INFO - "product": "firefox", 09:00:09 INFO - "script_repo_revision": "production", 09:00:09 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 09:00:09 INFO - "repository": "", 09:00:09 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 09:00:09 INFO - "buildid": "20161214074201", 09:00:09 INFO - "pgo_build": "False", 09:00:09 INFO - "basedir": "/builds/slave/test", 09:00:09 INFO - "buildnumber": 35, 09:00:09 INFO - "slavename": "t-yosemite-r7-0379", 09:00:09 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 09:00:09 INFO - "platform": "yosemite_r7", 09:00:09 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 09:00:09 INFO - "branch": "mozilla-central", 09:00:09 INFO - "repo_path": "mozilla-central", 09:00:09 INFO - "revision": "18b5a7a5d833f09b1f1e5cc45f4c0ff6235a1d5e", 09:00:09 INFO - "stage_platform": "macosx64", 09:00:09 INFO - "builduid": "d9ffc0d0c1d44bcdbf32861523aa6d6f", 09:00:09 INFO - "slavebuilddir": "test" 09:00:09 INFO - } 09:00:09 INFO - Found installer url https://queue.taskcluster.net/v1/task/Vwyo-49SQiifCUX9dw0pjA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 09:00:09 INFO - Running post-action listener: _resource_record_post_action 09:00:09 INFO - [mozharness: 2016-12-14 17:00:09.348296Z] Finished read-buildbot-config step (success) 09:00:09 INFO - [mozharness: 2016-12-14 17:00:09.348415Z] Running download-and-extract step. 09:00:09 INFO - Running pre-action listener: _resource_record_pre_action 09:00:09 INFO - Running main action method: download_and_extract 09:00:09 INFO - mkdir: /builds/slave/test/build/tests 09:00:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:09 INFO - trying https://queue.taskcluster.net/v1/task/Vwyo-49SQiifCUX9dw0pjA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 09:00:09 INFO - Downloading https://queue.taskcluster.net/v1/task/Vwyo-49SQiifCUX9dw0pjA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 09:00:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Vwyo-49SQiifCUX9dw0pjA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 09:00:10 INFO - Downloaded 1199 bytes. 09:00:10 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 09:00:10 INFO - Using the following test package requirements: 09:00:10 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 09:00:10 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:00:10 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 09:00:10 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:00:10 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 09:00:10 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:00:10 INFO - u'jsshell-mac.zip'], 09:00:10 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:00:10 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 09:00:10 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 09:00:10 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:00:10 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 09:00:10 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:00:10 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 09:00:10 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:00:10 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 09:00:10 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:00:10 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 09:00:10 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:00:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Vwyo-49SQiifCUX9dw0pjA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 09:00:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Vwyo-49SQiifCUX9dw0pjA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:00:10 INFO - Fetch https://queue.taskcluster.net/v1/task/Vwyo-49SQiifCUX9dw0pjA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 09:00:11 INFO - Expected file size: 16276257 09:00:11 INFO - Obtained file size: 16276257 09:00:17 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 09:00:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Vwyo-49SQiifCUX9dw0pjA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 09:00:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Vwyo-49SQiifCUX9dw0pjA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:00:17 INFO - Fetch https://queue.taskcluster.net/v1/task/Vwyo-49SQiifCUX9dw0pjA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 09:00:18 INFO - Expected file size: 16276257 09:00:18 INFO - Obtained file size: 16276257 09:00:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Vwyo-49SQiifCUX9dw0pjA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 09:00:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Vwyo-49SQiifCUX9dw0pjA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:00:25 INFO - Fetch https://queue.taskcluster.net/v1/task/Vwyo-49SQiifCUX9dw0pjA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 09:00:26 INFO - Expected file size: 14409734 09:00:26 INFO - Obtained file size: 14409734 09:00:26 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:00:26 INFO - trying https://queue.taskcluster.net/v1/task/Vwyo-49SQiifCUX9dw0pjA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 09:00:26 INFO - Downloading https://queue.taskcluster.net/v1/task/Vwyo-49SQiifCUX9dw0pjA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 09:00:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Vwyo-49SQiifCUX9dw0pjA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 09:00:28 INFO - Downloaded 69676266 bytes. 09:00:28 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Vwyo-49SQiifCUX9dw0pjA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 09:00:28 INFO - mkdir: /builds/slave/test/properties 09:00:28 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:00:28 INFO - Writing to file /builds/slave/test/properties/build_url 09:00:28 INFO - Contents: 09:00:28 INFO - build_url:https://queue.taskcluster.net/v1/task/Vwyo-49SQiifCUX9dw0pjA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 09:00:29 INFO - Running post-action listener: _resource_record_post_action 09:00:29 INFO - Running post-action listener: set_extra_try_arguments 09:00:29 INFO - [mozharness: 2016-12-14 17:00:29.586423Z] Finished download-and-extract step (success) 09:00:29 INFO - [mozharness: 2016-12-14 17:00:29.586566Z] Running populate-webroot step. 09:00:29 INFO - Running pre-action listener: _resource_record_pre_action 09:00:29 INFO - Running main action method: populate_webroot 09:00:29 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:00:29 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:00:29 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:00:29 INFO - '--tppagecycles', 09:00:29 INFO - '7'], 09:00:29 INFO - 'tests': ['tsvgm']}}, 09:00:29 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:00:29 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:00:29 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:00:29 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:00:29 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:00:29 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:00:29 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:00:29 INFO - 'talos_options': ['--disable-e10s'], 09:00:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:00:29 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:00:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:00:29 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:00:29 INFO - 'talos_options': ['--disable-e10s'], 09:00:29 INFO - 'tests': ['damp', 'tps']}, 09:00:29 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:00:29 INFO - 'tests': ['damp', 'tps']}, 09:00:29 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:00:29 INFO - 'tests': ['dromaeo_dom']}, 09:00:29 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:00:29 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:00:29 INFO - 'tests': ['basic_compositor_video']}, 09:00:29 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:00:29 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:00:29 INFO - 'tests': ['a11yr', 09:00:29 INFO - 'ts_paint', 09:00:29 INFO - 'tpaint', 09:00:29 INFO - 'sessionrestore', 09:00:29 INFO - 'sessionrestore_no_auto_restore', 09:00:29 INFO - 'tabpaint']}, 09:00:29 INFO - 'other-e10s': {'tests': ['a11yr', 09:00:29 INFO - 'ts_paint', 09:00:29 INFO - 'tpaint', 09:00:29 INFO - 'sessionrestore', 09:00:29 INFO - 'sessionrestore_no_auto_restore', 09:00:29 INFO - 'tabpaint']}, 09:00:29 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:00:29 INFO - 'ts_paint', 09:00:29 INFO - 'tpaint', 09:00:29 INFO - 'sessionrestore', 09:00:29 INFO - 'sessionrestore_no_auto_restore', 09:00:29 INFO - 'tabpaint']}, 09:00:29 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:00:29 INFO - 'ts_paint', 09:00:29 INFO - 'tpaint', 09:00:29 INFO - 'sessionrestore', 09:00:29 INFO - 'sessionrestore_no_auto_restore', 09:00:29 INFO - 'tabpaint']}, 09:00:29 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:00:29 INFO - 'tests': ['a11yr', 09:00:29 INFO - 'ts_paint', 09:00:29 INFO - 'tpaint', 09:00:29 INFO - 'sessionrestore', 09:00:29 INFO - 'sessionrestore_no_auto_restore', 09:00:29 INFO - 'tabpaint']}, 09:00:29 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:00:29 INFO - 'tests': ['a11yr', 09:00:29 INFO - 'ts_paint', 09:00:29 INFO - 'tpaint', 09:00:29 INFO - 'sessionrestore', 09:00:29 INFO - 'sessionrestore_no_auto_restore', 09:00:29 INFO - 'tabpaint']}, 09:00:29 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:00:29 INFO - 'tests': ['tsvgx', 09:00:29 INFO - 'tsvgr_opacity', 09:00:29 INFO - 'tart', 09:00:29 INFO - 'tscrollx', 09:00:29 INFO - 'cart', 09:00:29 INFO - 'tsvg_static']}, 09:00:29 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:00:29 INFO - 'tsvgr_opacity', 09:00:29 INFO - 'tart', 09:00:29 INFO - 'tscrollx', 09:00:29 INFO - 'cart', 09:00:29 INFO - 'tsvg_static']}, 09:00:29 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:00:29 INFO - 'talos_options': ['--disable-e10s'], 09:00:29 INFO - 'tests': ['tp5o']}, 09:00:29 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:00:29 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:00:29 INFO - 'talos_options': ['--disable-e10s', 09:00:29 INFO - '--xperf_path', 09:00:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:00:29 INFO - 'tests': ['tp5n']}, 09:00:29 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:00:29 INFO - 'talos_options': ['--xperf_path', 09:00:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:00:29 INFO - 'tests': ['tp5n']}}, 09:00:29 INFO - 'talos.zip': {'path': '', 09:00:29 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:00:29 INFO - Running post-action listener: _resource_record_post_action 09:00:29 INFO - [mozharness: 2016-12-14 17:00:29.604485Z] Finished populate-webroot step (success) 09:00:29 INFO - [mozharness: 2016-12-14 17:00:29.604614Z] Running create-virtualenv step. 09:00:29 INFO - Running pre-action listener: _resource_record_pre_action 09:00:29 INFO - Running main action method: create_virtualenv 09:00:29 INFO - Creating virtualenv /builds/slave/test/build/venv 09:00:29 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:00:29 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:00:29 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:00:29 INFO - Using real prefix '/tools/python27' 09:00:29 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:00:30 INFO - Installing distribute.............................................................................................................................................................................................done. 09:00:33 INFO - Installing pip.................done. 09:00:33 INFO - Return code: 0 09:00:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:00:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:00:33 INFO - Reading from file tmpfile_stdout 09:00:33 INFO - Output received: 09:00:33 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 09:00:33 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:00:33 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:00:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:33 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:00:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:33 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 0x106ab93f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a2f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb295005740>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1068ced30>, '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 0x106735db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1068c6b20>, '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.LgiMgHN4OG/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.W1OiUomxMj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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:00:33 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:00:33 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:00:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LgiMgHN4OG/Render', 09:00:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:00:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:00:33 INFO - 'HOME': '/Users/cltbld', 09:00:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:00:33 INFO - 'LOGNAME': 'cltbld', 09:00:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:33 INFO - 'MOZ_NO_REMOTE': '1', 09:00:33 INFO - 'NO_EM_RESTART': '1', 09:00:33 INFO - 'PAGER': '/bin/cat', 09:00:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:00:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:33 INFO - 'PWD': '/builds/slave/test', 09:00:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:00:33 INFO - 'SHELL': '/bin/bash', 09:00:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.W1OiUomxMj/Listeners', 09:00:33 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 09:00:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:00:33 INFO - 'USER': 'cltbld', 09:00:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:00:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:00:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:33 INFO - 'XPC_FLAGS': '0x0', 09:00:33 INFO - 'XPC_SERVICE_NAME': '0', 09:00:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:00:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:33 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:00:33 INFO - Cleaning up... 09:00:33 INFO - Return code: 0 09:00:33 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:00:33 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:00:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:33 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:00:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:33 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 0x106ab93f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a2f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb295005740>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1068ced30>, '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 0x106735db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1068c6b20>, '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.LgiMgHN4OG/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.W1OiUomxMj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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:00:33 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:00:33 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:00:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LgiMgHN4OG/Render', 09:00:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:00:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:00:33 INFO - 'HOME': '/Users/cltbld', 09:00:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:00:33 INFO - 'LOGNAME': 'cltbld', 09:00:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:33 INFO - 'MOZ_NO_REMOTE': '1', 09:00:33 INFO - 'NO_EM_RESTART': '1', 09:00:33 INFO - 'PAGER': '/bin/cat', 09:00:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:00:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:33 INFO - 'PWD': '/builds/slave/test', 09:00:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:00:33 INFO - 'SHELL': '/bin/bash', 09:00:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.W1OiUomxMj/Listeners', 09:00:33 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 09:00:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:00:33 INFO - 'USER': 'cltbld', 09:00:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:00:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:00:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:33 INFO - 'XPC_FLAGS': '0x0', 09:00:33 INFO - 'XPC_SERVICE_NAME': '0', 09:00:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:00:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:34 INFO - Downloading/unpacking psutil>=3.1.1 09:00:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:00:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:00:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:00:37 INFO - Installing collected packages: psutil 09:00:37 INFO - Running setup.py install for psutil 09:00:37 INFO - building 'psutil._psutil_osx' extension 09:00:37 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:00:38 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:00:38 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:00:38 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:00:38 INFO - building 'psutil._psutil_posix' extension 09:00:38 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:00:38 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:00:38 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:00:38 INFO - ^ 09:00:38 INFO - 1 warning generated. 09:00:38 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:00:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:00:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:00:38 INFO - Successfully installed psutil 09:00:38 INFO - Cleaning up... 09:00:38 INFO - Return code: 0 09:00:38 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:00:38 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:00:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:38 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:00:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:38 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 0x106ab93f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a2f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb295005740>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1068ced30>, '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 0x106735db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1068c6b20>, '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.LgiMgHN4OG/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.W1OiUomxMj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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:00:38 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:00:38 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:00:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LgiMgHN4OG/Render', 09:00:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:00:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:00:38 INFO - 'HOME': '/Users/cltbld', 09:00:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:00:38 INFO - 'LOGNAME': 'cltbld', 09:00:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:38 INFO - 'MOZ_NO_REMOTE': '1', 09:00:38 INFO - 'NO_EM_RESTART': '1', 09:00:38 INFO - 'PAGER': '/bin/cat', 09:00:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:00:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:38 INFO - 'PWD': '/builds/slave/test', 09:00:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:00:38 INFO - 'SHELL': '/bin/bash', 09:00:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.W1OiUomxMj/Listeners', 09:00:38 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 09:00:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:00:38 INFO - 'USER': 'cltbld', 09:00:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:00:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:00:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:38 INFO - 'XPC_FLAGS': '0x0', 09:00:38 INFO - 'XPC_SERVICE_NAME': '0', 09:00:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:00:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:39 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:00:39 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:00:39 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:00:39 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:00:39 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:00:39 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:00:39 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:00:41 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:00:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:00:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:00:41 INFO - Installing collected packages: mozsystemmonitor 09:00:41 INFO - Running setup.py install for mozsystemmonitor 09:00:42 INFO - Successfully installed mozsystemmonitor 09:00:42 INFO - Cleaning up... 09:00:42 INFO - Return code: 0 09:00:42 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:00:42 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:00:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:42 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:00:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:42 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 0x106ab93f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a2f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb295005740>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1068ced30>, '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 0x106735db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1068c6b20>, '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.LgiMgHN4OG/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.W1OiUomxMj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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:00:42 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:00:42 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:00:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LgiMgHN4OG/Render', 09:00:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:00:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:00:42 INFO - 'HOME': '/Users/cltbld', 09:00:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:00:42 INFO - 'LOGNAME': 'cltbld', 09:00:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:42 INFO - 'MOZ_NO_REMOTE': '1', 09:00:42 INFO - 'NO_EM_RESTART': '1', 09:00:42 INFO - 'PAGER': '/bin/cat', 09:00:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:00:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:42 INFO - 'PWD': '/builds/slave/test', 09:00:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:00:42 INFO - 'SHELL': '/bin/bash', 09:00:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.W1OiUomxMj/Listeners', 09:00:42 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 09:00:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:00:42 INFO - 'USER': 'cltbld', 09:00:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:00:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:00:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:42 INFO - 'XPC_FLAGS': '0x0', 09:00:42 INFO - 'XPC_SERVICE_NAME': '0', 09:00:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:00:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:42 INFO - Downloading/unpacking jsonschema==2.5.1 09:00:42 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:00:42 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:00:42 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:00:42 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:00:42 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:00:42 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:00:44 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:00:45 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:00:45 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:00:45 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:00:45 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:00:45 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:00:45 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:00:45 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:00:45 INFO - Downloading functools32-3.2.3-2.tar.gz 09:00:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:00:45 INFO - warning: no files found matching '*.txt' 09:00:45 INFO - no previously-included directories found matching 'build' 09:00:45 INFO - no previously-included directories found matching 'dist' 09:00:45 INFO - no previously-included directories found matching '.git*' 09:00:45 INFO - Installing collected packages: jsonschema, functools32 09:00:45 INFO - Running setup.py install for functools32 09:00:45 INFO - warning: no files found matching '*.txt' 09:00:45 INFO - no previously-included directories found matching 'build' 09:00:45 INFO - no previously-included directories found matching 'dist' 09:00:45 INFO - no previously-included directories found matching '.git*' 09:00:45 INFO - Successfully installed jsonschema functools32 09:00:45 INFO - Cleaning up... 09:00:45 INFO - Return code: 0 09:00:45 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:00:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:45 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 0x106ab93f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a2f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb295005740>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1068ced30>, '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 0x106735db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1068c6b20>, '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.LgiMgHN4OG/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.W1OiUomxMj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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:00:45 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:00:45 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:00:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LgiMgHN4OG/Render', 09:00:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:00:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:00:45 INFO - 'HOME': '/Users/cltbld', 09:00:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:00:45 INFO - 'LOGNAME': 'cltbld', 09:00:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:45 INFO - 'MOZ_NO_REMOTE': '1', 09:00:45 INFO - 'NO_EM_RESTART': '1', 09:00:45 INFO - 'PAGER': '/bin/cat', 09:00:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:00:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:45 INFO - 'PWD': '/builds/slave/test', 09:00:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:00:45 INFO - 'SHELL': '/bin/bash', 09:00:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.W1OiUomxMj/Listeners', 09:00:45 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 09:00:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:00:45 INFO - 'USER': 'cltbld', 09:00:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:00:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:00:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:45 INFO - 'XPC_FLAGS': '0x0', 09:00:45 INFO - 'XPC_SERVICE_NAME': '0', 09:00:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:00:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:46 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:00:46 INFO - Cleaning up... 09:00:46 INFO - Return code: 0 09:00:46 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:00:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x106ab93f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a2f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb295005740>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1068ced30>, '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 0x106735db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1068c6b20>, '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.LgiMgHN4OG/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.W1OiUomxMj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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:00:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:00:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:00:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LgiMgHN4OG/Render', 09:00:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:00:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:00:46 INFO - 'HOME': '/Users/cltbld', 09:00:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:00:46 INFO - 'LOGNAME': 'cltbld', 09:00:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:46 INFO - 'MOZ_NO_REMOTE': '1', 09:00:46 INFO - 'NO_EM_RESTART': '1', 09:00:46 INFO - 'PAGER': '/bin/cat', 09:00:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:00:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:46 INFO - 'PWD': '/builds/slave/test', 09:00:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:00:46 INFO - 'SHELL': '/bin/bash', 09:00:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.W1OiUomxMj/Listeners', 09:00:46 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 09:00:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:00:46 INFO - 'USER': 'cltbld', 09:00:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:00:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:00:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:46 INFO - 'XPC_FLAGS': '0x0', 09:00:46 INFO - 'XPC_SERVICE_NAME': '0', 09:00:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:00:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:46 INFO - Downloading/unpacking blobuploader==1.2.4 09:00:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:48 INFO - Downloading blobuploader-1.2.4.tar.gz 09:00:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:00:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:00:48 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:00:48 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:00:48 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:00:48 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:00:48 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:00:48 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:00:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:00:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:00:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:50 INFO - Downloading docopt-0.6.1.tar.gz 09:00:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:00:50 INFO - Installing collected packages: blobuploader, requests, docopt 09:00:50 INFO - Running setup.py install for blobuploader 09:00:50 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:00:50 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:00:50 INFO - Running setup.py install for requests 09:00:50 INFO - Running setup.py install for docopt 09:00:51 INFO - Successfully installed blobuploader requests docopt 09:00:51 INFO - Cleaning up... 09:00:51 INFO - Return code: 0 09:00:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:00: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:00:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00: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:00:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:51 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 0x106ab93f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a2f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb295005740>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1068ced30>, '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 0x106735db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1068c6b20>, '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.LgiMgHN4OG/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.W1OiUomxMj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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:00:51 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:00:51 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:00:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LgiMgHN4OG/Render', 09:00:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:00:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:00:51 INFO - 'HOME': '/Users/cltbld', 09:00:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:00:51 INFO - 'LOGNAME': 'cltbld', 09:00:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:51 INFO - 'MOZ_NO_REMOTE': '1', 09:00:51 INFO - 'NO_EM_RESTART': '1', 09:00:51 INFO - 'PAGER': '/bin/cat', 09:00:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:00:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:51 INFO - 'PWD': '/builds/slave/test', 09:00:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:00:51 INFO - 'SHELL': '/bin/bash', 09:00:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.W1OiUomxMj/Listeners', 09:00:51 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 09:00:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:00:51 INFO - 'USER': 'cltbld', 09:00:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:00:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:00:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:51 INFO - 'XPC_FLAGS': '0x0', 09:00:51 INFO - 'XPC_SERVICE_NAME': '0', 09:00:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:00:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:00:51 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-gDjsVE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:00:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:00:51 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-Qa235d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:00:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:00:51 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-qFwjfF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:00:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:00:51 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-egxLOz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:00:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:00:51 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-RkzpYp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:00:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:00:51 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-UqSIAp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:00:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:00:51 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-VILHHN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:00:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:00:52 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-EoS0Hy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:00:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:00:52 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-mT1Jgf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:00:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:00:52 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-vkL9OW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:00:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:00:52 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-2E1dSM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:00:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:00:52 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-BpL1Ia-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:00:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:00:52 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-pT2Bnu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:00:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:00:52 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-uzhvi4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:00:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:00:52 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-B_CPtH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:00:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:00:52 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-KQq5tK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:00:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:00:53 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-Tzdlvw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:00:53 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:00:53 INFO - Running setup.py install for manifestparser 09:00:53 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:00:53 INFO - Running setup.py install for mozcrash 09:00:53 INFO - Running setup.py install for mozdebug 09:00:53 INFO - Running setup.py install for mozdevice 09:00:53 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:00:53 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:00:53 INFO - Running setup.py install for mozfile 09:00:53 INFO - Running setup.py install for mozhttpd 09:00:54 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:00:54 INFO - Running setup.py install for mozinfo 09:00:54 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:00:54 INFO - Running setup.py install for mozInstall 09:00:54 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:00:54 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:00:54 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:00:54 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:00:54 INFO - Running setup.py install for mozleak 09:00:54 INFO - Running setup.py install for mozlog 09:00:54 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:00:54 INFO - Running setup.py install for moznetwork 09:00:54 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:00:54 INFO - Running setup.py install for mozprocess 09:00:54 INFO - Running setup.py install for mozprofile 09:00:55 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:00:55 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:00:55 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:00:55 INFO - Running setup.py install for mozrunner 09:00:55 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:00:55 INFO - Running setup.py install for mozscreenshot 09:00:55 INFO - Running setup.py install for moztest 09:00:55 INFO - Running setup.py install for mozversion 09:00:55 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:00:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:00:55 INFO - Cleaning up... 09:00:55 INFO - Return code: 0 09:00:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:00:55 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:00:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:55 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:00:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:55 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 0x106ab93f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a2f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb295005740>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1068ced30>, '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 0x106735db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1068c6b20>, '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.LgiMgHN4OG/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.W1OiUomxMj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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:00:55 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:00:55 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:00:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LgiMgHN4OG/Render', 09:00:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:00:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:00:55 INFO - 'HOME': '/Users/cltbld', 09:00:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:00:55 INFO - 'LOGNAME': 'cltbld', 09:00:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:55 INFO - 'MOZ_NO_REMOTE': '1', 09:00:55 INFO - 'NO_EM_RESTART': '1', 09:00:55 INFO - 'PAGER': '/bin/cat', 09:00:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:00:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:55 INFO - 'PWD': '/builds/slave/test', 09:00:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:00:55 INFO - 'SHELL': '/bin/bash', 09:00:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.W1OiUomxMj/Listeners', 09:00:55 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 09:00:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:00:55 INFO - 'USER': 'cltbld', 09:00:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:00:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:00:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:55 INFO - 'XPC_FLAGS': '0x0', 09:00:55 INFO - 'XPC_SERVICE_NAME': '0', 09:00:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:00:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:00:56 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-gvG6zf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:00:56 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:00:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:00:56 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-dZE72S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:00:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:00:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:00:56 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-Wbc2ui-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:00:56 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:00:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:00:56 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-yhD4Ae-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:00:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:00:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:00:56 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-yNSod8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:00:56 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:00:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:00:56 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-BhoUHb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:00:56 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:00:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:00:56 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-uZGzOe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:00:56 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:00:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:00:56 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-Yv5LYv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:00:56 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:00:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:00:56 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-MyNueP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:00:56 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:00:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:00:56 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-sDVd9G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:00:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:00:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:00:57 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-cmA0cI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:00:57 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:00:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:00:57 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-k4wFKq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:00:57 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:00:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:00:57 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-cF6770-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:00:57 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:00:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:00:57 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-pK2N8W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:00:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:00:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:00:57 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-i6Ko85-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:00:57 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:00:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:00:57 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-OYdnvY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:00:57 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:00:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:00:57 INFO - Running setup.py (path:/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/pip-9R8hOP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:00:57 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:00:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:00:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:00:57 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:00:57 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:00:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:00:57 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:00:57 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:00:57 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:00:57 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:00:57 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:00:57 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:00:57 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:01:00 INFO - Downloading blessings-1.6.tar.gz 09:01:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:01:00 INFO - Installing collected packages: blessings 09:01:00 INFO - Running setup.py install for blessings 09:01:00 INFO - Successfully installed blessings 09:01:00 INFO - Cleaning up... 09:01:00 INFO - Return code: 0 09:01:00 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:01:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:01:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:01:01 INFO - Reading from file tmpfile_stdout 09:01:01 INFO - Current package versions: 09:01:01 INFO - blessings == 1.6 09:01:01 INFO - blobuploader == 1.2.4 09:01:01 INFO - docopt == 0.6.1 09:01:01 INFO - functools32 == 3.2.3-2 09:01:01 INFO - jsonschema == 2.5.1 09:01:01 INFO - manifestparser == 1.1 09:01:01 INFO - mozInstall == 1.12 09:01:01 INFO - mozcrash == 1.0 09:01:01 INFO - mozdebug == 0.1 09:01:01 INFO - mozdevice == 0.49 09:01:01 INFO - mozfile == 1.2 09:01:01 INFO - mozhttpd == 0.7 09:01:01 INFO - mozinfo == 0.9 09:01:01 INFO - mozleak == 0.1 09:01:01 INFO - mozlog == 3.4 09:01:01 INFO - moznetwork == 0.27 09:01:01 INFO - mozprocess == 0.23 09:01:01 INFO - mozprofile == 0.28 09:01:01 INFO - mozrunner == 6.13 09:01:01 INFO - mozscreenshot == 0.1 09:01:01 INFO - mozsystemmonitor == 0.3 09:01:01 INFO - moztest == 0.7 09:01:01 INFO - mozversion == 1.4 09:01:01 INFO - psutil == 3.1.1 09:01:01 INFO - requests == 1.2.3 09:01:01 INFO - wsgiref == 0.1.2 09:01:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:01:01 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:01:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:01 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:01:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:01 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 0x106ab93f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a2f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb295005740>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1068ced30>, '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 0x106735db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1068c6b20>, '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.LgiMgHN4OG/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.W1OiUomxMj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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:01:01 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:01:01 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:01:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LgiMgHN4OG/Render', 09:01:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:01:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:01:01 INFO - 'HOME': '/Users/cltbld', 09:01:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:01:01 INFO - 'LOGNAME': 'cltbld', 09:01:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:01 INFO - 'MOZ_NO_REMOTE': '1', 09:01:01 INFO - 'NO_EM_RESTART': '1', 09:01:01 INFO - 'PAGER': '/bin/cat', 09:01:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:01:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:01 INFO - 'PWD': '/builds/slave/test', 09:01:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:01:01 INFO - 'SHELL': '/bin/bash', 09:01:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.W1OiUomxMj/Listeners', 09:01:01 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 09:01:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:01:01 INFO - 'USER': 'cltbld', 09:01:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:01:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:01:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:01 INFO - 'XPC_FLAGS': '0x0', 09:01:01 INFO - 'XPC_SERVICE_NAME': '0', 09:01:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:01:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:01:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:01:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:01:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:01:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:01:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:01:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:01:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:01:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:01:02 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:01:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:01:02 INFO - Cleaning up... 09:01:02 INFO - Return code: 0 09:01:02 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:01:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:01:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:01:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:01:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106ab93f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106a2f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb295005740>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1068ced30>, '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 0x106735db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1068c6b20>, '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.LgiMgHN4OG/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.W1OiUomxMj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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:01:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:01:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:01:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LgiMgHN4OG/Render', 09:01:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:01:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:01:02 INFO - 'HOME': '/Users/cltbld', 09:01:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:01:02 INFO - 'LOGNAME': 'cltbld', 09:01:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:02 INFO - 'MOZ_NO_REMOTE': '1', 09:01:02 INFO - 'NO_EM_RESTART': '1', 09:01:02 INFO - 'PAGER': '/bin/cat', 09:01:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:01:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:02 INFO - 'PWD': '/builds/slave/test', 09:01:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:01:02 INFO - 'SHELL': '/bin/bash', 09:01:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.W1OiUomxMj/Listeners', 09:01:02 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 09:01:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:01:02 INFO - 'USER': 'cltbld', 09:01:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:01:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:01:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:02 INFO - 'XPC_FLAGS': '0x0', 09:01:02 INFO - 'XPC_SERVICE_NAME': '0', 09:01:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:01:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:01:02 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:01:02 INFO - Cleaning up... 09:01:02 INFO - Return code: 0 09:01:02 INFO - Running post-action listener: _resource_record_post_action 09:01:02 INFO - Running post-action listener: _start_resource_monitoring 09:01:02 INFO - Starting resource monitoring. 09:01:02 INFO - [mozharness: 2016-12-14 17:01:02.712040Z] Finished create-virtualenv step (success) 09:01:02 INFO - [mozharness: 2016-12-14 17:01:02.713041Z] Running install step. 09:01:02 INFO - Running pre-action listener: _resource_record_pre_action 09:01:02 INFO - Running main action method: install 09:01:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:01:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:01:02 INFO - Reading from file tmpfile_stdout 09:01:02 INFO - Detecting whether we're running mozinstall >=1.0... 09:01:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:01:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:01:03 INFO - Reading from file tmpfile_stdout 09:01:03 INFO - Output received: 09:01:03 INFO - Usage: mozinstall [options] installer 09:01:03 INFO - Options: 09:01:03 INFO - -h, --help show this help message and exit 09:01:03 INFO - -d DEST, --destination=DEST 09:01:03 INFO - Directory to install application into. [default: 09:01:03 INFO - "/builds/slave/test"] 09:01:03 INFO - --app=APP Application being installed. [default: firefox] 09:01:03 INFO - mkdir: /builds/slave/test/build/application 09:01:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:01:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 09:01:24 INFO - Reading from file tmpfile_stdout 09:01:24 INFO - Output received: 09:01:24 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:01:24 INFO - Running post-action listener: _resource_record_post_action 09:01:24 INFO - [mozharness: 2016-12-14 17:01:24.714713Z] Finished install step (success) 09:01:24 INFO - [mozharness: 2016-12-14 17:01:24.714859Z] Running run-tests step. 09:01:24 INFO - Running pre-action listener: _resource_record_pre_action 09:01:24 INFO - Running main action method: run_tests 09:01:24 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:01:24 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:01:24 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:01:24 INFO - 2016-12-14 09:01:24.729 screenresolution[1629:7459] starting screenresolution argv=screenresolution get 09:01:24 INFO - 2016-12-14 09:01:24.731 screenresolution[1629:7459] Display 0: 1600x1200x32@60 09:01:24 INFO - 2016-12-14 09:01:24.737 screenresolution[1630:7461] starting screenresolution argv=screenresolution list 09:01:24 INFO - Available Modes on Display 0 09:01:24 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:01:24 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:01:24 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:01:24 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:01:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:01:24 INFO - Intel Iris: 09:01:24 INFO - Chipset Model: Intel Iris 09:01:24 INFO - Type: GPU 09:01:24 INFO - Bus: Built-In 09:01:24 INFO - VRAM (Dynamic, Max): 1536 MB 09:01:24 INFO - Vendor: Intel (0x8086) 09:01:24 INFO - Device ID: 0x0a2e 09:01:24 INFO - Revision ID: 0x0009 09:01:24 INFO - Displays: 09:01:24 INFO - Display: 09:01:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:01:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:01:24 INFO - Display Serial Number: 1600x1200 60 09:01:24 INFO - Main Display: Yes 09:01:24 INFO - Mirror: Off 09:01:24 INFO - Online: Yes 09:01:24 INFO - Rotation: Supported 09:01:24 INFO - Return code: 0 09:01:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:01:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:01:24 INFO - Python 2.7.3 09:01:24 INFO - Return code: 0 09:01:24 INFO - grabbing minidump binary from tooltool 09:01:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:24 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 0x1068ced30>, '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 0x106735db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1068c6b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:01:24 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:01:24 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:01:24 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:01:25 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:01:25 INFO - Return code: 0 09:01:25 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:01:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:01:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:01:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:01:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:01:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Vwyo-49SQiifCUX9dw0pjA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0379', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 09:01:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Vwyo-49SQiifCUX9dw0pjA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0379 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 09:01:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LgiMgHN4OG/Render', 09:01:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:01:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:01:25 INFO - 'HOME': '/Users/cltbld', 09:01:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:01:25 INFO - 'LOGNAME': 'cltbld', 09:01:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:01:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:01:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:25 INFO - 'MOZ_NO_REMOTE': '1', 09:01:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:01:25 INFO - 'NO_EM_RESTART': '1', 09:01:25 INFO - 'PAGER': '/bin/cat', 09:01:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:01:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:25 INFO - 'PWD': '/builds/slave/test', 09:01:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:01:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:01:25 INFO - 'SHELL': '/bin/bash', 09:01:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.W1OiUomxMj/Listeners', 09:01:25 INFO - 'TMPDIR': '/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/', 09:01:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:01:25 INFO - 'USER': 'cltbld', 09:01:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:01:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:01:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:25 INFO - 'XPC_FLAGS': '0x0', 09:01:25 INFO - 'XPC_SERVICE_NAME': '0', 09:01:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:01:25 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Vwyo-49SQiifCUX9dw0pjA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0379', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 09:01:25 INFO - mozversion application_buildid: 20161214074201 09:01:25 INFO - mozversion application_changeset: 18b5a7a5d833f09b1f1e5cc45f4c0ff6235a1d5e 09:01:25 INFO - mozversion application_display_name: Nightly 09:01:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:01:25 INFO - mozversion application_name: Firefox 09:01:25 INFO - mozversion application_remotingname: firefox 09:01:25 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:01:25 INFO - mozversion application_vendor: Mozilla 09:01:25 INFO - mozversion application_version: 53.0a1 09:01:25 INFO - mozversion platform_buildid: 20161214074201 09:01:25 INFO - mozversion platform_changeset: 18b5a7a5d833f09b1f1e5cc45f4c0ff6235a1d5e 09:01:25 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:01:25 INFO - mozversion platform_version: 53.0a1 09:01:25 INFO - using testdate: 1481734885 09:01:25 INFO - actual date: 1481734885 09:01:25 INFO - starting webserver on 'localhost:49242' 09:01:25 INFO - SUITE-START | Running 2 tests 09:01:25 INFO - TEST-START | dromaeo_css 09:01:25 INFO - operating with platform_type : mac_ 09:01:25 INFO - Initialising browser for dromaeo_css test... 09:01:25 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/tmpFJCEju/profile http://localhost:49242/getInfo.html) 09:01:28 INFO - PROCESS | 1640 | __metrics Screen width/height:1600/1200 09:01:28 INFO - PROCESS | 1640 | colorDepth:24 09:01:28 INFO - PROCESS | 1640 | Browser inner width/height: 1024/689 09:01:28 INFO - PROCESS | 1640 | __metrics 09:01:28 INFO - PROCESS | 1640 | 2016-12-14 09:01:28.344 plugin-container[1642:7630] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e3f, name = 'com.apple.tsm.portname' 09:01:28 INFO - PROCESS | 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:01:28 INFO - PROCESS | 1640 | 2016-12-14 09:01:28.347 plugin-container[1642:7630] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x940b, name = 'com.apple.CFPasteboardClient' 09:01:28 INFO - PROCESS | 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:01:28 INFO - PROCESS | 1640 | 2016-12-14 09:01:28.348 plugin-container[1642:7630] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:01:28 ERROR - PROCESS | 1640 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3238: TypeError: this.tabs is undefined 09:01:29 INFO - TEST-INFO | 1640: exit 0 09:01:29 INFO - Browser initialized. 09:01:29 INFO - Running cycle 1/1 for dromaeo_css test... 09:01:29 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/tmpFJCEju/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:01:31 INFO - PROCESS | 1643 | 2016-12-14 09:01:31.560 plugin-container[1644:7751] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4443, name = 'com.apple.tsm.portname' 09:01:31 INFO - PROCESS | 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:01:31 INFO - PROCESS | 1643 | 2016-12-14 09:01:31.560 plugin-container[1644:7751] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4717, name = 'com.apple.CFPasteboardClient' 09:01:31 INFO - PROCESS | 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:01:31 INFO - PROCESS | 1643 | 2016-12-14 09:01:31.560 plugin-container[1644:7751] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:01:41 INFO - PROCESS | 1643 | RSS: Main: 237023232 09:01:41 INFO - PROCESS | 1643 | 09:02:30 INFO - PROCESS | 1643 | 1481734950863 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:04:43 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49242/tests/dromaeo/cssquery-ext.html) 09:04:46 INFO - PROCESS | 1643 | RSS: Main: 253239296 09:04:46 INFO - PROCESS | 1643 | 09:07:48 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-ext.html (next: http://localhost:49242/tests/dromaeo/cssquery-jquery.html) 09:07:49 INFO - PROCESS | 1643 | RSS: Main: 263688192 09:07:49 INFO - PROCESS | 1643 | 09:10:51 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49242/tests/dromaeo/cssquery-mootools.html) 09:10:51 INFO - PROCESS | 1643 | RSS: Main: 268255232 09:10:51 INFO - PROCESS | 1643 | 09:13:53 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49242/tests/dromaeo/cssquery-prototype.html) 09:13:54 INFO - PROCESS | 1643 | RSS: Main: 268378112 09:13:54 INFO - PROCESS | 1643 | 09:16:57 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49242/tests/dromaeo/cssquery-yui.html) 09:16:57 INFO - PROCESS | 1643 | RSS: Main: 272896000 09:16:57 INFO - PROCESS | 1643 | 09:20:00 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-yui.html (next: http://localhost:49242/tests/dromaeo/cssquery-dojo.html) 09:20:00 INFO - PROCESS | 1643 | RSS: Main: 275656704 09:20:00 INFO - PROCESS | 1643 | 09:20:00 INFO - PROCESS | 1643 | __start_tp_report 09:20:00 INFO - PROCESS | 1643 | _x_x_mozilla_page_load 09:20:00 INFO - PROCESS | 1643 | _x_x_mozilla_page_load_details 09:20:00 INFO - PROCESS | 1643 | |i|pagename|runs| 09:20:00 INFO - PROCESS | 1643 | |0;dojo.html;2770.279727270909;2802.1033269353816;2815.155453363991;3366.9831650841743;3405.8467368968395;4918.163912134936;5522.944770552298;5604.551635869131;5694.5729070319685;5708.372079071299;5069.062223488657;5217.399999000116;5633.661980281186;5659.6321239119425;5713.400092990239;5093.617978651598;5165.431802501722;5172.379314482248;5229.86925326868;5279.472052794709;5193.246979188034;5220.086484865153;5225.294585231002;5258.50044245796;5306.416294207634;5125.154349532323;5188.221766734982;5205.791768329263;5216.00895829793;5230.450802665722;5135.255387968761;5192.896142077175;5193.9740301298625;5201.765920533585;5203.4016088149965;5048.570871475929;5100.872478188038;5115.181570948667;5153.407358153823;5252.212168174766;5453.945460545384;5622.353429355636;5628.802991895305;5691;5705;6115.510759139258;6188.47397971173;6201.069839524062;6201.131841542187;6201.379862013762;5078.695278283278;6111.174991376151;6147.231596050494;6184.257889053298;6199.752009919598;5752.913706294367;5799.130130480419;5817.825465236049;5825.533957283407;5834.941650583482;6225.1907252057845;6239.376062393815;6241.812745617639;6268.592541484789;6277.905831412532;5389.299391079135;5620.943790562042;6012.458878700938;6075.605085669417;6134.294556126102;6151.6924153793025;6158.199434073542;6166.691665416712;6171.6297022178815;6171.907421388682;6108.5723999319625;6118.223266370716;6123.112148738497;6124.295705993779;6151.169592105009;5425.864353391118;5911.581220507133;6141.477974372139;6143.52555386704;6208.348123447064;4902.7058376497525;4907.362042934467;4910.140725373046;4922.212446008622;4939.901201976012;4331.848385306562;4353.172897149531;4367.497737760134;4408.228560001987;4431.800568974404;4902.1911384621135;5182.8963420732125;5231.947680523146;5414.242006119146;5932.3177834548715;5054.570361519239;5448.482394172547;6193.876122477525;6213.285472170668;6243.937560624336;5685.260916080883;5720.856978575569;5720.914186287126;5870.354261031283;5917.911231331534;3993.4608827808456;5336.092864213093;5337.959097975858;5342.332208473941;5348.037353276448;5102.209157580581;6165.0444181152;6191.628502289877;6230.252369715663;6237.532185086136;5393.191021346673;6150.200473938359;6215.65813880241;6220.409061139185;6252.562320637512;5301;5648.293963254593;6244.937550624436;6246.063090536456;6266.373362663697;6056.1824153740445;6104.511639068775;6108.694565271808;6130.7241174146375;6137.447629713347;4696.38946936896;6041.305249896318;6188.504919606331;6212.223472065992;6232.220972378453;6065.59585647361;6075.544334174866;6081.6351018939;6086.421789929949;6140.5701600887505;5897.528197744256;5950.2264705587995;6072.119542666201;6073.362296958932;6111.8777624446375;5732.05421105513;5826.592138550261;5832.475077243069;5845.620034697731;5870.207521984477;5991.2510936132985;6027.4876635484725;6055.6063855849225;6068.787592434243;6079.665618390854;5923.644581325134;5938.792142275;5949.0481522956115;5954.612950158225;5961.850953726192 09:20:00 INFO - PROCESS | 1643 | |1;ext.html;430.3050573324082;454.7158026233602;497.81829632184264;498.76059491444107;510.6731691717299;32665;40666.39000414996;40737.99999999996;40927.59072409275;40940.38589421161;41727;42217.57782422175;42358.36462453058;42380.57619423805;42446.57553424465;8513.148685131468;9207.677731279407;9463.81072378552;9484.241260699127;9560.56977436017;8612.354073444485;11230.775384492305;11489.942550287236;11508;11660.416979151008;11399.829002564968;11419.600313989013;11450.427478626078;11473.713157171054;11485.942570287138;10703.1437485001;11300.660980170607;11342.886571134306;11366.147538934612;11370.545178192862;11289.322640641587;11317.377544235063;11373;11392.25950313227;11394;39707.8014609928;39820.80089599563;40045.79977100125;40056.59943400558;40422.797886010674;3370.1574606348413;3436.1581412553833;3474.3398754236614;3883.864064757748;4044.3509501276417;30091.548626770615;30823.38353232923;31138.910138145282;31563.52654710181;31637.20906977321;3156.952645710316;3235.352929414103;3237.676232376767;3238.3199528099126;3294.901152965391;445281.7735911332;449360;450337;452072;453212;49523;62331.06503402453;63366;63424;63453;65060.674696626214;65492;65599;65631;65681;14751.926240368944;14971.176585287914;15625.218739063;16257.51227463178;16265.67468650644;10338.793224135476;11124.110071194285;11412.771744565229;11688.824667629993;11716.355600442057;17991.640167196583;18361.7245741314;18394;18686.34597789071;18876.150573224237;5599.7480113394995;6684.331566843277;6710.899336509956;6742.9662851685425;6753.628550429676;862.6161358195668;949.212153912265;953.7329547726648;990.8166989107038;991.7371896447447;29201.85399072991;29792.553111703342;30106.698933010826;30653.080407587808;31019.84490077535;1030.531108345716;1041.7499800048085;1047.5914393386586;1057.0856209378985;1132.6262333429947;15269.770953435707;15767.29047192879;15803.920980395025;15892.56966872987;16071.084021432152;621971;626355;630305;631788;632348;60554;60569;60571;60740.39259607436;60816.69591652102;1363.9454421823214;1375.917444953306;1394.072941493895;1394.6931675031476;1401;28746.568801468413;29649.5552566716;29923.850380747957;30263.697363026087;30586;52485.950280993406;52631.21053184282;52668.47331526789;52774.73612631912;52791.944161115796;577233;579229;579276;580295.7881684685;580804;24506.877465612557;24703.60632967948;25450;41925.580744192164;42001.78999104985;42427.1514569713;43004.784976074916;43254.783726081165;43399.783001086056;43732.56267437285;34425.65574344224;35522;35772.46341304934;36611.26777464489;36731;30331;35488.46767298544;36066.639333607374;36303;36620.81689591535;34907.65092349044;35209.47185792164;35529.46705799467;35863.56545738141;36246.818765906;26144.215673529823;30840.845795770878;32034.359312813147;32192.83903580561;32830.22452418003;20517.58964820725;23882.880585596962;24065.18841028358;24154.03383864696;24633.38416539529 09:20:00 INFO - PROCESS | 1643 | |2;jquery.html;368.20467789574525;373.3485068555371;376.279424901314;385.06813511302647;400.5133762478588;18934.90532547337;19916.20335186591;20090.296839610615;20756;20823.479413014684;19926.601467970668;20207.49481262969;20215.59568808623;20496.38510844673;20721.06755196016;5003.874903127415;5753.309602847654;6029.216201893748;6047.7278522466595;6063.969680151593;6002.189704389895;6008.849778755544;6033.969830150843;6069.241344831896;6090.056041313604;5818.621789583684;5948.375420580842;5969.373215812342;5978.760849566012;6015.0676645120075;4701.412323459567;5035.54680078791;5233.21501774733;5897.911531327012;5940.673262970535;4659.254519276899;4934;5340.198970154469;5378.435264297271;5415.566754659618;12899.484020639164;13160.407781649848;15111.848881511154;24305.513889722282;24708;2123.3948324727535;2171.9239826605994;2194.0675213034556;2279.5213005268915;2485.5028994201234;10009.218234262069;10082.24383171265;10216.92722264159;10252.794944101077;11684;1752.2027477497836;1774.9378771742927;1800.675878341898;1816.4535858784388;1841.558026073746;89629.55185224097;96366;113733.43133284364;122480.38759806233;123762.38118809438;8933.508657023796;11313.71715707114;11336.6032188873;11353.54585816558;11485.368304743319;14504.274786260645;14513.492027779188;14514.129152250687;14524.419023239163;14526.564203073925;8062.637181326737;8317.708880189164;8381.790455238546;8422.705205317785;8500.914990850137;8189.959050204711;8196.877046844302;8217.465864718773;8349.457285276438;8386.245237928615;3469.826508674607;4485.573870482299;4499.21263778844;4522.3668686383935;4589.311603259538;1637.1814092953523;1761.7755659816305;1811.2483319422556;1866.0576408913412;1872.288530358455;673.1551902362504;889.5063239901916;895.8297923394534;954.6181527388961;955.5986485675894;19792.90103549473;20523.179072837218;21340.359789206348;21752;21776.020079096477;904.1952662130709;910.321810251367;944.2870632672289;995.571904081252;995.5868314649484;8097.878531822027;8140.267375936194;8392.244697977212;8547.401681882333;11043.503042363112;572090;583556;590333;593285;596854;112257;121149;132572.33713831368;133963;133970;932.8274269260208;980.1913421427352;1046.9005444482762;1051.9631812886512;1052.1319911073394;19782.90108549448;20090.59818803645;20264.696029559862;20343.4914127148;20350.79649203489;37474.063148421505;38416.6158338413;44959.32561011653;45294.14410137807;53432;62592.06111908416;82392.3521529578;82444.58777706073;83441.74837377323;85102.57448712717;6954.3393377629045;7290.526115802456;7386.187519372757;7494.775156745306;8144.75565733029;11432.142589306002;12737.426815793497;12781.936090319488;13249.205047697169;13381;10781.652293463318;11237.55049797999;11245.831312530483;11311.981921627093;11339;10903;10987.670369888916;11272;11353;11369.090472761995;9993.350432221881;11189.216754827085;11255.831162532404;11500.94249528747;11715.472803724024;7385.446091543263;7439.590822504814;7547.509411888429;7550.35821955118;7555;9099.408538444979;11141.442927853413;11377.715557111138;11491.138164637518;11538.59614913474 09:20:00 INFO - PROCESS | 1643 | |3;mootools.html;1408.9084209526382;1450.6808502129531;1452.3827373366323;1827.5613852675363;1858.4053103007034;7473.103227612681;7751.806204844867;7960.92039079609;8424.789380265496;8426.157384261578;7899.64451599678;8178.141295164011;8223.753287401387;8257.42198046137;8318.542480163589;2275.3174047785683;2329.04509151248;2331.230693871027;2333.3583264602285;2337.614293641556;1940.41787463761;1941.4272789527054;2327.55776402483;2337.0651739304244;2359.9528009439887;2225.8330625203093;2231.866088034715;2235.228846048111;2255.3121298004185;2260.6835043093897;2270.6821045053707;2293.3578597992587;2310.1798861404213;2311.6185829338137;2320.3061764911627;2313.2366319114653;2333.719953605578;2341.7541158178315;2344.0037983856964;2344.788968992799;8263.214994575506;8296.419250652456;8351.457155284948;8391.412601117925;8404.327653787683;4998.550130488237;5171.301874247004;5172.069027575035;5172.094883395391;5179.689218646893;2648.496785610728;3250.6099268087983;3615.1865830188262;3619.4570814378053;3620.131168519563;1046.2832959422744;1049.0610903241557;1068.8557044798931;1084.1706094837455;1086.462201210406;10563;10608.992145746142;10698.46507674621;10709.303895246861;10738.570457181782;3723.9813800931365;4425.867223983286;4488;4562.1560011398;4575.679702420799;9045.864312035324;9228.123328283802;9234.86147707785;9238.122378374044;9262.814743705088;5125.769340379692;5151.072806894795;5158.329417175743;5168.793248270103;5183.14478111115;4272.87181384559;4418.8453404130705;4574.268117101248;4697.060587882437;4734.644901632391;3708.647678470541;3765.1904840459433;3767.020574650556;3856.4986551748652;3868.5357757069332;1242.5340497313507;1244.8132780082879;1249.7375551134364;1250.0999280518013;1250.4182209504984;753.1602263476185;852.3138873207004;891.5943245823134;907.2016625369764;932.8274269260208;5135.589152867761;5310.7610157543;5359.839204823784;5558.277423934863;5582.8325150245555;891.2246345679171;895.8835351404276;911.8632205169145;943.6791490893129;971.6162115964155;3677.3932301170544;3687.391580389206;3811.5807261201244;3872.6708229800784;4012.3981402789814;9242.075792420705;9436.056394360508;9599.952000239955;10034.69895903124;10045.146162576117;9800.705978820646;9875.358101723365;9886.802263954825;9907.900920990698;9983.850242246372;7583.848323033509;7637.389008879276;7719.996400467903;7845.0978137513775;7882.817577363441;5409.4590540947165;5440.646357986719;5514.696691681996;5619.93221287954;5662.292213473316;9111.95444022802;9201.585928633094;9272.119148680864;9275.489848058422;9335.439873607605;9343.205827504466;9408.247340212629;9468.195203407788;9480.57337419804;9595.520223988913;6566.704498297684;6795.116634837439;7001.614911179934;7030.613316267654;7243.818904527429;7747.690092396467;7916.327112195528;7918.9208107920485;7954.721584744506;7966.442349035512;6975.720971161097;7057;7113.146422429341;7156.749513766993;7207.495475316888;6174.38256174379;6310.936890631218;6658.3674550917585;6887.621180835102;6999.930000699927;6476.481881449567;6567;6656.101426307387;6661.16735408074;6678.332166783282;3903.4925459691194;4768.856934291976;4790.233562629963;4807.471178170468;4894.535019173173;4675.602573781267;4767.688885556499;4846.36997190363;4852.878678033077;5037.622178336566 09:20:00 INFO - PROCESS | 1643 | |4;prototype.html;353.3621812628205;353.7488383247894;358.97666651667646;365.07271530313005;368.1275377355666;5371.301730774994;5371.355437347524;5391.433899440556;5487.862803429916;5515.338159420875;4578;5174.628723388296;5354.72424219707;5423.755930983105;5475.67145971242;3813.1229817142075;4074.9185016299657;4221.24017676818;4305.806238719265;4313.870583882473;4186;4206.47419072616;4259.531451540328;4264.317709166534;4268.701190916637;3292.826734355326;4199.4120823084795;4218.219629368561;4221.514525829522;4264.33902745075;3715.8852344296874;4235.470566179228;4246.893827654302;4247.745135291892;4262.424572682695;3101.596792416994;3307.851146698404;3459.08334291413;3542.8051457169845;3763.8870833875026;4208.473940757405;4384.956150438518;5117.744112794346;5283.63014588979;5321.201819727034;3941.5861334559745;4680.531946805327;4696.178168820443;4715.575598196178;4728.621710263171;5349.518543331119;5640.520555752766;5705.258316418881;5736.451158187308;5766.711664416763;3432.5366075579727;3518.1881331114496;3636;3893.0462036801164;3895.7078219133396;5336.665833541615;5784.623999439981;6079.240094988127;6093.817185484442;6106.2061931948565;3706.8694048315524;3907.8046097695;3945.8421663133154;3946.165033259548;3954.584768599313;3582.0865679251624;3848.441975913533;4020.979895100506;4021.813564998274;4042.8382864685673;4491.640668746492;4702.882427939329;4721.591988086101;4725.669203155746;4729.55069268419;5281.498257665516;5809.3609702932645;5858.238429004202;5866.2373891393845;5910.527157827363;1094.2449709700281;1145.118258940611;1146.4580139712075;1266.1769849597836;1301.44688507384;748.5957582905279;770.2836362183224;815.7185770909027;862.2929198057658;878.4597472554333;4748.572628463386;5068.797248110108;5220.530152286236;5230.424653288135;5259.552938000236;5661.915071273934;5670.291213598301;5679.630823996427;5694.658320500783;5697.715114244271;4665.390440298092;4772.904541909212;5054.949450505522;5239.8166064188335;5263.7894484220205;2376.6078597031296;2650.8807103679997;2972.6135602371555;2973.598564193806;3008.293051132982;5320.707361095177;5691.573132015115;5891.263592050994;5951.494122999594;5970.462553414782;5101.234814777738;5261.3686357637325;5358.132844535227;5400.702961337164;5850.619709718854;4910.533499317559;5025.346704928336;5846.123081537803;5863.882722345615;5890.998530249868;4684.812607495662;4969.540077661581;5018.90958868521;5305.471861212251;5773.758641892014;4868.812820973889;5288.444713305047;5482.588805839658;5579.218909352613;5677.801276955287;5336.973315133399;5884.911726324023;5937.614055086406;5969.492593129459;5985.880282394241;3633.454981752758;3728.422094575345;3736.439534069911;3752.6059763724415;3753.0617345663586;4927.926081108714;5463.31708536433;5863.801074597026;5876.823695289148;5885.527538752157;5075.720835353944;5618.550515958796;5779.8555036124435;5786.276715410574;5840.912386314294;4620.5148459411275;5185.688858668492;5745.897215503581;5816.70916454184;5816.796412125555;4914.9017019658695;5615.6630602164005;5673.574481913791;5674.233978413026;5740.440926625519;4918.4097908249805;5107.208382700687;5421.2293181999585;5607.691576963306;5813.401314638509;5057.31726216956;5707.20099186106;5707.914381284367;5733;5753.424657534213 09:20:00 INFO - PROCESS | 1643 | |5;yui.html;637.2926052082189;732.868083744926;746.5296862976325;763.2215140556632;819.0792591373231;14774.852251477481;15169.241537923115;15643.139627320494;15666.921665391686;15734.52796416108;14061.367238474268;14128.646783830409;14190;14385.784213236784;14404.27978601071;876.7808047988003;892.0633334998258;893.4773157702751;893.4907102951321;893.5844832153048;697.9720811167547;698.12734082397;719.9992011104569;884.2704768565927;895.3195571365776;941.750436134425;943.905609439054;948.1466679987997;948.6395169835453;948.9051094890525;937.7186843946823;938.7277689470045;940.2290122099881;941;941.2987324443334;685.0648864300194;696.9024336592881;697.285282585349;699.4579201119122;701.982450438738;14272.785908211385;14375;14436.783448248285;14672;15249.695006099926;13865.960052996066;14404.711905761826;14411.063280886709;14424.35090420923;14442.422303107864;11709.414529273503;11752.58865939688;11832.645020649394;11833.526658933632;12025.579104731292;3384.4754063120254;3714.0886239907118;3722.7207959403154;3824.082220267144;3892.961070389288;96014;98573.0142698571;107423.46288268587;109851.07159461833;112936;11115;11525.423728813526;11535.192536522363;11595.594154204562;11755.824417558175;23604.055837766296;23823.04707811668;23992.7600723994;24046;24071.157509487082;8180.509169449852;8186.426950113435;8226.712065077694;8236.299914507214;8336.124706905808;4313.180495705806;4372.344148377769;4401.713888597231;4472.9776351118035;4549;3621.8370173342264;3673.94489082664;3717.2565486902727;3717.8326975286177;3720.8697695580527;619.2909119058653;684.6302996381997;717.1967396515936;718.3463048625725;728.1771598094225;590.8906852232351;606.8391876152823;664.4451882678018;664.5746722097862;675.8242856857253;7220.141386580677;8977.730668079968;9435.481048542397;9457.574409151604;9673.613055477843;451.40189249244884;506.9366329208849;526.6550409481796;607.984800379994;653.8659574787157;3884.261990221849;4047.817848196838;4246.460038151445;4253.085586598896;4308.138372325548;71285.28714712786;82595.5870220645;101069.97860042758;109253.45373273082;113729;84429.31141377139;91985.5400722992;94370.5281473602;97078.51460742792;99252;62570.68714656398;81706;81892;81935;82937.75593366104;8472.44929079608;8513.489190648581;8549.658013679384;8638.82722345537;8805.295576354005;51840.74079629578;52668.473315266354;53329;55186.17220741772;55568.61078472909;50744.731381715756;60510.69744651249;62237.68881155565;72195.91706124308;77896.22103779117;13053.34733263314;13155.802662960255;13172.40724167434;13198.670033248862;13681.931590342383;14618.121548058098;14674.160867582641;14695.41218351254;15075.924620377267;15413.152276624893;13805.585832425044;13896.652583685489;13973.834015779648;14000.649983750487;14104.717905641624;13691.45234190661;13827.316971734677;13882.791758123838;14463.49377771773;14602.634934126732;13393.062485625913;13517.492464860776;13520;13598.796018059538;13723.382447789674;10060.748481287734;10522.842487326236;11165.218434709492;11368.261063030877;11368.886311136783;9419.293552983416;11219.71950701239;11535.76928461443;11543.538258469567;11568.421578921188 09:20:00 INFO - PROCESS | 1643 | __end_tp_report 09:20:00 INFO - PROCESS | 1643 | __start_cc_report 09:20:00 INFO - PROCESS | 1643 | _x_x_mozilla_cycle_collect,368 09:20:00 INFO - PROCESS | 1643 | __end_cc_report 09:20:00 INFO - PROCESS | 1643 | __startTimestamp1481736000795__endTimestamp 09:20:00 INFO - PROCESS | 1643 | 09:20:00 INFO - PROCESS | 1643 | ------- Summary: start ------- 09:20:00 INFO - PROCESS | 1643 | Number of tests: 6 09:20:00 INFO - PROCESS | 1643 | 09:20:00 INFO - PROCESS | 1643 | [#0] dojo.html Cycles:165 Average:5606.57 Median:5825.53 stddev:682.21 (11.7%) stddev-sans-first:647.00 09:20:00 INFO - PROCESS | 1643 | Values: 2770.3 2802.1 2815.2 3367.0 3405.8 4918.2 5522.9 5604.6 5694.6 5708.4 5069.1 5217.4 5633.7 5659.6 5713.4 5093.6 5165.4 5172.4 5229.9 5279.5 5193.2 5220.1 5225.3 5258.5 5306.4 5125.2 5188.2 5205.8 5216.0 5230.5 5135.3 5192.9 5194.0 5201.8 5203.4 5048.6 5100.9 5115.2 5153.4 5252.2 5453.9 5622.4 5628.8 5691.0 5705.0 6115.5 6188.5 6201.1 6201.1 6201.4 5078.7 6111.2 6147.2 6184.3 6199.8 5752.9 5799.1 5817.8 5825.5 5834.9 6225.2 6239.4 6241.8 6268.6 6277.9 5389.3 5620.9 6012.5 6075.6 6134.3 6151.7 6158.2 6166.7 6171.6 6171.9 6108.6 6118.2 6123.1 6124.3 6151.2 5425.9 5911.6 6141.5 6143.5 6208.3 4902.7 4907.4 4910.1 4922.2 4939.9 4331.8 4353.2 4367.5 4408.2 4431.8 4902.2 5182.9 5231.9 5414.2 5932.3 5054.6 5448.5 6193.9 6213.3 6243.9 5685.3 5720.9 5720.9 5870.4 5917.9 3993.5 5336.1 5338.0 5342.3 5348.0 5102.2 6165.0 6191.6 6230.3 6237.5 5393.2 6150.2 6215.7 6220.4 6252.6 5301.0 5648.3 6244.9 6246.1 6266.4 6056.2 6104.5 6108.7 6130.7 6137.4 4696.4 6041.3 6188.5 6212.2 6232.2 6065.6 6075.5 6081.6 6086.4 6140.6 5897.5 5950.2 6072.1 6073.4 6111.9 5732.1 5826.6 5832.5 5845.6 5870.2 5991.3 6027.5 6055.6 6068.8 6079.7 5923.6 5938.8 5949.0 5954.6 5961.9 09:20:00 INFO - PROCESS | 1643 | 09:20:00 INFO - PROCESS | 1643 | [#1] ext.html Cycles:180 Average:68822.24 Median:35505.23 stddev:148997.02 (419.6%) stddev-sans-first:149326.51 09:20:00 INFO - PROCESS | 1643 | Values: 430.3 454.7 497.8 498.8 510.7 32665.0 40666.4 40738.0 40927.6 40940.4 41727.0 42217.6 42358.4 42380.6 42446.6 8513.1 9207.7 9463.8 9484.2 9560.6 8612.4 11230.8 11489.9 11508.0 11660.4 11399.8 11419.6 11450.4 11473.7 11485.9 10703.1 11300.7 11342.9 11366.1 11370.5 11289.3 11317.4 11373.0 11392.3 11394.0 39707.8 39820.8 40045.8 40056.6 40422.8 3370.2 3436.2 3474.3 3883.9 4044.4 30091.5 30823.4 31138.9 31563.5 31637.2 3157.0 3235.4 3237.7 3238.3 3294.9 445281.8 449360.0 450337.0 452072.0 453212.0 49523.0 62331.1 63366.0 63424.0 63453.0 65060.7 65492.0 65599.0 65631.0 65681.0 14751.9 14971.2 15625.2 16257.5 16265.7 10338.8 11124.1 11412.8 11688.8 11716.4 17991.6 18361.7 18394.0 18686.3 18876.2 5599.7 6684.3 6710.9 6743.0 6753.6 862.6 949.2 953.7 990.8 991.7 29201.9 29792.6 30106.7 30653.1 31019.8 1030.5 1041.7 1047.6 1057.1 1132.6 15269.8 15767.3 15803.9 15892.6 16071.1 621971.0 626355.0 630305.0 631788.0 632348.0 60554.0 60569.0 60571.0 60740.4 60816.7 1363.9 1375.9 1394.1 1394.7 1401.0 28746.6 29649.6 29923.9 30263.7 30586.0 52486.0 52631.2 52668.5 52774.7 52791.9 577233.0 579229.0 579276.0 580295.8 580804.0 24506.9 24703.6 25450.0 41925.6 42001.8 42427.2 43004.8 43254.8 43399.8 43732.6 34425.7 35522.0 35772.5 36611.3 36731.0 30331.0 35488.5 36066.6 36303.0 36620.8 34907.7 35209.5 35529.5 35863.6 36246.8 26144.2 30840.8 32034.4 32192.8 32830.2 20517.6 23882.9 24065.2 24154.0 24633.4 09:20:00 INFO - PROCESS | 1643 | 09:20:00 INFO - PROCESS | 1643 | [#2] jquery.html Cycles:180 Average:33916.57 Median:370.78 stddev:97983.09 (26426.4%) stddev-sans-first:98225.57 09:20:00 INFO - PROCESS | 1643 | Values: 368.2 373.3 376.3 385.1 400.5 18934.9 19916.2 20090.3 20756.0 20823.5 19926.6 20207.5 20215.6 20496.4 20721.1 5003.9 5753.3 6029.2 6047.7 6064.0 6002.2 6008.8 6034.0 6069.2 6090.1 5818.6 5948.4 5969.4 5978.8 6015.1 4701.4 5035.5 5233.2 5897.9 5940.7 4659.3 4934.0 5340.2 5378.4 5415.6 12899.5 13160.4 15111.8 24305.5 24708.0 2123.4 2171.9 2194.1 2279.5 2485.5 10009.2 10082.2 10216.9 10252.8 11684.0 1752.2 1774.9 1800.7 1816.5 1841.6 89629.6 96366.0 113733.4 122480.4 123762.4 8933.5 11313.7 11336.6 11353.5 11485.4 14504.3 14513.5 14514.1 14524.4 14526.6 8062.6 8317.7 8381.8 8422.7 8500.9 8190.0 8196.9 8217.5 8349.5 8386.2 3469.8 4485.6 4499.2 4522.4 4589.3 1637.2 1761.8 1811.2 1866.1 1872.3 673.2 889.5 895.8 954.6 955.6 19792.9 20523.2 21340.4 21752.0 21776.0 904.2 910.3 944.3 995.6 995.6 8097.9 8140.3 8392.2 8547.4 11043.5 572090.0 583556.0 590333.0 593285.0 596854.0 112257.0 121149.0 132572.3 133963.0 133970.0 932.8 980.2 1046.9 1052.0 1052.1 19782.9 20090.6 20264.7 20343.5 20350.8 37474.1 38416.6 44959.3 45294.1 53432.0 62592.1 82392.4 82444.6 83441.7 85102.6 6954.3 7290.5 7386.2 7494.8 8144.8 11432.1 12737.4 12781.9 13249.2 13381.0 10781.7 11237.6 11245.8 11312.0 11339.0 10903.0 10987.7 11272.0 11353.0 11369.1 9993.4 11189.2 11255.8 11500.9 11715.5 7385.4 7439.6 7547.5 7550.4 7555.0 9099.4 11141.4 11377.7 11491.1 11538.6 09:20:00 INFO - PROCESS | 1643 | 09:20:00 INFO - PROCESS | 1643 | [#3] mootools.html Cycles:180 Average:5365.26 Median:5175.89 stddev:2932.40 (56.7%) stddev-sans-first:2925.55 09:20:00 INFO - PROCESS | 1643 | Values: 1408.9 1450.7 1452.4 1827.6 1858.4 7473.1 7751.8 7960.9 8424.8 8426.2 7899.6 8178.1 8223.8 8257.4 8318.5 2275.3 2329.0 2331.2 2333.4 2337.6 1940.4 1941.4 2327.6 2337.1 2360.0 2225.8 2231.9 2235.2 2255.3 2260.7 2270.7 2293.4 2310.2 2311.6 2320.3 2313.2 2333.7 2341.8 2344.0 2344.8 8263.2 8296.4 8351.5 8391.4 8404.3 4998.6 5171.3 5172.1 5172.1 5179.7 2648.5 3250.6 3615.2 3619.5 3620.1 1046.3 1049.1 1068.9 1084.2 1086.5 10563.0 10609.0 10698.5 10709.3 10738.6 3724.0 4425.9 4488.0 4562.2 4575.7 9045.9 9228.1 9234.9 9238.1 9262.8 5125.8 5151.1 5158.3 5168.8 5183.1 4272.9 4418.8 4574.3 4697.1 4734.6 3708.6 3765.2 3767.0 3856.5 3868.5 1242.5 1244.8 1249.7 1250.1 1250.4 753.2 852.3 891.6 907.2 932.8 5135.6 5310.8 5359.8 5558.3 5582.8 891.2 895.9 911.9 943.7 971.6 3677.4 3687.4 3811.6 3872.7 4012.4 9242.1 9436.1 9600.0 10034.7 10045.1 9800.7 9875.4 9886.8 9907.9 9983.9 7583.8 7637.4 7720.0 7845.1 7882.8 5409.5 5440.6 5514.7 5619.9 5662.3 9112.0 9201.6 9272.1 9275.5 9335.4 9343.2 9408.2 9468.2 9480.6 9595.5 6566.7 6795.1 7001.6 7030.6 7243.8 7747.7 7916.3 7918.9 7954.7 7966.4 6975.7 7057.0 7113.1 7156.7 7207.5 6174.4 6310.9 6658.4 6887.6 6999.9 6476.5 6567.0 6656.1 6661.2 6678.3 3903.5 4768.9 4790.2 4807.5 4894.5 4675.6 4767.7 4846.4 4852.9 5037.6 09:20:00 INFO - PROCESS | 1643 | 09:20:00 INFO - PROCESS | 1643 | [#4] prototype.html Cycles:180 Average:4563.65 Median:5104.22 stddev:1413.53 (27.7%) stddev-sans-first:1381.72 09:20:00 INFO - PROCESS | 1643 | Values: 353.4 353.7 359.0 365.1 368.1 5371.3 5371.4 5391.4 5487.9 5515.3 4578.0 5174.6 5354.7 5423.8 5475.7 3813.1 4074.9 4221.2 4305.8 4313.9 4186.0 4206.5 4259.5 4264.3 4268.7 3292.8 4199.4 4218.2 4221.5 4264.3 3715.9 4235.5 4246.9 4247.7 4262.4 3101.6 3307.9 3459.1 3542.8 3763.9 4208.5 4385.0 5117.7 5283.6 5321.2 3941.6 4680.5 4696.2 4715.6 4728.6 5349.5 5640.5 5705.3 5736.5 5766.7 3432.5 3518.2 3636.0 3893.0 3895.7 5336.7 5784.6 6079.2 6093.8 6106.2 3706.9 3907.8 3945.8 3946.2 3954.6 3582.1 3848.4 4021.0 4021.8 4042.8 4491.6 4702.9 4721.6 4725.7 4729.6 5281.5 5809.4 5858.2 5866.2 5910.5 1094.2 1145.1 1146.5 1266.2 1301.4 748.6 770.3 815.7 862.3 878.5 4748.6 5068.8 5220.5 5230.4 5259.6 5661.9 5670.3 5679.6 5694.7 5697.7 4665.4 4772.9 5054.9 5239.8 5263.8 2376.6 2650.9 2972.6 2973.6 3008.3 5320.7 5691.6 5891.3 5951.5 5970.5 5101.2 5261.4 5358.1 5400.7 5850.6 4910.5 5025.3 5846.1 5863.9 5891.0 4684.8 4969.5 5018.9 5305.5 5773.8 4868.8 5288.4 5482.6 5579.2 5677.8 5337.0 5884.9 5937.6 5969.5 5985.9 3633.5 3728.4 3736.4 3752.6 3753.1 4927.9 5463.3 5863.8 5876.8 5885.5 5075.7 5618.6 5779.9 5786.3 5840.9 4620.5 5185.7 5745.9 5816.7 5816.8 4914.9 5615.7 5673.6 5674.2 5740.4 4918.4 5107.2 5421.2 5607.7 5813.4 5057.3 5707.2 5707.9 5733.0 5753.4 09:20:00 INFO - PROCESS | 1643 | 09:20:00 INFO - PROCESS | 1643 | [#5] yui.html Cycles:180 Average:20464.63 Median:4342.76 stddev:29278.68 (674.2%) stddev-sans-first:29322.97 09:20:00 INFO - PROCESS | 1643 | Values: 637.3 732.9 746.5 763.2 819.1 14774.9 15169.2 15643.1 15666.9 15734.5 14061.4 14128.6 14190.0 14385.8 14404.3 876.8 892.1 893.5 893.5 893.6 698.0 698.1 720.0 884.3 895.3 941.8 943.9 948.1 948.6 948.9 937.7 938.7 940.2 941.0 941.3 685.1 696.9 697.3 699.5 702.0 14272.8 14375.0 14436.8 14672.0 15249.7 13866.0 14404.7 14411.1 14424.4 14442.4 11709.4 11752.6 11832.6 11833.5 12025.6 3384.5 3714.1 3722.7 3824.1 3893.0 96014.0 98573.0 107423.5 109851.1 112936.0 11115.0 11525.4 11535.2 11595.6 11755.8 23604.1 23823.0 23992.8 24046.0 24071.2 8180.5 8186.4 8226.7 8236.3 8336.1 4313.2 4372.3 4401.7 4473.0 4549.0 3621.8 3673.9 3717.3 3717.8 3720.9 619.3 684.6 717.2 718.3 728.2 590.9 606.8 664.4 664.6 675.8 7220.1 8977.7 9435.5 9457.6 9673.6 451.4 506.9 526.7 608.0 653.9 3884.3 4047.8 4246.5 4253.1 4308.1 71285.3 82595.6 101070.0 109253.5 113729.0 84429.3 91985.5 94370.5 97078.5 99252.0 62570.7 81706.0 81892.0 81935.0 82937.8 8472.4 8513.5 8549.7 8638.8 8805.3 51840.7 52668.5 53329.0 55186.2 55568.6 50744.7 60510.7 62237.7 72195.9 77896.2 13053.3 13155.8 13172.4 13198.7 13681.9 14618.1 14674.2 14695.4 15075.9 15413.2 13805.6 13896.7 13973.8 14000.6 14104.7 13691.5 13827.3 13882.8 14463.5 14602.6 13393.1 13517.5 13520.0 13598.8 13723.4 10060.7 10522.8 11165.2 11368.3 11368.9 9419.3 11219.7 11535.8 11543.5 11568.4 09:20:00 INFO - PROCESS | 1643 | -------- Summary: end -------- 09:20:00 INFO - PROCESS | 1643 | 09:20:01 INFO - TEST-INFO | 1643: exit 0 09:20:01 INFO - TEST-OK | dromaeo_css | took 1116195ms 09:20:01 INFO - TEST-START | kraken 09:20:01 INFO - operating with platform_type : mac_ 09:20:01 INFO - Initialising browser for kraken test... 09:20:01 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/tmpGrvbGk/profile http://localhost:49242/getInfo.html) 09:20:04 INFO - PROCESS | 1655 | 2016-12-14 09:20:04.413 plugin-container[1656:10180] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 09:20:04 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:20:04 INFO - PROCESS | 1655 | 2016-12-14 09:20:04.415 plugin-container[1656:10180] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 09:20:04 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:20:04 INFO - PROCESS | 1655 | 2016-12-14 09:20:04.416 plugin-container[1656:10180] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:20:04 INFO - PROCESS | 1655 | __metrics Screen width/height:1600/1200 09:20:04 INFO - PROCESS | 1655 | colorDepth:24 09:20:04 INFO - PROCESS | 1655 | Browser inner width/height: 1024/689 09:20:04 INFO - PROCESS | 1655 | __metrics 09:20:04 ERROR - PROCESS | 1655 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3238: TypeError: this.tabs is undefined 09:20:05 INFO - TEST-INFO | 1655: exit 0 09:20:05 INFO - Browser initialized. 09:20:05 INFO - Running cycle 1/1 for kraken test... 09:20:05 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/tmpGrvbGk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:20:07 INFO - PROCESS | 1657 | 2016-12-14 09:20:07.420 plugin-container[1658:10299] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a4f, name = 'com.apple.tsm.portname' 09:20:07 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:20:07 INFO - PROCESS | 1657 | 2016-12-14 09:20:07.421 plugin-container[1658:10299] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9013, name = 'com.apple.CFPasteboardClient' 09:20:07 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:20:07 INFO - PROCESS | 1657 | 2016-12-14 09:20:07.421 plugin-container[1658:10299] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:20:17 INFO - PROCESS | 1657 | RSS: Main: 236969984 09:20:17 INFO - PROCESS | 1657 | 09:20:20 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection) 09:20:21 INFO - PROCESS | 1657 | RSS: Main: 249548800 09:20:21 INFO - PROCESS | 1657 | 09:20:23 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-dft) 09:20:24 INFO - PROCESS | 1657 | RSS: Main: 257069056 09:20:24 INFO - PROCESS | 1657 | 09:20:27 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-fft) 09:20:28 INFO - PROCESS | 1657 | RSS: Main: 259686400 09:20:28 INFO - PROCESS | 1657 | 09:20:29 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator) 09:20:30 INFO - PROCESS | 1657 | RSS: Main: 262205440 09:20:30 INFO - PROCESS | 1657 | 09:20:32 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:20:33 INFO - PROCESS | 1657 | RSS: Main: 262287360 09:20:33 INFO - PROCESS | 1657 | 09:20:36 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom) 09:20:37 INFO - PROCESS | 1657 | RSS: Main: 268865536 09:20:37 INFO - PROCESS | 1657 | 09:20:40 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate) 09:20:40 INFO - PROCESS | 1657 | RSS: Main: 267804672 09:20:40 INFO - PROCESS | 1657 | 09:20:43 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial) 09:20:44 INFO - PROCESS | 1657 | RSS: Main: 267816960 09:20:44 INFO - PROCESS | 1657 | 09:20:45 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:20:46 INFO - PROCESS | 1657 | RSS: Main: 267026432 09:20:46 INFO - PROCESS | 1657 | 09:20:48 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:20:49 INFO - PROCESS | 1657 | RSS: Main: 267284480 09:20:49 INFO - PROCESS | 1657 | 09:20:51 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:20:51 INFO - PROCESS | 1657 | RSS: Main: 267173888 09:20:51 INFO - PROCESS | 1657 | 09:20:55 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:20:55 INFO - PROCESS | 1657 | RSS: Main: 267345920 09:20:55 INFO - PROCESS | 1657 | 09:20:58 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:20:59 INFO - PROCESS | 1657 | RSS: Main: 267440128 09:20:59 INFO - PROCESS | 1657 | 09:21:01 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49242/tests/kraken/driver.html?testName=ai-astar) 09:21:01 INFO - PROCESS | 1657 | RSS: Main: 268062720 09:21:01 INFO - PROCESS | 1657 | 09:21:01 INFO - PROCESS | 1657 | __start_tp_report 09:21:01 INFO - PROCESS | 1657 | _x_x_mozilla_page_load 09:21:01 INFO - PROCESS | 1657 | _x_x_mozilla_page_load_details 09:21:01 INFO - PROCESS | 1657 | |i|pagename|runs| 09:21:01 INFO - PROCESS | 1657 | |0;ai-astar;107;117;116;117;114;117;116;117;116;118 09:21:01 INFO - PROCESS | 1657 | |1;audio-beat-detection;146;139;119;152;116;111;116;118;134;115 09:21:01 INFO - PROCESS | 1657 | |2;audio-dft;194;196;204;232;183;194;195;199;195;192 09:21:01 INFO - PROCESS | 1657 | |3;audio-fft;77;58;60;84;59;62;60;60;72;59 09:21:01 INFO - PROCESS | 1657 | |4;audio-oscillator;90;72;85;72;71;71;115;105;85;82 09:21:01 INFO - PROCESS | 1657 | |5;imaging-gaussian-blur;111;113;110;171;111;170;149;118;112;112 09:21:01 INFO - PROCESS | 1657 | |6;imaging-darkroom;129;99;99;100;99;115;116;95;97;150 09:21:01 INFO - PROCESS | 1657 | |7;imaging-desaturate;93;94;92;95;85;91;99;93;103;84 09:21:01 INFO - PROCESS | 1657 | |8;json-parse-financial;57;57;57;59;67;64;60;59;58;59 09:21:01 INFO - PROCESS | 1657 | |9;json-stringify-tinderbox;60;89;78;77;69;71;70;71;75;70 09:21:01 INFO - PROCESS | 1657 | |10;stanford-crypto-aes;89;88;94;93;90;89;92;90;95;109 09:21:01 INFO - PROCESS | 1657 | |11;stanford-crypto-ccm;148;203;166;164;159;142;150;145;247;161 09:21:01 INFO - PROCESS | 1657 | |12;stanford-crypto-pbkdf2;178;184;177;175;180;181;181;191;176;183 09:21:01 INFO - PROCESS | 1657 | |13;stanford-crypto-sha256-iterative;73;84;74;69;65;67;73;87;69;67 09:21:01 INFO - PROCESS | 1657 | __end_tp_report 09:21:01 INFO - PROCESS | 1657 | __start_cc_report 09:21:01 INFO - PROCESS | 1657 | _x_x_mozilla_cycle_collect,936 09:21:01 INFO - PROCESS | 1657 | __end_cc_report 09:21:01 INFO - PROCESS | 1657 | __startTimestamp1481736061526__endTimestamp 09:21:01 INFO - PROCESS | 1657 | 09:21:01 INFO - PROCESS | 1657 | ------- Summary: start ------- 09:21:01 INFO - PROCESS | 1657 | Number of tests: 14 09:21:01 INFO - PROCESS | 1657 | 09:21:01 INFO - PROCESS | 1657 | [#0] ai-astar Cycles:10 Average:115.50 Median:117.00 stddev:3.17 (2.7%) stddev-sans-first:1.13 09:21:01 INFO - PROCESS | 1657 | Values: 107.0 117.0 116.0 117.0 114.0 117.0 116.0 117.0 116.0 118.0 09:21:01 INFO - PROCESS | 1657 | 09:21:01 INFO - PROCESS | 1657 | [#1] audio-beat-detection Cycles:10 Average:126.60 Median:126.50 stddev:14.77 (11.7%) stddev-sans-first:13.90 09:21:01 INFO - PROCESS | 1657 | Values: 146.0 139.0 119.0 152.0 116.0 111.0 116.0 118.0 134.0 115.0 09:21:01 INFO - PROCESS | 1657 | 09:21:01 INFO - PROCESS | 1657 | [#2] audio-dft Cycles:10 Average:198.40 Median:195.50 stddev:12.94 (6.6%) stddev-sans-first:13.62 09:21:01 INFO - PROCESS | 1657 | Values: 194.0 196.0 204.0 232.0 183.0 194.0 195.0 199.0 195.0 192.0 09:21:01 INFO - PROCESS | 1657 | 09:21:01 INFO - PROCESS | 1657 | [#3] audio-fft Cycles:10 Average:65.10 Median:61.00 stddev:9.18 (15.1%) stddev-sans-first:8.67 09:21:01 INFO - PROCESS | 1657 | Values: 77.0 58.0 60.0 84.0 59.0 62.0 60.0 60.0 72.0 59.0 09:21:01 INFO - PROCESS | 1657 | 09:21:01 INFO - PROCESS | 1657 | [#4] audio-oscillator Cycles:10 Average:84.80 Median:77.00 stddev:15.14 (19.7%) stddev-sans-first:15.94 09:21:01 INFO - PROCESS | 1657 | Values: 90.0 72.0 85.0 72.0 71.0 71.0 115.0 105.0 85.0 82.0 09:21:01 INFO - PROCESS | 1657 | 09:21:01 INFO - PROCESS | 1657 | [#5] imaging-gaussian-blur Cycles:10 Average:127.70 Median:115.50 stddev:25.37 (22.0%) stddev-sans-first:26.18 09:21:01 INFO - PROCESS | 1657 | Values: 111.0 113.0 110.0 171.0 111.0 170.0 149.0 118.0 112.0 112.0 09:21:01 INFO - PROCESS | 1657 | 09:21:01 INFO - PROCESS | 1657 | [#6] imaging-darkroom Cycles:10 Average:109.90 Median:96.00 stddev:17.89 (18.6%) stddev-sans-first:17.58 09:21:01 INFO - PROCESS | 1657 | Values: 129.0 99.0 99.0 100.0 99.0 115.0 116.0 95.0 97.0 150.0 09:21:01 INFO - PROCESS | 1657 | 09:21:01 INFO - PROCESS | 1657 | [#7] imaging-desaturate Cycles:10 Average:92.90 Median:93.00 stddev:5.69 (6.1%) stddev-sans-first:6.03 09:21:01 INFO - PROCESS | 1657 | Values: 93.0 94.0 92.0 95.0 85.0 91.0 99.0 93.0 103.0 84.0 09:21:01 INFO - PROCESS | 1657 | 09:21:01 INFO - PROCESS | 1657 | [#8] json-parse-financial Cycles:10 Average:59.70 Median:59.00 stddev:3.30 (5.6%) stddev-sans-first:3.35 09:21:01 INFO - PROCESS | 1657 | Values: 57.0 57.0 57.0 59.0 67.0 64.0 60.0 59.0 58.0 59.0 09:21:01 INFO - PROCESS | 1657 | 09:21:01 INFO - PROCESS | 1657 | [#9] json-stringify-tinderbox Cycles:10 Average:73.00 Median:73.00 stddev:7.54 (10.3%) stddev-sans-first:6.37 09:21:01 INFO - PROCESS | 1657 | Values: 60.0 89.0 78.0 77.0 69.0 71.0 70.0 71.0 75.0 70.0 09:21:01 INFO - PROCESS | 1657 | 09:21:01 INFO - PROCESS | 1657 | [#10] stanford-crypto-aes Cycles:10 Average:92.90 Median:91.00 stddev:6.12 (6.7%) stddev-sans-first:6.32 09:21:01 INFO - PROCESS | 1657 | Values: 89.0 88.0 94.0 93.0 90.0 89.0 92.0 90.0 95.0 109.0 09:21:01 INFO - PROCESS | 1657 | 09:21:01 INFO - PROCESS | 1657 | [#11] stanford-crypto-ccm Cycles:10 Average:168.50 Median:162.50 stddev:32.56 (20.0%) stddev-sans-first:33.68 09:21:01 INFO - PROCESS | 1657 | Values: 148.0 203.0 166.0 164.0 159.0 142.0 150.0 145.0 247.0 161.0 09:21:01 INFO - PROCESS | 1657 | 09:21:01 INFO - PROCESS | 1657 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.60 Median:181.00 stddev:4.70 (2.6%) stddev-sans-first:4.88 09:21:01 INFO - PROCESS | 1657 | Values: 178.0 184.0 177.0 175.0 180.0 181.0 181.0 191.0 176.0 183.0 09:21:01 INFO - PROCESS | 1657 | 09:21:01 INFO - PROCESS | 1657 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.80 Median:73.00 stddev:7.35 (10.1%) stddev-sans-first:7.79 09:21:01 INFO - PROCESS | 1657 | Values: 73.0 84.0 74.0 69.0 65.0 67.0 73.0 87.0 69.0 67.0 09:21:01 INFO - PROCESS | 1657 | -------- Summary: end -------- 09:21:01 INFO - PROCESS | 1657 | 09:21:02 INFO - TEST-INFO | 1657: exit 0 09:21:02 INFO - TEST-OK | kraken | took 61018ms 09:21:02 INFO - SUITE-END | took 1177s 09:21:02 INFO - Completed test suite (00:19:37) 09:21:02 INFO - javascript benchmark 09:21:02 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7185.444438340448, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2770.279727270909, 2802.1033269353816, 2815.155453363991, 3366.9831650841743, 3405.8467368968395, 4918.163912134936, 5522.944770552298, 5604.551635869131, 5694.5729070319685, 5708.372079071299, 5069.062223488657, 5217.399999000116, 5633.661980281186, 5659.6321239119425, 5713.400092990239, 5093.617978651598, 5165.431802501722, 5172.379314482248, 5229.86925326868, 5279.472052794709, 5193.246979188034, 5220.086484865153, 5225.294585231002, 5258.50044245796, 5306.416294207634, 5125.154349532323, 5188.221766734982, 5205.791768329263, 5216.00895829793, 5230.450802665722, 5135.255387968761, 5192.896142077175, 5193.9740301298625, 5201.765920533585, 5203.4016088149965, 5048.570871475929, 5100.872478188038, 5115.181570948667, 5153.407358153823, 5252.212168174766, 5453.945460545384, 5622.353429355636, 5628.802991895305, 5691.0, 5705.0, 6115.510759139258, 6188.47397971173, 6201.069839524062, 6201.131841542187, 6201.379862013762, 5078.695278283278, 6111.174991376151, 6147.231596050494, 6184.257889053298, 6199.752009919598, 5752.913706294367, 5799.130130480419, 5817.825465236049, 5825.533957283407, 5834.941650583482, 6225.1907252057845, 6239.376062393815, 6241.812745617639, 6268.592541484789, 6277.905831412532, 5389.299391079135, 5620.943790562042, 6012.458878700938, 6075.605085669417, 6134.294556126102, 6151.6924153793025, 6158.199434073542, 6166.691665416712, 6171.6297022178815, 6171.907421388682, 6108.5723999319625, 6118.223266370716, 6123.112148738497, 6124.295705993779, 6151.169592105009, 5425.864353391118, 5911.581220507133, 6141.477974372139, 6143.52555386704, 6208.348123447064, 4902.7058376497525, 4907.362042934467, 4910.140725373046, 4922.212446008622, 4939.901201976012, 4331.848385306562, 4353.172897149531, 4367.497737760134, 4408.228560001987, 4431.800568974404, 4902.1911384621135, 5182.8963420732125, 5231.947680523146, 5414.242006119146, 5932.3177834548715, 5054.570361519239, 5448.482394172547, 6193.876122477525, 6213.285472170668, 6243.937560624336, 5685.260916080883, 5720.856978575569, 5720.914186287126, 5870.354261031283, 5917.911231331534, 3993.4608827808456, 5336.092864213093, 5337.959097975858, 5342.332208473941, 5348.037353276448, 5102.209157580581, 6165.0444181152, 6191.628502289877, 6230.252369715663, 6237.532185086136, 5393.191021346673, 6150.200473938359, 6215.65813880241, 6220.409061139185, 6252.562320637512, 5301.0, 5648.293963254593, 6244.937550624436, 6246.063090536456, 6266.373362663697, 6056.1824153740445, 6104.511639068775, 6108.694565271808, 6130.7241174146375, 6137.447629713347, 4696.38946936896, 6041.305249896318, 6188.504919606331, 6212.223472065992, 6232.220972378453, 6065.59585647361, 6075.544334174866, 6081.6351018939, 6086.421789929949, 6140.5701600887505, 5897.528197744256, 5950.2264705587995, 6072.119542666201, 6073.362296958932, 6111.8777624446375, 5732.05421105513, 5826.592138550261, 5832.475077243069, 5845.620034697731, 5870.207521984477, 5991.2510936132985, 6027.4876635484725, 6055.6063855849225, 6068.787592434243, 6079.665618390854, 5923.644581325134, 5938.792142275, 5949.0481522956115, 5954.612950158225, 5961.850953726192], "value": 5562.107545261788, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [430.3050573324082, 454.7158026233602, 497.81829632184264, 498.76059491444107, 510.6731691717299, 32665.0, 40666.39000414996, 40737.99999999996, 40927.59072409275, 40940.38589421161, 41727.0, 42217.57782422175, 42358.36462453058, 42380.57619423805, 42446.57553424465, 8513.148685131468, 9207.677731279407, 9463.81072378552, 9484.241260699127, 9560.56977436017, 8612.354073444485, 11230.775384492305, 11489.942550287236, 11508.0, 11660.416979151008, 11399.829002564968, 11419.600313989013, 11450.427478626078, 11473.713157171054, 11485.942570287138, 10703.1437485001, 11300.660980170607, 11342.886571134306, 11366.147538934612, 11370.545178192862, 11289.322640641587, 11317.377544235063, 11373.0, 11392.25950313227, 11394.0, 39707.8014609928, 39820.80089599563, 40045.79977100125, 40056.59943400558, 40422.797886010674, 3370.1574606348413, 3436.1581412553833, 3474.3398754236614, 3883.864064757748, 4044.3509501276417, 30091.548626770615, 30823.38353232923, 31138.910138145282, 31563.52654710181, 31637.20906977321, 3156.952645710316, 3235.352929414103, 3237.676232376767, 3238.3199528099126, 3294.901152965391, 445281.7735911332, 449360.0, 450337.0, 452072.0, 453212.0, 49523.0, 62331.06503402453, 63366.0, 63424.0, 63453.0, 65060.674696626214, 65492.0, 65599.0, 65631.0, 65681.0, 14751.926240368944, 14971.176585287914, 15625.218739063, 16257.51227463178, 16265.67468650644, 10338.793224135476, 11124.110071194285, 11412.771744565229, 11688.824667629993, 11716.355600442057, 17991.640167196583, 18361.7245741314, 18394.0, 18686.34597789071, 18876.150573224237, 5599.7480113394995, 6684.331566843277, 6710.899336509956, 6742.9662851685425, 6753.628550429676, 862.6161358195668, 949.212153912265, 953.7329547726648, 990.8166989107038, 991.7371896447447, 29201.85399072991, 29792.553111703342, 30106.698933010826, 30653.080407587808, 31019.84490077535, 1030.531108345716, 1041.7499800048085, 1047.5914393386586, 1057.0856209378985, 1132.6262333429947, 15269.770953435707, 15767.29047192879, 15803.920980395025, 15892.56966872987, 16071.084021432152, 621971.0, 626355.0, 630305.0, 631788.0, 632348.0, 60554.0, 60569.0, 60571.0, 60740.39259607436, 60816.69591652102, 1363.9454421823214, 1375.917444953306, 1394.072941493895, 1394.6931675031476, 1401.0, 28746.568801468413, 29649.5552566716, 29923.850380747957, 30263.697363026087, 30586.0, 52485.950280993406, 52631.21053184282, 52668.47331526789, 52774.73612631912, 52791.944161115796, 577233.0, 579229.0, 579276.0, 580295.7881684685, 580804.0, 24506.877465612557, 24703.60632967948, 25450.0, 41925.580744192164, 42001.78999104985, 42427.1514569713, 43004.784976074916, 43254.783726081165, 43399.783001086056, 43732.56267437285, 34425.65574344224, 35522.0, 35772.46341304934, 36611.26777464489, 36731.0, 30331.0, 35488.46767298544, 36066.639333607374, 36303.0, 36620.81689591535, 34907.65092349044, 35209.47185792164, 35529.46705799467, 35863.56545738141, 36246.818765906, 26144.215673529823, 30840.845795770878, 32034.359312813147, 32192.83903580561, 32830.22452418003, 20517.58964820725, 23882.880585596962, 24065.18841028358, 24154.03383864696, 24633.38416539529], "value": 19968.991224907717, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [368.20467789574525, 373.3485068555371, 376.279424901314, 385.06813511302647, 400.5133762478588, 18934.90532547337, 19916.20335186591, 20090.296839610615, 20756.0, 20823.479413014684, 19926.601467970668, 20207.49481262969, 20215.59568808623, 20496.38510844673, 20721.06755196016, 5003.874903127415, 5753.309602847654, 6029.216201893748, 6047.7278522466595, 6063.969680151593, 6002.189704389895, 6008.849778755544, 6033.969830150843, 6069.241344831896, 6090.056041313604, 5818.621789583684, 5948.375420580842, 5969.373215812342, 5978.760849566012, 6015.0676645120075, 4701.412323459567, 5035.54680078791, 5233.21501774733, 5897.911531327012, 5940.673262970535, 4659.254519276899, 4934.0, 5340.198970154469, 5378.435264297271, 5415.566754659618, 12899.484020639164, 13160.407781649848, 15111.848881511154, 24305.513889722282, 24708.0, 2123.3948324727535, 2171.9239826605994, 2194.0675213034556, 2279.5213005268915, 2485.5028994201234, 10009.218234262069, 10082.24383171265, 10216.92722264159, 10252.794944101077, 11684.0, 1752.2027477497836, 1774.9378771742927, 1800.675878341898, 1816.4535858784388, 1841.558026073746, 89629.55185224097, 96366.0, 113733.43133284364, 122480.38759806233, 123762.38118809438, 8933.508657023796, 11313.71715707114, 11336.6032188873, 11353.54585816558, 11485.368304743319, 14504.274786260645, 14513.492027779188, 14514.129152250687, 14524.419023239163, 14526.564203073925, 8062.637181326737, 8317.708880189164, 8381.790455238546, 8422.705205317785, 8500.914990850137, 8189.959050204711, 8196.877046844302, 8217.465864718773, 8349.457285276438, 8386.245237928615, 3469.826508674607, 4485.573870482299, 4499.21263778844, 4522.3668686383935, 4589.311603259538, 1637.1814092953523, 1761.7755659816305, 1811.2483319422556, 1866.0576408913412, 1872.288530358455, 673.1551902362504, 889.5063239901916, 895.8297923394534, 954.6181527388961, 955.5986485675894, 19792.90103549473, 20523.179072837218, 21340.359789206348, 21752.0, 21776.020079096477, 904.1952662130709, 910.321810251367, 944.2870632672289, 995.571904081252, 995.5868314649484, 8097.878531822027, 8140.267375936194, 8392.244697977212, 8547.401681882333, 11043.503042363112, 572090.0, 583556.0, 590333.0, 593285.0, 596854.0, 112257.0, 121149.0, 132572.33713831368, 133963.0, 133970.0, 932.8274269260208, 980.1913421427352, 1046.9005444482762, 1051.9631812886512, 1052.1319911073394, 19782.90108549448, 20090.59818803645, 20264.696029559862, 20343.4914127148, 20350.79649203489, 37474.063148421505, 38416.6158338413, 44959.32561011653, 45294.14410137807, 53432.0, 62592.06111908416, 82392.3521529578, 82444.58777706073, 83441.74837377323, 85102.57448712717, 6954.3393377629045, 7290.526115802456, 7386.187519372757, 7494.775156745306, 8144.75565733029, 11432.142589306002, 12737.426815793497, 12781.936090319488, 13249.205047697169, 13381.0, 10781.652293463318, 11237.55049797999, 11245.831312530483, 11311.981921627093, 11339.0, 10903.0, 10987.670369888916, 11272.0, 11353.0, 11369.090472761995, 9993.350432221881, 11189.216754827085, 11255.831162532404, 11500.94249528747, 11715.472803724024, 7385.446091543263, 7439.590822504814, 7547.509411888429, 7550.35821955118, 7555.0, 9099.408538444979, 11141.442927853413, 11377.715557111138, 11491.138164637518, 11538.59614913474], "value": 9227.49575747039, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1408.9084209526382, 1450.6808502129531, 1452.3827373366323, 1827.5613852675363, 1858.4053103007034, 7473.103227612681, 7751.806204844867, 7960.92039079609, 8424.789380265496, 8426.157384261578, 7899.64451599678, 8178.141295164011, 8223.753287401387, 8257.42198046137, 8318.542480163589, 2275.3174047785683, 2329.04509151248, 2331.230693871027, 2333.3583264602285, 2337.614293641556, 1940.41787463761, 1941.4272789527054, 2327.55776402483, 2337.0651739304244, 2359.9528009439887, 2225.8330625203093, 2231.866088034715, 2235.228846048111, 2255.3121298004185, 2260.6835043093897, 2270.6821045053707, 2293.3578597992587, 2310.1798861404213, 2311.6185829338137, 2320.3061764911627, 2313.2366319114653, 2333.719953605578, 2341.7541158178315, 2344.0037983856964, 2344.788968992799, 8263.214994575506, 8296.419250652456, 8351.457155284948, 8391.412601117925, 8404.327653787683, 4998.550130488237, 5171.301874247004, 5172.069027575035, 5172.094883395391, 5179.689218646893, 2648.496785610728, 3250.6099268087983, 3615.1865830188262, 3619.4570814378053, 3620.131168519563, 1046.2832959422744, 1049.0610903241557, 1068.8557044798931, 1084.1706094837455, 1086.462201210406, 10563.0, 10608.992145746142, 10698.46507674621, 10709.303895246861, 10738.570457181782, 3723.9813800931365, 4425.867223983286, 4488.0, 4562.1560011398, 4575.679702420799, 9045.864312035324, 9228.123328283802, 9234.86147707785, 9238.122378374044, 9262.814743705088, 5125.769340379692, 5151.072806894795, 5158.329417175743, 5168.793248270103, 5183.14478111115, 4272.87181384559, 4418.8453404130705, 4574.268117101248, 4697.060587882437, 4734.644901632391, 3708.647678470541, 3765.1904840459433, 3767.020574650556, 3856.4986551748652, 3868.5357757069332, 1242.5340497313507, 1244.8132780082879, 1249.7375551134364, 1250.0999280518013, 1250.4182209504984, 753.1602263476185, 852.3138873207004, 891.5943245823134, 907.2016625369764, 932.8274269260208, 5135.589152867761, 5310.7610157543, 5359.839204823784, 5558.277423934863, 5582.8325150245555, 891.2246345679171, 895.8835351404276, 911.8632205169145, 943.6791490893129, 971.6162115964155, 3677.3932301170544, 3687.391580389206, 3811.5807261201244, 3872.6708229800784, 4012.3981402789814, 9242.075792420705, 9436.056394360508, 9599.952000239955, 10034.69895903124, 10045.146162576117, 9800.705978820646, 9875.358101723365, 9886.802263954825, 9907.900920990698, 9983.850242246372, 7583.848323033509, 7637.389008879276, 7719.996400467903, 7845.0978137513775, 7882.817577363441, 5409.4590540947165, 5440.646357986719, 5514.696691681996, 5619.93221287954, 5662.292213473316, 9111.95444022802, 9201.585928633094, 9272.119148680864, 9275.489848058422, 9335.439873607605, 9343.205827504466, 9408.247340212629, 9468.195203407788, 9480.57337419804, 9595.520223988913, 6566.704498297684, 6795.116634837439, 7001.614911179934, 7030.613316267654, 7243.818904527429, 7747.690092396467, 7916.327112195528, 7918.9208107920485, 7954.721584744506, 7966.442349035512, 6975.720971161097, 7057.0, 7113.146422429341, 7156.749513766993, 7207.495475316888, 6174.38256174379, 6310.936890631218, 6658.3674550917585, 6887.621180835102, 6999.930000699927, 6476.481881449567, 6567.0, 6656.101426307387, 6661.16735408074, 6678.332166783282, 3903.4925459691194, 4768.856934291976, 4790.233562629963, 4807.471178170468, 4894.535019173173, 4675.602573781267, 4767.688885556499, 4846.36997190363, 4852.878678033077, 5037.622178336566], "value": 4358.650720283796, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [353.3621812628205, 353.7488383247894, 358.97666651667646, 365.07271530313005, 368.1275377355666, 5371.301730774994, 5371.355437347524, 5391.433899440556, 5487.862803429916, 5515.338159420875, 4578.0, 5174.628723388296, 5354.72424219707, 5423.755930983105, 5475.67145971242, 3813.1229817142075, 4074.9185016299657, 4221.24017676818, 4305.806238719265, 4313.870583882473, 4186.0, 4206.47419072616, 4259.531451540328, 4264.317709166534, 4268.701190916637, 3292.826734355326, 4199.4120823084795, 4218.219629368561, 4221.514525829522, 4264.33902745075, 3715.8852344296874, 4235.470566179228, 4246.893827654302, 4247.745135291892, 4262.424572682695, 3101.596792416994, 3307.851146698404, 3459.08334291413, 3542.8051457169845, 3763.8870833875026, 4208.473940757405, 4384.956150438518, 5117.744112794346, 5283.63014588979, 5321.201819727034, 3941.5861334559745, 4680.531946805327, 4696.178168820443, 4715.575598196178, 4728.621710263171, 5349.518543331119, 5640.520555752766, 5705.258316418881, 5736.451158187308, 5766.711664416763, 3432.5366075579727, 3518.1881331114496, 3636.0, 3893.0462036801164, 3895.7078219133396, 5336.665833541615, 5784.623999439981, 6079.240094988127, 6093.817185484442, 6106.2061931948565, 3706.8694048315524, 3907.8046097695, 3945.8421663133154, 3946.165033259548, 3954.584768599313, 3582.0865679251624, 3848.441975913533, 4020.979895100506, 4021.813564998274, 4042.8382864685673, 4491.640668746492, 4702.882427939329, 4721.591988086101, 4725.669203155746, 4729.55069268419, 5281.498257665516, 5809.3609702932645, 5858.238429004202, 5866.2373891393845, 5910.527157827363, 1094.2449709700281, 1145.118258940611, 1146.4580139712075, 1266.1769849597836, 1301.44688507384, 748.5957582905279, 770.2836362183224, 815.7185770909027, 862.2929198057658, 878.4597472554333, 4748.572628463386, 5068.797248110108, 5220.530152286236, 5230.424653288135, 5259.552938000236, 5661.915071273934, 5670.291213598301, 5679.630823996427, 5694.658320500783, 5697.715114244271, 4665.390440298092, 4772.904541909212, 5054.949450505522, 5239.8166064188335, 5263.7894484220205, 2376.6078597031296, 2650.8807103679997, 2972.6135602371555, 2973.598564193806, 3008.293051132982, 5320.707361095177, 5691.573132015115, 5891.263592050994, 5951.494122999594, 5970.462553414782, 5101.234814777738, 5261.3686357637325, 5358.132844535227, 5400.702961337164, 5850.619709718854, 4910.533499317559, 5025.346704928336, 5846.123081537803, 5863.882722345615, 5890.998530249868, 4684.812607495662, 4969.540077661581, 5018.90958868521, 5305.471861212251, 5773.758641892014, 4868.812820973889, 5288.444713305047, 5482.588805839658, 5579.218909352613, 5677.801276955287, 5336.973315133399, 5884.911726324023, 5937.614055086406, 5969.492593129459, 5985.880282394241, 3633.454981752758, 3728.422094575345, 3736.439534069911, 3752.6059763724415, 3753.0617345663586, 4927.926081108714, 5463.31708536433, 5863.801074597026, 5876.823695289148, 5885.527538752157, 5075.720835353944, 5618.550515958796, 5779.8555036124435, 5786.276715410574, 5840.912386314294, 4620.5148459411275, 5185.688858668492, 5745.897215503581, 5816.70916454184, 5816.796412125555, 4914.9017019658695, 5615.6630602164005, 5673.574481913791, 5674.233978413026, 5740.440926625519, 4918.4097908249805, 5107.208382700687, 5421.2293181999585, 5607.691576963306, 5813.401314638509, 5057.31726216956, 5707.20099186106, 5707.914381284367, 5733.0, 5753.424657534213], "value": 4114.291916252092, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [637.2926052082189, 732.868083744926, 746.5296862976325, 763.2215140556632, 819.0792591373231, 14774.852251477481, 15169.241537923115, 15643.139627320494, 15666.921665391686, 15734.52796416108, 14061.367238474268, 14128.646783830409, 14190.0, 14385.784213236784, 14404.27978601071, 876.7808047988003, 892.0633334998258, 893.4773157702751, 893.4907102951321, 893.5844832153048, 697.9720811167547, 698.12734082397, 719.9992011104569, 884.2704768565927, 895.3195571365776, 941.750436134425, 943.905609439054, 948.1466679987997, 948.6395169835453, 948.9051094890525, 937.7186843946823, 938.7277689470045, 940.2290122099881, 941.0, 941.2987324443334, 685.0648864300194, 696.9024336592881, 697.285282585349, 699.4579201119122, 701.982450438738, 14272.785908211385, 14375.0, 14436.783448248285, 14672.0, 15249.695006099926, 13865.960052996066, 14404.711905761826, 14411.063280886709, 14424.35090420923, 14442.422303107864, 11709.414529273503, 11752.58865939688, 11832.645020649394, 11833.526658933632, 12025.579104731292, 3384.4754063120254, 3714.0886239907118, 3722.7207959403154, 3824.082220267144, 3892.961070389288, 96014.0, 98573.0142698571, 107423.46288268587, 109851.07159461833, 112936.0, 11115.0, 11525.423728813526, 11535.192536522363, 11595.594154204562, 11755.824417558175, 23604.055837766296, 23823.04707811668, 23992.7600723994, 24046.0, 24071.157509487082, 8180.509169449852, 8186.426950113435, 8226.712065077694, 8236.299914507214, 8336.124706905808, 4313.180495705806, 4372.344148377769, 4401.713888597231, 4472.9776351118035, 4549.0, 3621.8370173342264, 3673.94489082664, 3717.2565486902727, 3717.8326975286177, 3720.8697695580527, 619.2909119058653, 684.6302996381997, 717.1967396515936, 718.3463048625725, 728.1771598094225, 590.8906852232351, 606.8391876152823, 664.4451882678018, 664.5746722097862, 675.8242856857253, 7220.141386580677, 8977.730668079968, 9435.481048542397, 9457.574409151604, 9673.613055477843, 451.40189249244884, 506.9366329208849, 526.6550409481796, 607.984800379994, 653.8659574787157, 3884.261990221849, 4047.817848196838, 4246.460038151445, 4253.085586598896, 4308.138372325548, 71285.28714712786, 82595.5870220645, 101069.97860042758, 109253.45373273082, 113729.0, 84429.31141377139, 91985.5400722992, 94370.5281473602, 97078.51460742792, 99252.0, 62570.68714656398, 81706.0, 81892.0, 81935.0, 82937.75593366104, 8472.44929079608, 8513.489190648581, 8549.658013679384, 8638.82722345537, 8805.295576354005, 51840.74079629578, 52668.473315266354, 53329.0, 55186.17220741772, 55568.61078472909, 50744.731381715756, 60510.69744651249, 62237.68881155565, 72195.91706124308, 77896.22103779117, 13053.34733263314, 13155.802662960255, 13172.40724167434, 13198.670033248862, 13681.931590342383, 14618.121548058098, 14674.160867582641, 14695.41218351254, 15075.924620377267, 15413.152276624893, 13805.585832425044, 13896.652583685489, 13973.834015779648, 14000.649983750487, 14104.717905641624, 13691.45234190661, 13827.316971734677, 13882.791758123838, 14463.49377771773, 14602.634934126732, 13393.062485625913, 13517.492464860776, 13520.0, 13598.796018059538, 13723.382447789674, 10060.748481287734, 10522.842487326236, 11165.218434709492, 11368.261063030877, 11368.886311136783, 9419.293552983416, 11219.71950701239, 11535.76928461443, 11543.538258469567, 11568.421578921188], "value": 7487.6898251581915, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1568.3999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 117.0, 116.0, 117.0, 114.0, 117.0, 116.0, 117.0, 116.0, 118.0], "value": 115.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 139.0, 119.0, 152.0, 116.0, 111.0, 116.0, 118.0, 134.0, 115.0], "value": 126.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 196.0, 204.0, 232.0, 183.0, 194.0, 195.0, 199.0, 195.0, 192.0], "value": 198.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 58.0, 60.0, 84.0, 59.0, 62.0, 60.0, 60.0, 72.0, 59.0], "value": 65.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 72.0, 85.0, 72.0, 71.0, 71.0, 115.0, 105.0, 85.0, 82.0], "value": 84.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 113.0, 110.0, 171.0, 111.0, 170.0, 149.0, 118.0, 112.0, 112.0], "value": 127.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 99.0, 99.0, 100.0, 99.0, 115.0, 116.0, 95.0, 97.0, 150.0], "value": 109.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 92.0, 95.0, 85.0, 91.0, 99.0, 93.0, 103.0, 84.0], "value": 92.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 57.0, 57.0, 59.0, 67.0, 64.0, 60.0, 59.0, 58.0, 59.0], "value": 59.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 89.0, 78.0, 77.0, 69.0, 71.0, 70.0, 71.0, 75.0, 70.0], "value": 73.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 88.0, 94.0, 93.0, 90.0, 89.0, 92.0, 90.0, 95.0, 109.0], "value": 92.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 203.0, 166.0, 164.0, 159.0, 142.0, 150.0, 145.0, 247.0, 161.0], "value": 168.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 184.0, 177.0, 175.0, 180.0, 181.0, 181.0, 191.0, 176.0, 183.0], "value": 180.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 84.0, 74.0, 69.0, 65.0, 67.0, 73.0, 87.0, 69.0, 67.0], "value": 72.8, "unit": "score"}]}]} 09:21:02 INFO - Return code: 0 09:21:02 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:21:02 ERROR - # TBPL SUCCESS # 09:21:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:21:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:21:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:21:02 INFO - 2016-12-14 09:21:02.873 screenresolution[1662:10682] starting screenresolution argv=screenresolution get 09:21:02 INFO - 2016-12-14 09:21:02.876 screenresolution[1662:10682] Display 0: 1600x1200x32@60 09:21:02 INFO - 2016-12-14 09:21:02.883 screenresolution[1663:10685] starting screenresolution argv=screenresolution list 09:21:02 INFO - Available Modes on Display 0 09:21:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:21:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:21:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:21:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:21:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:21:03 INFO - Intel Iris: 09:21:03 INFO - Chipset Model: Intel Iris 09:21:03 INFO - Type: GPU 09:21:03 INFO - Bus: Built-In 09:21:03 INFO - VRAM (Dynamic, Max): 1536 MB 09:21:03 INFO - Vendor: Intel (0x8086) 09:21:03 INFO - Device ID: 0x0a2e 09:21:03 INFO - Revision ID: 0x0009 09:21:03 INFO - Displays: 09:21:03 INFO - Display: 09:21:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:21:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:21:03 INFO - Display Serial Number: 1600x1200 60 09:21:03 INFO - Main Display: Yes 09:21:03 INFO - Mirror: Off 09:21:03 INFO - Online: Yes 09:21:03 INFO - Rotation: Supported 09:21:03 INFO - Return code: 0 09:21:03 INFO - Running post-action listener: _resource_record_post_action 09:21:03 INFO - [mozharness: 2016-12-14 17:21:03.124781Z] Finished run-tests step (success) 09:21:03 INFO - Running post-run listener: _resource_record_post_run 09:21:03 INFO - Total resource usage - Wall time: 1199s; CPU: 55.0%; Read bytes: 38607360; Write bytes: 550817280; Read time: 252; Write time: 1695 09:21:03 INFO - TinderboxPrint: CPU usage
54.7% 09:21:03 INFO - TinderboxPrint: I/O read bytes / time
38,607,360 / 252 09:21:03 INFO - TinderboxPrint: I/O write bytes / time
550,817,280 / 1,695 09:21:03 INFO - TinderboxPrint: CPU idle
2,174.7 (45.3%) 09:21:03 INFO - TinderboxPrint: CPU system
969.0 (20.2%) 09:21:03 INFO - TinderboxPrint: CPU user
1,653.9 (34.5%) 09:21:03 INFO - TinderboxPrint: Swap in / out
288,563,200 / 0 09:21:03 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 226589184; Write bytes: 219955200; Read time: 16362; Write time: 478 09:21:03 INFO - run-tests - Wall time: 1178s; CPU: 55.0%; Read bytes: 37673472; Write bytes: 300518912; Read time: 239; Write time: 1150 09:21:03 INFO - Running post-run listener: _upload_blobber_files 09:21:03 INFO - Blob upload gear active. 09:21:03 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:21:03 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:21:03 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:21:03 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:21:04 INFO - (blobuploader) - INFO - Open directory for files ... 09:21:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:21:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:21:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:21:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:21:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:21:04 INFO - (blobuploader) - INFO - Done attempting. 09:21:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:21:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:21:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:21:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:21:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:21:05 INFO - (blobuploader) - INFO - Done attempting. 09:21:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:21:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:21:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:21:08 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:21:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:21:08 INFO - (blobuploader) - INFO - Done attempting. 09:21:08 INFO - (blobuploader) - INFO - Iteration through files over. 09:21:08 INFO - Return code: 0 09:21:08 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:21:08 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:21:08 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c4219af0567c01e7b6fffe33265c1df7138c490bac3fb0687f5d4e00f895a393115b3a8689ff5f8efabd9c50a35c827c2c2b2215ed31691b488a890268db4c85", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2fe67e32628d67fb04bc8601b6ee705044b2089345194837b28ef0578bd1f409d67fc6ecbf79d2b6d51898e11fbe5386e7c01b4e8a7c019f7a8f30c88f70c256", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1180b00457c490aca16bf0291e7bcff77e8f53ce21c1c84490137e381101d7d53ef8c7045fe5a773cf985e730cbe3899dbdd392a508f3f10e6165efdc0982c21"} 09:21:08 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:21:08 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:21:08 INFO - Contents: 09:21:08 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c4219af0567c01e7b6fffe33265c1df7138c490bac3fb0687f5d4e00f895a393115b3a8689ff5f8efabd9c50a35c827c2c2b2215ed31691b488a890268db4c85", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2fe67e32628d67fb04bc8601b6ee705044b2089345194837b28ef0578bd1f409d67fc6ecbf79d2b6d51898e11fbe5386e7c01b4e8a7c019f7a8f30c88f70c256", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1180b00457c490aca16bf0291e7bcff77e8f53ce21c1c84490137e381101d7d53ef8c7045fe5a773cf985e730cbe3899dbdd392a508f3f10e6165efdc0982c21"} 09:21:08 INFO - Running post-run listener: copy_logs_to_upload_dir 09:21:08 INFO - Copying logs to upload dir... 09:21:08 INFO - mkdir: /builds/slave/test/build/upload/logs 09:21:08 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1261.224340 ========= master_lag: 0.48 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2016-12-14 09:21:08.198142) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-14 09:21:08.201631) ========= 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.LgiMgHN4OG/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.W1OiUomxMj/Listeners TMPDIR=/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c4219af0567c01e7b6fffe33265c1df7138c490bac3fb0687f5d4e00f895a393115b3a8689ff5f8efabd9c50a35c827c2c2b2215ed31691b488a890268db4c85", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2fe67e32628d67fb04bc8601b6ee705044b2089345194837b28ef0578bd1f409d67fc6ecbf79d2b6d51898e11fbe5386e7c01b4e8a7c019f7a8f30c88f70c256", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1180b00457c490aca16bf0291e7bcff77e8f53ce21c1c84490137e381101d7d53ef8c7045fe5a773cf985e730cbe3899dbdd392a508f3f10e6165efdc0982c21"} build_url:https://queue.taskcluster.net/v1/task/Vwyo-49SQiifCUX9dw0pjA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011146 build_url: 'https://queue.taskcluster.net/v1/task/Vwyo-49SQiifCUX9dw0pjA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c4219af0567c01e7b6fffe33265c1df7138c490bac3fb0687f5d4e00f895a393115b3a8689ff5f8efabd9c50a35c827c2c2b2215ed31691b488a890268db4c85", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2fe67e32628d67fb04bc8601b6ee705044b2089345194837b28ef0578bd1f409d67fc6ecbf79d2b6d51898e11fbe5386e7c01b4e8a7c019f7a8f30c88f70c256", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1180b00457c490aca16bf0291e7bcff77e8f53ce21c1c84490137e381101d7d53ef8c7045fe5a773cf985e730cbe3899dbdd392a508f3f10e6165efdc0982c21"}' ========= master_lag: 0.08 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-14 09:21:08.294451) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-14 09:21:08.294792) ========= 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.LgiMgHN4OG/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.W1OiUomxMj/Listeners TMPDIR=/var/folders/c3/8q485yn95ydb1p5724v877vr00000w/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.005150 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-14 09:21:08.322361) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-14 09:21:08.322710) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-14 09:21:08.323057) ========= ========= Total master_lag: 0.65 =========