builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0111 starttime: 1488846326.82 results: success (0) buildid: 20170306150532 builduid: 5240828b34a84cc48708f19f61b62496 revision: 0931190cd725a744b74f8f47863b7b38be8e8ebf ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-06 16:25:26.822357) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-06 16:25:26.823067) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-06 16:25:26.823422) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-06 16:25:26.877215) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-06 16:25:26.877626) ========= 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.tfI0DFnF2K/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.nLWClWbWlC/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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-03-06 16:25:26-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 10.6M=0.001s 2017-03-06 16:25:27 (10.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.147745 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-06 16:25:27.070180) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-06 16:25:27.070556) ========= 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.tfI0DFnF2K/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.nLWClWbWlC/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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.027173 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-06 16:25:27.124634) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-03-06 16:25:27.125378) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 0931190cd725a744b74f8f47863b7b38be8e8ebf --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 0931190cd725a744b74f8f47863b7b38be8e8ebf --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.tfI0DFnF2K/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.nLWClWbWlC/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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-03-06 16:25:27,228 truncating revision to first 12 chars 2017-03-06 16:25:27,229 Setting DEBUG logging. 2017-03-06 16:25:27,229 attempt 1/10 2017-03-06 16:25:27,229 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/0931190cd725?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-06 16:25:28,945 unpacking tar archive at: mozilla-aurora-0931190cd725/testing/mozharness/ program finished with exit code 0 elapsedTime=2.008149 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-03-06 16:25:29.162285) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-06 16:25:29.163073) ========= script_repo_revision: 0931190cd725a744b74f8f47863b7b38be8e8ebf ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-06 16:25:29.164026) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-06 16:25:29.164694) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-06 16:25:29.190996) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 25 secs) (at 2017-03-06 16:25:29.191881) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.tfI0DFnF2K/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.nLWClWbWlC/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 16:25:29 INFO - MultiFileLogger online at 20170306 16:25:29 in /builds/slave/test 16:25:29 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 16:25:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:25:29 INFO - {'append_to_log': False, 16:25:29 INFO - 'base_work_dir': '/builds/slave/test', 16:25:29 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 16:25:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:25:29 INFO - 'branch': 'Mozilla-Aurora', 16:25:29 INFO - 'buildbot_json_path': 'buildprops.json', 16:25:29 INFO - 'config_files': ('talos/mac_config.py',), 16:25:29 INFO - 'default_actions': ('clobber', 16:25:29 INFO - 'read-buildbot-config', 16:25:29 INFO - 'download-and-extract', 16:25:29 INFO - 'populate-webroot', 16:25:29 INFO - 'create-virtualenv', 16:25:29 INFO - 'install', 16:25:29 INFO - 'run-tests'), 16:25:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:25:29 INFO - 'download_minidump_stackwalk': True, 16:25:29 INFO - 'download_symbols': 'ondemand', 16:25:29 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:25:29 INFO - 'tooltool.py': '/tools/tooltool.py', 16:25:29 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:25:29 INFO - '/tools/misc-python/virtualenv.py')}, 16:25:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:25:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:25:29 INFO - 'gecko_profile': False, 16:25:29 INFO - 'gecko_profile_interval': 0, 16:25:29 INFO - 'installer_path': 'installer.exe', 16:25:29 INFO - 'log_level': 'info', 16:25:29 INFO - 'log_name': 'talos', 16:25:29 INFO - 'log_to_console': True, 16:25:29 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:25:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:25:29 INFO - 'opt_config_files': (), 16:25:29 INFO - 'pip_index': False, 16:25:29 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:25:29 INFO - 'cmd': ('bash', 16:25:29 INFO - '-c', 16:25:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:25:29 INFO - 'enabled': True, 16:25:29 INFO - 'halt_on_failure': False, 16:25:29 INFO - 'name': 'check_screen_resolution'},), 16:25:29 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:25:29 INFO - 'cmd': ('bash', 16:25:29 INFO - '-c', 16:25:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:25:29 INFO - 'enabled': True, 16:25:29 INFO - 'halt_on_failure': False, 16:25:29 INFO - 'name': 'check_screen_resolution'},), 16:25:29 INFO - 'run_cmd_checks_enabled': True, 16:25:29 INFO - 'suite': 'dromaeojs-e10s', 16:25:29 INFO - 'system_bits': '32', 16:25:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:25:29 INFO - 'title': 't-yosemite-r7-0111', 16:25:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:25:29 INFO - 'use_talos_json': True, 16:25:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:25:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:25:29 INFO - 'work_dir': 'build'} 16:25:29 INFO - [mozharness: 2017-03-07 00:25:29.411394Z] Running clobber step. 16:25:29 INFO - Running pre-action listener: _resource_record_pre_action 16:25:29 INFO - Running main action method: clobber 16:25:29 INFO - rmtree: /builds/slave/test/build 16:25:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:25:31 INFO - Running post-action listener: _resource_record_post_action 16:25:31 INFO - [mozharness: 2017-03-07 00:25:31.924314Z] Finished clobber step (success) 16:25:31 INFO - [mozharness: 2017-03-07 00:25:31.924446Z] Running read-buildbot-config step. 16:25:31 INFO - Running pre-action listener: _resource_record_pre_action 16:25:31 INFO - Running main action method: read_buildbot_config 16:25:31 INFO - Using buildbot properties: 16:25:31 INFO - { 16:25:31 INFO - "project": "", 16:25:31 INFO - "product": "firefox", 16:25:31 INFO - "script_repo_revision": "production", 16:25:31 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 16:25:31 INFO - "repository": "", 16:25:31 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 16:25:31 INFO - "buildid": "20170306150532", 16:25:31 INFO - "pgo_build": "False", 16:25:31 INFO - "basedir": "/builds/slave/test", 16:25:31 INFO - "buildnumber": 59, 16:25:31 INFO - "slavename": "t-yosemite-r7-0111", 16:25:31 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 16:25:31 INFO - "platform": "yosemite_r7", 16:25:31 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 16:25:31 INFO - "branch": "mozilla-aurora", 16:25:31 INFO - "repo_path": "releases/mozilla-aurora", 16:25:31 INFO - "revision": "0931190cd725a744b74f8f47863b7b38be8e8ebf", 16:25:31 INFO - "stage_platform": "macosx64", 16:25:31 INFO - "builduid": "5240828b34a84cc48708f19f61b62496", 16:25:31 INFO - "slavebuilddir": "test" 16:25:31 INFO - } 16:25:31 INFO - Found installer url https://queue.taskcluster.net/v1/task/I6LWlIepTaqY9F-11WQl6Q/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 16:25:31 INFO - Running post-action listener: _resource_record_post_action 16:25:31 INFO - [mozharness: 2017-03-07 00:25:31.927727Z] Finished read-buildbot-config step (success) 16:25:31 INFO - [mozharness: 2017-03-07 00:25:31.927843Z] Running download-and-extract step. 16:25:31 INFO - Running pre-action listener: _resource_record_pre_action 16:25:31 INFO - Running main action method: download_and_extract 16:25:31 INFO - mkdir: /builds/slave/test/build/tests 16:25:31 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')]} 16:25:31 INFO - trying https://queue.taskcluster.net/v1/task/I6LWlIepTaqY9F-11WQl6Q/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 16:25:31 INFO - Downloading https://queue.taskcluster.net/v1/task/I6LWlIepTaqY9F-11WQl6Q/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 16:25:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/I6LWlIepTaqY9F-11WQl6Q/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json'}, attempt #1 16:25:32 INFO - Downloaded 1199 bytes. 16:25:32 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 16:25:32 INFO - Using the following test package requirements: 16:25:32 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 16:25:32 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 16:25:32 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 16:25:32 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 16:25:32 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 16:25:32 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 16:25:32 INFO - u'jsshell-mac.zip'], 16:25:32 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 16:25:32 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 16:25:32 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 16:25:32 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 16:25:32 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 16:25:32 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 16:25:32 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 16:25:32 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 16:25:32 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 16:25:32 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 16:25:32 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 16:25:32 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 16:25:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/I6LWlIepTaqY9F-11WQl6Q/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 16:25:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/I6LWlIepTaqY9F-11WQl6Q/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 16:25:32 INFO - Fetch https://queue.taskcluster.net/v1/task/I6LWlIepTaqY9F-11WQl6Q/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 16:25:34 INFO - Expected file size: 51782530 16:25:34 INFO - Obtained file size: 51782530 16:25:50 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip', u'firefox-54.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 16:25:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/I6LWlIepTaqY9F-11WQl6Q/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 16:25:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/I6LWlIepTaqY9F-11WQl6Q/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 16:25:50 INFO - Fetch https://queue.taskcluster.net/v1/task/I6LWlIepTaqY9F-11WQl6Q/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 16:25:52 INFO - Expected file size: 51782530 16:25:52 INFO - Obtained file size: 51782530 16:26:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/I6LWlIepTaqY9F-11WQl6Q/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 16:26:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/I6LWlIepTaqY9F-11WQl6Q/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 16:26:07 INFO - Fetch https://queue.taskcluster.net/v1/task/I6LWlIepTaqY9F-11WQl6Q/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 16:26:08 INFO - Expected file size: 14404424 16:26:08 INFO - Obtained file size: 14404424 16:26:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:26:09 INFO - trying https://queue.taskcluster.net/v1/task/I6LWlIepTaqY9F-11WQl6Q/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 16:26:09 INFO - Downloading https://queue.taskcluster.net/v1/task/I6LWlIepTaqY9F-11WQl6Q/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 16:26:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/I6LWlIepTaqY9F-11WQl6Q/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg'}, attempt #1 16:26:11 INFO - Downloaded 74612273 bytes. 16:26:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/I6LWlIepTaqY9F-11WQl6Q/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 16:26:11 INFO - mkdir: /builds/slave/test/properties 16:26:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:26:11 INFO - Writing to file /builds/slave/test/properties/build_url 16:26:11 INFO - Contents: 16:26:11 INFO - build_url:https://queue.taskcluster.net/v1/task/I6LWlIepTaqY9F-11WQl6Q/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 16:26:11 INFO - Running post-action listener: _resource_record_post_action 16:26:11 INFO - Running post-action listener: set_extra_try_arguments 16:26:11 INFO - [mozharness: 2017-03-07 00:26:11.947962Z] Finished download-and-extract step (success) 16:26:11 INFO - [mozharness: 2017-03-07 00:26:11.948108Z] Running populate-webroot step. 16:26:11 INFO - Running pre-action listener: _resource_record_pre_action 16:26:11 INFO - Running main action method: populate_webroot 16:26:11 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:26:11 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:26:11 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:26:11 INFO - '--tppagecycles', 16:26:11 INFO - '7'], 16:26:11 INFO - 'tests': ['tsvgm']}}, 16:26:11 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:26:11 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:26:11 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:26:11 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:26:11 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:26:11 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:26:11 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 16:26:11 INFO - 'talos_options': ['--disable-e10s'], 16:26:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:26:11 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:26:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:26:11 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 16:26:11 INFO - 'talos_options': ['--disable-e10s'], 16:26:11 INFO - 'tests': ['damp', 'tps']}, 16:26:11 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:26:11 INFO - 'tests': ['damp', 'tps']}, 16:26:11 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:26:11 INFO - 'tests': ['dromaeo_dom']}, 16:26:11 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:26:11 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:26:11 INFO - 'tests': ['basic_compositor_video']}, 16:26:11 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:26:11 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:26:11 INFO - 'tests': ['a11yr', 16:26:11 INFO - 'ts_paint', 16:26:11 INFO - 'tpaint', 16:26:11 INFO - 'sessionrestore', 16:26:11 INFO - 'sessionrestore_no_auto_restore', 16:26:11 INFO - 'tabpaint']}, 16:26:11 INFO - 'other-e10s': {'tests': ['a11yr', 16:26:11 INFO - 'ts_paint', 16:26:11 INFO - 'tpaint', 16:26:11 INFO - 'sessionrestore', 16:26:11 INFO - 'sessionrestore_no_auto_restore', 16:26:11 INFO - 'tabpaint']}, 16:26:11 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:26:11 INFO - 'ts_paint', 16:26:11 INFO - 'tpaint', 16:26:11 INFO - 'sessionrestore', 16:26:11 INFO - 'sessionrestore_no_auto_restore', 16:26:11 INFO - 'tabpaint']}, 16:26:11 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:26:11 INFO - 'ts_paint', 16:26:11 INFO - 'tpaint', 16:26:11 INFO - 'sessionrestore', 16:26:11 INFO - 'sessionrestore_no_auto_restore', 16:26:11 INFO - 'tabpaint']}, 16:26:11 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:26:11 INFO - 'tests': ['a11yr', 16:26:11 INFO - 'ts_paint', 16:26:11 INFO - 'tpaint', 16:26:11 INFO - 'sessionrestore', 16:26:11 INFO - 'sessionrestore_no_auto_restore', 16:26:11 INFO - 'tabpaint']}, 16:26:11 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:26:11 INFO - 'tests': ['a11yr', 16:26:11 INFO - 'ts_paint', 16:26:11 INFO - 'tpaint', 16:26:11 INFO - 'sessionrestore', 16:26:11 INFO - 'sessionrestore_no_auto_restore', 16:26:11 INFO - 'tabpaint']}, 16:26:11 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:26:11 INFO - 'tests': ['tsvgx', 16:26:11 INFO - 'tsvgr_opacity', 16:26:11 INFO - 'tart', 16:26:11 INFO - 'tscrollx', 16:26:11 INFO - 'cart', 16:26:11 INFO - 'tsvg_static']}, 16:26:11 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:26:11 INFO - 'tsvgr_opacity', 16:26:11 INFO - 'tart', 16:26:11 INFO - 'tscrollx', 16:26:11 INFO - 'cart', 16:26:11 INFO - 'tsvg_static']}, 16:26:11 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 16:26:11 INFO - 'talos_options': ['--disable-e10s'], 16:26:11 INFO - 'tests': ['tp5o']}, 16:26:11 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:26:11 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 16:26:11 INFO - 'talos_options': ['--disable-e10s', 16:26:11 INFO - '--xperf_path', 16:26:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:26:11 INFO - 'tests': ['tp5n']}, 16:26:11 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:26:11 INFO - 'talos_options': ['--xperf_path', 16:26:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:26:11 INFO - 'tests': ['tp5n']}}, 16:26:11 INFO - 'talos.zip': {'path': '', 16:26:11 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:26:11 INFO - Running post-action listener: _resource_record_post_action 16:26:11 INFO - [mozharness: 2017-03-07 00:26:11.966896Z] Finished populate-webroot step (success) 16:26:11 INFO - [mozharness: 2017-03-07 00:26:11.967033Z] Running create-virtualenv step. 16:26:11 INFO - Running pre-action listener: _resource_record_pre_action 16:26:11 INFO - Running main action method: create_virtualenv 16:26:11 INFO - Creating virtualenv /builds/slave/test/build/venv 16:26: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 16:26:11 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:26:12 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:26:12 INFO - Using real prefix '/tools/python27' 16:26:12 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:26:12 INFO - Installing distribute.............................................................................................................................................................................................done. 16:26:15 INFO - Installing pip.................done. 16:26:15 INFO - Return code: 0 16:26:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:26:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:26:15 INFO - Reading from file tmpfile_stdout 16:26:15 INFO - Output received: 16:26: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) 16:26:15 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:26: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')]} 16:26:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:26:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:26: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')]} 16:26:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:26:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:26: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 0x10e97bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e97e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2514cf2f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e759d30>, '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 0x10e680db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e74ebe8>, '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.tfI0DFnF2K/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.nLWClWbWlC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 16:26: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 16:26: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 16:26:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tfI0DFnF2K/Render', 16:26:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:26:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:26:15 INFO - 'HOME': '/Users/cltbld', 16:26:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:26:15 INFO - 'LOGNAME': 'cltbld', 16:26:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:26:15 INFO - 'MOZ_NO_REMOTE': '1', 16:26:15 INFO - 'NO_EM_RESTART': '1', 16:26:15 INFO - 'PAGER': '/bin/cat', 16:26:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:26:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:26:15 INFO - 'PWD': '/builds/slave/test', 16:26:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:26:15 INFO - 'SHELL': '/bin/bash', 16:26:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nLWClWbWlC/Listeners', 16:26:15 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 16:26:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:26:15 INFO - 'USER': 'cltbld', 16:26:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:26:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:26:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:26:15 INFO - 'XPC_FLAGS': '0x0', 16:26:15 INFO - 'XPC_SERVICE_NAME': '0', 16:26:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:26:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:26:16 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 16:26:16 INFO - Cleaning up... 16:26:16 INFO - Return code: 0 16:26:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:26:16 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')]} 16:26:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:26:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:26:16 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')]} 16:26:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:26:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:26:16 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 0x10e97bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e97e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2514cf2f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e759d30>, '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 0x10e680db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e74ebe8>, '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.tfI0DFnF2K/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.nLWClWbWlC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 16:26:16 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 16:26:16 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 16:26:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tfI0DFnF2K/Render', 16:26:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:26:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:26:16 INFO - 'HOME': '/Users/cltbld', 16:26:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:26:16 INFO - 'LOGNAME': 'cltbld', 16:26:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:26:16 INFO - 'MOZ_NO_REMOTE': '1', 16:26:16 INFO - 'NO_EM_RESTART': '1', 16:26:16 INFO - 'PAGER': '/bin/cat', 16:26:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:26:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:26:16 INFO - 'PWD': '/builds/slave/test', 16:26:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:26:16 INFO - 'SHELL': '/bin/bash', 16:26:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nLWClWbWlC/Listeners', 16:26:16 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 16:26:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:26:16 INFO - 'USER': 'cltbld', 16:26:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:26:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:26:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:26:16 INFO - 'XPC_FLAGS': '0x0', 16:26:16 INFO - 'XPC_SERVICE_NAME': '0', 16:26:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:26:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:26:16 INFO - Downloading/unpacking psutil>=3.1.1 16:26:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:26:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:26:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:26:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:26:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:26:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:26:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:26:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:26:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:26:20 INFO - Installing collected packages: psutil 16:26:20 INFO - Running setup.py install for psutil 16:26:20 INFO - building 'psutil._psutil_osx' extension 16:26: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 16:26:21 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 16:26:21 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 16:26: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 16:26:21 INFO - building 'psutil._psutil_posix' extension 16:26: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 16:26:21 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:26:21 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:26:21 INFO - ^ 16:26:21 INFO - 1 warning generated. 16:26: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 16:26:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:26:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:26:21 INFO - Successfully installed psutil 16:26:21 INFO - Cleaning up... 16:26:21 INFO - Return code: 0 16:26:21 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:26: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')]} 16:26:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:26:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:26: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')]} 16:26:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:26:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:26: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 0x10e97bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e97e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2514cf2f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e759d30>, '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 0x10e680db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e74ebe8>, '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.tfI0DFnF2K/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.nLWClWbWlC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 16:26: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 16:26: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 16:26:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tfI0DFnF2K/Render', 16:26:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:26:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:26:21 INFO - 'HOME': '/Users/cltbld', 16:26:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:26:21 INFO - 'LOGNAME': 'cltbld', 16:26:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:26:21 INFO - 'MOZ_NO_REMOTE': '1', 16:26:21 INFO - 'NO_EM_RESTART': '1', 16:26:21 INFO - 'PAGER': '/bin/cat', 16:26:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:26:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:26:21 INFO - 'PWD': '/builds/slave/test', 16:26:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:26:21 INFO - 'SHELL': '/bin/bash', 16:26:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nLWClWbWlC/Listeners', 16:26:21 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 16:26:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:26:21 INFO - 'USER': 'cltbld', 16:26:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:26:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:26:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:26:21 INFO - 'XPC_FLAGS': '0x0', 16:26:21 INFO - 'XPC_SERVICE_NAME': '0', 16:26:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:26:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:26:21 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:26: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 16:26: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 16:26: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 16:26: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 16:26: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 16:26: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 16:26:24 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:26:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:26:24 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:26:24 INFO - Installing collected packages: mozsystemmonitor 16:26:24 INFO - Running setup.py install for mozsystemmonitor 16:26:24 INFO - Successfully installed mozsystemmonitor 16:26:24 INFO - Cleaning up... 16:26:24 INFO - Return code: 0 16:26:24 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:26: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')]} 16:26:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:26:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:26: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')]} 16:26:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:26:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:26: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 0x10e97bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e97e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2514cf2f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e759d30>, '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 0x10e680db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e74ebe8>, '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.tfI0DFnF2K/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.nLWClWbWlC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 16:26: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 16:26: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 16:26:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tfI0DFnF2K/Render', 16:26:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:26:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:26:24 INFO - 'HOME': '/Users/cltbld', 16:26:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:26:24 INFO - 'LOGNAME': 'cltbld', 16:26:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:26:24 INFO - 'MOZ_NO_REMOTE': '1', 16:26:24 INFO - 'NO_EM_RESTART': '1', 16:26:24 INFO - 'PAGER': '/bin/cat', 16:26:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:26:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:26:24 INFO - 'PWD': '/builds/slave/test', 16:26:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:26:24 INFO - 'SHELL': '/bin/bash', 16:26:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nLWClWbWlC/Listeners', 16:26:24 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 16:26:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:26:24 INFO - 'USER': 'cltbld', 16:26:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:26:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:26:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:26:24 INFO - 'XPC_FLAGS': '0x0', 16:26:24 INFO - 'XPC_SERVICE_NAME': '0', 16:26:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:26:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:26:25 INFO - Downloading/unpacking jsonschema==2.5.1 16:26:25 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 16:26:25 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 16:26:25 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 16:26:25 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 16:26:25 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 16:26:25 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 16:26:27 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:26:27 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:26: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 16:26: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 16:26: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 16:26: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 16:26: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 16:26: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 16:26:28 INFO - Downloading functools32-3.2.3-2.tar.gz 16:26:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:26:28 INFO - warning: no files found matching '*.txt' 16:26:28 INFO - no previously-included directories found matching 'build' 16:26:28 INFO - no previously-included directories found matching 'dist' 16:26:28 INFO - no previously-included directories found matching '.git*' 16:26:28 INFO - Installing collected packages: jsonschema, functools32 16:26:28 INFO - Running setup.py install for functools32 16:26:28 INFO - warning: no files found matching '*.txt' 16:26:28 INFO - no previously-included directories found matching 'build' 16:26:28 INFO - no previously-included directories found matching 'dist' 16:26:28 INFO - no previously-included directories found matching '.git*' 16:26:28 INFO - Successfully installed jsonschema functools32 16:26:28 INFO - Cleaning up... 16:26:28 INFO - Return code: 0 16:26:28 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:26: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')]} 16:26:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:26:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:26: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')]} 16:26:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:26:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:26: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 0x10e97bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e97e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2514cf2f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e759d30>, '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 0x10e680db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e74ebe8>, '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.tfI0DFnF2K/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.nLWClWbWlC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 16:26: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 16:26: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 16:26:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tfI0DFnF2K/Render', 16:26:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:26:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:26:28 INFO - 'HOME': '/Users/cltbld', 16:26:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:26:28 INFO - 'LOGNAME': 'cltbld', 16:26:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:26:28 INFO - 'MOZ_NO_REMOTE': '1', 16:26:28 INFO - 'NO_EM_RESTART': '1', 16:26:28 INFO - 'PAGER': '/bin/cat', 16:26:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:26:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:26:28 INFO - 'PWD': '/builds/slave/test', 16:26:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:26:28 INFO - 'SHELL': '/bin/bash', 16:26:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nLWClWbWlC/Listeners', 16:26:28 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 16:26:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:26:28 INFO - 'USER': 'cltbld', 16:26:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:26:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:26:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:26:28 INFO - 'XPC_FLAGS': '0x0', 16:26:28 INFO - 'XPC_SERVICE_NAME': '0', 16:26:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:26:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:26:28 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:26:28 INFO - Cleaning up... 16:26:28 INFO - Return code: 0 16:26:28 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:26: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')]} 16:26:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:26:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:26: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')]} 16:26:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:26:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:26: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 0x10e97bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e97e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2514cf2f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e759d30>, '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 0x10e680db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e74ebe8>, '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.tfI0DFnF2K/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.nLWClWbWlC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 16:26: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 16:26: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 16:26:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tfI0DFnF2K/Render', 16:26:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:26:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:26:28 INFO - 'HOME': '/Users/cltbld', 16:26:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:26:28 INFO - 'LOGNAME': 'cltbld', 16:26:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:26:28 INFO - 'MOZ_NO_REMOTE': '1', 16:26:28 INFO - 'NO_EM_RESTART': '1', 16:26:28 INFO - 'PAGER': '/bin/cat', 16:26:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:26:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:26:28 INFO - 'PWD': '/builds/slave/test', 16:26:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:26:28 INFO - 'SHELL': '/bin/bash', 16:26:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nLWClWbWlC/Listeners', 16:26:28 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 16:26:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:26:28 INFO - 'USER': 'cltbld', 16:26:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:26:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:26:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:26:28 INFO - 'XPC_FLAGS': '0x0', 16:26:28 INFO - 'XPC_SERVICE_NAME': '0', 16:26:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:26:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:26:28 INFO - Downloading/unpacking blobuploader==1.2.4 16:26: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 16:26: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 16:26: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 16:26: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 16:26: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 16:26: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 16:26:31 INFO - Downloading blobuploader-1.2.4.tar.gz 16:26:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:26:31 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:26: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 16:26: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 16:26: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 16:26: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 16:26: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 16:26: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 16:26:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:26:32 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:26: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 16:26: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 16:26: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 16:26: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 16:26: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 16:26: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 16:26:32 INFO - Downloading docopt-0.6.1.tar.gz 16:26:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:26:33 INFO - Installing collected packages: blobuploader, requests, docopt 16:26:33 INFO - Running setup.py install for blobuploader 16:26:33 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:26:33 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:26:33 INFO - Running setup.py install for requests 16:26:33 INFO - Running setup.py install for docopt 16:26:33 INFO - Successfully installed blobuploader requests docopt 16:26:33 INFO - Cleaning up... 16:26:33 INFO - Return code: 0 16:26:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:26: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')]} 16:26:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:26:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:26: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')]} 16:26:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:26:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:26: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 0x10e97bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e97e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2514cf2f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e759d30>, '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 0x10e680db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e74ebe8>, '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.tfI0DFnF2K/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.nLWClWbWlC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 16:26: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 16:26: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 16:26:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tfI0DFnF2K/Render', 16:26:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:26:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:26:33 INFO - 'HOME': '/Users/cltbld', 16:26:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:26:33 INFO - 'LOGNAME': 'cltbld', 16:26:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:26:33 INFO - 'MOZ_NO_REMOTE': '1', 16:26:33 INFO - 'NO_EM_RESTART': '1', 16:26:33 INFO - 'PAGER': '/bin/cat', 16:26:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:26:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:26:33 INFO - 'PWD': '/builds/slave/test', 16:26:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:26:33 INFO - 'SHELL': '/bin/bash', 16:26:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nLWClWbWlC/Listeners', 16:26:33 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 16:26:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:26:33 INFO - 'USER': 'cltbld', 16:26:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:26:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:26:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:26:33 INFO - 'XPC_FLAGS': '0x0', 16:26:33 INFO - 'XPC_SERVICE_NAME': '0', 16:26:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:26:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:26:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:26:34 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-H3CMot-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:26:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:26:34 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-RjDpvI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:26:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:26:34 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-6i75HI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:26:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:26:34 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-llwg_X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:26:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:26:34 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-3Wbu5i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:26:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:26:34 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-SZpNQ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:26:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:26:34 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-qiHuQc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:26:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:26:34 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-k66xY2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:26:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:26:35 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-PY8pzE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:26:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:26:35 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-vJoZzz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:26:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:26:35 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-Xaxn1g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:26:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:26:35 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-_AvRo5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:26:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:26:35 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-3d6PaJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:26:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:26:35 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-7DATCI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:26:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:26:35 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-vtdZYs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:26:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:26:35 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-61JA0W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:26:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:26:35 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-XjtwEJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:26:36 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:26:36 INFO - Running setup.py install for manifestparser 16:26:36 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:26:36 INFO - Running setup.py install for mozcrash 16:26:36 INFO - Running setup.py install for mozdebug 16:26:36 INFO - Running setup.py install for mozdevice 16:26:36 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:26:36 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:26:36 INFO - Running setup.py install for mozfile 16:26:36 INFO - Running setup.py install for mozhttpd 16:26:36 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:26:36 INFO - Running setup.py install for mozinfo 16:26:37 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:26:37 INFO - Running setup.py install for mozInstall 16:26:37 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:26:37 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:26:37 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:26:37 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:26:37 INFO - Running setup.py install for mozleak 16:26:37 INFO - Running setup.py install for mozlog 16:26:37 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:26:37 INFO - Running setup.py install for moznetwork 16:26:37 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:26:37 INFO - Running setup.py install for mozprocess 16:26:37 INFO - Running setup.py install for mozprofile 16:26:37 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:26:37 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:26:37 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:26:37 INFO - Running setup.py install for mozrunner 16:26:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:26:38 INFO - Running setup.py install for mozscreenshot 16:26:38 INFO - Running setup.py install for moztest 16:26:38 INFO - Running setup.py install for mozversion 16:26:38 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:26:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:26:38 INFO - Cleaning up... 16:26:38 INFO - Return code: 0 16:26:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:26: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')]} 16:26:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:26:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:26: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')]} 16:26:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:26:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:26: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 0x10e97bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e97e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2514cf2f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e759d30>, '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 0x10e680db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e74ebe8>, '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.tfI0DFnF2K/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.nLWClWbWlC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 16:26: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 16:26: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 16:26:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tfI0DFnF2K/Render', 16:26:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:26:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:26:38 INFO - 'HOME': '/Users/cltbld', 16:26:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:26:38 INFO - 'LOGNAME': 'cltbld', 16:26:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:26:38 INFO - 'MOZ_NO_REMOTE': '1', 16:26:38 INFO - 'NO_EM_RESTART': '1', 16:26:38 INFO - 'PAGER': '/bin/cat', 16:26:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:26:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:26:38 INFO - 'PWD': '/builds/slave/test', 16:26:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:26:38 INFO - 'SHELL': '/bin/bash', 16:26:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nLWClWbWlC/Listeners', 16:26:38 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 16:26:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:26:38 INFO - 'USER': 'cltbld', 16:26:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:26:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:26:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:26:38 INFO - 'XPC_FLAGS': '0x0', 16:26:38 INFO - 'XPC_SERVICE_NAME': '0', 16:26:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:26:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:26:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:26:38 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-S3s7CW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:26:39 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)) 16:26:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:26:39 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-bEGBpy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:26:39 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)) 16:26:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:26:39 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-tEILoL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:26:39 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)) 16:26:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:26:39 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-vnJLCe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:26: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)) 16:26:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:26:39 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-iLPEia-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:26: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)) 16:26:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:26:39 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-weBkMc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:26: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)) 16:26:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:26:39 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-8ydUlO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:26: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)) 16:26:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:26:39 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-1IB1bq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:26: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)) 16:26:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:26:39 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-w1pdHv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:26: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)) 16:26:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:26:39 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-glcbRH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:26: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)) 16:26:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:26:39 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-l3jzat-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:26:40 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)) 16:26:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:26:40 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-M61lLW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:26:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 16:26:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:26:40 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-Fxvq85-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:26: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)) 16:26:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:26:40 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-fUz7Vw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:26: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)) 16:26:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:26:40 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-lkdjrd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:26: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)) 16:26:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:26:40 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-vcELyu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:26: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)) 16:26:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:26:40 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-QxCLez-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:26: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)) 16:26: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)) 16:26: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)) 16:26: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)) 16:26: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)) 16:26: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)) 16:26:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:26: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 16:26: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 16:26: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 16:26: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 16:26: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 16:26: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 16:26:43 INFO - Downloading blessings-1.6.tar.gz 16:26:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:26:43 INFO - Installing collected packages: blessings 16:26:43 INFO - Running setup.py install for blessings 16:26:43 INFO - Successfully installed blessings 16:26:43 INFO - Cleaning up... 16:26:43 INFO - Return code: 0 16:26:43 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:26:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:26:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:26:43 INFO - Reading from file tmpfile_stdout 16:26:43 INFO - Current package versions: 16:26:43 INFO - blessings == 1.6 16:26:43 INFO - blobuploader == 1.2.4 16:26:43 INFO - docopt == 0.6.1 16:26:43 INFO - functools32 == 3.2.3-2 16:26:43 INFO - jsonschema == 2.5.1 16:26:43 INFO - manifestparser == 1.1 16:26:43 INFO - mozInstall == 1.12 16:26:43 INFO - mozcrash == 1.0 16:26:43 INFO - mozdebug == 0.1 16:26:43 INFO - mozdevice == 0.49 16:26:43 INFO - mozfile == 1.2 16:26:43 INFO - mozhttpd == 0.7 16:26:43 INFO - mozinfo == 0.9 16:26:43 INFO - mozleak == 0.1 16:26:43 INFO - mozlog == 3.4 16:26:43 INFO - moznetwork == 0.27 16:26:43 INFO - mozprocess == 0.25 16:26:43 INFO - mozprofile == 0.28 16:26:43 INFO - mozrunner == 6.13 16:26:43 INFO - mozscreenshot == 0.1 16:26:43 INFO - mozsystemmonitor == 0.3 16:26:43 INFO - moztest == 0.8 16:26:43 INFO - mozversion == 1.4 16:26:43 INFO - psutil == 3.1.1 16:26:43 INFO - requests == 1.2.3 16:26:43 INFO - wsgiref == 0.1.2 16:26:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:26: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')]} 16:26:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:26:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:26: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')]} 16:26:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:26:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:26: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 0x10e97bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e97e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2514cf2f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e759d30>, '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 0x10e680db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e74ebe8>, '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.tfI0DFnF2K/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.nLWClWbWlC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 16:26: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 16:26: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 16:26:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tfI0DFnF2K/Render', 16:26:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:26:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:26:43 INFO - 'HOME': '/Users/cltbld', 16:26:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:26:43 INFO - 'LOGNAME': 'cltbld', 16:26:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:26:43 INFO - 'MOZ_NO_REMOTE': '1', 16:26:43 INFO - 'NO_EM_RESTART': '1', 16:26:43 INFO - 'PAGER': '/bin/cat', 16:26:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:26:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:26:43 INFO - 'PWD': '/builds/slave/test', 16:26:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:26:43 INFO - 'SHELL': '/bin/bash', 16:26:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nLWClWbWlC/Listeners', 16:26:43 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 16:26:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:26:43 INFO - 'USER': 'cltbld', 16:26:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:26:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:26:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:26:43 INFO - 'XPC_FLAGS': '0x0', 16:26:43 INFO - 'XPC_SERVICE_NAME': '0', 16:26:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:26:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:26: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)) 16:26: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)) 16:26: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)) 16:26: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)) 16:26: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)) 16:26: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)) 16:26: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)) 16:26: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)) 16:26: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)) 16:26: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)) 16:26: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)) 16:26:44 INFO - Cleaning up... 16:26:44 INFO - Return code: 0 16:26:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:26: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')]} 16:26:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:26:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:26: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')]} 16:26:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:26:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:26: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 0x10e97bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e97e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2514cf2f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e759d30>, '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 0x10e680db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e74ebe8>, '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.tfI0DFnF2K/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.nLWClWbWlC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 16:26: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 16:26: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 16:26:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tfI0DFnF2K/Render', 16:26:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:26:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:26:44 INFO - 'HOME': '/Users/cltbld', 16:26:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:26:44 INFO - 'LOGNAME': 'cltbld', 16:26:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:26:44 INFO - 'MOZ_NO_REMOTE': '1', 16:26:44 INFO - 'NO_EM_RESTART': '1', 16:26:44 INFO - 'PAGER': '/bin/cat', 16:26:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:26:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:26:44 INFO - 'PWD': '/builds/slave/test', 16:26:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:26:44 INFO - 'SHELL': '/bin/bash', 16:26:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nLWClWbWlC/Listeners', 16:26:44 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 16:26:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:26:44 INFO - 'USER': 'cltbld', 16:26:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:26:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:26:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:26:44 INFO - 'XPC_FLAGS': '0x0', 16:26:44 INFO - 'XPC_SERVICE_NAME': '0', 16:26:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:26:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:26:44 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:26:44 INFO - Cleaning up... 16:26:44 INFO - Return code: 0 16:26:44 INFO - Running post-action listener: _resource_record_post_action 16:26:44 INFO - Running post-action listener: _start_resource_monitoring 16:26:44 INFO - Starting resource monitoring. 16:26:44 INFO - [mozharness: 2017-03-07 00:26:44.481405Z] Finished create-virtualenv step (success) 16:26:44 INFO - [mozharness: 2017-03-07 00:26:44.482324Z] Running install step. 16:26:44 INFO - Running pre-action listener: _resource_record_pre_action 16:26:44 INFO - Running main action method: install 16:26:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:26:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:26:44 INFO - Reading from file tmpfile_stdout 16:26:44 INFO - Detecting whether we're running mozinstall >=1.0... 16:26:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:26:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:26:44 INFO - Reading from file tmpfile_stdout 16:26:44 INFO - Output received: 16:26:44 INFO - Usage: mozinstall [options] installer 16:26:44 INFO - Options: 16:26:44 INFO - -h, --help show this help message and exit 16:26:44 INFO - -d DEST, --destination=DEST 16:26:44 INFO - Directory to install application into. [default: 16:26:44 INFO - "/builds/slave/test"] 16:26:44 INFO - --app=APP Application being installed. [default: firefox] 16:26:44 INFO - mkdir: /builds/slave/test/build/application 16:26:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 16:26:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 16:27:05 INFO - Reading from file tmpfile_stdout 16:27:05 INFO - Output received: 16:27:05 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 16:27:05 INFO - Running post-action listener: _resource_record_post_action 16:27:05 INFO - [mozharness: 2017-03-07 00:27:05.273674Z] Finished install step (success) 16:27:05 INFO - [mozharness: 2017-03-07 00:27:05.273824Z] Running run-tests step. 16:27:05 INFO - Running pre-action listener: _resource_record_pre_action 16:27:05 INFO - Running main action method: run_tests 16:27:05 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:27:05 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:27:05 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:27:05 INFO - 2017-03-06 16:27:05.288 screenresolution[1631:8620] starting screenresolution argv=screenresolution get 16:27:05 INFO - 2017-03-06 16:27:05.291 screenresolution[1631:8620] Display 0: 1600x1200x32@60 16:27:05 INFO - 2017-03-06 16:27:05.296 screenresolution[1632:8623] starting screenresolution argv=screenresolution list 16:27:05 INFO - Available Modes on Display 0 16:27:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:27:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:27:05 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:27:05 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:27:05 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:27:05 INFO - Intel Iris: 16:27:05 INFO - Chipset Model: Intel Iris 16:27:05 INFO - Type: GPU 16:27:05 INFO - Bus: Built-In 16:27:05 INFO - VRAM (Dynamic, Max): 1536 MB 16:27:05 INFO - Vendor: Intel (0x8086) 16:27:05 INFO - Device ID: 0x0a2e 16:27:05 INFO - Revision ID: 0x0009 16:27:05 INFO - Displays: 16:27:05 INFO - Display: 16:27:05 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:27:05 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:27:05 INFO - Display Serial Number: 1600x1200 60 16:27:05 INFO - Main Display: Yes 16:27:05 INFO - Mirror: Off 16:27:05 INFO - Online: Yes 16:27:05 INFO - Rotation: Supported 16:27:05 INFO - Return code: 0 16:27:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:27:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:27:05 INFO - Python 2.7.3 16:27:05 INFO - Return code: 0 16:27:05 INFO - grabbing minidump binary from tooltool 16:27:05 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')]} 16:27:05 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 0x10e759d30>, '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 0x10e680db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e74ebe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:27:05 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 16:27:05 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 16:27:05 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 16:27:05 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:27:05 INFO - Return code: 0 16:27:05 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:27:05 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:27:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:27:05 INFO - ENV: RUST_BACKTRACE is now 1 16:27:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:27:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:27:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/I6LWlIepTaqY9F-11WQl6Q/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0111', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 16:27:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/I6LWlIepTaqY9F-11WQl6Q/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0111 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 16:27:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tfI0DFnF2K/Render', 16:27:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:27:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:27:05 INFO - 'HOME': '/Users/cltbld', 16:27:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:27:05 INFO - 'LOGNAME': 'cltbld', 16:27:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:27:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:27:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:27:05 INFO - 'MOZ_NO_REMOTE': '1', 16:27:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:27:05 INFO - 'NO_EM_RESTART': '1', 16:27:05 INFO - 'PAGER': '/bin/cat', 16:27:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:27:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:27:05 INFO - 'PWD': '/builds/slave/test', 16:27:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:27:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:27:05 INFO - 'RUST_BACKTRACE': '1', 16:27:05 INFO - 'SHELL': '/bin/bash', 16:27:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nLWClWbWlC/Listeners', 16:27:05 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 16:27:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:27:05 INFO - 'USER': 'cltbld', 16:27:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:27:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:27:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:27:05 INFO - 'XPC_FLAGS': '0x0', 16:27:05 INFO - 'XPC_SERVICE_NAME': '0', 16:27:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:27:05 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/I6LWlIepTaqY9F-11WQl6Q/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0111', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 16:27:05 INFO - mozversion application_buildid: 20170306150532 16:27:05 INFO - mozversion application_changeset: 0931190cd725a744b74f8f47863b7b38be8e8ebf 16:27:05 INFO - mozversion application_display_name: FirefoxDeveloperEdition 16:27:05 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:27:05 INFO - mozversion application_name: Firefox 16:27:05 INFO - mozversion application_remotingname: firefox-dev 16:27:05 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 16:27:05 INFO - mozversion application_vendor: Mozilla 16:27:05 INFO - mozversion application_version: 54.0a2 16:27:05 INFO - mozversion platform_buildid: 20170306150532 16:27:05 INFO - mozversion platform_changeset: 0931190cd725a744b74f8f47863b7b38be8e8ebf 16:27:05 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 16:27:05 INFO - mozversion platform_version: 54.0a2 16:27:05 INFO - using testdate: 1488846425 16:27:05 INFO - actual date: 1488846425 16:27:05 INFO - starting webserver on 'localhost:49244' 16:27:05 INFO - SUITE-START | Running 2 tests 16:27:05 INFO - TEST-START | dromaeo_css 16:27:05 INFO - operating with platform_type : mac_ 16:27:05 INFO - Initialising browser for dromaeo_css test... 16:27:05 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/tmpz_RXbb/profile http://localhost:49244/getInfo.html) 16:27:08 INFO - PROCESS | 1642 | 2017-03-06 16:27:08.820 plugin-container[1645:8817] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x912f, name = 'com.apple.tsm.portname' 16:27:08 INFO - PROCESS | 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:27:08 INFO - PROCESS | 1642 | 2017-03-06 16:27:08.823 plugin-container[1645:8817] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b17, name = 'com.apple.CFPasteboardClient' 16:27:08 INFO - PROCESS | 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:27:08 INFO - PROCESS | 1642 | 2017-03-06 16:27:08.823 plugin-container[1645:8817] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:27:08 INFO - PROCESS | 1642 | __metrics Screen width/height:1600/1200 16:27:08 INFO - PROCESS | 1642 | colorDepth:24 16:27:08 INFO - PROCESS | 1642 | Browser inner width/height: 1024/689 16:27:08 INFO - PROCESS | 1642 | __metrics 16:27:08 ERROR - PROCESS | 1642 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3353: TypeError: this.tabs is undefined 16:27:09 INFO - TEST-INFO | 1642: exit 0 16:27:09 INFO - Browser initialized. 16:27:09 INFO - Running cycle 1/1 for dromaeo_css test... 16:27:09 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/tmpz_RXbb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:27:12 INFO - PROCESS | 1646 | 2017-03-06 16:27:12.173 plugin-container[1647:8944] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893f, name = 'com.apple.tsm.portname' 16:27:12 INFO - PROCESS | 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:27:12 INFO - PROCESS | 1646 | 2017-03-06 16:27:12.173 plugin-container[1647:8944] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 16:27:12 INFO - PROCESS | 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:27:12 INFO - PROCESS | 1646 | 2017-03-06 16:27:12.173 plugin-container[1647:8944] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:27:21 INFO - PROCESS | 1646 | RSS: Main: 220229632 16:27:21 INFO - PROCESS | 1646 | 16:30:25 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49244/tests/dromaeo/cssquery-ext.html) 16:30:27 INFO - PROCESS | 1646 | RSS: Main: 238231552 16:30:27 INFO - PROCESS | 1646 | 16:32:10 INFO - PROCESS | 1646 | ************************* 16:32:10 INFO - PROCESS | 1646 | A coding exception was thrown and uncaught in a Task. 16:32:10 INFO - PROCESS | 1646 | 16:32:10 ERROR - PROCESS | 1646 | Full message: TypeError: NetworkError when attempting to fetch resource. 16:32:10 INFO - PROCESS | 1646 | Full stack: 16:32:10 INFO - PROCESS | 1646 | ************************* 16:33:29 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-ext.html (next: http://localhost:49244/tests/dromaeo/cssquery-jquery.html) 16:33:30 INFO - PROCESS | 1646 | RSS: Main: 246136832 16:33:30 INFO - PROCESS | 1646 | 16:36:31 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49244/tests/dromaeo/cssquery-mootools.html) 16:36:32 INFO - PROCESS | 1646 | RSS: Main: 255787008 16:36:32 INFO - PROCESS | 1646 | 16:39:34 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49244/tests/dromaeo/cssquery-prototype.html) 16:39:35 INFO - PROCESS | 1646 | RSS: Main: 251940864 16:39:35 INFO - PROCESS | 1646 | 16:42:38 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49244/tests/dromaeo/cssquery-yui.html) 16:42:38 INFO - PROCESS | 1646 | RSS: Main: 257843200 16:42:38 INFO - PROCESS | 1646 | 16:45:41 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-yui.html (next: http://localhost:49244/tests/dromaeo/cssquery-dojo.html) 16:45:41 INFO - PROCESS | 1646 | RSS: Main: 254103552 16:45:41 INFO - PROCESS | 1646 | 16:45:41 INFO - PROCESS | 1646 | __start_tp_report 16:45:41 INFO - PROCESS | 1646 | _x_x_mozilla_page_load 16:45:41 INFO - PROCESS | 1646 | _x_x_mozilla_page_load_details 16:45:41 INFO - PROCESS | 1646 | |i|pagename|runs| 16:45:41 INFO - PROCESS | 1646 | |0;dojo.html;3141.685831416858;3450.585929688438;3480.182157193059;3505.859765609375;3509.4911237870524;6015.578909476339;6016.969915150424;6033.7888173913925;6074.2710874694985;6089.086637004451;6033.1855199548045;6105.786297479589;6113.296970848353;6115.785947491839;6126.754929802813;5420.783168673248;5429.321334833146;5496.780128794863;5540.168974653794;5554.750036248379;5463.754131064112;5505.944940550583;5551.056320425538;5571.749271282782;5574.526165275956;5305.1776974569;5344.599155063347;5476.890462190734;5485.725713714318;5502.614816962814;5345.1714984177515;5368.248445217672;5391.541718953894;5433.510984011418;5433.646812957185;5310.070737620901;5376.542993845528;5376.946230537684;5376.973115134439;5382.811601593965;5393.946060539383;5775.942240577582;5970.893639093101;6060.212172417601;6072.271327440735;5394.946050539523;6248.343923888017;6259.530535209878;6285.622862628257;6350.745970161188;6434.774782882577;6451.451626611743;6452.032195170715;6457.2896981332015;6465.515086368541;6194.194754681863;6204.3795620437595;6211.844703882394;6227.190465239581;6231.283402408781;6152.472595605359;6326.49388048955;6424.26120996082;6440.806775796734;6579.407853293226;6227;6289.842753931097;6299.937000630027;6301.558890877686;6317.399847014526;6402.423781859655;6407.615543067431;6430.131932189094;6431.871362572722;6433.356664333529;5439.864003399946;6247.937520624827;6253.7185826637915;6254.093156492374;6335.144755458046;6367.172267605274;6371;6376.043593461018;6376.107344971708;6379.106925030499;5122.257272695439;5146.922796158061;5159.355080614923;5180.145276029489;5181.948180518147;3824.464574959508;3946.2304850553865;4202.579742025773;4443.089166720879;4517.909641807146;6106.297775755756;6111.757279333595;6143.170671959318;6163.506919446433;6165.722542485599;5624.409437009137;6442.452391546771;6479.611223326521;6487.286398496161;6513.609183449008;5022.723750193702;5819.82540523785;5835.095560188178;5947.197128387699;5982.312034116046;4970.527799859007;5615.9157612635845;5672.858178545569;5717.42825717425;5725.627834190765;6596.571222870498;6652.467802575782;6653.733850646016;6656.667166641551;6741.426978706865;6465.030245463218;6515.54391192612;6522.673866306761;6550.377714117151;6551.181102362205;5741.3397459291045;6308.653024083719;6578.934210657832;6607.2071351438135;6698.765543205965;5270.60470464709;6345.1434056401795;6362.172917520693;6390.424861762278;6401.391867772554;6290.811275661737;6449.548531602931;6459.354064593691;6464.450521705519;6466.256380516301;5974.462298393166;6040;6262.279837818527;6265;6307.621542707452;6293.527985401021;6316.778912738032;6325.272593651789;6325.905111423424;6334.954732469276;5605.4114317997655;5961.155054989594;6310.873782524232;6346.936530634634;6353.332900045613;6155.538334624832;6253.249610046823;6255.093011513396;6272.780452684134;6303.054541818764;6216.129741836237;6246.281677607133;6300.306966233619;6300.842478938063;6325.05124231369 16:45:41 INFO - PROCESS | 1646 | |1;ext.html;605.9638018987531;796.2634563029198;816.0778320497836;950.4819873169126;955.2644463762898;33667.831660841664;37596.62403375969;38467.422988655155;40122.59877401225;40160;38229;38438;38443.80778096113;38507;38530.61469385305;7871.465064312461;8025.036995560541;10508.159347252202;10765.1926105542;10972.835407468854;9040.728778136667;10763.3541987481;10841.83737243942;10845.891541084606;10855.67432977012;9791.314607977445;10276.869544350115;10795.892041079567;10844.078253348474;10845;10764.73088172794;10787.56849726014;10796.460176991119;10809.243352965257;10828.079613232885;10448.059674629243;10460.535525026502;10478.214133939984;10518.684439466828;10540.420276884768;33982.49026264608;34080.829595851865;34089;34171.145721356916;34265.48601770975;3638.1450359165574;3657.56109266887;3683.794387783008;3736.6076561961145;3769;25444.872775636188;25506.36234094144;26066.34834129143;26073.869630651916;26145.954161833502;3002.564628128909;3054.282243672735;3062.5099984002454;3068.6164229471315;3075.03136511998;299051;371775;372539;372671;374766.12616937014;35840.82079589585;43565.78217108894;43632.69101926947;43742.343864842056;43955.78022109869;44806.77596611996;45169.54830451719;45188.3221751674;45207.321890171675;45217.77391113089;13752.65618359529;13756.243406612532;13863.168209907439;13882.930585347009;13923.86076139246;11829.940850295865;11876;11905.52377904874;11906.404679765978;11921.88078119225;10060.346077504939;11261.099112071015;11829.349385783864;11921.940390298167;12059.517619295306;4583.954160458419;5442;5466.262054622654;5476.288082549243;5495.670259784426;1076.924615276923;1105.9557617695364;1109.6560066379448;1110.8111621024284;1159.9420028998516;26160.476790464083;26424;26591.601125983125;26745.33136671599;26799.732002679724;1209.8911098001222;1219.451246938881;1220.2861326124316;1248.6691026877884;1258.4714419943646;12238.75522489563;13351.265680387478;13699.863001369858;13943.023988320923;14085.15489070659;474129;474447;477871;477875;479067;55830.883382332126;55892.72053639706;56030.719846400505;56122.43877561172;56144.438555613924;1231.8891299783063;1388.5279005138302;1406.1492796857958;1422.6016715319727;1423.964400889986;20677.58644827065;28984.275393115342;29955.70044299529;30086.69913300839;30686.232844179074;51121.7443912778;51314.48685513246;51437.48562514327;51556.484435155166;51597.71005724887;419723;479399;484159.57920210174;484649;485073;31219.063428097194;31497.370052598362;31530.842345789042;31545.842270789417;31560;32955.34089318248;36895.815520922224;39203.607963919996;40397.798011009756;41058.38412423876;31826.522602160523;32230;32378.67621323851;32393.028209153763;33206.833965830985;32737.50893736548;32802.34395312128;32889;33522;33924.66075339314;32260.032199034067;32736;32811.50782738213;32834.67165328412;32892.1776955578;26098.739012609632;26857.462850742486;27792.305192370353;28607.42785144327;28632.856835715687;22498.550028999656;22924.541509170056;22936.8531573424;23025.539489210456;23063.88468057649 16:45:41 INFO - PROCESS | 1646 | |2;jquery.html;386.24489123763044;413.6525318732266;415.78587027680743;416.4315709057138;454.3933848312503;19786.70319945202;19886.90056549717;20438.489037774063;20824.375268741962;21121.943902804855;17219;20822.375328740123;21194.47013824655;21402.89298553505;21537.24619638313;6153.323134455206;6530.053142294364;6558.278589355167;6564.967175164117;6570.211574611053;6179.3202747697715;6377.585456945284;6491.772787952423;6515.022746588026;6551.082848401227;6550.410463058324;6553.541252112354;6560.147180866505;6560.475161987029;6568.934310656904;5993.850153746148;6231.283402408691;6313.779017734358;6464.547481676284;6483.935160648404;5491.28613280271;6103.084537319393;6324.367563243685;6365.3316401778075;6382.61704297739;23166.76833231663;23311.417214569603;24016.399590010216;25832.741672583223;26167.738322616722;2896.4641541314923;2899.420115976813;3059.8301640224013;3184.8693713731755;3422.110251334646;16394.754078688828;16661.583460413465;16723;16845.831541684547;17295.875768075035;2016.1229865008768;2022.5247066939257;2026.0680087159924;2028.3306508852042;2046.4474591860264;135593;136042.31978840043;139681;142488;142506;20421.081051352725;21817.727340898993;22108.668369974464;22407.77592224057;22500.887495562743;33960.32079358399;34306.48540271898;34816.65183348185;34833.30333393318;34896.65103348934;10553.419561924167;10793.02862742357;11049.502772375263;11089.55641774336;11093.944530277458;8955.776105597412;8993.055729148477;9019.052999434965;9216.585253663601;9228.538573071453;10124.848127278097;10245.538950747234;10263.794724105475;10367.63713270047;10397.636082737068;3894.9805250973563;3937.6062393760967;4229.175310814423;4240.427542281815;4285.871423857289;882.3338379523547;907.0747837206145;933.8972713001565;944.9149576538144;957.7461972577272;20793.376198714064;21909.123635054737;21936.561268774854;21958.01188946501;21997.120115195212;942.3450701762209;954.6467806911348;957.1653636931164;974.4007435427161;979.7795496013482;11140.442977851075;11515.596954106566;11621.709457263636;11726.70683232926;11775.646730598095;713346;728362;728545.3572732102;732789;788193;132772.3361383187;133110;133945.33027334997;135949;139891;1245.5391505143155;1246.8815462531045;1274.7641686288252;1276.1386064406488;1277.3804704718204;18483;19111.139998699815;19131;19222.903885480482;19258.8074119257;45254.09491810211;55778.48996795235;57212.56968575653;57286.42713572811;58258.70870645619;76254.61872690823;77989;78427;79873;80145;11489.253198542068;11933.82099268529;11939.701507462383;12053.879461205626;12058;12783.04127190483;12845.90809781179;13196.944244460228;13372.732545348845;13555.728885422039;9756.756081098029;11345.943270283597;11393.715157121138;11481.425928703364;11627.244229125079;9708.5145742714;10921.617743378943;11323.773524529628;11384.43077846121;11829.82255266189;10831.837522437327;11559.30644161353;11834.349110798988;11858.584949526725;11916.046716262505;11743.412829358327;11829.467673954521;12041.397930103634;12098.516059357878;12405.875941240833;11574.363410012531;11610.419479026184;11700.239484433485;11825.586104486301;11884.643460695846 16:45:41 INFO - PROCESS | 1646 | |3;mootools.html;1369.869857367672;1405.3605609447695;1419.354193841802;1471.514400247918;1543.2129613896916;10648.201384896134;11077.833832492519;11232.775344493106;11333.376664283462;11338.263012904166;9651.855222171656;10958;11075.50160242789;11116.166287528427;11126.276792008513;2118.8093071623553;2404.8316617836804;2427.664784368599;2447.984086604057;2454.5459090068307;2354.988225058872;2380.0836677879056;2401.2796161151587;2406.109739396426;2406.169871394366;2170.6852506386563;2246.2475070851283;2393.5571919194917;2409.144753612469;2410.1323523531514;2272.6022945984387;2293.69035180251;2310.041332846875;2310.283812018263;2316.548273086749;2297.2993237062688;2349.4126468382906;2400.099962514057;2403.519296140779;2410.710714714228;11425.428728563538;11451.083913286917;11464.656060318204;11476.967072963389;11502.482388292463;5259.237410575483;5732.538202758312;5780.682062486561;5976.073708575178;5994;3549.893503194908;3665.3402387570227;3678.8160591970563;3730.5150330457136;3741.5323084614424;1167.270455965022;1171.2621048739325;1225.2158618484177;1230.267990545624;1236.8886800187936;13785.379657915419;13982.091164074303;14691.265436728121;15427.3829046838;15570.610734731608;4706.0823139487475;4741.875695045101;4753.263244197155;4849.903001940012;4857.684250523705;10317.968203179622;11541.76916461666;11648.592299269485;11674.299542027506;11688.298702077902;5839.153322768175;5896.823095307148;5908.409159084057;5909.763609455659;5938.049912014057;5088.974555127201;5289.68261904287;5317.2024196370085;5353.892922141536;5386.892262154735;3875.418687196887;3937.8580211738276;3961.841526338972;4006.358982562776;4035.051762835731;1007.09361574582;1061.1192710050639;1064.3613831700918;1079.2445288298222;1080.0387654987069;800.5997001499251;834.3783881008566;912.4160537256162;915.8122584870218;930.6463543853292;6519.8696026079215;6533.477321814244;6551.672416379162;6582.967085164543;6774;996.3324572536418;1004.6634377483479;1015.1016350529686;1021.4382089850552;1026.779242462874;3731.4216296473546;4200.810963506588;4357.128574285156;4414.757188354607;4508.233600287959;13838.377273022737;13937.303134843218;14117.647058823406;14122.85877141236;14173.00788944764;13999;14016;14042.1574705518;14057.578272651837;14062.929685351508;8606.31149508038;10123.622615227749;10283;10389.532471038821;10630.30902991303;6264.749410023548;6603.9009414859775;6658.63377514222;6820.181578210448;6884.862302753817;11717.06263498935;11894.881051189377;11927.76144477123;11938.343391113225;12002.279863208236;11334.433278336215;12334.50661973511;12496.187747796364;12648;12683.746325073262;9207.953960230156;9230.953845230732;9383.343165978316;9397.154256116983;9456.905430945602;10259.281850270709;10288.845667314847;10370.740731481472;10389.9480502597;10420.270581059556;8982.146696064125;9165;9226.35415520934;9275.350725449154;9408.106229908148;8823.338249631175;8826.823463530565;8830.558472076242;8871.290296776371;8902.55487225649;7889.171636978034;7918.445708800559;8001.479903806234;8069.959650201711;8076.394270429624;4905.77923993414;5911.911321330097;5975;6001.279846418283;6075.908861366995;5476.424975377688;5909.85225369352;5937.615559131426;6110.847228819315;6217.285012223471 16:45:41 INFO - PROCESS | 1646 | |4;prototype.html;344.6949449736982;353.9221371298315;363.18283861312045;385.51810237203495;392.1001302011882;5050.84847454577;5235.246192525504;5238.694974211345;5276.894462110756;5296.443873393296;4431.977840110803;5230.457015180521;5231.633785635007;5256.868578285545;5301.1783173608155;3848.7690738555775;4225.873223803275;4305.720128191665;4354.564543545652;4387.332813530854;4055.5944405559358;4185.434966279544;4323.827046918135;4421.226285400058;4430.335449682541;3848.6536211740936;4401.317795741666;4405.911881762363;4412.9479791896765;4438.201123797715;3396.624367131316;3654.3604869148057;4426.734395936254;4446.088551846879;4463.285874260119;3764.6800021998165;4006.4390985262085;4026.355783074694;4048.5141782985934;4056.695747818896;5125.384953805567;5156.719922464383;5162.561182299509;5180.663256888328;5203.60972927032;4510.864674059783;4796.057596673318;4986.4764199758865;5042.672226305279;5094.2103973884105;4821.855344339676;5018.774155163026;5051.848444546669;5352.751844225396;5623.437656234385;4166.354215096665;4345.956540434587;4526.456825180966;4529.04889973106;4565.863024109282;5001.599872010231;5672.659640421588;5691;5926.466618004357;5965.970170149121;4132.648724858361;4565.657575681837;4584.9541504585195;4648.744319062416;4658.604018658384;4207.663386929038;4405.185040767468;4660.4174478190225;4691.390119284471;4709.987352719182;4582.198115329804;4606.742696078964;4832.371791667061;4909.5090490950615;5010.824621138316;4642.141203877293;4679.438467383868;5313.946860531422;5576.6375185613615;5597.552195824324;1412.131539103444;1412.8869690424742;1465.9371955332513;1508.0951429142426;1638;1006.4952895421392;1108.578740078765;1195.678774953681;1201.3212534917743;1222.6821026533166;4607.746573938465;4868.9269660955115;4962.15643340633;5125.461826508163;5229.450907654642;4970.757310672332;5027.346444962204;5345.625806193607;5626;5872;4430.534793846664;4752.215884379108;4856.635752318591;4968.30443737877;5188.792448302026;3022.2293315204483;3024.04948808022;3025.1848890665865;3046.6496352919253;3055.724984751383;5311.7344132793205;5511.366192887789;5948.282323547783;6027.955882279421;6041.667708276087;5110.386753589519;5320.76056577455;5492.533134683516;5549.72251387429;5901.232839730807;5210.47895210476;5477.75350109238;5481.561475081984;5729.682173100127;6068.939310606837;5168.689878607295;5304.302623160552;5561.4973262031435;5699.715014249354;5746.224259724883;5382.4886635769535;5455.427180146186;5595.8880822384135;5681.261436013292;5729.197912292366;5381.48875856793;5586.52514536269;5716.911948366041;6025.367336429787;6126.722203621041;4614.930776038429;4649.65127615437;4691.381473391674;4692;4696.272077827805;4866.683665561727;5124.9743751281;5550.861228469321;5802.303723553201;5820.650760954356;4954.479779623088;4966.304717339503;5654.802081927113;5678.608740858516;5711.371749107678;4919.080919080919;5316.6012549058205;5464.808731694372;5644.915326270191;5799.8550036249435;4910.017996400663;5206.453322401245;5501.807436739695;5539.529140023144;5540.224368588481;4583.197940360357;5304.60215483848;5636.633618814764;5710.2576665033275;5781.566382521243;4440.311751678495;5088.491150884882;5329.521772375864;5373.722029562042;5553.250311207935 16:45:41 INFO - PROCESS | 1646 | |5;yui.html;646.7154452041104;701.0045735056219;758.7534051433286;779.6842278877053;823.4770920465504;14849.257537123141;15678;15846.524604261862;15876.761848572252;15892.602684932883;14262.714745705105;14645.560633180996;14713.779293310583;14723.631909202275;14739.263036848168;930.0885132570069;941.6892425499603;946.0114180681205;946.0870260198907;949.2216382566298;903.6791938861708;923.2521657457447;932.5430539035891;937.8452555328397;938.737153596994;976.3556053005018;978.6672531339343;980.0444566547594;984.3749219245794;984.4339504784741;968.2544440780559;973.3673112476911;973.522973742868;974.3763991045737;975.0395860077817;722.2849379114691;722.9530080544748;723.7032816545192;727.3926271563251;728.4645785347765;15335.769963450557;15912.283947222402;15943;16463.588410289718;16690.165491725365;13631.386587603582;13929.721405571832;13970.650733731636;13982.440702371892;14005.439782408692;11813.763724725542;11852.472841409795;11913;11950.163488555805;12064;3587.4080776671817;3638.163222458823;3684.778913265213;3701.592824789271;3711.981440092782;105772;107574.3863842043;108525;110967.44516277447;111245.44377278142;10696.625618103484;10716.78566428667;10774.676759697222;10970.890291096986;11030.2278840482;22395;22457.77542224557;22809.973551190236;22813.657795133087;22875.771242287698;6889.965550172317;7119.3592576667315;7792.883106753403;7822.882656760153;7947.887295778595;4266.274733295348;4378.824847006084;4381.846635367747;4404.471463424409;4432.268675668543;3321.368939901411;3550.467429885486;3577.856885724594;3609.097725568608;3609.22401683634;642.7557528139278;643.4498503779295;645.1838424393146;645.6158585641535;652.4030512081487;661.1867403094125;673.8281738156836;675.898615207713;687.7180356053993;719.8380364417964;10162.593496260215;10248.38509689421;10405.9479702601;10474.423906685206;10676.519556619816;698.1587187439123;706.0503622627558;706.8338940349014;711.1040089487283;717.5049216040915;4706.741129237925;5049.6212784041345;5056.191009438546;5060.746962651852;5061.493850614909;76732;78478;85188.57405713055;102778.48610756898;108800.4559977195;71366;78909.60545197237;80122.19877801264;84409;84924;48428;69023.30976690269;77158.45683086419;79472;79941.20058799337;10699.358038517714;11056.281341712764;11227.943860280646;11275.210735248782;11325.263857849213;49141.75429122832;49943.250851237986;51174;51361;51892.740536297075;61618;63322;64570;64636.67681661562;65347.67326163339;11151.609693660683;11382.032527235278;12298.75402491963;12432.88104070638;12999.415026324028;13883.791743124064;14259.358328875433;14536.90973177037;14623.780643290147;15135.470258540898;11072;13105.2136871788;13595.116317439335;13656.726865462833;14063.578092657237;12783.296918669563;13499.460021599027;13662.590122296348;13675.24786136772;14441.638959026108;12684.302363370103;13433.596992090253;13443.596692099252;13672.042956992915;13698.65753356174;10125.89874101279;10333.276670632984;10458;10463.790724185626;10762.51568679427;7665.501742386727;8420.790103751047;9331.393459425115;9877.506124693593;10097.495125243562 16:45:41 INFO - PROCESS | 1646 | __end_tp_report 16:45:41 INFO - PROCESS | 1646 | __start_cc_report 16:45:41 INFO - PROCESS | 1646 | _x_x_mozilla_cycle_collect,419 16:45:41 INFO - PROCESS | 1646 | __end_cc_report 16:45:41 INFO - PROCESS | 1646 | __startTimestamp1488847541873__endTimestamp 16:45:41 INFO - PROCESS | 1646 | 16:45:41 INFO - PROCESS | 1646 | ------- Summary: start ------- 16:45:41 INFO - PROCESS | 1646 | Number of tests: 6 16:45:41 INFO - PROCESS | 1646 | 16:45:41 INFO - PROCESS | 1646 | [#0] dojo.html Cycles:165 Average:5918.20 Median:6204.38 stddev:694.59 (11.2%) stddev-sans-first:661.69 16:45:41 INFO - PROCESS | 1646 | Values: 3141.7 3450.6 3480.2 3505.9 3509.5 6015.6 6017.0 6033.8 6074.3 6089.1 6033.2 6105.8 6113.3 6115.8 6126.8 5420.8 5429.3 5496.8 5540.2 5554.8 5463.8 5505.9 5551.1 5571.7 5574.5 5305.2 5344.6 5476.9 5485.7 5502.6 5345.2 5368.2 5391.5 5433.5 5433.6 5310.1 5376.5 5376.9 5377.0 5382.8 5393.9 5775.9 5970.9 6060.2 6072.3 5394.9 6248.3 6259.5 6285.6 6350.7 6434.8 6451.5 6452.0 6457.3 6465.5 6194.2 6204.4 6211.8 6227.2 6231.3 6152.5 6326.5 6424.3 6440.8 6579.4 6227.0 6289.8 6299.9 6301.6 6317.4 6402.4 6407.6 6430.1 6431.9 6433.4 5439.9 6247.9 6253.7 6254.1 6335.1 6367.2 6371.0 6376.0 6376.1 6379.1 5122.3 5146.9 5159.4 5180.1 5181.9 3824.5 3946.2 4202.6 4443.1 4517.9 6106.3 6111.8 6143.2 6163.5 6165.7 5624.4 6442.5 6479.6 6487.3 6513.6 5022.7 5819.8 5835.1 5947.2 5982.3 4970.5 5615.9 5672.9 5717.4 5725.6 6596.6 6652.5 6653.7 6656.7 6741.4 6465.0 6515.5 6522.7 6550.4 6551.2 5741.3 6308.7 6578.9 6607.2 6698.8 5270.6 6345.1 6362.2 6390.4 6401.4 6290.8 6449.5 6459.4 6464.5 6466.3 5974.5 6040.0 6262.3 6265.0 6307.6 6293.5 6316.8 6325.3 6325.9 6335.0 5605.4 5961.2 6310.9 6346.9 6353.3 6155.5 6253.2 6255.1 6272.8 6303.1 6216.1 6246.3 6300.3 6300.8 6325.1 16:45:41 INFO - PROCESS | 1646 | 16:45:41 INFO - PROCESS | 1646 | [#1] ext.html Cycles:180 Average:56349.40 Median:31552.92 stddev:116783.69 (370.1%) stddev-sans-first:117036.30 16:45:41 INFO - PROCESS | 1646 | Values: 606.0 796.3 816.1 950.5 955.3 33667.8 37596.6 38467.4 40122.6 40160.0 38229.0 38438.0 38443.8 38507.0 38530.6 7871.5 8025.0 10508.2 10765.2 10972.8 9040.7 10763.4 10841.8 10845.9 10855.7 9791.3 10276.9 10795.9 10844.1 10845.0 10764.7 10787.6 10796.5 10809.2 10828.1 10448.1 10460.5 10478.2 10518.7 10540.4 33982.5 34080.8 34089.0 34171.1 34265.5 3638.1 3657.6 3683.8 3736.6 3769.0 25444.9 25506.4 26066.3 26073.9 26146.0 3002.6 3054.3 3062.5 3068.6 3075.0 299051.0 371775.0 372539.0 372671.0 374766.1 35840.8 43565.8 43632.7 43742.3 43955.8 44806.8 45169.5 45188.3 45207.3 45217.8 13752.7 13756.2 13863.2 13882.9 13923.9 11829.9 11876.0 11905.5 11906.4 11921.9 10060.3 11261.1 11829.3 11921.9 12059.5 4584.0 5442.0 5466.3 5476.3 5495.7 1076.9 1106.0 1109.7 1110.8 1159.9 26160.5 26424.0 26591.6 26745.3 26799.7 1209.9 1219.5 1220.3 1248.7 1258.5 12238.8 13351.3 13699.9 13943.0 14085.2 474129.0 474447.0 477871.0 477875.0 479067.0 55830.9 55892.7 56030.7 56122.4 56144.4 1231.9 1388.5 1406.1 1422.6 1424.0 20677.6 28984.3 29955.7 30086.7 30686.2 51121.7 51314.5 51437.5 51556.5 51597.7 419723.0 479399.0 484159.6 484649.0 485073.0 31219.1 31497.4 31530.8 31545.8 31560.0 32955.3 36895.8 39203.6 40397.8 41058.4 31826.5 32230.0 32378.7 32393.0 33206.8 32737.5 32802.3 32889.0 33522.0 33924.7 32260.0 32736.0 32811.5 32834.7 32892.2 26098.7 26857.5 27792.3 28607.4 28632.9 22498.6 22924.5 22936.9 23025.5 23063.9 16:45:41 INFO - PROCESS | 1646 | 16:45:41 INFO - PROCESS | 1646 | [#2] jquery.html Cycles:180 Average:41508.74 Median:21863.43 stddev:122405.61 (559.9%) stddev-sans-first:122710.05 16:45:41 INFO - PROCESS | 1646 | Values: 386.2 413.7 415.8 416.4 454.4 19786.7 19886.9 20438.5 20824.4 21121.9 17219.0 20822.4 21194.5 21402.9 21537.2 6153.3 6530.1 6558.3 6565.0 6570.2 6179.3 6377.6 6491.8 6515.0 6551.1 6550.4 6553.5 6560.1 6560.5 6568.9 5993.9 6231.3 6313.8 6464.5 6483.9 5491.3 6103.1 6324.4 6365.3 6382.6 23166.8 23311.4 24016.4 25832.7 26167.7 2896.5 2899.4 3059.8 3184.9 3422.1 16394.8 16661.6 16723.0 16845.8 17295.9 2016.1 2022.5 2026.1 2028.3 2046.4 135593.0 136042.3 139681.0 142488.0 142506.0 20421.1 21817.7 22108.7 22407.8 22500.9 33960.3 34306.5 34816.7 34833.3 34896.7 10553.4 10793.0 11049.5 11089.6 11093.9 8955.8 8993.1 9019.1 9216.6 9228.5 10124.8 10245.5 10263.8 10367.6 10397.6 3895.0 3937.6 4229.2 4240.4 4285.9 882.3 907.1 933.9 944.9 957.7 20793.4 21909.1 21936.6 21958.0 21997.1 942.3 954.6 957.2 974.4 979.8 11140.4 11515.6 11621.7 11726.7 11775.6 713346.0 728362.0 728545.4 732789.0 788193.0 132772.3 133110.0 133945.3 135949.0 139891.0 1245.5 1246.9 1274.8 1276.1 1277.4 18483.0 19111.1 19131.0 19222.9 19258.8 45254.1 55778.5 57212.6 57286.4 58258.7 76254.6 77989.0 78427.0 79873.0 80145.0 11489.3 11933.8 11939.7 12053.9 12058.0 12783.0 12845.9 13196.9 13372.7 13555.7 9756.8 11345.9 11393.7 11481.4 11627.2 9708.5 10921.6 11323.8 11384.4 11829.8 10831.8 11559.3 11834.3 11858.6 11916.0 11743.4 11829.5 12041.4 12098.5 12405.9 11574.4 11610.4 11700.2 11825.6 11884.6 16:45:41 INFO - PROCESS | 1646 | 16:45:41 INFO - PROCESS | 1646 | [#3] mootools.html Cycles:180 Average:6759.63 Median:2407.66 stddev:4223.80 (175.4%) stddev-sans-first:4216.23 16:45:41 INFO - PROCESS | 1646 | Values: 1369.9 1405.4 1419.4 1471.5 1543.2 10648.2 11077.8 11232.8 11333.4 11338.3 9651.9 10958.0 11075.5 11116.2 11126.3 2118.8 2404.8 2427.7 2448.0 2454.5 2355.0 2380.1 2401.3 2406.1 2406.2 2170.7 2246.2 2393.6 2409.1 2410.1 2272.6 2293.7 2310.0 2310.3 2316.5 2297.3 2349.4 2400.1 2403.5 2410.7 11425.4 11451.1 11464.7 11477.0 11502.5 5259.2 5732.5 5780.7 5976.1 5994.0 3549.9 3665.3 3678.8 3730.5 3741.5 1167.3 1171.3 1225.2 1230.3 1236.9 13785.4 13982.1 14691.3 15427.4 15570.6 4706.1 4741.9 4753.3 4849.9 4857.7 10318.0 11541.8 11648.6 11674.3 11688.3 5839.2 5896.8 5908.4 5909.8 5938.0 5089.0 5289.7 5317.2 5353.9 5386.9 3875.4 3937.9 3961.8 4006.4 4035.1 1007.1 1061.1 1064.4 1079.2 1080.0 800.6 834.4 912.4 915.8 930.6 6519.9 6533.5 6551.7 6583.0 6774.0 996.3 1004.7 1015.1 1021.4 1026.8 3731.4 4200.8 4357.1 4414.8 4508.2 13838.4 13937.3 14117.6 14122.9 14173.0 13999.0 14016.0 14042.2 14057.6 14062.9 8606.3 10123.6 10283.0 10389.5 10630.3 6264.7 6603.9 6658.6 6820.2 6884.9 11717.1 11894.9 11927.8 11938.3 12002.3 11334.4 12334.5 12496.2 12648.0 12683.7 9208.0 9231.0 9383.3 9397.2 9456.9 10259.3 10288.8 10370.7 10389.9 10420.3 8982.1 9165.0 9226.4 9275.4 9408.1 8823.3 8826.8 8830.6 8871.3 8902.6 7889.2 7918.4 8001.5 8070.0 8076.4 4905.8 5911.9 5975.0 6001.3 6075.9 5476.4 5909.9 5937.6 6110.8 6217.3 16:45:41 INFO - PROCESS | 1646 | 16:45:41 INFO - PROCESS | 1646 | [#4] prototype.html Cycles:180 Average:4643.91 Median:4994.04 stddev:1280.02 (25.6%) stddev-sans-first:1242.27 16:45:41 INFO - PROCESS | 1646 | Values: 344.7 353.9 363.2 385.5 392.1 5050.8 5235.2 5238.7 5276.9 5296.4 4432.0 5230.5 5231.6 5256.9 5301.2 3848.8 4225.9 4305.7 4354.6 4387.3 4055.6 4185.4 4323.8 4421.2 4430.3 3848.7 4401.3 4405.9 4412.9 4438.2 3396.6 3654.4 4426.7 4446.1 4463.3 3764.7 4006.4 4026.4 4048.5 4056.7 5125.4 5156.7 5162.6 5180.7 5203.6 4510.9 4796.1 4986.5 5042.7 5094.2 4821.9 5018.8 5051.8 5352.8 5623.4 4166.4 4346.0 4526.5 4529.0 4565.9 5001.6 5672.7 5691.0 5926.5 5966.0 4132.6 4565.7 4585.0 4648.7 4658.6 4207.7 4405.2 4660.4 4691.4 4710.0 4582.2 4606.7 4832.4 4909.5 5010.8 4642.1 4679.4 5313.9 5576.6 5597.6 1412.1 1412.9 1465.9 1508.1 1638.0 1006.5 1108.6 1195.7 1201.3 1222.7 4607.7 4868.9 4962.2 5125.5 5229.5 4970.8 5027.3 5345.6 5626.0 5872.0 4430.5 4752.2 4856.6 4968.3 5188.8 3022.2 3024.0 3025.2 3046.6 3055.7 5311.7 5511.4 5948.3 6028.0 6041.7 5110.4 5320.8 5492.5 5549.7 5901.2 5210.5 5477.8 5481.6 5729.7 6068.9 5168.7 5304.3 5561.5 5699.7 5746.2 5382.5 5455.4 5595.9 5681.3 5729.2 5381.5 5586.5 5716.9 6025.4 6126.7 4614.9 4649.7 4691.4 4692.0 4696.3 4866.7 5125.0 5550.9 5802.3 5820.7 4954.5 4966.3 5654.8 5678.6 5711.4 4919.1 5316.6 5464.8 5644.9 5799.9 4910.0 5206.5 5501.8 5539.5 5540.2 4583.2 5304.6 5636.6 5710.3 5781.6 4440.3 5088.5 5329.5 5373.7 5553.3 16:45:41 INFO - PROCESS | 1646 | 16:45:41 INFO - PROCESS | 1646 | [#5] yui.html Cycles:180 Average:19701.85 Median:3693.19 stddev:27751.61 (751.4%) stddev-sans-first:27792.58 16:45:41 INFO - PROCESS | 1646 | Values: 646.7 701.0 758.8 779.7 823.5 14849.3 15678.0 15846.5 15876.8 15892.6 14262.7 14645.6 14713.8 14723.6 14739.3 930.1 941.7 946.0 946.1 949.2 903.7 923.3 932.5 937.8 938.7 976.4 978.7 980.0 984.4 984.4 968.3 973.4 973.5 974.4 975.0 722.3 723.0 723.7 727.4 728.5 15335.8 15912.3 15943.0 16463.6 16690.2 13631.4 13929.7 13970.7 13982.4 14005.4 11813.8 11852.5 11913.0 11950.2 12064.0 3587.4 3638.2 3684.8 3701.6 3712.0 105772.0 107574.4 108525.0 110967.4 111245.4 10696.6 10716.8 10774.7 10970.9 11030.2 22395.0 22457.8 22810.0 22813.7 22875.8 6890.0 7119.4 7792.9 7822.9 7947.9 4266.3 4378.8 4381.8 4404.5 4432.3 3321.4 3550.5 3577.9 3609.1 3609.2 642.8 643.4 645.2 645.6 652.4 661.2 673.8 675.9 687.7 719.8 10162.6 10248.4 10405.9 10474.4 10676.5 698.2 706.1 706.8 711.1 717.5 4706.7 5049.6 5056.2 5060.7 5061.5 76732.0 78478.0 85188.6 102778.5 108800.5 71366.0 78909.6 80122.2 84409.0 84924.0 48428.0 69023.3 77158.5 79472.0 79941.2 10699.4 11056.3 11227.9 11275.2 11325.3 49141.8 49943.3 51174.0 51361.0 51892.7 61618.0 63322.0 64570.0 64636.7 65347.7 11151.6 11382.0 12298.8 12432.9 12999.4 13883.8 14259.4 14536.9 14623.8 15135.5 11072.0 13105.2 13595.1 13656.7 14063.6 12783.3 13499.5 13662.6 13675.2 14441.6 12684.3 13433.6 13443.6 13672.0 13698.7 10125.9 10333.3 10458.0 10463.8 10762.5 7665.5 8420.8 9331.4 9877.5 10097.5 16:45:41 INFO - PROCESS | 1646 | -------- Summary: end -------- 16:45:41 INFO - PROCESS | 1646 | 16:45:42 INFO - TEST-INFO | 1646: exit 0 16:45:42 INFO - TEST-OK | dromaeo_css | took 1116668ms 16:45:42 INFO - TEST-START | kraken 16:45:42 INFO - operating with platform_type : mac_ 16:45:42 INFO - Initialising browser for kraken test... 16:45:42 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/tmpg8ep5U/profile http://localhost:49244/getInfo.html) 16:45:45 INFO - PROCESS | 1657 | 2017-03-06 16:45:45.437 plugin-container[1658:11418] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a43, name = 'com.apple.tsm.portname' 16:45:45 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:45:45 INFO - PROCESS | 1657 | 2017-03-06 16:45:45.440 plugin-container[1658:11418] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 16:45:45 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:45:45 INFO - PROCESS | 1657 | 2017-03-06 16:45:45.440 plugin-container[1658:11418] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:45:45 INFO - PROCESS | 1657 | __metrics Screen width/height:1600/1200 16:45:45 INFO - PROCESS | 1657 | colorDepth:24 16:45:45 INFO - PROCESS | 1657 | Browser inner width/height: 1024/689 16:45:45 INFO - PROCESS | 1657 | __metrics 16:45:45 ERROR - PROCESS | 1657 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3353: TypeError: this.tabs is undefined 16:45:46 INFO - TEST-INFO | 1657: exit 0 16:45:46 INFO - Browser initialized. 16:45:46 INFO - Running cycle 1/1 for kraken test... 16:45:46 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/tmpg8ep5U/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:45:49 INFO - PROCESS | 1659 | 2017-03-06 16:45:49.098 plugin-container[1660:11548] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x433b, name = 'com.apple.tsm.portname' 16:45:49 INFO - PROCESS | 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:45:49 INFO - PROCESS | 1659 | 2017-03-06 16:45:49.099 plugin-container[1660:11548] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 16:45:49 INFO - PROCESS | 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:45:49 INFO - PROCESS | 1659 | 2017-03-06 16:45:49.099 plugin-container[1660:11548] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:45:58 INFO - PROCESS | 1659 | RSS: Main: 220291072 16:45:58 INFO - PROCESS | 1659 | 16:46:01 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-beat-detection) 16:46:02 INFO - PROCESS | 1659 | RSS: Main: 234782720 16:46:02 INFO - PROCESS | 1659 | 16:46:05 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-dft) 16:46:05 INFO - PROCESS | 1659 | RSS: Main: 243023872 16:46:05 INFO - PROCESS | 1659 | 16:46:09 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-fft) 16:46:09 INFO - PROCESS | 1659 | RSS: Main: 246771712 16:46:09 INFO - PROCESS | 1659 | 16:46:11 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-oscillator) 16:46:12 INFO - PROCESS | 1659 | RSS: Main: 248426496 16:46:12 INFO - PROCESS | 1659 | 16:46:14 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:46:15 INFO - PROCESS | 1659 | RSS: Main: 248524800 16:46:15 INFO - PROCESS | 1659 | 16:46:18 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-darkroom) 16:46:19 INFO - PROCESS | 1659 | RSS: Main: 252399616 16:46:19 INFO - PROCESS | 1659 | 16:46:22 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-desaturate) 16:46:23 INFO - PROCESS | 1659 | RSS: Main: 252338176 16:46:23 INFO - PROCESS | 1659 | 16:46:26 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49244/tests/kraken/driver.html?testName=json-parse-financial) 16:46:27 INFO - PROCESS | 1659 | RSS: Main: 252203008 16:46:27 INFO - PROCESS | 1659 | 16:46:28 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49244/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:46:29 INFO - PROCESS | 1659 | RSS: Main: 251514880 16:46:29 INFO - PROCESS | 1659 | 16:46:31 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:46:32 INFO - PROCESS | 1659 | RSS: Main: 251498496 16:46:32 INFO - PROCESS | 1659 | 16:46:34 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:46:34 INFO - PROCESS | 1659 | RSS: Main: 251527168 16:46:34 INFO - PROCESS | 1659 | 16:46:37 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:46:38 INFO - PROCESS | 1659 | RSS: Main: 251858944 16:46:38 INFO - PROCESS | 1659 | 16:46:41 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:46:42 INFO - PROCESS | 1659 | RSS: Main: 251764736 16:46:42 INFO - PROCESS | 1659 | 16:46:44 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49244/tests/kraken/driver.html?testName=ai-astar) 16:46:44 INFO - PROCESS | 1659 | RSS: Main: 255225856 16:46:44 INFO - PROCESS | 1659 | 16:46:44 INFO - PROCESS | 1659 | __start_tp_report 16:46:44 INFO - PROCESS | 1659 | _x_x_mozilla_page_load 16:46:44 INFO - PROCESS | 1659 | _x_x_mozilla_page_load_details 16:46:44 INFO - PROCESS | 1659 | |i|pagename|runs| 16:46:44 INFO - PROCESS | 1659 | |0;ai-astar;118;126;168;121;119;120;120;120;121;118 16:46:44 INFO - PROCESS | 1659 | |1;audio-beat-detection;137;116;133;161;114;114;125;139;156;110 16:46:44 INFO - PROCESS | 1659 | |2;audio-dft;198;201;196;201;194;284;251;208;195;197 16:46:44 INFO - PROCESS | 1659 | |3;audio-fft;86;50;72;103;85;81;72;64;75;62 16:46:44 INFO - PROCESS | 1659 | |4;audio-oscillator;88;70;85;71;72;72;81;70;71;83 16:46:44 INFO - PROCESS | 1659 | |5;imaging-gaussian-blur;96;89;90;95;92;89;90;90;127;126 16:46:44 INFO - PROCESS | 1659 | |6;imaging-darkroom;144;129;97;101;97;98;96;137;99;125 16:46:44 INFO - PROCESS | 1659 | |7;imaging-desaturate;92;139;138;108;98;144;133;94;91;91 16:46:44 INFO - PROCESS | 1659 | |8;json-parse-financial;64;63;64;65;65;66;87;60;60;69 16:46:44 INFO - PROCESS | 1659 | |9;json-stringify-tinderbox;58;65;60;67;65;54;66;75;84;79 16:46:44 INFO - PROCESS | 1659 | |10;stanford-crypto-aes;94;77;85;78;96;100;75;103;101;100 16:46:44 INFO - PROCESS | 1659 | |11;stanford-crypto-ccm;142;166;139;130;150;161;151;153;208;192 16:46:44 INFO - PROCESS | 1659 | |12;stanford-crypto-pbkdf2;173;220;192;173;214;183;161;219;214;223 16:46:44 INFO - PROCESS | 1659 | |13;stanford-crypto-sha256-iterative;67;68;70;68;67;65;70;68;66;68 16:46:44 INFO - PROCESS | 1659 | __end_tp_report 16:46:44 INFO - PROCESS | 1659 | __start_cc_report 16:46:44 INFO - PROCESS | 1659 | _x_x_mozilla_cycle_collect,1030 16:46:44 INFO - PROCESS | 1659 | __end_cc_report 16:46:44 INFO - PROCESS | 1659 | __startTimestamp1488847604538__endTimestamp 16:46:44 INFO - PROCESS | 1659 | 16:46:44 INFO - PROCESS | 1659 | ------- Summary: start ------- 16:46:44 INFO - PROCESS | 1659 | Number of tests: 14 16:46:44 INFO - PROCESS | 1659 | 16:46:44 INFO - PROCESS | 1659 | [#0] ai-astar Cycles:10 Average:125.10 Median:120.50 stddev:15.24 (12.6%) stddev-sans-first:15.95 16:46:44 INFO - PROCESS | 1659 | Values: 118.0 126.0 168.0 121.0 119.0 120.0 120.0 120.0 121.0 118.0 16:46:44 INFO - PROCESS | 1659 | 16:46:44 INFO - PROCESS | 1659 | [#1] audio-beat-detection Cycles:10 Average:130.50 Median:135.00 stddev:17.97 (13.3%) stddev-sans-first:18.91 16:46:44 INFO - PROCESS | 1659 | Values: 137.0 116.0 133.0 161.0 114.0 114.0 125.0 139.0 156.0 110.0 16:46:44 INFO - PROCESS | 1659 | 16:46:44 INFO - PROCESS | 1659 | [#2] audio-dft Cycles:10 Average:212.50 Median:201.00 stddev:30.28 (15.1%) stddev-sans-first:31.66 16:46:44 INFO - PROCESS | 1659 | Values: 198.0 201.0 196.0 201.0 194.0 284.0 251.0 208.0 195.0 197.0 16:46:44 INFO - PROCESS | 1659 | 16:46:44 INFO - PROCESS | 1659 | [#3] audio-fft Cycles:10 Average:75.00 Median:73.50 stddev:14.81 (20.1%) stddev-sans-first:15.16 16:46:44 INFO - PROCESS | 1659 | Values: 86.0 50.0 72.0 103.0 85.0 81.0 72.0 64.0 75.0 62.0 16:46:44 INFO - PROCESS | 1659 | 16:46:44 INFO - PROCESS | 1659 | [#4] audio-oscillator Cycles:10 Average:76.30 Median:76.50 stddev:7.09 (9.3%) stddev-sans-first:6.12 16:46:44 INFO - PROCESS | 1659 | Values: 88.0 70.0 85.0 71.0 72.0 72.0 81.0 70.0 71.0 83.0 16:46:44 INFO - PROCESS | 1659 | 16:46:44 INFO - PROCESS | 1659 | [#5] imaging-gaussian-blur Cycles:10 Average:98.40 Median:90.00 stddev:15.01 (16.7%) stddev-sans-first:15.89 16:46:44 INFO - PROCESS | 1659 | Values: 96.0 89.0 90.0 95.0 92.0 89.0 90.0 90.0 127.0 126.0 16:46:44 INFO - PROCESS | 1659 | 16:46:44 INFO - PROCESS | 1659 | [#6] imaging-darkroom Cycles:10 Average:112.30 Median:96.50 stddev:19.14 (19.8%) stddev-sans-first:16.51 16:46:44 INFO - PROCESS | 1659 | Values: 144.0 129.0 97.0 101.0 97.0 98.0 96.0 137.0 99.0 125.0 16:46:44 INFO - PROCESS | 1659 | 16:46:44 INFO - PROCESS | 1659 | [#7] imaging-desaturate Cycles:10 Average:112.80 Median:91.00 stddev:22.81 (25.1%) stddev-sans-first:22.92 16:46:44 INFO - PROCESS | 1659 | Values: 92.0 139.0 138.0 108.0 98.0 144.0 133.0 94.0 91.0 91.0 16:46:44 INFO - PROCESS | 1659 | 16:46:44 INFO - PROCESS | 1659 | [#8] json-parse-financial Cycles:10 Average:66.30 Median:65.00 stddev:7.75 (11.9%) stddev-sans-first:8.17 16:46:44 INFO - PROCESS | 1659 | Values: 64.0 63.0 64.0 65.0 65.0 66.0 87.0 60.0 60.0 69.0 16:46:44 INFO - PROCESS | 1659 | 16:46:44 INFO - PROCESS | 1659 | [#9] json-stringify-tinderbox Cycles:10 Average:67.30 Median:66.50 stddev:9.45 (14.2%) stddev-sans-first:9.41 16:46:44 INFO - PROCESS | 1659 | Values: 58.0 65.0 60.0 67.0 65.0 54.0 66.0 75.0 84.0 79.0 16:46:44 INFO - PROCESS | 1659 | 16:46:44 INFO - PROCESS | 1659 | [#10] stanford-crypto-aes Cycles:10 Average:90.90 Median:77.50 stddev:11.04 (14.2%) stddev-sans-first:11.65 16:46:44 INFO - PROCESS | 1659 | Values: 94.0 77.0 85.0 78.0 96.0 100.0 75.0 103.0 101.0 100.0 16:46:44 INFO - PROCESS | 1659 | 16:46:44 INFO - PROCESS | 1659 | [#11] stanford-crypto-ccm Cycles:10 Average:159.20 Median:157.00 stddev:24.16 (15.4%) stddev-sans-first:24.81 16:46:44 INFO - PROCESS | 1659 | Values: 142.0 166.0 139.0 130.0 150.0 161.0 151.0 153.0 208.0 192.0 16:46:44 INFO - PROCESS | 1659 | 16:46:44 INFO - PROCESS | 1659 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:197.20 Median:214.00 stddev:23.42 (10.9%) stddev-sans-first:23.14 16:46:44 INFO - PROCESS | 1659 | Values: 173.0 220.0 192.0 173.0 214.0 183.0 161.0 219.0 214.0 223.0 16:46:44 INFO - PROCESS | 1659 | 16:46:44 INFO - PROCESS | 1659 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.70 Median:68.00 stddev:1.57 (2.3%) stddev-sans-first:1.64 16:46:44 INFO - PROCESS | 1659 | Values: 67.0 68.0 70.0 68.0 67.0 65.0 70.0 68.0 66.0 68.0 16:46:44 INFO - PROCESS | 1659 | -------- Summary: end -------- 16:46:44 INFO - PROCESS | 1659 | 16:46:45 INFO - TEST-INFO | 1659: exit 0 16:46:45 INFO - TEST-OK | kraken | took 62884ms 16:46:45 INFO - SUITE-END | took 1179s 16:46:45 INFO - Completed test suite (00:19:39) 16:46:45 INFO - javascript benchmark 16:46:45 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7676.550734167896, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3141.685831416858, 3450.585929688438, 3480.182157193059, 3505.859765609375, 3509.4911237870524, 6015.578909476339, 6016.969915150424, 6033.7888173913925, 6074.2710874694985, 6089.086637004451, 6033.1855199548045, 6105.786297479589, 6113.296970848353, 6115.785947491839, 6126.754929802813, 5420.783168673248, 5429.321334833146, 5496.780128794863, 5540.168974653794, 5554.750036248379, 5463.754131064112, 5505.944940550583, 5551.056320425538, 5571.749271282782, 5574.526165275956, 5305.1776974569, 5344.599155063347, 5476.890462190734, 5485.725713714318, 5502.614816962814, 5345.1714984177515, 5368.248445217672, 5391.541718953894, 5433.510984011418, 5433.646812957185, 5310.070737620901, 5376.542993845528, 5376.946230537684, 5376.973115134439, 5382.811601593965, 5393.946060539383, 5775.942240577582, 5970.893639093101, 6060.212172417601, 6072.271327440735, 5394.946050539523, 6248.343923888017, 6259.530535209878, 6285.622862628257, 6350.745970161188, 6434.774782882577, 6451.451626611743, 6452.032195170715, 6457.2896981332015, 6465.515086368541, 6194.194754681863, 6204.3795620437595, 6211.844703882394, 6227.190465239581, 6231.283402408781, 6152.472595605359, 6326.49388048955, 6424.26120996082, 6440.806775796734, 6579.407853293226, 6227.0, 6289.842753931097, 6299.937000630027, 6301.558890877686, 6317.399847014526, 6402.423781859655, 6407.615543067431, 6430.131932189094, 6431.871362572722, 6433.356664333529, 5439.864003399946, 6247.937520624827, 6253.7185826637915, 6254.093156492374, 6335.144755458046, 6367.172267605274, 6371.0, 6376.043593461018, 6376.107344971708, 6379.106925030499, 5122.257272695439, 5146.922796158061, 5159.355080614923, 5180.145276029489, 5181.948180518147, 3824.464574959508, 3946.2304850553865, 4202.579742025773, 4443.089166720879, 4517.909641807146, 6106.297775755756, 6111.757279333595, 6143.170671959318, 6163.506919446433, 6165.722542485599, 5624.409437009137, 6442.452391546771, 6479.611223326521, 6487.286398496161, 6513.609183449008, 5022.723750193702, 5819.82540523785, 5835.095560188178, 5947.197128387699, 5982.312034116046, 4970.527799859007, 5615.9157612635845, 5672.858178545569, 5717.42825717425, 5725.627834190765, 6596.571222870498, 6652.467802575782, 6653.733850646016, 6656.667166641551, 6741.426978706865, 6465.030245463218, 6515.54391192612, 6522.673866306761, 6550.377714117151, 6551.181102362205, 5741.3397459291045, 6308.653024083719, 6578.934210657832, 6607.2071351438135, 6698.765543205965, 5270.60470464709, 6345.1434056401795, 6362.172917520693, 6390.424861762278, 6401.391867772554, 6290.811275661737, 6449.548531602931, 6459.354064593691, 6464.450521705519, 6466.256380516301, 5974.462298393166, 6040.0, 6262.279837818527, 6265.0, 6307.621542707452, 6293.527985401021, 6316.778912738032, 6325.272593651789, 6325.905111423424, 6334.954732469276, 5605.4114317997655, 5961.155054989594, 6310.873782524232, 6346.936530634634, 6353.332900045613, 6155.538334624832, 6253.249610046823, 6255.093011513396, 6272.780452684134, 6303.054541818764, 6216.129741836237, 6246.281677607133, 6300.306966233619, 6300.842478938063, 6325.05124231369], "value": 5872.441959262751, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [605.9638018987531, 796.2634563029198, 816.0778320497836, 950.4819873169126, 955.2644463762898, 33667.831660841664, 37596.62403375969, 38467.422988655155, 40122.59877401225, 40160.0, 38229.0, 38438.0, 38443.80778096113, 38507.0, 38530.61469385305, 7871.465064312461, 8025.036995560541, 10508.159347252202, 10765.1926105542, 10972.835407468854, 9040.728778136667, 10763.3541987481, 10841.83737243942, 10845.891541084606, 10855.67432977012, 9791.314607977445, 10276.869544350115, 10795.892041079567, 10844.078253348474, 10845.0, 10764.73088172794, 10787.56849726014, 10796.460176991119, 10809.243352965257, 10828.079613232885, 10448.059674629243, 10460.535525026502, 10478.214133939984, 10518.684439466828, 10540.420276884768, 33982.49026264608, 34080.829595851865, 34089.0, 34171.145721356916, 34265.48601770975, 3638.1450359165574, 3657.56109266887, 3683.794387783008, 3736.6076561961145, 3769.0, 25444.872775636188, 25506.36234094144, 26066.34834129143, 26073.869630651916, 26145.954161833502, 3002.564628128909, 3054.282243672735, 3062.5099984002454, 3068.6164229471315, 3075.03136511998, 299051.0, 371775.0, 372539.0, 372671.0, 374766.12616937014, 35840.82079589585, 43565.78217108894, 43632.69101926947, 43742.343864842056, 43955.78022109869, 44806.77596611996, 45169.54830451719, 45188.3221751674, 45207.321890171675, 45217.77391113089, 13752.65618359529, 13756.243406612532, 13863.168209907439, 13882.930585347009, 13923.86076139246, 11829.940850295865, 11876.0, 11905.52377904874, 11906.404679765978, 11921.88078119225, 10060.346077504939, 11261.099112071015, 11829.349385783864, 11921.940390298167, 12059.517619295306, 4583.954160458419, 5442.0, 5466.262054622654, 5476.288082549243, 5495.670259784426, 1076.924615276923, 1105.9557617695364, 1109.6560066379448, 1110.8111621024284, 1159.9420028998516, 26160.476790464083, 26424.0, 26591.601125983125, 26745.33136671599, 26799.732002679724, 1209.8911098001222, 1219.451246938881, 1220.2861326124316, 1248.6691026877884, 1258.4714419943646, 12238.75522489563, 13351.265680387478, 13699.863001369858, 13943.023988320923, 14085.15489070659, 474129.0, 474447.0, 477871.0, 477875.0, 479067.0, 55830.883382332126, 55892.72053639706, 56030.719846400505, 56122.43877561172, 56144.438555613924, 1231.8891299783063, 1388.5279005138302, 1406.1492796857958, 1422.6016715319727, 1423.964400889986, 20677.58644827065, 28984.275393115342, 29955.70044299529, 30086.69913300839, 30686.232844179074, 51121.7443912778, 51314.48685513246, 51437.48562514327, 51556.484435155166, 51597.71005724887, 419723.0, 479399.0, 484159.57920210174, 484649.0, 485073.0, 31219.063428097194, 31497.370052598362, 31530.842345789042, 31545.842270789417, 31560.0, 32955.34089318248, 36895.815520922224, 39203.607963919996, 40397.798011009756, 41058.38412423876, 31826.522602160523, 32230.0, 32378.67621323851, 32393.028209153763, 33206.833965830985, 32737.50893736548, 32802.34395312128, 32889.0, 33522.0, 33924.66075339314, 32260.032199034067, 32736.0, 32811.50782738213, 32834.67165328412, 32892.1776955578, 26098.739012609632, 26857.462850742486, 27792.305192370353, 28607.42785144327, 28632.856835715687, 22498.550028999656, 22924.541509170056, 22936.8531573424, 23025.539489210456, 23063.88468057649], "value": 18363.600081634628, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [386.24489123763044, 413.6525318732266, 415.78587027680743, 416.4315709057138, 454.3933848312503, 19786.70319945202, 19886.90056549717, 20438.489037774063, 20824.375268741962, 21121.943902804855, 17219.0, 20822.375328740123, 21194.47013824655, 21402.89298553505, 21537.24619638313, 6153.323134455206, 6530.053142294364, 6558.278589355167, 6564.967175164117, 6570.211574611053, 6179.3202747697715, 6377.585456945284, 6491.772787952423, 6515.022746588026, 6551.082848401227, 6550.410463058324, 6553.541252112354, 6560.147180866505, 6560.475161987029, 6568.934310656904, 5993.850153746148, 6231.283402408691, 6313.779017734358, 6464.547481676284, 6483.935160648404, 5491.28613280271, 6103.084537319393, 6324.367563243685, 6365.3316401778075, 6382.61704297739, 23166.76833231663, 23311.417214569603, 24016.399590010216, 25832.741672583223, 26167.738322616722, 2896.4641541314923, 2899.420115976813, 3059.8301640224013, 3184.8693713731755, 3422.110251334646, 16394.754078688828, 16661.583460413465, 16723.0, 16845.831541684547, 17295.875768075035, 2016.1229865008768, 2022.5247066939257, 2026.0680087159924, 2028.3306508852042, 2046.4474591860264, 135593.0, 136042.31978840043, 139681.0, 142488.0, 142506.0, 20421.081051352725, 21817.727340898993, 22108.668369974464, 22407.77592224057, 22500.887495562743, 33960.32079358399, 34306.48540271898, 34816.65183348185, 34833.30333393318, 34896.65103348934, 10553.419561924167, 10793.02862742357, 11049.502772375263, 11089.55641774336, 11093.944530277458, 8955.776105597412, 8993.055729148477, 9019.052999434965, 9216.585253663601, 9228.538573071453, 10124.848127278097, 10245.538950747234, 10263.794724105475, 10367.63713270047, 10397.636082737068, 3894.9805250973563, 3937.6062393760967, 4229.175310814423, 4240.427542281815, 4285.871423857289, 882.3338379523547, 907.0747837206145, 933.8972713001565, 944.9149576538144, 957.7461972577272, 20793.376198714064, 21909.123635054737, 21936.561268774854, 21958.01188946501, 21997.120115195212, 942.3450701762209, 954.6467806911348, 957.1653636931164, 974.4007435427161, 979.7795496013482, 11140.442977851075, 11515.596954106566, 11621.709457263636, 11726.70683232926, 11775.646730598095, 713346.0, 728362.0, 728545.3572732102, 732789.0, 788193.0, 132772.3361383187, 133110.0, 133945.33027334997, 135949.0, 139891.0, 1245.5391505143155, 1246.8815462531045, 1274.7641686288252, 1276.1386064406488, 1277.3804704718204, 18483.0, 19111.139998699815, 19131.0, 19222.903885480482, 19258.8074119257, 45254.09491810211, 55778.48996795235, 57212.56968575653, 57286.42713572811, 58258.70870645619, 76254.61872690823, 77989.0, 78427.0, 79873.0, 80145.0, 11489.253198542068, 11933.82099268529, 11939.701507462383, 12053.879461205626, 12058.0, 12783.04127190483, 12845.90809781179, 13196.944244460228, 13372.732545348845, 13555.728885422039, 9756.756081098029, 11345.943270283597, 11393.715157121138, 11481.425928703364, 11627.244229125079, 9708.5145742714, 10921.617743378943, 11323.773524529628, 11384.43077846121, 11829.82255266189, 10831.837522437327, 11559.30644161353, 11834.349110798988, 11858.584949526725, 11916.046716262505, 11743.412829358327, 11829.467673954521, 12041.397930103634, 12098.516059357878, 12405.875941240833, 11574.363410012531, 11610.419479026184, 11700.239484433485, 11825.586104486301, 11884.643460695846], "value": 11497.28325742027, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1369.869857367672, 1405.3605609447695, 1419.354193841802, 1471.514400247918, 1543.2129613896916, 10648.201384896134, 11077.833832492519, 11232.775344493106, 11333.376664283462, 11338.263012904166, 9651.855222171656, 10958.0, 11075.50160242789, 11116.166287528427, 11126.276792008513, 2118.8093071623553, 2404.8316617836804, 2427.664784368599, 2447.984086604057, 2454.5459090068307, 2354.988225058872, 2380.0836677879056, 2401.2796161151587, 2406.109739396426, 2406.169871394366, 2170.6852506386563, 2246.2475070851283, 2393.5571919194917, 2409.144753612469, 2410.1323523531514, 2272.6022945984387, 2293.69035180251, 2310.041332846875, 2310.283812018263, 2316.548273086749, 2297.2993237062688, 2349.4126468382906, 2400.099962514057, 2403.519296140779, 2410.710714714228, 11425.428728563538, 11451.083913286917, 11464.656060318204, 11476.967072963389, 11502.482388292463, 5259.237410575483, 5732.538202758312, 5780.682062486561, 5976.073708575178, 5994.0, 3549.893503194908, 3665.3402387570227, 3678.8160591970563, 3730.5150330457136, 3741.5323084614424, 1167.270455965022, 1171.2621048739325, 1225.2158618484177, 1230.267990545624, 1236.8886800187936, 13785.379657915419, 13982.091164074303, 14691.265436728121, 15427.3829046838, 15570.610734731608, 4706.0823139487475, 4741.875695045101, 4753.263244197155, 4849.903001940012, 4857.684250523705, 10317.968203179622, 11541.76916461666, 11648.592299269485, 11674.299542027506, 11688.298702077902, 5839.153322768175, 5896.823095307148, 5908.409159084057, 5909.763609455659, 5938.049912014057, 5088.974555127201, 5289.68261904287, 5317.2024196370085, 5353.892922141536, 5386.892262154735, 3875.418687196887, 3937.8580211738276, 3961.841526338972, 4006.358982562776, 4035.051762835731, 1007.09361574582, 1061.1192710050639, 1064.3613831700918, 1079.2445288298222, 1080.0387654987069, 800.5997001499251, 834.3783881008566, 912.4160537256162, 915.8122584870218, 930.6463543853292, 6519.8696026079215, 6533.477321814244, 6551.672416379162, 6582.967085164543, 6774.0, 996.3324572536418, 1004.6634377483479, 1015.1016350529686, 1021.4382089850552, 1026.779242462874, 3731.4216296473546, 4200.810963506588, 4357.128574285156, 4414.757188354607, 4508.233600287959, 13838.377273022737, 13937.303134843218, 14117.647058823406, 14122.85877141236, 14173.00788944764, 13999.0, 14016.0, 14042.1574705518, 14057.578272651837, 14062.929685351508, 8606.31149508038, 10123.622615227749, 10283.0, 10389.532471038821, 10630.30902991303, 6264.749410023548, 6603.9009414859775, 6658.63377514222, 6820.181578210448, 6884.862302753817, 11717.06263498935, 11894.881051189377, 11927.76144477123, 11938.343391113225, 12002.279863208236, 11334.433278336215, 12334.50661973511, 12496.187747796364, 12648.0, 12683.746325073262, 9207.953960230156, 9230.953845230732, 9383.343165978316, 9397.154256116983, 9456.905430945602, 10259.281850270709, 10288.845667314847, 10370.740731481472, 10389.9480502597, 10420.270581059556, 8982.146696064125, 9165.0, 9226.35415520934, 9275.350725449154, 9408.106229908148, 8823.338249631175, 8826.823463530565, 8830.558472076242, 8871.290296776371, 8902.55487225649, 7889.171636978034, 7918.445708800559, 8001.479903806234, 8069.959650201711, 8076.394270429624, 4905.77923993414, 5911.911321330097, 5975.0, 6001.279846418283, 6075.908861366995, 5476.424975377688, 5909.85225369352, 5937.615559131426, 6110.847228819315, 6217.285012223471], "value": 5139.482816055289, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [344.6949449736982, 353.9221371298315, 363.18283861312045, 385.51810237203495, 392.1001302011882, 5050.84847454577, 5235.246192525504, 5238.694974211345, 5276.894462110756, 5296.443873393296, 4431.977840110803, 5230.457015180521, 5231.633785635007, 5256.868578285545, 5301.1783173608155, 3848.7690738555775, 4225.873223803275, 4305.720128191665, 4354.564543545652, 4387.332813530854, 4055.5944405559358, 4185.434966279544, 4323.827046918135, 4421.226285400058, 4430.335449682541, 3848.6536211740936, 4401.317795741666, 4405.911881762363, 4412.9479791896765, 4438.201123797715, 3396.624367131316, 3654.3604869148057, 4426.734395936254, 4446.088551846879, 4463.285874260119, 3764.6800021998165, 4006.4390985262085, 4026.355783074694, 4048.5141782985934, 4056.695747818896, 5125.384953805567, 5156.719922464383, 5162.561182299509, 5180.663256888328, 5203.60972927032, 4510.864674059783, 4796.057596673318, 4986.4764199758865, 5042.672226305279, 5094.2103973884105, 4821.855344339676, 5018.774155163026, 5051.848444546669, 5352.751844225396, 5623.437656234385, 4166.354215096665, 4345.956540434587, 4526.456825180966, 4529.04889973106, 4565.863024109282, 5001.599872010231, 5672.659640421588, 5691.0, 5926.466618004357, 5965.970170149121, 4132.648724858361, 4565.657575681837, 4584.9541504585195, 4648.744319062416, 4658.604018658384, 4207.663386929038, 4405.185040767468, 4660.4174478190225, 4691.390119284471, 4709.987352719182, 4582.198115329804, 4606.742696078964, 4832.371791667061, 4909.5090490950615, 5010.824621138316, 4642.141203877293, 4679.438467383868, 5313.946860531422, 5576.6375185613615, 5597.552195824324, 1412.131539103444, 1412.8869690424742, 1465.9371955332513, 1508.0951429142426, 1638.0, 1006.4952895421392, 1108.578740078765, 1195.678774953681, 1201.3212534917743, 1222.6821026533166, 4607.746573938465, 4868.9269660955115, 4962.15643340633, 5125.461826508163, 5229.450907654642, 4970.757310672332, 5027.346444962204, 5345.625806193607, 5626.0, 5872.0, 4430.534793846664, 4752.215884379108, 4856.635752318591, 4968.30443737877, 5188.792448302026, 3022.2293315204483, 3024.04948808022, 3025.1848890665865, 3046.6496352919253, 3055.724984751383, 5311.7344132793205, 5511.366192887789, 5948.282323547783, 6027.955882279421, 6041.667708276087, 5110.386753589519, 5320.76056577455, 5492.533134683516, 5549.72251387429, 5901.232839730807, 5210.47895210476, 5477.75350109238, 5481.561475081984, 5729.682173100127, 6068.939310606837, 5168.689878607295, 5304.302623160552, 5561.4973262031435, 5699.715014249354, 5746.224259724883, 5382.4886635769535, 5455.427180146186, 5595.8880822384135, 5681.261436013292, 5729.197912292366, 5381.48875856793, 5586.52514536269, 5716.911948366041, 6025.367336429787, 6126.722203621041, 4614.930776038429, 4649.65127615437, 4691.381473391674, 4692.0, 4696.272077827805, 4866.683665561727, 5124.9743751281, 5550.861228469321, 5802.303723553201, 5820.650760954356, 4954.479779623088, 4966.304717339503, 5654.802081927113, 5678.608740858516, 5711.371749107678, 4919.080919080919, 5316.6012549058205, 5464.808731694372, 5644.915326270191, 5799.8550036249435, 4910.017996400663, 5206.453322401245, 5501.807436739695, 5539.529140023144, 5540.224368588481, 4583.197940360357, 5304.60215483848, 5636.633618814764, 5710.2576665033275, 5781.566382521243, 4440.311751678495, 5088.491150884882, 5329.521772375864, 5373.722029562042, 5553.250311207935], "value": 4263.8252794844475, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [646.7154452041104, 701.0045735056219, 758.7534051433286, 779.6842278877053, 823.4770920465504, 14849.257537123141, 15678.0, 15846.524604261862, 15876.761848572252, 15892.602684932883, 14262.714745705105, 14645.560633180996, 14713.779293310583, 14723.631909202275, 14739.263036848168, 930.0885132570069, 941.6892425499603, 946.0114180681205, 946.0870260198907, 949.2216382566298, 903.6791938861708, 923.2521657457447, 932.5430539035891, 937.8452555328397, 938.737153596994, 976.3556053005018, 978.6672531339343, 980.0444566547594, 984.3749219245794, 984.4339504784741, 968.2544440780559, 973.3673112476911, 973.522973742868, 974.3763991045737, 975.0395860077817, 722.2849379114691, 722.9530080544748, 723.7032816545192, 727.3926271563251, 728.4645785347765, 15335.769963450557, 15912.283947222402, 15943.0, 16463.588410289718, 16690.165491725365, 13631.386587603582, 13929.721405571832, 13970.650733731636, 13982.440702371892, 14005.439782408692, 11813.763724725542, 11852.472841409795, 11913.0, 11950.163488555805, 12064.0, 3587.4080776671817, 3638.163222458823, 3684.778913265213, 3701.592824789271, 3711.981440092782, 105772.0, 107574.3863842043, 108525.0, 110967.44516277447, 111245.44377278142, 10696.625618103484, 10716.78566428667, 10774.676759697222, 10970.890291096986, 11030.2278840482, 22395.0, 22457.77542224557, 22809.973551190236, 22813.657795133087, 22875.771242287698, 6889.965550172317, 7119.3592576667315, 7792.883106753403, 7822.882656760153, 7947.887295778595, 4266.274733295348, 4378.824847006084, 4381.846635367747, 4404.471463424409, 4432.268675668543, 3321.368939901411, 3550.467429885486, 3577.856885724594, 3609.097725568608, 3609.22401683634, 642.7557528139278, 643.4498503779295, 645.1838424393146, 645.6158585641535, 652.4030512081487, 661.1867403094125, 673.8281738156836, 675.898615207713, 687.7180356053993, 719.8380364417964, 10162.593496260215, 10248.38509689421, 10405.9479702601, 10474.423906685206, 10676.519556619816, 698.1587187439123, 706.0503622627558, 706.8338940349014, 711.1040089487283, 717.5049216040915, 4706.741129237925, 5049.6212784041345, 5056.191009438546, 5060.746962651852, 5061.493850614909, 76732.0, 78478.0, 85188.57405713055, 102778.48610756898, 108800.4559977195, 71366.0, 78909.60545197237, 80122.19877801264, 84409.0, 84924.0, 48428.0, 69023.30976690269, 77158.45683086419, 79472.0, 79941.20058799337, 10699.358038517714, 11056.281341712764, 11227.943860280646, 11275.210735248782, 11325.263857849213, 49141.75429122832, 49943.250851237986, 51174.0, 51361.0, 51892.740536297075, 61618.0, 63322.0, 64570.0, 64636.67681661562, 65347.67326163339, 11151.609693660683, 11382.032527235278, 12298.75402491963, 12432.88104070638, 12999.415026324028, 13883.791743124064, 14259.358328875433, 14536.90973177037, 14623.780643290147, 15135.470258540898, 11072.0, 13105.2136871788, 13595.116317439335, 13656.726865462833, 14063.578092657237, 12783.296918669563, 13499.460021599027, 13662.590122296348, 13675.24786136772, 14441.638959026108, 12684.302363370103, 13433.596992090253, 13443.596692099252, 13672.042956992915, 13698.65753356174, 10125.89874101279, 10333.276670632984, 10458.0, 10463.790724185626, 10762.51568679427, 7665.501742386727, 8420.790103751047, 9331.393459425115, 9877.506124693593, 10097.495125243562], "value": 7531.234883872759, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1591.5, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 126.0, 168.0, 121.0, 119.0, 120.0, 120.0, 120.0, 121.0, 118.0], "value": 125.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 116.0, 133.0, 161.0, 114.0, 114.0, 125.0, 139.0, 156.0, 110.0], "value": 130.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 201.0, 196.0, 201.0, 194.0, 284.0, 251.0, 208.0, 195.0, 197.0], "value": 212.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 50.0, 72.0, 103.0, 85.0, 81.0, 72.0, 64.0, 75.0, 62.0], "value": 75.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 70.0, 85.0, 71.0, 72.0, 72.0, 81.0, 70.0, 71.0, 83.0], "value": 76.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 89.0, 90.0, 95.0, 92.0, 89.0, 90.0, 90.0, 127.0, 126.0], "value": 98.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 129.0, 97.0, 101.0, 97.0, 98.0, 96.0, 137.0, 99.0, 125.0], "value": 112.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 139.0, 138.0, 108.0, 98.0, 144.0, 133.0, 94.0, 91.0, 91.0], "value": 112.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 64.0, 65.0, 65.0, 66.0, 87.0, 60.0, 60.0, 69.0], "value": 66.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 65.0, 60.0, 67.0, 65.0, 54.0, 66.0, 75.0, 84.0, 79.0], "value": 67.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 77.0, 85.0, 78.0, 96.0, 100.0, 75.0, 103.0, 101.0, 100.0], "value": 90.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 166.0, 139.0, 130.0, 150.0, 161.0, 151.0, 153.0, 208.0, 192.0], "value": 159.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 220.0, 192.0, 173.0, 214.0, 183.0, 161.0, 219.0, 214.0, 223.0], "value": 197.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 70.0, 68.0, 67.0, 65.0, 70.0, 68.0, 66.0, 68.0], "value": 67.7, "unit": "score"}]}]} 16:46:45 INFO - Return code: 0 16:46:45 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:46:45 ERROR - # TBPL SUCCESS # 16:46:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:46:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:46:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:46:45 INFO - 2017-03-06 16:46:45.853 screenresolution[1664:11947] starting screenresolution argv=screenresolution get 16:46:45 INFO - 2017-03-06 16:46:45.855 screenresolution[1664:11947] Display 0: 1600x1200x32@60 16:46:45 INFO - 2017-03-06 16:46:45.861 screenresolution[1665:11950] starting screenresolution argv=screenresolution list 16:46:45 INFO - Available Modes on Display 0 16:46:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:46:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:46:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:46:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:46:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:46:46 INFO - Intel Iris: 16:46:46 INFO - Chipset Model: Intel Iris 16:46:46 INFO - Type: GPU 16:46:46 INFO - Bus: Built-In 16:46:46 INFO - VRAM (Dynamic, Max): 1536 MB 16:46:46 INFO - Vendor: Intel (0x8086) 16:46:46 INFO - Device ID: 0x0a2e 16:46:46 INFO - Revision ID: 0x0009 16:46:46 INFO - Displays: 16:46:46 INFO - Display: 16:46:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:46:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:46:46 INFO - Display Serial Number: 1600x1200 60 16:46:46 INFO - Main Display: Yes 16:46:46 INFO - Mirror: Off 16:46:46 INFO - Online: Yes 16:46:46 INFO - Rotation: Supported 16:46:46 INFO - Return code: 0 16:46:46 INFO - Running post-action listener: _resource_record_post_action 16:46:46 INFO - [mozharness: 2017-03-07 00:46:46.098303Z] Finished run-tests step (success) 16:46:46 INFO - Running post-run listener: _resource_record_post_run 16:46:46 INFO - Total resource usage - Wall time: 1201s; CPU: 55.0%; Read bytes: 34882048; Write bytes: 514655232; Read time: 324; Write time: 1720 16:46:46 INFO - TinderboxPrint: CPU usage
54.7% 16:46:46 INFO - TinderboxPrint: I/O read bytes / time
34,882,048 / 324 16:46:46 INFO - TinderboxPrint: I/O write bytes / time
514,655,232 / 1,720 16:46:46 INFO - TinderboxPrint: CPU idle
2,175.4 (45.3%) 16:46:46 INFO - TinderboxPrint: CPU system
964.8 (20.1%) 16:46:46 INFO - TinderboxPrint: CPU user
1,665.6 (34.7%) 16:46:46 INFO - TinderboxPrint: Swap in / out
239,759,360 / 0 16:46:46 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 201015808; Write bytes: 200779264; Read time: 15408; Write time: 411 16:46:46 INFO - run-tests - Wall time: 1181s; CPU: 55.0%; Read bytes: 33890816; Write bytes: 303984128; Read time: 306; Write time: 1275 16:46:46 INFO - Running post-run listener: _upload_blobber_files 16:46:46 INFO - Blob upload gear active. 16:46:46 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:46:46 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 16:46:46 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:46:46 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:46:47 INFO - (blobuploader) - INFO - Open directory for files ... 16:46:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:46:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:46:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:46:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:46:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:46:48 INFO - (blobuploader) - INFO - Done attempting. 16:46:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:46:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:46:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:46:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:46:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:46:49 INFO - (blobuploader) - INFO - Done attempting. 16:46:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:46:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:46:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:46:50 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:46:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:46:50 INFO - (blobuploader) - INFO - Done attempting. 16:46:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:46:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:46:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:46:52 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:46:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:46:52 INFO - (blobuploader) - INFO - Done attempting. 16:46:52 INFO - (blobuploader) - INFO - Iteration through files over. 16:46:52 INFO - Return code: 0 16:46:52 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:46:52 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:46:52 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/29e5d0d5ea3bffa6ead0cef3c15e80e29641ebb7a7f54c447f011fe5f0a93b98b98b74ae37606f7c4d764c7d788f3cadf6efed296b8cc5a41baf62dff8aee9dd", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/eb8611c2fe959684b04966da210d31b78b3036551499055a38be7917d005c4d0794b85d9de145e972a9f5890c925be26c83ae9342482db4ce9de187e201c335a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a228e4f7cbfaa3349f000c50de8cb46a7d25186fcb5a245f352a3a1bc01150763d25ce707aef3a80955fe135aaa6c1635523ba829aa609efce9ece15799545c0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/429e095185e96eed5bfdb6d46516de3b128f29850cbeed6486a89c451974a021b0e9ddc4a65f168200a7999bbc33b62aa7535438e8cb1db1ea7e27a6e519931d"} 16:46:52 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:46:52 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:46:52 INFO - Contents: 16:46:52 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/29e5d0d5ea3bffa6ead0cef3c15e80e29641ebb7a7f54c447f011fe5f0a93b98b98b74ae37606f7c4d764c7d788f3cadf6efed296b8cc5a41baf62dff8aee9dd", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/eb8611c2fe959684b04966da210d31b78b3036551499055a38be7917d005c4d0794b85d9de145e972a9f5890c925be26c83ae9342482db4ce9de187e201c335a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a228e4f7cbfaa3349f000c50de8cb46a7d25186fcb5a245f352a3a1bc01150763d25ce707aef3a80955fe135aaa6c1635523ba829aa609efce9ece15799545c0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/429e095185e96eed5bfdb6d46516de3b128f29850cbeed6486a89c451974a021b0e9ddc4a65f168200a7999bbc33b62aa7535438e8cb1db1ea7e27a6e519931d"} 16:46:52 INFO - Running post-run listener: copy_logs_to_upload_dir 16:46:52 INFO - Copying logs to upload dir... 16:46:52 INFO - mkdir: /builds/slave/test/build/upload/logs 16:46:52 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1283.418350 ========= master_lag: 1.63 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 25 secs) (at 2017-03-06 16:46:54.237357) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-06 16:46:54.240764) ========= 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.tfI0DFnF2K/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.nLWClWbWlC/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/29e5d0d5ea3bffa6ead0cef3c15e80e29641ebb7a7f54c447f011fe5f0a93b98b98b74ae37606f7c4d764c7d788f3cadf6efed296b8cc5a41baf62dff8aee9dd", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/eb8611c2fe959684b04966da210d31b78b3036551499055a38be7917d005c4d0794b85d9de145e972a9f5890c925be26c83ae9342482db4ce9de187e201c335a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a228e4f7cbfaa3349f000c50de8cb46a7d25186fcb5a245f352a3a1bc01150763d25ce707aef3a80955fe135aaa6c1635523ba829aa609efce9ece15799545c0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/429e095185e96eed5bfdb6d46516de3b128f29850cbeed6486a89c451974a021b0e9ddc4a65f168200a7999bbc33b62aa7535438e8cb1db1ea7e27a6e519931d"} build_url:https://queue.taskcluster.net/v1/task/I6LWlIepTaqY9F-11WQl6Q/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011155 build_url: 'https://queue.taskcluster.net/v1/task/I6LWlIepTaqY9F-11WQl6Q/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/29e5d0d5ea3bffa6ead0cef3c15e80e29641ebb7a7f54c447f011fe5f0a93b98b98b74ae37606f7c4d764c7d788f3cadf6efed296b8cc5a41baf62dff8aee9dd", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/eb8611c2fe959684b04966da210d31b78b3036551499055a38be7917d005c4d0794b85d9de145e972a9f5890c925be26c83ae9342482db4ce9de187e201c335a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a228e4f7cbfaa3349f000c50de8cb46a7d25186fcb5a245f352a3a1bc01150763d25ce707aef3a80955fe135aaa6c1635523ba829aa609efce9ece15799545c0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/429e095185e96eed5bfdb6d46516de3b128f29850cbeed6486a89c451974a021b0e9ddc4a65f168200a7999bbc33b62aa7535438e8cb1db1ea7e27a6e519931d"}' ========= master_lag: 0.04 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-06 16:46:54.288785) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-06 16:46:54.289093) ========= 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.tfI0DFnF2K/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.nLWClWbWlC/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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.005066 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-06 16:46:54.341091) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-06 16:46:54.341402) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-06 16:46:54.341701) ========= ========= Total master_lag: 1.81 =========