builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0181 starttime: 1486942144.27 results: success (0) buildid: 20170212145140 builduid: 2310ce2ed09b4dbfb9f8039abdd02aa8 revision: 43270c88b2513a6758a0ce6669559f4a5f3c9207 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-12 15:29:04.273803) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-12 15:29:04.274524) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-12 15:29:04.274997) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-12 15:29:04.300014) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-12 15:29:04.300360) ========= 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.nPRpMuzilr/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.Sjh8mtfBV6/Listeners TMPDIR=/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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-12 15:29:04-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 13.5M=0.001s 2017-02-12 15:29:04 (13.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.465912 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-12 15:29:04.790164) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-12 15:29:04.790482) ========= 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.nPRpMuzilr/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.Sjh8mtfBV6/Listeners TMPDIR=/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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.020981 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-12 15:29:04.838919) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-12 15:29:04.839284) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 43270c88b2513a6758a0ce6669559f4a5f3c9207 --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 43270c88b2513a6758a0ce6669559f4a5f3c9207 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.nPRpMuzilr/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.Sjh8mtfBV6/Listeners TMPDIR=/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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-12 15:29:04,925 truncating revision to first 12 chars 2017-02-12 15:29:04,925 Setting DEBUG logging. 2017-02-12 15:29:04,925 attempt 1/10 2017-02-12 15:29:04,925 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/43270c88b251?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-12 15:29:05,554 unpacking tar archive at: mozilla-central-43270c88b251/testing/mozharness/ program finished with exit code 0 elapsedTime=0.905862 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-12 15:29:05.769139) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-12 15:29:05.769520) ========= script_repo_revision: 43270c88b2513a6758a0ce6669559f4a5f3c9207 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-12 15:29:05.769923) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-12 15:29:05.770199) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-12 15:29:05.789877) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2017-02-12 15:29:05.790163) ========= /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.nPRpMuzilr/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.Sjh8mtfBV6/Listeners TMPDIR=/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 15:29:05 INFO - MultiFileLogger online at 20170212 15:29:05 in /builds/slave/test 15:29:05 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 15:29:05 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:29:05 INFO - {'append_to_log': False, 15:29:05 INFO - 'base_work_dir': '/builds/slave/test', 15:29:05 INFO - 'blob_upload_branch': 'Firefox', 15:29:05 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:29:05 INFO - 'branch': 'Firefox', 15:29:05 INFO - 'buildbot_json_path': 'buildprops.json', 15:29:05 INFO - 'config_files': ('talos/mac_config.py',), 15:29:05 INFO - 'default_actions': ('clobber', 15:29:05 INFO - 'read-buildbot-config', 15:29:05 INFO - 'download-and-extract', 15:29:05 INFO - 'populate-webroot', 15:29:05 INFO - 'create-virtualenv', 15:29:05 INFO - 'install', 15:29:05 INFO - 'run-tests'), 15:29:05 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:29:05 INFO - 'download_minidump_stackwalk': True, 15:29:05 INFO - 'download_symbols': 'ondemand', 15:29:05 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:29:05 INFO - 'tooltool.py': '/tools/tooltool.py', 15:29:05 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:29:05 INFO - '/tools/misc-python/virtualenv.py')}, 15:29:05 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:29:05 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:29:05 INFO - 'installer_path': 'installer.exe', 15:29:05 INFO - 'log_level': 'info', 15:29:05 INFO - 'log_name': 'talos', 15:29:05 INFO - 'log_to_console': True, 15:29:05 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:29:05 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:29:05 INFO - 'opt_config_files': (), 15:29:05 INFO - 'pip_index': False, 15:29:05 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:29:05 INFO - 'cmd': ('bash', 15:29:05 INFO - '-c', 15:29:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:29:05 INFO - 'enabled': True, 15:29:06 INFO - 'halt_on_failure': False, 15:29:06 INFO - 'name': 'check_screen_resolution'},), 15:29:06 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:29:06 INFO - 'cmd': ('bash', 15:29:06 INFO - '-c', 15:29:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:29:06 INFO - 'enabled': True, 15:29:06 INFO - 'halt_on_failure': False, 15:29:06 INFO - 'name': 'check_screen_resolution'},), 15:29:06 INFO - 'run_cmd_checks_enabled': True, 15:29:06 INFO - 'sps_profile': False, 15:29:06 INFO - 'sps_profile_interval': 0, 15:29:06 INFO - 'suite': 'dromaeojs-e10s', 15:29:06 INFO - 'system_bits': '32', 15:29:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:29:06 INFO - 'title': 't-yosemite-r7-0181', 15:29:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:29:06 INFO - 'use_talos_json': True, 15:29:06 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:29:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:29:06 INFO - 'work_dir': 'build'} 15:29:06 INFO - [mozharness: 2017-02-12 23:29:06.002176Z] Running clobber step. 15:29:06 INFO - Running pre-action listener: _resource_record_pre_action 15:29:06 INFO - Running main action method: clobber 15:29:06 INFO - rmtree: /builds/slave/test/build 15:29:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:29:08 INFO - Running post-action listener: _resource_record_post_action 15:29:08 INFO - [mozharness: 2017-02-12 23:29:08.081451Z] Finished clobber step (success) 15:29:08 INFO - [mozharness: 2017-02-12 23:29:08.081623Z] Running read-buildbot-config step. 15:29:08 INFO - Running pre-action listener: _resource_record_pre_action 15:29:08 INFO - Running main action method: read_buildbot_config 15:29:08 INFO - Using buildbot properties: 15:29:08 INFO - { 15:29:08 INFO - "project": "", 15:29:08 INFO - "product": "firefox", 15:29:08 INFO - "script_repo_revision": "production", 15:29:08 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 15:29:08 INFO - "repository": "", 15:29:08 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 15:29:08 INFO - "buildid": "20170212145140", 15:29:08 INFO - "pgo_build": "False", 15:29:08 INFO - "basedir": "/builds/slave/test", 15:29:08 INFO - "buildnumber": 137, 15:29:08 INFO - "slavename": "t-yosemite-r7-0181", 15:29:08 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 15:29:08 INFO - "platform": "yosemite_r7", 15:29:08 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 15:29:08 INFO - "branch": "mozilla-central", 15:29:08 INFO - "repo_path": "mozilla-central", 15:29:08 INFO - "revision": "43270c88b2513a6758a0ce6669559f4a5f3c9207", 15:29:08 INFO - "stage_platform": "macosx64", 15:29:08 INFO - "builduid": "2310ce2ed09b4dbfb9f8039abdd02aa8", 15:29:08 INFO - "slavebuilddir": "test" 15:29:08 INFO - } 15:29:08 INFO - Found installer url https://queue.taskcluster.net/v1/task/Q-U4Hp99SAyEq3896vWNkA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg. 15:29:08 INFO - Running post-action listener: _resource_record_post_action 15:29:08 INFO - [mozharness: 2017-02-12 23:29:08.086080Z] Finished read-buildbot-config step (success) 15:29:08 INFO - [mozharness: 2017-02-12 23:29:08.086221Z] Running download-and-extract step. 15:29:08 INFO - Running pre-action listener: _resource_record_pre_action 15:29:08 INFO - Running main action method: download_and_extract 15:29:08 INFO - mkdir: /builds/slave/test/build/tests 15:29:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:08 INFO - trying https://queue.taskcluster.net/v1/task/Q-U4Hp99SAyEq3896vWNkA/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json 15:29:08 INFO - Downloading https://queue.taskcluster.net/v1/task/Q-U4Hp99SAyEq3896vWNkA/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 15:29:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Q-U4Hp99SAyEq3896vWNkA/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 15:29:09 INFO - Downloaded 1199 bytes. 15:29:09 INFO - Reading from file /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 15:29:09 INFO - Using the following test package requirements: 15:29:09 INFO - {u'common': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 15:29:09 INFO - u'cppunittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 15:29:09 INFO - u'firefox-54.0a1.en-US.mac.cppunittest.tests.zip'], 15:29:09 INFO - u'gtest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 15:29:09 INFO - u'firefox-54.0a1.en-US.mac.gtest.tests.zip'], 15:29:09 INFO - u'jittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 15:29:09 INFO - u'jsshell-mac.zip'], 15:29:09 INFO - u'mochitest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 15:29:09 INFO - u'firefox-54.0a1.en-US.mac.mochitest.tests.zip'], 15:29:09 INFO - u'mozbase': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 15:29:09 INFO - u'reftest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 15:29:09 INFO - u'firefox-54.0a1.en-US.mac.reftest.tests.zip'], 15:29:09 INFO - u'talos': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 15:29:09 INFO - u'firefox-54.0a1.en-US.mac.talos.tests.zip'], 15:29:09 INFO - u'web-platform': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 15:29:09 INFO - u'firefox-54.0a1.en-US.mac.web-platform.tests.zip'], 15:29:09 INFO - u'xpcshell': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 15:29:09 INFO - u'firefox-54.0a1.en-US.mac.xpcshell.tests.zip']} 15:29:09 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip'] for test suite category: common 15:29:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Q-U4Hp99SAyEq3896vWNkA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 15:29:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Q-U4Hp99SAyEq3896vWNkA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:29:09 INFO - Fetch https://queue.taskcluster.net/v1/task/Q-U4Hp99SAyEq3896vWNkA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 15:29:10 INFO - Expected file size: 51502709 15:29:10 INFO - Obtained file size: 51502709 15:29:26 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 15:29:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Q-U4Hp99SAyEq3896vWNkA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 15:29:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Q-U4Hp99SAyEq3896vWNkA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:29:26 INFO - Fetch https://queue.taskcluster.net/v1/task/Q-U4Hp99SAyEq3896vWNkA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 15:29:28 INFO - Expected file size: 51502709 15:29:28 INFO - Obtained file size: 51502709 15:29:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Q-U4Hp99SAyEq3896vWNkA/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip 15:29:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Q-U4Hp99SAyEq3896vWNkA/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip'}, attempt #1 15:29:43 INFO - Fetch https://queue.taskcluster.net/v1/task/Q-U4Hp99SAyEq3896vWNkA/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip into memory 15:29:44 INFO - Expected file size: 14403647 15:29:44 INFO - Obtained file size: 14403647 15:29:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:45 INFO - trying https://queue.taskcluster.net/v1/task/Q-U4Hp99SAyEq3896vWNkA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 15:29:45 INFO - Downloading https://queue.taskcluster.net/v1/task/Q-U4Hp99SAyEq3896vWNkA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg 15:29:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Q-U4Hp99SAyEq3896vWNkA/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 15:29:47 INFO - Downloaded 70464715 bytes. 15:29:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Q-U4Hp99SAyEq3896vWNkA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 15:29:47 INFO - mkdir: /builds/slave/test/properties 15:29:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:29:47 INFO - Writing to file /builds/slave/test/properties/build_url 15:29:47 INFO - Contents: 15:29:47 INFO - build_url:https://queue.taskcluster.net/v1/task/Q-U4Hp99SAyEq3896vWNkA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 15:29:47 INFO - Running post-action listener: _resource_record_post_action 15:29:47 INFO - Running post-action listener: set_extra_try_arguments 15:29:47 INFO - [mozharness: 2017-02-12 23:29:47.965435Z] Finished download-and-extract step (success) 15:29:47 INFO - [mozharness: 2017-02-12 23:29:47.965559Z] Running populate-webroot step. 15:29:47 INFO - Running pre-action listener: _resource_record_pre_action 15:29:47 INFO - Running main action method: populate_webroot 15:29:47 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:29:47 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:29:47 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:29:47 INFO - '--tppagecycles', 15:29:47 INFO - '7'], 15:29:47 INFO - 'tests': ['tsvgm']}}, 15:29:47 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:29:47 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:29:47 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:29:47 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:29:47 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:29:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:29:47 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 15:29:47 INFO - 'talos_options': ['--disable-e10s'], 15:29:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:29:47 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:29:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:29:47 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 15:29:47 INFO - 'talos_options': ['--disable-e10s'], 15:29:47 INFO - 'tests': ['damp', 'tps']}, 15:29:47 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:29:47 INFO - 'tests': ['damp', 'tps']}, 15:29:47 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:29:47 INFO - 'tests': ['dromaeo_dom']}, 15:29:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:29:47 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:29:47 INFO - 'tests': ['basic_compositor_video']}, 15:29:47 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:29:47 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:29:47 INFO - 'tests': ['a11yr', 15:29:47 INFO - 'ts_paint', 15:29:47 INFO - 'tpaint', 15:29:47 INFO - 'sessionrestore', 15:29:47 INFO - 'sessionrestore_no_auto_restore', 15:29:47 INFO - 'tabpaint']}, 15:29:47 INFO - 'other-e10s': {'tests': ['a11yr', 15:29:47 INFO - 'ts_paint', 15:29:47 INFO - 'tpaint', 15:29:47 INFO - 'sessionrestore', 15:29:47 INFO - 'sessionrestore_no_auto_restore', 15:29:47 INFO - 'tabpaint']}, 15:29:47 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:29:47 INFO - 'ts_paint', 15:29:47 INFO - 'tpaint', 15:29:47 INFO - 'sessionrestore', 15:29:47 INFO - 'sessionrestore_no_auto_restore', 15:29:47 INFO - 'tabpaint']}, 15:29:47 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:29:47 INFO - 'ts_paint', 15:29:47 INFO - 'tpaint', 15:29:47 INFO - 'sessionrestore', 15:29:47 INFO - 'sessionrestore_no_auto_restore', 15:29:47 INFO - 'tabpaint']}, 15:29:47 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:29:47 INFO - 'tests': ['a11yr', 15:29:47 INFO - 'ts_paint', 15:29:47 INFO - 'tpaint', 15:29:47 INFO - 'sessionrestore', 15:29:47 INFO - 'sessionrestore_no_auto_restore', 15:29:47 INFO - 'tabpaint']}, 15:29:47 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:29:47 INFO - 'tests': ['a11yr', 15:29:47 INFO - 'ts_paint', 15:29:47 INFO - 'tpaint', 15:29:47 INFO - 'sessionrestore', 15:29:47 INFO - 'sessionrestore_no_auto_restore', 15:29:47 INFO - 'tabpaint']}, 15:29:47 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:29:47 INFO - 'tests': ['tsvgx', 15:29:47 INFO - 'tsvgr_opacity', 15:29:47 INFO - 'tart', 15:29:47 INFO - 'tscrollx', 15:29:47 INFO - 'cart', 15:29:47 INFO - 'tsvg_static']}, 15:29:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:29:47 INFO - 'tsvgr_opacity', 15:29:47 INFO - 'tart', 15:29:47 INFO - 'tscrollx', 15:29:47 INFO - 'cart', 15:29:47 INFO - 'tsvg_static']}, 15:29:47 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 15:29:47 INFO - 'talos_options': ['--disable-e10s'], 15:29:47 INFO - 'tests': ['tp5o']}, 15:29:47 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:29:47 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 15:29:47 INFO - 'talos_options': ['--disable-e10s', 15:29:47 INFO - '--xperf_path', 15:29:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:29:47 INFO - 'tests': ['tp5n']}, 15:29:47 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:29:47 INFO - 'talos_options': ['--xperf_path', 15:29:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:29:47 INFO - 'tests': ['tp5n']}}, 15:29:47 INFO - 'talos.zip': {'path': '', 15:29:47 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:29:47 INFO - Running post-action listener: _resource_record_post_action 15:29:47 INFO - [mozharness: 2017-02-12 23:29:47.983955Z] Finished populate-webroot step (success) 15:29:47 INFO - [mozharness: 2017-02-12 23:29:47.984092Z] Running create-virtualenv step. 15:29:47 INFO - Running pre-action listener: _resource_record_pre_action 15:29:47 INFO - Running main action method: create_virtualenv 15:29:47 INFO - Creating virtualenv /builds/slave/test/build/venv 15:29:47 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 15:29:47 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:29:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:29:48 INFO - Using real prefix '/tools/python27' 15:29:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:29:48 INFO - Installing distribute.............................................................................................................................................................................................done. 15:29:51 INFO - Installing pip.................done. 15:29:51 INFO - Return code: 0 15:29:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:29:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:29:51 INFO - Reading from file tmpfile_stdout 15:29:51 INFO - Output received: 15:29:51 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) 15:29:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:29:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:29:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:29:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10daebb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10daee768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd03aca8a20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d8c9d30>, '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 0x10d7f0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d8bebe8>, '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.nPRpMuzilr/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.Sjh8mtfBV6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 15:29:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:29:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:29:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nPRpMuzilr/Render', 15:29:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:29:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:29:51 INFO - 'HOME': '/Users/cltbld', 15:29:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:29:51 INFO - 'LOGNAME': 'cltbld', 15:29:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:29:51 INFO - 'MOZ_NO_REMOTE': '1', 15:29:51 INFO - 'NO_EM_RESTART': '1', 15:29:51 INFO - 'PAGER': '/bin/cat', 15:29:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:29:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:29:51 INFO - 'PWD': '/builds/slave/test', 15:29:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:29:51 INFO - 'SHELL': '/bin/bash', 15:29:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Sjh8mtfBV6/Listeners', 15:29:51 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 15:29:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:29:51 INFO - 'USER': 'cltbld', 15:29:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:29:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:29:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:29:51 INFO - 'XPC_FLAGS': '0x0', 15:29:51 INFO - 'XPC_SERVICE_NAME': '0', 15:29:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:29:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:29:52 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 15:29:52 INFO - Cleaning up... 15:29:52 INFO - Return code: 0 15:29:52 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:29:52 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')]} 15:29:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:29:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:52 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')]} 15:29:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:29:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:52 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 0x10daebb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10daee768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd03aca8a20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d8c9d30>, '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 0x10d7f0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d8bebe8>, '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.nPRpMuzilr/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.Sjh8mtfBV6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 15:29:52 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 15:29:52 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 15:29:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nPRpMuzilr/Render', 15:29:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:29:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:29:52 INFO - 'HOME': '/Users/cltbld', 15:29:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:29:52 INFO - 'LOGNAME': 'cltbld', 15:29:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:29:52 INFO - 'MOZ_NO_REMOTE': '1', 15:29:52 INFO - 'NO_EM_RESTART': '1', 15:29:52 INFO - 'PAGER': '/bin/cat', 15:29:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:29:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:29:52 INFO - 'PWD': '/builds/slave/test', 15:29:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:29:52 INFO - 'SHELL': '/bin/bash', 15:29:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Sjh8mtfBV6/Listeners', 15:29:52 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 15:29:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:29:52 INFO - 'USER': 'cltbld', 15:29:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:29:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:29:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:29:52 INFO - 'XPC_FLAGS': '0x0', 15:29:52 INFO - 'XPC_SERVICE_NAME': '0', 15:29:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:29:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:29:52 INFO - Downloading/unpacking psutil>=3.1.1 15:29: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 15:29: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 15:29: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 15:29: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 15:29: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 15:29: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 15:29:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:29:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:29:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:29:56 INFO - Installing collected packages: psutil 15:29:56 INFO - Running setup.py install for psutil 15:29:56 INFO - building 'psutil._psutil_osx' extension 15:29:56 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 15:29:56 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 15:29:56 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 15:29:56 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 15:29:56 INFO - building 'psutil._psutil_posix' extension 15:29:56 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 15:29:56 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:29:56 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:29:56 INFO - ^ 15:29:57 INFO - 1 warning generated. 15:29:57 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 15:29:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:29:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:29:57 INFO - Successfully installed psutil 15:29:57 INFO - Cleaning up... 15:29:57 INFO - Return code: 0 15:29:57 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:29: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')]} 15:29:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:29:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29: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')]} 15:29:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:29:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10daebb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10daee768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd03aca8a20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d8c9d30>, '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 0x10d7f0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d8bebe8>, '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.nPRpMuzilr/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.Sjh8mtfBV6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 15:29: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:29: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 mozsystemmonitor==0.3 15:29:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nPRpMuzilr/Render', 15:29:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:29:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:29:57 INFO - 'HOME': '/Users/cltbld', 15:29:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:29:57 INFO - 'LOGNAME': 'cltbld', 15:29:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:29:57 INFO - 'MOZ_NO_REMOTE': '1', 15:29:57 INFO - 'NO_EM_RESTART': '1', 15:29:57 INFO - 'PAGER': '/bin/cat', 15:29:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:29:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:29:57 INFO - 'PWD': '/builds/slave/test', 15:29:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:29:57 INFO - 'SHELL': '/bin/bash', 15:29:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Sjh8mtfBV6/Listeners', 15:29:57 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 15:29:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:29:57 INFO - 'USER': 'cltbld', 15:29:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:29:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:29:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:29:57 INFO - 'XPC_FLAGS': '0x0', 15:29:57 INFO - 'XPC_SERVICE_NAME': '0', 15:29:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:29:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:29:57 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:29:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:29:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:29:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:29:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:29:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:29:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:30:00 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:30:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:30:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:30:00 INFO - Installing collected packages: mozsystemmonitor 15:30:00 INFO - Running setup.py install for mozsystemmonitor 15:30:00 INFO - Successfully installed mozsystemmonitor 15:30:00 INFO - Cleaning up... 15:30:00 INFO - Return code: 0 15:30:00 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:30:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:30:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:30:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:30:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:30:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:00 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 0x10daebb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10daee768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd03aca8a20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d8c9d30>, '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 0x10d7f0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d8bebe8>, '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.nPRpMuzilr/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.Sjh8mtfBV6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 15:30:00 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 15:30:00 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 15:30:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nPRpMuzilr/Render', 15:30:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:30:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:30:00 INFO - 'HOME': '/Users/cltbld', 15:30:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:30:00 INFO - 'LOGNAME': 'cltbld', 15:30:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:30:00 INFO - 'MOZ_NO_REMOTE': '1', 15:30:00 INFO - 'NO_EM_RESTART': '1', 15:30:00 INFO - 'PAGER': '/bin/cat', 15:30:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:30:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:30:00 INFO - 'PWD': '/builds/slave/test', 15:30:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:30:00 INFO - 'SHELL': '/bin/bash', 15:30:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Sjh8mtfBV6/Listeners', 15:30:00 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 15:30:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:30:00 INFO - 'USER': 'cltbld', 15:30:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:30:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:30:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:30:00 INFO - 'XPC_FLAGS': '0x0', 15:30:00 INFO - 'XPC_SERVICE_NAME': '0', 15:30:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:30:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:30:00 INFO - Downloading/unpacking jsonschema==2.5.1 15:30:00 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 15:30:00 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 15:30:00 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 15:30:00 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 15:30:00 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 15:30:00 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 15:30:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:30:03 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:30:03 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 15:30:03 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 15:30:03 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 15:30:03 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 15:30:03 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 15:30:03 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 15:30:03 INFO - Downloading functools32-3.2.3-2.tar.gz 15:30:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:30:03 INFO - warning: no files found matching '*.txt' 15:30:03 INFO - no previously-included directories found matching 'build' 15:30:03 INFO - no previously-included directories found matching 'dist' 15:30:03 INFO - no previously-included directories found matching '.git*' 15:30:03 INFO - Installing collected packages: jsonschema, functools32 15:30:03 INFO - Running setup.py install for functools32 15:30:03 INFO - warning: no files found matching '*.txt' 15:30:03 INFO - no previously-included directories found matching 'build' 15:30:03 INFO - no previously-included directories found matching 'dist' 15:30:03 INFO - no previously-included directories found matching '.git*' 15:30:03 INFO - Successfully installed jsonschema functools32 15:30:03 INFO - Cleaning up... 15:30:04 INFO - Return code: 0 15:30:04 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:30:04 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')]} 15:30:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:30:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:04 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')]} 15:30:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:30:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:04 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 0x10daebb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10daee768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd03aca8a20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d8c9d30>, '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 0x10d7f0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d8bebe8>, '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.nPRpMuzilr/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.Sjh8mtfBV6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 15:30:04 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 15:30:04 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 15:30:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nPRpMuzilr/Render', 15:30:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:30:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:30:04 INFO - 'HOME': '/Users/cltbld', 15:30:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:30:04 INFO - 'LOGNAME': 'cltbld', 15:30:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:30:04 INFO - 'MOZ_NO_REMOTE': '1', 15:30:04 INFO - 'NO_EM_RESTART': '1', 15:30:04 INFO - 'PAGER': '/bin/cat', 15:30:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:30:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:30:04 INFO - 'PWD': '/builds/slave/test', 15:30:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:30:04 INFO - 'SHELL': '/bin/bash', 15:30:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Sjh8mtfBV6/Listeners', 15:30:04 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 15:30:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:30:04 INFO - 'USER': 'cltbld', 15:30:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:30:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:30:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:30:04 INFO - 'XPC_FLAGS': '0x0', 15:30:04 INFO - 'XPC_SERVICE_NAME': '0', 15:30:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:30:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:30:04 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:30:04 INFO - Cleaning up... 15:30:04 INFO - Return code: 0 15:30:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:30:04 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')]} 15:30:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:30:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:04 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')]} 15:30:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:30:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:04 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 0x10daebb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10daee768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd03aca8a20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d8c9d30>, '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 0x10d7f0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d8bebe8>, '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.nPRpMuzilr/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.Sjh8mtfBV6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 15:30:04 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 15:30:04 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 15:30:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nPRpMuzilr/Render', 15:30:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:30:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:30:04 INFO - 'HOME': '/Users/cltbld', 15:30:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:30:04 INFO - 'LOGNAME': 'cltbld', 15:30:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:30:04 INFO - 'MOZ_NO_REMOTE': '1', 15:30:04 INFO - 'NO_EM_RESTART': '1', 15:30:04 INFO - 'PAGER': '/bin/cat', 15:30:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:30:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:30:04 INFO - 'PWD': '/builds/slave/test', 15:30:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:30:04 INFO - 'SHELL': '/bin/bash', 15:30:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Sjh8mtfBV6/Listeners', 15:30:04 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 15:30:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:30:04 INFO - 'USER': 'cltbld', 15:30:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:30:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:30:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:30:04 INFO - 'XPC_FLAGS': '0x0', 15:30:04 INFO - 'XPC_SERVICE_NAME': '0', 15:30:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:30:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:30:04 INFO - Downloading/unpacking blobuploader==1.2.4 15:30:04 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 15:30:04 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 15:30:04 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 15:30:04 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 15:30:04 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 15:30:04 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 15:30:07 INFO - Downloading blobuploader-1.2.4.tar.gz 15:30:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:30:07 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:30:07 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 15:30:07 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 15:30:07 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 15:30:07 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 15:30:07 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 15:30:07 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 15:30:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:30:07 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:30:07 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 15:30:07 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 15:30:07 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 15:30:07 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 15:30:07 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 15:30:07 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 15:30:08 INFO - Downloading docopt-0.6.1.tar.gz 15:30:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:30:08 INFO - Installing collected packages: blobuploader, requests, docopt 15:30:08 INFO - Running setup.py install for blobuploader 15:30:08 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:30:08 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:30:08 INFO - Running setup.py install for requests 15:30:09 INFO - Running setup.py install for docopt 15:30:09 INFO - Successfully installed blobuploader requests docopt 15:30:09 INFO - Cleaning up... 15:30:09 INFO - Return code: 0 15:30:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:30:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:30:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:30:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:30:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:30:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:09 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 0x10daebb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10daee768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd03aca8a20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d8c9d30>, '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 0x10d7f0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d8bebe8>, '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.nPRpMuzilr/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.Sjh8mtfBV6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 15:30:09 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 15:30:09 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 15:30:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nPRpMuzilr/Render', 15:30:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:30:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:30:09 INFO - 'HOME': '/Users/cltbld', 15:30:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:30:09 INFO - 'LOGNAME': 'cltbld', 15:30:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:30:09 INFO - 'MOZ_NO_REMOTE': '1', 15:30:09 INFO - 'NO_EM_RESTART': '1', 15:30:09 INFO - 'PAGER': '/bin/cat', 15:30:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:30:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:30:09 INFO - 'PWD': '/builds/slave/test', 15:30:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:30:09 INFO - 'SHELL': '/bin/bash', 15:30:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Sjh8mtfBV6/Listeners', 15:30:09 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 15:30:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:30:09 INFO - 'USER': 'cltbld', 15:30:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:30:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:30:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:30:09 INFO - 'XPC_FLAGS': '0x0', 15:30:09 INFO - 'XPC_SERVICE_NAME': '0', 15:30:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:30:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:30:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:30:09 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-VwX2Ud-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:30:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:30:09 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-PBLaBX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:30:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:30:09 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-mmGUFt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:30:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:30:10 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-fSbyt3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:30:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:30:10 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-RhSBgA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:30:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:30:10 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-1xQRap-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:30:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:30:10 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-bn_wK5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:30:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:30:10 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-5EuB9s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:30:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:30:10 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-nbFgR5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:30:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:30:10 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-Qv8Ljy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:30:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:30:10 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-kyuMXF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:30:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:30:10 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-iWIiG_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:30:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:30:10 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-w5cpSR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:30:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:30:11 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-Pe9qbc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:30:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:30:11 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-Wrm8r8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:30:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:30:11 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-HJy9WS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:30:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:30:11 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-sUDIJx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:30:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:30:11 INFO - Running setup.py install for manifestparser 15:30:11 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:30:11 INFO - Running setup.py install for mozcrash 15:30:11 INFO - Running setup.py install for mozdebug 15:30:11 INFO - Running setup.py install for mozdevice 15:30:12 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:30:12 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:30:12 INFO - Running setup.py install for mozfile 15:30:12 INFO - Running setup.py install for mozhttpd 15:30:12 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:30:12 INFO - Running setup.py install for mozinfo 15:30:12 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:30:12 INFO - Running setup.py install for mozInstall 15:30:12 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:30:12 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:30:12 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:30:12 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:30:12 INFO - Running setup.py install for mozleak 15:30:12 INFO - Running setup.py install for mozlog 15:30:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:30:12 INFO - Running setup.py install for moznetwork 15:30:13 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:30:13 INFO - Running setup.py install for mozprocess 15:30:13 INFO - Running setup.py install for mozprofile 15:30:13 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:30:13 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:30:13 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:30:13 INFO - Running setup.py install for mozrunner 15:30:13 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:30:13 INFO - Running setup.py install for mozscreenshot 15:30:13 INFO - Running setup.py install for moztest 15:30:13 INFO - Running setup.py install for mozversion 15:30:14 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:30:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:30:14 INFO - Cleaning up... 15:30:14 INFO - Return code: 0 15:30:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:30:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:30:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:30:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:30:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:30:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:14 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 0x10daebb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10daee768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd03aca8a20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d8c9d30>, '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 0x10d7f0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d8bebe8>, '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.nPRpMuzilr/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.Sjh8mtfBV6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 15:30:14 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 15:30:14 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 15:30:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nPRpMuzilr/Render', 15:30:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:30:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:30:14 INFO - 'HOME': '/Users/cltbld', 15:30:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:30:14 INFO - 'LOGNAME': 'cltbld', 15:30:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:30:14 INFO - 'MOZ_NO_REMOTE': '1', 15:30:14 INFO - 'NO_EM_RESTART': '1', 15:30:14 INFO - 'PAGER': '/bin/cat', 15:30:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:30:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:30:14 INFO - 'PWD': '/builds/slave/test', 15:30:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:30:14 INFO - 'SHELL': '/bin/bash', 15:30:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Sjh8mtfBV6/Listeners', 15:30:14 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 15:30:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:30:14 INFO - 'USER': 'cltbld', 15:30:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:30:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:30:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:30:14 INFO - 'XPC_FLAGS': '0x0', 15:30:14 INFO - 'XPC_SERVICE_NAME': '0', 15:30:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:30:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:30:14 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-h8WSgo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:30:14 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)) 15:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:30:14 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-3z_KAr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:30:14 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)) 15:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:30:14 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-EEMioo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:30:14 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)) 15:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:30:14 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-9YETad-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:30:14 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)) 15:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:30:14 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-hcCc7T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:30:14 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)) 15:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:30:14 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-AhWpxX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:30:15 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)) 15:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:30:15 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-JDM79V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:30:15 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)) 15:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:30:15 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-c2mnah-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:30:15 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)) 15:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:30:15 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-ahy0PE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:30:15 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)) 15:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:30:15 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-DXVZA2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:30:15 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)) 15:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:30:15 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-_A0wvy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:30:15 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)) 15:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:30:15 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-xZPVhC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:30:15 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)) 15:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:30:15 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-GAgKMF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:30:15 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)) 15:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:30:15 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-qULtGC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:30:15 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)) 15:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:30:15 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-YNuqoW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:30:16 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)) 15:30:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:30:16 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-eiBR5z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:30:16 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)) 15:30:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:30:16 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-bGRDxJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:30:16 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)) 15:30:16 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)) 15:30:16 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)) 15:30:16 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)) 15:30:16 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)) 15:30:16 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)) 15:30:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:30:16 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 15:30:16 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 15:30:16 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 15:30:16 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 15:30:16 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 15:30:16 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 15:30:18 INFO - Downloading blessings-1.6.tar.gz 15:30:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:30:18 INFO - Installing collected packages: blessings 15:30:18 INFO - Running setup.py install for blessings 15:30:19 INFO - Successfully installed blessings 15:30:19 INFO - Cleaning up... 15:30:19 INFO - Return code: 0 15:30:19 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:30:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:30:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:30:19 INFO - Reading from file tmpfile_stdout 15:30:19 INFO - Current package versions: 15:30:19 INFO - blessings == 1.6 15:30:19 INFO - blobuploader == 1.2.4 15:30:19 INFO - docopt == 0.6.1 15:30:19 INFO - functools32 == 3.2.3-2 15:30:19 INFO - jsonschema == 2.5.1 15:30:19 INFO - manifestparser == 1.1 15:30:19 INFO - mozInstall == 1.12 15:30:19 INFO - mozcrash == 1.0 15:30:19 INFO - mozdebug == 0.1 15:30:19 INFO - mozdevice == 0.49 15:30:19 INFO - mozfile == 1.2 15:30:19 INFO - mozhttpd == 0.7 15:30:19 INFO - mozinfo == 0.9 15:30:19 INFO - mozleak == 0.1 15:30:19 INFO - mozlog == 3.4 15:30:19 INFO - moznetwork == 0.27 15:30:19 INFO - mozprocess == 0.24 15:30:19 INFO - mozprofile == 0.28 15:30:19 INFO - mozrunner == 6.13 15:30:19 INFO - mozscreenshot == 0.1 15:30:19 INFO - mozsystemmonitor == 0.3 15:30:19 INFO - moztest == 0.8 15:30:19 INFO - mozversion == 1.4 15:30:19 INFO - psutil == 3.1.1 15:30:19 INFO - requests == 1.2.3 15:30:19 INFO - wsgiref == 0.1.2 15:30:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:30:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:30:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:30:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:30:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:30:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:19 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 0x10daebb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10daee768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd03aca8a20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d8c9d30>, '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 0x10d7f0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d8bebe8>, '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.nPRpMuzilr/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.Sjh8mtfBV6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 15:30:19 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 15:30:19 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 15:30:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nPRpMuzilr/Render', 15:30:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:30:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:30:19 INFO - 'HOME': '/Users/cltbld', 15:30:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:30:19 INFO - 'LOGNAME': 'cltbld', 15:30:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:30:19 INFO - 'MOZ_NO_REMOTE': '1', 15:30:19 INFO - 'NO_EM_RESTART': '1', 15:30:19 INFO - 'PAGER': '/bin/cat', 15:30:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:30:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:30:19 INFO - 'PWD': '/builds/slave/test', 15:30:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:30:19 INFO - 'SHELL': '/bin/bash', 15:30:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Sjh8mtfBV6/Listeners', 15:30:19 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 15:30:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:30:19 INFO - 'USER': 'cltbld', 15:30:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:30:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:30:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:30:19 INFO - 'XPC_FLAGS': '0x0', 15:30:19 INFO - 'XPC_SERVICE_NAME': '0', 15:30:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:30:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:30:19 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)) 15:30:19 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)) 15:30:19 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)) 15:30:19 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)) 15:30:19 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)) 15:30:19 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)) 15:30:19 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)) 15:30:19 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)) 15:30:19 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)) 15:30:19 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)) 15:30:19 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)) 15:30:19 INFO - Cleaning up... 15:30:19 INFO - Return code: 0 15:30:19 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:30:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:30:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:30:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:30:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:30:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:19 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 0x10daebb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10daee768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd03aca8a20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d8c9d30>, '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 0x10d7f0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d8bebe8>, '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.nPRpMuzilr/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.Sjh8mtfBV6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 15:30:19 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 15:30:19 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 15:30:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nPRpMuzilr/Render', 15:30:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:30:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:30:19 INFO - 'HOME': '/Users/cltbld', 15:30:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:30:19 INFO - 'LOGNAME': 'cltbld', 15:30:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:30:19 INFO - 'MOZ_NO_REMOTE': '1', 15:30:19 INFO - 'NO_EM_RESTART': '1', 15:30:19 INFO - 'PAGER': '/bin/cat', 15:30:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:30:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:30:19 INFO - 'PWD': '/builds/slave/test', 15:30:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:30:19 INFO - 'SHELL': '/bin/bash', 15:30:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Sjh8mtfBV6/Listeners', 15:30:19 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 15:30:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:30:19 INFO - 'USER': 'cltbld', 15:30:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:30:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:30:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:30:19 INFO - 'XPC_FLAGS': '0x0', 15:30:19 INFO - 'XPC_SERVICE_NAME': '0', 15:30:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:30:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:30:20 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:30:20 INFO - Cleaning up... 15:30:20 INFO - Return code: 0 15:30:20 INFO - Running post-action listener: _resource_record_post_action 15:30:20 INFO - Running post-action listener: _start_resource_monitoring 15:30:20 INFO - Starting resource monitoring. 15:30:20 INFO - [mozharness: 2017-02-12 23:30:20.081933Z] Finished create-virtualenv step (success) 15:30:20 INFO - [mozharness: 2017-02-12 23:30:20.082923Z] Running install step. 15:30:20 INFO - Running pre-action listener: _resource_record_pre_action 15:30:20 INFO - Running main action method: install 15:30:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:30:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:30:20 INFO - Reading from file tmpfile_stdout 15:30:20 INFO - Detecting whether we're running mozinstall >=1.0... 15:30:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:30:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:30:20 INFO - Reading from file tmpfile_stdout 15:30:20 INFO - Output received: 15:30:20 INFO - Usage: mozinstall [options] installer 15:30:20 INFO - Options: 15:30:20 INFO - -h, --help show this help message and exit 15:30:20 INFO - -d DEST, --destination=DEST 15:30:20 INFO - Directory to install application into. [default: 15:30:20 INFO - "/builds/slave/test"] 15:30:20 INFO - --app=APP Application being installed. [default: firefox] 15:30:20 INFO - mkdir: /builds/slave/test/build/application 15:30:20 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'] 15:30:20 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 15:30:42 INFO - Reading from file tmpfile_stdout 15:30:42 INFO - Output received: 15:30:42 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 15:30:42 INFO - Running post-action listener: _resource_record_post_action 15:30:42 INFO - [mozharness: 2017-02-12 23:30:42.175498Z] Finished install step (success) 15:30:42 INFO - [mozharness: 2017-02-12 23:30:42.175640Z] Running run-tests step. 15:30:42 INFO - Running pre-action listener: _resource_record_pre_action 15:30:42 INFO - Running main action method: run_tests 15:30:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:30:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:30:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:30:42 INFO - 2017-02-12 15:30:42.190 screenresolution[1933:24511] starting screenresolution argv=screenresolution get 15:30:42 INFO - 2017-02-12 15:30:42.193 screenresolution[1933:24511] Display 0: 1600x1200x32@60 15:30:42 INFO - 2017-02-12 15:30:42.198 screenresolution[1934:24514] starting screenresolution argv=screenresolution list 15:30:42 INFO - Available Modes on Display 0 15:30:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:30:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:30:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:30:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:30:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:30:42 INFO - Intel Iris: 15:30:42 INFO - Chipset Model: Intel Iris 15:30:42 INFO - Type: GPU 15:30:42 INFO - Bus: Built-In 15:30:42 INFO - VRAM (Dynamic, Max): 1536 MB 15:30:42 INFO - Vendor: Intel (0x8086) 15:30:42 INFO - Device ID: 0x0a2e 15:30:42 INFO - Revision ID: 0x0009 15:30:42 INFO - Displays: 15:30:42 INFO - Display: 15:30:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:30:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:30:42 INFO - Display Serial Number: 1600x1200 60 15:30:42 INFO - Main Display: Yes 15:30:42 INFO - Mirror: Off 15:30:42 INFO - Online: Yes 15:30:42 INFO - Rotation: Supported 15:30:42 INFO - Return code: 0 15:30:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:30:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:30:42 INFO - Python 2.7.3 15:30:42 INFO - Return code: 0 15:30:42 INFO - grabbing minidump binary from tooltool 15:30:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:30:42 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 0x10d8c9d30>, '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 0x10d7f0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d8bebe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:30:42 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 15:30:42 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 15:30:42 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 15:30:42 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:30:42 INFO - Return code: 0 15:30:42 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:30:42 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:30:42 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:30:42 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:30:42 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:30:42 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/Q-U4Hp99SAyEq3896vWNkA/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0181', '--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 15:30:42 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/Q-U4Hp99SAyEq3896vWNkA/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0181 --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 15:30:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nPRpMuzilr/Render', 15:30:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:30:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:30:42 INFO - 'HOME': '/Users/cltbld', 15:30:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:30:42 INFO - 'LOGNAME': 'cltbld', 15:30:42 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:30:42 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:30:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:30:42 INFO - 'MOZ_NO_REMOTE': '1', 15:30:42 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:30:42 INFO - 'NO_EM_RESTART': '1', 15:30:42 INFO - 'PAGER': '/bin/cat', 15:30:42 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:30:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:30:42 INFO - 'PWD': '/builds/slave/test', 15:30:42 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:30:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:30:42 INFO - 'SHELL': '/bin/bash', 15:30:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Sjh8mtfBV6/Listeners', 15:30:42 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 15:30:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:30:42 INFO - 'USER': 'cltbld', 15:30:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:30:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:30:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:30:42 INFO - 'XPC_FLAGS': '0x0', 15:30:42 INFO - 'XPC_SERVICE_NAME': '0', 15:30:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:30:42 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/Q-U4Hp99SAyEq3896vWNkA/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0181', '--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 15:30:42 INFO - mozversion application_buildid: 20170212145140 15:30:42 INFO - mozversion application_changeset: 43270c88b2513a6758a0ce6669559f4a5f3c9207 15:30:42 INFO - mozversion application_display_name: Nightly 15:30:42 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:30:42 INFO - mozversion application_name: Firefox 15:30:42 INFO - mozversion application_remotingname: firefox 15:30:42 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:30:42 INFO - mozversion application_vendor: Mozilla 15:30:42 INFO - mozversion application_version: 54.0a1 15:30:42 INFO - mozversion platform_buildid: 20170212145140 15:30:42 INFO - mozversion platform_changeset: 43270c88b2513a6758a0ce6669559f4a5f3c9207 15:30:42 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:30:42 INFO - mozversion platform_version: 54.0a1 15:30:42 INFO - using testdate: 1486942242 15:30:42 INFO - actual date: 1486942242 15:30:42 INFO - starting webserver on 'localhost:49375' 15:30:42 INFO - SUITE-START | Running 2 tests 15:30:42 INFO - TEST-START | dromaeo_css 15:30:42 INFO - operating with platform_type : mac_ 15:30:42 INFO - Initialising browser for dromaeo_css test... 15:30:42 INFO - TEST-INFO | started process 1944 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/tmpafeAbI/profile http://localhost:49375/getInfo.html) 15:30:45 INFO - PROCESS | 1944 | 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] 15:30:45 INFO - PROCESS | 1944 | 2017-02-12 15:30:45.456 plugin-container[1946:24665] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e43, name = 'com.apple.tsm.portname' 15:30:45 INFO - PROCESS | 1944 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:30:45 INFO - PROCESS | 1944 | 2017-02-12 15:30:45.459 plugin-container[1946:24665] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x910b, name = 'com.apple.CFPasteboardClient' 15:30:45 INFO - PROCESS | 1944 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:30:45 INFO - PROCESS | 1944 | 2017-02-12 15:30:45.459 plugin-container[1946:24665] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:30:45 INFO - PROCESS | 1944 | __metrics Screen width/height:1600/1200 15:30:45 INFO - PROCESS | 1944 | colorDepth:24 15:30:45 INFO - PROCESS | 1944 | Browser inner width/height: 1024/689 15:30:45 INFO - PROCESS | 1944 | __metrics 15:30:45 ERROR - PROCESS | 1944 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3283: TypeError: this.tabs is undefined 15:30:46 INFO - TEST-INFO | 1944: exit 0 15:30:46 INFO - Browser initialized. 15:30:46 INFO - Running cycle 1/1 for dromaeo_css test... 15:30:46 INFO - TEST-INFO | started process 1947 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/tmpafeAbI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:30:48 INFO - PROCESS | 1947 | 2017-02-12 15:30:48.911 plugin-container[1948:24782] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893b, name = 'com.apple.tsm.portname' 15:30:48 INFO - PROCESS | 1947 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:30:48 INFO - PROCESS | 1947 | 2017-02-12 15:30:48.911 plugin-container[1948:24782] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4417, name = 'com.apple.CFPasteboardClient' 15:30:48 INFO - PROCESS | 1947 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:30:48 INFO - PROCESS | 1947 | 2017-02-12 15:30:48.911 plugin-container[1948:24782] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:30:58 INFO - PROCESS | 1947 | RSS: Main: 234405888 15:30:58 INFO - PROCESS | 1947 | 15:34:01 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:49375/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49375/tests/dromaeo/cssquery-ext.html) 15:34:03 INFO - PROCESS | 1947 | RSS: Main: 255791104 15:34:03 INFO - PROCESS | 1947 | 15:35:46 INFO - PROCESS | 1947 | ************************* 15:35:46 INFO - PROCESS | 1947 | A coding exception was thrown and uncaught in a Task. 15:35:46 INFO - PROCESS | 1947 | 15:35:46 ERROR - PROCESS | 1947 | Full message: TypeError: NetworkError when attempting to fetch resource. 15:35:46 INFO - PROCESS | 1947 | Full stack: 15:35:46 INFO - PROCESS | 1947 | ************************* 15:37:06 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:49375/tests/dromaeo/cssquery-ext.html (next: http://localhost:49375/tests/dromaeo/cssquery-jquery.html) 15:37:06 INFO - PROCESS | 1947 | RSS: Main: 261541888 15:37:06 INFO - PROCESS | 1947 | 15:40:08 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:49375/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49375/tests/dromaeo/cssquery-mootools.html) 15:40:09 INFO - PROCESS | 1947 | RSS: Main: 272379904 15:40:09 INFO - PROCESS | 1947 | 15:43:11 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:49375/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49375/tests/dromaeo/cssquery-prototype.html) 15:43:11 INFO - PROCESS | 1947 | RSS: Main: 264183808 15:43:11 INFO - PROCESS | 1947 | 15:46:14 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:49375/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49375/tests/dromaeo/cssquery-yui.html) 15:46:15 INFO - PROCESS | 1947 | RSS: Main: 268083200 15:46:15 INFO - PROCESS | 1947 | 15:49:18 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:49375/tests/dromaeo/cssquery-yui.html (next: http://localhost:49375/tests/dromaeo/cssquery-dojo.html) 15:49:19 INFO - PROCESS | 1947 | RSS: Main: 268865536 15:49:19 INFO - PROCESS | 1947 | 15:49:19 INFO - PROCESS | 1947 | __start_tp_report 15:49:19 INFO - PROCESS | 1947 | _x_x_mozilla_page_load 15:49:19 INFO - PROCESS | 1947 | _x_x_mozilla_page_load_details 15:49:19 INFO - PROCESS | 1947 | |i|pagename|runs| 15:49:19 INFO - PROCESS | 1947 | |0;dojo.html;3255.9999999999995;3264.7877887937284;3272.231025708958;3280.2291461506557;3330.933381332373;5516.91724624131;5536.501714845663;5541.445855414457;5568.248286481324;5586.217929489874;5612.382637909826;5639.718014099289;5652.858678533028;5663.9433605663935;5704.743286552105;4751.453582837983;5068.873278168038;5078.771455284503;5123.077845987721;5180.507851754077;5140.074786538422;5169.172932330828;5196.8700782480555;5219.243209734586;5263.631545791796;4172.853950111731;4342.370356298335;5060.46865079165;5172.741362931876;5216.739163041832;5085.110105731519;5093.261477085802;5117.7185254810975;5127.48725127488;5136.101182293121;4216.873493795191;4858.562729354339;5089.770960306758;5089.8727531812;5152.690838549699;5022.573081288095;5297.258383826267;5329.840104796862;5489.39616642169;5497.202905578669;6112.351504965182;6119.837693858745;6140.995089002448;6141.33258723369;6157.476614487774;6191.473724733403;6207.379262073801;6208.906866397007;6216.68916554175;6230.5638605297645;5559.6664200148125;5577.972110139504;5587.944120558743;5658.74535645897;5720.799772007959;6169.722362493699;6170.81487555374;6206.317305096436;6209.19280493542;6210.844728881814;5707.429257074259;5776.768929242868;5884.7940322088525;6033.788817391458;6081.351189286393;6103.034636161462;6169.475594574512;6209.689515524296;6217.4715149212825;6247.875042499216;5829.358770535237;6119.082137679384;6167.907481387782;6176.227971503562;6182;5929.169916211734;5978.342382337939;6013.4888534474185;6019.337872833985;6042.425969532887;4850.514948505192;4903.632227582874;4938.654294199443;4956.479569645207;5015.498450155028;4365.934510982338;4383.3863259143745;4395.53846846083;4402.449693788277;4408.338749187647;5868.559858010666;5877.853053673693;5883.529317654577;5884.6469211847425;5886.793962211302;6047.485963693135;6048.969755151196;6108;6131.264248290234;6138.907916381258;5664.943350566442;5687.118496633028;5689.7724091036725;5704.22992895962;5741.995150848585;5292.92060619091;5322.760475778599;5325.600579956519;5330.600204984642;5335.893282134336;6131.938680613226;6172.598781079216;6185.041318595625;6185.948388773829;6227.813165605039;6258.624482531062;6268.937310626835;6269.341719119427;6279.874402512016;6285.717142728497;6163.229596300463;6219.440250377488;6226.283977342664;6258.9374106260175;6301.243850737941;5403.108487099585;5980.491658209101;5981.880362392815;6158.3841615838055;6187.938120618916;4918.508149185053;6105.3894610540365;6120.724567394389;6141.877162456636;6150.892839288971;4812.903741925072;5175.870603234798;5926.140709596998;6035.004224302941;6056.242969628796;4559.658025648156;6016.338202797776;6074.878502430015;6089.512838972783;6129.481048574228;5598.608097433304;5741.564608847788;6015.969920150546;6027.186329845415;6032.728527216374;5828.154917537018;5846.5615078870105;5869.941300586939;5973.432523910222;6002.189704389852;5825.737841797214;5897.174395584536;5981.910271346021;5983.311919129356;6026.849328766816 15:49:19 INFO - PROCESS | 1947 | |1;ext.html;453.265709550528;481.1579735462942;484.8496965940557;503.78337314954564;516.346821271092;32174;38988.220235595305;39087.41368879469;39238.411423828664;41527.37708934368;39524.81425557231;40140.19719605606;40152.59847401525;40198.799006005;40208.38645488041;8859.424137431079;9196.862047069299;9221.308401869854;9243.722688319362;10452.790944181112;10269.28115031948;10711.732206694816;10719.839202411931;10729.08802751767;10766;10679.519421626006;10695.732606684818;10724.517396717167;10728.624498142566;10732.624358147466;10645.733856653607;10679.78640427187;10713.250072494928;10715.678529644123;10724.302920310232;10555.84166237507;10565.260431769779;10646.25476216665;10649.946750266277;10667.413292268922;34654.48018279728;34924.30151396983;34979.82510087459;34990.65009349925;35056.29887402263;3165.7625678074237;3227.306129182237;3639.6178401268016;3668.229671768932;3854.1520865409843;27785;29072.563911541343;29410.264743381587;29836;29889;2941.3970136122043;3083.7378822800088;3112.7821052526333;3182.80903145812;3193.8243396613175;414982;468986;469549;470817;473328;54172.729136354064;54549.45450545523;64911;65321;65722.67138664276;66550;68021.65989170023;68159.63680726358;73704;75307;15363;15518.767218491732;15521.223938803014;15548.76676849848;15590.766138507932;11642.41787910618;11731.88268117325;11806.46870890812;11893.40532973348;11903.34531600753;19434.339624514076;19442.125104370054;19825.801741982683;19868.304609338604;19939.10274037672;8435.114312997048;8472.872906906401;8488.660453581911;8534.146585341416;8617.741467755977;835.1084792943986;854.2695994924375;857.9013413457408;862.3733668866486;867.5488745852248;28559.857200713865;28727.2818179543;29276.414471710446;29539.966101186787;29550.113496595135;1018.1193267823279;1038.2783965144304;1051.8895515970712;1065.872095348563;1076.0907033556632;14340.498082567061;14799.852001480063;14867.479638212611;15282.159481228638;15319.157446340558;606266;609989;610115;611557;611658;56660.716696417076;57305.85388292211;57590.84818303694;57998.71000645054;58117.4188258112;1327.9468821247042;1357.4773712120707;1361.101672895884;1371.2184055088503;1371.766799644063;27620.685517738;28332.57501137526;28861.27846803847;28935.565966510097;29410.264743381587;49399;49530.00939981252;49535.256971146184;49547.26584569523;49806.252906205715;506855;507099;508435;508734;511096;39313.60686393214;40302.596974031054;40673;41069;41127.794361028005;44411.33382999193;45608.31587526256;45634.63096107122;45797;45965.54034459613;28541;33550.49674254937;33867;36687;37567.43648845324;32475.675243247264;36146.27707445784;36329.81835090808;37311.880643580735;37389.81305093457;35620.82189589035;35704.82147589349;36138.8193059033;36558.8172059138;36569.90290291295;30762.84618576893;31792.523112152874;32229.194270143435;32488.025359239262;32541.67458325481;21423;22412.32763017112;23719.407014824767;23999.400014999766;24050 15:49:19 INFO - PROCESS | 1947 | |2;jquery.html;384.15294276121153;386.248746188663;399.8220791747673;402.51898980718045;421.94513715710724;17464.82535174648;18211.907285562876;19428.708569371454;20249.59500809985;20297.797022029776;11987;12316.261024338524;12351.073669474781;18523.518118550517;20358.287459938867;4344.695871289011;5032.144535428986;5233.241180028894;5248.606354523426;5272.604554658396;5104.438511763703;5247.973760131194;5249.107651699202;5258.106121959276;5259.789608415678;5134.974325128369;5255.211718242256;5259.342582177228;5259.395169555492;5277.3139491866;5606.24315717377;5714.971425142848;5740.339860916007;5751.654900705929;5752.424757524235;4918.262260660929;5069.44236134025;5168.121419358718;5186.092433824104;5191.247269145991;13148.60554183376;13220.933895330558;13317;13465.057445978786;13502.797458038138;2375.452337282503;2470.876456177202;2481.2249686876526;2491.2277194069716;2515.207709571479;5169.974150129226;9762.414255144644;9953;10022.098011179029;10201.948990255076;1751.7897852257684;1780.2078075256386;1788.499220218341;1790.8477779388768;1840.4754644926265;111427;117126;117686.41156794247;118737.40631296788;119764.80235197624;9727.249095162939;10247.897521024843;10397.48012599367;10437.165026797838;10705.304155229886;10999.725006874893;12930.935345323212;12961;12971.935140324238;12983.091183617245;7793.571353575494;7945.483543569765;8021.558814265271;8047.83904321922;8065.314448271845;7928.207179282025;7932.008498937633;7976.800579985431;8113;8119.837603247902;4073.185362927367;4154.667626589866;4158.625723684883;4235.8729238122905;4245.936310955338;1787.7586525819108;2016.5160361513424;2072.8445366597566;2074.1392322186425;2075.491504581357;861.3711990247155;899.3929097858919;973.2700474644017;1001.8697569315942;1042.4110377636612;18896.338628147947;20550.897245513675;20638.38084857457;20640.380788576367;20761;906.2795077913075;997.1574019953129;998.4059484606647;1035.694470131313;1045.4668119259081;8585.742427727178;10524.789504209873;10955.4522273886;11003.779924401468;11446.484908179153;702793;707840;708625;730142;731000;111652.44173779241;147332.26333868262;152461;155285.22357388368;155310;875.9794839012479;956.4261443134203;1021.7343490692325;1024.8513965475113;1027.8715160604925;15522;18074.18666160052;19212.61574768469;19487;19793.40619781409;49517.752411237714;55768.884622308135;57388.71305643612;57588.71205643945;57854.7107264461;59516.40483595282;63597;74489.6275518619;78494.607526962;86300;7217.530860494051;7383.261673832789;7551.131619863566;8052.758417247492;8578.871316930126;9185.448873067637;11873.228240164362;12547.749045018865;12931.676708082374;13092.410841511964;9383.718488445358;10694.037536621741;10863.076638485818;11055.0603198729;11187.776244475228;10339.276250662382;10817.45912704346;11208.887911120784;11234;11492.310461372344;9585.281103917094;11158.442077896236;11448.3703396314;11474.368909710047;11496.712582185512;7473.215312392338;7576.8484630308185;7603.467757256939;7699.3840492761565;7876.527408355517;9738.853917191105;10992.120630349393;11275.887241127484;11337.943310283395;11340.262882912584 15:49:19 INFO - PROCESS | 1947 | |3;mootools.html;1413.724323756867;1427.6573622330643;1430.7782293744474;1441.4090223008566;1468.6475245940974;8046.275835174833;8088.595570221495;8387.748367548968;8416.368772342068;8443.19789619986;7085.9645701771415;8315.542645154514;8427.747167584967;8452;8466.576671166447;2207.1612787140866;2262.864228146308;2263.0155882191216;2263.2757517594378;2263.76230495798;2226.309843948381;2258.1870526610405;2258.7063681721434;2262.4230821140586;2265.105283413051;2225;2230.9888450557723;2259.7966183043523;2260.728712554496;2263.513344630904;2145.7854214578574;2194.6707993800896;2211;2220.555888822242;2226.554689062186;1920.8847469151895;2001.8398528117862;2119.925802596917;2154;2161.0383379396176;6327.367263273682;7165.820854478679;7269.382102521292;8461.450005749668;8947.747315375853;4991.6256280778725;5125.436202017775;5140.434552199255;5158.690478571298;5165.974170129163;3329.350776598564;3435.089701229151;3471.548698669182;3500.8949731508096;3507.754457187998;1179.445660539545;1183.810590305547;1208.9576864809778;1216.1851559455097;1237.641084085614;8121.715739949073;10652.041316281495;10686.305390149615;10794.6761597153;10800.405977671226;3792.5259342582176;4100.8107648781515;4349.347597860283;4416.183006143874;4450.710703804242;7498.475106742476;7698.923010769932;8692.217700406994;8712.91287087121;8724.69463568772;5032.521910418578;5048.192289233779;5055.848324550269;5067.670601410897;5069.340985671913;4548.658850586219;4595.058013107305;4683.320918466871;4688.320193571913;4694.624430045588;3585.0678823506078;3652.7808331500196;3668.2296717689583;3694.3165514379925;3734.3278209922473;1047.1936608811172;1056.0495554001461;1127.3405058040973;1137.9374134422687;1147;740.8073900785835;822.005373498074;886.9600867960958;902.8104098139464;923.1829830599834;5154.845354639367;5445.128779395278;5569.610127291051;5717.885642287214;6008.849778755479;904.8099899021148;934.243262957007;969.8060387922444;977.726236653738;996.9900300997042;3230.499272612749;3450.913727156841;3758.4174452959833;3776.263628592453;3850.2876967761053;8957.641694332284;9322.813543729224;9405.106514881076;9432.575534100983;9824.901750982399;9770.169535589412;9960.601575936882;9987.900120998842;10010.099091081838;10045.748856278651;7542.094948606093;7791.922080779346;7805;7812.882806757903;7821.452498325063;5031.295618613324;5044.899102018013;5370.9731451342495;5510.201020852034;5869.196205691463;8972.596233169654;8991.28069754431;9144.039875812943;9167.44995300284;9230.169284764403;8549.700760473354;8797.824043519222;9227.261819054595;9383.436993780395;9389.718308450758;6937.791866244021;7230.13238411394;7244.166920804175;7267.200607933018;7267.6366181691765;7466.290702383265;7738.380929525511;7758.3017528422715;7794.103678076867;7870.842583148419;7008.789736307919;7020.613866237406;7025.227225005143;7093.432525398059;7120.750773722895;6769.864602708017;6771.424428923399;6868.896966545606;6990.720371185299;7006;6221.2845522765465;6298.496120310272;6355.618662880242;6383.297837237993;6405.615663060231;4340.457442819647;4764.690295130806;4842.539958703917;4909.582685471775;4968.080905032581;4778.116048531033;4881.193313844483;4922.744700101452;5044.520770526941;5064.924026139537 15:49:19 INFO - PROCESS | 1947 | |4;prototype.html;311.91890108571766;337.4887046125119;361.73412541781795;366.9339518886601;368.01922875536695;5292.184112457664;5397.5951803614735;5477.9591877543235;5510.228568000483;5534.584906132046;4423.208600516788;5368.275282836816;5421.159720243359;5452.236686863842;5473.370562385327;3670.50448189495;3733.842508822274;3746.801023672426;4134.090500089983;4134.354013431406;3433.416319225725;3533.6466353364717;3862.2854771867296;3976.860809871655;4031.778252196128;4032.5362583302854;4052.9797351013203;4054.351303791394;4081.204165187775;4110.266672662676;3461.9614115765294;4085.611866872642;4093.8157782899543;4097.344424892033;4102.917941641181;3242.837858107085;3449.58604967403;3526.8589256429714;3527.1658737456637;3530.852472946277;4415.496193882254;4589.35748995141;5055.570276526463;5301.734913254322;5332.893342133174;4137.124698334658;4663.323818046399;4687;4696.342512048316;4709.835155769531;4940.517844646628;5665.49010589049;5688.516476099537;5732.484076433099;5780.132980052984;3865.4588357629955;3868.6518213360932;3874.7720060964775;3893.53277606689;3906.941395879064;4575.359449677047;5121.641485096007;5885.735141918581;6061.181740465025;6085.908711369333;3462.671046250602;3550.851734700469;3724.962750372516;3886.4753258310334;3908.713402659476;3971.245463361952;3978;3978.462907507507;3984.880453586397;3991.900202494961;3998.4602078719;4283.528811830696;4399.560043995639;4627.652926030494;4642.16441040616;5710.172025056344;5771;5787.94212057874;5796.188533605298;5819.127130930395;1126.6451067913745;1184.976300474003;1290.0131399479405;1297.9935100324437;1303.7522870654545;805.8549461097059;811.6063709101081;872.2498651159997;877.8814859993947;884.4648987362682;5110.744462776846;5167.191482980927;5171.130022837287;5182.585393168538;5188.558972487305;4583.957646588726;5363.517283444508;5508;5686.715664216773;5739.368669446358;4384.6273066789045;4807.6394270429855;5183.796886323297;5220.895582088413;5254.500822421864;2991.2928799436545;2993.9670813569282;2996.925076873096;3008.7592992560544;3021.9093427197217;5590.832275031756;5757.474269318634;5895.882082358329;5944.078667806497;5948.821535353946;4954.925676114861;5411.485908838654;5690.146478028329;6108.389161083945;6146.323904370515;6217.751289948442;6233.252009758858;6256.5307601930035;6269.373062693694;6365.9681701593045;4625.306204069417;5485.491489840167;5657.302809157319;5932.7033648318275;5992.940070599413;5808.693044065051;5901.970490147522;5909.23179986599;5918.378570250062;5935.792247271324;5419.2413062170535;5774.538036957118;5914.615549989237;5951.58338916289;6203.069539569101;3639.526861507987;3883.4369016493015;3931.5085614298214;3942.4677668514382;3956.9604303957744;5102.387713474407;5419.0245755764345;5760.971195143998;5918.389782232921;5953.345132035386;5331.546818520314;5776.508996735324;5777.24895763548;5805.506531944663;5813.447722466359;5401.594880384066;5775;5791.391903850035;5801.564882633735;5826.912596310974;4624.722516649012;5395.8416633345405;5512.834614961557;5631.662100273997;5708.229389032427;5744.639385959261;5748.856278593069;5787.913181302368;5808.244928159313;5823.505002074701;5634.549236061187;5649.548036157015;5650.802221922213;5658.886822263449;5772.740226689725 15:49:19 INFO - PROCESS | 1947 | |5;yui.html;675.351662404092;705.6577559883459;770.4953255617569;784.4665627373388;786.8937693411387;13406.396712147953;14800;14852.554423367303;14860;14869.851301486982;12922.676933076678;13514.797278040838;13624.250666213355;13958.232297223649;14001.089929154597;883.3463237204471;893.0756666849812;901.5672477210943;902.2195800632471;904.5070436612062;841.4572600672562;874.6588830356167;892.1034360467721;894.0790985285166;894.1014280647939;896.8161526886972;902.3863772634605;906.3383729877194;915.5925613102172;921.0191146429065;912.9954350228239;917.1058218237232;917.8990311065777;918.9816203675889;920.9777147366451;680.9319068093151;684.096991970594;688.2257460357098;688.2257460357098;688.5971706551674;14639.707205855822;14755.704885902222;14848.406063757437;15140.242987850563;15306.311215995307;13615.387307571184;13717.17696938177;13762.24307663078;13884.861151388457;13894.23581703006;9883.604655813759;11174.944125279402;11278.210525263235;11351.772964540663;12065.758684826342;3550.6626870447267;3554.4668299754985;3571.14292569784;3605.405108157151;3700.870469533553;91191.08808911893;93851.06148938491;94452.05547944501;95381.52309238478;96091.51954240254;10175.487687879762;10386.532606032746;10544.261901666963;10703.518341674644;10714.642778588697;19925.501862453555;21037.68443473349;21072.15711371528;21626.567468650537;21658.78341216599;6476.838079047967;7466.552006879605;7467.962660186773;7691.538507689556;7816.765497034984;4018.3771515414574;4187.937180942288;4192.119654872511;4192.434021407132;4196.51740049892;3596.9280614387567;3683.9447408288897;3699.5005674233635;3705.94441083384;3709.1654377764785;573.3120255693185;657.9276279609239;677.9830504237434;691.4226620771713;696.6864910790165;628.7013668507423;642.7878799996083;678.9966050169817;681.6455443169524;706.3486938509867;9220.354575179806;9872.371058775365;9957.352772069793;9968.55141518619;9997.200223982063;662.9337066293332;686.6978529447027;690.3249906611879;691.2914262880588;706.1662917330862;4796.304535842283;4796.880077998008;4807.6394270429855;4822.71063736177;4846.224604063333;94319;96050.51974740221;96887;98065.50967245118;99362;62349;82015.58992205001;84972;85441.4367568974;87814.68277975707;76609.61695191488;77098.61450692822;77393.99999999888;77740;79772;7174.497785154989;8627.645831262893;9351.625934962798;9390.985570337558;9435;47928.5207147938;48617.784555384984;49529.78996785314;49719.75700607514;49731.502684972686;46565;56095;63287;64711.67644161749;66030.33969660434;11037.11703063737;11233.157513186381;11391;11440.141989350666;11874.584389546324;12152.392380381123;12465.875341246472;12521.686957825763;12556.183848049848;12732.681682958;9981;11279.09767218637;11668.766624667289;12049.156559040783;12177.573784917486;11282.244547807419;11678.883211168119;11691.415429228335;12055.758884822078;12096.516139354328;11370.94314528422;11464.770704586028;11964.46159922823;11969.521219151136;12011.399430028638;9425.670101546413;9708.563114659715;10012.541806020217;10078.899211007796;10112.544935478069;8215.548144852091;9183.265338772748;9403.43579385239;9431.764205894908;9478.810423791623 15:49:19 INFO - PROCESS | 1947 | __end_tp_report 15:49:19 INFO - PROCESS | 1947 | __start_cc_report 15:49:19 INFO - PROCESS | 1947 | _x_x_mozilla_cycle_collect,421 15:49:19 INFO - PROCESS | 1947 | __end_cc_report 15:49:19 INFO - PROCESS | 1947 | __startTimestamp1486943359208__endTimestamp 15:49:19 INFO - PROCESS | 1947 | 15:49:19 INFO - PROCESS | 1947 | ------- Summary: start ------- 15:49:19 INFO - PROCESS | 1947 | Number of tests: 6 15:49:19 INFO - PROCESS | 1947 | 15:49:19 INFO - PROCESS | 1947 | [#0] dojo.html Cycles:165 Average:5609.57 Median:5846.56 stddev:667.89 (11.4%) stddev-sans-first:643.92 15:49:19 INFO - PROCESS | 1947 | Values: 3256.0 3264.8 3272.2 3280.2 3330.9 5516.9 5536.5 5541.4 5568.2 5586.2 5612.4 5639.7 5652.9 5663.9 5704.7 4751.5 5068.9 5078.8 5123.1 5180.5 5140.1 5169.2 5196.9 5219.2 5263.6 4172.9 4342.4 5060.5 5172.7 5216.7 5085.1 5093.3 5117.7 5127.5 5136.1 4216.9 4858.6 5089.8 5089.9 5152.7 5022.6 5297.3 5329.8 5489.4 5497.2 6112.4 6119.8 6141.0 6141.3 6157.5 6191.5 6207.4 6208.9 6216.7 6230.6 5559.7 5578.0 5587.9 5658.7 5720.8 6169.7 6170.8 6206.3 6209.2 6210.8 5707.4 5776.8 5884.8 6033.8 6081.4 6103.0 6169.5 6209.7 6217.5 6247.9 5829.4 6119.1 6167.9 6176.2 6182.0 5929.2 5978.3 6013.5 6019.3 6042.4 4850.5 4903.6 4938.7 4956.5 5015.5 4365.9 4383.4 4395.5 4402.4 4408.3 5868.6 5877.9 5883.5 5884.6 5886.8 6047.5 6049.0 6108.0 6131.3 6138.9 5664.9 5687.1 5689.8 5704.2 5742.0 5292.9 5322.8 5325.6 5330.6 5335.9 6131.9 6172.6 6185.0 6185.9 6227.8 6258.6 6268.9 6269.3 6279.9 6285.7 6163.2 6219.4 6226.3 6258.9 6301.2 5403.1 5980.5 5981.9 6158.4 6187.9 4918.5 6105.4 6120.7 6141.9 6150.9 4812.9 5175.9 5926.1 6035.0 6056.2 4559.7 6016.3 6074.9 6089.5 6129.5 5598.6 5741.6 6016.0 6027.2 6032.7 5828.2 5846.6 5869.9 5973.4 6002.2 5825.7 5897.2 5981.9 5983.3 6026.8 15:49:19 INFO - PROCESS | 1947 | 15:49:19 INFO - PROCESS | 1947 | [#1] ext.html Cycles:180 Average:66567.32 Median:34985.24 stddev:141284.75 (403.8%) stddev-sans-first:141593.89 15:49:19 INFO - PROCESS | 1947 | Values: 453.3 481.2 484.8 503.8 516.3 32174.0 38988.2 39087.4 39238.4 41527.4 39524.8 40140.2 40152.6 40198.8 40208.4 8859.4 9196.9 9221.3 9243.7 10452.8 10269.3 10711.7 10719.8 10729.1 10766.0 10679.5 10695.7 10724.5 10728.6 10732.6 10645.7 10679.8 10713.3 10715.7 10724.3 10555.8 10565.3 10646.3 10649.9 10667.4 34654.5 34924.3 34979.8 34990.7 35056.3 3165.8 3227.3 3639.6 3668.2 3854.2 27785.0 29072.6 29410.3 29836.0 29889.0 2941.4 3083.7 3112.8 3182.8 3193.8 414982.0 468986.0 469549.0 470817.0 473328.0 54172.7 54549.5 64911.0 65321.0 65722.7 66550.0 68021.7 68159.6 73704.0 75307.0 15363.0 15518.8 15521.2 15548.8 15590.8 11642.4 11731.9 11806.5 11893.4 11903.3 19434.3 19442.1 19825.8 19868.3 19939.1 8435.1 8472.9 8488.7 8534.1 8617.7 835.1 854.3 857.9 862.4 867.5 28559.9 28727.3 29276.4 29540.0 29550.1 1018.1 1038.3 1051.9 1065.9 1076.1 14340.5 14799.9 14867.5 15282.2 15319.2 606266.0 609989.0 610115.0 611557.0 611658.0 56660.7 57305.9 57590.8 57998.7 58117.4 1327.9 1357.5 1361.1 1371.2 1371.8 27620.7 28332.6 28861.3 28935.6 29410.3 49399.0 49530.0 49535.3 49547.3 49806.3 506855.0 507099.0 508435.0 508734.0 511096.0 39313.6 40302.6 40673.0 41069.0 41127.8 44411.3 45608.3 45634.6 45797.0 45965.5 28541.0 33550.5 33867.0 36687.0 37567.4 32475.7 36146.3 36329.8 37311.9 37389.8 35620.8 35704.8 36138.8 36558.8 36569.9 30762.8 31792.5 32229.2 32488.0 32541.7 21423.0 22412.3 23719.4 23999.4 24050.0 15:49:19 INFO - PROCESS | 1947 | 15:49:19 INFO - PROCESS | 1947 | [#2] jquery.html Cycles:180 Average:37812.20 Median:2486.23 stddev:119092.74 (4790.1%) stddev-sans-first:119393.66 15:49:19 INFO - PROCESS | 1947 | Values: 384.2 386.2 399.8 402.5 421.9 17464.8 18211.9 19428.7 20249.6 20297.8 11987.0 12316.3 12351.1 18523.5 20358.3 4344.7 5032.1 5233.2 5248.6 5272.6 5104.4 5248.0 5249.1 5258.1 5259.8 5135.0 5255.2 5259.3 5259.4 5277.3 5606.2 5715.0 5740.3 5751.7 5752.4 4918.3 5069.4 5168.1 5186.1 5191.2 13148.6 13220.9 13317.0 13465.1 13502.8 2375.5 2470.9 2481.2 2491.2 2515.2 5170.0 9762.4 9953.0 10022.1 10201.9 1751.8 1780.2 1788.5 1790.8 1840.5 111427.0 117126.0 117686.4 118737.4 119764.8 9727.2 10247.9 10397.5 10437.2 10705.3 10999.7 12930.9 12961.0 12971.9 12983.1 7793.6 7945.5 8021.6 8047.8 8065.3 7928.2 7932.0 7976.8 8113.0 8119.8 4073.2 4154.7 4158.6 4235.9 4245.9 1787.8 2016.5 2072.8 2074.1 2075.5 861.4 899.4 973.3 1001.9 1042.4 18896.3 20550.9 20638.4 20640.4 20761.0 906.3 997.2 998.4 1035.7 1045.5 8585.7 10524.8 10955.5 11003.8 11446.5 702793.0 707840.0 708625.0 730142.0 731000.0 111652.4 147332.3 152461.0 155285.2 155310.0 876.0 956.4 1021.7 1024.9 1027.9 15522.0 18074.2 19212.6 19487.0 19793.4 49517.8 55768.9 57388.7 57588.7 57854.7 59516.4 63597.0 74489.6 78494.6 86300.0 7217.5 7383.3 7551.1 8052.8 8578.9 9185.4 11873.2 12547.7 12931.7 13092.4 9383.7 10694.0 10863.1 11055.1 11187.8 10339.3 10817.5 11208.9 11234.0 11492.3 9585.3 11158.4 11448.4 11474.4 11496.7 7473.2 7576.8 7603.5 7699.4 7876.5 9738.9 10992.1 11275.9 11337.9 11340.3 15:49:19 INFO - PROCESS | 1947 | 15:49:19 INFO - PROCESS | 1947 | [#3] mootools.html Cycles:180 Average:5277.30 Median:5147.64 stddev:2869.25 (55.7%) stddev-sans-first:2862.60 15:49:19 INFO - PROCESS | 1947 | Values: 1413.7 1427.7 1430.8 1441.4 1468.6 8046.3 8088.6 8387.7 8416.4 8443.2 7086.0 8315.5 8427.7 8452.0 8466.6 2207.2 2262.9 2263.0 2263.3 2263.8 2226.3 2258.2 2258.7 2262.4 2265.1 2225.0 2231.0 2259.8 2260.7 2263.5 2145.8 2194.7 2211.0 2220.6 2226.6 1920.9 2001.8 2119.9 2154.0 2161.0 6327.4 7165.8 7269.4 8461.5 8947.7 4991.6 5125.4 5140.4 5158.7 5166.0 3329.4 3435.1 3471.5 3500.9 3507.8 1179.4 1183.8 1209.0 1216.2 1237.6 8121.7 10652.0 10686.3 10794.7 10800.4 3792.5 4100.8 4349.3 4416.2 4450.7 7498.5 7698.9 8692.2 8712.9 8724.7 5032.5 5048.2 5055.8 5067.7 5069.3 4548.7 4595.1 4683.3 4688.3 4694.6 3585.1 3652.8 3668.2 3694.3 3734.3 1047.2 1056.0 1127.3 1137.9 1147.0 740.8 822.0 887.0 902.8 923.2 5154.8 5445.1 5569.6 5717.9 6008.8 904.8 934.2 969.8 977.7 997.0 3230.5 3450.9 3758.4 3776.3 3850.3 8957.6 9322.8 9405.1 9432.6 9824.9 9770.2 9960.6 9987.9 10010.1 10045.7 7542.1 7791.9 7805.0 7812.9 7821.5 5031.3 5044.9 5371.0 5510.2 5869.2 8972.6 8991.3 9144.0 9167.4 9230.2 8549.7 8797.8 9227.3 9383.4 9389.7 6937.8 7230.1 7244.2 7267.2 7267.6 7466.3 7738.4 7758.3 7794.1 7870.8 7008.8 7020.6 7025.2 7093.4 7120.8 6769.9 6771.4 6868.9 6990.7 7006.0 6221.3 6298.5 6355.6 6383.3 6405.6 4340.5 4764.7 4842.5 4909.6 4968.1 4778.1 4881.2 4922.7 5044.5 5064.9 15:49:19 INFO - PROCESS | 1947 | 15:49:19 INFO - PROCESS | 1947 | [#4] prototype.html Cycles:180 Average:4608.99 Median:5237.70 stddev:1451.10 (27.7%) stddev-sans-first:1418.87 15:49:19 INFO - PROCESS | 1947 | Values: 311.9 337.5 361.7 366.9 368.0 5292.2 5397.6 5478.0 5510.2 5534.6 4423.2 5368.3 5421.2 5452.2 5473.4 3670.5 3733.8 3746.8 4134.1 4134.4 3433.4 3533.6 3862.3 3976.9 4031.8 4032.5 4053.0 4054.4 4081.2 4110.3 3462.0 4085.6 4093.8 4097.3 4102.9 3242.8 3449.6 3526.9 3527.2 3530.9 4415.5 4589.4 5055.6 5301.7 5332.9 4137.1 4663.3 4687.0 4696.3 4709.8 4940.5 5665.5 5688.5 5732.5 5780.1 3865.5 3868.7 3874.8 3893.5 3906.9 4575.4 5121.6 5885.7 6061.2 6085.9 3462.7 3550.9 3725.0 3886.5 3908.7 3971.2 3978.0 3978.5 3984.9 3991.9 3998.5 4283.5 4399.6 4627.7 4642.2 5710.2 5771.0 5787.9 5796.2 5819.1 1126.6 1185.0 1290.0 1298.0 1303.8 805.9 811.6 872.2 877.9 884.5 5110.7 5167.2 5171.1 5182.6 5188.6 4584.0 5363.5 5508.0 5686.7 5739.4 4384.6 4807.6 5183.8 5220.9 5254.5 2991.3 2994.0 2996.9 3008.8 3021.9 5590.8 5757.5 5895.9 5944.1 5948.8 4954.9 5411.5 5690.1 6108.4 6146.3 6217.8 6233.3 6256.5 6269.4 6366.0 4625.3 5485.5 5657.3 5932.7 5992.9 5808.7 5902.0 5909.2 5918.4 5935.8 5419.2 5774.5 5914.6 5951.6 6203.1 3639.5 3883.4 3931.5 3942.5 3957.0 5102.4 5419.0 5761.0 5918.4 5953.3 5331.5 5776.5 5777.2 5805.5 5813.4 5401.6 5775.0 5791.4 5801.6 5826.9 4624.7 5395.8 5512.8 5631.7 5708.2 5744.6 5748.9 5787.9 5808.2 5823.5 5634.5 5649.5 5650.8 5658.9 5772.7 15:49:19 INFO - PROCESS | 1947 | 15:49:19 INFO - PROCESS | 1947 | [#5] yui.html Cycles:180 Average:19014.63 Median:31957.85 stddev:27218.36 (85.2%) stddev-sans-first:27259.88 15:49:19 INFO - PROCESS | 1947 | Values: 675.4 705.7 770.5 784.5 786.9 13406.4 14800.0 14852.6 14860.0 14869.9 12922.7 13514.8 13624.3 13958.2 14001.1 883.3 893.1 901.6 902.2 904.5 841.5 874.7 892.1 894.1 894.1 896.8 902.4 906.3 915.6 921.0 913.0 917.1 917.9 919.0 921.0 680.9 684.1 688.2 688.2 688.6 14639.7 14755.7 14848.4 15140.2 15306.3 13615.4 13717.2 13762.2 13884.9 13894.2 9883.6 11174.9 11278.2 11351.8 12065.8 3550.7 3554.5 3571.1 3605.4 3700.9 91191.1 93851.1 94452.1 95381.5 96091.5 10175.5 10386.5 10544.3 10703.5 10714.6 19925.5 21037.7 21072.2 21626.6 21658.8 6476.8 7466.6 7468.0 7691.5 7816.8 4018.4 4187.9 4192.1 4192.4 4196.5 3596.9 3683.9 3699.5 3705.9 3709.2 573.3 657.9 678.0 691.4 696.7 628.7 642.8 679.0 681.6 706.3 9220.4 9872.4 9957.4 9968.6 9997.2 662.9 686.7 690.3 691.3 706.2 4796.3 4796.9 4807.6 4822.7 4846.2 94319.0 96050.5 96887.0 98065.5 99362.0 62349.0 82015.6 84972.0 85441.4 87814.7 76609.6 77098.6 77394.0 77740.0 79772.0 7174.5 8627.6 9351.6 9391.0 9435.0 47928.5 48617.8 49529.8 49719.8 49731.5 46565.0 56095.0 63287.0 64711.7 66030.3 11037.1 11233.2 11391.0 11440.1 11874.6 12152.4 12465.9 12521.7 12556.2 12732.7 9981.0 11279.1 11668.8 12049.2 12177.6 11282.2 11678.9 11691.4 12055.8 12096.5 11370.9 11464.8 11964.5 11969.5 12011.4 9425.7 9708.6 10012.5 10078.9 10112.5 8215.5 9183.3 9403.4 9431.8 9478.8 15:49:19 INFO - PROCESS | 1947 | -------- Summary: end -------- 15:49:19 INFO - PROCESS | 1947 | 15:49:19 INFO - TEST-INFO | 1947: exit 0 15:49:19 INFO - TEST-OK | dromaeo_css | took 1117118ms 15:49:19 INFO - TEST-START | kraken 15:49:19 INFO - operating with platform_type : mac_ 15:49:19 INFO - Initialising browser for kraken test... 15:49:19 INFO - TEST-INFO | started process 1952 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/tmpHr_It8/profile http://localhost:49375/getInfo.html) 15:49:22 INFO - PROCESS | 1952 | 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] 15:49:22 INFO - PROCESS | 1952 | 2017-02-12 15:49:22.800 plugin-container[1953:27020] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a43, name = 'com.apple.tsm.portname' 15:49:22 INFO - PROCESS | 1952 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:49:22 INFO - PROCESS | 1952 | 2017-02-12 15:49:22.803 plugin-container[1953:27020] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4707, name = 'com.apple.CFPasteboardClient' 15:49:22 INFO - PROCESS | 1952 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:49:22 INFO - PROCESS | 1952 | 2017-02-12 15:49:22.803 plugin-container[1953:27020] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:49:22 INFO - PROCESS | 1952 | __metrics Screen width/height:1600/1200 15:49:22 INFO - PROCESS | 1952 | colorDepth:24 15:49:22 INFO - PROCESS | 1952 | Browser inner width/height: 1024/689 15:49:22 INFO - PROCESS | 1952 | __metrics 15:49:22 ERROR - PROCESS | 1952 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3283: TypeError: this.tabs is undefined 15:49:23 INFO - TEST-INFO | 1952: exit 0 15:49:23 INFO - Browser initialized. 15:49:23 INFO - Running cycle 1/1 for kraken test... 15:49:23 INFO - TEST-INFO | started process 1954 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/tmpHr_It8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:49:26 INFO - PROCESS | 1954 | 2017-02-12 15:49:26.084 plugin-container[1955:27132] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x443f, name = 'com.apple.tsm.portname' 15:49:26 INFO - PROCESS | 1954 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:49:26 INFO - PROCESS | 1954 | 2017-02-12 15:49:26.085 plugin-container[1955:27132] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 15:49:26 INFO - PROCESS | 1954 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:49:26 INFO - PROCESS | 1954 | 2017-02-12 15:49:26.085 plugin-container[1955:27132] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:49:35 INFO - PROCESS | 1954 | RSS: Main: 233996288 15:49:35 INFO - PROCESS | 1954 | 15:49:38 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:49375/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49375/tests/kraken/driver.html?testName=audio-beat-detection) 15:49:39 INFO - PROCESS | 1954 | RSS: Main: 247574528 15:49:39 INFO - PROCESS | 1954 | 15:49:42 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:49375/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49375/tests/kraken/driver.html?testName=audio-dft) 15:49:43 INFO - PROCESS | 1954 | RSS: Main: 255442944 15:49:43 INFO - PROCESS | 1954 | 15:49:46 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:49375/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49375/tests/kraken/driver.html?testName=audio-fft) 15:49:47 INFO - PROCESS | 1954 | RSS: Main: 258105344 15:49:47 INFO - PROCESS | 1954 | 15:49:49 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:49375/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49375/tests/kraken/driver.html?testName=audio-oscillator) 15:49:49 INFO - PROCESS | 1954 | RSS: Main: 260485120 15:49:49 INFO - PROCESS | 1954 | 15:49:51 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:49375/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49375/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:49:52 INFO - PROCESS | 1954 | RSS: Main: 260861952 15:49:52 INFO - PROCESS | 1954 | 15:49:55 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:49375/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49375/tests/kraken/driver.html?testName=imaging-darkroom) 15:49:55 INFO - PROCESS | 1954 | RSS: Main: 266416128 15:49:55 INFO - PROCESS | 1954 | 15:49:58 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:49375/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49375/tests/kraken/driver.html?testName=imaging-desaturate) 15:49:59 INFO - PROCESS | 1954 | RSS: Main: 266104832 15:49:59 INFO - PROCESS | 1954 | 15:50:02 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:49375/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49375/tests/kraken/driver.html?testName=json-parse-financial) 15:50:03 INFO - PROCESS | 1954 | RSS: Main: 266227712 15:50:03 INFO - PROCESS | 1954 | 15:50:04 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:49375/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49375/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:50:05 INFO - PROCESS | 1954 | RSS: Main: 265461760 15:50:05 INFO - PROCESS | 1954 | 15:50:07 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:49375/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49375/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:50:08 INFO - PROCESS | 1954 | RSS: Main: 265723904 15:50:08 INFO - PROCESS | 1954 | 15:50:10 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:49375/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49375/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:50:11 INFO - PROCESS | 1954 | RSS: Main: 265588736 15:50:11 INFO - PROCESS | 1954 | 15:50:14 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:49375/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49375/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:50:15 INFO - PROCESS | 1954 | RSS: Main: 265932800 15:50:15 INFO - PROCESS | 1954 | 15:50:18 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:49375/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49375/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:50:18 INFO - PROCESS | 1954 | RSS: Main: 265887744 15:50:18 INFO - PROCESS | 1954 | 15:50:20 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:49375/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49375/tests/kraken/driver.html?testName=ai-astar) 15:50:20 INFO - PROCESS | 1954 | RSS: Main: 267919360 15:50:20 INFO - PROCESS | 1954 | 15:50:20 INFO - PROCESS | 1954 | __start_tp_report 15:50:20 INFO - PROCESS | 1954 | _x_x_mozilla_page_load 15:50:20 INFO - PROCESS | 1954 | _x_x_mozilla_page_load_details 15:50:20 INFO - PROCESS | 1954 | |i|pagename|runs| 15:50:20 INFO - PROCESS | 1954 | |0;ai-astar;105;118;120;118;117;117;118;118;118;118 15:50:20 INFO - PROCESS | 1954 | |1;audio-beat-detection;146;116;112;159;122;149;107;148;132;148 15:50:20 INFO - PROCESS | 1954 | |2;audio-dft;220;242;206;217;197;199;199;201;200;201 15:50:20 INFO - PROCESS | 1954 | |3;audio-fft;85;57;60;87;59;61;63;61;72;61 15:50:20 INFO - PROCESS | 1954 | |4;audio-oscillator;87;71;86;71;72;71;82;73;70;84 15:50:20 INFO - PROCESS | 1954 | |5;imaging-gaussian-blur;99;88;89;88;89;93;128;94;92;89 15:50:20 INFO - PROCESS | 1954 | |6;imaging-darkroom;96;97;97;96;98;107;96;151;96;95 15:50:20 INFO - PROCESS | 1954 | |7;imaging-desaturate;94;95;98;129;91;116;142;145;93;88 15:50:20 INFO - PROCESS | 1954 | |8;json-parse-financial;62;93;64;63;65;58;84;97;99;97 15:50:20 INFO - PROCESS | 1954 | |9;json-stringify-tinderbox;60;91;73;108;107;63;71;97;104;106 15:50:20 INFO - PROCESS | 1954 | |10;stanford-crypto-aes;92;92;90;90;107;107;96;93;90;96 15:50:20 INFO - PROCESS | 1954 | |11;stanford-crypto-ccm;159;182;142;137;143;152;153;155;192;175 15:50:20 INFO - PROCESS | 1954 | |12;stanford-crypto-pbkdf2;243;187;186;176;176;176;178;176;173;173 15:50:20 INFO - PROCESS | 1954 | |13;stanford-crypto-sha256-iterative;69;69;67;70;69;68;68;69;67;69 15:50:20 INFO - PROCESS | 1954 | __end_tp_report 15:50:20 INFO - PROCESS | 1954 | __start_cc_report 15:50:20 INFO - PROCESS | 1954 | _x_x_mozilla_cycle_collect,996 15:50:20 INFO - PROCESS | 1954 | __end_cc_report 15:50:20 INFO - PROCESS | 1954 | __startTimestamp1486943420690__endTimestamp 15:50:20 INFO - PROCESS | 1954 | 15:50:20 INFO - PROCESS | 1954 | ------- Summary: start ------- 15:50:20 INFO - PROCESS | 1954 | Number of tests: 14 15:50:20 INFO - PROCESS | 1954 | 15:50:20 INFO - PROCESS | 1954 | [#0] ai-astar Cycles:10 Average:116.70 Median:118.00 stddev:4.19 (3.6%) stddev-sans-first:0.87 15:50:20 INFO - PROCESS | 1954 | Values: 105.0 118.0 120.0 118.0 117.0 117.0 118.0 118.0 118.0 118.0 15:50:20 INFO - PROCESS | 1954 | 15:50:20 INFO - PROCESS | 1954 | [#1] audio-beat-detection Cycles:10 Average:133.90 Median:147.00 stddev:18.47 (12.6%) stddev-sans-first:19.07 15:50:20 INFO - PROCESS | 1954 | Values: 146.0 116.0 112.0 159.0 122.0 149.0 107.0 148.0 132.0 148.0 15:50:20 INFO - PROCESS | 1954 | 15:50:20 INFO - PROCESS | 1954 | [#2] audio-dft Cycles:10 Average:208.20 Median:203.50 stddev:14.26 (7.0%) stddev-sans-first:14.47 15:50:20 INFO - PROCESS | 1954 | Values: 220.0 242.0 206.0 217.0 197.0 199.0 199.0 201.0 200.0 201.0 15:50:20 INFO - PROCESS | 1954 | 15:50:20 INFO - PROCESS | 1954 | [#3] audio-fft Cycles:10 Average:66.60 Median:62.00 stddev:10.98 (17.7%) stddev-sans-first:9.41 15:50:20 INFO - PROCESS | 1954 | Values: 85.0 57.0 60.0 87.0 59.0 61.0 63.0 61.0 72.0 61.0 15:50:20 INFO - PROCESS | 1954 | 15:50:20 INFO - PROCESS | 1954 | [#4] audio-oscillator Cycles:10 Average:76.70 Median:77.50 stddev:7.09 (9.1%) stddev-sans-first:6.46 15:50:20 INFO - PROCESS | 1954 | Values: 87.0 71.0 86.0 71.0 72.0 71.0 82.0 73.0 70.0 84.0 15:50:20 INFO - PROCESS | 1954 | 15:50:20 INFO - PROCESS | 1954 | [#5] imaging-gaussian-blur Cycles:10 Average:94.90 Median:90.50 stddev:12.13 (13.4%) stddev-sans-first:12.78 15:50:20 INFO - PROCESS | 1954 | Values: 99.0 88.0 89.0 88.0 89.0 93.0 128.0 94.0 92.0 89.0 15:50:20 INFO - PROCESS | 1954 | 15:50:20 INFO - PROCESS | 1954 | [#6] imaging-darkroom Cycles:10 Average:102.90 Median:96.00 stddev:17.25 (18.0%) stddev-sans-first:18.11 15:50:20 INFO - PROCESS | 1954 | Values: 96.0 97.0 97.0 96.0 98.0 107.0 96.0 151.0 96.0 95.0 15:50:20 INFO - PROCESS | 1954 | 15:50:20 INFO - PROCESS | 1954 | [#7] imaging-desaturate Cycles:10 Average:109.10 Median:92.00 stddev:22.10 (24.0%) stddev-sans-first:22.76 15:50:20 INFO - PROCESS | 1954 | Values: 94.0 95.0 98.0 129.0 91.0 116.0 142.0 145.0 93.0 88.0 15:50:20 INFO - PROCESS | 1954 | 15:50:20 INFO - PROCESS | 1954 | [#8] json-parse-financial Cycles:10 Average:78.20 Median:88.50 stddev:17.22 (19.5%) stddev-sans-first:17.24 15:50:20 INFO - PROCESS | 1954 | Values: 62.0 93.0 64.0 63.0 65.0 58.0 84.0 97.0 99.0 97.0 15:50:20 INFO - PROCESS | 1954 | 15:50:20 INFO - PROCESS | 1954 | [#9] json-stringify-tinderbox Cycles:10 Average:88.00 Median:67.00 stddev:19.30 (28.8%) stddev-sans-first:17.62 15:50:20 INFO - PROCESS | 1954 | Values: 60.0 91.0 73.0 108.0 107.0 63.0 71.0 97.0 104.0 106.0 15:50:20 INFO - PROCESS | 1954 | 15:50:20 INFO - PROCESS | 1954 | [#10] stanford-crypto-aes Cycles:10 Average:95.30 Median:92.00 stddev:6.55 (7.1%) stddev-sans-first:6.84 15:50:20 INFO - PROCESS | 1954 | Values: 92.0 92.0 90.0 90.0 107.0 107.0 96.0 93.0 90.0 96.0 15:50:20 INFO - PROCESS | 1954 | 15:50:20 INFO - PROCESS | 1954 | [#11] stanford-crypto-ccm Cycles:10 Average:159.00 Median:157.00 stddev:18.27 (11.6%) stddev-sans-first:19.38 15:50:20 INFO - PROCESS | 1954 | Values: 159.0 182.0 142.0 137.0 143.0 152.0 153.0 155.0 192.0 175.0 15:50:20 INFO - PROCESS | 1954 | 15:50:20 INFO - PROCESS | 1954 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:184.40 Median:177.00 stddev:21.15 (11.9%) stddev-sans-first:5.13 15:50:20 INFO - PROCESS | 1954 | Values: 243.0 187.0 186.0 176.0 176.0 176.0 178.0 176.0 173.0 173.0 15:50:20 INFO - PROCESS | 1954 | 15:50:20 INFO - PROCESS | 1954 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.50 Median:69.00 stddev:0.97 (1.4%) stddev-sans-first:1.01 15:50:20 INFO - PROCESS | 1954 | Values: 69.0 69.0 67.0 70.0 69.0 68.0 68.0 69.0 67.0 69.0 15:50:20 INFO - PROCESS | 1954 | -------- Summary: end -------- 15:50:20 INFO - PROCESS | 1954 | 15:50:21 INFO - TEST-INFO | 1954: exit 0 15:50:21 INFO - TEST-OK | kraken | took 61723ms 15:50:21 INFO - SUITE-END | took 1178s 15:50:21 INFO - Completed test suite (00:19:39) 15:50:21 INFO - javascript benchmark 15:50:21 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7095.831783082406, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3255.9999999999995, 3264.7877887937284, 3272.231025708958, 3280.2291461506557, 3330.933381332373, 5516.91724624131, 5536.501714845663, 5541.445855414457, 5568.248286481324, 5586.217929489874, 5612.382637909826, 5639.718014099289, 5652.858678533028, 5663.9433605663935, 5704.743286552105, 4751.453582837983, 5068.873278168038, 5078.771455284503, 5123.077845987721, 5180.507851754077, 5140.074786538422, 5169.172932330828, 5196.8700782480555, 5219.243209734586, 5263.631545791796, 4172.853950111731, 4342.370356298335, 5060.46865079165, 5172.741362931876, 5216.739163041832, 5085.110105731519, 5093.261477085802, 5117.7185254810975, 5127.48725127488, 5136.101182293121, 4216.873493795191, 4858.562729354339, 5089.770960306758, 5089.8727531812, 5152.690838549699, 5022.573081288095, 5297.258383826267, 5329.840104796862, 5489.39616642169, 5497.202905578669, 6112.351504965182, 6119.837693858745, 6140.995089002448, 6141.33258723369, 6157.476614487774, 6191.473724733403, 6207.379262073801, 6208.906866397007, 6216.68916554175, 6230.5638605297645, 5559.6664200148125, 5577.972110139504, 5587.944120558743, 5658.74535645897, 5720.799772007959, 6169.722362493699, 6170.81487555374, 6206.317305096436, 6209.19280493542, 6210.844728881814, 5707.429257074259, 5776.768929242868, 5884.7940322088525, 6033.788817391458, 6081.351189286393, 6103.034636161462, 6169.475594574512, 6209.689515524296, 6217.4715149212825, 6247.875042499216, 5829.358770535237, 6119.082137679384, 6167.907481387782, 6176.227971503562, 6182.0, 5929.169916211734, 5978.342382337939, 6013.4888534474185, 6019.337872833985, 6042.425969532887, 4850.514948505192, 4903.632227582874, 4938.654294199443, 4956.479569645207, 5015.498450155028, 4365.934510982338, 4383.3863259143745, 4395.53846846083, 4402.449693788277, 4408.338749187647, 5868.559858010666, 5877.853053673693, 5883.529317654577, 5884.6469211847425, 5886.793962211302, 6047.485963693135, 6048.969755151196, 6108.0, 6131.264248290234, 6138.907916381258, 5664.943350566442, 5687.118496633028, 5689.7724091036725, 5704.22992895962, 5741.995150848585, 5292.92060619091, 5322.760475778599, 5325.600579956519, 5330.600204984642, 5335.893282134336, 6131.938680613226, 6172.598781079216, 6185.041318595625, 6185.948388773829, 6227.813165605039, 6258.624482531062, 6268.937310626835, 6269.341719119427, 6279.874402512016, 6285.717142728497, 6163.229596300463, 6219.440250377488, 6226.283977342664, 6258.9374106260175, 6301.243850737941, 5403.108487099585, 5980.491658209101, 5981.880362392815, 6158.3841615838055, 6187.938120618916, 4918.508149185053, 6105.3894610540365, 6120.724567394389, 6141.877162456636, 6150.892839288971, 4812.903741925072, 5175.870603234798, 5926.140709596998, 6035.004224302941, 6056.242969628796, 4559.658025648156, 6016.338202797776, 6074.878502430015, 6089.512838972783, 6129.481048574228, 5598.608097433304, 5741.564608847788, 6015.969920150546, 6027.186329845415, 6032.728527216374, 5828.154917537018, 5846.5615078870105, 5869.941300586939, 5973.432523910222, 6002.189704389852, 5825.737841797214, 5897.174395584536, 5981.910271346021, 5983.311919129356, 6026.849328766816], "value": 5567.390686384605, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [453.265709550528, 481.1579735462942, 484.8496965940557, 503.78337314954564, 516.346821271092, 32174.0, 38988.220235595305, 39087.41368879469, 39238.411423828664, 41527.37708934368, 39524.81425557231, 40140.19719605606, 40152.59847401525, 40198.799006005, 40208.38645488041, 8859.424137431079, 9196.862047069299, 9221.308401869854, 9243.722688319362, 10452.790944181112, 10269.28115031948, 10711.732206694816, 10719.839202411931, 10729.08802751767, 10766.0, 10679.519421626006, 10695.732606684818, 10724.517396717167, 10728.624498142566, 10732.624358147466, 10645.733856653607, 10679.78640427187, 10713.250072494928, 10715.678529644123, 10724.302920310232, 10555.84166237507, 10565.260431769779, 10646.25476216665, 10649.946750266277, 10667.413292268922, 34654.48018279728, 34924.30151396983, 34979.82510087459, 34990.65009349925, 35056.29887402263, 3165.7625678074237, 3227.306129182237, 3639.6178401268016, 3668.229671768932, 3854.1520865409843, 27785.0, 29072.563911541343, 29410.264743381587, 29836.0, 29889.0, 2941.3970136122043, 3083.7378822800088, 3112.7821052526333, 3182.80903145812, 3193.8243396613175, 414982.0, 468986.0, 469549.0, 470817.0, 473328.0, 54172.729136354064, 54549.45450545523, 64911.0, 65321.0, 65722.67138664276, 66550.0, 68021.65989170023, 68159.63680726358, 73704.0, 75307.0, 15363.0, 15518.767218491732, 15521.223938803014, 15548.76676849848, 15590.766138507932, 11642.41787910618, 11731.88268117325, 11806.46870890812, 11893.40532973348, 11903.34531600753, 19434.339624514076, 19442.125104370054, 19825.801741982683, 19868.304609338604, 19939.10274037672, 8435.114312997048, 8472.872906906401, 8488.660453581911, 8534.146585341416, 8617.741467755977, 835.1084792943986, 854.2695994924375, 857.9013413457408, 862.3733668866486, 867.5488745852248, 28559.857200713865, 28727.2818179543, 29276.414471710446, 29539.966101186787, 29550.113496595135, 1018.1193267823279, 1038.2783965144304, 1051.8895515970712, 1065.872095348563, 1076.0907033556632, 14340.498082567061, 14799.852001480063, 14867.479638212611, 15282.159481228638, 15319.157446340558, 606266.0, 609989.0, 610115.0, 611557.0, 611658.0, 56660.716696417076, 57305.85388292211, 57590.84818303694, 57998.71000645054, 58117.4188258112, 1327.9468821247042, 1357.4773712120707, 1361.101672895884, 1371.2184055088503, 1371.766799644063, 27620.685517738, 28332.57501137526, 28861.27846803847, 28935.565966510097, 29410.264743381587, 49399.0, 49530.00939981252, 49535.256971146184, 49547.26584569523, 49806.252906205715, 506855.0, 507099.0, 508435.0, 508734.0, 511096.0, 39313.60686393214, 40302.596974031054, 40673.0, 41069.0, 41127.794361028005, 44411.33382999193, 45608.31587526256, 45634.63096107122, 45797.0, 45965.54034459613, 28541.0, 33550.49674254937, 33867.0, 36687.0, 37567.43648845324, 32475.675243247264, 36146.27707445784, 36329.81835090808, 37311.880643580735, 37389.81305093457, 35620.82189589035, 35704.82147589349, 36138.8193059033, 36558.8172059138, 36569.90290291295, 30762.84618576893, 31792.523112152874, 32229.194270143435, 32488.025359239262, 32541.67458325481, 21423.0, 22412.32763017112, 23719.407014824767, 23999.400014999766, 24050.0], "value": 19817.64825019683, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [384.15294276121153, 386.248746188663, 399.8220791747673, 402.51898980718045, 421.94513715710724, 17464.82535174648, 18211.907285562876, 19428.708569371454, 20249.59500809985, 20297.797022029776, 11987.0, 12316.261024338524, 12351.073669474781, 18523.518118550517, 20358.287459938867, 4344.695871289011, 5032.144535428986, 5233.241180028894, 5248.606354523426, 5272.604554658396, 5104.438511763703, 5247.973760131194, 5249.107651699202, 5258.106121959276, 5259.789608415678, 5134.974325128369, 5255.211718242256, 5259.342582177228, 5259.395169555492, 5277.3139491866, 5606.24315717377, 5714.971425142848, 5740.339860916007, 5751.654900705929, 5752.424757524235, 4918.262260660929, 5069.44236134025, 5168.121419358718, 5186.092433824104, 5191.247269145991, 13148.60554183376, 13220.933895330558, 13317.0, 13465.057445978786, 13502.797458038138, 2375.452337282503, 2470.876456177202, 2481.2249686876526, 2491.2277194069716, 2515.207709571479, 5169.974150129226, 9762.414255144644, 9953.0, 10022.098011179029, 10201.948990255076, 1751.7897852257684, 1780.2078075256386, 1788.499220218341, 1790.8477779388768, 1840.4754644926265, 111427.0, 117126.0, 117686.41156794247, 118737.40631296788, 119764.80235197624, 9727.249095162939, 10247.897521024843, 10397.48012599367, 10437.165026797838, 10705.304155229886, 10999.725006874893, 12930.935345323212, 12961.0, 12971.935140324238, 12983.091183617245, 7793.571353575494, 7945.483543569765, 8021.558814265271, 8047.83904321922, 8065.314448271845, 7928.207179282025, 7932.008498937633, 7976.800579985431, 8113.0, 8119.837603247902, 4073.185362927367, 4154.667626589866, 4158.625723684883, 4235.8729238122905, 4245.936310955338, 1787.7586525819108, 2016.5160361513424, 2072.8445366597566, 2074.1392322186425, 2075.491504581357, 861.3711990247155, 899.3929097858919, 973.2700474644017, 1001.8697569315942, 1042.4110377636612, 18896.338628147947, 20550.897245513675, 20638.38084857457, 20640.380788576367, 20761.0, 906.2795077913075, 997.1574019953129, 998.4059484606647, 1035.694470131313, 1045.4668119259081, 8585.742427727178, 10524.789504209873, 10955.4522273886, 11003.779924401468, 11446.484908179153, 702793.0, 707840.0, 708625.0, 730142.0, 731000.0, 111652.44173779241, 147332.26333868262, 152461.0, 155285.22357388368, 155310.0, 875.9794839012479, 956.4261443134203, 1021.7343490692325, 1024.8513965475113, 1027.8715160604925, 15522.0, 18074.18666160052, 19212.61574768469, 19487.0, 19793.40619781409, 49517.752411237714, 55768.884622308135, 57388.71305643612, 57588.71205643945, 57854.7107264461, 59516.40483595282, 63597.0, 74489.6275518619, 78494.607526962, 86300.0, 7217.530860494051, 7383.261673832789, 7551.131619863566, 8052.758417247492, 8578.871316930126, 9185.448873067637, 11873.228240164362, 12547.749045018865, 12931.676708082374, 13092.410841511964, 9383.718488445358, 10694.037536621741, 10863.076638485818, 11055.0603198729, 11187.776244475228, 10339.276250662382, 10817.45912704346, 11208.887911120784, 11234.0, 11492.310461372344, 9585.281103917094, 11158.442077896236, 11448.3703396314, 11474.368909710047, 11496.712582185512, 7473.215312392338, 7576.8484630308185, 7603.467757256939, 7699.3840492761565, 7876.527408355517, 9738.853917191105, 10992.120630349393, 11275.887241127484, 11337.943310283395, 11340.262882912584], "value": 9077.232596933154, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1413.724323756867, 1427.6573622330643, 1430.7782293744474, 1441.4090223008566, 1468.6475245940974, 8046.275835174833, 8088.595570221495, 8387.748367548968, 8416.368772342068, 8443.19789619986, 7085.9645701771415, 8315.542645154514, 8427.747167584967, 8452.0, 8466.576671166447, 2207.1612787140866, 2262.864228146308, 2263.0155882191216, 2263.2757517594378, 2263.76230495798, 2226.309843948381, 2258.1870526610405, 2258.7063681721434, 2262.4230821140586, 2265.105283413051, 2225.0, 2230.9888450557723, 2259.7966183043523, 2260.728712554496, 2263.513344630904, 2145.7854214578574, 2194.6707993800896, 2211.0, 2220.555888822242, 2226.554689062186, 1920.8847469151895, 2001.8398528117862, 2119.925802596917, 2154.0, 2161.0383379396176, 6327.367263273682, 7165.820854478679, 7269.382102521292, 8461.450005749668, 8947.747315375853, 4991.6256280778725, 5125.436202017775, 5140.434552199255, 5158.690478571298, 5165.974170129163, 3329.350776598564, 3435.089701229151, 3471.548698669182, 3500.8949731508096, 3507.754457187998, 1179.445660539545, 1183.810590305547, 1208.9576864809778, 1216.1851559455097, 1237.641084085614, 8121.715739949073, 10652.041316281495, 10686.305390149615, 10794.6761597153, 10800.405977671226, 3792.5259342582176, 4100.8107648781515, 4349.347597860283, 4416.183006143874, 4450.710703804242, 7498.475106742476, 7698.923010769932, 8692.217700406994, 8712.91287087121, 8724.69463568772, 5032.521910418578, 5048.192289233779, 5055.848324550269, 5067.670601410897, 5069.340985671913, 4548.658850586219, 4595.058013107305, 4683.320918466871, 4688.320193571913, 4694.624430045588, 3585.0678823506078, 3652.7808331500196, 3668.2296717689583, 3694.3165514379925, 3734.3278209922473, 1047.1936608811172, 1056.0495554001461, 1127.3405058040973, 1137.9374134422687, 1147.0, 740.8073900785835, 822.005373498074, 886.9600867960958, 902.8104098139464, 923.1829830599834, 5154.845354639367, 5445.128779395278, 5569.610127291051, 5717.885642287214, 6008.849778755479, 904.8099899021148, 934.243262957007, 969.8060387922444, 977.726236653738, 996.9900300997042, 3230.499272612749, 3450.913727156841, 3758.4174452959833, 3776.263628592453, 3850.2876967761053, 8957.641694332284, 9322.813543729224, 9405.106514881076, 9432.575534100983, 9824.901750982399, 9770.169535589412, 9960.601575936882, 9987.900120998842, 10010.099091081838, 10045.748856278651, 7542.094948606093, 7791.922080779346, 7805.0, 7812.882806757903, 7821.452498325063, 5031.295618613324, 5044.899102018013, 5370.9731451342495, 5510.201020852034, 5869.196205691463, 8972.596233169654, 8991.28069754431, 9144.039875812943, 9167.44995300284, 9230.169284764403, 8549.700760473354, 8797.824043519222, 9227.261819054595, 9383.436993780395, 9389.718308450758, 6937.791866244021, 7230.13238411394, 7244.166920804175, 7267.200607933018, 7267.6366181691765, 7466.290702383265, 7738.380929525511, 7758.3017528422715, 7794.103678076867, 7870.842583148419, 7008.789736307919, 7020.613866237406, 7025.227225005143, 7093.432525398059, 7120.750773722895, 6769.864602708017, 6771.424428923399, 6868.896966545606, 6990.720371185299, 7006.0, 6221.2845522765465, 6298.496120310272, 6355.618662880242, 6383.297837237993, 6405.615663060231, 4340.457442819647, 4764.690295130806, 4842.539958703917, 4909.582685471775, 4968.080905032581, 4778.116048531033, 4881.193313844483, 4922.744700101452, 5044.520770526941, 5064.924026139537], "value": 4290.673533142922, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [311.91890108571766, 337.4887046125119, 361.73412541781795, 366.9339518886601, 368.01922875536695, 5292.184112457664, 5397.5951803614735, 5477.9591877543235, 5510.228568000483, 5534.584906132046, 4423.208600516788, 5368.275282836816, 5421.159720243359, 5452.236686863842, 5473.370562385327, 3670.50448189495, 3733.842508822274, 3746.801023672426, 4134.090500089983, 4134.354013431406, 3433.416319225725, 3533.6466353364717, 3862.2854771867296, 3976.860809871655, 4031.778252196128, 4032.5362583302854, 4052.9797351013203, 4054.351303791394, 4081.204165187775, 4110.266672662676, 3461.9614115765294, 4085.611866872642, 4093.8157782899543, 4097.344424892033, 4102.917941641181, 3242.837858107085, 3449.58604967403, 3526.8589256429714, 3527.1658737456637, 3530.852472946277, 4415.496193882254, 4589.35748995141, 5055.570276526463, 5301.734913254322, 5332.893342133174, 4137.124698334658, 4663.323818046399, 4687.0, 4696.342512048316, 4709.835155769531, 4940.517844646628, 5665.49010589049, 5688.516476099537, 5732.484076433099, 5780.132980052984, 3865.4588357629955, 3868.6518213360932, 3874.7720060964775, 3893.53277606689, 3906.941395879064, 4575.359449677047, 5121.641485096007, 5885.735141918581, 6061.181740465025, 6085.908711369333, 3462.671046250602, 3550.851734700469, 3724.962750372516, 3886.4753258310334, 3908.713402659476, 3971.245463361952, 3978.0, 3978.462907507507, 3984.880453586397, 3991.900202494961, 3998.4602078719, 4283.528811830696, 4399.560043995639, 4627.652926030494, 4642.16441040616, 5710.172025056344, 5771.0, 5787.94212057874, 5796.188533605298, 5819.127130930395, 1126.6451067913745, 1184.976300474003, 1290.0131399479405, 1297.9935100324437, 1303.7522870654545, 805.8549461097059, 811.6063709101081, 872.2498651159997, 877.8814859993947, 884.4648987362682, 5110.744462776846, 5167.191482980927, 5171.130022837287, 5182.585393168538, 5188.558972487305, 4583.957646588726, 5363.517283444508, 5508.0, 5686.715664216773, 5739.368669446358, 4384.6273066789045, 4807.6394270429855, 5183.796886323297, 5220.895582088413, 5254.500822421864, 2991.2928799436545, 2993.9670813569282, 2996.925076873096, 3008.7592992560544, 3021.9093427197217, 5590.832275031756, 5757.474269318634, 5895.882082358329, 5944.078667806497, 5948.821535353946, 4954.925676114861, 5411.485908838654, 5690.146478028329, 6108.389161083945, 6146.323904370515, 6217.751289948442, 6233.252009758858, 6256.5307601930035, 6269.373062693694, 6365.9681701593045, 4625.306204069417, 5485.491489840167, 5657.302809157319, 5932.7033648318275, 5992.940070599413, 5808.693044065051, 5901.970490147522, 5909.23179986599, 5918.378570250062, 5935.792247271324, 5419.2413062170535, 5774.538036957118, 5914.615549989237, 5951.58338916289, 6203.069539569101, 3639.526861507987, 3883.4369016493015, 3931.5085614298214, 3942.4677668514382, 3956.9604303957744, 5102.387713474407, 5419.0245755764345, 5760.971195143998, 5918.389782232921, 5953.345132035386, 5331.546818520314, 5776.508996735324, 5777.24895763548, 5805.506531944663, 5813.447722466359, 5401.594880384066, 5775.0, 5791.391903850035, 5801.564882633735, 5826.912596310974, 4624.722516649012, 5395.8416633345405, 5512.834614961557, 5631.662100273997, 5708.229389032427, 5744.639385959261, 5748.856278593069, 5787.913181302368, 5808.244928159313, 5823.505002074701, 5634.549236061187, 5649.548036157015, 5650.802221922213, 5658.886822263449, 5772.740226689725], "value": 4150.442572225691, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [675.351662404092, 705.6577559883459, 770.4953255617569, 784.4665627373388, 786.8937693411387, 13406.396712147953, 14800.0, 14852.554423367303, 14860.0, 14869.851301486982, 12922.676933076678, 13514.797278040838, 13624.250666213355, 13958.232297223649, 14001.089929154597, 883.3463237204471, 893.0756666849812, 901.5672477210943, 902.2195800632471, 904.5070436612062, 841.4572600672562, 874.6588830356167, 892.1034360467721, 894.0790985285166, 894.1014280647939, 896.8161526886972, 902.3863772634605, 906.3383729877194, 915.5925613102172, 921.0191146429065, 912.9954350228239, 917.1058218237232, 917.8990311065777, 918.9816203675889, 920.9777147366451, 680.9319068093151, 684.096991970594, 688.2257460357098, 688.2257460357098, 688.5971706551674, 14639.707205855822, 14755.704885902222, 14848.406063757437, 15140.242987850563, 15306.311215995307, 13615.387307571184, 13717.17696938177, 13762.24307663078, 13884.861151388457, 13894.23581703006, 9883.604655813759, 11174.944125279402, 11278.210525263235, 11351.772964540663, 12065.758684826342, 3550.6626870447267, 3554.4668299754985, 3571.14292569784, 3605.405108157151, 3700.870469533553, 91191.08808911893, 93851.06148938491, 94452.05547944501, 95381.52309238478, 96091.51954240254, 10175.487687879762, 10386.532606032746, 10544.261901666963, 10703.518341674644, 10714.642778588697, 19925.501862453555, 21037.68443473349, 21072.15711371528, 21626.567468650537, 21658.78341216599, 6476.838079047967, 7466.552006879605, 7467.962660186773, 7691.538507689556, 7816.765497034984, 4018.3771515414574, 4187.937180942288, 4192.119654872511, 4192.434021407132, 4196.51740049892, 3596.9280614387567, 3683.9447408288897, 3699.5005674233635, 3705.94441083384, 3709.1654377764785, 573.3120255693185, 657.9276279609239, 677.9830504237434, 691.4226620771713, 696.6864910790165, 628.7013668507423, 642.7878799996083, 678.9966050169817, 681.6455443169524, 706.3486938509867, 9220.354575179806, 9872.371058775365, 9957.352772069793, 9968.55141518619, 9997.200223982063, 662.9337066293332, 686.6978529447027, 690.3249906611879, 691.2914262880588, 706.1662917330862, 4796.304535842283, 4796.880077998008, 4807.6394270429855, 4822.71063736177, 4846.224604063333, 94319.0, 96050.51974740221, 96887.0, 98065.50967245118, 99362.0, 62349.0, 82015.58992205001, 84972.0, 85441.4367568974, 87814.68277975707, 76609.61695191488, 77098.61450692822, 77393.99999999888, 77740.0, 79772.0, 7174.497785154989, 8627.645831262893, 9351.625934962798, 9390.985570337558, 9435.0, 47928.5207147938, 48617.784555384984, 49529.78996785314, 49719.75700607514, 49731.502684972686, 46565.0, 56095.0, 63287.0, 64711.67644161749, 66030.33969660434, 11037.11703063737, 11233.157513186381, 11391.0, 11440.141989350666, 11874.584389546324, 12152.392380381123, 12465.875341246472, 12521.686957825763, 12556.183848049848, 12732.681682958, 9981.0, 11279.09767218637, 11668.766624667289, 12049.156559040783, 12177.573784917486, 11282.244547807419, 11678.883211168119, 11691.415429228335, 12055.758884822078, 12096.516139354328, 11370.94314528422, 11464.770704586028, 11964.46159922823, 11969.521219151136, 12011.399430028638, 9425.670101546413, 9708.563114659715, 10012.541806020217, 10078.899211007796, 10112.544935478069, 8215.548144852091, 9183.265338772748, 9403.43579385239, 9431.764205894908, 9478.810423791623], "value": 7156.417008791622, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1582.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 118.0, 120.0, 118.0, 117.0, 117.0, 118.0, 118.0, 118.0, 118.0], "value": 116.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 116.0, 112.0, 159.0, 122.0, 149.0, 107.0, 148.0, 132.0, 148.0], "value": 133.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [220.0, 242.0, 206.0, 217.0, 197.0, 199.0, 199.0, 201.0, 200.0, 201.0], "value": 208.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 57.0, 60.0, 87.0, 59.0, 61.0, 63.0, 61.0, 72.0, 61.0], "value": 66.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 71.0, 86.0, 71.0, 72.0, 71.0, 82.0, 73.0, 70.0, 84.0], "value": 76.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 88.0, 89.0, 88.0, 89.0, 93.0, 128.0, 94.0, 92.0, 89.0], "value": 94.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 97.0, 96.0, 98.0, 107.0, 96.0, 151.0, 96.0, 95.0], "value": 102.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 98.0, 129.0, 91.0, 116.0, 142.0, 145.0, 93.0, 88.0], "value": 109.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 93.0, 64.0, 63.0, 65.0, 58.0, 84.0, 97.0, 99.0, 97.0], "value": 78.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 91.0, 73.0, 108.0, 107.0, 63.0, 71.0, 97.0, 104.0, 106.0], "value": 88.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 90.0, 90.0, 107.0, 107.0, 96.0, 93.0, 90.0, 96.0], "value": 95.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 182.0, 142.0, 137.0, 143.0, 152.0, 153.0, 155.0, 192.0, 175.0], "value": 159.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [243.0, 187.0, 186.0, 176.0, 176.0, 176.0, 178.0, 176.0, 173.0, 173.0], "value": 184.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 67.0, 70.0, 69.0, 68.0, 68.0, 69.0, 67.0, 69.0], "value": 68.5, "unit": "score"}]}]} 15:50:21 INFO - Return code: 0 15:50:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:50:22 ERROR - # TBPL SUCCESS # 15:50:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:50:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:50:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:50:22 INFO - 2017-02-12 15:50:22.055 screenresolution[1959:27516] starting screenresolution argv=screenresolution get 15:50:22 INFO - 2017-02-12 15:50:22.057 screenresolution[1959:27516] Display 0: 1600x1200x32@60 15:50:22 INFO - 2017-02-12 15:50:22.064 screenresolution[1960:27519] starting screenresolution argv=screenresolution list 15:50:22 INFO - Available Modes on Display 0 15:50:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:50:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:50:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:50:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:50:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:50:22 INFO - Intel Iris: 15:50:22 INFO - Chipset Model: Intel Iris 15:50:22 INFO - Type: GPU 15:50:22 INFO - Bus: Built-In 15:50:22 INFO - VRAM (Dynamic, Max): 1536 MB 15:50:22 INFO - Vendor: Intel (0x8086) 15:50:22 INFO - Device ID: 0x0a2e 15:50:22 INFO - Revision ID: 0x0009 15:50:22 INFO - Displays: 15:50:22 INFO - Display: 15:50:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:50:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:50:22 INFO - Display Serial Number: 1600x1200 60 15:50:22 INFO - Main Display: Yes 15:50:22 INFO - Mirror: Off 15:50:22 INFO - Online: Yes 15:50:22 INFO - Rotation: Supported 15:50:22 INFO - Return code: 0 15:50:22 INFO - Running post-action listener: _resource_record_post_action 15:50:22 INFO - [mozharness: 2017-02-12 23:50:22.302390Z] Finished run-tests step (success) 15:50:22 INFO - Running post-run listener: _resource_record_post_run 15:50:22 INFO - Total resource usage - Wall time: 1201s; CPU: 55.0%; Read bytes: 1984000; Write bytes: 561197056; Read time: 20; Write time: 1773 15:50:22 INFO - TinderboxPrint: CPU usage
54.6% 15:50:22 INFO - TinderboxPrint: I/O read bytes / time
1,984,000 / 20 15:50:22 INFO - TinderboxPrint: I/O write bytes / time
561,197,056 / 1,773 15:50:22 INFO - TinderboxPrint: CPU idle
2,179.5 (45.4%) 15:50:22 INFO - TinderboxPrint: CPU system
965.9 (20.1%) 15:50:22 INFO - TinderboxPrint: CPU user
1,660.2 (34.5%) 15:50:22 INFO - TinderboxPrint: Swap in / out
255,148,032 / 0 15:50:22 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 253764096; Write bytes: 257841152; Read time: 18133; Write time: 541 15:50:22 INFO - run-tests - Wall time: 1180s; CPU: 55.0%; Read bytes: 1889792; Write bytes: 301828096; Read time: 19; Write time: 1226 15:50:22 INFO - Running post-run listener: _upload_blobber_files 15:50:22 INFO - Blob upload gear active. 15:50:22 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:50:22 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 15:50:22 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'] 15:50:22 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 15:50:23 INFO - (blobuploader) - INFO - Open directory for files ... 15:50:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 15:50:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:50:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:50:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 15:50:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:50:24 INFO - (blobuploader) - INFO - Done attempting. 15:50:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 15:50:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:50:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:50:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 15:50:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:50:25 INFO - (blobuploader) - INFO - Done attempting. 15:50:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 15:50:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:50:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:50:26 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 15:50:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:50:26 INFO - (blobuploader) - INFO - Done attempting. 15:50:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:50:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:50:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:50:28 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:50:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:50:28 INFO - (blobuploader) - INFO - Done attempting. 15:50:28 INFO - (blobuploader) - INFO - Iteration through files over. 15:50:28 INFO - Return code: 0 15:50:28 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:50:28 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:50:28 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a8dd29702da7710e5d00eb24d4ae16dbc564bbd4bd876455f30e2590052c95eb979952c8c24ff29d5643cbd7f19d6e37c5101ebeec16cd082246ad91719cca0e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8bb2e76d99dc23856a718defad215760ae82bd120c57e7be3f5a07aebde7b1c0657cc3ca61619ec3bbbb433fc1d31fdd622817d7e273ac8275fe591e23cdcd0b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/32e8c1b13de12aa2da43ceeadce8a70cc056c9a1a57d72b7abda8b399ee29055665d822e2385e6c6e4900b6ddeb7cf6f44abc7b6dfeb6c9e36665a1c8584f532", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ef04aa2ee74e843f15e0b20929851326dce856495b212c1c35de5d78e6d2611f16fa62ef00c44bb6c4f4319e79952f80f1a32c8fefbd178ddbe327680ccfee72"} 15:50:28 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:50:28 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:50:28 INFO - Contents: 15:50:28 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a8dd29702da7710e5d00eb24d4ae16dbc564bbd4bd876455f30e2590052c95eb979952c8c24ff29d5643cbd7f19d6e37c5101ebeec16cd082246ad91719cca0e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8bb2e76d99dc23856a718defad215760ae82bd120c57e7be3f5a07aebde7b1c0657cc3ca61619ec3bbbb433fc1d31fdd622817d7e273ac8275fe591e23cdcd0b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/32e8c1b13de12aa2da43ceeadce8a70cc056c9a1a57d72b7abda8b399ee29055665d822e2385e6c6e4900b6ddeb7cf6f44abc7b6dfeb6c9e36665a1c8584f532", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ef04aa2ee74e843f15e0b20929851326dce856495b212c1c35de5d78e6d2611f16fa62ef00c44bb6c4f4319e79952f80f1a32c8fefbd178ddbe327680ccfee72"} 15:50:28 INFO - Running post-run listener: copy_logs_to_upload_dir 15:50:28 INFO - Copying logs to upload dir... 15:50:28 INFO - mkdir: /builds/slave/test/build/upload/logs 15:50:28 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1282.766869 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2017-02-12 15:50:28.598386) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-12 15:50:28.601662) ========= 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.nPRpMuzilr/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.Sjh8mtfBV6/Listeners TMPDIR=/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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/a8dd29702da7710e5d00eb24d4ae16dbc564bbd4bd876455f30e2590052c95eb979952c8c24ff29d5643cbd7f19d6e37c5101ebeec16cd082246ad91719cca0e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8bb2e76d99dc23856a718defad215760ae82bd120c57e7be3f5a07aebde7b1c0657cc3ca61619ec3bbbb433fc1d31fdd622817d7e273ac8275fe591e23cdcd0b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/32e8c1b13de12aa2da43ceeadce8a70cc056c9a1a57d72b7abda8b399ee29055665d822e2385e6c6e4900b6ddeb7cf6f44abc7b6dfeb6c9e36665a1c8584f532", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ef04aa2ee74e843f15e0b20929851326dce856495b212c1c35de5d78e6d2611f16fa62ef00c44bb6c4f4319e79952f80f1a32c8fefbd178ddbe327680ccfee72"} build_url:https://queue.taskcluster.net/v1/task/Q-U4Hp99SAyEq3896vWNkA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011365 build_url: 'https://queue.taskcluster.net/v1/task/Q-U4Hp99SAyEq3896vWNkA/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/a8dd29702da7710e5d00eb24d4ae16dbc564bbd4bd876455f30e2590052c95eb979952c8c24ff29d5643cbd7f19d6e37c5101ebeec16cd082246ad91719cca0e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8bb2e76d99dc23856a718defad215760ae82bd120c57e7be3f5a07aebde7b1c0657cc3ca61619ec3bbbb433fc1d31fdd622817d7e273ac8275fe591e23cdcd0b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/32e8c1b13de12aa2da43ceeadce8a70cc056c9a1a57d72b7abda8b399ee29055665d822e2385e6c6e4900b6ddeb7cf6f44abc7b6dfeb6c9e36665a1c8584f532", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ef04aa2ee74e843f15e0b20929851326dce856495b212c1c35de5d78e6d2611f16fa62ef00c44bb6c4f4319e79952f80f1a32c8fefbd178ddbe327680ccfee72"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-12 15:50:28.635982) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-12 15:50:28.636323) ========= 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.nPRpMuzilr/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.Sjh8mtfBV6/Listeners TMPDIR=/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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.005446 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-12 15:50:28.685043) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-12 15:50:28.685363) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-12 15:50:28.685761) ========= ========= Total master_lag: 0.18 =========