builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0209 starttime: 1486735619.45 results: success (0) buildid: 20170210044822 builduid: eb1cd49071c44e0c9aa29d686de1ba80 revision: b83e2b2524c981eabae7c48f8ea6988544e5087a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-10 06:06:59.450636) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-10 06:06:59.451602) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-10 06:06:59.452349) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-10 06:06:59.478532) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-10 06:06:59.479195) ========= 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.WWVtcnJVg2/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.ipwZYR4mIv/Listeners TMPDIR=/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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 --2017-02-10 06:06:59-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 40.9M=0s 2017-02-10 06:06:59 (40.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.109256 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-10 06:06:59.613871) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-10 06:06:59.614339) ========= 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.WWVtcnJVg2/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.ipwZYR4mIv/Listeners TMPDIR=/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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.020378 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-10 06:06:59.662851) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-10 06:06:59.663228) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev b83e2b2524c981eabae7c48f8ea6988544e5087a --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 b83e2b2524c981eabae7c48f8ea6988544e5087a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.WWVtcnJVg2/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.ipwZYR4mIv/Listeners TMPDIR=/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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 2017-02-10 06:06:59,752 truncating revision to first 12 chars 2017-02-10 06:06:59,752 Setting DEBUG logging. 2017-02-10 06:06:59,752 attempt 1/10 2017-02-10 06:06:59,752 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/b83e2b2524c9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-10 06:07:00,533 unpacking tar archive at: mozilla-central-b83e2b2524c9/testing/mozharness/ program finished with exit code 0 elapsedTime=1.046890 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-10 06:07:00.734220) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-10 06:07:00.734589) ========= script_repo_revision: b83e2b2524c981eabae7c48f8ea6988544e5087a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-10 06:07:00.735099) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-10 06:07:00.735425) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-10 06:07:00.756067) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2017-02-10 06:07:00.756715) ========= /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.WWVtcnJVg2/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.ipwZYR4mIv/Listeners TMPDIR=/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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 06:07:00 INFO - MultiFileLogger online at 20170210 06:07:00 in /builds/slave/test 06:07:00 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 06:07:00 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:07:00 INFO - {'append_to_log': False, 06:07:00 INFO - 'base_work_dir': '/builds/slave/test', 06:07:00 INFO - 'blob_upload_branch': 'Firefox', 06:07:00 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:07:00 INFO - 'branch': 'Firefox', 06:07:00 INFO - 'buildbot_json_path': 'buildprops.json', 06:07:00 INFO - 'config_files': ('talos/mac_config.py',), 06:07:00 INFO - 'default_actions': ('clobber', 06:07:00 INFO - 'read-buildbot-config', 06:07:00 INFO - 'download-and-extract', 06:07:00 INFO - 'populate-webroot', 06:07:00 INFO - 'create-virtualenv', 06:07:00 INFO - 'install', 06:07:00 INFO - 'run-tests'), 06:07:00 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:07:00 INFO - 'download_minidump_stackwalk': True, 06:07:00 INFO - 'download_symbols': 'ondemand', 06:07:00 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:07:00 INFO - 'tooltool.py': '/tools/tooltool.py', 06:07:00 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:07:00 INFO - '/tools/misc-python/virtualenv.py')}, 06:07:00 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:07:00 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:07:00 INFO - 'installer_path': 'installer.exe', 06:07:00 INFO - 'log_level': 'info', 06:07:00 INFO - 'log_name': 'talos', 06:07:00 INFO - 'log_to_console': True, 06:07:00 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 06:07:00 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 06:07:00 INFO - 'opt_config_files': (), 06:07:00 INFO - 'pip_index': False, 06:07:00 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:07:00 INFO - 'cmd': ('bash', 06:07:00 INFO - '-c', 06:07:00 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:07:00 INFO - 'enabled': True, 06:07:00 INFO - 'halt_on_failure': False, 06:07:00 INFO - 'name': 'check_screen_resolution'},), 06:07:00 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:07:00 INFO - 'cmd': ('bash', 06:07:00 INFO - '-c', 06:07:00 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:07:00 INFO - 'enabled': True, 06:07:00 INFO - 'halt_on_failure': False, 06:07:00 INFO - 'name': 'check_screen_resolution'},), 06:07:00 INFO - 'run_cmd_checks_enabled': True, 06:07:00 INFO - 'sps_profile': False, 06:07:00 INFO - 'sps_profile_interval': 0, 06:07:00 INFO - 'suite': 'dromaeojs-e10s', 06:07:00 INFO - 'system_bits': '32', 06:07:00 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:07:00 INFO - 'title': 't-yosemite-r7-0209', 06:07:00 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:07:00 INFO - 'use_talos_json': True, 06:07:00 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:07:00 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:07:00 INFO - 'work_dir': 'build'} 06:07:00 INFO - [mozharness: 2017-02-10 14:07:00.967293Z] Running clobber step. 06:07:00 INFO - Running pre-action listener: _resource_record_pre_action 06:07:00 INFO - Running main action method: clobber 06:07:00 INFO - rmtree: /builds/slave/test/build 06:07:00 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:07:03 INFO - Running post-action listener: _resource_record_post_action 06:07:03 INFO - [mozharness: 2017-02-10 14:07:03.051960Z] Finished clobber step (success) 06:07:03 INFO - [mozharness: 2017-02-10 14:07:03.052084Z] Running read-buildbot-config step. 06:07:03 INFO - Running pre-action listener: _resource_record_pre_action 06:07:03 INFO - Running main action method: read_buildbot_config 06:07:03 INFO - Using buildbot properties: 06:07:03 INFO - { 06:07:03 INFO - "project": "", 06:07:03 INFO - "product": "firefox", 06:07:03 INFO - "script_repo_revision": "production", 06:07:03 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 06:07:03 INFO - "repository": "", 06:07:03 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 06:07:03 INFO - "buildid": "20170210044822", 06:07:03 INFO - "pgo_build": "False", 06:07:03 INFO - "basedir": "/builds/slave/test", 06:07:03 INFO - "buildnumber": 59, 06:07:03 INFO - "slavename": "t-yosemite-r7-0209", 06:07:03 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 06:07:03 INFO - "platform": "yosemite_r7", 06:07:03 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 06:07:03 INFO - "branch": "mozilla-central", 06:07:03 INFO - "repo_path": "mozilla-central", 06:07:03 INFO - "revision": "b83e2b2524c981eabae7c48f8ea6988544e5087a", 06:07:03 INFO - "stage_platform": "macosx64", 06:07:03 INFO - "builduid": "eb1cd49071c44e0c9aa29d686de1ba80", 06:07:03 INFO - "slavebuilddir": "test" 06:07:03 INFO - } 06:07:03 INFO - Found installer url https://queue.taskcluster.net/v1/task/PC_Nrh8sTsmPVYDYql4p1Q/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg. 06:07:03 INFO - Running post-action listener: _resource_record_post_action 06:07:03 INFO - [mozharness: 2017-02-10 14:07:03.055153Z] Finished read-buildbot-config step (success) 06:07:03 INFO - [mozharness: 2017-02-10 14:07:03.055310Z] Running download-and-extract step. 06:07:03 INFO - Running pre-action listener: _resource_record_pre_action 06:07:03 INFO - Running main action method: download_and_extract 06:07:03 INFO - mkdir: /builds/slave/test/build/tests 06:07:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:03 INFO - trying https://queue.taskcluster.net/v1/task/PC_Nrh8sTsmPVYDYql4p1Q/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json 06:07:03 INFO - Downloading https://queue.taskcluster.net/v1/task/PC_Nrh8sTsmPVYDYql4p1Q/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 06:07:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PC_Nrh8sTsmPVYDYql4p1Q/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json'}, attempt #1 06:07:04 INFO - Downloaded 1199 bytes. 06:07:04 INFO - Reading from file /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 06:07:04 INFO - Using the following test package requirements: 06:07:04 INFO - {u'common': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 06:07:04 INFO - u'cppunittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 06:07:04 INFO - u'firefox-54.0a1.en-US.mac.cppunittest.tests.zip'], 06:07:04 INFO - u'gtest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 06:07:04 INFO - u'firefox-54.0a1.en-US.mac.gtest.tests.zip'], 06:07:04 INFO - u'jittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 06:07:04 INFO - u'jsshell-mac.zip'], 06:07:04 INFO - u'mochitest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 06:07:04 INFO - u'firefox-54.0a1.en-US.mac.mochitest.tests.zip'], 06:07:04 INFO - u'mozbase': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 06:07:04 INFO - u'reftest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 06:07:04 INFO - u'firefox-54.0a1.en-US.mac.reftest.tests.zip'], 06:07:04 INFO - u'talos': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 06:07:04 INFO - u'firefox-54.0a1.en-US.mac.talos.tests.zip'], 06:07:04 INFO - u'web-platform': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 06:07:04 INFO - u'firefox-54.0a1.en-US.mac.web-platform.tests.zip'], 06:07:04 INFO - u'xpcshell': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 06:07:04 INFO - u'firefox-54.0a1.en-US.mac.xpcshell.tests.zip']} 06:07:04 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip'] for test suite category: common 06:07:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PC_Nrh8sTsmPVYDYql4p1Q/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 06:07:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PC_Nrh8sTsmPVYDYql4p1Q/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:07:04 INFO - Fetch https://queue.taskcluster.net/v1/task/PC_Nrh8sTsmPVYDYql4p1Q/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 06:07:05 INFO - Expected file size: 34948693 06:07:05 INFO - Obtained file size: 34948693 06:07:12 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip', u'firefox-54.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 06:07:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PC_Nrh8sTsmPVYDYql4p1Q/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 06:07:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PC_Nrh8sTsmPVYDYql4p1Q/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:07:12 INFO - Fetch https://queue.taskcluster.net/v1/task/PC_Nrh8sTsmPVYDYql4p1Q/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 06:07:14 INFO - Expected file size: 34948693 06:07:14 INFO - Obtained file size: 34948693 06:07:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PC_Nrh8sTsmPVYDYql4p1Q/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip 06:07:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PC_Nrh8sTsmPVYDYql4p1Q/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip'}, attempt #1 06:07:20 INFO - Fetch https://queue.taskcluster.net/v1/task/PC_Nrh8sTsmPVYDYql4p1Q/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip into memory 06:07:21 INFO - Expected file size: 14403454 06:07:21 INFO - Obtained file size: 14403454 06:07:22 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')]} 06:07:22 INFO - trying https://queue.taskcluster.net/v1/task/PC_Nrh8sTsmPVYDYql4p1Q/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 06:07:22 INFO - Downloading https://queue.taskcluster.net/v1/task/PC_Nrh8sTsmPVYDYql4p1Q/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg 06:07:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PC_Nrh8sTsmPVYDYql4p1Q/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg'}, attempt #1 06:07:24 INFO - Downloaded 70343376 bytes. 06:07:24 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PC_Nrh8sTsmPVYDYql4p1Q/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 06:07:24 INFO - mkdir: /builds/slave/test/properties 06:07:24 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:07:24 INFO - Writing to file /builds/slave/test/properties/build_url 06:07:24 INFO - Contents: 06:07:24 INFO - build_url:https://queue.taskcluster.net/v1/task/PC_Nrh8sTsmPVYDYql4p1Q/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 06:07:24 INFO - Running post-action listener: _resource_record_post_action 06:07:24 INFO - Running post-action listener: set_extra_try_arguments 06:07:24 INFO - [mozharness: 2017-02-10 14:07:24.840193Z] Finished download-and-extract step (success) 06:07:24 INFO - [mozharness: 2017-02-10 14:07:24.840361Z] Running populate-webroot step. 06:07:24 INFO - Running pre-action listener: _resource_record_pre_action 06:07:24 INFO - Running main action method: populate_webroot 06:07:24 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:07:24 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:07:24 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:07:24 INFO - '--tppagecycles', 06:07:24 INFO - '7'], 06:07:24 INFO - 'tests': ['tsvgm']}}, 06:07:24 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:07:24 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:07:24 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:07:24 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:07:24 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:07:24 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:07:24 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 06:07:24 INFO - 'talos_options': ['--disable-e10s'], 06:07:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:07:24 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 06:07:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:07:24 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 06:07:24 INFO - 'talos_options': ['--disable-e10s'], 06:07:24 INFO - 'tests': ['damp', 'tps']}, 06:07:24 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 06:07:24 INFO - 'tests': ['damp', 'tps']}, 06:07:24 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:07:24 INFO - 'tests': ['dromaeo_dom']}, 06:07:24 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:07:24 INFO - 'g4': {'talos_options': ['--disable-e10s'], 06:07:24 INFO - 'tests': ['basic_compositor_video']}, 06:07:24 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 06:07:24 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:07:24 INFO - 'tests': ['a11yr', 06:07:24 INFO - 'ts_paint', 06:07:24 INFO - 'tpaint', 06:07:24 INFO - 'sessionrestore', 06:07:24 INFO - 'sessionrestore_no_auto_restore', 06:07:24 INFO - 'tabpaint']}, 06:07:24 INFO - 'other-e10s': {'tests': ['a11yr', 06:07:24 INFO - 'ts_paint', 06:07:24 INFO - 'tpaint', 06:07:24 INFO - 'sessionrestore', 06:07:24 INFO - 'sessionrestore_no_auto_restore', 06:07:24 INFO - 'tabpaint']}, 06:07:24 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:07:24 INFO - 'ts_paint', 06:07:24 INFO - 'tpaint', 06:07:24 INFO - 'sessionrestore', 06:07:24 INFO - 'sessionrestore_no_auto_restore', 06:07:24 INFO - 'tabpaint']}, 06:07:24 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:07:24 INFO - 'ts_paint', 06:07:24 INFO - 'tpaint', 06:07:24 INFO - 'sessionrestore', 06:07:24 INFO - 'sessionrestore_no_auto_restore', 06:07:24 INFO - 'tabpaint']}, 06:07:24 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:07:24 INFO - 'tests': ['a11yr', 06:07:24 INFO - 'ts_paint', 06:07:24 INFO - 'tpaint', 06:07:24 INFO - 'sessionrestore', 06:07:24 INFO - 'sessionrestore_no_auto_restore', 06:07:24 INFO - 'tabpaint']}, 06:07:24 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:07:24 INFO - 'tests': ['a11yr', 06:07:24 INFO - 'ts_paint', 06:07:24 INFO - 'tpaint', 06:07:24 INFO - 'sessionrestore', 06:07:24 INFO - 'sessionrestore_no_auto_restore', 06:07:24 INFO - 'tabpaint']}, 06:07:24 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:07:24 INFO - 'tests': ['tsvgx', 06:07:24 INFO - 'tsvgr_opacity', 06:07:24 INFO - 'tart', 06:07:24 INFO - 'tscrollx', 06:07:24 INFO - 'cart', 06:07:24 INFO - 'tsvg_static']}, 06:07:24 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:07:24 INFO - 'tsvgr_opacity', 06:07:24 INFO - 'tart', 06:07:24 INFO - 'tscrollx', 06:07:24 INFO - 'cart', 06:07:24 INFO - 'tsvg_static']}, 06:07:24 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 06:07:24 INFO - 'talos_options': ['--disable-e10s'], 06:07:24 INFO - 'tests': ['tp5o']}, 06:07:24 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 06:07:24 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 06:07:24 INFO - 'talos_options': ['--disable-e10s', 06:07:24 INFO - '--xperf_path', 06:07:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:07:24 INFO - 'tests': ['tp5n']}, 06:07:24 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 06:07:24 INFO - 'talos_options': ['--xperf_path', 06:07:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:07:24 INFO - 'tests': ['tp5n']}}, 06:07:24 INFO - 'talos.zip': {'path': '', 06:07:24 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:07:24 INFO - Running post-action listener: _resource_record_post_action 06:07:24 INFO - [mozharness: 2017-02-10 14:07:24.858745Z] Finished populate-webroot step (success) 06:07:24 INFO - [mozharness: 2017-02-10 14:07:24.858876Z] Running create-virtualenv step. 06:07:24 INFO - Running pre-action listener: _resource_record_pre_action 06:07:24 INFO - Running main action method: create_virtualenv 06:07:24 INFO - Creating virtualenv /builds/slave/test/build/venv 06:07:24 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 06:07:24 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:07:24 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:07:24 INFO - Using real prefix '/tools/python27' 06:07:24 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:07:25 INFO - Installing distribute.............................................................................................................................................................................................done. 06:07:28 INFO - Installing pip.................done. 06:07:28 INFO - Return code: 0 06:07:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 06:07:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 06:07:28 INFO - Reading from file tmpfile_stdout 06:07:28 INFO - Output received: 06:07:28 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) 06:07:28 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:07:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:07:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:07:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107719b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10771c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89315ac610>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1074f7d30>, '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 0x10741edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1074ecbe8>, '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.WWVtcnJVg2/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.ipwZYR4mIv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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 06:07:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 06:07:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 06:07:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WWVtcnJVg2/Render', 06:07:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:07:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:07:28 INFO - 'HOME': '/Users/cltbld', 06:07:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:07:28 INFO - 'LOGNAME': 'cltbld', 06:07:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:07:28 INFO - 'MOZ_NO_REMOTE': '1', 06:07:28 INFO - 'NO_EM_RESTART': '1', 06:07:28 INFO - 'PAGER': '/bin/cat', 06:07:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:07:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:07:28 INFO - 'PWD': '/builds/slave/test', 06:07:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:07:28 INFO - 'SHELL': '/bin/bash', 06:07:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ipwZYR4mIv/Listeners', 06:07:28 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 06:07:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:07:28 INFO - 'USER': 'cltbld', 06:07:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:07:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:07:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:07:28 INFO - 'XPC_FLAGS': '0x0', 06:07:28 INFO - 'XPC_SERVICE_NAME': '0', 06:07:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:07:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:07:29 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 06:07:29 INFO - Cleaning up... 06:07:29 INFO - Return code: 0 06:07:29 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:07:29 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')]} 06:07:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:07:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:29 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')]} 06:07:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:07:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:29 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 0x107719b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10771c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89315ac610>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1074f7d30>, '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 0x10741edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1074ecbe8>, '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.WWVtcnJVg2/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.ipwZYR4mIv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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 06:07:29 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 06:07:29 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 06:07:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WWVtcnJVg2/Render', 06:07:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:07:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:07:29 INFO - 'HOME': '/Users/cltbld', 06:07:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:07:29 INFO - 'LOGNAME': 'cltbld', 06:07:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:07:29 INFO - 'MOZ_NO_REMOTE': '1', 06:07:29 INFO - 'NO_EM_RESTART': '1', 06:07:29 INFO - 'PAGER': '/bin/cat', 06:07:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:07:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:07:29 INFO - 'PWD': '/builds/slave/test', 06:07:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:07:29 INFO - 'SHELL': '/bin/bash', 06:07:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ipwZYR4mIv/Listeners', 06:07:29 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 06:07:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:07:29 INFO - 'USER': 'cltbld', 06:07:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:07:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:07:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:07:29 INFO - 'XPC_FLAGS': '0x0', 06:07:29 INFO - 'XPC_SERVICE_NAME': '0', 06:07:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:07:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:07:29 INFO - Downloading/unpacking psutil>=3.1.1 06:07:29 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 06:07:29 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 06:07:29 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 06:07:29 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 06:07:29 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 06:07:29 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 06:07:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:07:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:07:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:07:33 INFO - Installing collected packages: psutil 06:07:33 INFO - Running setup.py install for psutil 06:07:33 INFO - building 'psutil._psutil_osx' extension 06:07:33 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 06:07:33 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 06:07:33 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 06:07:33 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 06:07:33 INFO - building 'psutil._psutil_posix' extension 06:07:33 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 06:07:33 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 06:07:33 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 06:07:33 INFO - ^ 06:07:33 INFO - 1 warning generated. 06:07:33 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 06:07:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:07:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:07:33 INFO - Successfully installed psutil 06:07:33 INFO - Cleaning up... 06:07:33 INFO - Return code: 0 06:07:33 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:07: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')]} 06:07:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:07:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07: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')]} 06:07:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:07:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107719b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10771c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89315ac610>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1074f7d30>, '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 0x10741edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1074ecbe8>, '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.WWVtcnJVg2/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.ipwZYR4mIv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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 06:07: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 06:07: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 mozsystemmonitor==0.3 06:07:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WWVtcnJVg2/Render', 06:07:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:07:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:07:33 INFO - 'HOME': '/Users/cltbld', 06:07:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:07:33 INFO - 'LOGNAME': 'cltbld', 06:07:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:07:33 INFO - 'MOZ_NO_REMOTE': '1', 06:07:33 INFO - 'NO_EM_RESTART': '1', 06:07:33 INFO - 'PAGER': '/bin/cat', 06:07:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:07:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:07:33 INFO - 'PWD': '/builds/slave/test', 06:07:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:07:33 INFO - 'SHELL': '/bin/bash', 06:07:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ipwZYR4mIv/Listeners', 06:07:33 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 06:07:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:07:33 INFO - 'USER': 'cltbld', 06:07:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:07:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:07:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:07:33 INFO - 'XPC_FLAGS': '0x0', 06:07:33 INFO - 'XPC_SERVICE_NAME': '0', 06:07:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:07:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:07:34 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:07: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 06:07: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 06:07: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 06:07: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 06:07: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 06:07: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 06:07:36 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:07:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:07:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:07:36 INFO - Installing collected packages: mozsystemmonitor 06:07:36 INFO - Running setup.py install for mozsystemmonitor 06:07:37 INFO - Successfully installed mozsystemmonitor 06:07:37 INFO - Cleaning up... 06:07:37 INFO - Return code: 0 06:07:37 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 06:07:37 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')]} 06:07:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:07:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:37 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')]} 06:07:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:07:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:37 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 0x107719b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10771c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89315ac610>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1074f7d30>, '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 0x10741edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1074ecbe8>, '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.WWVtcnJVg2/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.ipwZYR4mIv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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 06:07:37 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 06:07:37 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 06:07:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WWVtcnJVg2/Render', 06:07:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:07:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:07:37 INFO - 'HOME': '/Users/cltbld', 06:07:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:07:37 INFO - 'LOGNAME': 'cltbld', 06:07:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:07:37 INFO - 'MOZ_NO_REMOTE': '1', 06:07:37 INFO - 'NO_EM_RESTART': '1', 06:07:37 INFO - 'PAGER': '/bin/cat', 06:07:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:07:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:07:37 INFO - 'PWD': '/builds/slave/test', 06:07:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:07:37 INFO - 'SHELL': '/bin/bash', 06:07:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ipwZYR4mIv/Listeners', 06:07:37 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 06:07:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:07:37 INFO - 'USER': 'cltbld', 06:07:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:07:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:07:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:07:37 INFO - 'XPC_FLAGS': '0x0', 06:07:37 INFO - 'XPC_SERVICE_NAME': '0', 06:07:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:07:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:07:37 INFO - Downloading/unpacking jsonschema==2.5.1 06:07:37 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 06:07:37 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 06:07:37 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 06:07:37 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 06:07:37 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 06:07:37 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 06:07:40 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:07:40 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:07:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:07:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:07:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:07:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:07:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:07:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:07:40 INFO - Downloading functools32-3.2.3-2.tar.gz 06:07:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:07:40 INFO - warning: no files found matching '*.txt' 06:07:40 INFO - no previously-included directories found matching 'build' 06:07:40 INFO - no previously-included directories found matching 'dist' 06:07:40 INFO - no previously-included directories found matching '.git*' 06:07:40 INFO - Installing collected packages: jsonschema, functools32 06:07:40 INFO - Running setup.py install for functools32 06:07:40 INFO - warning: no files found matching '*.txt' 06:07:40 INFO - no previously-included directories found matching 'build' 06:07:40 INFO - no previously-included directories found matching 'dist' 06:07:40 INFO - no previously-included directories found matching '.git*' 06:07:40 INFO - Successfully installed jsonschema functools32 06:07:40 INFO - Cleaning up... 06:07:40 INFO - Return code: 0 06:07:40 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 06:07:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:07:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:07:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:40 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 0x107719b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10771c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89315ac610>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1074f7d30>, '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 0x10741edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1074ecbe8>, '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.WWVtcnJVg2/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.ipwZYR4mIv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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 06:07:40 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 06:07:40 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 06:07:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WWVtcnJVg2/Render', 06:07:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:07:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:07:40 INFO - 'HOME': '/Users/cltbld', 06:07:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:07:40 INFO - 'LOGNAME': 'cltbld', 06:07:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:07:40 INFO - 'MOZ_NO_REMOTE': '1', 06:07:40 INFO - 'NO_EM_RESTART': '1', 06:07:40 INFO - 'PAGER': '/bin/cat', 06:07:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:07:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:07:40 INFO - 'PWD': '/builds/slave/test', 06:07:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:07:40 INFO - 'SHELL': '/bin/bash', 06:07:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ipwZYR4mIv/Listeners', 06:07:40 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 06:07:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:07:40 INFO - 'USER': 'cltbld', 06:07:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:07:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:07:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:07:40 INFO - 'XPC_FLAGS': '0x0', 06:07:40 INFO - 'XPC_SERVICE_NAME': '0', 06:07:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:07:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:07:41 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 06:07:41 INFO - Cleaning up... 06:07:41 INFO - Return code: 0 06:07:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:07:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:07:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:07:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x107719b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10771c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89315ac610>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1074f7d30>, '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 0x10741edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1074ecbe8>, '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.WWVtcnJVg2/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.ipwZYR4mIv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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 06:07:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 06:07:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 06:07:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WWVtcnJVg2/Render', 06:07:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:07:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:07:41 INFO - 'HOME': '/Users/cltbld', 06:07:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:07:41 INFO - 'LOGNAME': 'cltbld', 06:07:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:07:41 INFO - 'MOZ_NO_REMOTE': '1', 06:07:41 INFO - 'NO_EM_RESTART': '1', 06:07:41 INFO - 'PAGER': '/bin/cat', 06:07:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:07:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:07:41 INFO - 'PWD': '/builds/slave/test', 06:07:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:07:41 INFO - 'SHELL': '/bin/bash', 06:07:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ipwZYR4mIv/Listeners', 06:07:41 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 06:07:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:07:41 INFO - 'USER': 'cltbld', 06:07:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:07:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:07:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:07:41 INFO - 'XPC_FLAGS': '0x0', 06:07:41 INFO - 'XPC_SERVICE_NAME': '0', 06:07:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:07:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:07:41 INFO - Downloading/unpacking blobuploader==1.2.4 06:07:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:07:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:07:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:07:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:07:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:07:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:07:44 INFO - Downloading blobuploader-1.2.4.tar.gz 06:07:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:07:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:07:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:07:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:07:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:07:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:07:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:07:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:07:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:07:44 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:07:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:07:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:07:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:07:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:07:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:07:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:07:45 INFO - Downloading docopt-0.6.1.tar.gz 06:07:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:07:45 INFO - Installing collected packages: blobuploader, requests, docopt 06:07:45 INFO - Running setup.py install for blobuploader 06:07:45 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:07:45 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:07:45 INFO - Running setup.py install for requests 06:07:46 INFO - Running setup.py install for docopt 06:07:46 INFO - Successfully installed blobuploader requests docopt 06:07:46 INFO - Cleaning up... 06:07:46 INFO - Return code: 0 06:07:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:07: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')]} 06:07:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:07:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07: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')]} 06:07:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:07:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:46 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 0x107719b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10771c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89315ac610>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1074f7d30>, '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 0x10741edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1074ecbe8>, '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.WWVtcnJVg2/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.ipwZYR4mIv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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 06:07:46 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 06:07:46 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 06:07:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WWVtcnJVg2/Render', 06:07:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:07:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:07:46 INFO - 'HOME': '/Users/cltbld', 06:07:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:07:46 INFO - 'LOGNAME': 'cltbld', 06:07:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:07:46 INFO - 'MOZ_NO_REMOTE': '1', 06:07:46 INFO - 'NO_EM_RESTART': '1', 06:07:46 INFO - 'PAGER': '/bin/cat', 06:07:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:07:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:07:46 INFO - 'PWD': '/builds/slave/test', 06:07:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:07:46 INFO - 'SHELL': '/bin/bash', 06:07:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ipwZYR4mIv/Listeners', 06:07:46 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 06:07:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:07:46 INFO - 'USER': 'cltbld', 06:07:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:07:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:07:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:07:46 INFO - 'XPC_FLAGS': '0x0', 06:07:46 INFO - 'XPC_SERVICE_NAME': '0', 06:07:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:07:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:07:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:07:46 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-8oua1g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:07:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:07:46 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-vPcG6m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:07:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:07:46 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-9pAxGv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:07:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:07:46 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-Jxv5lh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:07:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:07:46 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-nJ3PR1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:07:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:07:47 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-V_EXlW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:07:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:07:47 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-Ta8WyE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:07:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:07:47 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-cKgpXT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:07:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:07:47 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-rhZ1hs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:07:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:07:47 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-37Ge7k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:07:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:07:47 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-7LCqsB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:07:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:07:47 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-Fl7lw8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:07:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:07:47 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-Gm8RzU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:07:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:07:47 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-HUCgeY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:07:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:07:48 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-mm46jk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:07:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:07:48 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-MKDIuC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:07:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:07:48 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-Q75NaH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:07:48 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:07:48 INFO - Running setup.py install for manifestparser 06:07:48 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:07:48 INFO - Running setup.py install for mozcrash 06:07:48 INFO - Running setup.py install for mozdebug 06:07:48 INFO - Running setup.py install for mozdevice 06:07:48 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:07:48 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:07:48 INFO - Running setup.py install for mozfile 06:07:49 INFO - Running setup.py install for mozhttpd 06:07:49 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:07:49 INFO - Running setup.py install for mozinfo 06:07:49 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:07:49 INFO - Running setup.py install for mozInstall 06:07:49 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:07:49 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:07:49 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:07:49 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:07:49 INFO - Running setup.py install for mozleak 06:07:49 INFO - Running setup.py install for mozlog 06:07:49 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:07:49 INFO - Running setup.py install for moznetwork 06:07:49 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:07:49 INFO - Running setup.py install for mozprocess 06:07:50 INFO - Running setup.py install for mozprofile 06:07:50 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:07:50 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:07:50 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:07:50 INFO - Running setup.py install for mozrunner 06:07:50 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:07:50 INFO - Running setup.py install for mozscreenshot 06:07:50 INFO - Running setup.py install for moztest 06:07:50 INFO - Running setup.py install for mozversion 06:07:50 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:07:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:07:50 INFO - Cleaning up... 06:07:50 INFO - Return code: 0 06:07:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:07:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:07:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:07:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:50 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 0x107719b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10771c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89315ac610>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1074f7d30>, '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 0x10741edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1074ecbe8>, '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.WWVtcnJVg2/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.ipwZYR4mIv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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 06:07:50 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 06:07:50 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 06:07:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WWVtcnJVg2/Render', 06:07:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:07:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:07:50 INFO - 'HOME': '/Users/cltbld', 06:07:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:07:50 INFO - 'LOGNAME': 'cltbld', 06:07:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:07:50 INFO - 'MOZ_NO_REMOTE': '1', 06:07:50 INFO - 'NO_EM_RESTART': '1', 06:07:50 INFO - 'PAGER': '/bin/cat', 06:07:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:07:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:07:50 INFO - 'PWD': '/builds/slave/test', 06:07:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:07:50 INFO - 'SHELL': '/bin/bash', 06:07:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ipwZYR4mIv/Listeners', 06:07:50 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 06:07:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:07:50 INFO - 'USER': 'cltbld', 06:07:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:07:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:07:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:07:50 INFO - 'XPC_FLAGS': '0x0', 06:07:50 INFO - 'XPC_SERVICE_NAME': '0', 06:07:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:07:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:07:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:07:51 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-u9Ze18-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:07:51 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)) 06:07:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:07:51 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-sEXNWJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:07:51 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)) 06:07:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:07:51 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-66Au1K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:07:51 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)) 06:07:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:07:51 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-ZzlKjj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:07:51 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)) 06:07:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:07:51 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-V5Icbm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:07:51 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)) 06:07:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:07:51 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-uwLcNB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:07:51 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)) 06:07:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:07:51 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-xoEVEB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:07:51 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)) 06:07:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:07:51 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-wnWjua-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:07:51 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)) 06:07:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:07:51 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-slJOEL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:07:52 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)) 06:07:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:07:52 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-TX5jfJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:07:52 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)) 06:07:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:07:52 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-2jmMR5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:07:52 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)) 06:07:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:07:52 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-QnkN2m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:07:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 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)) 06:07:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:07:52 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-Hfg7Kj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:07:52 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)) 06:07:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:07:52 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-PNjSkN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:07:52 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)) 06:07:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:07:52 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-eS5UvL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:07:52 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)) 06:07:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:07:52 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-Jflmp8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:07:52 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 06:07:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:07:52 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-FwuDUs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:07:52 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)) 06:07:52 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)) 06:07:52 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)) 06:07:52 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)) 06:07:52 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)) 06:07:52 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)) 06:07:52 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:07:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:07:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:07:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:07:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:07:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:07:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:07:55 INFO - Downloading blessings-1.6.tar.gz 06:07:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:07:55 INFO - Installing collected packages: blessings 06:07:55 INFO - Running setup.py install for blessings 06:07:55 INFO - Successfully installed blessings 06:07:55 INFO - Cleaning up... 06:07:55 INFO - Return code: 0 06:07:55 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:07:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:07:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:07:56 INFO - Reading from file tmpfile_stdout 06:07:56 INFO - Current package versions: 06:07:56 INFO - blessings == 1.6 06:07:56 INFO - blobuploader == 1.2.4 06:07:56 INFO - docopt == 0.6.1 06:07:56 INFO - functools32 == 3.2.3-2 06:07:56 INFO - jsonschema == 2.5.1 06:07:56 INFO - manifestparser == 1.1 06:07:56 INFO - mozInstall == 1.12 06:07:56 INFO - mozcrash == 1.0 06:07:56 INFO - mozdebug == 0.1 06:07:56 INFO - mozdevice == 0.49 06:07:56 INFO - mozfile == 1.2 06:07:56 INFO - mozhttpd == 0.7 06:07:56 INFO - mozinfo == 0.9 06:07:56 INFO - mozleak == 0.1 06:07:56 INFO - mozlog == 3.4 06:07:56 INFO - moznetwork == 0.27 06:07:56 INFO - mozprocess == 0.24 06:07:56 INFO - mozprofile == 0.28 06:07:56 INFO - mozrunner == 6.13 06:07:56 INFO - mozscreenshot == 0.1 06:07:56 INFO - mozsystemmonitor == 0.3 06:07:56 INFO - moztest == 0.8 06:07:56 INFO - mozversion == 1.4 06:07:56 INFO - psutil == 3.1.1 06:07:56 INFO - requests == 1.2.3 06:07:56 INFO - wsgiref == 0.1.2 06:07:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:07:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:07:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:07:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:57 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 0x107719b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10771c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89315ac610>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1074f7d30>, '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 0x10741edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1074ecbe8>, '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.WWVtcnJVg2/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.ipwZYR4mIv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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 06:07:57 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 06:07:57 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 06:07:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WWVtcnJVg2/Render', 06:07:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:07:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:07:57 INFO - 'HOME': '/Users/cltbld', 06:07:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:07:57 INFO - 'LOGNAME': 'cltbld', 06:07:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:07:57 INFO - 'MOZ_NO_REMOTE': '1', 06:07:57 INFO - 'NO_EM_RESTART': '1', 06:07:57 INFO - 'PAGER': '/bin/cat', 06:07:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:07:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:07:57 INFO - 'PWD': '/builds/slave/test', 06:07:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:07:57 INFO - 'SHELL': '/bin/bash', 06:07:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ipwZYR4mIv/Listeners', 06:07:57 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 06:07:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:07:57 INFO - 'USER': 'cltbld', 06:07:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:07:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:07:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:07:57 INFO - 'XPC_FLAGS': '0x0', 06:07:57 INFO - 'XPC_SERVICE_NAME': '0', 06:07:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:07:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:07:57 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)) 06:07:57 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)) 06:07:57 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)) 06:07:57 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)) 06:07:57 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)) 06:07:57 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)) 06:07:57 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)) 06:07:57 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)) 06:07:57 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)) 06:07:57 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)) 06:07:57 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)) 06:07:57 INFO - Cleaning up... 06:07:57 INFO - Return code: 0 06:07:57 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:07:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:07:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:07:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107719b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10771c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89315ac610>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1074f7d30>, '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 0x10741edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1074ecbe8>, '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.WWVtcnJVg2/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.ipwZYR4mIv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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 06:07:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 06:07:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 06:07:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WWVtcnJVg2/Render', 06:07:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:07:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:07:57 INFO - 'HOME': '/Users/cltbld', 06:07:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:07:57 INFO - 'LOGNAME': 'cltbld', 06:07:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:07:57 INFO - 'MOZ_NO_REMOTE': '1', 06:07:57 INFO - 'NO_EM_RESTART': '1', 06:07:57 INFO - 'PAGER': '/bin/cat', 06:07:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:07:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:07:57 INFO - 'PWD': '/builds/slave/test', 06:07:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:07:57 INFO - 'SHELL': '/bin/bash', 06:07:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ipwZYR4mIv/Listeners', 06:07:57 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 06:07:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:07:57 INFO - 'USER': 'cltbld', 06:07:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:07:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:07:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:07:57 INFO - 'XPC_FLAGS': '0x0', 06:07:57 INFO - 'XPC_SERVICE_NAME': '0', 06:07:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:07:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:07:57 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 06:07:57 INFO - Cleaning up... 06:07:57 INFO - Return code: 0 06:07:57 INFO - Running post-action listener: _resource_record_post_action 06:07:57 INFO - Running post-action listener: _start_resource_monitoring 06:07:57 INFO - Starting resource monitoring. 06:07:57 INFO - [mozharness: 2017-02-10 14:07:57.971276Z] Finished create-virtualenv step (success) 06:07:57 INFO - [mozharness: 2017-02-10 14:07:57.972242Z] Running install step. 06:07:57 INFO - Running pre-action listener: _resource_record_pre_action 06:07:57 INFO - Running main action method: install 06:07:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:07:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:07:58 INFO - Reading from file tmpfile_stdout 06:07:58 INFO - Detecting whether we're running mozinstall >=1.0... 06:07:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:07:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:07:58 INFO - Reading from file tmpfile_stdout 06:07:58 INFO - Output received: 06:07:58 INFO - Usage: mozinstall [options] installer 06:07:58 INFO - Options: 06:07:58 INFO - -h, --help show this help message and exit 06:07:58 INFO - -d DEST, --destination=DEST 06:07:58 INFO - Directory to install application into. [default: 06:07:58 INFO - "/builds/slave/test"] 06:07:58 INFO - --app=APP Application being installed. [default: firefox] 06:07:58 INFO - mkdir: /builds/slave/test/build/application 06:07:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 06:07:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 06:08:20 INFO - Reading from file tmpfile_stdout 06:08:20 INFO - Output received: 06:08:20 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 06:08:20 INFO - Running post-action listener: _resource_record_post_action 06:08:20 INFO - [mozharness: 2017-02-10 14:08:20.219342Z] Finished install step (success) 06:08:20 INFO - [mozharness: 2017-02-10 14:08:20.219511Z] Running run-tests step. 06:08:20 INFO - Running pre-action listener: _resource_record_pre_action 06:08:20 INFO - Running main action method: run_tests 06:08:20 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:08:20 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:08:20 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:08:20 INFO - 2017-02-10 06:08:20.234 screenresolution[1887:64593] starting screenresolution argv=screenresolution get 06:08:20 INFO - 2017-02-10 06:08:20.236 screenresolution[1887:64593] Display 0: 1600x1200x32@60 06:08:20 INFO - 2017-02-10 06:08:20.242 screenresolution[1888:64596] starting screenresolution argv=screenresolution list 06:08:20 INFO - Available Modes on Display 0 06:08:20 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:08:20 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:08:20 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:08:20 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:08:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:08:20 INFO - Intel Iris: 06:08:20 INFO - Chipset Model: Intel Iris 06:08:20 INFO - Type: GPU 06:08:20 INFO - Bus: Built-In 06:08:20 INFO - VRAM (Dynamic, Max): 1536 MB 06:08:20 INFO - Vendor: Intel (0x8086) 06:08:20 INFO - Device ID: 0x0a2e 06:08:20 INFO - Revision ID: 0x0009 06:08:20 INFO - Displays: 06:08:20 INFO - Display: 06:08:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:08:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:08:20 INFO - Display Serial Number: 1600x1200 60 06:08:20 INFO - Main Display: Yes 06:08:20 INFO - Mirror: Off 06:08:20 INFO - Online: Yes 06:08:20 INFO - Rotation: Supported 06:08:20 INFO - Return code: 0 06:08:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:08:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:08:20 INFO - Python 2.7.3 06:08:20 INFO - Return code: 0 06:08:20 INFO - grabbing minidump binary from tooltool 06:08:20 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')]} 06:08:20 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 0x1074f7d30>, '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 0x10741edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1074ecbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:08:20 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 06:08:20 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 06:08:20 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 06:08:20 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:08:20 INFO - Return code: 0 06:08:20 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 06:08:20 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:08:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:08:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 06:08:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:08:20 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/PC_Nrh8sTsmPVYDYql4p1Q/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0209', '--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 06:08:20 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/PC_Nrh8sTsmPVYDYql4p1Q/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0209 --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 06:08:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WWVtcnJVg2/Render', 06:08:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:08:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:08:20 INFO - 'HOME': '/Users/cltbld', 06:08:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:08:20 INFO - 'LOGNAME': 'cltbld', 06:08:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:08:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 06:08:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:08:20 INFO - 'MOZ_NO_REMOTE': '1', 06:08:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:08:20 INFO - 'NO_EM_RESTART': '1', 06:08:20 INFO - 'PAGER': '/bin/cat', 06:08:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:08:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:08:20 INFO - 'PWD': '/builds/slave/test', 06:08:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:08:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:08:20 INFO - 'SHELL': '/bin/bash', 06:08:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ipwZYR4mIv/Listeners', 06:08:20 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 06:08:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:08:20 INFO - 'USER': 'cltbld', 06:08:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:08:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:08:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:08:20 INFO - 'XPC_FLAGS': '0x0', 06:08:20 INFO - 'XPC_SERVICE_NAME': '0', 06:08:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:08:20 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/PC_Nrh8sTsmPVYDYql4p1Q/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0209', '--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 06:08:20 INFO - mozversion application_buildid: 20170210044822 06:08:20 INFO - mozversion application_changeset: b83e2b2524c981eabae7c48f8ea6988544e5087a 06:08:20 INFO - mozversion application_display_name: Nightly 06:08:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:08:20 INFO - mozversion application_name: Firefox 06:08:20 INFO - mozversion application_remotingname: firefox 06:08:20 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 06:08:20 INFO - mozversion application_vendor: Mozilla 06:08:20 INFO - mozversion application_version: 54.0a1 06:08:20 INFO - mozversion platform_buildid: 20170210044822 06:08:20 INFO - mozversion platform_changeset: b83e2b2524c981eabae7c48f8ea6988544e5087a 06:08:20 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 06:08:20 INFO - mozversion platform_version: 54.0a1 06:08:20 INFO - using testdate: 1486735700 06:08:20 INFO - actual date: 1486735700 06:08:20 INFO - starting webserver on 'localhost:53544' 06:08:20 INFO - SUITE-START | Running 2 tests 06:08:20 INFO - TEST-START | dromaeo_css 06:08:20 INFO - operating with platform_type : mac_ 06:08:20 INFO - Initialising browser for dromaeo_css test... 06:08:20 INFO - TEST-INFO | started process 1898 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/tmpcsWAi7/profile http://localhost:53544/getInfo.html) 06:08:23 INFO - PROCESS | 1898 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 06:08:23 INFO - PROCESS | 1898 | 2017-02-10 06:08:23.728 plugin-container[1900:64760] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f37, name = 'com.apple.tsm.portname' 06:08:23 INFO - PROCESS | 1898 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:08:23 INFO - PROCESS | 1898 | 2017-02-10 06:08:23.731 plugin-container[1900:64760] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x910b, name = 'com.apple.CFPasteboardClient' 06:08:23 INFO - PROCESS | 1898 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:08:23 INFO - PROCESS | 1898 | 2017-02-10 06:08:23.731 plugin-container[1900:64760] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:08:23 INFO - PROCESS | 1898 | __metrics Screen width/height:1600/1200 06:08:23 INFO - PROCESS | 1898 | colorDepth:24 06:08:23 INFO - PROCESS | 1898 | Browser inner width/height: 1024/689 06:08:23 INFO - PROCESS | 1898 | __metrics 06:08:23 ERROR - PROCESS | 1898 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3283: TypeError: this.tabs is undefined 06:08:24 INFO - TEST-INFO | 1898: exit 0 06:08:24 INFO - Browser initialized. 06:08:24 INFO - Running cycle 1/1 for dromaeo_css test... 06:08:24 INFO - TEST-INFO | started process 1901 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/tmpcsWAi7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:08:27 INFO - PROCESS | 1901 | 2017-02-10 06:08:27.185 plugin-container[1902:64879] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c47, name = 'com.apple.tsm.portname' 06:08:27 INFO - PROCESS | 1901 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:08:27 INFO - PROCESS | 1901 | 2017-02-10 06:08:27.186 plugin-container[1902:64879] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900f, name = 'com.apple.CFPasteboardClient' 06:08:27 INFO - PROCESS | 1901 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:08:27 INFO - PROCESS | 1901 | 2017-02-10 06:08:27.186 plugin-container[1902:64879] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:08:36 INFO - PROCESS | 1901 | RSS: Main: 232538112 06:08:36 INFO - PROCESS | 1901 | 06:11:40 INFO - PROCESS | 1901 | Cycle 1(1): loaded http://localhost:53544/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53544/tests/dromaeo/cssquery-ext.html) 06:11:41 INFO - PROCESS | 1901 | RSS: Main: 255606784 06:11:41 INFO - PROCESS | 1901 | 06:13:25 INFO - PROCESS | 1901 | ************************* 06:13:25 INFO - PROCESS | 1901 | A coding exception was thrown and uncaught in a Task. 06:13:25 INFO - PROCESS | 1901 | 06:13:25 ERROR - PROCESS | 1901 | Full message: TypeError: NetworkError when attempting to fetch resource. 06:13:25 INFO - PROCESS | 1901 | Full stack: 06:13:25 INFO - PROCESS | 1901 | ************************* 06:14:44 INFO - PROCESS | 1901 | Cycle 1(1): loaded http://localhost:53544/tests/dromaeo/cssquery-ext.html (next: http://localhost:53544/tests/dromaeo/cssquery-jquery.html) 06:14:44 INFO - PROCESS | 1901 | RSS: Main: 264601600 06:14:44 INFO - PROCESS | 1901 | 06:17:46 INFO - PROCESS | 1901 | Cycle 1(1): loaded http://localhost:53544/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53544/tests/dromaeo/cssquery-mootools.html) 06:17:47 INFO - PROCESS | 1901 | RSS: Main: 275083264 06:17:47 INFO - PROCESS | 1901 | 06:20:49 INFO - PROCESS | 1901 | Cycle 1(1): loaded http://localhost:53544/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53544/tests/dromaeo/cssquery-prototype.html) 06:20:49 INFO - PROCESS | 1901 | RSS: Main: 268066816 06:20:49 INFO - PROCESS | 1901 | 06:23:52 INFO - PROCESS | 1901 | Cycle 1(1): loaded http://localhost:53544/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53544/tests/dromaeo/cssquery-yui.html) 06:23:53 INFO - PROCESS | 1901 | RSS: Main: 273969152 06:23:53 INFO - PROCESS | 1901 | 06:26:56 INFO - PROCESS | 1901 | Cycle 1(1): loaded http://localhost:53544/tests/dromaeo/cssquery-yui.html (next: http://localhost:53544/tests/dromaeo/cssquery-dojo.html) 06:26:56 INFO - PROCESS | 1901 | RSS: Main: 276643840 06:26:56 INFO - PROCESS | 1901 | 06:26:56 INFO - PROCESS | 1901 | __start_tp_report 06:26:56 INFO - PROCESS | 1901 | _x_x_mozilla_page_load 06:26:56 INFO - PROCESS | 1901 | _x_x_mozilla_page_load_details 06:26:56 INFO - PROCESS | 1901 | |i|pagename|runs| 06:26:56 INFO - PROCESS | 1901 | |0;dojo.html;2845.72965568271;3291.9506207406894;3316.403047451458;3323.5014747787827;3370.4944258361243;5525.972370138149;5546.278983732119;5547.861303467415;5576.219329293892;5616.185653080312;5513.338399392069;5597.552195824334;5629.4652008059165;5638.464345887144;5646.378898321182;5048.419431765348;5104.2088476286235;5110.667806592578;5147.253648221005;5148.433672296044;5141.126008578551;5153.793848246066;5214.973925130369;5224.268602395668;5271.182966640177;4979;4997.85006449807;5049.192129259301;5145.073886700392;5178.766955487012;5072.594192464594;5140.948590514085;5161.948380516222;5166.638335316529;5190.896182076375;4115.773632450214;5064.215046667736;5072.112380333427;5076.74616269185;5106.336176297058;5416.943695979286;5451.509364157245;5464.153056276284;5600.691961942091;5630.521405680522;5272.420033796279;5998.610090344114;6073.574849760518;6109.586862300694;6136.76416992428;6217.533684973645;6237.345078766755;6240.188775459206;6265.248170219557;6266.154069200691;5787.913181302284;5813.505852002585;5816.272965879265;5822.0684690449325;5861.794837180721;6072.787452439144;6174.135621013062;6179.443850053427;6214.9689251553455;6230.314665386803;5874.412558744177;5929;6114.388610881752;6117.265928088569;6140.078988151813;6181.81454556364;6207.472364848948;6224.128621992924;6261.279952805395;6276.6547839869245;6125.081237814364;6188.752449902044;6221.5333849961435;6225.501959843202;6237.4698150657705;5976.820695379146;6040.758369665252;6105.247473117015;6260.812175634738;6269;4853.19922212838;4891.364122664102;4930.482299358516;4966;4974.204127339681;4407.140607581491;4415.448068991376;4415.735055896656;4433.1577000369825;4459.910801783946;5511.173324001432;5557.610967232255;5671.914921276184;5731.742071606787;5739.512141468022;6141.416565426277;6147.815565533041;6152.600080994811;6245.156903818017;6246.531510136758;5659.292588426447;5708.20085188074;5709.857253568611;5725.456081672234;5743.282089738783;5197.29836472079;5353.785848566092;5394.514493695587;5404.378496472956;5437.918431223535;6184.969075154595;6186.257649082049;6216.2540495140875;6232.25212974446;6285.308616052139;6204.5967012144065;6235.127082208403;6236.75052997875;6258.280297765815;6261.812145635638;5226.425093239816;5532.004239236976;6129.448349648553;6206.689665516616;6287.874242515216;4876.21980483121;5908.350081490947;6104.004449388181;6125.540584456095;6153.077038444269;5132;6206.565540412128;6232.501399888089;6257.342978987141;6284.685765711788;4645.721256724607;5887.911681324869;6038;6049.909251361321;6069.2110025696375;4944.505549445027;6047.818565443044;6059.666718330358;6126.120738204555;6165.475934545614;5270.657407268515;5661.264035675501;5927.229460170151;6000.069989151689;6043.667598281961;5808.6805225711305;6103.481204097524;6131.141640170469;6132.78535251264;6141.263048434216;5727.39862314451;5780.479756821906;5799.652020878761;5810.215620891295;5895.29256489224 06:26:56 INFO - PROCESS | 1901 | |1;ext.html;445.29865461897515;479.84165225475596;487.31532197262845;488.78249179115306;528.5032069854336;38256.42615360768;39942.60057399425;40178.99999999996;40275.39586906199;40403.54552135461;40524.189516209655;41023.58976410235;41080;41106.38340424889;41227.58772412282;9174.311926605482;9355.391899526543;9380.8592871107;9428.05719428055;10775.461226938662;10455.895441045606;10790.244682872202;10873.83689244662;10878.129749620051;10905.454727263645;10757.515911783988;10849.783004339908;10850.945745271263;10866.293690910104;10866.695996480434;10638.148948084136;10672.946635266851;10689.78620427591;10694.679159625224;10770.892291077145;8963.507007114606;10633.361998280128;10754.516046777913;10762.300450470695;10780;35621.82189089038;35812.82093589541;35941.28117437663;35946.64053359459;35991.4601280981;3710.684591809699;3737.9439308410397;3759;3759.154190307186;3858.6913046956174;27182.5922611161;27586.448271034467;27637.30906727328;27639.585406218925;27677.86161069202;2554.118829003991;2560.1950624809465;2565.7819085377764;2616.9607455888176;2711.5932610108443;428134;429751;429870;431292;433234;45771;47043.5295647046;52879.47120528745;53030.46969530333;53418;55417.614559636335;55974.440255597736;56355.43644563584;58865.411345885994;58982.11526827101;14179.929100354639;14280.285985700673;14328.641783955485;14335.92832035854;14370.784438233435;9332.626694932262;9404.388714733657;11081.002709756161;11097.38964356968;11248.212625116163;16901.661966760596;16999.660006799793;17020.638348932054;17032.744508832377;17169.7424538632;6476.996065609838;7133.215346311901;7587.468877178655;7634.351080158249;7641.579713115832;775.964087942603;776.5418403142173;824.4929368438367;839.9790005249796;846.170752662382;27545.173644790688;28753.712462875523;28913;28972.27569310742;29651.55522667162;992.4938281476499;1013.9290249682451;1019.0879163148941;1020.6835880876952;1028.99485502572;13999.860001399855;15151.924240378728;15459.072455652697;15526.844731552539;15527.84472155264;549261;551622;552138.2393088009;554880;555707;46233;56397;57976.55058623484;58012.70993645005;58054.709726451096;1296.280564286811;1361.584716661408;1361.6800051987775;1361.9455221791216;1362.0942073520998;27511.17466476009;28238.294042649097;28966.565501517915;28995.7100428993;29317.85341073281;48091.27863081986;48378.5162148374;48617;48769;48889.75555122201;477893;480407;481782;483522;485267;25137.371565711004;25949.870250649383;26208.475830483665;26233.475330493664;26295.238999904734;39513.407298889964;43491.347629786214;44650;45730;45777;36581.817090914374;36711.08222294464;37551.81224093862;37619;38007.619923800405;30150;36548.451773223955;37569;37626;37832.62167378291;35446.93659190228;35581.28837423185;36112;36125.63874361328;36433.817830911736;31539.36921261608;32892.671073289916;33239.6704131832;33418;33477.83261083679;23589.466684665444;24273.577355001235;24504;24656.165040780168;24662.01351945974 06:26:56 INFO - PROCESS | 1901 | |2;jquery.html;388.7958821618648;388.82114227455367;396.5776448082792;399.4387885021544;422.6407553579456;13702.794458083112;18092.90953545232;19441.805581944176;19726.210951561938;19745.605087898235;20196.263691217137;20253.39239822804;20331.796682033175;20456.284030058952;20543.075561599726;5557.6109672322955;5767.221425107598;5784.3058832940105;5837.24115864937;5967.283925928895;5572.972135139339;5817.185594016841;5882.735276912549;5926;5934.64392136471;4674.415698037746;5784;5800.506956908646;5806.187133801272;5808.970955145219;5424.430434804368;5709.457601527848;5790.449907258803;5795.536357091443;5811.157382179603;5061.468545802712;5064;5086.745662716849;5090.032893750175;5090.720010399426;20832.37502874916;21400.35798926035;23113.53772924549;23620.17329393463;24389.63415548768;2609.4502797977025;2650.112212408845;2651.4564514274525;2683.490136873988;2684.3691732442862;13944.790828137484;14361.928190359085;14530.92734536331;14601.466845981126;15066.322015509328;1780.1099450274862;1788.8926664400178;1806.2955447375534;1806.683830329687;1828.2686925229882;118656;119611;120054.39972800168;120628.39685801603;120726;11117.444127793578;11120.277181983145;11341.376224307578;11344.092472602171;11415.143864210051;12148.331841748788;12700.301483418316;14064.226467544178;14086.647833804032;14096.08375455592;7675.2324767522805;7988.201179882081;8050.798730031796;8069.515829050159;8118.878216826753;6412.97392417211;7891.447598668153;7919.7624071276805;8060.153683863227;8077.192280771993;5972.761089556369;6724.293949135368;6870.519063665595;6919.688614012281;6944.826379340557;1793.8116497767808;1977.7132315814126;1985.960280794376;1990;1991.3826713718793;995.4375777685584;1000.1498726082772;1011.7015480433142;1012.448215722433;1047.4919663962971;15467.690646187239;17321.826781732274;18722.833511338624;19324.8067519323;20255.392338229878;1020.1023099672241;1023.2172388123092;1037.5642230263309;1057.259918057363;1057.6139709006156;10010.399376037461;10247.436390998417;10745.409002504937;10765.946170269099;10801;543183;547972;554112;621996;667743;141513.58486415003;143578;144349;144594;145227;997.6209040564539;1014.2798612984716;1041.9270651054353;1042.5464922758472;1043.6295115234134;17479.038652874213;17522.73715894288;17718.379856704953;18343.54141146482;18831.058447077867;54217.72891135519;54534.45465545294;54913;55201.44798551963;55652.44347556472;73354;73480.16299592552;82645;84130;84133;7564.6761816682965;7967.521948683097;8015;8245.464044837066;8374.330053595819;9905.900940990787;12355.3822308886;12624.55814046504;12628;12747.107702460738;10690.465476725976;10834.945825270823;11014.229003969645;11058.281211721212;11078.169137314895;10453.42506162138;10532.157427405637;10632.787344253225;10907.454627268764;10944.288621239593;10735.516901739597;10860.837087443853;10880.564777408816;11042.558297668325;11084.113270938144;7301.561906285639;7918.920810791818;8171.79570510742;8338.62476188585;8541.444806087584;9407.952960235156;10721.249512534048;10888.782224355627;10911;11024.228304018641 06:26:56 INFO - PROCESS | 1901 | |3;mootools.html;1299.4867027524126;1380.413324337157;1404.54352335491;1413.6677880698041;1417.7660685986812;8174.26431621154;8229.835403291945;8510.70212542561;8532.872006919893;8626.65493380264;8379.455335403194;8480.406371553994;8507.787305317355;8515;8521.701740439086;1882.1812511557482;2238.708967834187;2252.144185209618;2255.8533695309834;2259.039908039085;2232.073689418889;2264.1735766445295;2267.943301417461;2267.9433014174697;2269.6595510673365;2227.231605096247;2230.810381117607;2254.7407048189502;2256.7630398808137;2261.231181398324;2158.6006588781124;2220.6335954567476;2220.8334374921783;2244.663300504921;2246.5057687308768;1659;1690.8816382853206;2233.2965115988413;2243.6073687104854;2269.716285464317;8331.95834020832;8485.533295668736;8526.190011948916;8634.222919937236;8646.827063458695;5024.296598476216;5179.922301165486;5181;5193.740312984336;5203.583713302926;3395.151212196951;3451.3960056989927;3466.584009918826;3482.8606855725993;3530.258645684409;1056.2078441169124;1065.2330322168027;1120.019982515299;1186.5846953566183;1236.097648716433;10856.022957933745;10897.182711296606;10977.670669879837;10989.285696429706;11012.559497620086;4093.467849179647;4178.059936514321;4301.376160542067;4377.846775362911;4404.339349097597;8695.913040869636;8726.432781869158;8749.256313213447;8768.95615521931;8780.473171609596;5007.173816320339;5040.49595040493;5041.395032596112;5045.7981680732355;5049.343585333957;3739.775613463201;3791.450239715226;4520.299353600197;4662.090892276042;4710.646701497401;3614.475900994341;3631.727620428426;3657.8719744808805;3665.633436656313;3689.575698794673;1147.17976646698;1148.6381789736206;1157.455995682028;1158.368689064462;1161.2625982501004;866.9653213871501;873.7902903303161;884.9734507964642;902.9909700903038;914.7210100919284;5412.107002344569;5697.316322041382;5717.456841600041;5823.184754134425;5888.205092312569;947.5357075033185;958.6405098088217;960.9951950240204;977.9315447918719;1014.7108074198966;3701.833417496165;3790.8673196438544;3833.3059525764893;3873.0511024799102;3874.6319099685484;9708.417494950325;9962.153216976638;10049.799004020026;10070.647527336509;10086.445245511568;9983.251256155672;9987.400755954666;10011.79976400468;10021.599136034478;10027.649032283834;7709;7755.146933837386;7828.882566761503;7971.681132754625;8025.398095142771;5342.5418750495655;5426.538744206787;5487.6981766003255;5599.468050535193;5676.545876329967;7190.7483238086415;8118.918810811816;8538.060813310407;9241.537923103684;9280;9112.179903808688;9117.22503587176;9325.53372331367;9458.196053335543;9459.337846350689;6613.867722645617;6733.730650773914;6820.897686534607;6825.180978282638;6832.692528836314;7849.764507064797;7869.685212591432;7921.32668723165;7948.761537153895;7955.602219889098;6567.27759946396;6712;7135.215126336203;7153.785386438415;7179.317964793337;6926.272741362286;7009.929900700928;7065.258147894397;7070.46971477131;7102.289771022856;6320.5891617044745;6409.935900640934;6469.288378278292;6473.643949582818;6484.059811327425;4882.414110306786;4958.83467385157;4960.751962401938;4999.175136102648;5147.485251474823;4944.233643785219;4970.378702662168;5047.545720885138;5140.691558506502;5219.269302297757 06:26:56 INFO - PROCESS | 1901 | |4;prototype.html;294.606700055426;308.3247687564235;311.2312587907868;357.15889081213743;374.1898789121552;5094.872628184297;5411.972940135299;5474.890502189954;5526.751296191671;5528.5024347808685;4914.803407863681;5436.728163591814;5443.700596467193;5492.231087647732;5501.477359650827;3444.689977901988;3583.713302935772;3908.5505166905814;4052.452918855961;4104.630583247522;3509.754037316754;4049.2104039712267;4099.8770036898795;4116.444280022203;4145.590499230261;3675.9448608270895;3731.776093434389;3975.5626881043063;4142.46148000761;4144.48193975753;3435.5570660322724;3806.771593704359;4066.451029111062;4111.424400583921;4134.689898257628;3249;3494.9864539283576;3502.667246611568;3529.4176460883923;3573.6426357364317;4284.335927931144;4538.636909047268;5130.127878260705;5293.444188360244;5311.973440132813;4409.955900440987;4647.4895658910655;4706.082313948781;4725.740084295362;4751.809927602892;5329.734862683344;5690.032694441913;5710.77156913723;5715.599908006441;5725.370209276976;3899.337112690849;3913.6673382762215;3914.178022615254;3920.7059470539825;3927.7839718815453;5316.6872410501555;5738.799142030009;5739.454751798614;5999.820005399845;6013.602314692185;3721.7953012584567;3763.8332117043806;3777.9244415111543;3873.3608954522188;3915.060385507458;3953.892909985209;3981.551345922295;3992.8819930419527;3995.580464051233;4003.2994226010333;4624.329472226508;4645.233536466445;4648.558386953234;4689.835855745033;4697.318888761179;4979.850604481799;4986.4514903360605;5393.7303134843105;5428.375736790241;5764.452377024176;1123.3035517978906;1290.4128621477205;1291.1284882704324;1305.8443277699114;1314.6065170918857;831.6257684042205;867.4751775176054;875.9969834539417;885.526243459746;887.2946007923587;4529.909401811945;4550.29470432077;4553.908921821545;5073.315102461121;5104.3109180260935;5323.907660356237;5646.571974051198;5700.828975130753;5721.508615736627;5742.684152371659;4355.972599043697;4595.425571803525;4909.582685471775;5098.974505127525;5162.5611822994715;2681.825681330707;2919.357741296911;2968.807027543203;2985.5072463768115;3014.427258820817;5797.840431913634;5813;5831.7375718091935;5845.941540584625;5933.288005439289;5256.738382788103;5347.41178470368;5430.70131142791;5535.533083732814;5754.367019627837;5409.891802164014;5562.720574267941;5742.6504771378695;5943.821685349447;6031.306399763907;4923.581495572773;5461.399246083007;5781.947408669862;5859.677717725566;5910.349861515316;4978.605990322715;5594.692291923983;5681.642118520767;5717.828465146053;5809.419058094157;5236.9738151309;5869.295684517885;6000.429959153874;6064.938635738817;6126.479249263684;3358.354406340924;3879.340512113004;3884.9417258740577;3890.9805450972563;3905.2775236581324;5524.91712624319;5851.502622277154;5892.467958330951;5906.228131560601;5940.376260492586;5171.431142574389;5308.814191503279;5318.441563635917;5600.767831077156;5697.544196464356;5331.893362132658;5555.138953462219;5699.7435115419075;5715.485606295453;5786.218860453785;5160.258812361159;5455.208994695826;5559.8888022240135;5563.609097725569;5584.609077364546;5013.674111182761;5472.20653005304;5508.44915508446;5671.546276297804;5704.458076482728;4479;4781.402324709411;5128.794848206179;5197.856471576247;5201.531862132426 06:26:56 INFO - PROCESS | 1901 | |5;yui.html;568.4003376437862;612.5681394616793;633.4394061255787;802.9558374289414;854.0690647194558;14194;14671.339789709462;15169.848301516982;15172.924135379335;15213.543593692179;12227;12813.807792883092;12946.964242860573;12989.675258118552;13754.587362379145;864.2912811494562;876.5617191404298;884.5135175653397;887.219247062584;889.1641856654757;849.7748096754365;881.9955900220522;888.7156110044788;895.0870112485294;899.4962820820361;902.6975963052386;917.4816228830723;933.3280038372361;936.7002559181064;938.1134827587932;816.2531283875247;832.0514613340796;923.9076092390775;927.2998885841214;929.1962452478591;563.4478211352897;665.7536711416807;674.5244602555235;680.8536164724559;686.8282929267683;14542.34559444817;14956;15040.398384064625;15462.690746185013;15511.069335839773;13587.796183057262;13808.861911380858;13866.237356945365;13918.30408479572;13935.094218875842;11445.427728613618;11538.499766427123;11709.414529273587;11771.88228117725;11934.28394296345;3590.5152804371337;3632.110133017401;3633.818309084562;3653.8355773990234;3782.111203867089;74579.62710186414;104557;106085;107179.46410267899;107687.46156269246;10345.224108191916;10583.84124238137;10736.08743256832;11055.447227638586;11226.943865280622;21359.038843252092;21398.893005535185;21621.891890540763;21824.563508729738;21927.561448770935;7528.548287102792;7594.734184303523;7617.961910190414;7682.615869206629;7759.767206983686;4130.173965206911;4133.524644665842;4134.64855487281;4136.4415803866095;4154.4183814266025;3551.3785087609567;3646.4894914711963;3694.07648087978;3696.815159242081;3698.981505092457;671.392389887153;676.6887231873393;680.618853442074;681.1655721740827;698.3714656808912;650.5836264790538;685.8456847209437;688.0161369242032;694.600604652327;727.2327694282477;9846.310758246855;10044.347117437343;10072.496375181212;10115.190784737204;10338.586456541805;657.5035847934779;694.8803234208292;694.9146678396888;704.503325155764;707.3951771235622;4702.4592171899985;4934.515675341392;4943.728094954812;4943.826966056171;4951.455339912607;78774.42451150945;81298.59350703326;83149;99966.00033999712;101360;57928.71035644879;71873.6406317965;73297.2670273301;86886;91093;50588.241176382384;55474.72262638661;58310.416895831346;70990;71303.64348178226;8283.917160828556;10300.433476158883;10634.946825265824;10737.248392612442;10950.945245273722;42952.92617684583;50451.243231350825;51594.484055158966;51781;52494.73752631212;60706.39293607007;66744;70736.64631676809;71130.64434677793;72413.63793181001;12304.200226985216;13735.24456154881;14166.78749818733;14289.499867504588;14311.141331520143;14262.928685356506;14312.06971546846;14516.419343226153;14535.636609084857;14803.333849976992;13334.26661533625;13340.533081342106;13513.80962341;13794.517191898236;13925;12197.597276312983;13366.398512066786;13644.454221831413;13815.240161791198;14101.858981410054;10460.476976151314;10980.835287470854;11536.134789890624;13288.335583220995;13416;8159.755207343789;8514.957425212835;10137.391756494633;10143.289969702346;10397.11624511925;9221.769455763659;10051.047877325756;10247.771951835148;11569.942150289195;11619.651410457362 06:26:56 INFO - PROCESS | 1901 | __end_tp_report 06:26:56 INFO - PROCESS | 1901 | __start_cc_report 06:26:56 INFO - PROCESS | 1901 | _x_x_mozilla_cycle_collect,410 06:26:56 INFO - PROCESS | 1901 | __end_cc_report 06:26:56 INFO - PROCESS | 1901 | __startTimestamp1486736816349__endTimestamp 06:26:56 INFO - PROCESS | 1901 | 06:26:56 INFO - PROCESS | 1901 | ------- Summary: start ------- 06:26:56 INFO - PROCESS | 1901 | Number of tests: 6 06:26:56 INFO - PROCESS | 1901 | 06:26:56 INFO - PROCESS | 1901 | [#0] dojo.html Cycles:165 Average:5629.44 Median:5799.65 stddev:663.84 (11.4%) stddev-sans-first:628.93 06:26:56 INFO - PROCESS | 1901 | Values: 2845.7 3292.0 3316.4 3323.5 3370.5 5526.0 5546.3 5547.9 5576.2 5616.2 5513.3 5597.6 5629.5 5638.5 5646.4 5048.4 5104.2 5110.7 5147.3 5148.4 5141.1 5153.8 5215.0 5224.3 5271.2 4979.0 4997.9 5049.2 5145.1 5178.8 5072.6 5140.9 5161.9 5166.6 5190.9 4115.8 5064.2 5072.1 5076.7 5106.3 5416.9 5451.5 5464.2 5600.7 5630.5 5272.4 5998.6 6073.6 6109.6 6136.8 6217.5 6237.3 6240.2 6265.2 6266.2 5787.9 5813.5 5816.3 5822.1 5861.8 6072.8 6174.1 6179.4 6215.0 6230.3 5874.4 5929.0 6114.4 6117.3 6140.1 6181.8 6207.5 6224.1 6261.3 6276.7 6125.1 6188.8 6221.5 6225.5 6237.5 5976.8 6040.8 6105.2 6260.8 6269.0 4853.2 4891.4 4930.5 4966.0 4974.2 4407.1 4415.4 4415.7 4433.2 4459.9 5511.2 5557.6 5671.9 5731.7 5739.5 6141.4 6147.8 6152.6 6245.2 6246.5 5659.3 5708.2 5709.9 5725.5 5743.3 5197.3 5353.8 5394.5 5404.4 5437.9 6185.0 6186.3 6216.3 6232.3 6285.3 6204.6 6235.1 6236.8 6258.3 6261.8 5226.4 5532.0 6129.4 6206.7 6287.9 4876.2 5908.4 6104.0 6125.5 6153.1 5132.0 6206.6 6232.5 6257.3 6284.7 4645.7 5887.9 6038.0 6049.9 6069.2 4944.5 6047.8 6059.7 6126.1 6165.5 5270.7 5661.3 5927.2 6000.1 6043.7 5808.7 6103.5 6131.1 6132.8 6141.3 5727.4 5780.5 5799.7 5810.2 5895.3 06:26:56 INFO - PROCESS | 1901 | 06:26:56 INFO - PROCESS | 1901 | [#1] ext.html Cycles:180 Average:62242.29 Median:36118.82 stddev:130682.07 (361.8%) stddev-sans-first:130966.30 06:26:56 INFO - PROCESS | 1901 | Values: 445.3 479.8 487.3 488.8 528.5 38256.4 39942.6 40179.0 40275.4 40403.5 40524.2 41023.6 41080.0 41106.4 41227.6 9174.3 9355.4 9380.9 9428.1 10775.5 10455.9 10790.2 10873.8 10878.1 10905.5 10757.5 10849.8 10850.9 10866.3 10866.7 10638.1 10672.9 10689.8 10694.7 10770.9 8963.5 10633.4 10754.5 10762.3 10780.0 35621.8 35812.8 35941.3 35946.6 35991.5 3710.7 3737.9 3759.0 3759.2 3858.7 27182.6 27586.4 27637.3 27639.6 27677.9 2554.1 2560.2 2565.8 2617.0 2711.6 428134.0 429751.0 429870.0 431292.0 433234.0 45771.0 47043.5 52879.5 53030.5 53418.0 55417.6 55974.4 56355.4 58865.4 58982.1 14179.9 14280.3 14328.6 14335.9 14370.8 9332.6 9404.4 11081.0 11097.4 11248.2 16901.7 16999.7 17020.6 17032.7 17169.7 6477.0 7133.2 7587.5 7634.4 7641.6 776.0 776.5 824.5 840.0 846.2 27545.2 28753.7 28913.0 28972.3 29651.6 992.5 1013.9 1019.1 1020.7 1029.0 13999.9 15151.9 15459.1 15526.8 15527.8 549261.0 551622.0 552138.2 554880.0 555707.0 46233.0 56397.0 57976.6 58012.7 58054.7 1296.3 1361.6 1361.7 1361.9 1362.1 27511.2 28238.3 28966.6 28995.7 29317.9 48091.3 48378.5 48617.0 48769.0 48889.8 477893.0 480407.0 481782.0 483522.0 485267.0 25137.4 25949.9 26208.5 26233.5 26295.2 39513.4 43491.3 44650.0 45730.0 45777.0 36581.8 36711.1 37551.8 37619.0 38007.6 30150.0 36548.5 37569.0 37626.0 37832.6 35446.9 35581.3 36112.0 36125.6 36433.8 31539.4 32892.7 33239.7 33418.0 33477.8 23589.5 24273.6 24504.0 24656.2 24662.0 06:26:56 INFO - PROCESS | 1901 | 06:26:56 INFO - PROCESS | 1901 | [#2] jquery.html Cycles:180 Average:35105.11 Median:20224.83 stddev:98981.25 (489.4%) stddev-sans-first:99224.60 06:26:56 INFO - PROCESS | 1901 | Values: 388.8 388.8 396.6 399.4 422.6 13702.8 18092.9 19441.8 19726.2 19745.6 20196.3 20253.4 20331.8 20456.3 20543.1 5557.6 5767.2 5784.3 5837.2 5967.3 5573.0 5817.2 5882.7 5926.0 5934.6 4674.4 5784.0 5800.5 5806.2 5809.0 5424.4 5709.5 5790.4 5795.5 5811.2 5061.5 5064.0 5086.7 5090.0 5090.7 20832.4 21400.4 23113.5 23620.2 24389.6 2609.5 2650.1 2651.5 2683.5 2684.4 13944.8 14361.9 14530.9 14601.5 15066.3 1780.1 1788.9 1806.3 1806.7 1828.3 118656.0 119611.0 120054.4 120628.4 120726.0 11117.4 11120.3 11341.4 11344.1 11415.1 12148.3 12700.3 14064.2 14086.6 14096.1 7675.2 7988.2 8050.8 8069.5 8118.9 6413.0 7891.4 7919.8 8060.2 8077.2 5972.8 6724.3 6870.5 6919.7 6944.8 1793.8 1977.7 1986.0 1990.0 1991.4 995.4 1000.1 1011.7 1012.4 1047.5 15467.7 17321.8 18722.8 19324.8 20255.4 1020.1 1023.2 1037.6 1057.3 1057.6 10010.4 10247.4 10745.4 10765.9 10801.0 543183.0 547972.0 554112.0 621996.0 667743.0 141513.6 143578.0 144349.0 144594.0 145227.0 997.6 1014.3 1041.9 1042.5 1043.6 17479.0 17522.7 17718.4 18343.5 18831.1 54217.7 54534.5 54913.0 55201.4 55652.4 73354.0 73480.2 82645.0 84130.0 84133.0 7564.7 7967.5 8015.0 8245.5 8374.3 9905.9 12355.4 12624.6 12628.0 12747.1 10690.5 10834.9 11014.2 11058.3 11078.2 10453.4 10532.2 10632.8 10907.5 10944.3 10735.5 10860.8 10880.6 11042.6 11084.1 7301.6 7918.9 8171.8 8338.6 8541.4 9408.0 10721.2 10888.8 10911.0 11024.2 06:26:56 INFO - PROCESS | 1901 | 06:26:56 INFO - PROCESS | 1901 | [#3] mootools.html Cycles:180 Average:5367.00 Median:5144.09 stddev:2966.36 (57.7%) stddev-sans-first:2958.93 06:26:56 INFO - PROCESS | 1901 | Values: 1299.5 1380.4 1404.5 1413.7 1417.8 8174.3 8229.8 8510.7 8532.9 8626.7 8379.5 8480.4 8507.8 8515.0 8521.7 1882.2 2238.7 2252.1 2255.9 2259.0 2232.1 2264.2 2267.9 2267.9 2269.7 2227.2 2230.8 2254.7 2256.8 2261.2 2158.6 2220.6 2220.8 2244.7 2246.5 1659.0 1690.9 2233.3 2243.6 2269.7 8332.0 8485.5 8526.2 8634.2 8646.8 5024.3 5179.9 5181.0 5193.7 5203.6 3395.2 3451.4 3466.6 3482.9 3530.3 1056.2 1065.2 1120.0 1186.6 1236.1 10856.0 10897.2 10977.7 10989.3 11012.6 4093.5 4178.1 4301.4 4377.8 4404.3 8695.9 8726.4 8749.3 8769.0 8780.5 5007.2 5040.5 5041.4 5045.8 5049.3 3739.8 3791.5 4520.3 4662.1 4710.6 3614.5 3631.7 3657.9 3665.6 3689.6 1147.2 1148.6 1157.5 1158.4 1161.3 867.0 873.8 885.0 903.0 914.7 5412.1 5697.3 5717.5 5823.2 5888.2 947.5 958.6 961.0 977.9 1014.7 3701.8 3790.9 3833.3 3873.1 3874.6 9708.4 9962.2 10049.8 10070.6 10086.4 9983.3 9987.4 10011.8 10021.6 10027.6 7709.0 7755.1 7828.9 7971.7 8025.4 5342.5 5426.5 5487.7 5599.5 5676.5 7190.7 8118.9 8538.1 9241.5 9280.0 9112.2 9117.2 9325.5 9458.2 9459.3 6613.9 6733.7 6820.9 6825.2 6832.7 7849.8 7869.7 7921.3 7948.8 7955.6 6567.3 6712.0 7135.2 7153.8 7179.3 6926.3 7009.9 7065.3 7070.5 7102.3 6320.6 6409.9 6469.3 6473.6 6484.1 4882.4 4958.8 4960.8 4999.2 5147.5 4944.2 4970.4 5047.5 5140.7 5219.3 06:26:56 INFO - PROCESS | 1901 | 06:26:56 INFO - PROCESS | 1901 | [#4] prototype.html Cycles:180 Average:4547.35 Median:5129.46 stddev:1400.91 (27.3%) stddev-sans-first:1367.99 06:26:56 INFO - PROCESS | 1901 | Values: 294.6 308.3 311.2 357.2 374.2 5094.9 5412.0 5474.9 5526.8 5528.5 4914.8 5436.7 5443.7 5492.2 5501.5 3444.7 3583.7 3908.6 4052.5 4104.6 3509.8 4049.2 4099.9 4116.4 4145.6 3675.9 3731.8 3975.6 4142.5 4144.5 3435.6 3806.8 4066.5 4111.4 4134.7 3249.0 3495.0 3502.7 3529.4 3573.6 4284.3 4538.6 5130.1 5293.4 5312.0 4410.0 4647.5 4706.1 4725.7 4751.8 5329.7 5690.0 5710.8 5715.6 5725.4 3899.3 3913.7 3914.2 3920.7 3927.8 5316.7 5738.8 5739.5 5999.8 6013.6 3721.8 3763.8 3777.9 3873.4 3915.1 3953.9 3981.6 3992.9 3995.6 4003.3 4624.3 4645.2 4648.6 4689.8 4697.3 4979.9 4986.5 5393.7 5428.4 5764.5 1123.3 1290.4 1291.1 1305.8 1314.6 831.6 867.5 876.0 885.5 887.3 4529.9 4550.3 4553.9 5073.3 5104.3 5323.9 5646.6 5700.8 5721.5 5742.7 4356.0 4595.4 4909.6 5099.0 5162.6 2681.8 2919.4 2968.8 2985.5 3014.4 5797.8 5813.0 5831.7 5845.9 5933.3 5256.7 5347.4 5430.7 5535.5 5754.4 5409.9 5562.7 5742.7 5943.8 6031.3 4923.6 5461.4 5781.9 5859.7 5910.3 4978.6 5594.7 5681.6 5717.8 5809.4 5237.0 5869.3 6000.4 6064.9 6126.5 3358.4 3879.3 3884.9 3891.0 3905.3 5524.9 5851.5 5892.5 5906.2 5940.4 5171.4 5308.8 5318.4 5600.8 5697.5 5331.9 5555.1 5699.7 5715.5 5786.2 5160.3 5455.2 5559.9 5563.6 5584.6 5013.7 5472.2 5508.4 5671.5 5704.5 4479.0 4781.4 5128.8 5197.9 5201.5 06:26:56 INFO - PROCESS | 1901 | 06:26:56 INFO - PROCESS | 1901 | [#5] yui.html Cycles:180 Average:18999.16 Median:4131.85 stddev:26487.83 (641.1%) stddev-sans-first:26525.98 06:26:56 INFO - PROCESS | 1901 | Values: 568.4 612.6 633.4 803.0 854.1 14194.0 14671.3 15169.8 15172.9 15213.5 12227.0 12813.8 12947.0 12989.7 13754.6 864.3 876.6 884.5 887.2 889.2 849.8 882.0 888.7 895.1 899.5 902.7 917.5 933.3 936.7 938.1 816.3 832.1 923.9 927.3 929.2 563.4 665.8 674.5 680.9 686.8 14542.3 14956.0 15040.4 15462.7 15511.1 13587.8 13808.9 13866.2 13918.3 13935.1 11445.4 11538.5 11709.4 11771.9 11934.3 3590.5 3632.1 3633.8 3653.8 3782.1 74579.6 104557.0 106085.0 107179.5 107687.5 10345.2 10583.8 10736.1 11055.4 11226.9 21359.0 21398.9 21621.9 21824.6 21927.6 7528.5 7594.7 7618.0 7682.6 7759.8 4130.2 4133.5 4134.6 4136.4 4154.4 3551.4 3646.5 3694.1 3696.8 3699.0 671.4 676.7 680.6 681.2 698.4 650.6 685.8 688.0 694.6 727.2 9846.3 10044.3 10072.5 10115.2 10338.6 657.5 694.9 694.9 704.5 707.4 4702.5 4934.5 4943.7 4943.8 4951.5 78774.4 81298.6 83149.0 99966.0 101360.0 57928.7 71873.6 73297.3 86886.0 91093.0 50588.2 55474.7 58310.4 70990.0 71303.6 8283.9 10300.4 10634.9 10737.2 10950.9 42952.9 50451.2 51594.5 51781.0 52494.7 60706.4 66744.0 70736.6 71130.6 72413.6 12304.2 13735.2 14166.8 14289.5 14311.1 14262.9 14312.1 14516.4 14535.6 14803.3 13334.3 13340.5 13513.8 13794.5 13925.0 12197.6 13366.4 13644.5 13815.2 14101.9 10460.5 10980.8 11536.1 13288.3 13416.0 8159.8 8515.0 10137.4 10143.3 10397.1 9221.8 10051.0 10247.8 11569.9 11619.7 06:26:56 INFO - PROCESS | 1901 | -------- Summary: end -------- 06:26:56 INFO - PROCESS | 1901 | 06:26:57 INFO - TEST-INFO | 1901: exit 0 06:26:57 INFO - TEST-OK | dromaeo_css | took 1116216ms 06:26:57 INFO - TEST-START | kraken 06:26:57 INFO - operating with platform_type : mac_ 06:26:57 INFO - Initialising browser for kraken test... 06:26:57 INFO - TEST-INFO | started process 1905 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/tmpI7c18b/profile http://localhost:53544/getInfo.html) 06:26:59 INFO - PROCESS | 1905 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 06:26:59 INFO - PROCESS | 1905 | 2017-02-10 06:26:59.832 plugin-container[1906:66742] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e4f, name = 'com.apple.tsm.portname' 06:26:59 INFO - PROCESS | 1905 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:26:59 INFO - PROCESS | 1905 | 2017-02-10 06:26:59.836 plugin-container[1906:66742] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x920b, name = 'com.apple.CFPasteboardClient' 06:26:59 INFO - PROCESS | 1905 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:26:59 INFO - PROCESS | 1905 | 2017-02-10 06:26:59.837 plugin-container[1906:66742] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:26:59 INFO - PROCESS | 1905 | __metrics Screen width/height:1600/1200 06:26:59 INFO - PROCESS | 1905 | colorDepth:24 06:26:59 INFO - PROCESS | 1905 | Browser inner width/height: 1024/689 06:26:59 INFO - PROCESS | 1905 | __metrics 06:27:00 ERROR - PROCESS | 1905 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3283: TypeError: this.tabs is undefined 06:27:00 INFO - TEST-INFO | 1905: exit 0 06:27:00 INFO - Browser initialized. 06:27:00 INFO - Running cycle 1/1 for kraken test... 06:27:00 INFO - TEST-INFO | started process 1907 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/tmpI7c18b/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:27:03 INFO - PROCESS | 1907 | 2017-02-10 06:27:03.548 plugin-container[1908:66872] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x473f, name = 'com.apple.tsm.portname' 06:27:03 INFO - PROCESS | 1907 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:27:03 INFO - PROCESS | 1907 | 2017-02-10 06:27:03.549 plugin-container[1908:66872] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8843, name = 'com.apple.CFPasteboardClient' 06:27:03 INFO - PROCESS | 1907 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:27:03 INFO - PROCESS | 1907 | 2017-02-10 06:27:03.549 plugin-container[1908:66872] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:27:13 INFO - PROCESS | 1907 | RSS: Main: 233844736 06:27:13 INFO - PROCESS | 1907 | 06:27:16 INFO - PROCESS | 1907 | Cycle 1(1): loaded http://localhost:53544/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53544/tests/kraken/driver.html?testName=audio-beat-detection) 06:27:17 INFO - PROCESS | 1907 | RSS: Main: 247754752 06:27:17 INFO - PROCESS | 1907 | 06:27:20 INFO - PROCESS | 1907 | Cycle 1(1): loaded http://localhost:53544/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53544/tests/kraken/driver.html?testName=audio-dft) 06:27:20 INFO - PROCESS | 1907 | RSS: Main: 255557632 06:27:20 INFO - PROCESS | 1907 | 06:27:24 INFO - PROCESS | 1907 | Cycle 1(1): loaded http://localhost:53544/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53544/tests/kraken/driver.html?testName=audio-fft) 06:27:24 INFO - PROCESS | 1907 | RSS: Main: 257880064 06:27:24 INFO - PROCESS | 1907 | 06:27:26 INFO - PROCESS | 1907 | Cycle 1(1): loaded http://localhost:53544/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53544/tests/kraken/driver.html?testName=audio-oscillator) 06:27:27 INFO - PROCESS | 1907 | RSS: Main: 260161536 06:27:27 INFO - PROCESS | 1907 | 06:27:29 INFO - PROCESS | 1907 | Cycle 1(1): loaded http://localhost:53544/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53544/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:27:30 INFO - PROCESS | 1907 | RSS: Main: 260845568 06:27:30 INFO - PROCESS | 1907 | 06:27:33 INFO - PROCESS | 1907 | Cycle 1(1): loaded http://localhost:53544/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53544/tests/kraken/driver.html?testName=imaging-darkroom) 06:27:33 INFO - PROCESS | 1907 | RSS: Main: 266878976 06:27:33 INFO - PROCESS | 1907 | 06:27:37 INFO - PROCESS | 1907 | Cycle 1(1): loaded http://localhost:53544/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53544/tests/kraken/driver.html?testName=imaging-desaturate) 06:27:37 INFO - PROCESS | 1907 | RSS: Main: 265904128 06:27:37 INFO - PROCESS | 1907 | 06:27:40 INFO - PROCESS | 1907 | Cycle 1(1): loaded http://localhost:53544/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53544/tests/kraken/driver.html?testName=json-parse-financial) 06:27:41 INFO - PROCESS | 1907 | RSS: Main: 265773056 06:27:41 INFO - PROCESS | 1907 | 06:27:42 INFO - PROCESS | 1907 | Cycle 1(1): loaded http://localhost:53544/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53544/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:27:43 INFO - PROCESS | 1907 | RSS: Main: 265424896 06:27:43 INFO - PROCESS | 1907 | 06:27:45 INFO - PROCESS | 1907 | Cycle 1(1): loaded http://localhost:53544/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53544/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:27:46 INFO - PROCESS | 1907 | RSS: Main: 265412608 06:27:46 INFO - PROCESS | 1907 | 06:27:48 INFO - PROCESS | 1907 | Cycle 1(1): loaded http://localhost:53544/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53544/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:27:48 INFO - PROCESS | 1907 | RSS: Main: 265433088 06:27:48 INFO - PROCESS | 1907 | 06:27:51 INFO - PROCESS | 1907 | Cycle 1(1): loaded http://localhost:53544/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53544/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:27:52 INFO - PROCESS | 1907 | RSS: Main: 265785344 06:27:52 INFO - PROCESS | 1907 | 06:27:55 INFO - PROCESS | 1907 | Cycle 1(1): loaded http://localhost:53544/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53544/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:27:56 INFO - PROCESS | 1907 | RSS: Main: 265592832 06:27:56 INFO - PROCESS | 1907 | 06:27:57 INFO - PROCESS | 1907 | Cycle 1(1): loaded http://localhost:53544/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53544/tests/kraken/driver.html?testName=ai-astar) 06:27:58 INFO - PROCESS | 1907 | RSS: Main: 267661312 06:27:58 INFO - PROCESS | 1907 | 06:27:58 INFO - PROCESS | 1907 | __start_tp_report 06:27:58 INFO - PROCESS | 1907 | _x_x_mozilla_page_load 06:27:58 INFO - PROCESS | 1907 | _x_x_mozilla_page_load_details 06:27:58 INFO - PROCESS | 1907 | |i|pagename|runs| 06:27:58 INFO - PROCESS | 1907 | |0;ai-astar;104;116;118;117;132;171;158;117;117;118 06:27:58 INFO - PROCESS | 1907 | |1;audio-beat-detection;152;117;115;173;145;104;143;141;164;115 06:27:58 INFO - PROCESS | 1907 | |2;audio-dft;208;236;255;199;197;199;196;194;226;228 06:27:58 INFO - PROCESS | 1907 | |3;audio-fft;77;74;50;98;78;87;50;74;88;54 06:27:58 INFO - PROCESS | 1907 | |4;audio-oscillator;87;87;108;100;103;103;83;72;55;80 06:27:58 INFO - PROCESS | 1907 | |5;imaging-gaussian-blur;90;88;121;135;89;91;105;134;91;91 06:27:58 INFO - PROCESS | 1907 | |6;imaging-darkroom;96;108;149;106;150;150;118;149;145;97 06:27:58 INFO - PROCESS | 1907 | |7;imaging-desaturate;91;97;91;94;91;91;92;92;94;90 06:27:58 INFO - PROCESS | 1907 | |8;json-parse-financial;63;62;56;55;60;63;64;64;65;62 06:27:58 INFO - PROCESS | 1907 | |9;json-stringify-tinderbox;70;74;67;78;70;68;68;72;71;71 06:27:58 INFO - PROCESS | 1907 | |10;stanford-crypto-aes;89;91;94;92;90;93;90;95;90;92 06:27:58 INFO - PROCESS | 1907 | |11;stanford-crypto-ccm;145;170;148;134;149;145;158;161;211;162 06:27:58 INFO - PROCESS | 1907 | |12;stanford-crypto-pbkdf2;171;179;176;176;172;175;171;166;179;177 06:27:58 INFO - PROCESS | 1907 | |13;stanford-crypto-sha256-iterative;69;70;70;67;70;69;66;67;73;81 06:27:58 INFO - PROCESS | 1907 | __end_tp_report 06:27:58 INFO - PROCESS | 1907 | __start_cc_report 06:27:58 INFO - PROCESS | 1907 | _x_x_mozilla_cycle_collect,978 06:27:58 INFO - PROCESS | 1907 | __end_cc_report 06:27:58 INFO - PROCESS | 1907 | __startTimestamp1486736878195__endTimestamp 06:27:58 INFO - PROCESS | 1907 | 06:27:58 INFO - PROCESS | 1907 | ------- Summary: start ------- 06:27:58 INFO - PROCESS | 1907 | Number of tests: 14 06:27:58 INFO - PROCESS | 1907 | 06:27:58 INFO - PROCESS | 1907 | [#0] ai-astar Cycles:10 Average:126.80 Median:118.00 stddev:21.17 (17.9%) stddev-sans-first:20.78 06:27:58 INFO - PROCESS | 1907 | Values: 104.0 116.0 118.0 117.0 132.0 171.0 158.0 117.0 117.0 118.0 06:27:58 INFO - PROCESS | 1907 | 06:27:58 INFO - PROCESS | 1907 | [#1] audio-beat-detection Cycles:10 Average:136.90 Median:144.00 stddev:23.15 (16.1%) stddev-sans-first:23.90 06:27:58 INFO - PROCESS | 1907 | Values: 152.0 117.0 115.0 173.0 145.0 104.0 143.0 141.0 164.0 115.0 06:27:58 INFO - PROCESS | 1907 | 06:27:58 INFO - PROCESS | 1907 | [#2] audio-dft Cycles:10 Average:213.80 Median:217.00 stddev:21.09 (9.7%) stddev-sans-first:22.27 06:27:58 INFO - PROCESS | 1907 | Values: 208.0 236.0 255.0 199.0 197.0 199.0 196.0 194.0 226.0 228.0 06:27:58 INFO - PROCESS | 1907 | 06:27:58 INFO - PROCESS | 1907 | [#3] audio-fft Cycles:10 Average:73.00 Median:77.50 stddev:16.69 (21.5%) stddev-sans-first:17.64 06:27:58 INFO - PROCESS | 1907 | Values: 77.0 74.0 50.0 98.0 78.0 87.0 50.0 74.0 88.0 54.0 06:27:58 INFO - PROCESS | 1907 | 06:27:58 INFO - PROCESS | 1907 | [#4] audio-oscillator Cycles:10 Average:87.80 Median:76.00 stddev:16.43 (21.6%) stddev-sans-first:17.42 06:27:58 INFO - PROCESS | 1907 | Values: 87.0 87.0 108.0 100.0 103.0 103.0 83.0 72.0 55.0 80.0 06:27:58 INFO - PROCESS | 1907 | 06:27:58 INFO - PROCESS | 1907 | [#5] imaging-gaussian-blur Cycles:10 Average:103.50 Median:89.50 stddev:19.24 (21.5%) stddev-sans-first:19.78 06:27:58 INFO - PROCESS | 1907 | Values: 90.0 88.0 121.0 135.0 89.0 91.0 105.0 134.0 91.0 91.0 06:27:58 INFO - PROCESS | 1907 | 06:27:58 INFO - PROCESS | 1907 | [#6] imaging-darkroom Cycles:10 Average:126.80 Median:149.50 stddev:23.79 (15.9%) stddev-sans-first:22.47 06:27:58 INFO - PROCESS | 1907 | Values: 96.0 108.0 149.0 106.0 150.0 150.0 118.0 149.0 145.0 97.0 06:27:58 INFO - PROCESS | 1907 | 06:27:58 INFO - PROCESS | 1907 | [#7] imaging-desaturate Cycles:10 Average:92.30 Median:92.00 stddev:2.11 (2.3%) stddev-sans-first:2.19 06:27:58 INFO - PROCESS | 1907 | Values: 91.0 97.0 91.0 94.0 91.0 91.0 92.0 92.0 94.0 90.0 06:27:58 INFO - PROCESS | 1907 | 06:27:58 INFO - PROCESS | 1907 | [#8] json-parse-financial Cycles:10 Average:61.40 Median:63.00 stddev:3.41 (5.4%) stddev-sans-first:3.56 06:27:58 INFO - PROCESS | 1907 | Values: 63.0 62.0 56.0 55.0 60.0 63.0 64.0 64.0 65.0 62.0 06:27:58 INFO - PROCESS | 1907 | 06:27:58 INFO - PROCESS | 1907 | [#9] json-stringify-tinderbox Cycles:10 Average:70.90 Median:71.00 stddev:3.25 (4.6%) stddev-sans-first:3.43 06:27:58 INFO - PROCESS | 1907 | Values: 70.0 74.0 67.0 78.0 70.0 68.0 68.0 72.0 71.0 71.0 06:27:58 INFO - PROCESS | 1907 | 06:27:58 INFO - PROCESS | 1907 | [#10] stanford-crypto-aes Cycles:10 Average:91.60 Median:92.00 stddev:1.96 (2.1%) stddev-sans-first:1.83 06:27:58 INFO - PROCESS | 1907 | Values: 89.0 91.0 94.0 92.0 90.0 93.0 90.0 95.0 90.0 92.0 06:27:58 INFO - PROCESS | 1907 | 06:27:58 INFO - PROCESS | 1907 | [#11] stanford-crypto-ccm Cycles:10 Average:158.30 Median:159.50 stddev:21.27 (13.3%) stddev-sans-first:22.01 06:27:58 INFO - PROCESS | 1907 | Values: 145.0 170.0 148.0 134.0 149.0 145.0 158.0 161.0 211.0 162.0 06:27:58 INFO - PROCESS | 1907 | 06:27:58 INFO - PROCESS | 1907 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.20 Median:176.00 stddev:4.13 (2.3%) stddev-sans-first:4.22 06:27:58 INFO - PROCESS | 1907 | Values: 171.0 179.0 176.0 176.0 172.0 175.0 171.0 166.0 179.0 177.0 06:27:58 INFO - PROCESS | 1907 | 06:27:58 INFO - PROCESS | 1907 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.20 Median:70.00 stddev:4.29 (6.1%) stddev-sans-first:4.53 06:27:58 INFO - PROCESS | 1907 | Values: 69.0 70.0 70.0 67.0 70.0 69.0 66.0 67.0 73.0 81.0 06:27:58 INFO - PROCESS | 1907 | -------- Summary: end -------- 06:27:58 INFO - PROCESS | 1907 | 06:27:59 INFO - TEST-INFO | 1907: exit 0 06:27:59 INFO - TEST-OK | kraken | took 62101ms 06:27:59 INFO - SUITE-END | took 1178s 06:27:59 INFO - Completed test suite (00:19:38) 06:27:59 INFO - javascript benchmark 06:27:59 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7143.373609609869, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2845.72965568271, 3291.9506207406894, 3316.403047451458, 3323.5014747787827, 3370.4944258361243, 5525.972370138149, 5546.278983732119, 5547.861303467415, 5576.219329293892, 5616.185653080312, 5513.338399392069, 5597.552195824334, 5629.4652008059165, 5638.464345887144, 5646.378898321182, 5048.419431765348, 5104.2088476286235, 5110.667806592578, 5147.253648221005, 5148.433672296044, 5141.126008578551, 5153.793848246066, 5214.973925130369, 5224.268602395668, 5271.182966640177, 4979.0, 4997.85006449807, 5049.192129259301, 5145.073886700392, 5178.766955487012, 5072.594192464594, 5140.948590514085, 5161.948380516222, 5166.638335316529, 5190.896182076375, 4115.773632450214, 5064.215046667736, 5072.112380333427, 5076.74616269185, 5106.336176297058, 5416.943695979286, 5451.509364157245, 5464.153056276284, 5600.691961942091, 5630.521405680522, 5272.420033796279, 5998.610090344114, 6073.574849760518, 6109.586862300694, 6136.76416992428, 6217.533684973645, 6237.345078766755, 6240.188775459206, 6265.248170219557, 6266.154069200691, 5787.913181302284, 5813.505852002585, 5816.272965879265, 5822.0684690449325, 5861.794837180721, 6072.787452439144, 6174.135621013062, 6179.443850053427, 6214.9689251553455, 6230.314665386803, 5874.412558744177, 5929.0, 6114.388610881752, 6117.265928088569, 6140.078988151813, 6181.81454556364, 6207.472364848948, 6224.128621992924, 6261.279952805395, 6276.6547839869245, 6125.081237814364, 6188.752449902044, 6221.5333849961435, 6225.501959843202, 6237.4698150657705, 5976.820695379146, 6040.758369665252, 6105.247473117015, 6260.812175634738, 6269.0, 4853.19922212838, 4891.364122664102, 4930.482299358516, 4966.0, 4974.204127339681, 4407.140607581491, 4415.448068991376, 4415.735055896656, 4433.1577000369825, 4459.910801783946, 5511.173324001432, 5557.610967232255, 5671.914921276184, 5731.742071606787, 5739.512141468022, 6141.416565426277, 6147.815565533041, 6152.600080994811, 6245.156903818017, 6246.531510136758, 5659.292588426447, 5708.20085188074, 5709.857253568611, 5725.456081672234, 5743.282089738783, 5197.29836472079, 5353.785848566092, 5394.514493695587, 5404.378496472956, 5437.918431223535, 6184.969075154595, 6186.257649082049, 6216.2540495140875, 6232.25212974446, 6285.308616052139, 6204.5967012144065, 6235.127082208403, 6236.75052997875, 6258.280297765815, 6261.812145635638, 5226.425093239816, 5532.004239236976, 6129.448349648553, 6206.689665516616, 6287.874242515216, 4876.21980483121, 5908.350081490947, 6104.004449388181, 6125.540584456095, 6153.077038444269, 5132.0, 6206.565540412128, 6232.501399888089, 6257.342978987141, 6284.685765711788, 4645.721256724607, 5887.911681324869, 6038.0, 6049.909251361321, 6069.2110025696375, 4944.505549445027, 6047.818565443044, 6059.666718330358, 6126.120738204555, 6165.475934545614, 5270.657407268515, 5661.264035675501, 5927.229460170151, 6000.069989151689, 6043.667598281961, 5808.6805225711305, 6103.481204097524, 6131.141640170469, 6132.78535251264, 6141.263048434216, 5727.39862314451, 5780.479756821906, 5799.652020878761, 5810.215620891295, 5895.29256489224], "value": 5587.882181359719, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [445.29865461897515, 479.84165225475596, 487.31532197262845, 488.78249179115306, 528.5032069854336, 38256.42615360768, 39942.60057399425, 40178.99999999996, 40275.39586906199, 40403.54552135461, 40524.189516209655, 41023.58976410235, 41080.0, 41106.38340424889, 41227.58772412282, 9174.311926605482, 9355.391899526543, 9380.8592871107, 9428.05719428055, 10775.461226938662, 10455.895441045606, 10790.244682872202, 10873.83689244662, 10878.129749620051, 10905.454727263645, 10757.515911783988, 10849.783004339908, 10850.945745271263, 10866.293690910104, 10866.695996480434, 10638.148948084136, 10672.946635266851, 10689.78620427591, 10694.679159625224, 10770.892291077145, 8963.507007114606, 10633.361998280128, 10754.516046777913, 10762.300450470695, 10780.0, 35621.82189089038, 35812.82093589541, 35941.28117437663, 35946.64053359459, 35991.4601280981, 3710.684591809699, 3737.9439308410397, 3759.0, 3759.154190307186, 3858.6913046956174, 27182.5922611161, 27586.448271034467, 27637.30906727328, 27639.585406218925, 27677.86161069202, 2554.118829003991, 2560.1950624809465, 2565.7819085377764, 2616.9607455888176, 2711.5932610108443, 428134.0, 429751.0, 429870.0, 431292.0, 433234.0, 45771.0, 47043.5295647046, 52879.47120528745, 53030.46969530333, 53418.0, 55417.614559636335, 55974.440255597736, 56355.43644563584, 58865.411345885994, 58982.11526827101, 14179.929100354639, 14280.285985700673, 14328.641783955485, 14335.92832035854, 14370.784438233435, 9332.626694932262, 9404.388714733657, 11081.002709756161, 11097.38964356968, 11248.212625116163, 16901.661966760596, 16999.660006799793, 17020.638348932054, 17032.744508832377, 17169.7424538632, 6476.996065609838, 7133.215346311901, 7587.468877178655, 7634.351080158249, 7641.579713115832, 775.964087942603, 776.5418403142173, 824.4929368438367, 839.9790005249796, 846.170752662382, 27545.173644790688, 28753.712462875523, 28913.0, 28972.27569310742, 29651.55522667162, 992.4938281476499, 1013.9290249682451, 1019.0879163148941, 1020.6835880876952, 1028.99485502572, 13999.860001399855, 15151.924240378728, 15459.072455652697, 15526.844731552539, 15527.84472155264, 549261.0, 551622.0, 552138.2393088009, 554880.0, 555707.0, 46233.0, 56397.0, 57976.55058623484, 58012.70993645005, 58054.709726451096, 1296.280564286811, 1361.584716661408, 1361.6800051987775, 1361.9455221791216, 1362.0942073520998, 27511.17466476009, 28238.294042649097, 28966.565501517915, 28995.7100428993, 29317.85341073281, 48091.27863081986, 48378.5162148374, 48617.0, 48769.0, 48889.75555122201, 477893.0, 480407.0, 481782.0, 483522.0, 485267.0, 25137.371565711004, 25949.870250649383, 26208.475830483665, 26233.475330493664, 26295.238999904734, 39513.407298889964, 43491.347629786214, 44650.0, 45730.0, 45777.0, 36581.817090914374, 36711.08222294464, 37551.81224093862, 37619.0, 38007.619923800405, 30150.0, 36548.451773223955, 37569.0, 37626.0, 37832.62167378291, 35446.93659190228, 35581.28837423185, 36112.0, 36125.63874361328, 36433.817830911736, 31539.36921261608, 32892.671073289916, 33239.6704131832, 33418.0, 33477.83261083679, 23589.466684665444, 24273.577355001235, 24504.0, 24656.165040780168, 24662.01351945974], "value": 18937.23576245151, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [388.7958821618648, 388.82114227455367, 396.5776448082792, 399.4387885021544, 422.6407553579456, 13702.794458083112, 18092.90953545232, 19441.805581944176, 19726.210951561938, 19745.605087898235, 20196.263691217137, 20253.39239822804, 20331.796682033175, 20456.284030058952, 20543.075561599726, 5557.6109672322955, 5767.221425107598, 5784.3058832940105, 5837.24115864937, 5967.283925928895, 5572.972135139339, 5817.185594016841, 5882.735276912549, 5926.0, 5934.64392136471, 4674.415698037746, 5784.0, 5800.506956908646, 5806.187133801272, 5808.970955145219, 5424.430434804368, 5709.457601527848, 5790.449907258803, 5795.536357091443, 5811.157382179603, 5061.468545802712, 5064.0, 5086.745662716849, 5090.032893750175, 5090.720010399426, 20832.37502874916, 21400.35798926035, 23113.53772924549, 23620.17329393463, 24389.63415548768, 2609.4502797977025, 2650.112212408845, 2651.4564514274525, 2683.490136873988, 2684.3691732442862, 13944.790828137484, 14361.928190359085, 14530.92734536331, 14601.466845981126, 15066.322015509328, 1780.1099450274862, 1788.8926664400178, 1806.2955447375534, 1806.683830329687, 1828.2686925229882, 118656.0, 119611.0, 120054.39972800168, 120628.39685801603, 120726.0, 11117.444127793578, 11120.277181983145, 11341.376224307578, 11344.092472602171, 11415.143864210051, 12148.331841748788, 12700.301483418316, 14064.226467544178, 14086.647833804032, 14096.08375455592, 7675.2324767522805, 7988.201179882081, 8050.798730031796, 8069.515829050159, 8118.878216826753, 6412.97392417211, 7891.447598668153, 7919.7624071276805, 8060.153683863227, 8077.192280771993, 5972.761089556369, 6724.293949135368, 6870.519063665595, 6919.688614012281, 6944.826379340557, 1793.8116497767808, 1977.7132315814126, 1985.960280794376, 1990.0, 1991.3826713718793, 995.4375777685584, 1000.1498726082772, 1011.7015480433142, 1012.448215722433, 1047.4919663962971, 15467.690646187239, 17321.826781732274, 18722.833511338624, 19324.8067519323, 20255.392338229878, 1020.1023099672241, 1023.2172388123092, 1037.5642230263309, 1057.259918057363, 1057.6139709006156, 10010.399376037461, 10247.436390998417, 10745.409002504937, 10765.946170269099, 10801.0, 543183.0, 547972.0, 554112.0, 621996.0, 667743.0, 141513.58486415003, 143578.0, 144349.0, 144594.0, 145227.0, 997.6209040564539, 1014.2798612984716, 1041.9270651054353, 1042.5464922758472, 1043.6295115234134, 17479.038652874213, 17522.73715894288, 17718.379856704953, 18343.54141146482, 18831.058447077867, 54217.72891135519, 54534.45465545294, 54913.0, 55201.44798551963, 55652.44347556472, 73354.0, 73480.16299592552, 82645.0, 84130.0, 84133.0, 7564.6761816682965, 7967.521948683097, 8015.0, 8245.464044837066, 8374.330053595819, 9905.900940990787, 12355.3822308886, 12624.55814046504, 12628.0, 12747.107702460738, 10690.465476725976, 10834.945825270823, 11014.229003969645, 11058.281211721212, 11078.169137314895, 10453.42506162138, 10532.157427405637, 10632.787344253225, 10907.454627268764, 10944.288621239593, 10735.516901739597, 10860.837087443853, 10880.564777408816, 11042.558297668325, 11084.113270938144, 7301.561906285639, 7918.920810791818, 8171.79570510742, 8338.62476188585, 8541.444806087584, 9407.952960235156, 10721.249512534048, 10888.782224355627, 10911.0, 11024.228304018641], "value": 9639.210604031296, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1299.4867027524126, 1380.413324337157, 1404.54352335491, 1413.6677880698041, 1417.7660685986812, 8174.26431621154, 8229.835403291945, 8510.70212542561, 8532.872006919893, 8626.65493380264, 8379.455335403194, 8480.406371553994, 8507.787305317355, 8515.0, 8521.701740439086, 1882.1812511557482, 2238.708967834187, 2252.144185209618, 2255.8533695309834, 2259.039908039085, 2232.073689418889, 2264.1735766445295, 2267.943301417461, 2267.9433014174697, 2269.6595510673365, 2227.231605096247, 2230.810381117607, 2254.7407048189502, 2256.7630398808137, 2261.231181398324, 2158.6006588781124, 2220.6335954567476, 2220.8334374921783, 2244.663300504921, 2246.5057687308768, 1659.0, 1690.8816382853206, 2233.2965115988413, 2243.6073687104854, 2269.716285464317, 8331.95834020832, 8485.533295668736, 8526.190011948916, 8634.222919937236, 8646.827063458695, 5024.296598476216, 5179.922301165486, 5181.0, 5193.740312984336, 5203.583713302926, 3395.151212196951, 3451.3960056989927, 3466.584009918826, 3482.8606855725993, 3530.258645684409, 1056.2078441169124, 1065.2330322168027, 1120.019982515299, 1186.5846953566183, 1236.097648716433, 10856.022957933745, 10897.182711296606, 10977.670669879837, 10989.285696429706, 11012.559497620086, 4093.467849179647, 4178.059936514321, 4301.376160542067, 4377.846775362911, 4404.339349097597, 8695.913040869636, 8726.432781869158, 8749.256313213447, 8768.95615521931, 8780.473171609596, 5007.173816320339, 5040.49595040493, 5041.395032596112, 5045.7981680732355, 5049.343585333957, 3739.775613463201, 3791.450239715226, 4520.299353600197, 4662.090892276042, 4710.646701497401, 3614.475900994341, 3631.727620428426, 3657.8719744808805, 3665.633436656313, 3689.575698794673, 1147.17976646698, 1148.6381789736206, 1157.455995682028, 1158.368689064462, 1161.2625982501004, 866.9653213871501, 873.7902903303161, 884.9734507964642, 902.9909700903038, 914.7210100919284, 5412.107002344569, 5697.316322041382, 5717.456841600041, 5823.184754134425, 5888.205092312569, 947.5357075033185, 958.6405098088217, 960.9951950240204, 977.9315447918719, 1014.7108074198966, 3701.833417496165, 3790.8673196438544, 3833.3059525764893, 3873.0511024799102, 3874.6319099685484, 9708.417494950325, 9962.153216976638, 10049.799004020026, 10070.647527336509, 10086.445245511568, 9983.251256155672, 9987.400755954666, 10011.79976400468, 10021.599136034478, 10027.649032283834, 7709.0, 7755.146933837386, 7828.882566761503, 7971.681132754625, 8025.398095142771, 5342.5418750495655, 5426.538744206787, 5487.6981766003255, 5599.468050535193, 5676.545876329967, 7190.7483238086415, 8118.918810811816, 8538.060813310407, 9241.537923103684, 9280.0, 9112.179903808688, 9117.22503587176, 9325.53372331367, 9458.196053335543, 9459.337846350689, 6613.867722645617, 6733.730650773914, 6820.897686534607, 6825.180978282638, 6832.692528836314, 7849.764507064797, 7869.685212591432, 7921.32668723165, 7948.761537153895, 7955.602219889098, 6567.27759946396, 6712.0, 7135.215126336203, 7153.785386438415, 7179.317964793337, 6926.272741362286, 7009.929900700928, 7065.258147894397, 7070.46971477131, 7102.289771022856, 6320.5891617044745, 6409.935900640934, 6469.288378278292, 6473.643949582818, 6484.059811327425, 4882.414110306786, 4958.83467385157, 4960.751962401938, 4999.175136102648, 5147.485251474823, 4944.233643785219, 4970.378702662168, 5047.545720885138, 5140.691558506502, 5219.269302297757], "value": 4335.246091262124, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [294.606700055426, 308.3247687564235, 311.2312587907868, 357.15889081213743, 374.1898789121552, 5094.872628184297, 5411.972940135299, 5474.890502189954, 5526.751296191671, 5528.5024347808685, 4914.803407863681, 5436.728163591814, 5443.700596467193, 5492.231087647732, 5501.477359650827, 3444.689977901988, 3583.713302935772, 3908.5505166905814, 4052.452918855961, 4104.630583247522, 3509.754037316754, 4049.2104039712267, 4099.8770036898795, 4116.444280022203, 4145.590499230261, 3675.9448608270895, 3731.776093434389, 3975.5626881043063, 4142.46148000761, 4144.48193975753, 3435.5570660322724, 3806.771593704359, 4066.451029111062, 4111.424400583921, 4134.689898257628, 3249.0, 3494.9864539283576, 3502.667246611568, 3529.4176460883923, 3573.6426357364317, 4284.335927931144, 4538.636909047268, 5130.127878260705, 5293.444188360244, 5311.973440132813, 4409.955900440987, 4647.4895658910655, 4706.082313948781, 4725.740084295362, 4751.809927602892, 5329.734862683344, 5690.032694441913, 5710.77156913723, 5715.599908006441, 5725.370209276976, 3899.337112690849, 3913.6673382762215, 3914.178022615254, 3920.7059470539825, 3927.7839718815453, 5316.6872410501555, 5738.799142030009, 5739.454751798614, 5999.820005399845, 6013.602314692185, 3721.7953012584567, 3763.8332117043806, 3777.9244415111543, 3873.3608954522188, 3915.060385507458, 3953.892909985209, 3981.551345922295, 3992.8819930419527, 3995.580464051233, 4003.2994226010333, 4624.329472226508, 4645.233536466445, 4648.558386953234, 4689.835855745033, 4697.318888761179, 4979.850604481799, 4986.4514903360605, 5393.7303134843105, 5428.375736790241, 5764.452377024176, 1123.3035517978906, 1290.4128621477205, 1291.1284882704324, 1305.8443277699114, 1314.6065170918857, 831.6257684042205, 867.4751775176054, 875.9969834539417, 885.526243459746, 887.2946007923587, 4529.909401811945, 4550.29470432077, 4553.908921821545, 5073.315102461121, 5104.3109180260935, 5323.907660356237, 5646.571974051198, 5700.828975130753, 5721.508615736627, 5742.684152371659, 4355.972599043697, 4595.425571803525, 4909.582685471775, 5098.974505127525, 5162.5611822994715, 2681.825681330707, 2919.357741296911, 2968.807027543203, 2985.5072463768115, 3014.427258820817, 5797.840431913634, 5813.0, 5831.7375718091935, 5845.941540584625, 5933.288005439289, 5256.738382788103, 5347.41178470368, 5430.70131142791, 5535.533083732814, 5754.367019627837, 5409.891802164014, 5562.720574267941, 5742.6504771378695, 5943.821685349447, 6031.306399763907, 4923.581495572773, 5461.399246083007, 5781.947408669862, 5859.677717725566, 5910.349861515316, 4978.605990322715, 5594.692291923983, 5681.642118520767, 5717.828465146053, 5809.419058094157, 5236.9738151309, 5869.295684517885, 6000.429959153874, 6064.938635738817, 6126.479249263684, 3358.354406340924, 3879.340512113004, 3884.9417258740577, 3890.9805450972563, 3905.2775236581324, 5524.91712624319, 5851.502622277154, 5892.467958330951, 5906.228131560601, 5940.376260492586, 5171.431142574389, 5308.814191503279, 5318.441563635917, 5600.767831077156, 5697.544196464356, 5331.893362132658, 5555.138953462219, 5699.7435115419075, 5715.485606295453, 5786.218860453785, 5160.258812361159, 5455.208994695826, 5559.8888022240135, 5563.609097725569, 5584.609077364546, 5013.674111182761, 5472.20653005304, 5508.44915508446, 5671.546276297804, 5704.458076482728, 4479.0, 4781.402324709411, 5128.794848206179, 5197.856471576247, 5201.531862132426], "value": 4101.597028214035, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [568.4003376437862, 612.5681394616793, 633.4394061255787, 802.9558374289414, 854.0690647194558, 14194.0, 14671.339789709462, 15169.848301516982, 15172.924135379335, 15213.543593692179, 12227.0, 12813.807792883092, 12946.964242860573, 12989.675258118552, 13754.587362379145, 864.2912811494562, 876.5617191404298, 884.5135175653397, 887.219247062584, 889.1641856654757, 849.7748096754365, 881.9955900220522, 888.7156110044788, 895.0870112485294, 899.4962820820361, 902.6975963052386, 917.4816228830723, 933.3280038372361, 936.7002559181064, 938.1134827587932, 816.2531283875247, 832.0514613340796, 923.9076092390775, 927.2998885841214, 929.1962452478591, 563.4478211352897, 665.7536711416807, 674.5244602555235, 680.8536164724559, 686.8282929267683, 14542.34559444817, 14956.0, 15040.398384064625, 15462.690746185013, 15511.069335839773, 13587.796183057262, 13808.861911380858, 13866.237356945365, 13918.30408479572, 13935.094218875842, 11445.427728613618, 11538.499766427123, 11709.414529273587, 11771.88228117725, 11934.28394296345, 3590.5152804371337, 3632.110133017401, 3633.818309084562, 3653.8355773990234, 3782.111203867089, 74579.62710186414, 104557.0, 106085.0, 107179.46410267899, 107687.46156269246, 10345.224108191916, 10583.84124238137, 10736.08743256832, 11055.447227638586, 11226.943865280622, 21359.038843252092, 21398.893005535185, 21621.891890540763, 21824.563508729738, 21927.561448770935, 7528.548287102792, 7594.734184303523, 7617.961910190414, 7682.615869206629, 7759.767206983686, 4130.173965206911, 4133.524644665842, 4134.64855487281, 4136.4415803866095, 4154.4183814266025, 3551.3785087609567, 3646.4894914711963, 3694.07648087978, 3696.815159242081, 3698.981505092457, 671.392389887153, 676.6887231873393, 680.618853442074, 681.1655721740827, 698.3714656808912, 650.5836264790538, 685.8456847209437, 688.0161369242032, 694.600604652327, 727.2327694282477, 9846.310758246855, 10044.347117437343, 10072.496375181212, 10115.190784737204, 10338.586456541805, 657.5035847934779, 694.8803234208292, 694.9146678396888, 704.503325155764, 707.3951771235622, 4702.4592171899985, 4934.515675341392, 4943.728094954812, 4943.826966056171, 4951.455339912607, 78774.42451150945, 81298.59350703326, 83149.0, 99966.00033999712, 101360.0, 57928.71035644879, 71873.6406317965, 73297.2670273301, 86886.0, 91093.0, 50588.241176382384, 55474.72262638661, 58310.416895831346, 70990.0, 71303.64348178226, 8283.917160828556, 10300.433476158883, 10634.946825265824, 10737.248392612442, 10950.945245273722, 42952.92617684583, 50451.243231350825, 51594.484055158966, 51781.0, 52494.73752631212, 60706.39293607007, 66744.0, 70736.64631676809, 71130.64434677793, 72413.63793181001, 12304.200226985216, 13735.24456154881, 14166.78749818733, 14289.499867504588, 14311.141331520143, 14262.928685356506, 14312.06971546846, 14516.419343226153, 14535.636609084857, 14803.333849976992, 13334.26661533625, 13340.533081342106, 13513.80962341, 13794.517191898236, 13925.0, 12197.597276312983, 13366.398512066786, 13644.454221831413, 13815.240161791198, 14101.858981410054, 10460.476976151314, 10980.835287470854, 11536.134789890624, 13288.335583220995, 13416.0, 8159.755207343789, 8514.957425212835, 10137.391756494633, 10143.289969702346, 10397.11624511925, 9221.769455763659, 10051.047877325756, 10247.771951835148, 11569.942150289195, 11619.651410457362], "value": 7324.903067502321, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1587.4999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 116.0, 118.0, 117.0, 132.0, 171.0, 158.0, 117.0, 117.0, 118.0], "value": 126.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 117.0, 115.0, 173.0, 145.0, 104.0, 143.0, 141.0, 164.0, 115.0], "value": 136.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [208.0, 236.0, 255.0, 199.0, 197.0, 199.0, 196.0, 194.0, 226.0, 228.0], "value": 213.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 74.0, 50.0, 98.0, 78.0, 87.0, 50.0, 74.0, 88.0, 54.0], "value": 73.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 87.0, 108.0, 100.0, 103.0, 103.0, 83.0, 72.0, 55.0, 80.0], "value": 87.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 88.0, 121.0, 135.0, 89.0, 91.0, 105.0, 134.0, 91.0, 91.0], "value": 103.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 108.0, 149.0, 106.0, 150.0, 150.0, 118.0, 149.0, 145.0, 97.0], "value": 126.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 97.0, 91.0, 94.0, 91.0, 91.0, 92.0, 92.0, 94.0, 90.0], "value": 92.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 56.0, 55.0, 60.0, 63.0, 64.0, 64.0, 65.0, 62.0], "value": 61.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 74.0, 67.0, 78.0, 70.0, 68.0, 68.0, 72.0, 71.0, 71.0], "value": 70.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 91.0, 94.0, 92.0, 90.0, 93.0, 90.0, 95.0, 90.0, 92.0], "value": 91.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 170.0, 148.0, 134.0, 149.0, 145.0, 158.0, 161.0, 211.0, 162.0], "value": 158.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 179.0, 176.0, 176.0, 172.0, 175.0, 171.0, 166.0, 179.0, 177.0], "value": 174.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 70.0, 70.0, 67.0, 70.0, 69.0, 66.0, 67.0, 73.0, 81.0], "value": 70.2, "unit": "score"}]}]} 06:27:59 INFO - Return code: 0 06:27:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:27:59 ERROR - # TBPL SUCCESS # 06:27:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:27:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:27:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:27:59 INFO - 2017-02-10 06:27:59.499 screenresolution[1912:67250] starting screenresolution argv=screenresolution get 06:27:59 INFO - 2017-02-10 06:27:59.502 screenresolution[1912:67250] Display 0: 1600x1200x32@60 06:27:59 INFO - 2017-02-10 06:27:59.508 screenresolution[1913:67253] starting screenresolution argv=screenresolution list 06:27:59 INFO - Available Modes on Display 0 06:27:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:27:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:27:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:27:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:27:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:27:59 INFO - Intel Iris: 06:27:59 INFO - Chipset Model: Intel Iris 06:27:59 INFO - Type: GPU 06:27:59 INFO - Bus: Built-In 06:27:59 INFO - VRAM (Dynamic, Max): 1536 MB 06:27:59 INFO - Vendor: Intel (0x8086) 06:27:59 INFO - Device ID: 0x0a2e 06:27:59 INFO - Revision ID: 0x0009 06:27:59 INFO - Displays: 06:27:59 INFO - Display: 06:27:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:27:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:27:59 INFO - Display Serial Number: 1600x1200 60 06:27:59 INFO - Main Display: Yes 06:27:59 INFO - Mirror: Off 06:27:59 INFO - Online: Yes 06:27:59 INFO - Rotation: Supported 06:27:59 INFO - Return code: 0 06:27:59 INFO - Running post-action listener: _resource_record_post_action 06:27:59 INFO - [mozharness: 2017-02-10 14:27:59.745307Z] Finished run-tests step (success) 06:27:59 INFO - Running post-run listener: _resource_record_post_run 06:27:59 INFO - Total resource usage - Wall time: 1201s; CPU: 54.0%; Read bytes: 2221568; Write bytes: 558808064; Read time: 13; Write time: 1736 06:27:59 INFO - TinderboxPrint: CPU usage
54.4% 06:27:59 INFO - TinderboxPrint: I/O read bytes / time
2,221,568 / 13 06:27:59 INFO - TinderboxPrint: I/O write bytes / time
558,808,064 / 1,736 06:27:59 INFO - TinderboxPrint: CPU idle
2,191.8 (45.6%) 06:27:59 INFO - TinderboxPrint: CPU system
967.0 (20.1%) 06:27:59 INFO - TinderboxPrint: CPU user
1,647.0 (34.3%) 06:27:59 INFO - TinderboxPrint: Swap in / out
254,230,528 / 0 06:27:59 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 250573312; Write bytes: 244827648; Read time: 18171; Write time: 532 06:27:59 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 960000; Write bytes: 300525056; Read time: 10; Write time: 1159 06:28:00 INFO - Running post-run listener: _upload_blobber_files 06:28:00 INFO - Blob upload gear active. 06:28:00 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:28:00 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 06:28:00 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'] 06:28:00 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 06:28:00 INFO - (blobuploader) - INFO - Open directory for files ... 06:28:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 06:28:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:28:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:28:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 06:28:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:28:01 INFO - (blobuploader) - INFO - Done attempting. 06:28:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 06:28:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:28:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:28:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 06:28:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:28:02 INFO - (blobuploader) - INFO - Done attempting. 06:28:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 06:28:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:28:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:28:03 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 06:28:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:28:03 INFO - (blobuploader) - INFO - Done attempting. 06:28:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:28:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:28:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:28:06 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:28:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:28:06 INFO - (blobuploader) - INFO - Done attempting. 06:28:06 INFO - (blobuploader) - INFO - Iteration through files over. 06:28:06 INFO - Return code: 0 06:28:06 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:28:06 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:28:06 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e37f658fe108f0dc2dd4a0510c7f67ef3ed2d20058af25cdf54717d4922ec9826a02ca5ba98e690115858fd6f90d5f2151b66ccdc5995c18faf6375903df9420", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e4a85611854b08cb04a004a5437ffdb49de593af1fb9447e05e483290da856f23c6c8303441d4c3b45da3fc4e272dde85bf88e5417d610a07a5bd12972e71a78", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0790712244689802c4823b2de79035a4556f5e1accdb2092f83969fa535eb749c3a1a668b775cdf8c1caa4fe45daa28586542640e01831cd391ef71da2d35a76", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e7c39143bdb2edd378cdd817180706d9411691557fec223103fc4ebb63650a59ce23e21297734bcfa5d239b143984b9ab8905518411f0b8e796fea63bddb153e"} 06:28:06 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:28:06 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:28:06 INFO - Contents: 06:28:06 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e37f658fe108f0dc2dd4a0510c7f67ef3ed2d20058af25cdf54717d4922ec9826a02ca5ba98e690115858fd6f90d5f2151b66ccdc5995c18faf6375903df9420", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e4a85611854b08cb04a004a5437ffdb49de593af1fb9447e05e483290da856f23c6c8303441d4c3b45da3fc4e272dde85bf88e5417d610a07a5bd12972e71a78", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0790712244689802c4823b2de79035a4556f5e1accdb2092f83969fa535eb749c3a1a668b775cdf8c1caa4fe45daa28586542640e01831cd391ef71da2d35a76", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e7c39143bdb2edd378cdd817180706d9411691557fec223103fc4ebb63650a59ce23e21297734bcfa5d239b143984b9ab8905518411f0b8e796fea63bddb153e"} 06:28:06 INFO - Running post-run listener: copy_logs_to_upload_dir 06:28:06 INFO - Copying logs to upload dir... 06:28:06 INFO - mkdir: /builds/slave/test/build/upload/logs 06:28:06 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.550638 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2017-02-10 06:28:06.349301) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-10 06:28:06.353187) ========= 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.WWVtcnJVg2/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.ipwZYR4mIv/Listeners TMPDIR=/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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/e37f658fe108f0dc2dd4a0510c7f67ef3ed2d20058af25cdf54717d4922ec9826a02ca5ba98e690115858fd6f90d5f2151b66ccdc5995c18faf6375903df9420", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e4a85611854b08cb04a004a5437ffdb49de593af1fb9447e05e483290da856f23c6c8303441d4c3b45da3fc4e272dde85bf88e5417d610a07a5bd12972e71a78", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0790712244689802c4823b2de79035a4556f5e1accdb2092f83969fa535eb749c3a1a668b775cdf8c1caa4fe45daa28586542640e01831cd391ef71da2d35a76", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e7c39143bdb2edd378cdd817180706d9411691557fec223103fc4ebb63650a59ce23e21297734bcfa5d239b143984b9ab8905518411f0b8e796fea63bddb153e"} build_url:https://queue.taskcluster.net/v1/task/PC_Nrh8sTsmPVYDYql4p1Q/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011267 build_url: 'https://queue.taskcluster.net/v1/task/PC_Nrh8sTsmPVYDYql4p1Q/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e37f658fe108f0dc2dd4a0510c7f67ef3ed2d20058af25cdf54717d4922ec9826a02ca5ba98e690115858fd6f90d5f2151b66ccdc5995c18faf6375903df9420", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e4a85611854b08cb04a004a5437ffdb49de593af1fb9447e05e483290da856f23c6c8303441d4c3b45da3fc4e272dde85bf88e5417d610a07a5bd12972e71a78", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0790712244689802c4823b2de79035a4556f5e1accdb2092f83969fa535eb749c3a1a668b775cdf8c1caa4fe45daa28586542640e01831cd391ef71da2d35a76", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e7c39143bdb2edd378cdd817180706d9411691557fec223103fc4ebb63650a59ce23e21297734bcfa5d239b143984b9ab8905518411f0b8e796fea63bddb153e"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-10 06:28:06.386729) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-10 06:28:06.387027) ========= 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.WWVtcnJVg2/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.ipwZYR4mIv/Listeners TMPDIR=/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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.005271 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-10 06:28:06.435393) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-10 06:28:06.435675) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-10 06:28:06.435969) ========= ========= Total master_lag: 0.18 =========