builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0083 starttime: 1483385868.09 results: success (0) buildid: 20170102105519 builduid: d4292c10bf62428e91113b25c1cc8505 revision: c91249f41e3766274131a84f9157a4d9d9949520 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-02 11:37:48.088666) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-02 11:37:48.089138) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-02 11:37:48.089458) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-02 11:37:48.118242) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-02 11:37:48.118690) ========= 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.CIafmlgHF2/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.mlBm1gz2N1/Listeners TMPDIR=/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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-01-02 11:37:48-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 15.4M=0.001s 2017-01-02 11:37:48 (15.4 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.168234 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-02 11:37:48.311596) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-02 11:37:48.311939) ========= 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.CIafmlgHF2/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.mlBm1gz2N1/Listeners TMPDIR=/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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.026731 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-02 11:37:48.362572) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-02 11:37:48.362968) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev c91249f41e3766274131a84f9157a4d9d9949520 --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 c91249f41e3766274131a84f9157a4d9d9949520 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.CIafmlgHF2/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.mlBm1gz2N1/Listeners TMPDIR=/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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-01-02 11:37:48,455 truncating revision to first 12 chars 2017-01-02 11:37:48,455 Setting DEBUG logging. 2017-01-02 11:37:48,455 attempt 1/10 2017-01-02 11:37:48,455 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/c91249f41e37?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-02 11:37:48,759 unpacking tar archive at: mozilla-central-c91249f41e37/testing/mozharness/ program finished with exit code 0 elapsedTime=0.583253 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-02 11:37:48.970726) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-02 11:37:48.971139) ========= script_repo_revision: c91249f41e3766274131a84f9157a4d9d9949520 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-02 11:37:48.971694) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-02 11:37:48.972060) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-02 11:37:48.997150) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2017-01-02 11:37:48.997520) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.CIafmlgHF2/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.mlBm1gz2N1/Listeners TMPDIR=/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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 11:37:49 INFO - MultiFileLogger online at 20170102 11:37:49 in /builds/slave/test 11:37:49 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 11:37:49 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:37:49 INFO - {'append_to_log': False, 11:37:49 INFO - 'base_work_dir': '/builds/slave/test', 11:37:49 INFO - 'blob_upload_branch': 'Firefox', 11:37:49 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:37:49 INFO - 'branch': 'Firefox', 11:37:49 INFO - 'buildbot_json_path': 'buildprops.json', 11:37:49 INFO - 'config_files': ('talos/mac_config.py',), 11:37:49 INFO - 'default_actions': ('clobber', 11:37:49 INFO - 'read-buildbot-config', 11:37:49 INFO - 'download-and-extract', 11:37:49 INFO - 'populate-webroot', 11:37:49 INFO - 'create-virtualenv', 11:37:49 INFO - 'install', 11:37:49 INFO - 'run-tests'), 11:37:49 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:37:49 INFO - 'download_minidump_stackwalk': True, 11:37:49 INFO - 'download_symbols': 'ondemand', 11:37:49 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:37:49 INFO - 'tooltool.py': '/tools/tooltool.py', 11:37:49 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:37:49 INFO - '/tools/misc-python/virtualenv.py')}, 11:37:49 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:37:49 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:37:49 INFO - 'installer_path': 'installer.exe', 11:37:49 INFO - 'log_level': 'info', 11:37:49 INFO - 'log_name': 'talos', 11:37:49 INFO - 'log_to_console': True, 11:37:49 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:37:49 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:37:49 INFO - 'opt_config_files': (), 11:37:49 INFO - 'pip_index': False, 11:37:49 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:37:49 INFO - 'cmd': ('bash', 11:37:49 INFO - '-c', 11:37:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:37:49 INFO - 'enabled': True, 11:37:49 INFO - 'halt_on_failure': False, 11:37:49 INFO - 'name': 'check_screen_resolution'},), 11:37:49 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:37:49 INFO - 'cmd': ('bash', 11:37:49 INFO - '-c', 11:37:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:37:49 INFO - 'enabled': True, 11:37:49 INFO - 'halt_on_failure': False, 11:37:49 INFO - 'name': 'check_screen_resolution'},), 11:37:49 INFO - 'run_cmd_checks_enabled': True, 11:37:49 INFO - 'sps_profile': False, 11:37:49 INFO - 'sps_profile_interval': 0, 11:37:49 INFO - 'suite': 'dromaeojs', 11:37:49 INFO - 'system_bits': '32', 11:37:49 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:37:49 INFO - 'title': 't-yosemite-r7-0083', 11:37:49 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:37:49 INFO - 'use_talos_json': True, 11:37:49 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:37:49 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:37:49 INFO - 'work_dir': 'build'} 11:37:49 INFO - [mozharness: 2017-01-02 19:37:49.201715Z] Running clobber step. 11:37:49 INFO - Running pre-action listener: _resource_record_pre_action 11:37:49 INFO - Running main action method: clobber 11:37:49 INFO - rmtree: /builds/slave/test/build 11:37:49 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:37:51 INFO - Running post-action listener: _resource_record_post_action 11:37:51 INFO - [mozharness: 2017-01-02 19:37:51.278160Z] Finished clobber step (success) 11:37:51 INFO - [mozharness: 2017-01-02 19:37:51.278295Z] Running read-buildbot-config step. 11:37:51 INFO - Running pre-action listener: _resource_record_pre_action 11:37:51 INFO - Running main action method: read_buildbot_config 11:37:51 INFO - Using buildbot properties: 11:37:51 INFO - { 11:37:51 INFO - "project": "", 11:37:51 INFO - "product": "firefox", 11:37:51 INFO - "script_repo_revision": "production", 11:37:51 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 11:37:51 INFO - "repository": "", 11:37:51 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 11:37:51 INFO - "buildid": "20170102105519", 11:37:51 INFO - "pgo_build": "False", 11:37:51 INFO - "basedir": "/builds/slave/test", 11:37:51 INFO - "buildnumber": 166, 11:37:51 INFO - "slavename": "t-yosemite-r7-0083", 11:37:51 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 11:37:51 INFO - "platform": "yosemite_r7", 11:37:51 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 11:37:51 INFO - "branch": "mozilla-central", 11:37:51 INFO - "repo_path": "mozilla-central", 11:37:51 INFO - "revision": "c91249f41e3766274131a84f9157a4d9d9949520", 11:37:51 INFO - "stage_platform": "macosx64", 11:37:51 INFO - "builduid": "d4292c10bf62428e91113b25c1cc8505", 11:37:51 INFO - "slavebuilddir": "test" 11:37:51 INFO - } 11:37:51 INFO - Found installer url https://queue.taskcluster.net/v1/task/LjayOvF_SJeQaMuQReZttw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 11:37:51 INFO - Running post-action listener: _resource_record_post_action 11:37:51 INFO - [mozharness: 2017-01-02 19:37:51.281748Z] Finished read-buildbot-config step (success) 11:37:51 INFO - [mozharness: 2017-01-02 19:37:51.281853Z] Running download-and-extract step. 11:37:51 INFO - Running pre-action listener: _resource_record_pre_action 11:37:51 INFO - Running main action method: download_and_extract 11:37:51 INFO - mkdir: /builds/slave/test/build/tests 11:37:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:37:51 INFO - trying https://queue.taskcluster.net/v1/task/LjayOvF_SJeQaMuQReZttw/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 11:37:51 INFO - Downloading https://queue.taskcluster.net/v1/task/LjayOvF_SJeQaMuQReZttw/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 11:37:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LjayOvF_SJeQaMuQReZttw/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 11:37:52 INFO - Downloaded 1199 bytes. 11:37:52 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 11:37:52 INFO - Using the following test package requirements: 11:37:52 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 11:37:52 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 11:37:52 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 11:37:52 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 11:37:52 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 11:37:52 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 11:37:52 INFO - u'jsshell-mac.zip'], 11:37:52 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 11:37:52 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 11:37:52 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 11:37:52 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 11:37:52 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 11:37:52 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 11:37:52 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 11:37:52 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 11:37:52 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 11:37:52 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 11:37:52 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 11:37:52 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 11:37:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LjayOvF_SJeQaMuQReZttw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 11:37:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LjayOvF_SJeQaMuQReZttw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 11:37:52 INFO - Fetch https://queue.taskcluster.net/v1/task/LjayOvF_SJeQaMuQReZttw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 11:37:53 INFO - Expected file size: 16300420 11:37:53 INFO - Obtained file size: 16300420 11:37:59 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 11:37:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LjayOvF_SJeQaMuQReZttw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 11:37:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LjayOvF_SJeQaMuQReZttw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 11:37:59 INFO - Fetch https://queue.taskcluster.net/v1/task/LjayOvF_SJeQaMuQReZttw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 11:38:00 INFO - Expected file size: 16300420 11:38:00 INFO - Obtained file size: 16300420 11:38:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LjayOvF_SJeQaMuQReZttw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 11:38:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LjayOvF_SJeQaMuQReZttw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 11:38:07 INFO - Fetch https://queue.taskcluster.net/v1/task/LjayOvF_SJeQaMuQReZttw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 11:38:08 INFO - Expected file size: 14409776 11:38:08 INFO - Obtained file size: 14409776 11:38: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')]} 11:38:08 INFO - trying https://queue.taskcluster.net/v1/task/LjayOvF_SJeQaMuQReZttw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 11:38:08 INFO - Downloading https://queue.taskcluster.net/v1/task/LjayOvF_SJeQaMuQReZttw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 11:38:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LjayOvF_SJeQaMuQReZttw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 11:38:10 INFO - Downloaded 69759480 bytes. 11:38:10 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LjayOvF_SJeQaMuQReZttw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 11:38:10 INFO - mkdir: /builds/slave/test/properties 11:38:10 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:38:10 INFO - Writing to file /builds/slave/test/properties/build_url 11:38:10 INFO - Contents: 11:38:10 INFO - build_url:https://queue.taskcluster.net/v1/task/LjayOvF_SJeQaMuQReZttw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 11:38:11 INFO - Running post-action listener: _resource_record_post_action 11:38:11 INFO - Running post-action listener: set_extra_try_arguments 11:38:11 INFO - [mozharness: 2017-01-02 19:38:11.454190Z] Finished download-and-extract step (success) 11:38:11 INFO - [mozharness: 2017-01-02 19:38:11.454345Z] Running populate-webroot step. 11:38:11 INFO - Running pre-action listener: _resource_record_pre_action 11:38:11 INFO - Running main action method: populate_webroot 11:38:11 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:38:11 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:38:11 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:38:11 INFO - '--tppagecycles', 11:38:11 INFO - '7'], 11:38:11 INFO - 'tests': ['tsvgm']}}, 11:38:11 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:38:11 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:38:11 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:38:11 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:38:11 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:38:11 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:38:11 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 11:38:11 INFO - 'talos_options': ['--disable-e10s'], 11:38:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:38:11 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:38:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:38:11 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 11:38:11 INFO - 'talos_options': ['--disable-e10s'], 11:38:11 INFO - 'tests': ['damp', 'tps']}, 11:38:11 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:38:11 INFO - 'tests': ['damp', 'tps']}, 11:38:11 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:38:11 INFO - 'tests': ['dromaeo_dom']}, 11:38:11 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:38:11 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:38:11 INFO - 'tests': ['basic_compositor_video']}, 11:38:11 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:38:11 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:38:11 INFO - 'tests': ['a11yr', 11:38:11 INFO - 'ts_paint', 11:38:11 INFO - 'tpaint', 11:38:11 INFO - 'sessionrestore', 11:38:11 INFO - 'sessionrestore_no_auto_restore', 11:38:11 INFO - 'tabpaint']}, 11:38:11 INFO - 'other-e10s': {'tests': ['a11yr', 11:38:11 INFO - 'ts_paint', 11:38:11 INFO - 'tpaint', 11:38:11 INFO - 'sessionrestore', 11:38:11 INFO - 'sessionrestore_no_auto_restore', 11:38:11 INFO - 'tabpaint']}, 11:38:11 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:38:11 INFO - 'ts_paint', 11:38:11 INFO - 'tpaint', 11:38:11 INFO - 'sessionrestore', 11:38:11 INFO - 'sessionrestore_no_auto_restore', 11:38:11 INFO - 'tabpaint']}, 11:38:11 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:38:11 INFO - 'ts_paint', 11:38:11 INFO - 'tpaint', 11:38:11 INFO - 'sessionrestore', 11:38:11 INFO - 'sessionrestore_no_auto_restore', 11:38:11 INFO - 'tabpaint']}, 11:38:11 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:38:11 INFO - 'tests': ['a11yr', 11:38:11 INFO - 'ts_paint', 11:38:11 INFO - 'tpaint', 11:38:11 INFO - 'sessionrestore', 11:38:11 INFO - 'sessionrestore_no_auto_restore', 11:38:11 INFO - 'tabpaint']}, 11:38:11 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:38:11 INFO - 'tests': ['a11yr', 11:38:11 INFO - 'ts_paint', 11:38:11 INFO - 'tpaint', 11:38:11 INFO - 'sessionrestore', 11:38:11 INFO - 'sessionrestore_no_auto_restore', 11:38:11 INFO - 'tabpaint']}, 11:38:11 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:38:11 INFO - 'tests': ['tsvgx', 11:38:11 INFO - 'tsvgr_opacity', 11:38:11 INFO - 'tart', 11:38:11 INFO - 'tscrollx', 11:38:11 INFO - 'cart', 11:38:11 INFO - 'tsvg_static']}, 11:38:11 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:38:11 INFO - 'tsvgr_opacity', 11:38:11 INFO - 'tart', 11:38:11 INFO - 'tscrollx', 11:38:11 INFO - 'cart', 11:38:11 INFO - 'tsvg_static']}, 11:38:11 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 11:38:11 INFO - 'talos_options': ['--disable-e10s'], 11:38:11 INFO - 'tests': ['tp5o']}, 11:38:11 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:38:11 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 11:38:11 INFO - 'talos_options': ['--disable-e10s', 11:38:11 INFO - '--xperf_path', 11:38:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:38:11 INFO - 'tests': ['tp5n']}, 11:38:11 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:38:11 INFO - 'talos_options': ['--xperf_path', 11:38:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:38:11 INFO - 'tests': ['tp5n']}}, 11:38:11 INFO - 'talos.zip': {'path': '', 11:38:11 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:38:11 INFO - Running post-action listener: _resource_record_post_action 11:38:11 INFO - [mozharness: 2017-01-02 19:38:11.472936Z] Finished populate-webroot step (success) 11:38:11 INFO - [mozharness: 2017-01-02 19:38:11.473043Z] Running create-virtualenv step. 11:38:11 INFO - Running pre-action listener: _resource_record_pre_action 11:38:11 INFO - Running main action method: create_virtualenv 11:38:11 INFO - Creating virtualenv /builds/slave/test/build/venv 11:38:11 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 11:38:11 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:38:11 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:38:11 INFO - Using real prefix '/tools/python27' 11:38:11 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:38:12 INFO - Installing distribute.............................................................................................................................................................................................done. 11:38:15 INFO - Installing pip.................done. 11:38:15 INFO - Return code: 0 11:38:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:38:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:38:15 INFO - Reading from file tmpfile_stdout 11:38:15 INFO - Output received: 11:38:15 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) 11:38:15 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:38: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')]} 11:38:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:38:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:38: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')]} 11:38:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:38:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:38: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x110520b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110523768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd32a5c6d40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1102fed30>, '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 0x110225db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1102f3be8>, '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.CIafmlgHF2/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.mlBm1gz2N1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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 11:38: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', 'pip>=1.5'] in /builds/slave/test/build 11:38: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 pip>=1.5 11:38:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CIafmlgHF2/Render', 11:38:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:38:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:38:15 INFO - 'HOME': '/Users/cltbld', 11:38:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:38:15 INFO - 'LOGNAME': 'cltbld', 11:38:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:38:15 INFO - 'MOZ_NO_REMOTE': '1', 11:38:15 INFO - 'NO_EM_RESTART': '1', 11:38:15 INFO - 'PAGER': '/bin/cat', 11:38:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:38:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:38:15 INFO - 'PWD': '/builds/slave/test', 11:38:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:38:15 INFO - 'SHELL': '/bin/bash', 11:38:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mlBm1gz2N1/Listeners', 11:38:15 INFO - 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/', 11:38:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:38:15 INFO - 'USER': 'cltbld', 11:38:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:38:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:38:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:38:15 INFO - 'XPC_FLAGS': '0x0', 11:38:15 INFO - 'XPC_SERVICE_NAME': '0', 11:38:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:38:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:38:15 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 11:38:15 INFO - Cleaning up... 11:38:15 INFO - Return code: 0 11:38:15 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:38: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')]} 11:38:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:38:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:38: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')]} 11:38:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:38:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:38: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', '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 0x110520b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110523768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd32a5c6d40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1102fed30>, '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 0x110225db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1102f3be8>, '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.CIafmlgHF2/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.mlBm1gz2N1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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 11:38: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', 'psutil>=3.1.1'] in /builds/slave/test/build 11:38: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 psutil>=3.1.1 11:38:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CIafmlgHF2/Render', 11:38:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:38:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:38:15 INFO - 'HOME': '/Users/cltbld', 11:38:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:38:15 INFO - 'LOGNAME': 'cltbld', 11:38:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:38:15 INFO - 'MOZ_NO_REMOTE': '1', 11:38:15 INFO - 'NO_EM_RESTART': '1', 11:38:15 INFO - 'PAGER': '/bin/cat', 11:38:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:38:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:38:15 INFO - 'PWD': '/builds/slave/test', 11:38:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:38:15 INFO - 'SHELL': '/bin/bash', 11:38:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mlBm1gz2N1/Listeners', 11:38:15 INFO - 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/', 11:38:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:38:15 INFO - 'USER': 'cltbld', 11:38:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:38:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:38:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:38:15 INFO - 'XPC_FLAGS': '0x0', 11:38:15 INFO - 'XPC_SERVICE_NAME': '0', 11:38:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:38:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:38:15 INFO - Downloading/unpacking psutil>=3.1.1 11:38:15 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 11:38:15 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 11:38:15 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 11:38:15 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 11:38:15 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 11:38:15 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 11:38:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:38:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:38:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:38:20 INFO - Installing collected packages: psutil 11:38:20 INFO - Running setup.py install for psutil 11:38:20 INFO - building 'psutil._psutil_osx' extension 11:38:20 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 11:38:20 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 11:38:20 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 11:38:21 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 11:38:21 INFO - building 'psutil._psutil_posix' extension 11:38:21 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 11:38:21 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:38:21 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:38:21 INFO - ^ 11:38:21 INFO - 1 warning generated. 11:38:21 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 11:38:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:38:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:38:21 INFO - Successfully installed psutil 11:38:21 INFO - Cleaning up... 11:38:21 INFO - Return code: 0 11:38:21 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:38:21 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')]} 11:38:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:38:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:38:21 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')]} 11:38:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:38:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:38:21 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 0x110520b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110523768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd32a5c6d40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1102fed30>, '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 0x110225db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1102f3be8>, '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.CIafmlgHF2/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.mlBm1gz2N1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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 11:38:21 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 11:38:21 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 11:38:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CIafmlgHF2/Render', 11:38:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:38:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:38:21 INFO - 'HOME': '/Users/cltbld', 11:38:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:38:21 INFO - 'LOGNAME': 'cltbld', 11:38:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:38:21 INFO - 'MOZ_NO_REMOTE': '1', 11:38:21 INFO - 'NO_EM_RESTART': '1', 11:38:21 INFO - 'PAGER': '/bin/cat', 11:38:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:38:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:38:21 INFO - 'PWD': '/builds/slave/test', 11:38:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:38:21 INFO - 'SHELL': '/bin/bash', 11:38:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mlBm1gz2N1/Listeners', 11:38:21 INFO - 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/', 11:38:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:38:21 INFO - 'USER': 'cltbld', 11:38:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:38:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:38:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:38:21 INFO - 'XPC_FLAGS': '0x0', 11:38:21 INFO - 'XPC_SERVICE_NAME': '0', 11:38:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:38:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:38:21 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:38:21 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 11:38:21 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 11:38:21 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 11:38:21 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 11:38:21 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 11:38:21 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 11:38:24 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:38:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:38:24 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:38:24 INFO - Installing collected packages: mozsystemmonitor 11:38:24 INFO - Running setup.py install for mozsystemmonitor 11:38:24 INFO - Successfully installed mozsystemmonitor 11:38:24 INFO - Cleaning up... 11:38:24 INFO - Return code: 0 11:38:24 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:38:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:38:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:38:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:38:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:38:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:38:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:38:24 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 0x110520b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110523768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd32a5c6d40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1102fed30>, '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 0x110225db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1102f3be8>, '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.CIafmlgHF2/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.mlBm1gz2N1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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 11:38:24 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 11:38:24 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 11:38:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CIafmlgHF2/Render', 11:38:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:38:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:38:24 INFO - 'HOME': '/Users/cltbld', 11:38:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:38:24 INFO - 'LOGNAME': 'cltbld', 11:38:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:38:24 INFO - 'MOZ_NO_REMOTE': '1', 11:38:24 INFO - 'NO_EM_RESTART': '1', 11:38:24 INFO - 'PAGER': '/bin/cat', 11:38:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:38:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:38:24 INFO - 'PWD': '/builds/slave/test', 11:38:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:38:24 INFO - 'SHELL': '/bin/bash', 11:38:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mlBm1gz2N1/Listeners', 11:38:24 INFO - 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/', 11:38:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:38:24 INFO - 'USER': 'cltbld', 11:38:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:38:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:38:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:38:24 INFO - 'XPC_FLAGS': '0x0', 11:38:24 INFO - 'XPC_SERVICE_NAME': '0', 11:38:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:38:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:38:24 INFO - Downloading/unpacking jsonschema==2.5.1 11:38:24 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 11:38:24 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 11:38:24 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 11:38:24 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 11:38:24 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 11:38:24 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 11:38:27 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:38:27 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:38:27 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 11:38:27 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 11:38:27 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 11:38:27 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 11:38:27 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 11:38:27 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 11:38:27 INFO - Downloading functools32-3.2.3-2.tar.gz 11:38:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:38:28 INFO - warning: no files found matching '*.txt' 11:38:28 INFO - no previously-included directories found matching 'build' 11:38:28 INFO - no previously-included directories found matching 'dist' 11:38:28 INFO - no previously-included directories found matching '.git*' 11:38:28 INFO - Installing collected packages: jsonschema, functools32 11:38:28 INFO - Running setup.py install for functools32 11:38:28 INFO - warning: no files found matching '*.txt' 11:38:28 INFO - no previously-included directories found matching 'build' 11:38:28 INFO - no previously-included directories found matching 'dist' 11:38:28 INFO - no previously-included directories found matching '.git*' 11:38:28 INFO - Successfully installed jsonschema functools32 11:38:28 INFO - Cleaning up... 11:38:28 INFO - Return code: 0 11:38:28 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:38:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:38:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:38:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:38:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:38:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:38:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:38:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x110520b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110523768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd32a5c6d40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1102fed30>, '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 0x110225db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1102f3be8>, '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.CIafmlgHF2/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.mlBm1gz2N1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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 11:38:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 11:38:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 11:38:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CIafmlgHF2/Render', 11:38:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:38:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:38:28 INFO - 'HOME': '/Users/cltbld', 11:38:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:38:28 INFO - 'LOGNAME': 'cltbld', 11:38:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:38:28 INFO - 'MOZ_NO_REMOTE': '1', 11:38:28 INFO - 'NO_EM_RESTART': '1', 11:38:28 INFO - 'PAGER': '/bin/cat', 11:38:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:38:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:38:28 INFO - 'PWD': '/builds/slave/test', 11:38:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:38:28 INFO - 'SHELL': '/bin/bash', 11:38:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mlBm1gz2N1/Listeners', 11:38:28 INFO - 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/', 11:38:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:38:28 INFO - 'USER': 'cltbld', 11:38:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:38:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:38:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:38:28 INFO - 'XPC_FLAGS': '0x0', 11:38:28 INFO - 'XPC_SERVICE_NAME': '0', 11:38:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:38:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:38:28 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:38:28 INFO - Cleaning up... 11:38:28 INFO - Return code: 0 11:38:28 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:38:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:38:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:38:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:38:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:38:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:38:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:38:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x110520b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110523768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd32a5c6d40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1102fed30>, '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 0x110225db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1102f3be8>, '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.CIafmlgHF2/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.mlBm1gz2N1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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 11:38:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 11:38:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 11:38:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CIafmlgHF2/Render', 11:38:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:38:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:38:28 INFO - 'HOME': '/Users/cltbld', 11:38:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:38:28 INFO - 'LOGNAME': 'cltbld', 11:38:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:38:28 INFO - 'MOZ_NO_REMOTE': '1', 11:38:28 INFO - 'NO_EM_RESTART': '1', 11:38:28 INFO - 'PAGER': '/bin/cat', 11:38:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:38:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:38:28 INFO - 'PWD': '/builds/slave/test', 11:38:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:38:28 INFO - 'SHELL': '/bin/bash', 11:38:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mlBm1gz2N1/Listeners', 11:38:28 INFO - 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/', 11:38:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:38:28 INFO - 'USER': 'cltbld', 11:38:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:38:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:38:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:38:28 INFO - 'XPC_FLAGS': '0x0', 11:38:28 INFO - 'XPC_SERVICE_NAME': '0', 11:38:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:38:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:38:28 INFO - Downloading/unpacking blobuploader==1.2.4 11:38:28 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 11:38:28 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 11:38:28 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 11:38:28 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 11:38:28 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 11:38:28 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 11:38:31 INFO - Downloading blobuploader-1.2.4.tar.gz 11:38:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:38:31 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:38:31 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 11:38:31 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 11:38:31 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 11:38:31 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 11:38:31 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 11:38:31 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 11:38:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:38:32 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:38:32 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 11:38:32 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 11:38:32 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 11:38:32 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 11:38:32 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 11:38:32 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 11:38:32 INFO - Downloading docopt-0.6.1.tar.gz 11:38:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:38:32 INFO - Installing collected packages: blobuploader, requests, docopt 11:38:32 INFO - Running setup.py install for blobuploader 11:38:32 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:38:32 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:38:32 INFO - Running setup.py install for requests 11:38:33 INFO - Running setup.py install for docopt 11:38:33 INFO - Successfully installed blobuploader requests docopt 11:38:33 INFO - Cleaning up... 11:38:33 INFO - Return code: 0 11:38:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:38:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:38:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:38:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:38:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:38:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:38:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:38:33 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 0x110520b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110523768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd32a5c6d40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1102fed30>, '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 0x110225db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1102f3be8>, '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.CIafmlgHF2/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.mlBm1gz2N1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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 11:38:33 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 11:38:33 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 11:38:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CIafmlgHF2/Render', 11:38:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:38:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:38:33 INFO - 'HOME': '/Users/cltbld', 11:38:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:38:33 INFO - 'LOGNAME': 'cltbld', 11:38:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:38:33 INFO - 'MOZ_NO_REMOTE': '1', 11:38:33 INFO - 'NO_EM_RESTART': '1', 11:38:33 INFO - 'PAGER': '/bin/cat', 11:38:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:38:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:38:33 INFO - 'PWD': '/builds/slave/test', 11:38:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:38:33 INFO - 'SHELL': '/bin/bash', 11:38:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mlBm1gz2N1/Listeners', 11:38:33 INFO - 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/', 11:38:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:38:33 INFO - 'USER': 'cltbld', 11:38:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:38:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:38:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:38:33 INFO - 'XPC_FLAGS': '0x0', 11:38:33 INFO - 'XPC_SERVICE_NAME': '0', 11:38:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:38:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:38:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:38:33 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-Y8uO6s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:38:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:38:34 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-0gKEL9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:38:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:38:34 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-ZFjiN5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:38:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:38:34 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-JjuT6w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:38:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:38:34 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-mekX6_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:38:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:38:34 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-G7fFci-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:38:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:38:34 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-_o72j1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:38:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:38:34 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-1R2nm3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:38:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:38:34 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-6qen5i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:38:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:38:34 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-OPq1XD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:38:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:38:35 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-I8mkpD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:38:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:38:35 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-xhlL03-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:38:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:38:35 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-7GzpbX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:38:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:38:35 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-M64iru-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:38:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:38:35 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-07MOq7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:38:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:38:35 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-KnHwqr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:38:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:38:35 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-R84OTL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:38:35 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:38:35 INFO - Running setup.py install for manifestparser 11:38:35 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:38:35 INFO - Running setup.py install for mozcrash 11:38:36 INFO - Running setup.py install for mozdebug 11:38:36 INFO - Running setup.py install for mozdevice 11:38:36 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:38:36 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:38:36 INFO - Running setup.py install for mozfile 11:38:36 INFO - Running setup.py install for mozhttpd 11:38:36 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:38:36 INFO - Running setup.py install for mozinfo 11:38:36 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:38:36 INFO - Running setup.py install for mozInstall 11:38:36 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:38:36 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:38:36 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:38:36 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:38:36 INFO - Running setup.py install for mozleak 11:38:37 INFO - Running setup.py install for mozlog 11:38:37 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:38:37 INFO - Running setup.py install for moznetwork 11:38:37 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:38:37 INFO - Running setup.py install for mozprocess 11:38:37 INFO - Running setup.py install for mozprofile 11:38:37 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:38:37 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:38:37 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:38:37 INFO - Running setup.py install for mozrunner 11:38:37 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:38:37 INFO - Running setup.py install for mozscreenshot 11:38:38 INFO - Running setup.py install for moztest 11:38:38 INFO - Running setup.py install for mozversion 11:38:38 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:38:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:38:38 INFO - Cleaning up... 11:38:38 INFO - Return code: 0 11:38:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:38:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:38:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:38:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:38:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:38:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:38:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:38:38 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 0x110520b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110523768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd32a5c6d40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1102fed30>, '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 0x110225db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1102f3be8>, '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.CIafmlgHF2/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.mlBm1gz2N1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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 11:38:38 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 11:38:38 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 11:38:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CIafmlgHF2/Render', 11:38:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:38:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:38:38 INFO - 'HOME': '/Users/cltbld', 11:38:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:38:38 INFO - 'LOGNAME': 'cltbld', 11:38:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:38:38 INFO - 'MOZ_NO_REMOTE': '1', 11:38:38 INFO - 'NO_EM_RESTART': '1', 11:38:38 INFO - 'PAGER': '/bin/cat', 11:38:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:38:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:38:38 INFO - 'PWD': '/builds/slave/test', 11:38:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:38:38 INFO - 'SHELL': '/bin/bash', 11:38:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mlBm1gz2N1/Listeners', 11:38:38 INFO - 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/', 11:38:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:38:38 INFO - 'USER': 'cltbld', 11:38:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:38:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:38:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:38:38 INFO - 'XPC_FLAGS': '0x0', 11:38:38 INFO - 'XPC_SERVICE_NAME': '0', 11:38:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:38:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:38:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:38:38 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-qI4AhJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:38:38 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)) 11:38:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:38:38 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-CtjEPm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:38:38 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)) 11:38:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:38:38 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-bGThNb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:38:38 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)) 11:38:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:38:39 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-mYbQ1m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:38:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:38:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:38:39 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-Repw1u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:38:39 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)) 11:38:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:38:39 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-RDX1Hc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:38:39 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)) 11:38:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:38:39 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-1KkgIw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:38:39 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)) 11:38:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:38:39 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-464kK2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:38:39 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)) 11:38:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:38:39 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-FLTikf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:38:39 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)) 11:38:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:38:39 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-T7WedR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:38:39 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)) 11:38:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:38:39 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-3F6bYl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:38:39 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)) 11:38:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:38:39 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-i1H6C6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:38:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 11:38:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:38:39 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-UuxwgI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:38:40 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)) 11:38:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:38:40 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-73bMNu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:38:40 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)) 11:38:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:38:40 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-ckUurF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:38:40 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)) 11:38:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:38:40 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-MJA2S4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:38:40 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)) 11:38:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:38:40 INFO - Running setup.py (path:/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/pip-5WaIMh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:38:40 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)) 11:38:40 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)) 11:38:40 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)) 11:38:40 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)) 11:38:40 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:38:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:38:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:38:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:38:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:38:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:38:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:38:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:38:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:38:43 INFO - Downloading blessings-1.6.tar.gz 11:38:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:38:43 INFO - Installing collected packages: blessings 11:38:43 INFO - Running setup.py install for blessings 11:38:43 INFO - Successfully installed blessings 11:38:43 INFO - Cleaning up... 11:38:43 INFO - Return code: 0 11:38:43 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:38:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:38:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:38:43 INFO - Reading from file tmpfile_stdout 11:38:43 INFO - Current package versions: 11:38:43 INFO - blessings == 1.6 11:38:43 INFO - blobuploader == 1.2.4 11:38:43 INFO - docopt == 0.6.1 11:38:43 INFO - functools32 == 3.2.3-2 11:38:43 INFO - jsonschema == 2.5.1 11:38:43 INFO - manifestparser == 1.1 11:38:43 INFO - mozInstall == 1.12 11:38:43 INFO - mozcrash == 1.0 11:38:43 INFO - mozdebug == 0.1 11:38:43 INFO - mozdevice == 0.49 11:38:43 INFO - mozfile == 1.2 11:38:43 INFO - mozhttpd == 0.7 11:38:43 INFO - mozinfo == 0.9 11:38:43 INFO - mozleak == 0.1 11:38:43 INFO - mozlog == 3.4 11:38:43 INFO - moznetwork == 0.27 11:38:43 INFO - mozprocess == 0.23 11:38:43 INFO - mozprofile == 0.28 11:38:43 INFO - mozrunner == 6.13 11:38:43 INFO - mozscreenshot == 0.1 11:38:43 INFO - mozsystemmonitor == 0.3 11:38:43 INFO - moztest == 0.8 11:38:43 INFO - mozversion == 1.4 11:38:43 INFO - psutil == 3.1.1 11:38:43 INFO - requests == 1.2.3 11:38:43 INFO - wsgiref == 0.1.2 11:38:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:38:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:38:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:38:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:38:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:38:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:38:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:38:43 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 0x110520b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110523768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd32a5c6d40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1102fed30>, '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 0x110225db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1102f3be8>, '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.CIafmlgHF2/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.mlBm1gz2N1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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 11:38:43 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 11:38:43 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 11:38:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CIafmlgHF2/Render', 11:38:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:38:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:38:43 INFO - 'HOME': '/Users/cltbld', 11:38:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:38:43 INFO - 'LOGNAME': 'cltbld', 11:38:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:38:43 INFO - 'MOZ_NO_REMOTE': '1', 11:38:43 INFO - 'NO_EM_RESTART': '1', 11:38:43 INFO - 'PAGER': '/bin/cat', 11:38:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:38:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:38:43 INFO - 'PWD': '/builds/slave/test', 11:38:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:38:43 INFO - 'SHELL': '/bin/bash', 11:38:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mlBm1gz2N1/Listeners', 11:38:43 INFO - 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/', 11:38:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:38:43 INFO - 'USER': 'cltbld', 11:38:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:38:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:38:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:38:43 INFO - 'XPC_FLAGS': '0x0', 11:38:43 INFO - 'XPC_SERVICE_NAME': '0', 11:38:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:38:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:38:44 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)) 11:38:44 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)) 11:38:44 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)) 11:38:44 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)) 11:38:44 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)) 11:38:44 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)) 11:38:44 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)) 11:38:44 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)) 11:38:44 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)) 11:38:44 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)) 11:38:44 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)) 11:38:44 INFO - Cleaning up... 11:38:44 INFO - Return code: 0 11:38:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:38:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:38:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:38:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:38:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:38:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:38:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:38:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x110520b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110523768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd32a5c6d40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1102fed30>, '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 0x110225db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1102f3be8>, '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.CIafmlgHF2/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.mlBm1gz2N1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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 11:38:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 11:38:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 11:38:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CIafmlgHF2/Render', 11:38:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:38:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:38:44 INFO - 'HOME': '/Users/cltbld', 11:38:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:38:44 INFO - 'LOGNAME': 'cltbld', 11:38:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:38:44 INFO - 'MOZ_NO_REMOTE': '1', 11:38:44 INFO - 'NO_EM_RESTART': '1', 11:38:44 INFO - 'PAGER': '/bin/cat', 11:38:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:38:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:38:44 INFO - 'PWD': '/builds/slave/test', 11:38:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:38:44 INFO - 'SHELL': '/bin/bash', 11:38:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mlBm1gz2N1/Listeners', 11:38:44 INFO - 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/', 11:38:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:38:44 INFO - 'USER': 'cltbld', 11:38:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:38:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:38:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:38:44 INFO - 'XPC_FLAGS': '0x0', 11:38:44 INFO - 'XPC_SERVICE_NAME': '0', 11:38:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:38:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:38:44 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:38:44 INFO - Cleaning up... 11:38:44 INFO - Return code: 0 11:38:44 INFO - Running post-action listener: _resource_record_post_action 11:38:44 INFO - Running post-action listener: _start_resource_monitoring 11:38:44 INFO - Starting resource monitoring. 11:38:44 INFO - [mozharness: 2017-01-02 19:38:44.351749Z] Finished create-virtualenv step (success) 11:38:44 INFO - [mozharness: 2017-01-02 19:38:44.352769Z] Running install step. 11:38:44 INFO - Running pre-action listener: _resource_record_pre_action 11:38:44 INFO - Running main action method: install 11:38:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:38:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:38:44 INFO - Reading from file tmpfile_stdout 11:38:44 INFO - Detecting whether we're running mozinstall >=1.0... 11:38:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:38:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:38:44 INFO - Reading from file tmpfile_stdout 11:38:44 INFO - Output received: 11:38:44 INFO - Usage: mozinstall [options] installer 11:38:44 INFO - Options: 11:38:44 INFO - -h, --help show this help message and exit 11:38:44 INFO - -d DEST, --destination=DEST 11:38:44 INFO - Directory to install application into. [default: 11:38:44 INFO - "/builds/slave/test"] 11:38:44 INFO - --app=APP Application being installed. [default: firefox] 11:38:44 INFO - mkdir: /builds/slave/test/build/application 11:38:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 11:38:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 11:39:06 INFO - Reading from file tmpfile_stdout 11:39:06 INFO - Output received: 11:39:06 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 11:39:06 INFO - Running post-action listener: _resource_record_post_action 11:39:06 INFO - [mozharness: 2017-01-02 19:39:06.579085Z] Finished install step (success) 11:39:06 INFO - [mozharness: 2017-01-02 19:39:06.579253Z] Running run-tests step. 11:39:06 INFO - Running pre-action listener: _resource_record_pre_action 11:39:06 INFO - Running main action method: run_tests 11:39:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:39:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:39:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:39:06 INFO - 2017-01-02 11:39:06.593 screenresolution[1633:9163] starting screenresolution argv=screenresolution get 11:39:06 INFO - 2017-01-02 11:39:06.596 screenresolution[1633:9163] Display 0: 1600x1200x32@60 11:39:06 INFO - 2017-01-02 11:39:06.602 screenresolution[1634:9166] starting screenresolution argv=screenresolution list 11:39:06 INFO - Available Modes on Display 0 11:39:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:39:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:39:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:39:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:39:06 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:39:06 INFO - Intel Iris: 11:39:06 INFO - Chipset Model: Intel Iris 11:39:06 INFO - Type: GPU 11:39:06 INFO - Bus: Built-In 11:39:06 INFO - VRAM (Dynamic, Max): 1536 MB 11:39:06 INFO - Vendor: Intel (0x8086) 11:39:06 INFO - Device ID: 0x0a2e 11:39:06 INFO - Revision ID: 0x0009 11:39:06 INFO - Displays: 11:39:06 INFO - Display: 11:39:06 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:39:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:39:06 INFO - Display Serial Number: 1600x1200 60 11:39:06 INFO - Main Display: Yes 11:39:06 INFO - Mirror: Off 11:39:06 INFO - Online: Yes 11:39:06 INFO - Rotation: Supported 11:39:06 INFO - Return code: 0 11:39:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:39:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:39:06 INFO - Python 2.7.3 11:39:06 INFO - Return code: 0 11:39:06 INFO - grabbing minidump binary from tooltool 11:39: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')]} 11:39:06 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 0x1102fed30>, '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 0x110225db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1102f3be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:39:06 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 11:39:06 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 11:39:06 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 11:39:06 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:39:06 INFO - Return code: 0 11:39:06 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:39:06 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:39:06 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:39:06 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:39:06 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:39:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LjayOvF_SJeQaMuQReZttw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0083', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 11:39:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/LjayOvF_SJeQaMuQReZttw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0083 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 11:39:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CIafmlgHF2/Render', 11:39:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:39:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:39:06 INFO - 'HOME': '/Users/cltbld', 11:39:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:39:06 INFO - 'LOGNAME': 'cltbld', 11:39:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:39:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:39:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:39:06 INFO - 'MOZ_NO_REMOTE': '1', 11:39:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:39:06 INFO - 'NO_EM_RESTART': '1', 11:39:06 INFO - 'PAGER': '/bin/cat', 11:39:06 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:39:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:39:06 INFO - 'PWD': '/builds/slave/test', 11:39:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:39:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:39:06 INFO - 'SHELL': '/bin/bash', 11:39:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mlBm1gz2N1/Listeners', 11:39:06 INFO - 'TMPDIR': '/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/', 11:39:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:39:06 INFO - 'USER': 'cltbld', 11:39:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:39:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:39:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:39:06 INFO - 'XPC_FLAGS': '0x0', 11:39:06 INFO - 'XPC_SERVICE_NAME': '0', 11:39:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:39:06 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LjayOvF_SJeQaMuQReZttw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0083', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 11:39:07 INFO - mozversion application_buildid: 20170102105519 11:39:07 INFO - mozversion application_changeset: c91249f41e3766274131a84f9157a4d9d9949520 11:39:07 INFO - mozversion application_display_name: Nightly 11:39:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:39:07 INFO - mozversion application_name: Firefox 11:39:07 INFO - mozversion application_remotingname: firefox 11:39:07 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 11:39:07 INFO - mozversion application_vendor: Mozilla 11:39:07 INFO - mozversion application_version: 53.0a1 11:39:07 INFO - mozversion platform_buildid: 20170102105519 11:39:07 INFO - mozversion platform_changeset: c91249f41e3766274131a84f9157a4d9d9949520 11:39:07 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 11:39:07 INFO - mozversion platform_version: 53.0a1 11:39:07 INFO - using testdate: 1483385947 11:39:07 INFO - actual date: 1483385947 11:39:07 INFO - starting webserver on 'localhost:49239' 11:39:07 INFO - SUITE-START | Running 2 tests 11:39:07 INFO - TEST-START | dromaeo_css 11:39:07 INFO - operating with platform_type : mac_ 11:39:07 INFO - Initialising browser for dromaeo_css test... 11:39:07 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/tmpdJ3ocl/profile http://localhost:49239/getInfo.html) 11:39:07 INFO - PROCESS | 1644 | 1483385947726 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4986 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3827 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2831 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 11:39:09 INFO - PROCESS | 1644 | __metrics Screen width/height:1600/1200 11:39:09 INFO - PROCESS | 1644 | colorDepth:24 11:39:09 INFO - PROCESS | 1644 | Browser inner width/height: 1024/689 11:39:09 INFO - PROCESS | 1644 | __metrics 11:39:09 INFO - PROCESS | 1644 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 11:39:09 INFO - PROCESS | 1644 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:39:10 INFO - TEST-INFO | 1644: exit 0 11:39:10 INFO - Browser initialized. 11:39:10 INFO - Running cycle 1/1 for dromaeo_css test... 11:39:10 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/tmpdJ3ocl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:39:22 INFO - PROCESS | 1647 | RSS: Main: 234012672 11:39:22 INFO - PROCESS | 1647 | 11:40:14 INFO - PROCESS | 1647 | 1483386014157 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 11:42:26 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49239/tests/dromaeo/cssquery-ext.html) 11:42:29 INFO - PROCESS | 1647 | RSS: Main: 477429760 11:42:29 INFO - PROCESS | 1647 | 11:44:11 INFO - PROCESS | 1647 | ************************* 11:44:11 INFO - PROCESS | 1647 | A coding exception was thrown and uncaught in a Task. 11:44:11 INFO - PROCESS | 1647 | 11:44:11 INFO - PROCESS | 1647 | Full message: ReferenceError: fetch is not defined 11:44:11 INFO - PROCESS | 1647 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 11:44:11 INFO - PROCESS | 1647 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 11:44:11 INFO - PROCESS | 1647 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 11:44:11 INFO - PROCESS | 1647 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 11:44:11 INFO - PROCESS | 1647 | TaskImpl@resource://gre/modules/Task.jsm:277:3 11:44:11 INFO - PROCESS | 1647 | asyncFunction@resource://gre/modules/Task.jsm:252:14 11:44:11 INFO - PROCESS | 1647 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 11:44:11 INFO - PROCESS | 1647 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 11:44:11 INFO - PROCESS | 1647 | TaskImpl@resource://gre/modules/Task.jsm:277:3 11:44:11 INFO - PROCESS | 1647 | asyncFunction@resource://gre/modules/Task.jsm:252:14 11:44:11 INFO - PROCESS | 1647 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 11:44:11 INFO - PROCESS | 1647 | 11:44:11 INFO - PROCESS | 1647 | ************************* 11:45:32 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-ext.html (next: http://localhost:49239/tests/dromaeo/cssquery-jquery.html) 11:45:33 INFO - PROCESS | 1647 | RSS: Main: 367042560 11:45:33 INFO - PROCESS | 1647 | 11:48:35 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49239/tests/dromaeo/cssquery-mootools.html) 11:48:36 INFO - PROCESS | 1647 | RSS: Main: 342589440 11:48:36 INFO - PROCESS | 1647 | 11:51:38 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49239/tests/dromaeo/cssquery-prototype.html) 11:51:39 INFO - PROCESS | 1647 | RSS: Main: 347688960 11:51:39 INFO - PROCESS | 1647 | 11:54:42 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49239/tests/dromaeo/cssquery-yui.html) 11:54:43 INFO - PROCESS | 1647 | RSS: Main: 330899456 11:54:43 INFO - PROCESS | 1647 | 11:57:46 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-yui.html (next: http://localhost:49239/tests/dromaeo/cssquery-dojo.html) 11:57:47 INFO - PROCESS | 1647 | RSS: Main: 385409024 11:57:47 INFO - PROCESS | 1647 | 11:57:47 INFO - PROCESS | 1647 | __start_tp_report 11:57:47 INFO - PROCESS | 1647 | _x_x_mozilla_page_load 11:57:47 INFO - PROCESS | 1647 | _x_x_mozilla_page_load_details 11:57:47 INFO - PROCESS | 1647 | |i|pagename|runs| 11:57:47 INFO - PROCESS | 1647 | |0;dojo.html;3341.148007258149;3348.414027545179;3348.631650518443;3376.223468602223;3410.8976730698073;5572.4706152915505;5637;5658.603897727161;5687.459691329323;5704.058830292996;5659.556813012687;5708.372079071299;5719;5721.542276617871;5730.025895597748;5138.691678499293;5175.223716442526;5186.507281808223;5210.843674689746;5226.947730522684;5264.736763161845;5266.7366631668265;5270.130428479296;5272.1300985337375;5277.86805329866;5257.894842103175;5268.130758424855;5268.841934741964;5270.868228294285;5271.15661494161;5253.159494480865;5253.922945796141;5254.816081437131;5262.736863156846;5282.2076688496645;5126.282320475137;5172.301739265189;5212.53087222148;5214.113600687892;5215.322008138955;5726.856828579277;5860.97069514654;5876.236089308405;5888.234529511179;5899;6247.7188526515965;6253.3433989430905;6285.622862628257;6287.591306565105;6295.5907865988565;6143.341297849555;6143.723532441051;6164.476019538344;6215.56491045627;6225.595336303126;5817.127430885401;5817.476427121579;5818.069108942634;5828.067509198508;5839.64962102271;6252.155958945484;6253.343398943137;6262.405071518198;6265.122882796412;6267.561270711098;6008.969955150284;6052.092186172109;6068.696565171724;6157.953147964857;6171.59884607499;6200.3799620037635;6243.656598887014;6252.624842509373;6297.4647154992335;6303.558750887486;5199.610029247821;6180.783672571439;6193.287771906198;6257.030160325156;6258.687065646699;6091.360407157274;6240.375962403723;6251.218597675291;6282.308946015934;6286.151369565142;4867.561919427198;4899.485554016777;4923.187674033816;4924.852254432372;4928.581070609039;4413.073254616503;4420.778961051935;4436.800343984529;4447.822087116544;4465.24090904547;5873.853153671107;5874.765009399662;5877.617954832922;5884;5884.7940322088525;5760.164776107525;6208.348123446973;6225.719842607094;6228.813135605939;6285.308616052231;5826.446487583673;5854.121881717776;5893.970530147408;5941.643501389931;5961.135635332853;5113.846584602468;5271.393789714232;5333.306670132858;5336.813211537577;5445.945540544544;5259.710715910662;5714.742836572447;6037.366076561898;6241.157443745218;6247.533802514376;6122.367660829107;6157.476614487819;6288.339724329063;6295.842603934755;6295.905561416674;5539.7230138492105;5648.689322087324;6105.297890742621;6249.750009999549;6257.342978987323;6172.321044685171;6187.721552530237;6192.938070619416;6197.163382943425;6213.627182369072;4820.68665536739;6132.846678833065;6134.079888016833;6145.631262124287;6155.199824022849;6035.818925432244;6078.45293923549;6124.479419249414;6125.111858780541;6135.325114237341;6069.939300606938;6071.969640151771;6075.1798507200965;6114.875284800388;6143.385661433821;4959.479254678206;5856.560757943086;5889.587728858939;5902.822915312548;5918.23063001807;6046.09308603713;6076.57463977535;6078.665673388006;6078.878422431615;6090.786822461193;5800.419958004335;5817.185594016756;5817.534597232296;5820.621659592282;5824.504917081926 11:57:47 INFO - PROCESS | 1647 | |1;ext.html;422.8837069805801;454.9831127430193;467.33404898020325;491.71234827625835;533.7483600955762;24636;30168.39663206737;36897.44653830195;37867.810660946656;38205.61794382055;39169.21661566767;39498.21003579927;39530.802345988304;39562.60437395625;39676.00809979752;8889.4666320021;9099.818003639923;9100.817983640323;9103.317251206154;9107.95446022769;8165.428420010601;8382.87425688615;10700.892991070106;10986.285891417072;11027.83458248127;10995.450227488594;11082.722931926686;11100.222984391095;11113.388763618039;11128.221024528286;8195.549244791535;10863.619773307935;10970.122390208844;10989.06592939601;10989.230753847234;8243.422960392774;10709.625163119332;11026.338419694765;11053.392063436508;11204;37477;37569;37670.81164594187;37713;37750.62249377499;3829;3903.6877049835944;3924.504944317131;3937.1731936293413;4069.491313585802;23810.523789524286;28206.294842628893;29323.560146597818;29478.263043424085;29508.557371639443;2885.321949341903;3103.649912288162;3178.773674101053;3252.47960326349;3272.181954511372;460801;463726;463882;464086;465033;53307;53759;53783.73108134434;53806.92386152333;53877.46122538725;56586.71706641441;56640.43359566434;56759.43240567541;56845;56937.71531142318;14838.33227504765;15081.924590376977;15099.433096373643;15124.941254112327;15141.394344226328;10725.89274107249;11024.559017639205;11896.345700986356;11946.10404219687;11984.760304793856;13232.338383080807;14633.560993170222;14790.334434950453;15010.47463338778;15360.308786104652;5897.675627840425;6240.907841127784;6935.3411425914455;7167.677454514559;7187;807.9474834135763;815.233680340478;823.4565186976566;850.6469815026694;859.8237361340911;27533.485658288584;28243.90463382798;28684.42631147407;28867.6570424504;29144.562831557545;996.5565323431149;999.645125980281;1031.6234574380296;1032.1897310611284;1149.787289351456;14270.571882843531;14650.706985860223;14734.631634209016;15001.2499375033;15058.774118388234;536136.4704470728;567573;575080;577866;578754;53840.19239711407;53943.190852137246;54031;54217;54229.644258893844;1341.3159288762802;1359.7212571422835;1377.5109836008073;1381.0194761719292;1382.81332020178;27236.45527089487;28215.821517223172;28533.42933141284;28592.714072859006;28657.42685146327;39031.559792633896;40086.599134008284;40189.59810401859;40202;40233.79883100566;474591;475847;484053;484360;484966;35951.7738221965;36499.72250971203;36888;36983.8150809255;36992.63007369892;33874.983750487525;42109;43974.90062748457;46100.30849537192;46519;37076;37096.443553347264;37435.6256437443;37926.0518487029;38021.61978380291;35055.64944350524;36569.26861462703;36840.26319473542;37757.81121094377;37777.866664000125;35308.47037294391;36090.27819443649;36672.5992880856;36764.63235367612;36844.447333289485;25975.870120649277;30299.24251893632;30523.847380763844;31250.531242031844;31509.36981260316;22032.228871989402;22684.886575567678;22760.430989225402;23382.53234935326;23436.765632343457 11:57:47 INFO - PROCESS | 1647 | |2;jquery.html;326.5281667989755;375.46871177284135;375.6412625942224;395.9980200098999;405.68762053219024;18505;18959.24163033477;19679.212831486722;19851.900740496294;19984.50271202541;11717.296962182276;11984.820227696591;12120.454579543919;12122.272663640188;12169.513219471233;5009.6242781791325;5253.6322457428;5303.992241474127;5312.389075256337;5318.627696061278;3849.6727778138893;4059.208454351388;4935.432425271104;5197.896042079156;5295.338082739658;4338.17574660815;6438.066480360345;6473.74105035798;6474.7733829315985;6482.254540727806;5630.690312032836;5932.080527518242;6453.903191452132;6468.579542329757;6476.996065609838;5820.708964551798;5825.038868586678;5830.591858569902;5834.4165583441745;5844.649321040752;12813.487460501568;12907.483700651963;13115.540956066492;13132.34338283082;19065.714014289795;2484.503099380131;2497.4880149569294;2503.311589312935;2533.569293220157;2686.986565067182;9757.951210243975;9818.269057758078;9855.55649995752;10123.79752404955;10371.844422333672;1763.6913540130427;1811.529002459357;1825.26989204318;1831.6519861226457;1835.1374853818818;103382.48308758409;108789.91210087876;109502;109595;110787.44606276997;11178.75207421191;11212.66362009141;11397.829032564518;11589.942050289863;11650.35923024225;14234.928825355806;14257.78613320801;14265;14274.000819942505;14278.928605356907;8042.517448953082;8162.347012239006;8261.38039647029;8282.633365494627;8284.25441710249;7963.800904977422;8000.759977200693;8046.074701409296;8162;8239.505629662239;4333.241682705514;4483.910321793546;4495.977520112378;4511.571400716993;4537.137943790669;1868.6729822281047;1925.1336898395778;1925.6341295153875;1927.778305494858;1933.5069557262807;824.6866190847439;829.6017911402561;830.7175560309523;846.0777752250077;864.6282098697494;19672.409827705193;19796.60406791856;19819.009049547465;19837.801621983595;19915.60168796616;695.478391206595;857.2756021162108;858.3133493205411;863.9222469977732;867.0722327109931;10217;10247.59009639621;10448.68653940383;10499;10508.316959397767;492105;550193;553590;555279;558494;87167.56416217878;89653.55173224222;99332.00667993228;119436;124149;927.3554879358916;1010.2322235101376;1032.0195813976752;1033.7312298802365;1036.4144258493775;18224.817751822313;18448.262069517605;18665.533361665523;18778.342758003404;18981.810181898556;45371;45453.81903173361;45507.63477095692;46138.538614614765;47261.29108063313;61327.08009379952;61602.69198653978;62244;62532.374676254476;62874.94000959824;7856.803579910548;7943.364530837403;8154.918450815653;8291.58542072882;8395.370347223861;10534.789304214026;12678.366081695694;12814.871851281368;12906.03204759628;12956.870431295943;10862.185336099983;11044.668659940215;11129.777404451703;11453.198276120926;11553.017993470314;9481.7673702421;10063.345882517613;10995.890041099487;11454.599089031844;11454.770904582028;11247.831282530933;11439.828402574134;11792.23350482216;11855.76288474243;11876.82184767212;8060.637271322922;8105.675772969015;8141.22658347456;8176.46852954556;8205.933228680233;9056.501892395981;11088.722781930517;11327.093832493216;11385.772284554429;11501.82747258775 11:57:47 INFO - PROCESS | 1647 | |3;mootools.html;1167.638032210015;1385.0166381868873;1406.725688490744;1409.6334952912252;1412.5550451607742;7908.406869484783;8250.711225107107;8312.8753068704;8320.459170153936;8341.499510029402;7302.233265507125;7597.08834939808;7609.657565409556;8013.559254241029;8262.173782621734;1931.671615825306;2246.1576908659254;2284.9543009139807;2291.301153148289;2293.850899691523;2282.052948026567;2285.702858628376;2308.6883270758485;2322.256877799105;2325.6744055832196;1817.7455156278131;1983.2463663807732;2056.897155142244;2235.4634887627017;2288.3592594073684;2272.3296627494847;2277.6697378880135;2277.8861056947085;2279.2364557873134;2281.475260690034;2250.7411647660565;2273.45437095096;2276.8064714499287;2284.0292875527834;2287.874166920819;8206.876896846552;8253.876191857127;8276;8281.54451505167;8282.13037631046;4695.530446955311;4796.400449943757;4913.901721965576;5105.795768169269;5123.795048198068;3174.3175217328385;3336.4494858348344;3411.1131105912395;3420.58952925648;3422.435298175798;1153.2446247707765;1230.686175025363;1242.19257482637;1699.5496193508607;1744.520256929342;10348.896511034869;10523.842142367794;10647.30792498485;10647.680569582848;10649.946750266277;4337.349397590324;4463.866084017484;4507.143642707875;4581.060882519076;4583.197940360424;9305.9526904081;9321.44071355721;9322.1610055094;9349.345545811864;9368;5007.69953802773;5117.948820511747;5136.075506408883;5136.512031357015;5152.3044389007;4049.5545489996075;4163.084121493267;4293.3989241506215;4617.168909596305;4690.929636055462;3234.5633339499336;3677.4922281864306;3772.434134879735;3809.4095415210686;3845.3270677631863;1230.4831970572382;1249.5314257153568;1253.2918900821007;1263.7219811641423;1274.1908887856277;862.7411776467035;873.6723728601775;875.5403413207989;889.6619284671913;927.2396634759433;5443.129099344086;5577.944220557823;5599.468050535193;5606.579506537026;5870.6771127587535;807.8263173417744;859.1022381611232;899.8605216191501;902.2136170382679;919.9310051746146;3542.9017004728103;3609.6117787331914;3708.332500149999;3730.1047748540695;3809.561900381492;9373.20327772147;9649.276304277208;9847.310688251897;9931.155851752683;9946.452945087945;9762.804743905082;9907.45508997012;9909.702708918743;9911.10800028001;9942.552585133686;7481.812954676177;7612;7652.540847549053;7759.72840950564;7785.688572457151;5255.868603284948;5363;5374.086405311028;5422.250542623285;5521.116621340566;9153.17621414076;9223.58493867791;9256.305777066882;9258.768530786785;9261.583228754587;7751.302382785577;9084.727458176265;9133.269338453041;9274;9332.626694932127;6599.571027883172;6647.302033286435;6692.196936367667;6752.426043786333;6803.795886123424;7710.383169346326;7792.4545281832;7799.415043871619;7813.804654883674;7830.2952734254195;6889.173299204128;7075.716971321295;7099.432045436456;7180.138383394026;7235.312645298688;6638.900416493853;6738.696758645775;6864.176298844171;6919.446444284546;6960.199577048705;5979.013462778593;6163.9383606163365;6219.533534984804;6235.688215589294;6287.654179020014;4619.538046195354;4689.288409730492;4697.6711630185555;4713.2223183175765;4842.273658951185;4670;4774.283857421415;4792.329073929583;4804.015176888799;4822.107910036654 11:57:47 INFO - PROCESS | 1647 | |4;prototype.html;350.93683137035333;356.8019749039282;363.26620227141166;373.57412549693356;376.4259504256011;4903.362562866827;5219.42586315505;5251.317328747262;5287;5314.548263397606;4512.592992290816;4607.9308810367875;4881.8291359802415;5047.939932614156;5229.477052294768;4020.2361551305303;4053.196327634204;4083.1629515949235;4083.816228269735;4089.386592011207;3598.478220658003;3653.0319465341704;3798.2593394288124;4031.536373317062;4043.8180281887385;3351.530785689993;3674.1549443627846;3864.613538646141;4028.7179897407195;4103.692223083266;3859.3824988001925;3907.98046009771;3932.2528719543197;3935.6457918787446;3940.9359472942147;2853.4007858349773;3403.353362861073;3405.5062016007787;3407.3185362927265;3429.262708517656;5065.366829146356;5071.239314102877;5072.695638261716;5076.517730815566;5086.21163719624;3473.4616134499192;4108.5143488189515;4397.384366188736;4480.6191473724775;4530.275155975029;4784.435595481354;4812.759362031885;5145.948540514584;5420.268263784378;5437.586227580858;3224.967750322514;3304.983475082633;3725.3480640887738;3725.6646901778972;3728.9813550932345;5027.773750181251;5130.74296797287;5629.860652951893;5651.6043876928225;5663.915041274384;3141.5601815745813;3209.759268054905;3343.414902392072;3780.281746468162;3793.6615778115083;3371.685042833297;3708.6662200402093;3840.807959602009;3844.308024555607;3850.4994350734223;4437.001674623249;4462.866114016585;4464.55354464551;4493;4501.7974191161475;4512.796924138422;4982.878852258285;5385.649932754359;5426.593005524602;5452.618316717871;1240.6402143378318;1241.9068569857297;1253.035162924543;1254.404158024931;1259.641002314336;788.7200043984418;814.5479259011306;823.8558252305822;849.5048714262941;857.7426771968385;4909.263610458388;4942.4316203637045;4961.677490963076;5012.54887060159;5012.749362531858;5317.73411329432;5324.094903866352;5328.440513746001;5333.386660534011;5359.08895487768;4590.65495207665;4697.6711630185555;4774.570288674036;4814.378839286562;4828.420589529279;2467.802575793932;2548.2610043087666;2595.9610605840926;2649.205238428464;2801.971980280212;4940.456549779521;5089.3638295213095;5411.485908838654;5474.863128421821;5528.612997090165;5266.7366631668265;5442.700651464128;5716.256886604798;5745.195672605839;5765.85585360361;4460.361224326729;5271.894562108813;5741.8564535886935;5787.218725472091;5791.855203619859;5221.712805795718;5535.6955367455175;5661.490465858093;5667.574931880208;5670.631408958405;5244.816431424882;5473.781048758005;5514.834554963358;5526.866992266495;5541.639793413416;4667.439907211021;4838.709677419225;5784.913226301693;5795.304563452412;5802.912956305574;3201.183698156955;3591.9281614368088;3761.5862255151364;3808.695304375588;3823.369144091194;5141.640085194001;5560.860978475571;5574.721263936706;5584.357798853184;5647.887042259214;4721.173794585865;5322.019668985381;5430.8099216527235;5482.5888058394985;5488.396276409512;5133.256717561507;5399.811006614749;5429.5656347492895;5470.674963140729;5475.425080366664;5082.246624914399;5300.734963251899;5304.469553044664;5317.787288508416;5325.7070861101465;4519.728816271035;4521.7165331508695;5324.840254792362;5330.920940833155;5404.703001224542;4920.45874953762;5247.265382846328;5310.097283461898;5355.678659280456;5365.087935051129 11:57:47 INFO - PROCESS | 1647 | |5;yui.html;698.5424546921766;703.9917620091985;741.4624397311946;766.6051983228638;801.430984001359;14492.927535362322;15530.53408397747;15540.689186216268;15587.220638968063;15638.374465021414;13836.515721949734;13971.441142354319;13999.65000874976;14007.229602371866;14016.579502614912;679.3715812873097;700.7792545348225;851.9701810436636;880.3221519430035;880.7974165941837;695.4644923408972;843.2832092721169;871.7341210930664;873.1399571422144;879.8328317619632;906.8821053263066;920.1258804136064;926.6478738079554;927.3740225347864;929.6699671616581;862.2369203255101;909.8180363927174;914.7667344827094;915.4095608332622;915.771057235691;533.4931814775979;548.3419896124626;664.5912763650368;691.2638040486916;693.414065114977;14647;14679.266036698122;14685.118892866461;14700.485483008044;14735.778963315559;13108.934455327757;13266.071375003754;13352.465901363934;13367.532136375181;13371.06402551812;12198.939005305005;12254.448549815279;12321.012653177266;12409.75180496394;12579.937100314532;4295.91408171838;4343.522212556616;4374.803133858953;4419.0940857124215;4436.866893993185;95019.04980950171;97163;97752.51123744407;98019.50990245075;98909.5163572547;10194.43930583825;10510.737231569272;10511.211659125596;10525.263231573716;10538.89461105379;20823.479413014797;20946.895265523573;21122.788772112388;21225.575488490453;21242.681359779617;6978.302169782981;7287.672054757549;7453.366463850633;7465.06686664167;7648.541087534654;4182.790860456965;4192.790360481964;4235.02594403289;4236.894077648022;4316.352547117895;3262.7389808815237;3273.1162586102096;3349.112485191426;3352.0614228016193;3353.6646335366268;537.6558603491272;563.9633423827438;564.2101058518108;571.3714913595095;587.0372588954033;630.9432151106422;663.7432728722382;664.3223911610131;670.4234358451728;688.7189826921921;9686.515674216402;9860.753481162885;10201.234907381977;10205;10237.897621023843;542.9131338985743;572.5734327925722;574.9971250143806;594.9881002379929;674.1202730436827;3470.600880898729;3502.754807163525;4317.633001194871;4376.168527979674;4377;79583.43957324045;85124;94175;95199;96653;74661.5067698643;76056;76622;86010.56994715112;88667.66998495028;71795.92306115518;72301;72866;73213.63393183;74810;9502.952485237529;9712.223022158068;9722.368046076983;9730.270229732656;9731;49543.50456495389;50238.99522009613;50837.23744143767;51331;51918.48081519137;53521;63339.683301583194;64286.357136428036;64653.67673161604;65010;12231.938840305742;12456.252624842176;12474.313912734886;12543.184692994926;13041.869581304065;14288.28558572088;14376.065555738844;14532.346044427815;14546.490872819824;14864;12860.614181574567;13163.407646656115;13165.868341316464;13313.600591982256;13357.198568085945;13000.479980801041;13411.530596429078;13568.660783480493;13866.237356945465;13952.511662091778;12718.427670755023;13239.867601323864;13325.400356984153;13382.732345353234;13460.192388456724;10393.688189354332;10759.87021362777;11000.779984400107;11048.944755276172;11244.381559014333;8762.430442021248;10555.736106597396;10932.706482330062;11480.655580332603;11600.30398176112 11:57:47 INFO - PROCESS | 1647 | __end_tp_report 11:57:47 INFO - PROCESS | 1647 | __start_cc_report 11:57:47 INFO - PROCESS | 1647 | _x_x_mozilla_cycle_collect,3000 11:57:47 INFO - PROCESS | 1647 | __end_cc_report 11:57:47 INFO - PROCESS | 1647 | __startTimestamp1483387067194__endTimestamp 11:57:47 INFO - PROCESS | 1647 | 11:57:47 INFO - PROCESS | 1647 | ------- Summary: start ------- 11:57:47 INFO - PROCESS | 1647 | Number of tests: 6 11:57:47 INFO - PROCESS | 1647 | 11:57:47 INFO - PROCESS | 1647 | [#0] dojo.html Cycles:165 Average:5709.12 Median:5884.00 stddev:629.18 (10.7%) stddev-sans-first:603.07 11:57:47 INFO - PROCESS | 1647 | Values: 3341.1 3348.4 3348.6 3376.2 3410.9 5572.5 5637.0 5658.6 5687.5 5704.1 5659.6 5708.4 5719.0 5721.5 5730.0 5138.7 5175.2 5186.5 5210.8 5226.9 5264.7 5266.7 5270.1 5272.1 5277.9 5257.9 5268.1 5268.8 5270.9 5271.2 5253.2 5253.9 5254.8 5262.7 5282.2 5126.3 5172.3 5212.5 5214.1 5215.3 5726.9 5861.0 5876.2 5888.2 5899.0 6247.7 6253.3 6285.6 6287.6 6295.6 6143.3 6143.7 6164.5 6215.6 6225.6 5817.1 5817.5 5818.1 5828.1 5839.6 6252.2 6253.3 6262.4 6265.1 6267.6 6009.0 6052.1 6068.7 6158.0 6171.6 6200.4 6243.7 6252.6 6297.5 6303.6 5199.6 6180.8 6193.3 6257.0 6258.7 6091.4 6240.4 6251.2 6282.3 6286.2 4867.6 4899.5 4923.2 4924.9 4928.6 4413.1 4420.8 4436.8 4447.8 4465.2 5873.9 5874.8 5877.6 5884.0 5884.8 5760.2 6208.3 6225.7 6228.8 6285.3 5826.4 5854.1 5894.0 5941.6 5961.1 5113.8 5271.4 5333.3 5336.8 5445.9 5259.7 5714.7 6037.4 6241.2 6247.5 6122.4 6157.5 6288.3 6295.8 6295.9 5539.7 5648.7 6105.3 6249.8 6257.3 6172.3 6187.7 6192.9 6197.2 6213.6 4820.7 6132.8 6134.1 6145.6 6155.2 6035.8 6078.5 6124.5 6125.1 6135.3 6069.9 6072.0 6075.2 6114.9 6143.4 4959.5 5856.6 5889.6 5902.8 5918.2 6046.1 6076.6 6078.7 6078.9 6090.8 5800.4 5817.2 5817.5 5820.6 5824.5 11:57:47 INFO - PROCESS | 1647 | 11:57:47 INFO - PROCESS | 1647 | [#1] ext.html Cycles:180 Average:63281.55 Median:37034.32 stddev:134837.91 (364.1%) stddev-sans-first:135133.57 11:57:47 INFO - PROCESS | 1647 | Values: 422.9 455.0 467.3 491.7 533.7 24636.0 30168.4 36897.4 37867.8 38205.6 39169.2 39498.2 39530.8 39562.6 39676.0 8889.5 9099.8 9100.8 9103.3 9108.0 8165.4 8382.9 10700.9 10986.3 11027.8 10995.5 11082.7 11100.2 11113.4 11128.2 8195.5 10863.6 10970.1 10989.1 10989.2 8243.4 10709.6 11026.3 11053.4 11204.0 37477.0 37569.0 37670.8 37713.0 37750.6 3829.0 3903.7 3924.5 3937.2 4069.5 23810.5 28206.3 29323.6 29478.3 29508.6 2885.3 3103.6 3178.8 3252.5 3272.2 460801.0 463726.0 463882.0 464086.0 465033.0 53307.0 53759.0 53783.7 53806.9 53877.5 56586.7 56640.4 56759.4 56845.0 56937.7 14838.3 15081.9 15099.4 15124.9 15141.4 10725.9 11024.6 11896.3 11946.1 11984.8 13232.3 14633.6 14790.3 15010.5 15360.3 5897.7 6240.9 6935.3 7167.7 7187.0 807.9 815.2 823.5 850.6 859.8 27533.5 28243.9 28684.4 28867.7 29144.6 996.6 999.6 1031.6 1032.2 1149.8 14270.6 14650.7 14734.6 15001.2 15058.8 536136.5 567573.0 575080.0 577866.0 578754.0 53840.2 53943.2 54031.0 54217.0 54229.6 1341.3 1359.7 1377.5 1381.0 1382.8 27236.5 28215.8 28533.4 28592.7 28657.4 39031.6 40086.6 40189.6 40202.0 40233.8 474591.0 475847.0 484053.0 484360.0 484966.0 35951.8 36499.7 36888.0 36983.8 36992.6 33875.0 42109.0 43974.9 46100.3 46519.0 37076.0 37096.4 37435.6 37926.1 38021.6 35055.6 36569.3 36840.3 37757.8 37777.9 35308.5 36090.3 36672.6 36764.6 36844.4 25975.9 30299.2 30523.8 31250.5 31509.4 22032.2 22684.9 22760.4 23382.5 23436.8 11:57:47 INFO - PROCESS | 1647 | 11:57:47 INFO - PROCESS | 1647 | [#2] jquery.html Cycles:180 Average:31209.96 Median:1506.76 stddev:90179.40 (5985.0%) stddev-sans-first:90402.56 11:57:47 INFO - PROCESS | 1647 | Values: 326.5 375.5 375.6 396.0 405.7 18505.0 18959.2 19679.2 19851.9 19984.5 11717.3 11984.8 12120.5 12122.3 12169.5 5009.6 5253.6 5304.0 5312.4 5318.6 3849.7 4059.2 4935.4 5197.9 5295.3 4338.2 6438.1 6473.7 6474.8 6482.3 5630.7 5932.1 6453.9 6468.6 6477.0 5820.7 5825.0 5830.6 5834.4 5844.6 12813.5 12907.5 13115.5 13132.3 19065.7 2484.5 2497.5 2503.3 2533.6 2687.0 9758.0 9818.3 9855.6 10123.8 10371.8 1763.7 1811.5 1825.3 1831.7 1835.1 103382.5 108789.9 109502.0 109595.0 110787.4 11178.8 11212.7 11397.8 11589.9 11650.4 14234.9 14257.8 14265.0 14274.0 14278.9 8042.5 8162.3 8261.4 8282.6 8284.3 7963.8 8000.8 8046.1 8162.0 8239.5 4333.2 4483.9 4496.0 4511.6 4537.1 1868.7 1925.1 1925.6 1927.8 1933.5 824.7 829.6 830.7 846.1 864.6 19672.4 19796.6 19819.0 19837.8 19915.6 695.5 857.3 858.3 863.9 867.1 10217.0 10247.6 10448.7 10499.0 10508.3 492105.0 550193.0 553590.0 555279.0 558494.0 87167.6 89653.6 99332.0 119436.0 124149.0 927.4 1010.2 1032.0 1033.7 1036.4 18224.8 18448.3 18665.5 18778.3 18981.8 45371.0 45453.8 45507.6 46138.5 47261.3 61327.1 61602.7 62244.0 62532.4 62874.9 7856.8 7943.4 8154.9 8291.6 8395.4 10534.8 12678.4 12814.9 12906.0 12956.9 10862.2 11044.7 11129.8 11453.2 11553.0 9481.8 10063.3 10995.9 11454.6 11454.8 11247.8 11439.8 11792.2 11855.8 11876.8 8060.6 8105.7 8141.2 8176.5 8205.9 9056.5 11088.7 11327.1 11385.8 11501.8 11:57:47 INFO - PROCESS | 1647 | 11:57:47 INFO - PROCESS | 1647 | [#3] mootools.html Cycles:180 Average:5310.71 Median:5309.43 stddev:2906.58 (54.7%) stddev-sans-first:2898.05 11:57:47 INFO - PROCESS | 1647 | Values: 1167.6 1385.0 1406.7 1409.6 1412.6 7908.4 8250.7 8312.9 8320.5 8341.5 7302.2 7597.1 7609.7 8013.6 8262.2 1931.7 2246.2 2285.0 2291.3 2293.9 2282.1 2285.7 2308.7 2322.3 2325.7 1817.7 1983.2 2056.9 2235.5 2288.4 2272.3 2277.7 2277.9 2279.2 2281.5 2250.7 2273.5 2276.8 2284.0 2287.9 8206.9 8253.9 8276.0 8281.5 8282.1 4695.5 4796.4 4913.9 5105.8 5123.8 3174.3 3336.4 3411.1 3420.6 3422.4 1153.2 1230.7 1242.2 1699.5 1744.5 10348.9 10523.8 10647.3 10647.7 10649.9 4337.3 4463.9 4507.1 4581.1 4583.2 9306.0 9321.4 9322.2 9349.3 9368.0 5007.7 5117.9 5136.1 5136.5 5152.3 4049.6 4163.1 4293.4 4617.2 4690.9 3234.6 3677.5 3772.4 3809.4 3845.3 1230.5 1249.5 1253.3 1263.7 1274.2 862.7 873.7 875.5 889.7 927.2 5443.1 5577.9 5599.5 5606.6 5870.7 807.8 859.1 899.9 902.2 919.9 3542.9 3609.6 3708.3 3730.1 3809.6 9373.2 9649.3 9847.3 9931.2 9946.5 9762.8 9907.5 9909.7 9911.1 9942.6 7481.8 7612.0 7652.5 7759.7 7785.7 5255.9 5363.0 5374.1 5422.3 5521.1 9153.2 9223.6 9256.3 9258.8 9261.6 7751.3 9084.7 9133.3 9274.0 9332.6 6599.6 6647.3 6692.2 6752.4 6803.8 7710.4 7792.5 7799.4 7813.8 7830.3 6889.2 7075.7 7099.4 7180.1 7235.3 6638.9 6738.7 6864.2 6919.4 6960.2 5979.0 6163.9 6219.5 6235.7 6287.7 4619.5 4689.3 4697.7 4713.2 4842.3 4670.0 4774.3 4792.3 4804.0 4822.1 11:57:47 INFO - PROCESS | 1647 | 11:57:47 INFO - PROCESS | 1647 | [#4] prototype.html Cycles:180 Average:4367.39 Median:4941.44 stddev:1349.48 (27.3%) stddev-sans-first:1319.17 11:57:47 INFO - PROCESS | 1647 | Values: 350.9 356.8 363.3 373.6 376.4 4903.4 5219.4 5251.3 5287.0 5314.5 4512.6 4607.9 4881.8 5047.9 5229.5 4020.2 4053.2 4083.2 4083.8 4089.4 3598.5 3653.0 3798.3 4031.5 4043.8 3351.5 3674.2 3864.6 4028.7 4103.7 3859.4 3908.0 3932.3 3935.6 3940.9 2853.4 3403.4 3405.5 3407.3 3429.3 5065.4 5071.2 5072.7 5076.5 5086.2 3473.5 4108.5 4397.4 4480.6 4530.3 4784.4 4812.8 5145.9 5420.3 5437.6 3225.0 3305.0 3725.3 3725.7 3729.0 5027.8 5130.7 5629.9 5651.6 5663.9 3141.6 3209.8 3343.4 3780.3 3793.7 3371.7 3708.7 3840.8 3844.3 3850.5 4437.0 4462.9 4464.6 4493.0 4501.8 4512.8 4982.9 5385.6 5426.6 5452.6 1240.6 1241.9 1253.0 1254.4 1259.6 788.7 814.5 823.9 849.5 857.7 4909.3 4942.4 4961.7 5012.5 5012.7 5317.7 5324.1 5328.4 5333.4 5359.1 4590.7 4697.7 4774.6 4814.4 4828.4 2467.8 2548.3 2596.0 2649.2 2802.0 4940.5 5089.4 5411.5 5474.9 5528.6 5266.7 5442.7 5716.3 5745.2 5765.9 4460.4 5271.9 5741.9 5787.2 5791.9 5221.7 5535.7 5661.5 5667.6 5670.6 5244.8 5473.8 5514.8 5526.9 5541.6 4667.4 4838.7 5784.9 5795.3 5802.9 3201.2 3591.9 3761.6 3808.7 3823.4 5141.6 5560.9 5574.7 5584.4 5647.9 4721.2 5322.0 5430.8 5482.6 5488.4 5133.3 5399.8 5429.6 5470.7 5475.4 5082.2 5300.7 5304.5 5317.8 5325.7 4519.7 4521.7 5324.8 5330.9 5404.7 4920.5 5247.3 5310.1 5355.7 5365.1 11:57:47 INFO - PROCESS | 1647 | 11:57:47 INFO - PROCESS | 1647 | [#5] yui.html Cycles:180 Average:19258.15 Median:4427.98 stddev:26727.43 (603.6%) stddev-sans-first:26766.08 11:57:47 INFO - PROCESS | 1647 | Values: 698.5 704.0 741.5 766.6 801.4 14492.9 15530.5 15540.7 15587.2 15638.4 13836.5 13971.4 13999.7 14007.2 14016.6 679.4 700.8 852.0 880.3 880.8 695.5 843.3 871.7 873.1 879.8 906.9 920.1 926.6 927.4 929.7 862.2 909.8 914.8 915.4 915.8 533.5 548.3 664.6 691.3 693.4 14647.0 14679.3 14685.1 14700.5 14735.8 13108.9 13266.1 13352.5 13367.5 13371.1 12198.9 12254.4 12321.0 12409.8 12579.9 4295.9 4343.5 4374.8 4419.1 4436.9 95019.0 97163.0 97752.5 98019.5 98909.5 10194.4 10510.7 10511.2 10525.3 10538.9 20823.5 20946.9 21122.8 21225.6 21242.7 6978.3 7287.7 7453.4 7465.1 7648.5 4182.8 4192.8 4235.0 4236.9 4316.4 3262.7 3273.1 3349.1 3352.1 3353.7 537.7 564.0 564.2 571.4 587.0 630.9 663.7 664.3 670.4 688.7 9686.5 9860.8 10201.2 10205.0 10237.9 542.9 572.6 575.0 595.0 674.1 3470.6 3502.8 4317.6 4376.2 4377.0 79583.4 85124.0 94175.0 95199.0 96653.0 74661.5 76056.0 76622.0 86010.6 88667.7 71795.9 72301.0 72866.0 73213.6 74810.0 9503.0 9712.2 9722.4 9730.3 9731.0 49543.5 50239.0 50837.2 51331.0 51918.5 53521.0 63339.7 64286.4 64653.7 65010.0 12231.9 12456.3 12474.3 12543.2 13041.9 14288.3 14376.1 14532.3 14546.5 14864.0 12860.6 13163.4 13165.9 13313.6 13357.2 13000.5 13411.5 13568.7 13866.2 13952.5 12718.4 13239.9 13325.4 13382.7 13460.2 10393.7 10759.9 11000.8 11048.9 11244.4 8762.4 10555.7 10932.7 11480.7 11600.3 11:57:47 INFO - PROCESS | 1647 | -------- Summary: end -------- 11:57:47 INFO - PROCESS | 1647 | 11:57:47 INFO - TEST-INFO | 1647: exit 0 11:57:47 INFO - TEST-OK | dromaeo_css | took 1120698ms 11:57:47 INFO - TEST-START | kraken 11:57:47 INFO - operating with platform_type : mac_ 11:57:47 INFO - Initialising browser for kraken test... 11:57:47 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/tmpkU8U5B/profile http://localhost:49239/getInfo.html) 11:57:48 INFO - PROCESS | 1651 | 1483387068374 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4986 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3827 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2831 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 11:57:50 INFO - PROCESS | 1651 | __metrics Screen width/height:1600/1200 11:57:50 INFO - PROCESS | 1651 | colorDepth:24 11:57:50 INFO - PROCESS | 1651 | Browser inner width/height: 1024/689 11:57:50 INFO - PROCESS | 1651 | __metrics 11:57:50 INFO - PROCESS | 1651 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 11:57:50 INFO - PROCESS | 1651 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:57:51 INFO - TEST-INFO | 1651: exit 0 11:57:51 INFO - Browser initialized. 11:57:51 INFO - Running cycle 1/1 for kraken test... 11:57:51 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/tmpkU8U5B/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:58:03 INFO - PROCESS | 1652 | RSS: Main: 234426368 11:58:03 INFO - PROCESS | 1652 | 11:58:05 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49239/tests/kraken/driver.html?testName=audio-beat-detection) 11:58:06 INFO - PROCESS | 1652 | RSS: Main: 285818880 11:58:06 INFO - PROCESS | 1652 | 11:58:09 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49239/tests/kraken/driver.html?testName=audio-dft) 11:58:09 INFO - PROCESS | 1652 | RSS: Main: 285310976 11:58:09 INFO - PROCESS | 1652 | 11:58:13 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49239/tests/kraken/driver.html?testName=audio-fft) 11:58:13 INFO - PROCESS | 1652 | RSS: Main: 376684544 11:58:13 INFO - PROCESS | 1652 | 11:58:15 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49239/tests/kraken/driver.html?testName=audio-oscillator) 11:58:16 INFO - PROCESS | 1652 | RSS: Main: 282943488 11:58:16 INFO - PROCESS | 1652 | 11:58:18 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49239/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:58:18 INFO - PROCESS | 1652 | RSS: Main: 285343744 11:58:18 INFO - PROCESS | 1652 | 11:58:21 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49239/tests/kraken/driver.html?testName=imaging-darkroom) 11:58:22 INFO - PROCESS | 1652 | RSS: Main: 327426048 11:58:22 INFO - PROCESS | 1652 | 11:58:25 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49239/tests/kraken/driver.html?testName=imaging-desaturate) 11:58:25 INFO - PROCESS | 1652 | RSS: Main: 327852032 11:58:25 INFO - PROCESS | 1652 | 11:58:28 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49239/tests/kraken/driver.html?testName=json-parse-financial) 11:58:29 INFO - PROCESS | 1652 | RSS: Main: 326787072 11:58:29 INFO - PROCESS | 1652 | 11:58:30 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49239/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:58:31 INFO - PROCESS | 1652 | RSS: Main: 303321088 11:58:31 INFO - PROCESS | 1652 | 11:58:33 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:58:34 INFO - PROCESS | 1652 | RSS: Main: 305025024 11:58:34 INFO - PROCESS | 1652 | 11:58:36 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:58:36 INFO - PROCESS | 1652 | RSS: Main: 310591488 11:58:36 INFO - PROCESS | 1652 | 11:58:39 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:58:40 INFO - PROCESS | 1652 | RSS: Main: 325152768 11:58:40 INFO - PROCESS | 1652 | 11:58:43 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:58:44 INFO - PROCESS | 1652 | RSS: Main: 319410176 11:58:44 INFO - PROCESS | 1652 | 11:58:45 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49239/tests/kraken/driver.html?testName=ai-astar) 11:58:46 INFO - PROCESS | 1652 | RSS: Main: 363757568 11:58:46 INFO - PROCESS | 1652 | 11:58:46 INFO - PROCESS | 1652 | __start_tp_report 11:58:46 INFO - PROCESS | 1652 | _x_x_mozilla_page_load 11:58:46 INFO - PROCESS | 1652 | _x_x_mozilla_page_load_details 11:58:46 INFO - PROCESS | 1652 | |i|pagename|runs| 11:58:46 INFO - PROCESS | 1652 | |0;ai-astar;114;113;114;113;114;110;105;112;113;112 11:58:46 INFO - PROCESS | 1652 | |1;audio-beat-detection;144;111;110;146;111;109;112;109;131;109 11:58:46 INFO - PROCESS | 1652 | |2;audio-dft;186;185;184;185;183;184;181;181;184;184 11:58:46 INFO - PROCESS | 1652 | |3;audio-fft;79;58;59;86;61;59;57;58;92;83 11:58:46 INFO - PROCESS | 1652 | |4;audio-oscillator;88;69;86;70;69;68;84;68;69;83 11:58:46 INFO - PROCESS | 1652 | |5;imaging-gaussian-blur;87;86;88;85;87;84;88;86;89;84 11:58:46 INFO - PROCESS | 1652 | |6;imaging-darkroom;98;147;93;94;93;93;93;98;94;94 11:58:46 INFO - PROCESS | 1652 | |7;imaging-desaturate;85;87;87;85;85;85;87;85;86;88 11:58:46 INFO - PROCESS | 1652 | |8;json-parse-financial;55;57;58;56;57;57;54;54;55;56 11:58:46 INFO - PROCESS | 1652 | |9;json-stringify-tinderbox;66;70;75;69;76;69;69;69;71;71 11:58:46 INFO - PROCESS | 1652 | |10;stanford-crypto-aes;88;90;88;87;86;85;88;90;90;92 11:58:46 INFO - PROCESS | 1652 | |11;stanford-crypto-ccm;140;138;150;141;157;149;143;138;186;151 11:58:46 INFO - PROCESS | 1652 | |12;stanford-crypto-pbkdf2;171;170;173;170;170;172;169;174;175;238 11:58:46 INFO - PROCESS | 1652 | |13;stanford-crypto-sha256-iterative;66;68;70;67;69;67;68;66;66;66 11:58:46 INFO - PROCESS | 1652 | __end_tp_report 11:58:46 INFO - PROCESS | 1652 | __start_cc_report 11:58:46 INFO - PROCESS | 1652 | _x_x_mozilla_cycle_collect,1963 11:58:46 INFO - PROCESS | 1652 | __end_cc_report 11:58:46 INFO - PROCESS | 1652 | __startTimestamp1483387126046__endTimestamp 11:58:46 INFO - PROCESS | 1652 | 11:58:46 INFO - PROCESS | 1652 | ------- Summary: start ------- 11:58:46 INFO - PROCESS | 1652 | Number of tests: 14 11:58:46 INFO - PROCESS | 1652 | 11:58:46 INFO - PROCESS | 1652 | [#0] ai-astar Cycles:10 Average:112.00 Median:113.00 stddev:2.75 (2.4%) stddev-sans-first:2.82 11:58:46 INFO - PROCESS | 1652 | Values: 114.0 113.0 114.0 113.0 114.0 110.0 105.0 112.0 113.0 112.0 11:58:46 INFO - PROCESS | 1652 | 11:58:46 INFO - PROCESS | 1652 | [#1] audio-beat-detection Cycles:10 Average:119.20 Median:111.50 stddev:15.11 (13.6%) stddev-sans-first:13.10 11:58:46 INFO - PROCESS | 1652 | Values: 144.0 111.0 110.0 146.0 111.0 109.0 112.0 109.0 131.0 109.0 11:58:46 INFO - PROCESS | 1652 | 11:58:46 INFO - PROCESS | 1652 | [#2] audio-dft Cycles:10 Average:183.70 Median:184.00 stddev:1.64 (0.9%) stddev-sans-first:1.51 11:58:46 INFO - PROCESS | 1652 | Values: 186.0 185.0 184.0 185.0 183.0 184.0 181.0 181.0 184.0 184.0 11:58:46 INFO - PROCESS | 1652 | 11:58:46 INFO - PROCESS | 1652 | [#3] audio-fft Cycles:10 Average:69.20 Median:70.00 stddev:14.00 (20.0%) stddev-sans-first:14.39 11:58:46 INFO - PROCESS | 1652 | Values: 79.0 58.0 59.0 86.0 61.0 59.0 57.0 58.0 92.0 83.0 11:58:46 INFO - PROCESS | 1652 | 11:58:46 INFO - PROCESS | 1652 | [#4] audio-oscillator Cycles:10 Average:75.40 Median:76.50 stddev:8.59 (11.2%) stddev-sans-first:7.81 11:58:46 INFO - PROCESS | 1652 | Values: 88.0 69.0 86.0 70.0 69.0 68.0 84.0 68.0 69.0 83.0 11:58:46 INFO - PROCESS | 1652 | 11:58:46 INFO - PROCESS | 1652 | [#5] imaging-gaussian-blur Cycles:10 Average:86.40 Median:87.00 stddev:1.71 (2.0%) stddev-sans-first:1.80 11:58:46 INFO - PROCESS | 1652 | Values: 87.0 86.0 88.0 85.0 87.0 84.0 88.0 86.0 89.0 84.0 11:58:46 INFO - PROCESS | 1652 | 11:58:46 INFO - PROCESS | 1652 | [#6] imaging-darkroom Cycles:10 Average:99.70 Median:94.00 stddev:16.73 (17.8%) stddev-sans-first:17.74 11:58:46 INFO - PROCESS | 1652 | Values: 98.0 147.0 93.0 94.0 93.0 93.0 93.0 98.0 94.0 94.0 11:58:46 INFO - PROCESS | 1652 | 11:58:46 INFO - PROCESS | 1652 | [#7] imaging-desaturate Cycles:10 Average:86.00 Median:86.50 stddev:1.15 (1.3%) stddev-sans-first:1.17 11:58:46 INFO - PROCESS | 1652 | Values: 85.0 87.0 87.0 85.0 85.0 85.0 87.0 85.0 86.0 88.0 11:58:46 INFO - PROCESS | 1652 | 11:58:46 INFO - PROCESS | 1652 | [#8] json-parse-financial Cycles:10 Average:55.90 Median:56.50 stddev:1.37 (2.4%) stddev-sans-first:1.41 11:58:46 INFO - PROCESS | 1652 | Values: 55.0 57.0 58.0 56.0 57.0 57.0 54.0 54.0 55.0 56.0 11:58:46 INFO - PROCESS | 1652 | 11:58:46 INFO - PROCESS | 1652 | [#9] json-stringify-tinderbox Cycles:10 Average:70.50 Median:70.50 stddev:2.99 (4.2%) stddev-sans-first:2.69 11:58:46 INFO - PROCESS | 1652 | Values: 66.0 70.0 75.0 69.0 76.0 69.0 69.0 69.0 71.0 71.0 11:58:46 INFO - PROCESS | 1652 | 11:58:46 INFO - PROCESS | 1652 | [#10] stanford-crypto-aes Cycles:10 Average:88.40 Median:89.00 stddev:2.12 (2.4%) stddev-sans-first:2.24 11:58:46 INFO - PROCESS | 1652 | Values: 88.0 90.0 88.0 87.0 86.0 85.0 88.0 90.0 90.0 92.0 11:58:46 INFO - PROCESS | 1652 | 11:58:46 INFO - PROCESS | 1652 | [#11] stanford-crypto-ccm Cycles:10 Average:149.30 Median:149.50 stddev:14.38 (9.6%) stddev-sans-first:14.85 11:58:46 INFO - PROCESS | 1652 | Values: 140.0 138.0 150.0 141.0 157.0 149.0 143.0 138.0 186.0 151.0 11:58:46 INFO - PROCESS | 1652 | 11:58:46 INFO - PROCESS | 1652 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.20 Median:172.50 stddev:21.10 (12.2%) stddev-sans-first:22.22 11:58:46 INFO - PROCESS | 1652 | Values: 171.0 170.0 173.0 170.0 170.0 172.0 169.0 174.0 175.0 238.0 11:58:46 INFO - PROCESS | 1652 | 11:58:46 INFO - PROCESS | 1652 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.30 Median:67.50 stddev:1.42 (2.1%) stddev-sans-first:1.42 11:58:46 INFO - PROCESS | 1652 | Values: 66.0 68.0 70.0 67.0 69.0 67.0 68.0 66.0 66.0 66.0 11:58:46 INFO - PROCESS | 1652 | -------- Summary: end -------- 11:58:46 INFO - PROCESS | 1652 | 11:58:47 INFO - TEST-INFO | 1652: exit 0 11:58:47 INFO - TEST-OK | kraken | took 59182ms 11:58:47 INFO - SUITE-END | took 1179s 11:58:47 INFO - Completed test suite (00:19:40) 11:58:47 INFO - javascript benchmark 11:58:47 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3341.148007258149, 3348.414027545179, 3348.631650518443, 3376.223468602223, 3410.8976730698073, 5572.4706152915505, 5637.0, 5658.603897727161, 5687.459691329323, 5704.058830292996, 5659.556813012687, 5708.372079071299, 5719.0, 5721.542276617871, 5730.025895597748, 5138.691678499293, 5175.223716442526, 5186.507281808223, 5210.843674689746, 5226.947730522684, 5264.736763161845, 5266.7366631668265, 5270.130428479296, 5272.1300985337375, 5277.86805329866, 5257.894842103175, 5268.130758424855, 5268.841934741964, 5270.868228294285, 5271.15661494161, 5253.159494480865, 5253.922945796141, 5254.816081437131, 5262.736863156846, 5282.2076688496645, 5126.282320475137, 5172.301739265189, 5212.53087222148, 5214.113600687892, 5215.322008138955, 5726.856828579277, 5860.97069514654, 5876.236089308405, 5888.234529511179, 5899.0, 6247.7188526515965, 6253.3433989430905, 6285.622862628257, 6287.591306565105, 6295.5907865988565, 6143.341297849555, 6143.723532441051, 6164.476019538344, 6215.56491045627, 6225.595336303126, 5817.127430885401, 5817.476427121579, 5818.069108942634, 5828.067509198508, 5839.64962102271, 6252.155958945484, 6253.343398943137, 6262.405071518198, 6265.122882796412, 6267.561270711098, 6008.969955150284, 6052.092186172109, 6068.696565171724, 6157.953147964857, 6171.59884607499, 6200.3799620037635, 6243.656598887014, 6252.624842509373, 6297.4647154992335, 6303.558750887486, 5199.610029247821, 6180.783672571439, 6193.287771906198, 6257.030160325156, 6258.687065646699, 6091.360407157274, 6240.375962403723, 6251.218597675291, 6282.308946015934, 6286.151369565142, 4867.561919427198, 4899.485554016777, 4923.187674033816, 4924.852254432372, 4928.581070609039, 4413.073254616503, 4420.778961051935, 4436.800343984529, 4447.822087116544, 4465.24090904547, 5873.853153671107, 5874.765009399662, 5877.617954832922, 5884.0, 5884.7940322088525, 5760.164776107525, 6208.348123446973, 6225.719842607094, 6228.813135605939, 6285.308616052231, 5826.446487583673, 5854.121881717776, 5893.970530147408, 5941.643501389931, 5961.135635332853, 5113.846584602468, 5271.393789714232, 5333.306670132858, 5336.813211537577, 5445.945540544544, 5259.710715910662, 5714.742836572447, 6037.366076561898, 6241.157443745218, 6247.533802514376, 6122.367660829107, 6157.476614487819, 6288.339724329063, 6295.842603934755, 6295.905561416674, 5539.7230138492105, 5648.689322087324, 6105.297890742621, 6249.750009999549, 6257.342978987323, 6172.321044685171, 6187.721552530237, 6192.938070619416, 6197.163382943425, 6213.627182369072, 4820.68665536739, 6132.846678833065, 6134.079888016833, 6145.631262124287, 6155.199824022849, 6035.818925432244, 6078.45293923549, 6124.479419249414, 6125.111858780541, 6135.325114237341, 6069.939300606938, 6071.969640151771, 6075.1798507200965, 6114.875284800388, 6143.385661433821, 4959.479254678206, 5856.560757943086, 5889.587728858939, 5902.822915312548, 5918.23063001807, 6046.09308603713, 6076.57463977535, 6078.665673388006, 6078.878422431615, 6090.786822461193, 5800.419958004335, 5817.185594016756, 5817.534597232296, 5820.621659592282, 5824.504917081926], "value": 5670.619737916405, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [422.8837069805801, 454.9831127430193, 467.33404898020325, 491.71234827625835, 533.7483600955762, 24636.0, 30168.39663206737, 36897.44653830195, 37867.810660946656, 38205.61794382055, 39169.21661566767, 39498.21003579927, 39530.802345988304, 39562.60437395625, 39676.00809979752, 8889.4666320021, 9099.818003639923, 9100.817983640323, 9103.317251206154, 9107.95446022769, 8165.428420010601, 8382.87425688615, 10700.892991070106, 10986.285891417072, 11027.83458248127, 10995.450227488594, 11082.722931926686, 11100.222984391095, 11113.388763618039, 11128.221024528286, 8195.549244791535, 10863.619773307935, 10970.122390208844, 10989.06592939601, 10989.230753847234, 8243.422960392774, 10709.625163119332, 11026.338419694765, 11053.392063436508, 11204.0, 37477.0, 37569.0, 37670.81164594187, 37713.0, 37750.62249377499, 3829.0, 3903.6877049835944, 3924.504944317131, 3937.1731936293413, 4069.491313585802, 23810.523789524286, 28206.294842628893, 29323.560146597818, 29478.263043424085, 29508.557371639443, 2885.321949341903, 3103.649912288162, 3178.773674101053, 3252.47960326349, 3272.181954511372, 460801.0, 463726.0, 463882.0, 464086.0, 465033.0, 53307.0, 53759.0, 53783.73108134434, 53806.92386152333, 53877.46122538725, 56586.71706641441, 56640.43359566434, 56759.43240567541, 56845.0, 56937.71531142318, 14838.33227504765, 15081.924590376977, 15099.433096373643, 15124.941254112327, 15141.394344226328, 10725.89274107249, 11024.559017639205, 11896.345700986356, 11946.10404219687, 11984.760304793856, 13232.338383080807, 14633.560993170222, 14790.334434950453, 15010.47463338778, 15360.308786104652, 5897.675627840425, 6240.907841127784, 6935.3411425914455, 7167.677454514559, 7187.0, 807.9474834135763, 815.233680340478, 823.4565186976566, 850.6469815026694, 859.8237361340911, 27533.485658288584, 28243.90463382798, 28684.42631147407, 28867.6570424504, 29144.562831557545, 996.5565323431149, 999.645125980281, 1031.6234574380296, 1032.1897310611284, 1149.787289351456, 14270.571882843531, 14650.706985860223, 14734.631634209016, 15001.2499375033, 15058.774118388234, 536136.4704470728, 567573.0, 575080.0, 577866.0, 578754.0, 53840.19239711407, 53943.190852137246, 54031.0, 54217.0, 54229.644258893844, 1341.3159288762802, 1359.7212571422835, 1377.5109836008073, 1381.0194761719292, 1382.81332020178, 27236.45527089487, 28215.821517223172, 28533.42933141284, 28592.714072859006, 28657.42685146327, 39031.559792633896, 40086.599134008284, 40189.59810401859, 40202.0, 40233.79883100566, 474591.0, 475847.0, 484053.0, 484360.0, 484966.0, 35951.7738221965, 36499.72250971203, 36888.0, 36983.8150809255, 36992.63007369892, 33874.983750487525, 42109.0, 43974.90062748457, 46100.30849537192, 46519.0, 37076.0, 37096.443553347264, 37435.6256437443, 37926.0518487029, 38021.61978380291, 35055.64944350524, 36569.26861462703, 36840.26319473542, 37757.81121094377, 37777.866664000125, 35308.47037294391, 36090.27819443649, 36672.5992880856, 36764.63235367612, 36844.447333289485, 25975.870120649277, 30299.24251893632, 30523.847380763844, 31250.531242031844, 31509.36981260316, 22032.228871989402, 22684.886575567678, 22760.430989225402, 23382.53234935326, 23436.765632343457], "value": 18923.37838590631, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [326.5281667989755, 375.46871177284135, 375.6412625942224, 395.9980200098999, 405.68762053219024, 18505.0, 18959.24163033477, 19679.212831486722, 19851.900740496294, 19984.50271202541, 11717.296962182276, 11984.820227696591, 12120.454579543919, 12122.272663640188, 12169.513219471233, 5009.6242781791325, 5253.6322457428, 5303.992241474127, 5312.389075256337, 5318.627696061278, 3849.6727778138893, 4059.208454351388, 4935.432425271104, 5197.896042079156, 5295.338082739658, 4338.17574660815, 6438.066480360345, 6473.74105035798, 6474.7733829315985, 6482.254540727806, 5630.690312032836, 5932.080527518242, 6453.903191452132, 6468.579542329757, 6476.996065609838, 5820.708964551798, 5825.038868586678, 5830.591858569902, 5834.4165583441745, 5844.649321040752, 12813.487460501568, 12907.483700651963, 13115.540956066492, 13132.34338283082, 19065.714014289795, 2484.503099380131, 2497.4880149569294, 2503.311589312935, 2533.569293220157, 2686.986565067182, 9757.951210243975, 9818.269057758078, 9855.55649995752, 10123.79752404955, 10371.844422333672, 1763.6913540130427, 1811.529002459357, 1825.26989204318, 1831.6519861226457, 1835.1374853818818, 103382.48308758409, 108789.91210087876, 109502.0, 109595.0, 110787.44606276997, 11178.75207421191, 11212.66362009141, 11397.829032564518, 11589.942050289863, 11650.35923024225, 14234.928825355806, 14257.78613320801, 14265.0, 14274.000819942505, 14278.928605356907, 8042.517448953082, 8162.347012239006, 8261.38039647029, 8282.633365494627, 8284.25441710249, 7963.800904977422, 8000.759977200693, 8046.074701409296, 8162.0, 8239.505629662239, 4333.241682705514, 4483.910321793546, 4495.977520112378, 4511.571400716993, 4537.137943790669, 1868.6729822281047, 1925.1336898395778, 1925.6341295153875, 1927.778305494858, 1933.5069557262807, 824.6866190847439, 829.6017911402561, 830.7175560309523, 846.0777752250077, 864.6282098697494, 19672.409827705193, 19796.60406791856, 19819.009049547465, 19837.801621983595, 19915.60168796616, 695.478391206595, 857.2756021162108, 858.3133493205411, 863.9222469977732, 867.0722327109931, 10217.0, 10247.59009639621, 10448.68653940383, 10499.0, 10508.316959397767, 492105.0, 550193.0, 553590.0, 555279.0, 558494.0, 87167.56416217878, 89653.55173224222, 99332.00667993228, 119436.0, 124149.0, 927.3554879358916, 1010.2322235101376, 1032.0195813976752, 1033.7312298802365, 1036.4144258493775, 18224.817751822313, 18448.262069517605, 18665.533361665523, 18778.342758003404, 18981.810181898556, 45371.0, 45453.81903173361, 45507.63477095692, 46138.538614614765, 47261.29108063313, 61327.08009379952, 61602.69198653978, 62244.0, 62532.374676254476, 62874.94000959824, 7856.803579910548, 7943.364530837403, 8154.918450815653, 8291.58542072882, 8395.370347223861, 10534.789304214026, 12678.366081695694, 12814.871851281368, 12906.03204759628, 12956.870431295943, 10862.185336099983, 11044.668659940215, 11129.777404451703, 11453.198276120926, 11553.017993470314, 9481.7673702421, 10063.345882517613, 10995.890041099487, 11454.599089031844, 11454.770904582028, 11247.831282530933, 11439.828402574134, 11792.23350482216, 11855.76288474243, 11876.82184767212, 8060.637271322922, 8105.675772969015, 8141.22658347456, 8176.46852954556, 8205.933228680233, 9056.501892395981, 11088.722781930517, 11327.093832493216, 11385.772284554429, 11501.82747258775], "value": 8922.81754888625, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1167.638032210015, 1385.0166381868873, 1406.725688490744, 1409.6334952912252, 1412.5550451607742, 7908.406869484783, 8250.711225107107, 8312.8753068704, 8320.459170153936, 8341.499510029402, 7302.233265507125, 7597.08834939808, 7609.657565409556, 8013.559254241029, 8262.173782621734, 1931.671615825306, 2246.1576908659254, 2284.9543009139807, 2291.301153148289, 2293.850899691523, 2282.052948026567, 2285.702858628376, 2308.6883270758485, 2322.256877799105, 2325.6744055832196, 1817.7455156278131, 1983.2463663807732, 2056.897155142244, 2235.4634887627017, 2288.3592594073684, 2272.3296627494847, 2277.6697378880135, 2277.8861056947085, 2279.2364557873134, 2281.475260690034, 2250.7411647660565, 2273.45437095096, 2276.8064714499287, 2284.0292875527834, 2287.874166920819, 8206.876896846552, 8253.876191857127, 8276.0, 8281.54451505167, 8282.13037631046, 4695.530446955311, 4796.400449943757, 4913.901721965576, 5105.795768169269, 5123.795048198068, 3174.3175217328385, 3336.4494858348344, 3411.1131105912395, 3420.58952925648, 3422.435298175798, 1153.2446247707765, 1230.686175025363, 1242.19257482637, 1699.5496193508607, 1744.520256929342, 10348.896511034869, 10523.842142367794, 10647.30792498485, 10647.680569582848, 10649.946750266277, 4337.349397590324, 4463.866084017484, 4507.143642707875, 4581.060882519076, 4583.197940360424, 9305.9526904081, 9321.44071355721, 9322.1610055094, 9349.345545811864, 9368.0, 5007.69953802773, 5117.948820511747, 5136.075506408883, 5136.512031357015, 5152.3044389007, 4049.5545489996075, 4163.084121493267, 4293.3989241506215, 4617.168909596305, 4690.929636055462, 3234.5633339499336, 3677.4922281864306, 3772.434134879735, 3809.4095415210686, 3845.3270677631863, 1230.4831970572382, 1249.5314257153568, 1253.2918900821007, 1263.7219811641423, 1274.1908887856277, 862.7411776467035, 873.6723728601775, 875.5403413207989, 889.6619284671913, 927.2396634759433, 5443.129099344086, 5577.944220557823, 5599.468050535193, 5606.579506537026, 5870.6771127587535, 807.8263173417744, 859.1022381611232, 899.8605216191501, 902.2136170382679, 919.9310051746146, 3542.9017004728103, 3609.6117787331914, 3708.332500149999, 3730.1047748540695, 3809.561900381492, 9373.20327772147, 9649.276304277208, 9847.310688251897, 9931.155851752683, 9946.452945087945, 9762.804743905082, 9907.45508997012, 9909.702708918743, 9911.10800028001, 9942.552585133686, 7481.812954676177, 7612.0, 7652.540847549053, 7759.72840950564, 7785.688572457151, 5255.868603284948, 5363.0, 5374.086405311028, 5422.250542623285, 5521.116621340566, 9153.17621414076, 9223.58493867791, 9256.305777066882, 9258.768530786785, 9261.583228754587, 7751.302382785577, 9084.727458176265, 9133.269338453041, 9274.0, 9332.626694932127, 6599.571027883172, 6647.302033286435, 6692.196936367667, 6752.426043786333, 6803.795886123424, 7710.383169346326, 7792.4545281832, 7799.415043871619, 7813.804654883674, 7830.2952734254195, 6889.173299204128, 7075.716971321295, 7099.432045436456, 7180.138383394026, 7235.312645298688, 6638.900416493853, 6738.696758645775, 6864.176298844171, 6919.446444284546, 6960.199577048705, 5979.013462778593, 6163.9383606163365, 6219.533534984804, 6235.688215589294, 6287.654179020014, 4619.538046195354, 4689.288409730492, 4697.6711630185555, 4713.2223183175765, 4842.273658951185, 4670.0, 4774.283857421415, 4792.329073929583, 4804.015176888799, 4822.107910036654], "value": 4322.556184079576, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [350.93683137035333, 356.8019749039282, 363.26620227141166, 373.57412549693356, 376.4259504256011, 4903.362562866827, 5219.42586315505, 5251.317328747262, 5287.0, 5314.548263397606, 4512.592992290816, 4607.9308810367875, 4881.8291359802415, 5047.939932614156, 5229.477052294768, 4020.2361551305303, 4053.196327634204, 4083.1629515949235, 4083.816228269735, 4089.386592011207, 3598.478220658003, 3653.0319465341704, 3798.2593394288124, 4031.536373317062, 4043.8180281887385, 3351.530785689993, 3674.1549443627846, 3864.613538646141, 4028.7179897407195, 4103.692223083266, 3859.3824988001925, 3907.98046009771, 3932.2528719543197, 3935.6457918787446, 3940.9359472942147, 2853.4007858349773, 3403.353362861073, 3405.5062016007787, 3407.3185362927265, 3429.262708517656, 5065.366829146356, 5071.239314102877, 5072.695638261716, 5076.517730815566, 5086.21163719624, 3473.4616134499192, 4108.5143488189515, 4397.384366188736, 4480.6191473724775, 4530.275155975029, 4784.435595481354, 4812.759362031885, 5145.948540514584, 5420.268263784378, 5437.586227580858, 3224.967750322514, 3304.983475082633, 3725.3480640887738, 3725.6646901778972, 3728.9813550932345, 5027.773750181251, 5130.74296797287, 5629.860652951893, 5651.6043876928225, 5663.915041274384, 3141.5601815745813, 3209.759268054905, 3343.414902392072, 3780.281746468162, 3793.6615778115083, 3371.685042833297, 3708.6662200402093, 3840.807959602009, 3844.308024555607, 3850.4994350734223, 4437.001674623249, 4462.866114016585, 4464.55354464551, 4493.0, 4501.7974191161475, 4512.796924138422, 4982.878852258285, 5385.649932754359, 5426.593005524602, 5452.618316717871, 1240.6402143378318, 1241.9068569857297, 1253.035162924543, 1254.404158024931, 1259.641002314336, 788.7200043984418, 814.5479259011306, 823.8558252305822, 849.5048714262941, 857.7426771968385, 4909.263610458388, 4942.4316203637045, 4961.677490963076, 5012.54887060159, 5012.749362531858, 5317.73411329432, 5324.094903866352, 5328.440513746001, 5333.386660534011, 5359.08895487768, 4590.65495207665, 4697.6711630185555, 4774.570288674036, 4814.378839286562, 4828.420589529279, 2467.802575793932, 2548.2610043087666, 2595.9610605840926, 2649.205238428464, 2801.971980280212, 4940.456549779521, 5089.3638295213095, 5411.485908838654, 5474.863128421821, 5528.612997090165, 5266.7366631668265, 5442.700651464128, 5716.256886604798, 5745.195672605839, 5765.85585360361, 4460.361224326729, 5271.894562108813, 5741.8564535886935, 5787.218725472091, 5791.855203619859, 5221.712805795718, 5535.6955367455175, 5661.490465858093, 5667.574931880208, 5670.631408958405, 5244.816431424882, 5473.781048758005, 5514.834554963358, 5526.866992266495, 5541.639793413416, 4667.439907211021, 4838.709677419225, 5784.913226301693, 5795.304563452412, 5802.912956305574, 3201.183698156955, 3591.9281614368088, 3761.5862255151364, 3808.695304375588, 3823.369144091194, 5141.640085194001, 5560.860978475571, 5574.721263936706, 5584.357798853184, 5647.887042259214, 4721.173794585865, 5322.019668985381, 5430.8099216527235, 5482.5888058394985, 5488.396276409512, 5133.256717561507, 5399.811006614749, 5429.5656347492895, 5470.674963140729, 5475.425080366664, 5082.246624914399, 5300.734963251899, 5304.469553044664, 5317.787288508416, 5325.7070861101465, 4519.728816271035, 4521.7165331508695, 5324.840254792362, 5330.920940833155, 5404.703001224542, 4920.45874953762, 5247.265382846328, 5310.097283461898, 5355.678659280456, 5365.087935051129], "value": 3951.6713953443136, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [698.5424546921766, 703.9917620091985, 741.4624397311946, 766.6051983228638, 801.430984001359, 14492.927535362322, 15530.53408397747, 15540.689186216268, 15587.220638968063, 15638.374465021414, 13836.515721949734, 13971.441142354319, 13999.65000874976, 14007.229602371866, 14016.579502614912, 679.3715812873097, 700.7792545348225, 851.9701810436636, 880.3221519430035, 880.7974165941837, 695.4644923408972, 843.2832092721169, 871.7341210930664, 873.1399571422144, 879.8328317619632, 906.8821053263066, 920.1258804136064, 926.6478738079554, 927.3740225347864, 929.6699671616581, 862.2369203255101, 909.8180363927174, 914.7667344827094, 915.4095608332622, 915.771057235691, 533.4931814775979, 548.3419896124626, 664.5912763650368, 691.2638040486916, 693.414065114977, 14647.0, 14679.266036698122, 14685.118892866461, 14700.485483008044, 14735.778963315559, 13108.934455327757, 13266.071375003754, 13352.465901363934, 13367.532136375181, 13371.06402551812, 12198.939005305005, 12254.448549815279, 12321.012653177266, 12409.75180496394, 12579.937100314532, 4295.91408171838, 4343.522212556616, 4374.803133858953, 4419.0940857124215, 4436.866893993185, 95019.04980950171, 97163.0, 97752.51123744407, 98019.50990245075, 98909.5163572547, 10194.43930583825, 10510.737231569272, 10511.211659125596, 10525.263231573716, 10538.89461105379, 20823.479413014797, 20946.895265523573, 21122.788772112388, 21225.575488490453, 21242.681359779617, 6978.302169782981, 7287.672054757549, 7453.366463850633, 7465.06686664167, 7648.541087534654, 4182.790860456965, 4192.790360481964, 4235.02594403289, 4236.894077648022, 4316.352547117895, 3262.7389808815237, 3273.1162586102096, 3349.112485191426, 3352.0614228016193, 3353.6646335366268, 537.6558603491272, 563.9633423827438, 564.2101058518108, 571.3714913595095, 587.0372588954033, 630.9432151106422, 663.7432728722382, 664.3223911610131, 670.4234358451728, 688.7189826921921, 9686.515674216402, 9860.753481162885, 10201.234907381977, 10205.0, 10237.897621023843, 542.9131338985743, 572.5734327925722, 574.9971250143806, 594.9881002379929, 674.1202730436827, 3470.600880898729, 3502.754807163525, 4317.633001194871, 4376.168527979674, 4377.0, 79583.43957324045, 85124.0, 94175.0, 95199.0, 96653.0, 74661.5067698643, 76056.0, 76622.0, 86010.56994715112, 88667.66998495028, 71795.92306115518, 72301.0, 72866.0, 73213.63393183, 74810.0, 9502.952485237529, 9712.223022158068, 9722.368046076983, 9730.270229732656, 9731.0, 49543.50456495389, 50238.99522009613, 50837.23744143767, 51331.0, 51918.48081519137, 53521.0, 63339.683301583194, 64286.357136428036, 64653.67673161604, 65010.0, 12231.938840305742, 12456.252624842176, 12474.313912734886, 12543.184692994926, 13041.869581304065, 14288.28558572088, 14376.065555738844, 14532.346044427815, 14546.490872819824, 14864.0, 12860.614181574567, 13163.407646656115, 13165.868341316464, 13313.600591982256, 13357.198568085945, 13000.479980801041, 13411.530596429078, 13568.660783480493, 13866.237356945465, 13952.511662091778, 12718.427670755023, 13239.867601323864, 13325.400356984153, 13382.732345353234, 13460.192388456724, 10393.688189354332, 10759.87021362777, 11000.779984400107, 11048.944755276172, 11244.381559014333, 8762.430442021248, 10555.736106597396, 10932.706482330062, 11480.655580332603, 11600.30398176112], "value": 7251.177311649076, "unit": "score"}], "name": "dromaeo_css", "value": 7009.461474894196, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 114.0, 113.0, 114.0, 110.0, 105.0, 112.0, 113.0, 112.0], "value": 112.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 111.0, 110.0, 146.0, 111.0, 109.0, 112.0, 109.0, 131.0, 109.0], "value": 119.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 185.0, 184.0, 185.0, 183.0, 184.0, 181.0, 181.0, 184.0, 184.0], "value": 183.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 58.0, 59.0, 86.0, 61.0, 59.0, 57.0, 58.0, 92.0, 83.0], "value": 69.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 69.0, 86.0, 70.0, 69.0, 68.0, 84.0, 68.0, 69.0, 83.0], "value": 75.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 88.0, 85.0, 87.0, 84.0, 88.0, 86.0, 89.0, 84.0], "value": 86.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 147.0, 93.0, 94.0, 93.0, 93.0, 93.0, 98.0, 94.0, 94.0], "value": 99.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 87.0, 87.0, 85.0, 85.0, 85.0, 87.0, 85.0, 86.0, 88.0], "value": 86.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 57.0, 58.0, 56.0, 57.0, 57.0, 54.0, 54.0, 55.0, 56.0], "value": 55.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 70.0, 75.0, 69.0, 76.0, 69.0, 69.0, 69.0, 71.0, 71.0], "value": 70.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 90.0, 88.0, 87.0, 86.0, 85.0, 88.0, 90.0, 90.0, 92.0], "value": 88.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 138.0, 150.0, 141.0, 157.0, 149.0, 143.0, 138.0, 186.0, 151.0], "value": 149.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 170.0, 173.0, 170.0, 170.0, 172.0, 169.0, 174.0, 175.0, 238.0], "value": 178.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 70.0, 67.0, 69.0, 67.0, 68.0, 66.0, 66.0, 66.0], "value": 67.3, "unit": "score"}], "name": "kraken", "value": 1441.2, "alertThreshold": 2.0}]} 11:58:47 INFO - Return code: 0 11:58:47 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:58:47 INFO - # TBPL SUCCESS # 11:58:47 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:58:47 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:58:47 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:58:47 INFO - 2017-01-02 11:58:47.354 screenresolution[1656:11492] starting screenresolution argv=screenresolution get 11:58:47 INFO - 2017-01-02 11:58:47.357 screenresolution[1656:11492] Display 0: 1600x1200x32@60 11:58:47 INFO - 2017-01-02 11:58:47.363 screenresolution[1657:11495] starting screenresolution argv=screenresolution list 11:58:47 INFO - Available Modes on Display 0 11:58:47 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:58:47 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:58:47 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:58:47 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:58:47 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:58:47 INFO - Intel Iris: 11:58:47 INFO - Chipset Model: Intel Iris 11:58:47 INFO - Type: GPU 11:58:47 INFO - Bus: Built-In 11:58:47 INFO - VRAM (Dynamic, Max): 1536 MB 11:58:47 INFO - Vendor: Intel (0x8086) 11:58:47 INFO - Device ID: 0x0a2e 11:58:47 INFO - Revision ID: 0x0009 11:58:47 INFO - Displays: 11:58:47 INFO - Display: 11:58:47 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:58:47 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:58:47 INFO - Display Serial Number: 1600x1200 60 11:58:47 INFO - Main Display: Yes 11:58:47 INFO - Mirror: Off 11:58:47 INFO - Online: Yes 11:58:47 INFO - Rotation: Supported 11:58:47 INFO - Return code: 0 11:58:47 INFO - Running post-action listener: _resource_record_post_action 11:58:47 INFO - [mozharness: 2017-01-02 19:58:47.606094Z] Finished run-tests step (success) 11:58:47 INFO - Running post-run listener: _resource_record_post_run 11:58:47 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 33344512; Write bytes: 549780992; Read time: 311; Write time: 1597 11:58:47 INFO - TinderboxPrint: CPU usage
53.8% 11:58:47 INFO - TinderboxPrint: I/O read bytes / time
33,344,512 / 311 11:58:47 INFO - TinderboxPrint: I/O write bytes / time
549,780,992 / 1,597 11:58:47 INFO - TinderboxPrint: CPU idle
2,223.8 (46.2%) 11:58:47 INFO - TinderboxPrint: CPU system
962.9 (20.0%) 11:58:47 INFO - TinderboxPrint: CPU user
1,623.0 (33.7%) 11:58:47 INFO - TinderboxPrint: Swap in / out
278,298,624 / 0 11:58:47 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 247874048; Write bytes: 242414592; Read time: 18311; Write time: 454 11:58:47 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 28929024; Write bytes: 295901696; Read time: 276; Write time: 1106 11:58:48 INFO - Running post-run listener: _upload_blobber_files 11:58:48 INFO - Blob upload gear active. 11:58:48 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:58:48 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 11:58:48 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'] 11:58:48 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 11:58:48 INFO - (blobuploader) - INFO - Open directory for files ... 11:58:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:58:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:58:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:58:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:58:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:58:49 INFO - (blobuploader) - INFO - Done attempting. 11:58:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:58:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:58:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:58:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:58:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:58:50 INFO - (blobuploader) - INFO - Done attempting. 11:58:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 11:58:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:58:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:58:51 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 11:58:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:58:51 INFO - (blobuploader) - INFO - Done attempting. 11:58:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:58:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:58:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:58:54 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:58:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:58:54 INFO - (blobuploader) - INFO - Done attempting. 11:58:54 INFO - (blobuploader) - INFO - Iteration through files over. 11:58:54 INFO - Return code: 0 11:58:54 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:58:54 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:58:54 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2f9ed8ef45f1b0b3538eafa8aa8826de67ea0b72ccb22cf5bfa7a14aa4bcbd99cf871b6d005705d198fee9fff2f0cf012aa16826c9cdce325f4573ba9785a746", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/18f8f57cd1f4ccae510b52ce2131b3a26871f6bd208326721e4f3b1dcb2df3250c72dd215fbe1a3b83e588aafb00742e83a60750015674444d41e39a72cbc70a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bbddc687b6ec3a07a307fa34a7f38fd3867ef88b936dfbe84a87118c67676f9cc1ceee58d451957bd42a347a2ef46ea6e93b275bfd1262967c5cc8e3f0f23424", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/220cf3edf98c9e91fe75ce28191a44f3dceaaf8032a319352dbccb57c1e5d64dc7b14bd6ca6997d636824298260b9c733e796afbad60aacdce35408b38eacf7c"} 11:58:54 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:58:54 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:58:54 INFO - Contents: 11:58:54 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2f9ed8ef45f1b0b3538eafa8aa8826de67ea0b72ccb22cf5bfa7a14aa4bcbd99cf871b6d005705d198fee9fff2f0cf012aa16826c9cdce325f4573ba9785a746", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/18f8f57cd1f4ccae510b52ce2131b3a26871f6bd208326721e4f3b1dcb2df3250c72dd215fbe1a3b83e588aafb00742e83a60750015674444d41e39a72cbc70a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bbddc687b6ec3a07a307fa34a7f38fd3867ef88b936dfbe84a87118c67676f9cc1ceee58d451957bd42a347a2ef46ea6e93b275bfd1262967c5cc8e3f0f23424", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/220cf3edf98c9e91fe75ce28191a44f3dceaaf8032a319352dbccb57c1e5d64dc7b14bd6ca6997d636824298260b9c733e796afbad60aacdce35408b38eacf7c"} 11:58:54 INFO - Running post-run listener: copy_logs_to_upload_dir 11:58:54 INFO - Copying logs to upload dir... 11:58:54 INFO - mkdir: /builds/slave/test/build/upload/logs 11:58:54 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.059001 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2017-01-02 11:58:54.098809) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-02 11:58:54.101986) ========= 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.CIafmlgHF2/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.mlBm1gz2N1/Listeners TMPDIR=/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2f9ed8ef45f1b0b3538eafa8aa8826de67ea0b72ccb22cf5bfa7a14aa4bcbd99cf871b6d005705d198fee9fff2f0cf012aa16826c9cdce325f4573ba9785a746", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/18f8f57cd1f4ccae510b52ce2131b3a26871f6bd208326721e4f3b1dcb2df3250c72dd215fbe1a3b83e588aafb00742e83a60750015674444d41e39a72cbc70a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bbddc687b6ec3a07a307fa34a7f38fd3867ef88b936dfbe84a87118c67676f9cc1ceee58d451957bd42a347a2ef46ea6e93b275bfd1262967c5cc8e3f0f23424", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/220cf3edf98c9e91fe75ce28191a44f3dceaaf8032a319352dbccb57c1e5d64dc7b14bd6ca6997d636824298260b9c733e796afbad60aacdce35408b38eacf7c"} build_url:https://queue.taskcluster.net/v1/task/LjayOvF_SJeQaMuQReZttw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010643 build_url: 'https://queue.taskcluster.net/v1/task/LjayOvF_SJeQaMuQReZttw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2f9ed8ef45f1b0b3538eafa8aa8826de67ea0b72ccb22cf5bfa7a14aa4bcbd99cf871b6d005705d198fee9fff2f0cf012aa16826c9cdce325f4573ba9785a746", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/18f8f57cd1f4ccae510b52ce2131b3a26871f6bd208326721e4f3b1dcb2df3250c72dd215fbe1a3b83e588aafb00742e83a60750015674444d41e39a72cbc70a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bbddc687b6ec3a07a307fa34a7f38fd3867ef88b936dfbe84a87118c67676f9cc1ceee58d451957bd42a347a2ef46ea6e93b275bfd1262967c5cc8e3f0f23424", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/220cf3edf98c9e91fe75ce28191a44f3dceaaf8032a319352dbccb57c1e5d64dc7b14bd6ca6997d636824298260b9c733e796afbad60aacdce35408b38eacf7c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-02 11:58:54.136563) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-02 11:58:54.136950) ========= 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.CIafmlgHF2/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.mlBm1gz2N1/Listeners TMPDIR=/var/folders/_6/_bx65ff90k958brrpkt4nbpw00000w/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.005014 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-02 11:58:54.186535) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-02 11:58:54.186868) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-02 11:58:54.187200) ========= ========= Total master_lag: 0.18 =========