builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0243 starttime: 1494022204.56 results: success (0) buildid: 20170505132514 builduid: ab7b3c90aba04cce9e11f8687611e4b3 revision: e7bf9443be2c4a5187c37440e35f3526148d7fa8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-05 15:10:04.557241) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-05 15:10:04.557667) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-05 15:10:04.557937) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-05 15:10:04.588327) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-05 15:10:04.589069) ========= 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.aP2cAJEs3V/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.nqCHbmpe1T/Listeners TMPDIR=/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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-05-05 15:10: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% 18.7M=0.001s 2017-05-05 15:10:04 (18.7 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.135083 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-05 15:10:04.754284) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-05 15:10:04.754872) ========= 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.aP2cAJEs3V/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.nqCHbmpe1T/Listeners TMPDIR=/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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.032385 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-05 15:10:04.813923) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-05 15:10:04.814730) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev e7bf9443be2c4a5187c37440e35f3526148d7fa8 --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 e7bf9443be2c4a5187c37440e35f3526148d7fa8 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.aP2cAJEs3V/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.nqCHbmpe1T/Listeners TMPDIR=/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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-05-05 15:10:04,913 truncating revision to first 12 chars 2017-05-05 15:10:04,913 Setting DEBUG logging. 2017-05-05 15:10:04,913 attempt 1/10 2017-05-05 15:10:04,913 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/e7bf9443be2c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-05 15:10:06,068 unpacking tar archive at: mozilla-central-e7bf9443be2c/testing/mozharness/ program finished with exit code 0 elapsedTime=1.437916 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-05 15:10:06.277699) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-05 15:10:06.278068) ========= script_repo_revision: e7bf9443be2c4a5187c37440e35f3526148d7fa8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-05 15:10:06.278544) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-05 15:10:06.278850) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-05 15:10:06.299293) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2017-05-05 15:10:06.299633) ========= /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.aP2cAJEs3V/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.nqCHbmpe1T/Listeners TMPDIR=/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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:10:06 INFO - MultiFileLogger online at 20170505 15:10:06 in /builds/slave/test 15:10:06 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:10:06 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:10:06 INFO - {'append_to_log': False, 15:10:06 INFO - 'base_work_dir': '/builds/slave/test', 15:10:06 INFO - 'blob_upload_branch': 'Firefox', 15:10:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:10:06 INFO - 'branch': 'Firefox', 15:10:06 INFO - 'buildbot_json_path': 'buildprops.json', 15:10:06 INFO - 'config_files': ('talos/mac_config.py',), 15:10:06 INFO - 'default_actions': ('clobber', 15:10:06 INFO - 'read-buildbot-config', 15:10:06 INFO - 'download-and-extract', 15:10:06 INFO - 'populate-webroot', 15:10:06 INFO - 'create-virtualenv', 15:10:06 INFO - 'install', 15:10:06 INFO - 'run-tests'), 15:10:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:10:06 INFO - 'download_minidump_stackwalk': True, 15:10:06 INFO - 'download_symbols': 'ondemand', 15:10:06 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:10:06 INFO - 'tooltool.py': '/tools/tooltool.py', 15:10:06 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:10:06 INFO - '/tools/misc-python/virtualenv.py')}, 15:10:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:10:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:10:06 INFO - 'gecko_profile': False, 15:10:06 INFO - 'gecko_profile_interval': 0, 15:10:06 INFO - 'installer_path': 'installer.exe', 15:10:06 INFO - 'log_level': 'info', 15:10:06 INFO - 'log_name': 'talos', 15:10:06 INFO - 'log_to_console': True, 15:10:06 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:10:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:10:06 INFO - 'opt_config_files': (), 15:10:06 INFO - 'pip_index': False, 15:10:06 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:10:06 INFO - 'cmd': ('bash', 15:10:06 INFO - '-c', 15:10:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:10:06 INFO - 'enabled': True, 15:10:06 INFO - 'halt_on_failure': False, 15:10:06 INFO - 'name': 'check_screen_resolution'},), 15:10:06 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:10:06 INFO - 'cmd': ('bash', 15:10:06 INFO - '-c', 15:10:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:10:06 INFO - 'enabled': True, 15:10:06 INFO - 'halt_on_failure': False, 15:10:06 INFO - 'name': 'check_screen_resolution'},), 15:10:06 INFO - 'run_cmd_checks_enabled': True, 15:10:06 INFO - 'suite': 'dromaeojs-e10s', 15:10:06 INFO - 'system_bits': '32', 15:10:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:10:06 INFO - 'title': 't-yosemite-r7-0243', 15:10:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:10:06 INFO - 'use_talos_json': True, 15:10:06 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:10:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:10:06 INFO - 'work_dir': 'build'} 15:10:06 INFO - [mozharness: 2017-05-05 22:10:06.507245Z] Running clobber step. 15:10:06 INFO - Running pre-action listener: _resource_record_pre_action 15:10:06 INFO - Running main action method: clobber 15:10:06 INFO - rmtree: /builds/slave/test/build 15:10:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:10:08 INFO - Running post-action listener: _resource_record_post_action 15:10:08 INFO - [mozharness: 2017-05-05 22:10:08.952242Z] Finished clobber step (success) 15:10:08 INFO - [mozharness: 2017-05-05 22:10:08.952410Z] Running read-buildbot-config step. 15:10:08 INFO - Running pre-action listener: _resource_record_pre_action 15:10:08 INFO - Running main action method: read_buildbot_config 15:10:08 INFO - Using buildbot properties: 15:10:08 INFO - { 15:10:08 INFO - "project": "", 15:10:08 INFO - "product": "firefox", 15:10:08 INFO - "script_repo_revision": "production", 15:10:08 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 15:10:08 INFO - "repository": "", 15:10:08 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 15:10:08 INFO - "buildid": "20170505132514", 15:10:08 INFO - "pgo_build": "False", 15:10:08 INFO - "basedir": "/builds/slave/test", 15:10:08 INFO - "buildnumber": 51, 15:10:08 INFO - "slavename": "t-yosemite-r7-0243", 15:10:08 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 15:10:08 INFO - "platform": "yosemite_r7", 15:10:08 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 15:10:08 INFO - "branch": "mozilla-central", 15:10:08 INFO - "repo_path": "mozilla-central", 15:10:08 INFO - "revision": "e7bf9443be2c4a5187c37440e35f3526148d7fa8", 15:10:08 INFO - "stage_platform": "macosx64", 15:10:08 INFO - "builduid": "ab7b3c90aba04cce9e11f8687611e4b3", 15:10:08 INFO - "slavebuilddir": "test" 15:10:08 INFO - } 15:10:08 INFO - Found installer url https://queue.taskcluster.net/v1/task/V6_FvUlOT2GkS8FQv7CHWA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 15:10:08 INFO - Running post-action listener: _resource_record_post_action 15:10:08 INFO - [mozharness: 2017-05-05 22:10:08.955781Z] Finished read-buildbot-config step (success) 15:10:08 INFO - [mozharness: 2017-05-05 22:10:08.955911Z] Running download-and-extract step. 15:10:08 INFO - Running pre-action listener: _resource_record_pre_action 15:10:08 INFO - Running main action method: download_and_extract 15:10:08 INFO - mkdir: /builds/slave/test/build/tests 15:10: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:10:08 INFO - trying https://queue.taskcluster.net/v1/task/V6_FvUlOT2GkS8FQv7CHWA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 15:10:08 INFO - Downloading https://queue.taskcluster.net/v1/task/V6_FvUlOT2GkS8FQv7CHWA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 15:10:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/V6_FvUlOT2GkS8FQv7CHWA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 15:10:10 INFO - Downloaded 1325 bytes. 15:10:10 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 15:10:10 INFO - Using the following test package requirements: 15:10:10 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:10:10 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 15:10:10 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 15:10:10 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:10:10 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 15:10:10 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:10:10 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 15:10:10 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:10:10 INFO - u'jsshell-mac.zip'], 15:10:10 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:10:10 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 15:10:10 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 15:10:10 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:10:10 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 15:10:10 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:10:10 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 15:10:10 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:10:10 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 15:10:10 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:10:10 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 15:10:10 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 15:10:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/V6_FvUlOT2GkS8FQv7CHWA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 15:10:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/V6_FvUlOT2GkS8FQv7CHWA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:10:10 INFO - Fetch https://queue.taskcluster.net/v1/task/V6_FvUlOT2GkS8FQv7CHWA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 15:10:11 INFO - Content-Length response header: 52702890 15:10:11 INFO - Bytes received: 52702890 15:10:28 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 15:10:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/V6_FvUlOT2GkS8FQv7CHWA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 15:10:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/V6_FvUlOT2GkS8FQv7CHWA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:10:28 INFO - Fetch https://queue.taskcluster.net/v1/task/V6_FvUlOT2GkS8FQv7CHWA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 15:10:30 INFO - Content-Length response header: 52702890 15:10:30 INFO - Bytes received: 52702890 15:10:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/V6_FvUlOT2GkS8FQv7CHWA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 15:10:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/V6_FvUlOT2GkS8FQv7CHWA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 15:10:45 INFO - Fetch https://queue.taskcluster.net/v1/task/V6_FvUlOT2GkS8FQv7CHWA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 15:10:46 INFO - Content-Length response header: 14408697 15:10:46 INFO - Bytes received: 14408697 15:10:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:10:46 INFO - trying https://queue.taskcluster.net/v1/task/V6_FvUlOT2GkS8FQv7CHWA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 15:10:46 INFO - Downloading https://queue.taskcluster.net/v1/task/V6_FvUlOT2GkS8FQv7CHWA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 15:10:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/V6_FvUlOT2GkS8FQv7CHWA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 15:10:48 INFO - Downloaded 70885317 bytes. 15:10:48 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/V6_FvUlOT2GkS8FQv7CHWA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 15:10:48 INFO - mkdir: /builds/slave/test/properties 15:10:48 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:10:48 INFO - Writing to file /builds/slave/test/properties/build_url 15:10:48 INFO - Contents: 15:10:48 INFO - build_url:https://queue.taskcluster.net/v1/task/V6_FvUlOT2GkS8FQv7CHWA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 15:10:49 INFO - Running post-action listener: _resource_record_post_action 15:10:49 INFO - Running post-action listener: set_extra_try_arguments 15:10:49 INFO - [mozharness: 2017-05-05 22:10:49.389338Z] Finished download-and-extract step (success) 15:10:49 INFO - [mozharness: 2017-05-05 22:10:49.389447Z] Running populate-webroot step. 15:10:49 INFO - Running pre-action listener: _resource_record_pre_action 15:10:49 INFO - Running main action method: populate_webroot 15:10:49 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:10:49 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:10:49 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:10:49 INFO - '--tppagecycles', 15:10:49 INFO - '7'], 15:10:49 INFO - 'tests': ['tsvgm']}}, 15:10:49 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:10:49 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:10:49 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:10:49 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:10:49 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:10:49 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:10:49 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 15:10:49 INFO - 'talos_options': ['--disable-e10s'], 15:10:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:10:49 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:10:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:10:49 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 15:10:49 INFO - 'talos_options': ['--disable-e10s'], 15:10:49 INFO - 'tests': ['damp', 'tps']}, 15:10:49 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:10:49 INFO - 'tests': ['damp', 'tps']}, 15:10:49 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:10:49 INFO - 'tests': ['dromaeo_dom']}, 15:10:49 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:10:49 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:10:49 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 15:10:49 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 15:10:49 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:10:49 INFO - 'tests': ['a11yr', 15:10:49 INFO - 'ts_paint', 15:10:49 INFO - 'tpaint', 15:10:49 INFO - 'sessionrestore', 15:10:49 INFO - 'sessionrestore_no_auto_restore', 15:10:49 INFO - 'tabpaint']}, 15:10:49 INFO - 'other-e10s': {'tests': ['a11yr', 15:10:49 INFO - 'ts_paint', 15:10:49 INFO - 'tpaint', 15:10:49 INFO - 'sessionrestore', 15:10:49 INFO - 'sessionrestore_no_auto_restore', 15:10:49 INFO - 'tabpaint']}, 15:10:49 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:10:49 INFO - 'ts_paint', 15:10:49 INFO - 'tpaint', 15:10:49 INFO - 'sessionrestore', 15:10:49 INFO - 'sessionrestore_no_auto_restore', 15:10:49 INFO - 'tabpaint']}, 15:10:49 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:10:49 INFO - 'ts_paint', 15:10:49 INFO - 'tpaint', 15:10:49 INFO - 'sessionrestore', 15:10:49 INFO - 'sessionrestore_no_auto_restore', 15:10:49 INFO - 'tabpaint']}, 15:10:49 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:10:49 INFO - 'tests': ['a11yr', 15:10:49 INFO - 'ts_paint', 15:10:49 INFO - 'tpaint', 15:10:49 INFO - 'sessionrestore', 15:10:49 INFO - 'sessionrestore_no_auto_restore', 15:10:49 INFO - 'tabpaint']}, 15:10:49 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:10:49 INFO - 'tests': ['a11yr', 15:10:49 INFO - 'ts_paint', 15:10:49 INFO - 'tpaint', 15:10:49 INFO - 'sessionrestore', 15:10:49 INFO - 'sessionrestore_no_auto_restore', 15:10:49 INFO - 'tabpaint']}, 15:10:49 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 15:10:49 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 15:10:49 INFO - 'bloom_basic_ref']}, 15:10:49 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:10:49 INFO - 'tests': ['tsvgx', 15:10:49 INFO - 'tsvgr_opacity', 15:10:49 INFO - 'tart', 15:10:49 INFO - 'tscrollx', 15:10:49 INFO - 'cart', 15:10:49 INFO - 'tsvg_static']}, 15:10:49 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:10:49 INFO - 'tsvgr_opacity', 15:10:49 INFO - 'tart', 15:10:49 INFO - 'tscrollx', 15:10:49 INFO - 'cart', 15:10:49 INFO - 'tsvg_static']}, 15:10:49 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 15:10:49 INFO - 'talos_options': ['--disable-e10s'], 15:10:49 INFO - 'tests': ['tp5o']}, 15:10:49 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:10:49 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 15:10:49 INFO - 'talos_options': ['--disable-e10s', 15:10:49 INFO - '--xperf_path', 15:10:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:10:49 INFO - 'tests': ['tp5n']}, 15:10:49 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:10:49 INFO - 'talos_options': ['--xperf_path', 15:10:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:10:49 INFO - 'tests': ['tp5n']}}, 15:10:49 INFO - 'talos.zip': {'path': '', 15:10:49 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:10:49 INFO - Running post-action listener: _resource_record_post_action 15:10:49 INFO - [mozharness: 2017-05-05 22:10:49.408753Z] Finished populate-webroot step (success) 15:10:49 INFO - [mozharness: 2017-05-05 22:10:49.408878Z] Running create-virtualenv step. 15:10:49 INFO - Running pre-action listener: _resource_record_pre_action 15:10:49 INFO - Running main action method: create_virtualenv 15:10:49 INFO - Creating virtualenv /builds/slave/test/build/venv 15:10:49 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:10:49 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:10:49 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:10:49 INFO - Using real prefix '/tools/python27' 15:10:49 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:10:50 INFO - Installing distribute.............................................................................................................................................................................................done. 15:10:53 INFO - Installing pip.................done. 15:10:53 INFO - Return code: 0 15:10:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:10:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:10:53 INFO - Reading from file tmpfile_stdout 15:10:53 INFO - Output received: 15:10:53 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:10:53 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:10:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:10:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:10:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:10:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:10:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:10:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:10:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e540b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e543768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7cbccaa50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e31ed30>, '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 0x10e244db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e313be8>, '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.aP2cAJEs3V/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.nqCHbmpe1T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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:10:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:10:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:10:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aP2cAJEs3V/Render', 15:10:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:10:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:10:53 INFO - 'HOME': '/Users/cltbld', 15:10:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:10:53 INFO - 'LOGNAME': 'cltbld', 15:10:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:10:53 INFO - 'MOZ_NO_REMOTE': '1', 15:10:53 INFO - 'NO_EM_RESTART': '1', 15:10:53 INFO - 'PAGER': '/bin/cat', 15:10:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:10:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:10:53 INFO - 'PWD': '/builds/slave/test', 15:10:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:10:53 INFO - 'SHELL': '/bin/bash', 15:10:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nqCHbmpe1T/Listeners', 15:10:53 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 15:10:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:10:53 INFO - 'USER': 'cltbld', 15:10:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:10:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:10:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:10:53 INFO - 'XPC_FLAGS': '0x0', 15:10:53 INFO - 'XPC_SERVICE_NAME': '0', 15:10:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:10:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:10:53 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:10:53 INFO - Cleaning up... 15:10:53 INFO - Return code: 0 15:10:53 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:10:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:10:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:10:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:10:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:10:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:10:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:10:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10e540b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e543768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7cbccaa50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e31ed30>, '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 0x10e244db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e313be8>, '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.aP2cAJEs3V/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.nqCHbmpe1T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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:10:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 15:10:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 15:10:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aP2cAJEs3V/Render', 15:10:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:10:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:10:53 INFO - 'HOME': '/Users/cltbld', 15:10:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:10:53 INFO - 'LOGNAME': 'cltbld', 15:10:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:10:53 INFO - 'MOZ_NO_REMOTE': '1', 15:10:53 INFO - 'NO_EM_RESTART': '1', 15:10:53 INFO - 'PAGER': '/bin/cat', 15:10:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:10:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:10:53 INFO - 'PWD': '/builds/slave/test', 15:10:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:10:53 INFO - 'SHELL': '/bin/bash', 15:10:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nqCHbmpe1T/Listeners', 15:10:53 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 15:10:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:10:53 INFO - 'USER': 'cltbld', 15:10:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:10:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:10:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:10:53 INFO - 'XPC_FLAGS': '0x0', 15:10:53 INFO - 'XPC_SERVICE_NAME': '0', 15:10:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:10:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:10:53 INFO - Downloading/unpacking psutil>=3.1.1 15:10:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:10:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:10:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:10:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:10:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:10:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:10:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:10:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:10:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:10:56 INFO - Installing collected packages: psutil 15:10:56 INFO - Running setup.py install for psutil 15:10:56 INFO - building 'psutil._psutil_osx' extension 15:10: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:10: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:10: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:10:57 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:10:57 INFO - building 'psutil._psutil_posix' extension 15:10:57 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:10:57 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:10:57 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:10:57 INFO - ^ 15:10:57 INFO - 1 warning generated. 15:10: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:10:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:10:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:10:57 INFO - Successfully installed psutil 15:10:57 INFO - Cleaning up... 15:10:57 INFO - Return code: 0 15:10:57 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:10: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:10:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:10:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:10: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:10:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:10:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:10: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 0x10e540b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e543768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7cbccaa50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e31ed30>, '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 0x10e244db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e313be8>, '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.aP2cAJEs3V/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.nqCHbmpe1T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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:10: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:10: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:10:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aP2cAJEs3V/Render', 15:10:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:10:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:10:57 INFO - 'HOME': '/Users/cltbld', 15:10:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:10:57 INFO - 'LOGNAME': 'cltbld', 15:10:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:10:57 INFO - 'MOZ_NO_REMOTE': '1', 15:10:57 INFO - 'NO_EM_RESTART': '1', 15:10:57 INFO - 'PAGER': '/bin/cat', 15:10:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:10:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:10:57 INFO - 'PWD': '/builds/slave/test', 15:10:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:10:57 INFO - 'SHELL': '/bin/bash', 15:10:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nqCHbmpe1T/Listeners', 15:10:57 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 15:10:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:10:57 INFO - 'USER': 'cltbld', 15:10:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:10:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:10:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:10:57 INFO - 'XPC_FLAGS': '0x0', 15:10:57 INFO - 'XPC_SERVICE_NAME': '0', 15:10:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:10:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:10:57 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:10: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:10: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:10: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:10: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:10: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:10: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:10:59 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:10:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:10:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:10:59 INFO - Installing collected packages: mozsystemmonitor 15:10:59 INFO - Running setup.py install for mozsystemmonitor 15:10:59 INFO - Successfully installed mozsystemmonitor 15:10:59 INFO - Cleaning up... 15:10:59 INFO - Return code: 0 15:10:59 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:10:59 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:10:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:10:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:10:59 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:10:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:10:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:10:59 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 0x10e540b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e543768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7cbccaa50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e31ed30>, '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 0x10e244db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e313be8>, '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.aP2cAJEs3V/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.nqCHbmpe1T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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:10:59 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:10:59 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:10:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aP2cAJEs3V/Render', 15:10:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:10:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:10:59 INFO - 'HOME': '/Users/cltbld', 15:10:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:10:59 INFO - 'LOGNAME': 'cltbld', 15:10:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:10:59 INFO - 'MOZ_NO_REMOTE': '1', 15:10:59 INFO - 'NO_EM_RESTART': '1', 15:10:59 INFO - 'PAGER': '/bin/cat', 15:10:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:10:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:10:59 INFO - 'PWD': '/builds/slave/test', 15:10:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:10:59 INFO - 'SHELL': '/bin/bash', 15:10:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nqCHbmpe1T/Listeners', 15:10:59 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 15:10:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:10:59 INFO - 'USER': 'cltbld', 15:10:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:10:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:10:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:10:59 INFO - 'XPC_FLAGS': '0x0', 15:10:59 INFO - 'XPC_SERVICE_NAME': '0', 15:10:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:10:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:10:59 INFO - Downloading/unpacking jsonschema==2.5.1 15:10:59 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:10:59 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:10:59 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:10:59 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:10:59 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:10:59 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:11:00 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:11:00 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:11: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:11: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:11: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:11: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:11: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:11: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:11:01 INFO - Downloading functools32-3.2.3-2.tar.gz 15:11:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:11:01 INFO - warning: no files found matching '*.txt' 15:11:01 INFO - no previously-included directories found matching 'build' 15:11:01 INFO - no previously-included directories found matching 'dist' 15:11:01 INFO - no previously-included directories found matching '.git*' 15:11:01 INFO - Installing collected packages: jsonschema, functools32 15:11:01 INFO - Running setup.py install for functools32 15:11:01 INFO - warning: no files found matching '*.txt' 15:11:01 INFO - no previously-included directories found matching 'build' 15:11:01 INFO - no previously-included directories found matching 'dist' 15:11:01 INFO - no previously-included directories found matching '.git*' 15:11:01 INFO - Successfully installed jsonschema functools32 15:11:01 INFO - Cleaning up... 15:11:01 INFO - Return code: 0 15:11:01 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:11:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:11:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:11:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:11:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:11:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:11:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:11:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10e540b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e543768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7cbccaa50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e31ed30>, '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 0x10e244db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e313be8>, '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.aP2cAJEs3V/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.nqCHbmpe1T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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:11:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 15:11:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 15:11:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aP2cAJEs3V/Render', 15:11:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:11:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:11:02 INFO - 'HOME': '/Users/cltbld', 15:11:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:11:02 INFO - 'LOGNAME': 'cltbld', 15:11:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:11:02 INFO - 'MOZ_NO_REMOTE': '1', 15:11:02 INFO - 'NO_EM_RESTART': '1', 15:11:02 INFO - 'PAGER': '/bin/cat', 15:11:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:11:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:11:02 INFO - 'PWD': '/builds/slave/test', 15:11:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:11:02 INFO - 'SHELL': '/bin/bash', 15:11:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nqCHbmpe1T/Listeners', 15:11:02 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 15:11:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:11:02 INFO - 'USER': 'cltbld', 15:11:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:11:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:11:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:11:02 INFO - 'XPC_FLAGS': '0x0', 15:11:02 INFO - 'XPC_SERVICE_NAME': '0', 15:11:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:11:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:11:02 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:11:02 INFO - Cleaning up... 15:11:02 INFO - Return code: 0 15:11:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:11:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:11:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:11:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:11:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:11:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:11:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:11:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10e540b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e543768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7cbccaa50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e31ed30>, '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 0x10e244db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e313be8>, '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.aP2cAJEs3V/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.nqCHbmpe1T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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:11:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:11:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 15:11:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aP2cAJEs3V/Render', 15:11:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:11:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:11:02 INFO - 'HOME': '/Users/cltbld', 15:11:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:11:02 INFO - 'LOGNAME': 'cltbld', 15:11:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:11:02 INFO - 'MOZ_NO_REMOTE': '1', 15:11:02 INFO - 'NO_EM_RESTART': '1', 15:11:02 INFO - 'PAGER': '/bin/cat', 15:11:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:11:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:11:02 INFO - 'PWD': '/builds/slave/test', 15:11:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:11:02 INFO - 'SHELL': '/bin/bash', 15:11:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nqCHbmpe1T/Listeners', 15:11:02 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 15:11:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:11:02 INFO - 'USER': 'cltbld', 15:11:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:11:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:11:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:11:02 INFO - 'XPC_FLAGS': '0x0', 15:11:02 INFO - 'XPC_SERVICE_NAME': '0', 15:11:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:11:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:11:03 INFO - Downloading/unpacking blobuploader==1.2.4 15:11: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:11: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:11: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:11: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:11: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:11: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:11:04 INFO - Downloading blobuploader-1.2.4.tar.gz 15:11:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:11:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:11:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:11:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:11:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:11:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:11:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:11:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:11:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:11:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:11:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:11:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:11:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:11:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:11:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:11:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:11:05 INFO - Downloading docopt-0.6.1.tar.gz 15:11:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:11:05 INFO - Installing collected packages: blobuploader, requests, docopt 15:11:05 INFO - Running setup.py install for blobuploader 15:11:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:11:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:11:06 INFO - Running setup.py install for requests 15:11:06 INFO - Running setup.py install for docopt 15:11:06 INFO - Successfully installed blobuploader requests docopt 15:11:06 INFO - Cleaning up... 15:11:06 INFO - Return code: 0 15:11:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:11:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:11:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:11:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:11:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:11:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:11:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:11:06 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 0x10e540b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e543768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7cbccaa50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e31ed30>, '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 0x10e244db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e313be8>, '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.aP2cAJEs3V/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.nqCHbmpe1T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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:11:06 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:11:06 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:11:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aP2cAJEs3V/Render', 15:11:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:11:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:11:06 INFO - 'HOME': '/Users/cltbld', 15:11:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:11:06 INFO - 'LOGNAME': 'cltbld', 15:11:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:11:06 INFO - 'MOZ_NO_REMOTE': '1', 15:11:06 INFO - 'NO_EM_RESTART': '1', 15:11:06 INFO - 'PAGER': '/bin/cat', 15:11:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:11:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:11:06 INFO - 'PWD': '/builds/slave/test', 15:11:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:11:06 INFO - 'SHELL': '/bin/bash', 15:11:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nqCHbmpe1T/Listeners', 15:11:06 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 15:11:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:11:06 INFO - 'USER': 'cltbld', 15:11:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:11:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:11:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:11:06 INFO - 'XPC_FLAGS': '0x0', 15:11:06 INFO - 'XPC_SERVICE_NAME': '0', 15:11:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:11:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:11:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:11:07 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-0UdyAv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:11:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:11:07 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-r4jy7z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:11:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:11:07 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-mo0Uei-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:11:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:11:07 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-9B98pP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:11:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:11:07 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-4d8K3r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:11:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:11:07 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-XHlISt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:11:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:11:07 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-SRMbaH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:11:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:11:07 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-qFsKqj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:11:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:11:07 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-d9AlC8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:11:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:11:07 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-Hxq8QN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:11:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:11:08 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-rsLSi6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:11:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:11:08 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-_wwy4X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:11:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:11:08 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-Thgh2e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:11:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:11:08 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-Daji6F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:11:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:11:08 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-ER_mW0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:11:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:11:08 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-FDo2YT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:11:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:11:08 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-q0lDix-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:11:08 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:11:08 INFO - Running setup.py install for manifestparser 15:11:08 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:11:08 INFO - Running setup.py install for mozcrash 15:11:09 INFO - Running setup.py install for mozdebug 15:11:09 INFO - Running setup.py install for mozdevice 15:11:09 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:11:09 INFO - Running setup.py install for mozfile 15:11:09 INFO - Running setup.py install for mozhttpd 15:11:09 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:11:09 INFO - Running setup.py install for mozinfo 15:11:09 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:11:09 INFO - Running setup.py install for mozInstall 15:11:09 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:11:09 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:11:09 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:11:09 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:11:09 INFO - Running setup.py install for mozleak 15:11:10 INFO - Running setup.py install for mozlog 15:11:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:11:10 INFO - Running setup.py install for moznetwork 15:11:10 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:11:10 INFO - Running setup.py install for mozprocess 15:11:10 INFO - Running setup.py install for mozprofile 15:11:10 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:11:10 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:11:10 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:11:10 INFO - Running setup.py install for mozrunner 15:11:10 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:11:10 INFO - Running setup.py install for mozscreenshot 15:11:11 INFO - Running setup.py install for moztest 15:11:11 INFO - Running setup.py install for mozversion 15:11:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:11:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:11:11 INFO - Cleaning up... 15:11:11 INFO - Return code: 0 15:11:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:11:11 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:11:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:11:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:11:11 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:11:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:11:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:11:11 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 0x10e540b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e543768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7cbccaa50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e31ed30>, '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 0x10e244db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e313be8>, '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.aP2cAJEs3V/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.nqCHbmpe1T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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:11:11 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:11:11 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:11:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aP2cAJEs3V/Render', 15:11:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:11:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:11:11 INFO - 'HOME': '/Users/cltbld', 15:11:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:11:11 INFO - 'LOGNAME': 'cltbld', 15:11:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:11:11 INFO - 'MOZ_NO_REMOTE': '1', 15:11:11 INFO - 'NO_EM_RESTART': '1', 15:11:11 INFO - 'PAGER': '/bin/cat', 15:11:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:11:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:11:11 INFO - 'PWD': '/builds/slave/test', 15:11:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:11:11 INFO - 'SHELL': '/bin/bash', 15:11:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nqCHbmpe1T/Listeners', 15:11:11 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 15:11:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:11:11 INFO - 'USER': 'cltbld', 15:11:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:11:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:11:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:11:11 INFO - 'XPC_FLAGS': '0x0', 15:11:11 INFO - 'XPC_SERVICE_NAME': '0', 15:11:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:11:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:11:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:11:11 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-N6UOAE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:11:11 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:11:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:11:11 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-tY2TN2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:11:11 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:11:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:11:11 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-IxTjvl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:11:11 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:11:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:11:11 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-2b1vNX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:11:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 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:11:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:11:12 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-nYHUg2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:11:12 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:11:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:11:12 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-ZRe31n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:11:12 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:11:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:11:12 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-b8KBnX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:11:12 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:11:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:11:12 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-NTQsAF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:11:12 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:11:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:11:12 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-lNAmCn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:11:12 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:11:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:11:12 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-4kz4kG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:11:12 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:11:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:11:12 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-HNDikx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:11:12 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:11:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:11:12 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-_RTPWC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:11:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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:11:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:11:12 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-Z57vUI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:11:12 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:11:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:11:12 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-Qp9U3O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:11:13 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:11:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:11:13 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-yrHnAK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:11:13 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:11:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:11:13 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-dVYEm5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:11:13 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:11:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:11:13 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-uTxRk3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:11:13 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:11:13 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:11:13 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:11:13 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:11:13 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:11:13 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:11:13 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:11:13 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:11:13 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:11:13 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:11:13 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:11:13 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:11:13 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:11:14 INFO - Downloading blessings-1.6.tar.gz 15:11:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:11:14 INFO - Installing collected packages: blessings 15:11:14 INFO - Running setup.py install for blessings 15:11:15 INFO - Successfully installed blessings 15:11:15 INFO - Cleaning up... 15:11:15 INFO - Return code: 0 15:11:15 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:11:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:11:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:11:15 INFO - Reading from file tmpfile_stdout 15:11:15 INFO - Current package versions: 15:11:15 INFO - blessings == 1.6 15:11:15 INFO - blobuploader == 1.2.4 15:11:15 INFO - docopt == 0.6.1 15:11:15 INFO - functools32 == 3.2.3-2 15:11:15 INFO - jsonschema == 2.5.1 15:11:15 INFO - manifestparser == 1.1 15:11:15 INFO - mozInstall == 1.12 15:11:15 INFO - mozcrash == 1.0 15:11:15 INFO - mozdebug == 0.1 15:11:15 INFO - mozdevice == 0.50 15:11:15 INFO - mozfile == 1.2 15:11:15 INFO - mozhttpd == 0.7 15:11:15 INFO - mozinfo == 0.9 15:11:15 INFO - mozleak == 0.1 15:11:15 INFO - mozlog == 3.4 15:11:15 INFO - moznetwork == 0.27 15:11:15 INFO - mozprocess == 0.25 15:11:15 INFO - mozprofile == 0.28 15:11:15 INFO - mozrunner == 6.13 15:11:15 INFO - mozscreenshot == 0.1 15:11:15 INFO - mozsystemmonitor == 0.3 15:11:15 INFO - moztest == 0.8 15:11:15 INFO - mozversion == 1.4 15:11:15 INFO - psutil == 3.1.1 15:11:15 INFO - requests == 1.2.3 15:11:15 INFO - wsgiref == 0.1.2 15:11:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:11:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:11:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:11:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:11:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:11:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:11:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:11:15 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 0x10e540b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e543768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7cbccaa50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e31ed30>, '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 0x10e244db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e313be8>, '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.aP2cAJEs3V/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.nqCHbmpe1T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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:11:15 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:11:15 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:11:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aP2cAJEs3V/Render', 15:11:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:11:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:11:15 INFO - 'HOME': '/Users/cltbld', 15:11:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:11:15 INFO - 'LOGNAME': 'cltbld', 15:11:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:11:15 INFO - 'MOZ_NO_REMOTE': '1', 15:11:15 INFO - 'NO_EM_RESTART': '1', 15:11:15 INFO - 'PAGER': '/bin/cat', 15:11:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:11:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:11:15 INFO - 'PWD': '/builds/slave/test', 15:11:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:11:15 INFO - 'SHELL': '/bin/bash', 15:11:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nqCHbmpe1T/Listeners', 15:11:15 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 15:11:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:11:15 INFO - 'USER': 'cltbld', 15:11:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:11:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:11:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:11:15 INFO - 'XPC_FLAGS': '0x0', 15:11:15 INFO - 'XPC_SERVICE_NAME': '0', 15:11:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:11:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:11:15 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:11:15 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:11:15 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:11:15 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:11:15 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:11:15 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:11:15 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:11:15 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:11:15 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:11:15 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:11:15 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:11:15 INFO - Cleaning up... 15:11:15 INFO - Return code: 0 15:11:15 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:11:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:11:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:11:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:11:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:11:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:11:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:11:15 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 0x10e540b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e543768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7cbccaa50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e31ed30>, '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 0x10e244db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e313be8>, '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.aP2cAJEs3V/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.nqCHbmpe1T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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:11:15 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:11:15 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:11:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aP2cAJEs3V/Render', 15:11:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:11:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:11:15 INFO - 'HOME': '/Users/cltbld', 15:11:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:11:15 INFO - 'LOGNAME': 'cltbld', 15:11:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:11:15 INFO - 'MOZ_NO_REMOTE': '1', 15:11:15 INFO - 'NO_EM_RESTART': '1', 15:11:15 INFO - 'PAGER': '/bin/cat', 15:11:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:11:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:11:15 INFO - 'PWD': '/builds/slave/test', 15:11:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:11:15 INFO - 'SHELL': '/bin/bash', 15:11:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nqCHbmpe1T/Listeners', 15:11:15 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 15:11:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:11:15 INFO - 'USER': 'cltbld', 15:11:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:11:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:11:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:11:15 INFO - 'XPC_FLAGS': '0x0', 15:11:15 INFO - 'XPC_SERVICE_NAME': '0', 15:11:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:11:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:11:15 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:11:15 INFO - Cleaning up... 15:11:15 INFO - Return code: 0 15:11:15 INFO - Running post-action listener: _resource_record_post_action 15:11:15 INFO - Running post-action listener: _start_resource_monitoring 15:11:15 INFO - Starting resource monitoring. 15:11:15 INFO - [mozharness: 2017-05-05 22:11:15.905574Z] Finished create-virtualenv step (success) 15:11:15 INFO - [mozharness: 2017-05-05 22:11:15.906557Z] Running install step. 15:11:15 INFO - Running pre-action listener: _resource_record_pre_action 15:11:15 INFO - Running main action method: install 15:11:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:11:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:11:16 INFO - Reading from file tmpfile_stdout 15:11:16 INFO - Detecting whether we're running mozinstall >=1.0... 15:11:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:11:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:11:16 INFO - Reading from file tmpfile_stdout 15:11:16 INFO - Output received: 15:11:16 INFO - Usage: mozinstall [options] installer 15:11:16 INFO - Options: 15:11:16 INFO - -h, --help show this help message and exit 15:11:16 INFO - -d DEST, --destination=DEST 15:11:16 INFO - Directory to install application into. [default: 15:11:16 INFO - "/builds/slave/test"] 15:11:16 INFO - --app=APP Application being installed. [default: firefox] 15:11:16 INFO - mkdir: /builds/slave/test/build/application 15:11:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 15:11:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 15:11:36 INFO - Reading from file tmpfile_stdout 15:11:36 INFO - Output received: 15:11:36 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 15:11:36 INFO - Running post-action listener: _resource_record_post_action 15:11:36 INFO - [mozharness: 2017-05-05 22:11:36.281551Z] Finished install step (success) 15:11:36 INFO - [mozharness: 2017-05-05 22:11:36.281712Z] Running run-tests step. 15:11:36 INFO - Running pre-action listener: _resource_record_pre_action 15:11:36 INFO - Running main action method: run_tests 15:11:36 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:11:36 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:11:36 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:11:36 INFO - 2017-05-05 15:11:36.296 screenresolution[1627:7345] starting screenresolution argv=screenresolution get 15:11:36 INFO - 2017-05-05 15:11:36.298 screenresolution[1627:7345] Display 0: 1600x1200x32@60 15:11:36 INFO - 2017-05-05 15:11:36.304 screenresolution[1628:7348] starting screenresolution argv=screenresolution list 15:11:36 INFO - Available Modes on Display 0 15:11:36 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:11:36 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:11:36 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:11:36 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:11:36 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:11:36 INFO - Intel Iris: 15:11:36 INFO - Chipset Model: Intel Iris 15:11:36 INFO - Type: GPU 15:11:36 INFO - Bus: Built-In 15:11:36 INFO - VRAM (Dynamic, Max): 1536 MB 15:11:36 INFO - Vendor: Intel (0x8086) 15:11:36 INFO - Device ID: 0x0a2e 15:11:36 INFO - Revision ID: 0x0009 15:11:36 INFO - Displays: 15:11:36 INFO - Display: 15:11:36 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:11:36 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:11:36 INFO - Display Serial Number: 1600x1200 60 15:11:36 INFO - Main Display: Yes 15:11:36 INFO - Mirror: Off 15:11:36 INFO - Online: Yes 15:11:36 INFO - Rotation: Supported 15:11:36 INFO - Return code: 0 15:11:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:11:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:11:36 INFO - Python 2.7.3 15:11:36 INFO - Return code: 0 15:11:36 INFO - grabbing minidump binary from tooltool 15:11:36 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:11:36 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 0x10e31ed30>, '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 0x10e244db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e313be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:11:36 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:11:36 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:11:36 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:11:36 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:11:36 INFO - Return code: 0 15:11:36 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:11:36 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:11:36 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:11:36 INFO - ENV: RUST_BACKTRACE is now 1 15:11:36 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:11:36 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:11:36 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/V6_FvUlOT2GkS8FQv7CHWA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0243', '--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:11:36 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/V6_FvUlOT2GkS8FQv7CHWA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0243 --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:11:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aP2cAJEs3V/Render', 15:11:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:11:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:11:36 INFO - 'HOME': '/Users/cltbld', 15:11:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:11:36 INFO - 'LOGNAME': 'cltbld', 15:11:36 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:11:36 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:11:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:11:36 INFO - 'MOZ_NO_REMOTE': '1', 15:11:36 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:11:36 INFO - 'NO_EM_RESTART': '1', 15:11:36 INFO - 'PAGER': '/bin/cat', 15:11:36 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:11:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:11:36 INFO - 'PWD': '/builds/slave/test', 15:11:36 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:11:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:11:36 INFO - 'RUST_BACKTRACE': '1', 15:11:36 INFO - 'SHELL': '/bin/bash', 15:11:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nqCHbmpe1T/Listeners', 15:11:36 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 15:11:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:11:36 INFO - 'USER': 'cltbld', 15:11:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:11:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:11:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:11:36 INFO - 'XPC_FLAGS': '0x0', 15:11:36 INFO - 'XPC_SERVICE_NAME': '0', 15:11:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:11:36 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/V6_FvUlOT2GkS8FQv7CHWA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0243', '--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:11:36 INFO - mozversion application_buildid: 20170505132514 15:11:36 INFO - mozversion application_changeset: e7bf9443be2c4a5187c37440e35f3526148d7fa8 15:11:36 INFO - mozversion application_display_name: Nightly 15:11:36 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:11:36 INFO - mozversion application_name: Firefox 15:11:36 INFO - mozversion application_remotingname: firefox 15:11:36 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:11:36 INFO - mozversion application_vendor: Mozilla 15:11:36 INFO - mozversion application_version: 55.0a1 15:11:36 INFO - mozversion platform_buildid: 20170505132514 15:11:36 INFO - mozversion platform_changeset: e7bf9443be2c4a5187c37440e35f3526148d7fa8 15:11:36 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:11:36 INFO - mozversion platform_version: 55.0a1 15:11:36 INFO - using testdate: 1494022296 15:11:36 INFO - actual date: 1494022296 15:11:36 INFO - starting webserver on 'localhost:49241' 15:11:36 INFO - SUITE-START | Running 2 tests 15:11:36 INFO - TEST-START | dromaeo_css 15:11:36 INFO - operating with platform_type : mac_ 15:11:36 INFO - Initialising browser for dromaeo_css test... 15:11:36 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/tmpqYHwiH/profile http://localhost:49241/getInfo.html) 15:11:39 INFO - PID 1638 | 2017-05-05 15:11:39.946 plugin-container[1640:7533] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4d43, name = 'com.apple.tsm.portname' 15:11:39 INFO - PID 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:11:39 INFO - PID 1638 | 2017-05-05 15:11:39.949 plugin-container[1640:7533] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0xa00b, name = 'com.apple.CFPasteboardClient' 15:11:39 INFO - PID 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:11:39 INFO - PID 1638 | 2017-05-05 15:11:39.949 plugin-container[1640:7533] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:11:40 INFO - PID 1638 | __metrics Screen width/height:1600/1200 15:11:40 INFO - PID 1638 | colorDepth:24 15:11:40 INFO - PID 1638 | Browser inner width/height: 1024/691 15:11:40 INFO - PID 1638 | __metrics 15:11:40 INFO - PID 1638 | 2017-05-05 15:11:40.442 plugin-container[1641:7586] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 15:11:40 INFO - PID 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:11:40 INFO - PID 1638 | 2017-05-05 15:11:40.443 plugin-container[1641:7586] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x980b, name = 'com.apple.CFPasteboardClient' 15:11:40 INFO - PID 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:11:40 INFO - PID 1638 | 2017-05-05 15:11:40.443 plugin-container[1641:7586] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:11:41 INFO - TEST-INFO | 1638: exit 0 15:11:41 INFO - Browser initialized. 15:11:41 INFO - Running cycle 1/1 for dromaeo_css test... 15:11:41 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/tmpqYHwiH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:11:43 INFO - PID 1642 | 2017-05-05 15:11:43.578 plugin-container[1643:7702] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 15:11:43 INFO - PID 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:11:43 INFO - PID 1642 | 2017-05-05 15:11:43.579 plugin-container[1643:7702] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9613, name = 'com.apple.CFPasteboardClient' 15:11:43 INFO - PID 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:11:43 INFO - PID 1642 | 2017-05-05 15:11:43.579 plugin-container[1643:7702] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:11:43 INFO - PID 1642 | 2017-05-05 15:11:43.987 plugin-container[1644:7742] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4343, name = 'com.apple.tsm.portname' 15:11:43 INFO - PID 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:11:43 INFO - PID 1642 | 2017-05-05 15:11:43.988 plugin-container[1644:7742] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4717, name = 'com.apple.CFPasteboardClient' 15:11:43 INFO - PID 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:11:43 INFO - PID 1642 | 2017-05-05 15:11:43.988 plugin-container[1644:7742] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:11:53 INFO - PID 1642 | RSS: Main: 232574976 15:11:53 INFO - PID 1642 | 15:14:57 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 15:14:59 INFO - PID 1642 | RSS: Main: 249643008 15:14:59 INFO - PID 1642 | 15:18:01 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 15:18:01 INFO - PID 1642 | RSS: Main: 252731392 15:18:01 INFO - PID 1642 | 15:21:03 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 15:21:04 INFO - PID 1642 | RSS: Main: 263892992 15:21:04 INFO - PID 1642 | 15:24:06 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 15:24:06 INFO - PID 1642 | RSS: Main: 257429504 15:24:06 INFO - PID 1642 | 15:27:09 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 15:27:10 INFO - PID 1642 | RSS: Main: 262569984 15:27:10 INFO - PID 1642 | 15:30:12 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 15:30:12 INFO - PID 1642 | RSS: Main: 269205504 15:30:12 INFO - PID 1642 | 15:30:12 INFO - PID 1642 | __start_tp_report 15:30:12 INFO - PID 1642 | _x_x_mozilla_page_load 15:30:12 INFO - PID 1642 | _x_x_mozilla_page_load_details 15:30:12 INFO - PID 1642 | |i|pagename|runs| 15:30:12 INFO - PID 1642 | |0;dojo.html;3123.422166899123;3625.927481450369;3675.3751862183426;3684.428913518406;3695.796731179784;6160.81517554473;6253.812385628433;6294.559380843337;6324.241091069072;6392.041193820929;6227.719752611132;6238.688065596713;6263.843403914904;6280.466160376373;6306.653134077624;4477.6417886569;5481.397046324921;5486.698231597261;5505.889882202354;5522.392536820947;5532.921080389326;5598.664080155204;5603.467670571289;5644.520215781664;5675.148727690838;5398.217258497496;5543.695096769676;5574.080276754331;5613.578981576377;5630.802921897734;5487.396386397493;5498.835034948958;5546.362168350651;5567.9420910026965;5609.158626206102;5519.806806761784;5536.307961504812;5542.722863856791;5566.387697353288;5574.080276754331;6058.636481811105;6295.244570651551;6320.968395158041;6321.74713011479;6335.176427064452;6542.378474044958;6642.601443913332;6711.932880671228;6779.932200677979;6782.694778734919;6897.793066208022;6899.655017249117;6900.413464855494;6906.06768086307;6923;6343.492520598341;6369.0764839099;6388.55280130391;6425.807225783234;6449.32282110381;6713.832154196087;6838.384545390939;6889.862202756017;6974.163100427981;7077.964610176916;6366.427021568081;6450.548461607643;6493.058506516529;6566.868662626721;6620.337966203341;6780.694868730919;6798.150231221098;6831.555948863308;6837.316268373223;6895.931040689629;5667.80162694312;6763.492738044666;6783.59298442095;6791.59250444965;6795.490338224653;6004.579679422399;6110.528382738247;6573.934260657332;6632.2372927114;6800.455963523005;4759.428868535798;5374.435684253175;5415.539679127318;5434.809781657623;5443.455654434525;3673.632636736305;4598.333241679938;4770.76146192689;4812.205986012339;4839.588634966068;5355.732213389315;6332.4934005279465;6386.712597933104;6454.483641308684;6459.612423254526;6674.532782705164;6784.253732089466;6847.281035491202;6860.725570977205;6919.515633905578;5436.592255580847;6379.362063793584;6423.614583124934;6445.033245013192;6456.451201647913;5988.850278742979;6061.636301821904;6105.419985101408;6105.664188469588;6114.9910264806695;6836;6929.89605155913;7016.298370162943;7119.074520312326;7155.356017958305;6923.965380173268;6927.168739751262;6930.549514281555;6960.199577048705;6964.199117101598;7045.084139061889;7050.153981522045;7122.7507037253445;7141.571505709674;7164.247753985965;6583.473322134122;6826.556273842184;6840.931590684029;6846.760363387258;6861.725530978705;6753.054572359972;6858.554193977376;6880.552764070319;6906.482013848881;6947.23580406165;6443.387878151568;6704.262531121671;6714.597124172566;6717.361850624183;6733.124693789776;6106.06632622643;6449.96775016122;6696.866062678621;6708.563943343667;6737.69680364375;6274.215723034621;6435.066915297162;6537.738490460329;6581.638009909309;6582.440492557995;6471.805845824632;6489.156409666713;6581.901271481028;6617.569857959217;6655.633940133339;6503;6542.672866356568;6545.541812073109;6564.573302735307;6565.573237739532 15:30:12 INFO - PID 1642 | |1;ext.html;748.7416841189792;793.8174219929416;822.387321445523;1209.6371088673402;1269.5873841001676;34537.65462345376;39252;39255.80372098139;39315.60684393155;39429.60570394299;39070.60929390705;39466.21067578647;39480.40779388312;39503.209935801264;39519.802400988025;7843.843123137548;7983.3214176795045;8128.1465446128195;8384.706535271267;8404.537750423724;8470.66117355305;8557.48655080694;8602.48385096893;8604.913950860473;8617.827643447159;8500.404971651988;8556.48661080334;8607.010193827697;8630.827383452328;8631.784205394884;8494.108118647515;8565.143485651417;8612.354073444516;8622.698205562838;8625.741227763147;8274.2966847818;8337.541435221061;8393.580320983987;8399.28606068485;8407.579621018924;37811.24377512462;37865.81067094647;37959.62040379588;38001.429978550346;38062.61937380646;3144.591203143577;3642.7814331139953;3686.3733165361955;3688.1517251032146;3689.760165589228;31086.68913310883;31185.376292474022;31188.064358069296;31272.687273127205;31359.05922822319;3113.2372568720807;3156.779025468218;3159.0364938693688;3166.62000559932;3168.5405616185753;240864;245080;271435;289169;290605.09394906205;48365;48379.516204838204;48464.757676212095;48615;48622.513774862506;40721;50853.745731271105;51019;51079.23381149286;51159.23261151086;11950.462229199533;14340.354684039243;14481.710365792625;14492.710145797026;14493.92753036228;11736.295822250693;11758.470868810924;11789.88210117905;11793.941030294794;11803.468843902045;13672.589822305346;13764.31178441074;13782.310884455737;13830.893528517896;13954.232517211447;6135.662538560333;6176.8764624707255;6186.19579454668;6188.381161883865;6194.34959329273;889.4663202078831;996.7607774134156;1019.5921631347372;1080.2708171984036;1082.3397727386289;23736.050557977833;24753.752462475506;24871.87564062168;24955.126570569944;25369.492610148063;910.2808781063019;1029.341221618165;1056.9577216911237;1130.2879186112696;1169.6198735411026;12653.114282000355;12721.427535760913;12766;12797.424115914806;12830.358482075859;280546.3890722215;280907;308113.91886080854;308506;309670.8713933587;54383.45616543784;54432;54731;54746;54902;1497.6480527076128;1515.9848401515844;1550.9612259693597;1561.6642421879124;1572.842715728441;21925;24135.637965430884;24229.51540969206;24321.027158913446;24384.878075609508;38959.026024349616;39001.804990974866;39102.60897391103;39112.80443597764;39120;244511;246335;246381;247011;248795;34581.30837383188;34841;35671.64328356787;35776.926692199275;35907.640923591476;44555;45340;46848.297275541576;46880.59358219259;46886.76556617195;30756.692433075383;30762.538561922036;31397;31992.68007319897;32139.678603214605;31560.210994725316;32276.35447291088;32433.351332972736;32490.350192995535;32532.67467325391;36716.26567468582;36988.44517332188;37881.81059094687;38303;38429.61570384372;29841.25396865096;31557.526637100003;31651.892183773456;32082.358352832347;32084.839575801972;23319.300420987398;24459.38851528726;25077.74922250827;25085.99656013739;25491.617625736 15:30:12 INFO - PID 1642 | |2;jquery.html;339.09799932180397;399.9620983547693;421.79964516854517;424.68361071002107;444.488837836488;21074.051667674954;21236.36290911271;21593.244236451726;21948.561028779415;22119.66820497692;22704.545909081808;23029.848507574596;23216.3035108947;23740.0503979841;23805;5536.889262214753;5600.551955843523;6203.410675985774;6249.937500624981;6258.186435763344;5907.261592300963;6063.484603808682;6079.544034197431;6107.389261073901;6175.258968923736;6109.35851735568;6131.908021379683;6141.877162456748;6144.170536977495;6145.354737752538;5433.320834895638;5863.501602363783;5865.120231965197;6064.302605200414;6115;5332.813351532678;5414.35027796663;5438.211459338413;5446.618736688433;5452.781888724447;28324.29189270264;28363.72363243659;28399;28602.284942876595;28616.570751438747;2236.5974124657555;2300.1719381022817;2302.4013756423283;2304.3547806614174;2306.630939049761;18102.728459073125;18241;18456.815431845644;18759;18886;2114.851960362775;2156.6225910465605;2188.9890550547148;2191.0468946008377;2196.5716685246384;153933.2303338476;156251;156367.21816390846;157853;159450.83844130914;12630.179038362478;13345.399457024458;13363;13378.46486140563;13386.866131338757;16418.83581164197;16748;16760.994340339617;16797.832021679627;16813.243404046847;8763.079876612992;9100.590473428712;9106.817863642822;9185.402948808307;9190.310726695525;8505.362097842686;8537.74386768398;8569.571521423904;8683.956580217058;8738.781530461662;6536.7712130075215;8090.635921383552;8212.17878212186;8256.628451719687;8425.662973481114;2119.290037837311;2168.0894024509744;2177.2162021672184;2177.5644871025856;2179.2045903245203;873.7247766953517;1014.6505148152938;1019.7858449725641;1036.036486067949;1066.328213225663;19532.414027579194;21651.242206522697;22031.779682202974;22139.667904981437;22172.667409988862;939.8073394954018;1027.8098551767946;1057.3338796558273;1073.9140868730483;1078.789636020984;10816.729581760519;11205.04757095656;11212.55149793999;11288.153388495895;11338.489767960296;495565;504974;506439;522237;552533;115644.8435515634;118255;135629.3218533901;138443;139409;1027.4041056187393;1227.0796902323257;1250.7185883176394;1258.5657948007922;1263.4946021591254;22397.552048959256;23352.299431017098;23517.529649407257;23683.763162368155;23905.402364941016;58742.11886821616;58872;59487.70256148691;60436.697816510634;61148.38851611427;71493;80151.59924200342;84567.5771621138;85387;86717.56641217005;8359.746038094092;9041.954790226007;9090.181883630241;9150.725478235663;9157.267418606629;14081.929590351983;14143.64640883986;14236.857631423552;14391;14460.20468874222;9694.22446204288;10584.629537966135;10803.649543807023;11964.282143071443;12046.698832528906;11131.443427828413;11134;11365.020415905623;11485.770284594428;11652.883471165518;9715.68236233018;11118.444077796239;11745.647630571097;12132.454039568063;12196.146269761031;7252.41980641558;9035.232005279624;9046.819063618823;9137.732835026278;9273.119053689888;11582.42087895585;11825.645230643095;11879.287242765462;11977.580784672495;12160.270383777002 15:30:12 INFO - PID 1642 | |3;mootools.html;1383.9238841863696;1431.6134643646217;1458.1918421157754;1470.1913947328967;1475.3950880139141;11220.438978051096;11525.999999999978;11701.882981170196;11714.589989350374;11811.822822657667;11511.769764604704;11709;11724.765504689902;11742.647720568397;11750.764984700301;2504.874756262189;2533.898644054236;2555.6933168019864;2556.6292887531295;2556.7443255674375;2077.480629842539;2475.269795410349;2530.0891678995545;2543.300592337113;2550.9489810203786;2216.612092883739;2491.8504889706587;2500.6874140732407;2531.2659328794625;2536.7336429674897;2118.300960682971;2476.182859656318;2498.375406148472;2516.295437277547;2528.1657053172503;1969.4780883065998;2392.222527678494;2496.95006099877;2518.7733104020544;2534.4677617700304;11775.882241177564;11839.763204735944;11859.52561897523;11862.88137118635;11878.287302761863;6591.340865913418;6726.966365168192;6823.454123670144;6846.144231971004;6847.828804279883;4071.066243534327;4412.338149277602;4457.1977044132045;4481.35020422037;4485.237509623339;1251.743392604514;1262.7853264944981;1275.6810797300675;1302.3488255872064;1308.1823860087445;13731.931340343333;14885.33016014282;15505.689886202326;16218.75671864923;16389;5436.320459942507;5550.557754587969;5709.115087161496;5740.502881246671;5745.396733343023;11738.823917641243;11773.470193841298;11801.055915526736;11828.585999489977;11853.34806585646;6486;6570.113034740249;6586.769463068866;6588.53880228389;6588.571742836701;6107.969460152671;6117.96941015292;6253.37466253371;6255.937440625627;6299;4300.849470268526;4395.1868904252815;4421.336799480104;4465.977670111629;4523.751193684379;1255.12768625806;1279.3475327582814;1282.5959822655834;1292.8577856435784;1299.9805002924963;798.2217338095427;809.8137428391503;811.7727036429691;835.4778263585259;852.032942610142;6157.261128664589;6180.165677633462;6320.873582528324;6410.134631824644;6447.645379504079;590.3889474393982;597.265363602771;598.2940130645793;903.9864402033975;965.0928127560071;4069.2878746219294;4315.266404711207;4362.323839804835;4366.126774645084;4455.6212721918355;14507.114075170372;15134.091954482767;15143.545693629208;15148.560886715744;15155.696886062216;14743.852561474463;14902.403903843724;14981.775273370908;15085.698286034218;15260;8573.27127194183;8582;8684.34867384936;8710.041895391501;8771.342149338698;6898.758543450955;6966.1988871280455;6972.302769722987;6995.300469952964;7097.254788247366;11103.445517627377;13387.531436399679;13420.46318147263;13477.528286510318;13731.931340343235;13840.653983650489;13847.515336963157;13852.89176865828;13991.440342386191;13991.650208744863;9095.909040909506;9403.764905877408;9421.38760980534;9506.904930950603;9518.23854091685;10770.676879693621;10780.245382823126;10855;10863.456827158769;11002.449877506253;8837.337199710177;9211.723648290294;9239.168474837297;9288.024757400375;9299.72100836949;9049.366544341832;9077.04691007461;9107.27141828639;9138.040505746802;9160.946491153603;8094.83810323802;8227.712030079158;8244.340452763645;8256.587170641324;8261.710840120568;5085.77114029877;5199.818006369759;5969.313528944227;6069.939300606938;6279.120923070683;5967.731452084753;6134.079888016655;6191.442770150708;6220.72006759706;6239.469645080219 15:30:12 INFO - PID 1642 | |4;prototype.html;351.9771214871034;360.9873654422096;400.5933977013332;409.23268870867145;410.2779108768567;5167.224916262562;6102.816915492543;6362.268339141;6388.201474815648;6394.936050639498;5700.230468886699;5831.900515814314;6300.180976473052;6301.495880329574;6330.430261276484;4562.779429028153;4690.90618187636;4835.830745923876;4871.951280487186;4875.756212189394;4822.4554952110175;4878.731669758162;4898.069366820293;4920.114379411704;4939.28380384844;4854.635902307324;4856.2958371036175;4868.196747536652;4878.658493905428;4885.755712214375;4068.206699693561;4690.202665546848;4725.976370118162;4801.44783349914;4885.236894028294;4312.33158860374;4320.935185972213;4328.177646247234;4329.19909816685;4353.695241333108;4648.4427716714945;5095.388553373582;5468.7539060742365;5559.8888022239325;6273.498120150377;5145.6398052136365;5724.5992780505385;5791.536677065825;5804.419558044204;5833.3666573359105;5645.435456454322;6857.794266172023;6865.691043903036;6869.450443964521;6880.518363714542;3794.6774524165203;4802.903941921142;4864.367632207825;4937.506249375069;4988.102141614508;6062;6846.6649003444345;7078.362947334765;7184.38932690722;7272.672729727175;4914.827981020647;5079.81567925788;5087.491250874883;5106.45525482321;5113.923291150636;5181.948180518147;5197.974010129925;5199.960007998416;5210.21846722987;5223.738813059332;4722.6221902247735;5631.549476041989;5743.712814359265;5764.596478246566;5807.767689292466;6782.626955517494;6881.965590172117;6884.48366372514;6894.965525172342;6911.827204319932;1502.4591147187004;1608.2119761317103;1682.1757338904094;1704.6931552320702;1739.686856365841;1015.7054454208345;1106.9944650276698;1160.2864238493266;1164.4512797978837;1231.926084434919;6088.786892458743;6433.625134908214;6436;6436.616127532603;6513.827511047962;5888.381719919433;6490.837729056811;6533.941808330361;7161.9641901790155;7199.244079371695;5610;5910.940890591039;6033.763078568971;6391;6432;3221.935561288761;3426.6059403168956;3647.571282993977;3664.1755604988666;3693.686036686912;6886.552374095668;7203.855922881513;7248.318122294785;7305.817354566072;7337.559746415232;6130.693465326805;6897.9655101725175;7180.886962520817;7244.420446364278;7252.202257751643;6583.275839657634;6960.547564408297;7285.562866228043;7313.085864266966;7366.484346095722;7136.574444266677;7151.463640227108;7154.964225179049;7157.320054595013;7175.457276685538;6695.163104611924;6853.17761868579;6870.381665650116;6933.687984040629;6993.895091573732;5563.527100196528;6044.576879618385;7283.016792732913;7293.286077771825;7306.305900939402;3927.7054220932973;4451.376807247053;4488.730676159441;4523.253663145544;4524.9547504524535;6818.897716534157;7018.789436316919;7109.324614161646;7116.323949224816;7123.893141602776;6816.420604248496;6848.77414840169;6859.862802744017;6860.108185935996;6892.345227203407;6460.483161347175;6685.39831415175;6757.459403247827;6761;6764.898526522008;6598.505112116514;6638.767643132467;6677.2988836173445;6682.9331706682315;6692.732290708317;6634.568753031038;6720.260771315249;6757.493188011017;6798.490113241428;6827.487938404739;5259.710715910662;5779.624324418899;6481.70365926826;6623.569467984566;6650.667466626746 15:30:12 INFO - PID 1642 | |5;yui.html;785.8624740670382;816.9795755106126;838.6519594368334;846.6613354658136;859.1923591823684;16321.265543050562;16785.832141678595;16819;18022.279108835664;19162.425127246206;17917.641647167082;18150.273989040423;18444.631107377845;18461.261549538;18681.532961675966;999.9700308682051;1019.8266294729914;1025.5590096258607;1025.6922923123034;1036.1400037968467;974.3228456222928;1008.6268080810111;1026.0150255754484;1030.361176070837;1033.81908166071;1047.4291511126416;1053.2048303530823;1060.946952652368;1061.4056128567988;1063.4735805776152;1051.1905832508965;1055.6041484443372;1058.8041212375736;1059.7615536504302;1061.3260579531975;767.4129291092319;769.9461037727361;786.1116937860181;786.7207141464755;789.4276649429175;15216.695666086727;16830.410935617194;16986.320547178097;17134.742978855327;17417.04206268655;15199.392024319013;15263.847361526354;15438.150901700403;15500.379984800595;15613.68772624541;11240;12809.743805123846;13987.020908536406;14039.929800351036;14060.718785624333;3512.5609298837644;3517.3492903812876;3544.2734239480847;3546.308469848381;3548.3790336690977;132748.58750030978;134188;136528;138387;139170.60829391677;10147.69556913279;12244.387780610934;12735.681607959876;13297.06920515574;13317.467301308034;23436;25602.287668287994;26332.473350533266;26419.867900660374;26852.865735671196;7481.513701609487;7704.9614751927;7719.961400192963;7726.304632583095;7744.070711514654;3276.2955964467756;3824.4836947011795;3869.342211823997;3887.688984881203;3915.1778126593163;3784.3566593679143;3813.7139714521522;3840.231953609289;4371.978140109279;4450.310201918708;932.7528205025674;950.7432993091965;962.956666949989;966.7534778631403;974.1281553010016;587.2512546503157;639.3159319528061;672.653583404547;678.5860625018735;702.9648517574203;10872.402017888933;10933.398663073607;10984.121270298518;12001.519939202508;12004.579839705568;634.4733870887153;654.5712558274338;671.0739179931761;679.9116114905031;686.8042607856737;5484.424135465719;5535.335759708861;5546.611737178439;5587.329520457572;5681.460261275172;99907.00092999122;100245;100398.49402258972;102130.46804297941;102512;81914.59042704749;85558.57220713857;86642.56678716566;89843.55078224698;92906;81963.54109376729;84437.57781211054;85063.5746821262;85697.5726820491;86654.56672716596;11559.248648837798;12969.35153242353;12995.350232488148;13052.869471305165;13092.541761038317;39374.80312598419;39439.408408873314;40053.59946400499;40536;40538.39192412057;42467.7876610615;52593;52840.207396888305;52943;53668.19497707616;16085.678286433971;16150.434734784227;16657.583560411087;17099.487015389557;17183.656326873144;17377.21802518915;18654.906725466284;18708.625827483647;18909;18920.33778817735;14373.784393234318;16581.834181658513;17206.655866882844;17504.649907001534;17634.470965871045;16794.916025419796;16827.326906923587;17509;17512.64974700524;17605.471835844946;16020;16237.756433653742;16628.83371166273;16803.41188058412;16843.663126737643;9213;11723.941380293043;11808.645740627795;12129.332886691316;12166.635000949986;11837.76324473523;12316.507339706312;13007.219566826021;13132.40904159296;14024.789628155382 15:30:12 INFO - PID 1642 | __end_tp_report 15:30:12 INFO - PID 1642 | __start_cc_report 15:30:12 INFO - PID 1642 | _x_x_mozilla_cycle_collect,399 15:30:12 INFO - PID 1642 | __end_cc_report 15:30:12 INFO - PID 1642 | __startTimestamp1494023412518__endTimestamp 15:30:12 INFO - PID 1642 | 15:30:12 INFO - PID 1642 | ------- Summary: start ------- 15:30:12 INFO - PID 1642 | Number of tests: 6 15:30:12 INFO - PID 1642 | 15:30:12 INFO - PID 1642 | [#0] dojo.html Cycles:165 Average:6235.80 Median:6454.48 stddev:782.74 (12.1%) stddev-sans-first:746.09 15:30:12 INFO - PID 1642 | Values: 3123.4 3625.9 3675.4 3684.4 3695.8 6160.8 6253.8 6294.6 6324.2 6392.0 6227.7 6238.7 6263.8 6280.5 6306.7 4477.6 5481.4 5486.7 5505.9 5522.4 5532.9 5598.7 5603.5 5644.5 5675.1 5398.2 5543.7 5574.1 5613.6 5630.8 5487.4 5498.8 5546.4 5567.9 5609.2 5519.8 5536.3 5542.7 5566.4 5574.1 6058.6 6295.2 6321.0 6321.7 6335.2 6542.4 6642.6 6711.9 6779.9 6782.7 6897.8 6899.7 6900.4 6906.1 6923.0 6343.5 6369.1 6388.6 6425.8 6449.3 6713.8 6838.4 6889.9 6974.2 7078.0 6366.4 6450.5 6493.1 6566.9 6620.3 6780.7 6798.2 6831.6 6837.3 6895.9 5667.8 6763.5 6783.6 6791.6 6795.5 6004.6 6110.5 6573.9 6632.2 6800.5 4759.4 5374.4 5415.5 5434.8 5443.5 3673.6 4598.3 4770.8 4812.2 4839.6 5355.7 6332.5 6386.7 6454.5 6459.6 6674.5 6784.3 6847.3 6860.7 6919.5 5436.6 6379.4 6423.6 6445.0 6456.5 5988.9 6061.6 6105.4 6105.7 6115.0 6836.0 6929.9 7016.3 7119.1 7155.4 6924.0 6927.2 6930.5 6960.2 6964.2 7045.1 7050.2 7122.8 7141.6 7164.2 6583.5 6826.6 6840.9 6846.8 6861.7 6753.1 6858.6 6880.6 6906.5 6947.2 6443.4 6704.3 6714.6 6717.4 6733.1 6106.1 6450.0 6696.9 6708.6 6737.7 6274.2 6435.1 6537.7 6581.6 6582.4 6471.8 6489.2 6581.9 6617.6 6655.6 6503.0 6542.7 6545.5 6564.6 6565.6 15:30:12 INFO - PID 1642 | 15:30:12 INFO - PID 1642 | [#1] ext.html Cycles:180 Average:43031.58 Median:34559.48 stddev:70962.46 (205.3%) stddev-sans-first:71090.51 15:30:12 INFO - PID 1642 | Values: 748.7 793.8 822.4 1209.6 1269.6 34537.7 39252.0 39255.8 39315.6 39429.6 39070.6 39466.2 39480.4 39503.2 39519.8 7843.8 7983.3 8128.1 8384.7 8404.5 8470.7 8557.5 8602.5 8604.9 8617.8 8500.4 8556.5 8607.0 8630.8 8631.8 8494.1 8565.1 8612.4 8622.7 8625.7 8274.3 8337.5 8393.6 8399.3 8407.6 37811.2 37865.8 37959.6 38001.4 38062.6 3144.6 3642.8 3686.4 3688.2 3689.8 31086.7 31185.4 31188.1 31272.7 31359.1 3113.2 3156.8 3159.0 3166.6 3168.5 240864.0 245080.0 271435.0 289169.0 290605.1 48365.0 48379.5 48464.8 48615.0 48622.5 40721.0 50853.7 51019.0 51079.2 51159.2 11950.5 14340.4 14481.7 14492.7 14493.9 11736.3 11758.5 11789.9 11793.9 11803.5 13672.6 13764.3 13782.3 13830.9 13954.2 6135.7 6176.9 6186.2 6188.4 6194.3 889.5 996.8 1019.6 1080.3 1082.3 23736.1 24753.8 24871.9 24955.1 25369.5 910.3 1029.3 1057.0 1130.3 1169.6 12653.1 12721.4 12766.0 12797.4 12830.4 280546.4 280907.0 308113.9 308506.0 309670.9 54383.5 54432.0 54731.0 54746.0 54902.0 1497.6 1516.0 1551.0 1561.7 1572.8 21925.0 24135.6 24229.5 24321.0 24384.9 38959.0 39001.8 39102.6 39112.8 39120.0 244511.0 246335.0 246381.0 247011.0 248795.0 34581.3 34841.0 35671.6 35776.9 35907.6 44555.0 45340.0 46848.3 46880.6 46886.8 30756.7 30762.5 31397.0 31992.7 32139.7 31560.2 32276.4 32433.4 32490.4 32532.7 36716.3 36988.4 37881.8 38303.0 38429.6 29841.3 31557.5 31651.9 32082.4 32084.8 23319.3 24459.4 25077.7 25086.0 25491.6 15:30:12 INFO - PID 1642 | 15:30:12 INFO - PID 1642 | [#2] jquery.html Cycles:180 Average:35131.66 Median:12666.12 stddev:88335.24 (697.4%) stddev-sans-first:88544.42 15:30:12 INFO - PID 1642 | Values: 339.1 400.0 421.8 424.7 444.5 21074.1 21236.4 21593.2 21948.6 22119.7 22704.5 23029.8 23216.3 23740.1 23805.0 5536.9 5600.6 6203.4 6249.9 6258.2 5907.3 6063.5 6079.5 6107.4 6175.3 6109.4 6131.9 6141.9 6144.2 6145.4 5433.3 5863.5 5865.1 6064.3 6115.0 5332.8 5414.4 5438.2 5446.6 5452.8 28324.3 28363.7 28399.0 28602.3 28616.6 2236.6 2300.2 2302.4 2304.4 2306.6 18102.7 18241.0 18456.8 18759.0 18886.0 2114.9 2156.6 2189.0 2191.0 2196.6 153933.2 156251.0 156367.2 157853.0 159450.8 12630.2 13345.4 13363.0 13378.5 13386.9 16418.8 16748.0 16761.0 16797.8 16813.2 8763.1 9100.6 9106.8 9185.4 9190.3 8505.4 8537.7 8569.6 8684.0 8738.8 6536.8 8090.6 8212.2 8256.6 8425.7 2119.3 2168.1 2177.2 2177.6 2179.2 873.7 1014.7 1019.8 1036.0 1066.3 19532.4 21651.2 22031.8 22139.7 22172.7 939.8 1027.8 1057.3 1073.9 1078.8 10816.7 11205.0 11212.6 11288.2 11338.5 495565.0 504974.0 506439.0 522237.0 552533.0 115644.8 118255.0 135629.3 138443.0 139409.0 1027.4 1227.1 1250.7 1258.6 1263.5 22397.6 23352.3 23517.5 23683.8 23905.4 58742.1 58872.0 59487.7 60436.7 61148.4 71493.0 80151.6 84567.6 85387.0 86717.6 8359.7 9042.0 9090.2 9150.7 9157.3 14081.9 14143.6 14236.9 14391.0 14460.2 9694.2 10584.6 10803.6 11964.3 12046.7 11131.4 11134.0 11365.0 11485.8 11652.9 9715.7 11118.4 11745.6 12132.5 12196.1 7252.4 9035.2 9046.8 9137.7 9273.1 11582.4 11825.6 11879.3 11977.6 12160.3 15:30:12 INFO - PID 1642 | 15:30:12 INFO - PID 1642 | [#3] mootools.html Cycles:180 Average:7107.55 Median:4070.18 stddev:4457.17 (109.5%) stddev-sans-first:4448.92 15:30:12 INFO - PID 1642 | Values: 1383.9 1431.6 1458.2 1470.2 1475.4 11220.4 11526.0 11701.9 11714.6 11811.8 11511.8 11709.0 11724.8 11742.6 11750.8 2504.9 2533.9 2555.7 2556.6 2556.7 2077.5 2475.3 2530.1 2543.3 2550.9 2216.6 2491.9 2500.7 2531.3 2536.7 2118.3 2476.2 2498.4 2516.3 2528.2 1969.5 2392.2 2497.0 2518.8 2534.5 11775.9 11839.8 11859.5 11862.9 11878.3 6591.3 6727.0 6823.5 6846.1 6847.8 4071.1 4412.3 4457.2 4481.4 4485.2 1251.7 1262.8 1275.7 1302.3 1308.2 13731.9 14885.3 15505.7 16218.8 16389.0 5436.3 5550.6 5709.1 5740.5 5745.4 11738.8 11773.5 11801.1 11828.6 11853.3 6486.0 6570.1 6586.8 6588.5 6588.6 6108.0 6118.0 6253.4 6255.9 6299.0 4300.8 4395.2 4421.3 4466.0 4523.8 1255.1 1279.3 1282.6 1292.9 1300.0 798.2 809.8 811.8 835.5 852.0 6157.3 6180.2 6320.9 6410.1 6447.6 590.4 597.3 598.3 904.0 965.1 4069.3 4315.3 4362.3 4366.1 4455.6 14507.1 15134.1 15143.5 15148.6 15155.7 14743.9 14902.4 14981.8 15085.7 15260.0 8573.3 8582.0 8684.3 8710.0 8771.3 6898.8 6966.2 6972.3 6995.3 7097.3 11103.4 13387.5 13420.5 13477.5 13731.9 13840.7 13847.5 13852.9 13991.4 13991.7 9095.9 9403.8 9421.4 9506.9 9518.2 10770.7 10780.2 10855.0 10863.5 11002.4 8837.3 9211.7 9239.2 9288.0 9299.7 9049.4 9077.0 9107.3 9138.0 9160.9 8094.8 8227.7 8244.3 8256.6 8261.7 5085.8 5199.8 5969.3 6069.9 6279.1 5967.7 6134.1 6191.4 6220.7 6239.5 15:30:12 INFO - PID 1642 | 15:30:12 INFO - PID 1642 | [#4] prototype.html Cycles:180 Average:5552.67 Median:6053.29 stddev:1699.51 (28.1%) stddev-sans-first:1658.84 15:30:12 INFO - PID 1642 | Values: 352.0 361.0 400.6 409.2 410.3 5167.2 6102.8 6362.3 6388.2 6394.9 5700.2 5831.9 6300.2 6301.5 6330.4 4562.8 4690.9 4835.8 4872.0 4875.8 4822.5 4878.7 4898.1 4920.1 4939.3 4854.6 4856.3 4868.2 4878.7 4885.8 4068.2 4690.2 4726.0 4801.4 4885.2 4312.3 4320.9 4328.2 4329.2 4353.7 4648.4 5095.4 5468.8 5559.9 6273.5 5145.6 5724.6 5791.5 5804.4 5833.4 5645.4 6857.8 6865.7 6869.5 6880.5 3794.7 4802.9 4864.4 4937.5 4988.1 6062.0 6846.7 7078.4 7184.4 7272.7 4914.8 5079.8 5087.5 5106.5 5113.9 5181.9 5198.0 5200.0 5210.2 5223.7 4722.6 5631.5 5743.7 5764.6 5807.8 6782.6 6882.0 6884.5 6895.0 6911.8 1502.5 1608.2 1682.2 1704.7 1739.7 1015.7 1107.0 1160.3 1164.5 1231.9 6088.8 6433.6 6436.0 6436.6 6513.8 5888.4 6490.8 6533.9 7162.0 7199.2 5610.0 5910.9 6033.8 6391.0 6432.0 3221.9 3426.6 3647.6 3664.2 3693.7 6886.6 7203.9 7248.3 7305.8 7337.6 6130.7 6898.0 7180.9 7244.4 7252.2 6583.3 6960.5 7285.6 7313.1 7366.5 7136.6 7151.5 7155.0 7157.3 7175.5 6695.2 6853.2 6870.4 6933.7 6993.9 5563.5 6044.6 7283.0 7293.3 7306.3 3927.7 4451.4 4488.7 4523.3 4525.0 6818.9 7018.8 7109.3 7116.3 7123.9 6816.4 6848.8 6859.9 6860.1 6892.3 6460.5 6685.4 6757.5 6761.0 6764.9 6598.5 6638.8 6677.3 6682.9 6692.7 6634.6 6720.3 6757.5 6798.5 6827.5 5259.7 5779.6 6481.7 6623.6 6650.7 15:30:12 INFO - PID 1642 | 15:30:12 INFO - PID 1642 | [#5] yui.html Cycles:180 Average:21833.38 Median:17969.96 stddev:31267.77 (174.0%) stddev-sans-first:31315.55 15:30:12 INFO - PID 1642 | Values: 785.9 817.0 838.7 846.7 859.2 16321.3 16785.8 16819.0 18022.3 19162.4 17917.6 18150.3 18444.6 18461.3 18681.5 1000.0 1019.8 1025.6 1025.7 1036.1 974.3 1008.6 1026.0 1030.4 1033.8 1047.4 1053.2 1060.9 1061.4 1063.5 1051.2 1055.6 1058.8 1059.8 1061.3 767.4 769.9 786.1 786.7 789.4 15216.7 16830.4 16986.3 17134.7 17417.0 15199.4 15263.8 15438.2 15500.4 15613.7 11240.0 12809.7 13987.0 14039.9 14060.7 3512.6 3517.3 3544.3 3546.3 3548.4 132748.6 134188.0 136528.0 138387.0 139170.6 10147.7 12244.4 12735.7 13297.1 13317.5 23436.0 25602.3 26332.5 26419.9 26852.9 7481.5 7705.0 7720.0 7726.3 7744.1 3276.3 3824.5 3869.3 3887.7 3915.2 3784.4 3813.7 3840.2 4372.0 4450.3 932.8 950.7 963.0 966.8 974.1 587.3 639.3 672.7 678.6 703.0 10872.4 10933.4 10984.1 12001.5 12004.6 634.5 654.6 671.1 679.9 686.8 5484.4 5535.3 5546.6 5587.3 5681.5 99907.0 100245.0 100398.5 102130.5 102512.0 81914.6 85558.6 86642.6 89843.6 92906.0 81963.5 84437.6 85063.6 85697.6 86654.6 11559.2 12969.4 12995.4 13052.9 13092.5 39374.8 39439.4 40053.6 40536.0 40538.4 42467.8 52593.0 52840.2 52943.0 53668.2 16085.7 16150.4 16657.6 17099.5 17183.7 17377.2 18654.9 18708.6 18909.0 18920.3 14373.8 16581.8 17206.7 17504.6 17634.5 16794.9 16827.3 17509.0 17512.6 17605.5 16020.0 16237.8 16628.8 16803.4 16843.7 9213.0 11723.9 11808.6 12129.3 12166.6 11837.8 12316.5 13007.2 13132.4 14024.8 15:30:12 INFO - PID 1642 | -------- Summary: end -------- 15:30:12 INFO - PID 1642 | 15:30:13 INFO - TEST-INFO | 1642: exit 0 15:30:13 INFO - TEST-OK | dromaeo_css | took 1116225ms 15:30:13 INFO - TEST-START | kraken 15:30:13 INFO - operating with platform_type : mac_ 15:30:13 INFO - Initialising browser for kraken test... 15:30:13 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/tmp59oVu2/profile http://localhost:49241/getInfo.html) 15:30:15 INFO - PID 1657 | 2017-05-05 15:30:15.779 plugin-container[1658:10162] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x953f, name = 'com.apple.tsm.portname' 15:30:15 INFO - PID 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:30:15 INFO - PID 1657 | 2017-05-05 15:30:15.783 plugin-container[1658:10162] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x471f, name = 'com.apple.CFPasteboardClient' 15:30:15 INFO - PID 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:30:15 INFO - PID 1657 | 2017-05-05 15:30:15.783 plugin-container[1658:10162] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:30:15 INFO - PID 1657 | __metrics Screen width/height:1600/1200 15:30:15 INFO - PID 1657 | colorDepth:24 15:30:15 INFO - PID 1657 | Browser inner width/height: 1024/691 15:30:15 INFO - PID 1657 | __metrics 15:30:16 INFO - TEST-INFO | 1657: exit 0 15:30:16 INFO - Browser initialized. 15:30:16 INFO - Running cycle 1/1 for kraken test... 15:30:16 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/tmp59oVu2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:30:19 INFO - PID 1660 | 2017-05-05 15:30:19.362 plugin-container[1661:10321] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 15:30:19 INFO - PID 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:30:19 INFO - PID 1660 | 2017-05-05 15:30:19.363 plugin-container[1661:10321] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0b, name = 'com.apple.CFPasteboardClient' 15:30:19 INFO - PID 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:30:19 INFO - PID 1660 | 2017-05-05 15:30:19.363 plugin-container[1661:10321] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:30:19 INFO - PID 1660 | 2017-05-05 15:30:19.693 plugin-container[1662:10364] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x903f, name = 'com.apple.tsm.portname' 15:30:19 INFO - PID 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:30:19 INFO - PID 1660 | 2017-05-05 15:30:19.693 plugin-container[1662:10364] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x980b, name = 'com.apple.CFPasteboardClient' 15:30:19 INFO - PID 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:30:19 INFO - PID 1660 | 2017-05-05 15:30:19.693 plugin-container[1662:10364] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:30:29 INFO - PID 1660 | RSS: Main: 233361408 15:30:29 INFO - PID 1660 | 15:30:31 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 15:30:32 INFO - PID 1660 | RSS: Main: 245813248 15:30:32 INFO - PID 1660 | 15:30:35 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 15:30:35 INFO - PID 1660 | RSS: Main: 254431232 15:30:35 INFO - PID 1660 | 15:30:39 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 15:30:40 INFO - PID 1660 | RSS: Main: 257126400 15:30:40 INFO - PID 1660 | 15:30:42 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 15:30:42 INFO - PID 1660 | RSS: Main: 258060288 15:30:42 INFO - PID 1660 | 15:30:44 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:30:45 INFO - PID 1660 | RSS: Main: 259788800 15:30:45 INFO - PID 1660 | 15:30:48 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 15:30:49 INFO - PID 1660 | RSS: Main: 266264576 15:30:49 INFO - PID 1660 | 15:30:51 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 15:30:52 INFO - PID 1660 | RSS: Main: 266932224 15:30:52 INFO - PID 1660 | 15:30:55 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 15:30:56 INFO - PID 1660 | RSS: Main: 266489856 15:30:56 INFO - PID 1660 | 15:30:57 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:30:58 INFO - PID 1660 | RSS: Main: 266268672 15:30:58 INFO - PID 1660 | 15:31:00 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:31:00 INFO - PID 1660 | RSS: Main: 266522624 15:31:00 INFO - PID 1660 | 15:31:02 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:31:03 INFO - PID 1660 | RSS: Main: 266031104 15:31:03 INFO - PID 1660 | 15:31:06 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:31:07 INFO - PID 1660 | RSS: Main: 266014720 15:31:07 INFO - PID 1660 | 15:31:10 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:31:11 INFO - PID 1660 | RSS: Main: 263663616 15:31:11 INFO - PID 1660 | 15:31:12 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 15:31:13 INFO - PID 1660 | RSS: Main: 265572352 15:31:13 INFO - PID 1660 | 15:31:13 INFO - PID 1660 | __start_tp_report 15:31:13 INFO - PID 1660 | _x_x_mozilla_page_load 15:31:13 INFO - PID 1660 | _x_x_mozilla_page_load_details 15:31:13 INFO - PID 1660 | |i|pagename|runs| 15:31:13 INFO - PID 1660 | |0;ai-astar;132;168;116;116;115;118;122;137;116;117 15:31:13 INFO - PID 1660 | |1;audio-beat-detection;137;111;134;143;107;104;109;108;147;153 15:31:13 INFO - PID 1660 | |2;audio-dft;276;271;211;215;225;195;197;207;200;193 15:31:13 INFO - PID 1660 | |3;audio-fft;78;61;90;77;49;50;49;92;92;82 15:31:13 INFO - PID 1660 | |4;audio-oscillator;91;96;72;76;56;78;113;107;103;118 15:31:13 INFO - PID 1660 | |5;imaging-gaussian-blur;89;89;88;88;89;89;123;137;131;87 15:31:13 INFO - PID 1660 | |6;imaging-darkroom;98;96;97;97;95;96;97;96;95;97 15:31:13 INFO - PID 1660 | |7;imaging-desaturate;90;92;92;91;92;90;90;91;91;91 15:31:13 INFO - PID 1660 | |8;json-parse-financial;50;48;51;54;69;77;79;67;51;51 15:31:13 INFO - PID 1660 | |9;json-stringify-tinderbox;63;58;59;75;56;55;57;60;58;74 15:31:13 INFO - PID 1660 | |10;stanford-crypto-aes;85;81;84;81;82;83;82;88;81;83 15:31:13 INFO - PID 1660 | |11;stanford-crypto-ccm;156;159;139;138;141;137;147;164;204;137 15:31:13 INFO - PID 1660 | |12;stanford-crypto-pbkdf2;186;187;226;244;226;186;247;235;189;230 15:31:13 INFO - PID 1660 | |13;stanford-crypto-sha256-iterative;68;69;71;73;59;59;92;93;65;90 15:31:13 INFO - PID 1660 | __end_tp_report 15:31:13 INFO - PID 1660 | __start_cc_report 15:31:13 INFO - PID 1660 | _x_x_mozilla_cycle_collect,977 15:31:13 INFO - PID 1660 | __end_cc_report 15:31:13 INFO - PID 1660 | __startTimestamp1494023473186__endTimestamp 15:31:13 INFO - PID 1660 | 15:31:13 INFO - PID 1660 | ------- Summary: start ------- 15:31:13 INFO - PID 1660 | Number of tests: 14 15:31:13 INFO - PID 1660 | 15:31:13 INFO - PID 1660 | [#0] ai-astar Cycles:10 Average:125.70 Median:120.00 stddev:16.67 (13.9%) stddev-sans-first:17.53 15:31:13 INFO - PID 1660 | Values: 132.0 168.0 116.0 116.0 115.0 118.0 122.0 137.0 116.0 117.0 15:31:13 INFO - PID 1660 | 15:31:13 INFO - PID 1660 | [#1] audio-beat-detection Cycles:10 Average:125.30 Median:135.50 stddev:19.21 (14.2%) stddev-sans-first:19.91 15:31:13 INFO - PID 1660 | Values: 137.0 111.0 134.0 143.0 107.0 104.0 109.0 108.0 147.0 153.0 15:31:13 INFO - PID 1660 | 15:31:13 INFO - PID 1660 | [#2] audio-dft Cycles:10 Average:219.00 Median:213.00 stddev:30.39 (14.3%) stddev-sans-first:24.24 15:31:13 INFO - PID 1660 | Values: 276.0 271.0 211.0 215.0 225.0 195.0 197.0 207.0 200.0 193.0 15:31:13 INFO - PID 1660 | 15:31:13 INFO - PID 1660 | [#3] audio-fft Cycles:10 Average:72.00 Median:80.00 stddev:18.10 (22.6%) stddev-sans-first:19.07 15:31:13 INFO - PID 1660 | Values: 78.0 61.0 90.0 77.0 49.0 50.0 49.0 92.0 92.0 82.0 15:31:13 INFO - PID 1660 | 15:31:13 INFO - PID 1660 | [#4] audio-oscillator Cycles:10 Average:91.00 Median:74.00 stddev:20.05 (27.1%) stddev-sans-first:21.27 15:31:13 INFO - PID 1660 | Values: 91.0 96.0 72.0 76.0 56.0 78.0 113.0 107.0 103.0 118.0 15:31:13 INFO - PID 1660 | 15:31:13 INFO - PID 1660 | [#5] imaging-gaussian-blur Cycles:10 Average:101.00 Median:88.50 stddev:20.52 (23.2%) stddev-sans-first:21.30 15:31:13 INFO - PID 1660 | Values: 89.0 89.0 88.0 88.0 89.0 89.0 123.0 137.0 131.0 87.0 15:31:13 INFO - PID 1660 | 15:31:13 INFO - PID 1660 | [#6] imaging-darkroom Cycles:10 Average:96.40 Median:97.00 stddev:0.97 (1.0%) stddev-sans-first:0.83 15:31:13 INFO - PID 1660 | Values: 98.0 96.0 97.0 97.0 95.0 96.0 97.0 96.0 95.0 97.0 15:31:13 INFO - PID 1660 | 15:31:13 INFO - PID 1660 | [#7] imaging-desaturate Cycles:10 Average:91.00 Median:91.00 stddev:0.82 (0.9%) stddev-sans-first:0.78 15:31:13 INFO - PID 1660 | Values: 90.0 92.0 92.0 91.0 92.0 90.0 90.0 91.0 91.0 91.0 15:31:13 INFO - PID 1660 | 15:31:13 INFO - PID 1660 | [#8] json-parse-financial Cycles:10 Average:59.70 Median:60.50 stddev:12.03 (19.9%) stddev-sans-first:12.23 15:31:13 INFO - PID 1660 | Values: 50.0 48.0 51.0 54.0 69.0 77.0 79.0 67.0 51.0 51.0 15:31:13 INFO - PID 1660 | 15:31:13 INFO - PID 1660 | [#9] json-stringify-tinderbox Cycles:10 Average:61.50 Median:59.50 stddev:7.20 (12.1%) stddev-sans-first:7.62 15:31:13 INFO - PID 1660 | Values: 63.0 58.0 59.0 75.0 56.0 55.0 57.0 60.0 58.0 74.0 15:31:13 INFO - PID 1660 | 15:31:13 INFO - PID 1660 | [#10] stanford-crypto-aes Cycles:10 Average:83.00 Median:83.00 stddev:2.21 (2.7%) stddev-sans-first:2.22 15:31:13 INFO - PID 1660 | Values: 85.0 81.0 84.0 81.0 82.0 83.0 82.0 88.0 81.0 83.0 15:31:13 INFO - PID 1660 | 15:31:13 INFO - PID 1660 | [#11] stanford-crypto-ccm Cycles:10 Average:152.20 Median:151.50 stddev:20.75 (13.7%) stddev-sans-first:21.96 15:31:13 INFO - PID 1660 | Values: 156.0 159.0 139.0 138.0 141.0 137.0 147.0 164.0 204.0 137.0 15:31:13 INFO - PID 1660 | 15:31:13 INFO - PID 1660 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:215.60 Median:228.00 stddev:25.54 (11.2%) stddev-sans-first:24.74 15:31:13 INFO - PID 1660 | Values: 186.0 187.0 226.0 244.0 226.0 186.0 247.0 235.0 189.0 230.0 15:31:13 INFO - PID 1660 | 15:31:13 INFO - PID 1660 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.90 Median:72.00 stddev:13.09 (18.2%) stddev-sans-first:13.71 15:31:13 INFO - PID 1660 | Values: 68.0 69.0 71.0 73.0 59.0 59.0 92.0 93.0 65.0 90.0 15:31:13 INFO - PID 1660 | -------- Summary: end -------- 15:31:13 INFO - PID 1660 | 15:31:14 INFO - TEST-INFO | 1660: exit 0 15:31:14 INFO - TEST-OK | kraken | took 61177ms 15:31:14 INFO - SUITE-END | took 1177s 15:31:14 INFO - Completed test suite (00:19:37) 15:31:14 INFO - javascript benchmark 15:31:14 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7985.501312742916, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3123.422166899123, 3625.927481450369, 3675.3751862183426, 3684.428913518406, 3695.796731179784, 6160.81517554473, 6253.812385628433, 6294.559380843337, 6324.241091069072, 6392.041193820929, 6227.719752611132, 6238.688065596713, 6263.843403914904, 6280.466160376373, 6306.653134077624, 4477.6417886569, 5481.397046324921, 5486.698231597261, 5505.889882202354, 5522.392536820947, 5532.921080389326, 5598.664080155204, 5603.467670571289, 5644.520215781664, 5675.148727690838, 5398.217258497496, 5543.695096769676, 5574.080276754331, 5613.578981576377, 5630.802921897734, 5487.396386397493, 5498.835034948958, 5546.362168350651, 5567.9420910026965, 5609.158626206102, 5519.806806761784, 5536.307961504812, 5542.722863856791, 5566.387697353288, 5574.080276754331, 6058.636481811105, 6295.244570651551, 6320.968395158041, 6321.74713011479, 6335.176427064452, 6542.378474044958, 6642.601443913332, 6711.932880671228, 6779.932200677979, 6782.694778734919, 6897.793066208022, 6899.655017249117, 6900.413464855494, 6906.06768086307, 6923.0, 6343.492520598341, 6369.0764839099, 6388.55280130391, 6425.807225783234, 6449.32282110381, 6713.832154196087, 6838.384545390939, 6889.862202756017, 6974.163100427981, 7077.964610176916, 6366.427021568081, 6450.548461607643, 6493.058506516529, 6566.868662626721, 6620.337966203341, 6780.694868730919, 6798.150231221098, 6831.555948863308, 6837.316268373223, 6895.931040689629, 5667.80162694312, 6763.492738044666, 6783.59298442095, 6791.59250444965, 6795.490338224653, 6004.579679422399, 6110.528382738247, 6573.934260657332, 6632.2372927114, 6800.455963523005, 4759.428868535798, 5374.435684253175, 5415.539679127318, 5434.809781657623, 5443.455654434525, 3673.632636736305, 4598.333241679938, 4770.76146192689, 4812.205986012339, 4839.588634966068, 5355.732213389315, 6332.4934005279465, 6386.712597933104, 6454.483641308684, 6459.612423254526, 6674.532782705164, 6784.253732089466, 6847.281035491202, 6860.725570977205, 6919.515633905578, 5436.592255580847, 6379.362063793584, 6423.614583124934, 6445.033245013192, 6456.451201647913, 5988.850278742979, 6061.636301821904, 6105.419985101408, 6105.664188469588, 6114.9910264806695, 6836.0, 6929.89605155913, 7016.298370162943, 7119.074520312326, 7155.356017958305, 6923.965380173268, 6927.168739751262, 6930.549514281555, 6960.199577048705, 6964.199117101598, 7045.084139061889, 7050.153981522045, 7122.7507037253445, 7141.571505709674, 7164.247753985965, 6583.473322134122, 6826.556273842184, 6840.931590684029, 6846.760363387258, 6861.725530978705, 6753.054572359972, 6858.554193977376, 6880.552764070319, 6906.482013848881, 6947.23580406165, 6443.387878151568, 6704.262531121671, 6714.597124172566, 6717.361850624183, 6733.124693789776, 6106.06632622643, 6449.96775016122, 6696.866062678621, 6708.563943343667, 6737.69680364375, 6274.215723034621, 6435.066915297162, 6537.738490460329, 6581.638009909309, 6582.440492557995, 6471.805845824632, 6489.156409666713, 6581.901271481028, 6617.569857959217, 6655.633940133339, 6503.0, 6542.672866356568, 6545.541812073109, 6564.573302735307, 6565.573237739532], "value": 6181.294787784087, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [748.7416841189792, 793.8174219929416, 822.387321445523, 1209.6371088673402, 1269.5873841001676, 34537.65462345376, 39252.0, 39255.80372098139, 39315.60684393155, 39429.60570394299, 39070.60929390705, 39466.21067578647, 39480.40779388312, 39503.209935801264, 39519.802400988025, 7843.843123137548, 7983.3214176795045, 8128.1465446128195, 8384.706535271267, 8404.537750423724, 8470.66117355305, 8557.48655080694, 8602.48385096893, 8604.913950860473, 8617.827643447159, 8500.404971651988, 8556.48661080334, 8607.010193827697, 8630.827383452328, 8631.784205394884, 8494.108118647515, 8565.143485651417, 8612.354073444516, 8622.698205562838, 8625.741227763147, 8274.2966847818, 8337.541435221061, 8393.580320983987, 8399.28606068485, 8407.579621018924, 37811.24377512462, 37865.81067094647, 37959.62040379588, 38001.429978550346, 38062.61937380646, 3144.591203143577, 3642.7814331139953, 3686.3733165361955, 3688.1517251032146, 3689.760165589228, 31086.68913310883, 31185.376292474022, 31188.064358069296, 31272.687273127205, 31359.05922822319, 3113.2372568720807, 3156.779025468218, 3159.0364938693688, 3166.62000559932, 3168.5405616185753, 240864.0, 245080.0, 271435.0, 289169.0, 290605.09394906205, 48365.0, 48379.516204838204, 48464.757676212095, 48615.0, 48622.513774862506, 40721.0, 50853.745731271105, 51019.0, 51079.23381149286, 51159.23261151086, 11950.462229199533, 14340.354684039243, 14481.710365792625, 14492.710145797026, 14493.92753036228, 11736.295822250693, 11758.470868810924, 11789.88210117905, 11793.941030294794, 11803.468843902045, 13672.589822305346, 13764.31178441074, 13782.310884455737, 13830.893528517896, 13954.232517211447, 6135.662538560333, 6176.8764624707255, 6186.19579454668, 6188.381161883865, 6194.34959329273, 889.4663202078831, 996.7607774134156, 1019.5921631347372, 1080.2708171984036, 1082.3397727386289, 23736.050557977833, 24753.752462475506, 24871.87564062168, 24955.126570569944, 25369.492610148063, 910.2808781063019, 1029.341221618165, 1056.9577216911237, 1130.2879186112696, 1169.6198735411026, 12653.114282000355, 12721.427535760913, 12766.0, 12797.424115914806, 12830.358482075859, 280546.3890722215, 280907.0, 308113.91886080854, 308506.0, 309670.8713933587, 54383.45616543784, 54432.0, 54731.0, 54746.0, 54902.0, 1497.6480527076128, 1515.9848401515844, 1550.9612259693597, 1561.6642421879124, 1572.842715728441, 21925.0, 24135.637965430884, 24229.51540969206, 24321.027158913446, 24384.878075609508, 38959.026024349616, 39001.804990974866, 39102.60897391103, 39112.80443597764, 39120.0, 244511.0, 246335.0, 246381.0, 247011.0, 248795.0, 34581.30837383188, 34841.0, 35671.64328356787, 35776.926692199275, 35907.640923591476, 44555.0, 45340.0, 46848.297275541576, 46880.59358219259, 46886.76556617195, 30756.692433075383, 30762.538561922036, 31397.0, 31992.68007319897, 32139.678603214605, 31560.210994725316, 32276.35447291088, 32433.351332972736, 32490.350192995535, 32532.67467325391, 36716.26567468582, 36988.44517332188, 37881.81059094687, 38303.0, 38429.61570384372, 29841.25396865096, 31557.526637100003, 31651.892183773456, 32082.358352832347, 32084.839575801972, 23319.300420987398, 24459.38851528726, 25077.74922250827, 25085.99656013739, 25491.617625736], "value": 17610.40798092962, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [339.09799932180397, 399.9620983547693, 421.79964516854517, 424.68361071002107, 444.488837836488, 21074.051667674954, 21236.36290911271, 21593.244236451726, 21948.561028779415, 22119.66820497692, 22704.545909081808, 23029.848507574596, 23216.3035108947, 23740.0503979841, 23805.0, 5536.889262214753, 5600.551955843523, 6203.410675985774, 6249.937500624981, 6258.186435763344, 5907.261592300963, 6063.484603808682, 6079.544034197431, 6107.389261073901, 6175.258968923736, 6109.35851735568, 6131.908021379683, 6141.877162456748, 6144.170536977495, 6145.354737752538, 5433.320834895638, 5863.501602363783, 5865.120231965197, 6064.302605200414, 6115.0, 5332.813351532678, 5414.35027796663, 5438.211459338413, 5446.618736688433, 5452.781888724447, 28324.29189270264, 28363.72363243659, 28399.0, 28602.284942876595, 28616.570751438747, 2236.5974124657555, 2300.1719381022817, 2302.4013756423283, 2304.3547806614174, 2306.630939049761, 18102.728459073125, 18241.0, 18456.815431845644, 18759.0, 18886.0, 2114.851960362775, 2156.6225910465605, 2188.9890550547148, 2191.0468946008377, 2196.5716685246384, 153933.2303338476, 156251.0, 156367.21816390846, 157853.0, 159450.83844130914, 12630.179038362478, 13345.399457024458, 13363.0, 13378.46486140563, 13386.866131338757, 16418.83581164197, 16748.0, 16760.994340339617, 16797.832021679627, 16813.243404046847, 8763.079876612992, 9100.590473428712, 9106.817863642822, 9185.402948808307, 9190.310726695525, 8505.362097842686, 8537.74386768398, 8569.571521423904, 8683.956580217058, 8738.781530461662, 6536.7712130075215, 8090.635921383552, 8212.17878212186, 8256.628451719687, 8425.662973481114, 2119.290037837311, 2168.0894024509744, 2177.2162021672184, 2177.5644871025856, 2179.2045903245203, 873.7247766953517, 1014.6505148152938, 1019.7858449725641, 1036.036486067949, 1066.328213225663, 19532.414027579194, 21651.242206522697, 22031.779682202974, 22139.667904981437, 22172.667409988862, 939.8073394954018, 1027.8098551767946, 1057.3338796558273, 1073.9140868730483, 1078.789636020984, 10816.729581760519, 11205.04757095656, 11212.55149793999, 11288.153388495895, 11338.489767960296, 495565.0, 504974.0, 506439.0, 522237.0, 552533.0, 115644.8435515634, 118255.0, 135629.3218533901, 138443.0, 139409.0, 1027.4041056187393, 1227.0796902323257, 1250.7185883176394, 1258.5657948007922, 1263.4946021591254, 22397.552048959256, 23352.299431017098, 23517.529649407257, 23683.763162368155, 23905.402364941016, 58742.11886821616, 58872.0, 59487.70256148691, 60436.697816510634, 61148.38851611427, 71493.0, 80151.59924200342, 84567.5771621138, 85387.0, 86717.56641217005, 8359.746038094092, 9041.954790226007, 9090.181883630241, 9150.725478235663, 9157.267418606629, 14081.929590351983, 14143.64640883986, 14236.857631423552, 14391.0, 14460.20468874222, 9694.22446204288, 10584.629537966135, 10803.649543807023, 11964.282143071443, 12046.698832528906, 11131.443427828413, 11134.0, 11365.020415905623, 11485.770284594428, 11652.883471165518, 9715.68236233018, 11118.444077796239, 11745.647630571097, 12132.454039568063, 12196.146269761031, 7252.41980641558, 9035.232005279624, 9046.819063618823, 9137.732835026278, 9273.119053689888, 11582.42087895585, 11825.645230643095, 11879.287242765462, 11977.580784672495, 12160.270383777002], "value": 10579.666413631936, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1383.9238841863696, 1431.6134643646217, 1458.1918421157754, 1470.1913947328967, 1475.3950880139141, 11220.438978051096, 11525.999999999978, 11701.882981170196, 11714.589989350374, 11811.822822657667, 11511.769764604704, 11709.0, 11724.765504689902, 11742.647720568397, 11750.764984700301, 2504.874756262189, 2533.898644054236, 2555.6933168019864, 2556.6292887531295, 2556.7443255674375, 2077.480629842539, 2475.269795410349, 2530.0891678995545, 2543.300592337113, 2550.9489810203786, 2216.612092883739, 2491.8504889706587, 2500.6874140732407, 2531.2659328794625, 2536.7336429674897, 2118.300960682971, 2476.182859656318, 2498.375406148472, 2516.295437277547, 2528.1657053172503, 1969.4780883065998, 2392.222527678494, 2496.95006099877, 2518.7733104020544, 2534.4677617700304, 11775.882241177564, 11839.763204735944, 11859.52561897523, 11862.88137118635, 11878.287302761863, 6591.340865913418, 6726.966365168192, 6823.454123670144, 6846.144231971004, 6847.828804279883, 4071.066243534327, 4412.338149277602, 4457.1977044132045, 4481.35020422037, 4485.237509623339, 1251.743392604514, 1262.7853264944981, 1275.6810797300675, 1302.3488255872064, 1308.1823860087445, 13731.931340343333, 14885.33016014282, 15505.689886202326, 16218.75671864923, 16389.0, 5436.320459942507, 5550.557754587969, 5709.115087161496, 5740.502881246671, 5745.396733343023, 11738.823917641243, 11773.470193841298, 11801.055915526736, 11828.585999489977, 11853.34806585646, 6486.0, 6570.113034740249, 6586.769463068866, 6588.53880228389, 6588.571742836701, 6107.969460152671, 6117.96941015292, 6253.37466253371, 6255.937440625627, 6299.0, 4300.849470268526, 4395.1868904252815, 4421.336799480104, 4465.977670111629, 4523.751193684379, 1255.12768625806, 1279.3475327582814, 1282.5959822655834, 1292.8577856435784, 1299.9805002924963, 798.2217338095427, 809.8137428391503, 811.7727036429691, 835.4778263585259, 852.032942610142, 6157.261128664589, 6180.165677633462, 6320.873582528324, 6410.134631824644, 6447.645379504079, 590.3889474393982, 597.265363602771, 598.2940130645793, 903.9864402033975, 965.0928127560071, 4069.2878746219294, 4315.266404711207, 4362.323839804835, 4366.126774645084, 4455.6212721918355, 14507.114075170372, 15134.091954482767, 15143.545693629208, 15148.560886715744, 15155.696886062216, 14743.852561474463, 14902.403903843724, 14981.775273370908, 15085.698286034218, 15260.0, 8573.27127194183, 8582.0, 8684.34867384936, 8710.041895391501, 8771.342149338698, 6898.758543450955, 6966.1988871280455, 6972.302769722987, 6995.300469952964, 7097.254788247366, 11103.445517627377, 13387.531436399679, 13420.46318147263, 13477.528286510318, 13731.931340343235, 13840.653983650489, 13847.515336963157, 13852.89176865828, 13991.440342386191, 13991.650208744863, 9095.909040909506, 9403.764905877408, 9421.38760980534, 9506.904930950603, 9518.23854091685, 10770.676879693621, 10780.245382823126, 10855.0, 10863.456827158769, 11002.449877506253, 8837.337199710177, 9211.723648290294, 9239.168474837297, 9288.024757400375, 9299.72100836949, 9049.366544341832, 9077.04691007461, 9107.27141828639, 9138.040505746802, 9160.946491153603, 8094.83810323802, 8227.712030079158, 8244.340452763645, 8256.587170641324, 8261.710840120568, 5085.77114029877, 5199.818006369759, 5969.313528944227, 6069.939300606938, 6279.120923070683, 5967.731452084753, 6134.079888016655, 6191.442770150708, 6220.72006759706, 6239.469645080219], "value": 5363.015193726745, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [351.9771214871034, 360.9873654422096, 400.5933977013332, 409.23268870867145, 410.2779108768567, 5167.224916262562, 6102.816915492543, 6362.268339141, 6388.201474815648, 6394.936050639498, 5700.230468886699, 5831.900515814314, 6300.180976473052, 6301.495880329574, 6330.430261276484, 4562.779429028153, 4690.90618187636, 4835.830745923876, 4871.951280487186, 4875.756212189394, 4822.4554952110175, 4878.731669758162, 4898.069366820293, 4920.114379411704, 4939.28380384844, 4854.635902307324, 4856.2958371036175, 4868.196747536652, 4878.658493905428, 4885.755712214375, 4068.206699693561, 4690.202665546848, 4725.976370118162, 4801.44783349914, 4885.236894028294, 4312.33158860374, 4320.935185972213, 4328.177646247234, 4329.19909816685, 4353.695241333108, 4648.4427716714945, 5095.388553373582, 5468.7539060742365, 5559.8888022239325, 6273.498120150377, 5145.6398052136365, 5724.5992780505385, 5791.536677065825, 5804.419558044204, 5833.3666573359105, 5645.435456454322, 6857.794266172023, 6865.691043903036, 6869.450443964521, 6880.518363714542, 3794.6774524165203, 4802.903941921142, 4864.367632207825, 4937.506249375069, 4988.102141614508, 6062.0, 6846.6649003444345, 7078.362947334765, 7184.38932690722, 7272.672729727175, 4914.827981020647, 5079.81567925788, 5087.491250874883, 5106.45525482321, 5113.923291150636, 5181.948180518147, 5197.974010129925, 5199.960007998416, 5210.21846722987, 5223.738813059332, 4722.6221902247735, 5631.549476041989, 5743.712814359265, 5764.596478246566, 5807.767689292466, 6782.626955517494, 6881.965590172117, 6884.48366372514, 6894.965525172342, 6911.827204319932, 1502.4591147187004, 1608.2119761317103, 1682.1757338904094, 1704.6931552320702, 1739.686856365841, 1015.7054454208345, 1106.9944650276698, 1160.2864238493266, 1164.4512797978837, 1231.926084434919, 6088.786892458743, 6433.625134908214, 6436.0, 6436.616127532603, 6513.827511047962, 5888.381719919433, 6490.837729056811, 6533.941808330361, 7161.9641901790155, 7199.244079371695, 5610.0, 5910.940890591039, 6033.763078568971, 6391.0, 6432.0, 3221.935561288761, 3426.6059403168956, 3647.571282993977, 3664.1755604988666, 3693.686036686912, 6886.552374095668, 7203.855922881513, 7248.318122294785, 7305.817354566072, 7337.559746415232, 6130.693465326805, 6897.9655101725175, 7180.886962520817, 7244.420446364278, 7252.202257751643, 6583.275839657634, 6960.547564408297, 7285.562866228043, 7313.085864266966, 7366.484346095722, 7136.574444266677, 7151.463640227108, 7154.964225179049, 7157.320054595013, 7175.457276685538, 6695.163104611924, 6853.17761868579, 6870.381665650116, 6933.687984040629, 6993.895091573732, 5563.527100196528, 6044.576879618385, 7283.016792732913, 7293.286077771825, 7306.305900939402, 3927.7054220932973, 4451.376807247053, 4488.730676159441, 4523.253663145544, 4524.9547504524535, 6818.897716534157, 7018.789436316919, 7109.324614161646, 7116.323949224816, 7123.893141602776, 6816.420604248496, 6848.77414840169, 6859.862802744017, 6860.108185935996, 6892.345227203407, 6460.483161347175, 6685.39831415175, 6757.459403247827, 6761.0, 6764.898526522008, 6598.505112116514, 6638.767643132467, 6677.2988836173445, 6682.9331706682315, 6692.732290708317, 6634.568753031038, 6720.260771315249, 6757.493188011017, 6798.490113241428, 6827.487938404739, 5259.710715910662, 5779.624324418899, 6481.70365926826, 6623.569467984566, 6650.667466626746], "value": 5018.956034456207, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [785.8624740670382, 816.9795755106126, 838.6519594368334, 846.6613354658136, 859.1923591823684, 16321.265543050562, 16785.832141678595, 16819.0, 18022.279108835664, 19162.425127246206, 17917.641647167082, 18150.273989040423, 18444.631107377845, 18461.261549538, 18681.532961675966, 999.9700308682051, 1019.8266294729914, 1025.5590096258607, 1025.6922923123034, 1036.1400037968467, 974.3228456222928, 1008.6268080810111, 1026.0150255754484, 1030.361176070837, 1033.81908166071, 1047.4291511126416, 1053.2048303530823, 1060.946952652368, 1061.4056128567988, 1063.4735805776152, 1051.1905832508965, 1055.6041484443372, 1058.8041212375736, 1059.7615536504302, 1061.3260579531975, 767.4129291092319, 769.9461037727361, 786.1116937860181, 786.7207141464755, 789.4276649429175, 15216.695666086727, 16830.410935617194, 16986.320547178097, 17134.742978855327, 17417.04206268655, 15199.392024319013, 15263.847361526354, 15438.150901700403, 15500.379984800595, 15613.68772624541, 11240.0, 12809.743805123846, 13987.020908536406, 14039.929800351036, 14060.718785624333, 3512.5609298837644, 3517.3492903812876, 3544.2734239480847, 3546.308469848381, 3548.3790336690977, 132748.58750030978, 134188.0, 136528.0, 138387.0, 139170.60829391677, 10147.69556913279, 12244.387780610934, 12735.681607959876, 13297.06920515574, 13317.467301308034, 23436.0, 25602.287668287994, 26332.473350533266, 26419.867900660374, 26852.865735671196, 7481.513701609487, 7704.9614751927, 7719.961400192963, 7726.304632583095, 7744.070711514654, 3276.2955964467756, 3824.4836947011795, 3869.342211823997, 3887.688984881203, 3915.1778126593163, 3784.3566593679143, 3813.7139714521522, 3840.231953609289, 4371.978140109279, 4450.310201918708, 932.7528205025674, 950.7432993091965, 962.956666949989, 966.7534778631403, 974.1281553010016, 587.2512546503157, 639.3159319528061, 672.653583404547, 678.5860625018735, 702.9648517574203, 10872.402017888933, 10933.398663073607, 10984.121270298518, 12001.519939202508, 12004.579839705568, 634.4733870887153, 654.5712558274338, 671.0739179931761, 679.9116114905031, 686.8042607856737, 5484.424135465719, 5535.335759708861, 5546.611737178439, 5587.329520457572, 5681.460261275172, 99907.00092999122, 100245.0, 100398.49402258972, 102130.46804297941, 102512.0, 81914.59042704749, 85558.57220713857, 86642.56678716566, 89843.55078224698, 92906.0, 81963.54109376729, 84437.57781211054, 85063.5746821262, 85697.5726820491, 86654.56672716596, 11559.248648837798, 12969.35153242353, 12995.350232488148, 13052.869471305165, 13092.541761038317, 39374.80312598419, 39439.408408873314, 40053.59946400499, 40536.0, 40538.39192412057, 42467.7876610615, 52593.0, 52840.207396888305, 52943.0, 53668.19497707616, 16085.678286433971, 16150.434734784227, 16657.583560411087, 17099.487015389557, 17183.656326873144, 17377.21802518915, 18654.906725466284, 18708.625827483647, 18909.0, 18920.33778817735, 14373.784393234318, 16581.834181658513, 17206.655866882844, 17504.649907001534, 17634.470965871045, 16794.916025419796, 16827.326906923587, 17509.0, 17512.64974700524, 17605.471835844946, 16020.0, 16237.756433653742, 16628.83371166273, 16803.41188058412, 16843.663126737643, 9213.0, 11723.941380293043, 11808.645740627795, 12129.332886691316, 12166.635000949986, 11837.76324473523, 12316.507339706312, 13007.219566826021, 13132.40904159296, 14024.789628155382], "value": 8364.5149903166, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1567.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 168.0, 116.0, 116.0, 115.0, 118.0, 122.0, 137.0, 116.0, 117.0], "value": 125.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 111.0, 134.0, 143.0, 107.0, 104.0, 109.0, 108.0, 147.0, 153.0], "value": 125.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [276.0, 271.0, 211.0, 215.0, 225.0, 195.0, 197.0, 207.0, 200.0, 193.0], "value": 219.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 61.0, 90.0, 77.0, 49.0, 50.0, 49.0, 92.0, 92.0, 82.0], "value": 72.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 96.0, 72.0, 76.0, 56.0, 78.0, 113.0, 107.0, 103.0, 118.0], "value": 91.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 89.0, 88.0, 88.0, 89.0, 89.0, 123.0, 137.0, 131.0, 87.0], "value": 101.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 96.0, 97.0, 97.0, 95.0, 96.0, 97.0, 96.0, 95.0, 97.0], "value": 96.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 92.0, 92.0, 91.0, 92.0, 90.0, 90.0, 91.0, 91.0, 91.0], "value": 91.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 48.0, 51.0, 54.0, 69.0, 77.0, 79.0, 67.0, 51.0, 51.0], "value": 59.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 58.0, 59.0, 75.0, 56.0, 55.0, 57.0, 60.0, 58.0, 74.0], "value": 61.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 81.0, 84.0, 81.0, 82.0, 83.0, 82.0, 88.0, 81.0, 83.0], "value": 83.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 159.0, 139.0, 138.0, 141.0, 137.0, 147.0, 164.0, 204.0, 137.0], "value": 152.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 187.0, 226.0, 244.0, 226.0, 186.0, 247.0, 235.0, 189.0, 230.0], "value": 215.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 71.0, 73.0, 59.0, 59.0, 92.0, 93.0, 65.0, 90.0], "value": 73.9, "unit": "score"}]}]} 15:31:14 INFO - Return code: 0 15:31:14 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:31:14 INFO - # TBPL SUCCESS # 15:31:14 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:31:14 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:31:14 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:31:14 INFO - 2017-05-05 15:31:14.536 screenresolution[1667:10757] starting screenresolution argv=screenresolution get 15:31:14 INFO - 2017-05-05 15:31:14.538 screenresolution[1667:10757] Display 0: 1600x1200x32@60 15:31:14 INFO - 2017-05-05 15:31:14.545 screenresolution[1668:10760] starting screenresolution argv=screenresolution list 15:31:14 INFO - Available Modes on Display 0 15:31:14 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:31:14 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:31:14 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:31:14 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:31:14 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:31:14 INFO - Intel Iris: 15:31:14 INFO - Chipset Model: Intel Iris 15:31:14 INFO - Type: GPU 15:31:14 INFO - Bus: Built-In 15:31:14 INFO - VRAM (Dynamic, Max): 1536 MB 15:31:14 INFO - Vendor: Intel (0x8086) 15:31:14 INFO - Device ID: 0x0a2e 15:31:14 INFO - Revision ID: 0x0009 15:31:14 INFO - Displays: 15:31:14 INFO - Display: 15:31:14 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:31:14 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:31:14 INFO - Display Serial Number: 1600x1200 60 15:31:14 INFO - Main Display: Yes 15:31:14 INFO - Mirror: Off 15:31:14 INFO - Online: Yes 15:31:14 INFO - Rotation: Supported 15:31:14 INFO - Return code: 0 15:31:14 INFO - Running post-action listener: _resource_record_post_action 15:31:14 INFO - [mozharness: 2017-05-05 22:31:14.793212Z] Finished run-tests step (success) 15:31:14 INFO - Running post-run listener: _resource_record_post_run 15:31:14 INFO - Total resource usage - Wall time: 1198s; CPU: 54.0%; Read bytes: 39615488; Write bytes: 510575104; Read time: 254; Write time: 1614 15:31:14 INFO - TinderboxPrint: CPU usage
54.0% 15:31:14 INFO - TinderboxPrint: I/O read bytes / time
39,615,488 / 254 15:31:14 INFO - TinderboxPrint: I/O write bytes / time
510,575,104 / 1,614 15:31:14 INFO - TinderboxPrint: CPU idle
2,203.7 (46.0%) 15:31:14 INFO - TinderboxPrint: CPU system
960.1 (20.0%) 15:31:14 INFO - TinderboxPrint: CPU user
1,629.9 (34.0%) 15:31:14 INFO - TinderboxPrint: Swap in / out
248,401,920 / 0 15:31:14 INFO - install - Wall time: 20s; CPU: 55.0%; Read bytes: 205840896; Write bytes: 205043200; Read time: 15734; Write time: 440 15:31:14 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 37514240; Write bytes: 298458112; Read time: 235; Write time: 1146 15:31:15 INFO - Running post-run listener: _upload_blobber_files 15:31:15 INFO - Blob upload gear active. 15:31:15 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:31:15 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:31:15 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:31:15 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:31:15 INFO - (blobuploader) - INFO - Open directory for files ... 15:31:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 15:31:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:31:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:31:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 15:31:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:31:16 INFO - (blobuploader) - INFO - Done attempting. 15:31:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 15:31:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:31:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:31:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 15:31:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:31:17 INFO - (blobuploader) - INFO - Done attempting. 15:31:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 15:31:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:31:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:31:19 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 15:31:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:31:19 INFO - (blobuploader) - INFO - Done attempting. 15:31:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:31:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:31:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:31:21 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:31:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:31:21 INFO - (blobuploader) - INFO - Done attempting. 15:31:21 INFO - (blobuploader) - INFO - Iteration through files over. 15:31:21 INFO - Return code: 0 15:31:21 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:31:21 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:31:21 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/990dbb6a994ca7d902083485d7086aca70ec6be84807fe286ddb3b378e55f44917d26bc09f32280f436be04b8b68dd11de77af3f4d28c2a1197fc9e7236927e9", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/985c19cf92d021d993e98366d9b6cae3bee636906a01733a131414543ecfa6a25d5568d49069a798367712285312d8bf837800abedd2509716c7cb227f95c779", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/75f7c82cbeef51ca2babc105e45cff593ca37c023fbc0a8c69ad39ed7387ae59190af7cf34043420d7496e7c041e41bb962fab5eee318868247b4906e3e6a4e6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/78e8312cbb6a32ab621dd70621b84b9f091a5de5d95c0eaee74cbbbdf5e29d147dce81dbd671d64d43bc63440bb3fa31023e6f6797d62ddd147807011ba0c530"} 15:31:21 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:31:21 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:31:21 INFO - Contents: 15:31:21 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/990dbb6a994ca7d902083485d7086aca70ec6be84807fe286ddb3b378e55f44917d26bc09f32280f436be04b8b68dd11de77af3f4d28c2a1197fc9e7236927e9", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/985c19cf92d021d993e98366d9b6cae3bee636906a01733a131414543ecfa6a25d5568d49069a798367712285312d8bf837800abedd2509716c7cb227f95c779", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/75f7c82cbeef51ca2babc105e45cff593ca37c023fbc0a8c69ad39ed7387ae59190af7cf34043420d7496e7c041e41bb962fab5eee318868247b4906e3e6a4e6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/78e8312cbb6a32ab621dd70621b84b9f091a5de5d95c0eaee74cbbbdf5e29d147dce81dbd671d64d43bc63440bb3fa31023e6f6797d62ddd147807011ba0c530"} 15:31:21 INFO - Running post-run listener: copy_logs_to_upload_dir 15:31:21 INFO - Copying logs to upload dir... 15:31:21 INFO - mkdir: /builds/slave/test/build/upload/logs 15:31:21 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.140910 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2017-05-05 15:31:21.499659) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-05 15:31:21.503979) ========= 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.aP2cAJEs3V/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.nqCHbmpe1T/Listeners TMPDIR=/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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/990dbb6a994ca7d902083485d7086aca70ec6be84807fe286ddb3b378e55f44917d26bc09f32280f436be04b8b68dd11de77af3f4d28c2a1197fc9e7236927e9", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/985c19cf92d021d993e98366d9b6cae3bee636906a01733a131414543ecfa6a25d5568d49069a798367712285312d8bf837800abedd2509716c7cb227f95c779", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/75f7c82cbeef51ca2babc105e45cff593ca37c023fbc0a8c69ad39ed7387ae59190af7cf34043420d7496e7c041e41bb962fab5eee318868247b4906e3e6a4e6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/78e8312cbb6a32ab621dd70621b84b9f091a5de5d95c0eaee74cbbbdf5e29d147dce81dbd671d64d43bc63440bb3fa31023e6f6797d62ddd147807011ba0c530"} build_url:https://queue.taskcluster.net/v1/task/V6_FvUlOT2GkS8FQv7CHWA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011154 build_url: 'https://queue.taskcluster.net/v1/task/V6_FvUlOT2GkS8FQv7CHWA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/990dbb6a994ca7d902083485d7086aca70ec6be84807fe286ddb3b378e55f44917d26bc09f32280f436be04b8b68dd11de77af3f4d28c2a1197fc9e7236927e9", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/985c19cf92d021d993e98366d9b6cae3bee636906a01733a131414543ecfa6a25d5568d49069a798367712285312d8bf837800abedd2509716c7cb227f95c779", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/75f7c82cbeef51ca2babc105e45cff593ca37c023fbc0a8c69ad39ed7387ae59190af7cf34043420d7496e7c041e41bb962fab5eee318868247b4906e3e6a4e6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/78e8312cbb6a32ab621dd70621b84b9f091a5de5d95c0eaee74cbbbdf5e29d147dce81dbd671d64d43bc63440bb3fa31023e6f6797d62ddd147807011ba0c530"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-05 15:31:21.543500) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-05 15:31:21.546902) ========= 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.aP2cAJEs3V/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.nqCHbmpe1T/Listeners TMPDIR=/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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.005172 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-05 15:31:21.595300) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-05 15:31:21.595624) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-05 15:31:21.595948) ========= ========= Total master_lag: 0.21 =========