builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0119 starttime: 1491280305.24 results: success (0) buildid: 20170403200227 builduid: c09aea074b9c43db92aec2005599636d revision: e3ce3282ec26d1cff472f2bed8c65693ea168ff8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-03 21:31:45.241526) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-03 21:31:45.241988) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-03 21:31:45.242266) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-03 21:31:45.284513) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-03 21:31:45.284912) ========= 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.RH0K1cuKUI/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.F8qx1bnCvA/Listeners TMPDIR=/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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-04-03 21:31:45-- 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% 9.74M=0.001s 2017-04-03 21:31:45 (9.74 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.496001 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-03 21:31:45.805335) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-03 21:31:45.805662) ========= 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.RH0K1cuKUI/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.F8qx1bnCvA/Listeners TMPDIR=/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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.030899 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-03 21:31:45.859150) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-03 21:31:45.859503) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev e3ce3282ec26d1cff472f2bed8c65693ea168ff8 --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 e3ce3282ec26d1cff472f2bed8c65693ea168ff8 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.RH0K1cuKUI/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.F8qx1bnCvA/Listeners TMPDIR=/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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-04-03 21:31:45,992 truncating revision to first 12 chars 2017-04-03 21:31:45,993 Setting DEBUG logging. 2017-04-03 21:31:45,993 attempt 1/10 2017-04-03 21:31:45,993 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/e3ce3282ec26?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-03 21:31:46,288 unpacking tar archive at: mozilla-aurora-e3ce3282ec26/testing/mozharness/ program finished with exit code 0 elapsedTime=0.597833 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-03 21:31:46.483028) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-03 21:31:46.483374) ========= script_repo_revision: e3ce3282ec26d1cff472f2bed8c65693ea168ff8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-03 21:31:46.483755) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-03 21:31:46.484045) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-03 21:31:46.504894) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2017-04-03 21:31:46.505352) ========= /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.RH0K1cuKUI/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.F8qx1bnCvA/Listeners TMPDIR=/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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 21:31:46 INFO - MultiFileLogger online at 20170403 21:31:46 in /builds/slave/test 21:31:46 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 21:31:46 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:31:46 INFO - {'append_to_log': False, 21:31:46 INFO - 'base_work_dir': '/builds/slave/test', 21:31:46 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 21:31:46 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:31:46 INFO - 'branch': 'Mozilla-Aurora', 21:31:46 INFO - 'buildbot_json_path': 'buildprops.json', 21:31:46 INFO - 'config_files': ('talos/mac_config.py',), 21:31:46 INFO - 'default_actions': ('clobber', 21:31:46 INFO - 'read-buildbot-config', 21:31:46 INFO - 'download-and-extract', 21:31:46 INFO - 'populate-webroot', 21:31:46 INFO - 'create-virtualenv', 21:31:46 INFO - 'install', 21:31:46 INFO - 'run-tests'), 21:31:46 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:31:46 INFO - 'download_minidump_stackwalk': True, 21:31:46 INFO - 'download_symbols': 'ondemand', 21:31:46 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:31:46 INFO - 'tooltool.py': '/tools/tooltool.py', 21:31:46 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:31:46 INFO - '/tools/misc-python/virtualenv.py')}, 21:31:46 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:31:46 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:31:46 INFO - 'gecko_profile': False, 21:31:46 INFO - 'gecko_profile_interval': 0, 21:31:46 INFO - 'installer_path': 'installer.exe', 21:31:46 INFO - 'log_level': 'info', 21:31:46 INFO - 'log_name': 'talos', 21:31:46 INFO - 'log_to_console': True, 21:31:46 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 21:31:46 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 21:31:46 INFO - 'opt_config_files': (), 21:31:46 INFO - 'pip_index': False, 21:31:46 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:31:46 INFO - 'cmd': ('bash', 21:31:46 INFO - '-c', 21:31:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:31:46 INFO - 'enabled': True, 21:31:46 INFO - 'halt_on_failure': False, 21:31:46 INFO - 'name': 'check_screen_resolution'},), 21:31:46 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:31:46 INFO - 'cmd': ('bash', 21:31:46 INFO - '-c', 21:31:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:31:46 INFO - 'enabled': True, 21:31:46 INFO - 'halt_on_failure': False, 21:31:46 INFO - 'name': 'check_screen_resolution'},), 21:31:46 INFO - 'run_cmd_checks_enabled': True, 21:31:46 INFO - 'suite': 'dromaeojs-e10s', 21:31:46 INFO - 'system_bits': '32', 21:31:46 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:31:46 INFO - 'title': 't-yosemite-r7-0119', 21:31:46 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:31:46 INFO - 'use_talos_json': True, 21:31:46 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:31:46 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:31:46 INFO - 'work_dir': 'build'} 21:31:46 INFO - [mozharness: 2017-04-04 04:31:46.722974Z] Running clobber step. 21:31:46 INFO - Running pre-action listener: _resource_record_pre_action 21:31:46 INFO - Running main action method: clobber 21:31:46 INFO - rmtree: /builds/slave/test/build 21:31:46 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:31:49 INFO - Running post-action listener: _resource_record_post_action 21:31:49 INFO - [mozharness: 2017-04-04 04:31:49.158418Z] Finished clobber step (success) 21:31:49 INFO - [mozharness: 2017-04-04 04:31:49.158541Z] Running read-buildbot-config step. 21:31:49 INFO - Running pre-action listener: _resource_record_pre_action 21:31:49 INFO - Running main action method: read_buildbot_config 21:31:49 INFO - Using buildbot properties: 21:31:49 INFO - { 21:31:49 INFO - "project": "", 21:31:49 INFO - "product": "firefox", 21:31:49 INFO - "script_repo_revision": "production", 21:31:49 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 21:31:49 INFO - "repository": "", 21:31:49 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 21:31:49 INFO - "buildid": "20170403200227", 21:31:49 INFO - "pgo_build": "False", 21:31:49 INFO - "basedir": "/builds/slave/test", 21:31:49 INFO - "buildnumber": 36, 21:31:49 INFO - "slavename": "t-yosemite-r7-0119", 21:31:49 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 21:31:49 INFO - "platform": "yosemite_r7", 21:31:49 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 21:31:49 INFO - "branch": "mozilla-aurora", 21:31:49 INFO - "repo_path": "releases/mozilla-aurora", 21:31:49 INFO - "revision": "e3ce3282ec26d1cff472f2bed8c65693ea168ff8", 21:31:49 INFO - "stage_platform": "macosx64", 21:31:49 INFO - "builduid": "c09aea074b9c43db92aec2005599636d", 21:31:49 INFO - "slavebuilddir": "test" 21:31:49 INFO - } 21:31:49 INFO - Found installer url https://queue.taskcluster.net/v1/task/PmyUCak6ShatAiOjN46MCw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 21:31:49 INFO - Running post-action listener: _resource_record_post_action 21:31:49 INFO - [mozharness: 2017-04-04 04:31:49.161751Z] Finished read-buildbot-config step (success) 21:31:49 INFO - [mozharness: 2017-04-04 04:31:49.161852Z] Running download-and-extract step. 21:31:49 INFO - Running pre-action listener: _resource_record_pre_action 21:31:49 INFO - Running main action method: download_and_extract 21:31:49 INFO - mkdir: /builds/slave/test/build/tests 21:31:49 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')]} 21:31:49 INFO - trying https://queue.taskcluster.net/v1/task/PmyUCak6ShatAiOjN46MCw/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 21:31:49 INFO - Downloading https://queue.taskcluster.net/v1/task/PmyUCak6ShatAiOjN46MCw/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 21:31:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PmyUCak6ShatAiOjN46MCw/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 21:31:50 INFO - Downloaded 1199 bytes. 21:31:50 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 21:31:50 INFO - Using the following test package requirements: 21:31:50 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 21:31:50 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 21:31:50 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 21:31:50 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 21:31:50 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 21:31:50 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 21:31:50 INFO - u'jsshell-mac.zip'], 21:31:50 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 21:31:50 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 21:31:50 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 21:31:50 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 21:31:50 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 21:31:50 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 21:31:50 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 21:31:50 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 21:31:50 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 21:31:50 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 21:31:50 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 21:31:50 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 21:31:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PmyUCak6ShatAiOjN46MCw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 21:31:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PmyUCak6ShatAiOjN46MCw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 21:31:50 INFO - Fetch https://queue.taskcluster.net/v1/task/PmyUCak6ShatAiOjN46MCw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 21:31:52 INFO - Expected file size: 51790960 21:31:52 INFO - Obtained file size: 51790960 21:32:08 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 21:32:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PmyUCak6ShatAiOjN46MCw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 21:32:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PmyUCak6ShatAiOjN46MCw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 21:32:08 INFO - Fetch https://queue.taskcluster.net/v1/task/PmyUCak6ShatAiOjN46MCw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 21:32:10 INFO - Expected file size: 51790960 21:32:10 INFO - Obtained file size: 51790960 21:32:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PmyUCak6ShatAiOjN46MCw/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 21:32:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PmyUCak6ShatAiOjN46MCw/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 21:32:25 INFO - Fetch https://queue.taskcluster.net/v1/task/PmyUCak6ShatAiOjN46MCw/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 21:32:26 INFO - Expected file size: 14404438 21:32:26 INFO - Obtained file size: 14404438 21:32:27 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')]} 21:32:27 INFO - trying https://queue.taskcluster.net/v1/task/PmyUCak6ShatAiOjN46MCw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 21:32:27 INFO - Downloading https://queue.taskcluster.net/v1/task/PmyUCak6ShatAiOjN46MCw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 21:32:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PmyUCak6ShatAiOjN46MCw/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 21:32:29 INFO - Downloaded 74682124 bytes. 21:32:29 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PmyUCak6ShatAiOjN46MCw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 21:32:29 INFO - mkdir: /builds/slave/test/properties 21:32:29 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:32:29 INFO - Writing to file /builds/slave/test/properties/build_url 21:32:29 INFO - Contents: 21:32:29 INFO - build_url:https://queue.taskcluster.net/v1/task/PmyUCak6ShatAiOjN46MCw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 21:32:29 INFO - Running post-action listener: _resource_record_post_action 21:32:29 INFO - Running post-action listener: set_extra_try_arguments 21:32:29 INFO - [mozharness: 2017-04-04 04:32:29.769661Z] Finished download-and-extract step (success) 21:32:29 INFO - [mozharness: 2017-04-04 04:32:29.769771Z] Running populate-webroot step. 21:32:29 INFO - Running pre-action listener: _resource_record_pre_action 21:32:29 INFO - Running main action method: populate_webroot 21:32:29 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:32:29 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:32:29 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:32:29 INFO - '--tppagecycles', 21:32:29 INFO - '7'], 21:32:29 INFO - 'tests': ['tsvgm']}}, 21:32:29 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:32:29 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:32:29 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:32:29 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:32:29 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:32:29 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:32:29 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 21:32:29 INFO - 'talos_options': ['--disable-e10s'], 21:32:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:32:29 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 21:32:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:32:29 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 21:32:29 INFO - 'talos_options': ['--disable-e10s'], 21:32:29 INFO - 'tests': ['damp', 'tps']}, 21:32:29 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 21:32:29 INFO - 'tests': ['damp', 'tps']}, 21:32:29 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:32:29 INFO - 'tests': ['dromaeo_dom']}, 21:32:29 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:32:29 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:32:29 INFO - 'tests': ['basic_compositor_video']}, 21:32:29 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 21:32:29 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:32:29 INFO - 'tests': ['a11yr', 21:32:29 INFO - 'ts_paint', 21:32:29 INFO - 'tpaint', 21:32:29 INFO - 'sessionrestore', 21:32:29 INFO - 'sessionrestore_no_auto_restore', 21:32:29 INFO - 'tabpaint']}, 21:32:29 INFO - 'other-e10s': {'tests': ['a11yr', 21:32:29 INFO - 'ts_paint', 21:32:29 INFO - 'tpaint', 21:32:29 INFO - 'sessionrestore', 21:32:29 INFO - 'sessionrestore_no_auto_restore', 21:32:29 INFO - 'tabpaint']}, 21:32:29 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:32:29 INFO - 'ts_paint', 21:32:29 INFO - 'tpaint', 21:32:29 INFO - 'sessionrestore', 21:32:29 INFO - 'sessionrestore_no_auto_restore', 21:32:29 INFO - 'tabpaint']}, 21:32:29 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:32:29 INFO - 'ts_paint', 21:32:29 INFO - 'tpaint', 21:32:29 INFO - 'sessionrestore', 21:32:29 INFO - 'sessionrestore_no_auto_restore', 21:32:29 INFO - 'tabpaint']}, 21:32:29 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:32:29 INFO - 'tests': ['a11yr', 21:32:29 INFO - 'ts_paint', 21:32:29 INFO - 'tpaint', 21:32:29 INFO - 'sessionrestore', 21:32:29 INFO - 'sessionrestore_no_auto_restore', 21:32:29 INFO - 'tabpaint']}, 21:32:29 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:32:29 INFO - 'tests': ['a11yr', 21:32:29 INFO - 'ts_paint', 21:32:29 INFO - 'tpaint', 21:32:29 INFO - 'sessionrestore', 21:32:29 INFO - 'sessionrestore_no_auto_restore', 21:32:29 INFO - 'tabpaint']}, 21:32:29 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:32:29 INFO - 'tests': ['tsvgx', 21:32:29 INFO - 'tsvgr_opacity', 21:32:29 INFO - 'tart', 21:32:29 INFO - 'tscrollx', 21:32:29 INFO - 'cart', 21:32:29 INFO - 'tsvg_static']}, 21:32:29 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:32:29 INFO - 'tsvgr_opacity', 21:32:29 INFO - 'tart', 21:32:29 INFO - 'tscrollx', 21:32:29 INFO - 'cart', 21:32:29 INFO - 'tsvg_static']}, 21:32:29 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 21:32:29 INFO - 'talos_options': ['--disable-e10s'], 21:32:29 INFO - 'tests': ['tp5o']}, 21:32:29 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 21:32:29 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 21:32:29 INFO - 'talos_options': ['--disable-e10s', 21:32:29 INFO - '--xperf_path', 21:32:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:32:29 INFO - 'tests': ['tp5n']}, 21:32:29 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 21:32:29 INFO - 'talos_options': ['--xperf_path', 21:32:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:32:29 INFO - 'tests': ['tp5n']}}, 21:32:29 INFO - 'talos.zip': {'path': '', 21:32:29 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:32:29 INFO - Running post-action listener: _resource_record_post_action 21:32:29 INFO - [mozharness: 2017-04-04 04:32:29.788654Z] Finished populate-webroot step (success) 21:32:29 INFO - [mozharness: 2017-04-04 04:32:29.788757Z] Running create-virtualenv step. 21:32:29 INFO - Running pre-action listener: _resource_record_pre_action 21:32:29 INFO - Running main action method: create_virtualenv 21:32:29 INFO - Creating virtualenv /builds/slave/test/build/venv 21:32:29 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 21:32:29 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:32:29 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:32:29 INFO - Using real prefix '/tools/python27' 21:32:29 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:32:30 INFO - Installing distribute.............................................................................................................................................................................................done. 21:32:33 INFO - Installing pip.................done. 21:32:33 INFO - Return code: 0 21:32:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 21:32:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 21:32:33 INFO - Reading from file tmpfile_stdout 21:32:33 INFO - Output received: 21:32:33 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) 21:32:33 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:32: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')]} 21:32:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:32:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:32: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')]} 21:32:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:32:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:32:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10267ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10267d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbbd1c68a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102458d30>, '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 0x10237fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10244dbe8>, '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.RH0K1cuKUI/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.F8qx1bnCvA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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 21:32:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 21:32:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 21:32:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RH0K1cuKUI/Render', 21:32:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:32:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:32:33 INFO - 'HOME': '/Users/cltbld', 21:32:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:32:33 INFO - 'LOGNAME': 'cltbld', 21:32:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:32:33 INFO - 'MOZ_NO_REMOTE': '1', 21:32:33 INFO - 'NO_EM_RESTART': '1', 21:32:33 INFO - 'PAGER': '/bin/cat', 21:32:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:32:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:32:33 INFO - 'PWD': '/builds/slave/test', 21:32:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:32:33 INFO - 'SHELL': '/bin/bash', 21:32:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F8qx1bnCvA/Listeners', 21:32:33 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 21:32:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:32:33 INFO - 'USER': 'cltbld', 21:32:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:32:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:32:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:32:33 INFO - 'XPC_FLAGS': '0x0', 21:32:33 INFO - 'XPC_SERVICE_NAME': '0', 21:32:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:32:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:32:34 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 21:32:34 INFO - Cleaning up... 21:32:34 INFO - Return code: 0 21:32:34 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:32:34 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')]} 21:32:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:32:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:32:34 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')]} 21:32:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:32:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:32:34 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 0x10267ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10267d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbbd1c68a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102458d30>, '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 0x10237fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10244dbe8>, '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.RH0K1cuKUI/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.F8qx1bnCvA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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 21:32:34 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 21:32:34 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 21:32:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RH0K1cuKUI/Render', 21:32:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:32:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:32:34 INFO - 'HOME': '/Users/cltbld', 21:32:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:32:34 INFO - 'LOGNAME': 'cltbld', 21:32:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:32:34 INFO - 'MOZ_NO_REMOTE': '1', 21:32:34 INFO - 'NO_EM_RESTART': '1', 21:32:34 INFO - 'PAGER': '/bin/cat', 21:32:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:32:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:32:34 INFO - 'PWD': '/builds/slave/test', 21:32:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:32:34 INFO - 'SHELL': '/bin/bash', 21:32:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F8qx1bnCvA/Listeners', 21:32:34 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 21:32:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:32:34 INFO - 'USER': 'cltbld', 21:32:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:32:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:32:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:32:34 INFO - 'XPC_FLAGS': '0x0', 21:32:34 INFO - 'XPC_SERVICE_NAME': '0', 21:32:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:32:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:32:34 INFO - Downloading/unpacking psutil>=3.1.1 21:32:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:32:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:32:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:32:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:32:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:32:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:32:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:32:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:32:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:32:38 INFO - Installing collected packages: psutil 21:32:38 INFO - Running setup.py install for psutil 21:32:38 INFO - building 'psutil._psutil_osx' extension 21:32:38 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 21:32:38 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 21:32:38 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 21:32:39 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 21:32:39 INFO - building 'psutil._psutil_posix' extension 21:32:39 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 21:32:39 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 21:32:39 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 21:32:39 INFO - ^ 21:32:39 INFO - 1 warning generated. 21:32:39 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 21:32:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:32:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:32:39 INFO - Successfully installed psutil 21:32:39 INFO - Cleaning up... 21:32:39 INFO - Return code: 0 21:32:39 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:32:39 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')]} 21:32:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:32:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:32:39 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')]} 21:32:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:32:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:32:39 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 0x10267ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10267d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbbd1c68a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102458d30>, '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 0x10237fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10244dbe8>, '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.RH0K1cuKUI/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.F8qx1bnCvA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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 21:32:39 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 21:32:39 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 21:32:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RH0K1cuKUI/Render', 21:32:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:32:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:32:39 INFO - 'HOME': '/Users/cltbld', 21:32:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:32:39 INFO - 'LOGNAME': 'cltbld', 21:32:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:32:39 INFO - 'MOZ_NO_REMOTE': '1', 21:32:39 INFO - 'NO_EM_RESTART': '1', 21:32:39 INFO - 'PAGER': '/bin/cat', 21:32:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:32:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:32:39 INFO - 'PWD': '/builds/slave/test', 21:32:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:32:39 INFO - 'SHELL': '/bin/bash', 21:32:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F8qx1bnCvA/Listeners', 21:32:39 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 21:32:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:32:39 INFO - 'USER': 'cltbld', 21:32:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:32:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:32:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:32:39 INFO - 'XPC_FLAGS': '0x0', 21:32:39 INFO - 'XPC_SERVICE_NAME': '0', 21:32:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:32:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:32:39 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:32:39 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 21:32:39 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 21:32:39 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 21:32:39 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 21:32:39 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 21:32:39 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 21:32:42 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:32:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:32:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:32:42 INFO - Installing collected packages: mozsystemmonitor 21:32:42 INFO - Running setup.py install for mozsystemmonitor 21:32:42 INFO - Successfully installed mozsystemmonitor 21:32:42 INFO - Cleaning up... 21:32:42 INFO - Return code: 0 21:32:42 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:32:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:32:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:32:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:32:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:32:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:32:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:32:42 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 0x10267ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10267d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbbd1c68a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102458d30>, '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 0x10237fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10244dbe8>, '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.RH0K1cuKUI/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.F8qx1bnCvA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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 21:32:42 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 21:32:42 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 21:32:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RH0K1cuKUI/Render', 21:32:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:32:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:32:42 INFO - 'HOME': '/Users/cltbld', 21:32:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:32:42 INFO - 'LOGNAME': 'cltbld', 21:32:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:32:42 INFO - 'MOZ_NO_REMOTE': '1', 21:32:42 INFO - 'NO_EM_RESTART': '1', 21:32:42 INFO - 'PAGER': '/bin/cat', 21:32:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:32:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:32:42 INFO - 'PWD': '/builds/slave/test', 21:32:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:32:42 INFO - 'SHELL': '/bin/bash', 21:32:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F8qx1bnCvA/Listeners', 21:32:42 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 21:32:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:32:42 INFO - 'USER': 'cltbld', 21:32:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:32:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:32:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:32:42 INFO - 'XPC_FLAGS': '0x0', 21:32:42 INFO - 'XPC_SERVICE_NAME': '0', 21:32:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:32:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:32:42 INFO - Downloading/unpacking jsonschema==2.5.1 21:32:42 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 21:32:42 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 21:32:42 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 21:32:42 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 21:32:42 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 21:32:42 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 21:32:45 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:32:45 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:32:45 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 21:32:45 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 21:32:45 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 21:32:45 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 21:32:45 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 21:32:45 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 21:32:46 INFO - Downloading functools32-3.2.3-2.tar.gz 21:32:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:32:46 INFO - warning: no files found matching '*.txt' 21:32:46 INFO - no previously-included directories found matching 'build' 21:32:46 INFO - no previously-included directories found matching 'dist' 21:32:46 INFO - no previously-included directories found matching '.git*' 21:32:46 INFO - Installing collected packages: jsonschema, functools32 21:32:46 INFO - Running setup.py install for functools32 21:32:46 INFO - warning: no files found matching '*.txt' 21:32:46 INFO - no previously-included directories found matching 'build' 21:32:46 INFO - no previously-included directories found matching 'dist' 21:32:46 INFO - no previously-included directories found matching '.git*' 21:32:46 INFO - Successfully installed jsonschema functools32 21:32:46 INFO - Cleaning up... 21:32:46 INFO - Return code: 0 21:32:46 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:32:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:32:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:32:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:32:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:32:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:32:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:32:46 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 0x10267ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10267d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbbd1c68a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102458d30>, '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 0x10237fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10244dbe8>, '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.RH0K1cuKUI/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.F8qx1bnCvA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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 21:32:46 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 21:32:46 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 21:32:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RH0K1cuKUI/Render', 21:32:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:32:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:32:46 INFO - 'HOME': '/Users/cltbld', 21:32:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:32:46 INFO - 'LOGNAME': 'cltbld', 21:32:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:32:46 INFO - 'MOZ_NO_REMOTE': '1', 21:32:46 INFO - 'NO_EM_RESTART': '1', 21:32:46 INFO - 'PAGER': '/bin/cat', 21:32:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:32:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:32:46 INFO - 'PWD': '/builds/slave/test', 21:32:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:32:46 INFO - 'SHELL': '/bin/bash', 21:32:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F8qx1bnCvA/Listeners', 21:32:46 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 21:32:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:32:46 INFO - 'USER': 'cltbld', 21:32:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:32:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:32:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:32:46 INFO - 'XPC_FLAGS': '0x0', 21:32:46 INFO - 'XPC_SERVICE_NAME': '0', 21:32:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:32:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:32:46 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:32:46 INFO - Cleaning up... 21:32:46 INFO - Return code: 0 21:32:46 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:32:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:32:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:32:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:32:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:32:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:32:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:32:46 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 0x10267ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10267d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbbd1c68a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102458d30>, '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 0x10237fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10244dbe8>, '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.RH0K1cuKUI/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.F8qx1bnCvA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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 21:32:46 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 21:32:46 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 21:32:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RH0K1cuKUI/Render', 21:32:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:32:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:32:46 INFO - 'HOME': '/Users/cltbld', 21:32:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:32:46 INFO - 'LOGNAME': 'cltbld', 21:32:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:32:46 INFO - 'MOZ_NO_REMOTE': '1', 21:32:46 INFO - 'NO_EM_RESTART': '1', 21:32:46 INFO - 'PAGER': '/bin/cat', 21:32:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:32:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:32:46 INFO - 'PWD': '/builds/slave/test', 21:32:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:32:46 INFO - 'SHELL': '/bin/bash', 21:32:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F8qx1bnCvA/Listeners', 21:32:46 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 21:32:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:32:46 INFO - 'USER': 'cltbld', 21:32:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:32:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:32:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:32:46 INFO - 'XPC_FLAGS': '0x0', 21:32:46 INFO - 'XPC_SERVICE_NAME': '0', 21:32:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:32:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:32:46 INFO - Downloading/unpacking blobuploader==1.2.4 21:32:46 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 21:32:46 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 21:32:46 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 21:32:46 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 21:32:46 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 21:32:46 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 21:32:49 INFO - Downloading blobuploader-1.2.4.tar.gz 21:32:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:32:49 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:32:49 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 21:32:49 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 21:32:49 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 21:32:49 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 21:32:49 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 21:32:49 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 21:32:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:32:50 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:32:50 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 21:32:50 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 21:32:50 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 21:32:50 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 21:32:50 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 21:32:50 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 21:32:50 INFO - Downloading docopt-0.6.1.tar.gz 21:32:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:32:50 INFO - Installing collected packages: blobuploader, requests, docopt 21:32:50 INFO - Running setup.py install for blobuploader 21:32:51 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:32:51 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:32:51 INFO - Running setup.py install for requests 21:32:51 INFO - Running setup.py install for docopt 21:32:51 INFO - Successfully installed blobuploader requests docopt 21:32:51 INFO - Cleaning up... 21:32:51 INFO - Return code: 0 21:32:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:32:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:32:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:32:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:32:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:32:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:32:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:32:51 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 0x10267ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10267d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbbd1c68a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102458d30>, '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 0x10237fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10244dbe8>, '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.RH0K1cuKUI/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.F8qx1bnCvA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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 21:32:51 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 21:32:51 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 21:32:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RH0K1cuKUI/Render', 21:32:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:32:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:32:51 INFO - 'HOME': '/Users/cltbld', 21:32:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:32:51 INFO - 'LOGNAME': 'cltbld', 21:32:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:32:51 INFO - 'MOZ_NO_REMOTE': '1', 21:32:51 INFO - 'NO_EM_RESTART': '1', 21:32:51 INFO - 'PAGER': '/bin/cat', 21:32:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:32:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:32:51 INFO - 'PWD': '/builds/slave/test', 21:32:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:32:51 INFO - 'SHELL': '/bin/bash', 21:32:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F8qx1bnCvA/Listeners', 21:32:51 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 21:32:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:32:51 INFO - 'USER': 'cltbld', 21:32:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:32:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:32:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:32:51 INFO - 'XPC_FLAGS': '0x0', 21:32:51 INFO - 'XPC_SERVICE_NAME': '0', 21:32:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:32:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:32:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:32:52 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-yuUHUP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:32:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:32:52 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-JbYsXt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:32:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:32:52 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-HJ5ch2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:32:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:32:52 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-_gn_1e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:32:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:32:52 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-pwYTtH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:32:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:32:52 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-HMGBaF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:32:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:32:52 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-0OR7sl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:32:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:32:52 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-C_C5OH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:32:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:32:52 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-pWhgrw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:32:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:32:53 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-iEgHTX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:32:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:32:53 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-bxCocT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:32:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:32:53 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-QdvLps-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:32:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:32:53 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-blCrcf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:32:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:32:53 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-rM2_jD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:32:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:32:53 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-Ub96Xd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:32:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:32:53 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-_UonYm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:32:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:32:53 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-bdA93D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:32:53 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:32:53 INFO - Running setup.py install for manifestparser 21:32:53 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:32:54 INFO - Running setup.py install for mozcrash 21:32:54 INFO - Running setup.py install for mozdebug 21:32:54 INFO - Running setup.py install for mozdevice 21:32:54 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:32:54 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:32:54 INFO - Running setup.py install for mozfile 21:32:54 INFO - Running setup.py install for mozhttpd 21:32:54 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:32:54 INFO - Running setup.py install for mozinfo 21:32:54 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:32:54 INFO - Running setup.py install for mozInstall 21:32:55 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:32:55 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:32:55 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:32:55 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:32:55 INFO - Running setup.py install for mozleak 21:32:55 INFO - Running setup.py install for mozlog 21:32:55 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:32:55 INFO - Running setup.py install for moznetwork 21:32:55 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:32:55 INFO - Running setup.py install for mozprocess 21:32:55 INFO - Running setup.py install for mozprofile 21:32:55 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:32:55 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:32:55 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:32:55 INFO - Running setup.py install for mozrunner 21:32:55 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:32:56 INFO - Running setup.py install for mozscreenshot 21:32:56 INFO - Running setup.py install for moztest 21:32:56 INFO - Running setup.py install for mozversion 21:32:56 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:32:56 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:32:56 INFO - Cleaning up... 21:32:56 INFO - Return code: 0 21:32:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:32:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:32:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:32:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:32:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:32:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:32:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:32:56 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 0x10267ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10267d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbbd1c68a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102458d30>, '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 0x10237fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10244dbe8>, '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.RH0K1cuKUI/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.F8qx1bnCvA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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 21:32:56 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 21:32:56 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 21:32:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RH0K1cuKUI/Render', 21:32:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:32:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:32:56 INFO - 'HOME': '/Users/cltbld', 21:32:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:32:56 INFO - 'LOGNAME': 'cltbld', 21:32:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:32:56 INFO - 'MOZ_NO_REMOTE': '1', 21:32:56 INFO - 'NO_EM_RESTART': '1', 21:32:56 INFO - 'PAGER': '/bin/cat', 21:32:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:32:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:32:56 INFO - 'PWD': '/builds/slave/test', 21:32:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:32:56 INFO - 'SHELL': '/bin/bash', 21:32:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F8qx1bnCvA/Listeners', 21:32:56 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 21:32:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:32:56 INFO - 'USER': 'cltbld', 21:32:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:32:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:32:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:32:56 INFO - 'XPC_FLAGS': '0x0', 21:32:56 INFO - 'XPC_SERVICE_NAME': '0', 21:32:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:32:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:32:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:32:56 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-0yGqAz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:32:56 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)) 21:32:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:32:56 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-fTXiHG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:32:56 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)) 21:32:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:32:56 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-Hua4Zs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:32:57 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)) 21:32:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:32:57 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-HX2WPC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:32:57 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)) 21:32:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:32:57 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-yaEdqR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:32:57 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)) 21:32:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:32:57 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-R83iR2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:32:57 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)) 21:32:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:32:57 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-XxSjDd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:32:57 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)) 21:32:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:32:57 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-B9qPWs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:32:57 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)) 21:32:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:32:57 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-1V_8ey-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:32:57 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)) 21:32:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:32:57 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-OtoXRc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:32:57 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)) 21:32:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:32:57 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-KZ4KA3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:32:57 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)) 21:32:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:32:57 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-mesXp5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:32:58 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)) 21:32:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:32:58 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-3Pz8RH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:32:58 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)) 21:32:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:32:58 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-2XES1e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:32:58 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)) 21:32:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:32:58 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-jwXESS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:32:58 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)) 21:32:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:32:58 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-BVrtKK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:32:58 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)) 21:32:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:32:58 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-fj3IEz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:32:58 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)) 21:32:58 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)) 21:32:58 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)) 21:32:58 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)) 21:32:58 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)) 21:32:58 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)) 21:32:58 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:32:58 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 21:32:58 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 21:32:58 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 21:32:58 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 21:32:58 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 21:32:58 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 21:33:01 INFO - Downloading blessings-1.6.tar.gz 21:33:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:33:01 INFO - Installing collected packages: blessings 21:33:01 INFO - Running setup.py install for blessings 21:33:01 INFO - Successfully installed blessings 21:33:01 INFO - Cleaning up... 21:33:01 INFO - Return code: 0 21:33:01 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:33:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:33:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:33:01 INFO - Reading from file tmpfile_stdout 21:33:01 INFO - Current package versions: 21:33:01 INFO - blessings == 1.6 21:33:01 INFO - blobuploader == 1.2.4 21:33:01 INFO - docopt == 0.6.1 21:33:01 INFO - functools32 == 3.2.3-2 21:33:01 INFO - jsonschema == 2.5.1 21:33:01 INFO - manifestparser == 1.1 21:33:01 INFO - mozInstall == 1.12 21:33:01 INFO - mozcrash == 1.0 21:33:01 INFO - mozdebug == 0.1 21:33:01 INFO - mozdevice == 0.49 21:33:01 INFO - mozfile == 1.2 21:33:01 INFO - mozhttpd == 0.7 21:33:01 INFO - mozinfo == 0.9 21:33:01 INFO - mozleak == 0.1 21:33:01 INFO - mozlog == 3.4 21:33:01 INFO - moznetwork == 0.27 21:33:01 INFO - mozprocess == 0.25 21:33:01 INFO - mozprofile == 0.28 21:33:01 INFO - mozrunner == 6.13 21:33:01 INFO - mozscreenshot == 0.1 21:33:01 INFO - mozsystemmonitor == 0.3 21:33:01 INFO - moztest == 0.8 21:33:01 INFO - mozversion == 1.4 21:33:01 INFO - psutil == 3.1.1 21:33:01 INFO - requests == 1.2.3 21:33:01 INFO - wsgiref == 0.1.2 21:33:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:33:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:33:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:33:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:33:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:33:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:33:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:33:01 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 0x10267ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10267d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbbd1c68a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102458d30>, '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 0x10237fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10244dbe8>, '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.RH0K1cuKUI/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.F8qx1bnCvA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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 21:33:01 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 21:33:01 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 21:33:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RH0K1cuKUI/Render', 21:33:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:33:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:33:01 INFO - 'HOME': '/Users/cltbld', 21:33:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:33:01 INFO - 'LOGNAME': 'cltbld', 21:33:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:33:01 INFO - 'MOZ_NO_REMOTE': '1', 21:33:01 INFO - 'NO_EM_RESTART': '1', 21:33:01 INFO - 'PAGER': '/bin/cat', 21:33:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:33:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:33:01 INFO - 'PWD': '/builds/slave/test', 21:33:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:33:01 INFO - 'SHELL': '/bin/bash', 21:33:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F8qx1bnCvA/Listeners', 21:33:01 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 21:33:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:33:01 INFO - 'USER': 'cltbld', 21:33:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:33:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:33:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:33:01 INFO - 'XPC_FLAGS': '0x0', 21:33:01 INFO - 'XPC_SERVICE_NAME': '0', 21:33:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:33:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:33:02 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)) 21:33:02 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)) 21:33:02 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)) 21:33:02 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)) 21:33:02 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)) 21:33:02 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)) 21:33:02 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)) 21:33:02 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)) 21:33:02 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)) 21:33:02 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)) 21:33:02 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)) 21:33:02 INFO - Cleaning up... 21:33:02 INFO - Return code: 0 21:33:02 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:33:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:33:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:33:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:33:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:33:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:33:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:33:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10267ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10267d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbbd1c68a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102458d30>, '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 0x10237fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10244dbe8>, '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.RH0K1cuKUI/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.F8qx1bnCvA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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 21:33:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 21:33:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 21:33:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RH0K1cuKUI/Render', 21:33:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:33:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:33:02 INFO - 'HOME': '/Users/cltbld', 21:33:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:33:02 INFO - 'LOGNAME': 'cltbld', 21:33:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:33:02 INFO - 'MOZ_NO_REMOTE': '1', 21:33:02 INFO - 'NO_EM_RESTART': '1', 21:33:02 INFO - 'PAGER': '/bin/cat', 21:33:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:33:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:33:02 INFO - 'PWD': '/builds/slave/test', 21:33:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:33:02 INFO - 'SHELL': '/bin/bash', 21:33:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F8qx1bnCvA/Listeners', 21:33:02 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 21:33:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:33:02 INFO - 'USER': 'cltbld', 21:33:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:33:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:33:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:33:02 INFO - 'XPC_FLAGS': '0x0', 21:33:02 INFO - 'XPC_SERVICE_NAME': '0', 21:33:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:33:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:33:02 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:33:02 INFO - Cleaning up... 21:33:02 INFO - Return code: 0 21:33:02 INFO - Running post-action listener: _resource_record_post_action 21:33:02 INFO - Running post-action listener: _start_resource_monitoring 21:33:02 INFO - Starting resource monitoring. 21:33:02 INFO - [mozharness: 2017-04-04 04:33:02.511349Z] Finished create-virtualenv step (success) 21:33:02 INFO - [mozharness: 2017-04-04 04:33:02.512300Z] Running install step. 21:33:02 INFO - Running pre-action listener: _resource_record_pre_action 21:33:02 INFO - Running main action method: install 21:33:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:33:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:33:02 INFO - Reading from file tmpfile_stdout 21:33:02 INFO - Detecting whether we're running mozinstall >=1.0... 21:33:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:33:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:33:02 INFO - Reading from file tmpfile_stdout 21:33:02 INFO - Output received: 21:33:02 INFO - Usage: mozinstall [options] installer 21:33:02 INFO - Options: 21:33:02 INFO - -h, --help show this help message and exit 21:33:02 INFO - -d DEST, --destination=DEST 21:33:02 INFO - Directory to install application into. [default: 21:33:02 INFO - "/builds/slave/test"] 21:33:02 INFO - --app=APP Application being installed. [default: firefox] 21:33:02 INFO - mkdir: /builds/slave/test/build/application 21:33:02 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'] 21:33:02 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 21:33:23 INFO - Reading from file tmpfile_stdout 21:33:23 INFO - Output received: 21:33:23 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 21:33:23 INFO - Running post-action listener: _resource_record_post_action 21:33:23 INFO - [mozharness: 2017-04-04 04:33:23.019677Z] Finished install step (success) 21:33:23 INFO - [mozharness: 2017-04-04 04:33:23.019841Z] Running run-tests step. 21:33:23 INFO - Running pre-action listener: _resource_record_pre_action 21:33:23 INFO - Running main action method: run_tests 21:33:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:33:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:33:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:33:23 INFO - 2017-04-03 21:33:23.034 screenresolution[1631:7893] starting screenresolution argv=screenresolution get 21:33:23 INFO - 2017-04-03 21:33:23.037 screenresolution[1631:7893] Display 0: 1600x1200x32@60 21:33:23 INFO - 2017-04-03 21:33:23.042 screenresolution[1632:7896] starting screenresolution argv=screenresolution list 21:33:23 INFO - Available Modes on Display 0 21:33:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:33:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:33:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:33:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:33:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:33:23 INFO - Intel Iris: 21:33:23 INFO - Chipset Model: Intel Iris 21:33:23 INFO - Type: GPU 21:33:23 INFO - Bus: Built-In 21:33:23 INFO - VRAM (Dynamic, Max): 1536 MB 21:33:23 INFO - Vendor: Intel (0x8086) 21:33:23 INFO - Device ID: 0x0a2e 21:33:23 INFO - Revision ID: 0x0009 21:33:23 INFO - Displays: 21:33:23 INFO - Display: 21:33:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:33:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:33:23 INFO - Display Serial Number: 1600x1200 60 21:33:23 INFO - Main Display: Yes 21:33:23 INFO - Mirror: Off 21:33:23 INFO - Online: Yes 21:33:23 INFO - Rotation: Supported 21:33:23 INFO - Return code: 0 21:33:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:33:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:33:23 INFO - Python 2.7.3 21:33:23 INFO - Return code: 0 21:33:23 INFO - grabbing minidump binary from tooltool 21:33:23 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')]} 21:33:23 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 0x102458d30>, '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 0x10237fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10244dbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:33:23 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 21:33:23 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 21:33:23 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 21:33:23 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:33:23 INFO - Return code: 0 21:33:23 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 21:33:23 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:33:23 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:33:23 INFO - ENV: RUST_BACKTRACE is now 1 21:33:23 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 21:33:23 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:33:23 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/PmyUCak6ShatAiOjN46MCw/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0119', '--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 21:33:23 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/PmyUCak6ShatAiOjN46MCw/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0119 --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 21:33:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RH0K1cuKUI/Render', 21:33:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:33:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:33:23 INFO - 'HOME': '/Users/cltbld', 21:33:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:33:23 INFO - 'LOGNAME': 'cltbld', 21:33:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:33:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 21:33:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:33:23 INFO - 'MOZ_NO_REMOTE': '1', 21:33:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:33:23 INFO - 'NO_EM_RESTART': '1', 21:33:23 INFO - 'PAGER': '/bin/cat', 21:33:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:33:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:33:23 INFO - 'PWD': '/builds/slave/test', 21:33:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:33:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:33:23 INFO - 'RUST_BACKTRACE': '1', 21:33:23 INFO - 'SHELL': '/bin/bash', 21:33:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F8qx1bnCvA/Listeners', 21:33:23 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 21:33:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:33:23 INFO - 'USER': 'cltbld', 21:33:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:33:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:33:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:33:23 INFO - 'XPC_FLAGS': '0x0', 21:33:23 INFO - 'XPC_SERVICE_NAME': '0', 21:33:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:33:23 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/PmyUCak6ShatAiOjN46MCw/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0119', '--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 21:33:23 INFO - mozversion application_buildid: 20170403200227 21:33:23 INFO - mozversion application_changeset: e3ce3282ec26d1cff472f2bed8c65693ea168ff8 21:33:23 INFO - mozversion application_display_name: FirefoxDeveloperEdition 21:33:23 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:33:23 INFO - mozversion application_name: Firefox 21:33:23 INFO - mozversion application_remotingname: firefox-dev 21:33:23 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 21:33:23 INFO - mozversion application_vendor: Mozilla 21:33:23 INFO - mozversion application_version: 54.0a2 21:33:23 INFO - mozversion platform_buildid: 20170403200227 21:33:23 INFO - mozversion platform_changeset: e3ce3282ec26d1cff472f2bed8c65693ea168ff8 21:33:23 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 21:33:23 INFO - mozversion platform_version: 54.0a2 21:33:23 INFO - using testdate: 1491280403 21:33:23 INFO - actual date: 1491280403 21:33:23 INFO - starting webserver on 'localhost:49240' 21:33:23 INFO - SUITE-START | Running 2 tests 21:33:23 INFO - TEST-START | dromaeo_css 21:33:23 INFO - operating with platform_type : mac_ 21:33:23 INFO - Initialising browser for dromaeo_css test... 21:33:23 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/tmpt0XqMy/profile http://localhost:49240/getInfo.html) 21:33:26 INFO - PROCESS | 1642 | 2017-04-03 21:33:26.606 plugin-container[1644:8077] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f43, name = 'com.apple.tsm.portname' 21:33:26 INFO - PROCESS | 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:33:26 INFO - PROCESS | 1642 | 2017-04-03 21:33:26.610 plugin-container[1644:8077] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b0b, name = 'com.apple.CFPasteboardClient' 21:33:26 INFO - PROCESS | 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:33:26 INFO - PROCESS | 1642 | 2017-04-03 21:33:26.610 plugin-container[1644:8077] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 21:33:26 INFO - PROCESS | 1642 | __metrics Screen width/height:1600/1200 21:33:26 INFO - PROCESS | 1642 | colorDepth:24 21:33:26 INFO - PROCESS | 1642 | Browser inner width/height: 1024/689 21:33:26 INFO - PROCESS | 1642 | __metrics 21:33:26 ERROR - PROCESS | 1642 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3360: TypeError: this.tabs is undefined 21:33:27 INFO - TEST-INFO | 1642: exit 0 21:33:27 INFO - Browser initialized. 21:33:27 INFO - Running cycle 1/1 for dromaeo_css test... 21:33:27 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/tmpt0XqMy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:33:30 INFO - PROCESS | 1646 | 2017-04-03 21:33:30.060 plugin-container[1647:8206] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d3f, name = 'com.apple.tsm.portname' 21:33:30 INFO - PROCESS | 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:33:30 INFO - PROCESS | 1646 | 2017-04-03 21:33:30.060 plugin-container[1647:8206] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4717, name = 'com.apple.CFPasteboardClient' 21:33:30 INFO - PROCESS | 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:33:30 INFO - PROCESS | 1646 | 2017-04-03 21:33:30.060 plugin-container[1647:8206] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 21:33:39 INFO - PROCESS | 1646 | RSS: Main: 220786688 21:33:39 INFO - PROCESS | 1646 | 21:36:43 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49240/tests/dromaeo/cssquery-ext.html) 21:36:45 INFO - PROCESS | 1646 | RSS: Main: 238956544 21:36:45 INFO - PROCESS | 1646 | 21:38:27 INFO - PROCESS | 1646 | ************************* 21:38:27 INFO - PROCESS | 1646 | A coding exception was thrown and uncaught in a Task. 21:38:27 INFO - PROCESS | 1646 | 21:38:27 ERROR - PROCESS | 1646 | Full message: TypeError: NetworkError when attempting to fetch resource. 21:38:27 INFO - PROCESS | 1646 | Full stack: 21:38:27 INFO - PROCESS | 1646 | ************************* 21:39:47 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-ext.html (next: http://localhost:49240/tests/dromaeo/cssquery-jquery.html) 21:39:47 INFO - PROCESS | 1646 | RSS: Main: 248860672 21:39:47 INFO - PROCESS | 1646 | 21:42:49 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49240/tests/dromaeo/cssquery-mootools.html) 21:42:50 INFO - PROCESS | 1646 | RSS: Main: 255504384 21:42:50 INFO - PROCESS | 1646 | 21:45:52 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49240/tests/dromaeo/cssquery-prototype.html) 21:45:53 INFO - PROCESS | 1646 | RSS: Main: 251748352 21:45:53 INFO - PROCESS | 1646 | 21:48:56 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49240/tests/dromaeo/cssquery-yui.html) 21:48:56 INFO - PROCESS | 1646 | RSS: Main: 256909312 21:48:56 INFO - PROCESS | 1646 | 21:51:59 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-yui.html (next: http://localhost:49240/tests/dromaeo/cssquery-dojo.html) 21:51:59 INFO - PROCESS | 1646 | RSS: Main: 252981248 21:51:59 INFO - PROCESS | 1646 | 21:51:59 INFO - PROCESS | 1646 | __start_tp_report 21:51:59 INFO - PROCESS | 1646 | _x_x_mozilla_page_load 21:51:59 INFO - PROCESS | 1646 | _x_x_mozilla_page_load_details 21:51:59 INFO - PROCESS | 1646 | |i|pagename|runs| 21:51:59 INFO - PROCESS | 1646 | |0;dojo.html;3156.1162874395177;3441.4837774333864;3450.4824276358545;3484.059303987923;3485.1809824691195;6016.8796624067545;6022.457978781908;6022.698865056746;6034.125051867488;6085.604435711675;6070.2412198475195;6087.604305720125;6113.572049956505;6135.938640613593;6139.201903752517;5441.863953401177;5445.401005889349;5462.562994960394;5560.388357280696;5586.469285417878;5564.944350556483;5565.471280228392;5577.8047768328115;5580.553555715554;5605.299337582802;5321.855801002784;5338.225957236198;5550.750216240259;5565.693886836242;5578.2748242728385;5457.345118585756;5494.80768173112;5519.337679478488;5557.472040156179;5570.721463926828;5373.865653358658;5502.889942201174;5510.201020851994;5512.228288039678;5617.662940223599;4706.623470122382;5347.919781203285;5610.719464026783;6097.02447608385;6098.908516372258;6291.811245662591;6301.96849015752;6321.683915804191;6326.746930122789;6335.271443783978;6515.902261466034;6526.28210896801;6535.967320163416;6559.376859198416;6566.967165164191;5137.33214682089;6155.169052177988;6217.222847144107;6226.439620434183;6233.657148856766;5185.507376799198;5858.7070646468355;6274.309825919146;6400.455961243347;6447.323031081763;6243.906341404882;6253.5622506424115;6260;6288.937110628835;6313.968430157912;5742.109972954198;5867.2959244890035;6507.414332710079;6529.347065293433;6546.803595892131;6250.812475625738;6267.2165979252595;6397.45621622158;6419.325970773001;6426.678666066678;6310.652914089769;6312.589681670676;6339.873202535923;6341.968290158519;6382.936170638234;5221.242919776687;5224.111900976843;5241.580673546107;5248.212768084742;5280;4626.467956185082;4663.766811659403;4685.133250348628;4697.31888876111;4710.26990816424;5857.355690874086;5877.794277200277;5981.312149102909;5992.880142397127;6147.108669242934;6310.05349197624;6325.051242313598;6445.645489498029;6447.903281450782;6473.417392434703;5845.678487683133;5943.465088142088;6075.453209211192;6120.969395152996;6161.876762464725;5702.6863522506665;5764.394738552475;5776.1624564437925;5781.306243250753;5806.983777838861;6324.019776934582;6422.582532135396;6739.42714869222;6767.39093481589;6769.255381908085;6351.110844481868;6364.77723279683;6398.840028999312;6404.839879003062;6408.967955160194;6714.630695311805;6726.697298621476;6737.898931516129;6743.865122697617;6754.223264324469;6255.562110652393;6395.680215989275;6404.327545607831;6415.390537898892;6443.130177425987;6012.398760123952;6320.652364120017;6387.552871298964;6398.5521013528605;6415.615063096229;6241.812745617639;6331.461825744863;6337.556371053982;6350.4919606432295;6365.140706004815;6350.079238510482;6350.872982540231;6351.364863513611;6352.205974253218;6352.6506042166275;6021.54838387114;6027.939720602913;6190.1952746142715;6213.37866213375;6314;6290.025046117859;6330.082138090043;6339.334369891095;6344.809655710152;6350.7459701611415;6292.653904035322;6309.2744334400295;6311.495080393465;6312.305646378986;6330.113784070142 21:51:59 INFO - PROCESS | 1646 | |1;ext.html;756.3873262657248;812.8171161488666;835.1106072033284;957.8802649668792;957.9952100239498;38720.22559548811;39746.00634984127;41591.16817663645;41658.95852603686;41762.37356439648;38065.8096709516;38094.61905380945;38113.61886381135;38191.61808381915;38289.425658615146;7871.370290376756;7993.2806047455715;10668.039876411121;10944.3980581068;11071;10721.30311529752;10811.675649730521;10824.40465774382;10845.349279043283;10848.457577121113;9225.538723063853;10824.40465774382;10834;10850.945745271263;10882.40146791926;10740.624078157267;10808.51361688726;10823.080038196762;10833;10854.945725271402;10577.365358078463;10581.788364232749;10626.840597391045;10631.46842657864;10637.521311540922;34944;35165.94502164939;35222.29555408878;35232.59069637211;35289.823550882335;3271.656476070026;3678.9632103678887;3690.815459227055;3706.425504046877;3734.458503517002;25029.873655685537;25157.496850062897;25918.740812591823;26724.06465773708;26768.59847102295;3061.2652963288874;3071.9078427647205;3217.3404452087266;3231.676832316773;3235.983820080908;327204;331696;333611;334718;335176;37449.25101497955;45867;45928;45937.31094033592;46007.76996114998;47215.52784472111;47225.5277447228;47322.76338618285;47572;48179.03641927142;13889.513867014606;14161.937854660735;14187.219702916438;14192.503262385768;14245.216513091673;11880;12062.215955962834;12073.87926120745;12091.39543022863;12130.57542986009;11732.765344693229;12155.027597792156;12176.330301833485;12197.634070977885;12309.50761969529;5220.737039350406;5549.861253468614;5555.833325000256;5598.608097433222;5619.213310136584;1084.38732116354;1086.413336798136;1099.7910397024539;1103.5199688135526;1151.0503834336705;27341.726582734314;27610.723892761216;27945.860270698515;28153.43693126126;28269.57595636067;986.6349450703142;1109.3842917181041;1145.7536629624672;1166.4984056855633;1199.0347770045198;14393.92803035978;14564.49024284145;14703.044302120541;15173.62065948338;15243.542693719206;508108;510217.89782102447;510336;511276;511690;50359.24461133086;50694.74652626787;50723.49276507262;50829;50835.49164508308;1378.4761790519537;1385.300423286234;1412.6044707481922;1424.9073810202303;1425.016738450466;22574;27800.165995020183;28149;28388.716112839433;28501.42997140087;43416.78291608522;43460;43578;43589.346159808265;43655.126897462505;487560;488391.6741248813;488876;491216;491365;30474.390512189188;30774.692253077184;30828.53757193599;30896.69103308938;31149.532757009118;38269;39008;39263.803680981415;39639.40540891947;39714;23159.536809264057;25134.49193048423;26705.33236669099;28387.432251354443;28733.56899646465;22472;27850.44299113966;28407.7216525261;28606.284842878264;28716.13851584456;27764;28952.710472895;32636.673633263363;32651.34697306088;36010.4598431029;24875;28935.421291574472;29064;29317.706822931497;29514;23268.80323732893;23495.295141145794;23808.40478988039;24085.916133773673;24154.154604589457 21:51:59 INFO - PROCESS | 1646 | |2;jquery.html;417.17815902671725;427.6899248045167;432.49378657116574;439.99560004399956;442.70781284352313;19276.3253286135;19851.10670019849;20875.582488350243;20893.268735594258;21123;20186.293479728214;21661.104338615867;21987;22364.8881755591;22567.661485077737;5608.9439105609035;6696.732130714766;6967.930320696804;7021.157461104674;7072.469564782637;6842.281560436158;6880.415164711006;6912.758053468129;6915.239323674392;6927.792166234995;6618;6868;6914.308569143096;6919.550229235084;6945;6534.477241820643;6852.588844669336;6890.517663763539;6913.827154321132;6923.792286231396;5699.658020518782;6600.306967768363;6622.602643841385;6625.469962402996;6641.402273795382;26150.82321295527;26465.867670661715;26611.201663950113;26634.801433935518;26658.73341266582;2696.0563802669103;2950.9409811803857;2960.4967155583604;2973.3012742005826;2979.8212107273416;15020.948533602652;16283;16365.427210047592;17024.233909474104;17058;2081.9791802081936;2098.6327392706366;2149.6668016457475;2170.305502239284;2248.5502899420017;120678.79321206763;122653;126645;127223;128900;21250.78749212519;21971.780282197295;22013.339599812032;22272.329575281918;22272.331830045125;33939.32121357559;34383.96848094561;34686.65313346885;34711.479327810106;35144.47283290753;10611.09805666527;10850.348979061282;10912.672619821418;11033.393163376091;11178.105751539857;8920.73237802867;8939.284857211407;8965.372423930263;9106.043865394038;9125.908740912639;10434.16526677864;10506.366361274133;10550.261481696361;10678.786424271471;10682.252242342962;4142.2543942090715;4446.444194475691;4531.818727250873;4553.290791978768;4557.6581756368405;1013.878334599838;1021.5045702834119;1024.9494268374976;1037.5434808684154;1065.4246706778408;17232.451668365942;22119.44701382478;22191.335649826327;22312.995915183856;22344.776552234594;979.6081567373108;1020.5254556631202;1032.726327523207;1045.0646671229208;1045.2787576572293;10062.899371006197;11789.7031326554;11802.11484138693;11842;11843.348615826213;605597;615145;619182;633916;637319;123208;127200;152472;152892.23553882158;156429.99999999773;1178.3754610056683;1236.4436003798328;1280.5006047641427;1281.653953432568;1286.7683816913045;19139.277465028215;19477.220911163397;19747.703784442958;19909.502262443555;19924.50188745235;64148.67925660342;64193;64384.678076609314;64469;65099.34900651122;61615;89957.55021224852;91347.54326228326;92035.53982230046;92426;10170.237232207763;11756.353400563052;11776.882231177578;11874.940625296818;11966.760664786481;10664.733381665521;11046;12381.380930953597;12559.183653062522;12726.981841452847;10774.784504310026;10812.459377031275;10890.836637450602;11005.559777608805;11175.720606984565;10042.799144017225;10313.22650801178;10486.161107111566;10576.735581610521;10646.946765266124;9621.797275340583;11169.609063682732;11385.373804440504;11424.543018279175;11539.711507212387;9389.953050234704;11497.367644779617;11513.309201447604;11637.359945203096;11797;11235.719107022063;11273.605423810128;11365.943170284427;11481.196316257781;11755.412229388667 21:51:59 INFO - PROCESS | 1646 | |3;mootools.html;1271.2499625221121;1503.4437258214462;1552;1580.1230317173963;1580.265176692838;8133.308668763162;9489.193418559413;10747.516361763719;11363.545458181663;11382.2032457728;10668.839967400496;10999.945000275007;11001.449927503612;11027.724306892333;11059;2329.1847853251397;2342.3090188394463;2357.846739961897;2358.445765245166;2359.1035406545575;2284.497410569672;2305.4005958450757;2311.05246848792;2322.6516022596575;2322.7328857181474;2294.977050229493;2306.1582522379376;2310.6418505131733;2313.0400883633274;2316.1546035696933;2248.4491299631695;2260.886955652211;2263.9320820375415;2265.8300627452923;2268.092762894839;2217.1020736601704;2232.3303009097367;2265.150568536799;2298.586254474194;2318.7681231876845;9532.903716072671;11186;11207.383593902332;11243.775124497464;11250.3812290324;5332.14685650298;5508.586855985777;5512.503874651261;5518.724063796794;5529.474699903543;3110.237991692027;3181.809091454497;3373.409653310661;3378.4932260160926;3382.6109997350372;1175.3829239649253;1186.323795436593;1818.7999320074782;1860.7674040744907;1953.9609207815906;15202.391904323813;15475.14886681232;15536;15600.531984040497;15779.132147731985;4306.74159550428;4360.732419142092;4530.184566777945;4530.728156310566;4534.6372290216705;11729.941350293195;11773.293602383885;11805.055595552336;11831.585894493652;11833.349165795791;5447.155690867922;5459.563234941195;5471.562275017989;5473.343198816167;5498.340199176125;4309;4381;4979.103761322925;5059.013492368953;5077.619178561623;3176.872925083017;3669.834857431422;3776.773393596393;3802.562705288927;3836.3861782114727;964.5900492290748;966.0822218892081;972.1202311907734;978.515634760798;985.3152059318704;916.935814492979;920.9585568649293;930.6603089872148;931.231733819588;957.2772556719632;6513.446357059608;6543.640099794462;6556.475481961432;6604.504662150358;6704.765333213314;944.1455482788087;945.6548359848606;951.4481600671594;980.3578655980344;988.3149230561875;3926.0773718176206;4029.1135950090934;4096.73371230869;4293;4381.364702118175;12780.93609531965;14067.788983165261;14071.663191996744;14083.929580352033;14150.504732334319;13590.25253611041;13696.452141914211;13921.860781392259;14012.71974560503;14063.156210627396;10072.949635251776;10186.541605627617;10236.18110551169;10243.84634230487;10304.896951030543;6417.96791016042;6496.285408604956;6528.053432252392;6538.823011857911;6696.1629796275465;11928;12014.63956081319;12043.217190882564;12104.334261615695;12136.029117670741;12451.501939922302;12594;12595.992320614512;12793.488260469849;12868.678283042998;9113.817723645623;10347.12049475803;10549.261551691308;10587.4706264688;10707.625233116803;10635.787284254426;10868.565257389615;10939.28894621847;10941.56233750673;10977.28647637933;7891.250331218525;8916.509591972244;8935.195832375117;8938.50838203905;9097.953735320523;8769.123087691434;8807.295416366804;8863.867041994246;8947.19475247231;9056.230220431074;7895.447318687637;7935.444518883623;7950;8032.236937490929;8036.116027237116;5456.099743542271;5754.856128596819;5968.253968253968;6031.819045428644;6080.8783824324155;5944.791932282349;6008.909866352095;6011.489023432882;6013.368596297326;6152.446279834837 21:51:59 INFO - PROCESS | 1646 | |4;prototype.html;318.89954664280737;393.1037235103962;397.7792325259481;398.4837692579734;401.8934982229708;4943.159662857314;4956.33089532913;5303.973480132604;5353.598480113992;5355.446920393086;4665.253559430492;5054.1660625996665;5281.2342210379475;5305.867353316159;5338.874680306901;3747.7001839852815;4300.806463709125;4385.868423947287;4432.8005239764125;4479.3281007848755;4018.327812005013;4245.363195520666;4401.975091457934;4428.914915845607;4429.667774916878;3736.8229007862437;4119.157781131025;4437.7781110944325;4471.329300604919;4482.731036137826;3499.7725147865435;4384.693071484998;4389.253826849412;4393.946207385884;4434.157510073076;3843.7117216208617;3963.603639636013;4011.6991225657903;4016.7305472407998;4044.4337792709043;4234.809433575497;4378.387025816388;4953.860612059247;5095.847124586231;5141.4523978454745;4585.380973568559;4621.792019359137;4695.740770337244;4927.768057985503;5089;5175.835437026676;5597.320803758856;5641.576881733846;5708.971455142698;5727.942720572824;3919.451276821247;4219.78901054946;4423.557644235583;4573.291139873325;4629.768511574442;4809.2305231162845;5496.972515137439;5539.224508568804;5618.106675062346;5909.527237820964;4108.589141085868;4384.846530371422;4520.88697782558;4630.652701047435;4674.672772905864;4294.561321957117;4439.624800731658;4576.009435895397;4590.357349971006;4803.543663351976;4938.530839570235;5047.671901326402;5076.13705670037;5133.281340612317;5134.768935397916;5303.575713942875;5565.610407271533;5678.801241956512;5687.744051517609;5703.885922281531;1454.7963285140088;1540.8382119877251;1607.5579215715538;1609.9517014489584;1620.965688061499;997.7256254530063;1194.988050119505;1200.8498937632796;1206.8414322250562;1209.667341481082;4611.7863425555925;4642.373279607277;5278.076336641073;5299.549538289211;5325.201219817059;5116.462771409023;5467.371252305956;5773.769049237983;5888.440598143169;5917.05327147663;4843.98276361968;5008.222081161135;5080;5250.028744682246;5335.291238374902;2657.9202623921315;2767.6540432445945;2982.1799005273288;3008.969910300913;3017.706543027271;5087.516685914832;5293.991319851995;6000.639961602318;6010.248718910137;6036.486898613667;5024.84925452237;5061.4179369373;5435;6021.133448630939;6126.509879209652;4974.477679843637;5469.031148786434;5646.97176514123;5938.317093534212;6079.568172730943;5456.443869369121;5684.617652496758;5739.71301434935;5774.671825480247;5866.530677545871;5275.472452754694;5759.712014399347;5793.04414771558;5799.82600521985;5801.796937107181;5536.446355364431;5815.331236907809;5841.086472063813;5871.853203669943;5895.29256489224;3913.96086039136;4440.533743956838;4580;4598.471175814824;4620.5148459411275;4794.616430685606;5307.7611507483025;5836.241288632451;5853.824385268449;5879.412058794086;5215.895682086413;5371.355437347544;5569.245687608391;5661.660300381825;5678.74445649955;4962.925556116589;5170.7331703732825;5357.785688572413;5619.494245517923;5765.538756899354;5029.968856384359;5065.974670126626;5361.812336568358;5579.497845193952;5594.244776955059;4953.603711702983;5090.49095090488;5317.707526086102;5516.284270851964;5680.517156041617;4828.565429111397;5516.806911758069;5559.497516564538;5570.749316280696;5577.3028371453565 21:51:59 INFO - PROCESS | 1646 | |5;yui.html;714.4141803720951;716.5843810589859;764.2586690909816;820.4912953968538;822.5980137997049;12316.81524777128;16402.83597164028;17791.822081779195;18653.787503812244;18921.905390473064;15463.84536154641;17886.731699024524;17971.191296391662;18010.729839052423;18097.457076287734;932.4731526687417;955.6273053509102;957.9233661307079;961.2694352292273;962.4946902876011;883.0595415882098;943.6980166346772;959.52023988006;960.3661583354988;960.7309953213112;981.9165370943479;997.6607953295878;1001.6694490818013;1001.8146642871092;1003.6437064841986;972.3339512434005;995.775950411159;996.6511720897664;998.770282834952;1002.5839276700161;727.6507276507253;741.7218543046357;742.9479936404454;743.8214828441189;750.1598210004775;16124.677506449805;16389.672206555922;16428.917855410768;16974.660506789794;17059.06175160366;13874;13938.651533711636;14035.438582456689;14202.644933876632;14395.78406323906;11613.361265130414;11769.470373833197;11880.465379057963;11910.166288359818;12009.099317551132;3030.9848450757827;3123.5470856725647;3146.496560550324;3468.924633363665;3676.613955534684;83279;89975;111566;118655;119565.40217298857;10648.574057037787;10740.624078157227;10772.676819695422;11233.438328083563;11469.197156198987;18567.90716046411;22512.437189070406;22651.20720774798;22926.312210633707;23022.42443938915;7584.469087163846;7616.8476630467085;7679.577623230776;7739.535627862347;7973.521588704696;4145.274576949082;4166.395872598457;4236.364545318165;4360.4767427908855;4377.124575084932;3478.9563131061086;3537.2925414917117;3552.342816578941;3563.7149028077692;3576.2489877126095;628.6259675493075;645.6932956845461;646.3859333633068;655.1744801550082;661.9338066193342;674.6930146783202;686.4302628818069;700.3661686174021;704.7357241034612;728.621117019147;8688.826223475495;9280.860787088199;10362.585496580203;10374.533146008447;10467.581296748198;669.2335447136014;682.3347236444426;706.4595584377955;717.9138503379628;718.5783265263599;4713.835015774432;4927.728974906415;4929.507049295114;4930.580900623487;4947.529984651453;104530.11494023094;104817.47591261995;105683.94316056895;106628;108562;59658.55800123719;73644.63177684078;76434.61782691162;79991.60004199942;89411.55294223488;61072;66774.33064173434;67602.32397675961;74571.5085698283;76193;10416.947915260374;11046.392448415414;11068.778624427627;11341.376224307742;11389.373584452604;46967;49927.001459971325;51242.48757512377;52288;52337;64830;65178.348216517225;66041.33958660543;66120.66939665271;71015;13125.146865453715;13764.380602872694;14185.537763837054;14392.424303027761;14778.408863645334;14934.327955241823;15117.470888518848;15136.848631513985;15935.123568203397;16433.342666293214;14004.929975350058;14218.360173791998;14703;15004.849951500346;15249.847501525286;13400.262985535499;13968.161910285415;14363.928180359031;14746.557603271918;15525.922370388076;13301.534446294334;14032.438702452197;14049;14071.366788494746;14322.641933951734;9704.70885873425;9731.639052280178;9994.250431217542;10071.446070466194;10191.133753630727;9415.858762118436;9650.179734722627;10739.315171207198;11696.590619328623;11883.16817822744 21:51:59 INFO - PROCESS | 1646 | __end_tp_report 21:51:59 INFO - PROCESS | 1646 | __start_cc_report 21:51:59 INFO - PROCESS | 1646 | _x_x_mozilla_cycle_collect,413 21:51:59 INFO - PROCESS | 1646 | __end_cc_report 21:51:59 INFO - PROCESS | 1646 | __startTimestamp1491281519761__endTimestamp 21:51:59 INFO - PROCESS | 1646 | 21:51:59 INFO - PROCESS | 1646 | ------- Summary: start ------- 21:51:59 INFO - PROCESS | 1646 | Number of tests: 6 21:51:59 INFO - PROCESS | 1646 | 21:51:59 INFO - PROCESS | 1646 | [#0] dojo.html Cycles:165 Average:5962.21 Median:6226.44 stddev:655.67 (10.5%) stddev-sans-first:619.63 21:51:59 INFO - PROCESS | 1646 | Values: 3156.1 3441.5 3450.5 3484.1 3485.2 6016.9 6022.5 6022.7 6034.1 6085.6 6070.2 6087.6 6113.6 6135.9 6139.2 5441.9 5445.4 5462.6 5560.4 5586.5 5564.9 5565.5 5577.8 5580.6 5605.3 5321.9 5338.2 5550.8 5565.7 5578.3 5457.3 5494.8 5519.3 5557.5 5570.7 5373.9 5502.9 5510.2 5512.2 5617.7 4706.6 5347.9 5610.7 6097.0 6098.9 6291.8 6302.0 6321.7 6326.7 6335.3 6515.9 6526.3 6536.0 6559.4 6567.0 5137.3 6155.2 6217.2 6226.4 6233.7 5185.5 5858.7 6274.3 6400.5 6447.3 6243.9 6253.6 6260.0 6288.9 6314.0 5742.1 5867.3 6507.4 6529.3 6546.8 6250.8 6267.2 6397.5 6419.3 6426.7 6310.7 6312.6 6339.9 6342.0 6382.9 5221.2 5224.1 5241.6 5248.2 5280.0 4626.5 4663.8 4685.1 4697.3 4710.3 5857.4 5877.8 5981.3 5992.9 6147.1 6310.1 6325.1 6445.6 6447.9 6473.4 5845.7 5943.5 6075.5 6121.0 6161.9 5702.7 5764.4 5776.2 5781.3 5807.0 6324.0 6422.6 6739.4 6767.4 6769.3 6351.1 6364.8 6398.8 6404.8 6409.0 6714.6 6726.7 6737.9 6743.9 6754.2 6255.6 6395.7 6404.3 6415.4 6443.1 6012.4 6320.7 6387.6 6398.6 6415.6 6241.8 6331.5 6337.6 6350.5 6365.1 6350.1 6350.9 6351.4 6352.2 6352.7 6021.5 6027.9 6190.2 6213.4 6314.0 6290.0 6330.1 6339.3 6344.8 6350.7 6292.7 6309.3 6311.5 6312.3 6330.1 21:51:59 INFO - PROCESS | 1646 | 21:51:59 INFO - PROCESS | 1646 | [#1] ext.html Cycles:180 Average:56745.85 Median:29190.85 stddev:120232.75 (411.9%) stddev-sans-first:120496.55 21:51:59 INFO - PROCESS | 1646 | Values: 756.4 812.8 835.1 957.9 958.0 38720.2 39746.0 41591.2 41659.0 41762.4 38065.8 38094.6 38113.6 38191.6 38289.4 7871.4 7993.3 10668.0 10944.4 11071.0 10721.3 10811.7 10824.4 10845.3 10848.5 9225.5 10824.4 10834.0 10850.9 10882.4 10740.6 10808.5 10823.1 10833.0 10854.9 10577.4 10581.8 10626.8 10631.5 10637.5 34944.0 35165.9 35222.3 35232.6 35289.8 3271.7 3679.0 3690.8 3706.4 3734.5 25029.9 25157.5 25918.7 26724.1 26768.6 3061.3 3071.9 3217.3 3231.7 3236.0 327204.0 331696.0 333611.0 334718.0 335176.0 37449.3 45867.0 45928.0 45937.3 46007.8 47215.5 47225.5 47322.8 47572.0 48179.0 13889.5 14161.9 14187.2 14192.5 14245.2 11880.0 12062.2 12073.9 12091.4 12130.6 11732.8 12155.0 12176.3 12197.6 12309.5 5220.7 5549.9 5555.8 5598.6 5619.2 1084.4 1086.4 1099.8 1103.5 1151.1 27341.7 27610.7 27945.9 28153.4 28269.6 986.6 1109.4 1145.8 1166.5 1199.0 14393.9 14564.5 14703.0 15173.6 15243.5 508108.0 510217.9 510336.0 511276.0 511690.0 50359.2 50694.7 50723.5 50829.0 50835.5 1378.5 1385.3 1412.6 1424.9 1425.0 22574.0 27800.2 28149.0 28388.7 28501.4 43416.8 43460.0 43578.0 43589.3 43655.1 487560.0 488391.7 488876.0 491216.0 491365.0 30474.4 30774.7 30828.5 30896.7 31149.5 38269.0 39008.0 39263.8 39639.4 39714.0 23159.5 25134.5 26705.3 28387.4 28733.6 22472.0 27850.4 28407.7 28606.3 28716.1 27764.0 28952.7 32636.7 32651.3 36010.5 24875.0 28935.4 29064.0 29317.7 29514.0 23268.8 23495.3 23808.4 24085.9 24154.2 21:51:59 INFO - PROCESS | 1646 | 21:51:59 INFO - PROCESS | 1646 | [#2] jquery.html Cycles:180 Average:38597.11 Median:21186.89 stddev:104029.91 (491.0%) stddev-sans-first:104282.25 21:51:59 INFO - PROCESS | 1646 | Values: 417.2 427.7 432.5 440.0 442.7 19276.3 19851.1 20875.6 20893.3 21123.0 20186.3 21661.1 21987.0 22364.9 22567.7 5608.9 6696.7 6967.9 7021.2 7072.5 6842.3 6880.4 6912.8 6915.2 6927.8 6618.0 6868.0 6914.3 6919.6 6945.0 6534.5 6852.6 6890.5 6913.8 6923.8 5699.7 6600.3 6622.6 6625.5 6641.4 26150.8 26465.9 26611.2 26634.8 26658.7 2696.1 2950.9 2960.5 2973.3 2979.8 15020.9 16283.0 16365.4 17024.2 17058.0 2082.0 2098.6 2149.7 2170.3 2248.6 120678.8 122653.0 126645.0 127223.0 128900.0 21250.8 21971.8 22013.3 22272.3 22272.3 33939.3 34384.0 34686.7 34711.5 35144.5 10611.1 10850.3 10912.7 11033.4 11178.1 8920.7 8939.3 8965.4 9106.0 9125.9 10434.2 10506.4 10550.3 10678.8 10682.3 4142.3 4446.4 4531.8 4553.3 4557.7 1013.9 1021.5 1024.9 1037.5 1065.4 17232.5 22119.4 22191.3 22313.0 22344.8 979.6 1020.5 1032.7 1045.1 1045.3 10062.9 11789.7 11802.1 11842.0 11843.3 605597.0 615145.0 619182.0 633916.0 637319.0 123208.0 127200.0 152472.0 152892.2 156430.0 1178.4 1236.4 1280.5 1281.7 1286.8 19139.3 19477.2 19747.7 19909.5 19924.5 64148.7 64193.0 64384.7 64469.0 65099.3 61615.0 89957.6 91347.5 92035.5 92426.0 10170.2 11756.4 11776.9 11874.9 11966.8 10664.7 11046.0 12381.4 12559.2 12727.0 10774.8 10812.5 10890.8 11005.6 11175.7 10042.8 10313.2 10486.2 10576.7 10646.9 9621.8 11169.6 11385.4 11424.5 11539.7 9390.0 11497.4 11513.3 11637.4 11797.0 11235.7 11273.6 11365.9 11481.2 11755.4 21:51:59 INFO - PROCESS | 1646 | 21:51:59 INFO - PROCESS | 1646 | [#3] mootools.html Cycles:180 Average:6734.23 Median:2734.67 stddev:4305.80 (157.5%) stddev-sans-first:4298.31 21:51:59 INFO - PROCESS | 1646 | Values: 1271.2 1503.4 1552.0 1580.1 1580.3 8133.3 9489.2 10747.5 11363.5 11382.2 10668.8 10999.9 11001.4 11027.7 11059.0 2329.2 2342.3 2357.8 2358.4 2359.1 2284.5 2305.4 2311.1 2322.7 2322.7 2295.0 2306.2 2310.6 2313.0 2316.2 2248.4 2260.9 2263.9 2265.8 2268.1 2217.1 2232.3 2265.2 2298.6 2318.8 9532.9 11186.0 11207.4 11243.8 11250.4 5332.1 5508.6 5512.5 5518.7 5529.5 3110.2 3181.8 3373.4 3378.5 3382.6 1175.4 1186.3 1818.8 1860.8 1954.0 15202.4 15475.1 15536.0 15600.5 15779.1 4306.7 4360.7 4530.2 4530.7 4534.6 11729.9 11773.3 11805.1 11831.6 11833.3 5447.2 5459.6 5471.6 5473.3 5498.3 4309.0 4381.0 4979.1 5059.0 5077.6 3176.9 3669.8 3776.8 3802.6 3836.4 964.6 966.1 972.1 978.5 985.3 916.9 921.0 930.7 931.2 957.3 6513.4 6543.6 6556.5 6604.5 6704.8 944.1 945.7 951.4 980.4 988.3 3926.1 4029.1 4096.7 4293.0 4381.4 12780.9 14067.8 14071.7 14083.9 14150.5 13590.3 13696.5 13921.9 14012.7 14063.2 10072.9 10186.5 10236.2 10243.8 10304.9 6418.0 6496.3 6528.1 6538.8 6696.2 11928.0 12014.6 12043.2 12104.3 12136.0 12451.5 12594.0 12596.0 12793.5 12868.7 9113.8 10347.1 10549.3 10587.5 10707.6 10635.8 10868.6 10939.3 10941.6 10977.3 7891.3 8916.5 8935.2 8938.5 9098.0 8769.1 8807.3 8863.9 8947.2 9056.2 7895.4 7935.4 7950.0 8032.2 8036.1 5456.1 5754.9 5968.3 6031.8 6080.9 5944.8 6008.9 6011.5 6013.4 6152.4 21:51:59 INFO - PROCESS | 1646 | 21:51:59 INFO - PROCESS | 1646 | [#4] prototype.html Cycles:180 Average:4683.63 Median:5071.06 stddev:1303.50 (25.7%) stddev-sans-first:1265.32 21:51:59 INFO - PROCESS | 1646 | Values: 318.9 393.1 397.8 398.5 401.9 4943.2 4956.3 5304.0 5353.6 5355.4 4665.3 5054.2 5281.2 5305.9 5338.9 3747.7 4300.8 4385.9 4432.8 4479.3 4018.3 4245.4 4402.0 4428.9 4429.7 3736.8 4119.2 4437.8 4471.3 4482.7 3499.8 4384.7 4389.3 4393.9 4434.2 3843.7 3963.6 4011.7 4016.7 4044.4 4234.8 4378.4 4953.9 5095.8 5141.5 4585.4 4621.8 4695.7 4927.8 5089.0 5175.8 5597.3 5641.6 5709.0 5727.9 3919.5 4219.8 4423.6 4573.3 4629.8 4809.2 5497.0 5539.2 5618.1 5909.5 4108.6 4384.8 4520.9 4630.7 4674.7 4294.6 4439.6 4576.0 4590.4 4803.5 4938.5 5047.7 5076.1 5133.3 5134.8 5303.6 5565.6 5678.8 5687.7 5703.9 1454.8 1540.8 1607.6 1610.0 1621.0 997.7 1195.0 1200.8 1206.8 1209.7 4611.8 4642.4 5278.1 5299.5 5325.2 5116.5 5467.4 5773.8 5888.4 5917.1 4844.0 5008.2 5080.0 5250.0 5335.3 2657.9 2767.7 2982.2 3009.0 3017.7 5087.5 5294.0 6000.6 6010.2 6036.5 5024.8 5061.4 5435.0 6021.1 6126.5 4974.5 5469.0 5647.0 5938.3 6079.6 5456.4 5684.6 5739.7 5774.7 5866.5 5275.5 5759.7 5793.0 5799.8 5801.8 5536.4 5815.3 5841.1 5871.9 5895.3 3914.0 4440.5 4580.0 4598.5 4620.5 4794.6 5307.8 5836.2 5853.8 5879.4 5215.9 5371.4 5569.2 5661.7 5678.7 4962.9 5170.7 5357.8 5619.5 5765.5 5030.0 5066.0 5361.8 5579.5 5594.2 4953.6 5090.5 5317.7 5516.3 5680.5 4828.6 5516.8 5559.5 5570.7 5577.3 21:51:59 INFO - PROCESS | 1646 | 21:51:59 INFO - PROCESS | 1646 | [#5] yui.html Cycles:180 Average:20217.77 Median:3544.82 stddev:28368.70 (800.3%) stddev-sans-first:28410.49 21:51:59 INFO - PROCESS | 1646 | Values: 714.4 716.6 764.3 820.5 822.6 12316.8 16402.8 17791.8 18653.8 18921.9 15463.8 17886.7 17971.2 18010.7 18097.5 932.5 955.6 957.9 961.3 962.5 883.1 943.7 959.5 960.4 960.7 981.9 997.7 1001.7 1001.8 1003.6 972.3 995.8 996.7 998.8 1002.6 727.7 741.7 742.9 743.8 750.2 16124.7 16389.7 16428.9 16974.7 17059.1 13874.0 13938.7 14035.4 14202.6 14395.8 11613.4 11769.5 11880.5 11910.2 12009.1 3031.0 3123.5 3146.5 3468.9 3676.6 83279.0 89975.0 111566.0 118655.0 119565.4 10648.6 10740.6 10772.7 11233.4 11469.2 18567.9 22512.4 22651.2 22926.3 23022.4 7584.5 7616.8 7679.6 7739.5 7973.5 4145.3 4166.4 4236.4 4360.5 4377.1 3479.0 3537.3 3552.3 3563.7 3576.2 628.6 645.7 646.4 655.2 661.9 674.7 686.4 700.4 704.7 728.6 8688.8 9280.9 10362.6 10374.5 10467.6 669.2 682.3 706.5 717.9 718.6 4713.8 4927.7 4929.5 4930.6 4947.5 104530.1 104817.5 105683.9 106628.0 108562.0 59658.6 73644.6 76434.6 79991.6 89411.6 61072.0 66774.3 67602.3 74571.5 76193.0 10416.9 11046.4 11068.8 11341.4 11389.4 46967.0 49927.0 51242.5 52288.0 52337.0 64830.0 65178.3 66041.3 66120.7 71015.0 13125.1 13764.4 14185.5 14392.4 14778.4 14934.3 15117.5 15136.8 15935.1 16433.3 14004.9 14218.4 14703.0 15004.8 15249.8 13400.3 13968.2 14363.9 14746.6 15525.9 13301.5 14032.4 14049.0 14071.4 14322.6 9704.7 9731.6 9994.3 10071.4 10191.1 9415.9 9650.2 10739.3 11696.6 11883.2 21:51:59 INFO - PROCESS | 1646 | -------- Summary: end -------- 21:51:59 INFO - PROCESS | 1646 | 21:52:00 INFO - TEST-INFO | 1646: exit 0 21:52:00 INFO - TEST-OK | dromaeo_css | took 1116798ms 21:52:00 INFO - TEST-START | kraken 21:52:00 INFO - operating with platform_type : mac_ 21:52:00 INFO - Initialising browser for kraken test... 21:52:00 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/tmp1TnCff/profile http://localhost:49240/getInfo.html) 21:52:03 INFO - PROCESS | 1653 | 2017-04-03 21:52:03.088 plugin-container[1654:10396] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8943, name = 'com.apple.tsm.portname' 21:52:03 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:52:03 INFO - PROCESS | 1653 | 2017-04-03 21:52:03.091 plugin-container[1654:10396] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 21:52:03 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:52:03 INFO - PROCESS | 1653 | 2017-04-03 21:52:03.091 plugin-container[1654:10396] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 21:52:03 INFO - PROCESS | 1653 | __metrics Screen width/height:1600/1200 21:52:03 INFO - PROCESS | 1653 | colorDepth:24 21:52:03 INFO - PROCESS | 1653 | Browser inner width/height: 1024/689 21:52:03 INFO - PROCESS | 1653 | __metrics 21:52:03 ERROR - PROCESS | 1653 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3360: TypeError: this.tabs is undefined 21:52:03 INFO - TEST-INFO | 1653: exit 0 21:52:03 INFO - Browser initialized. 21:52:03 INFO - Running cycle 1/1 for kraken test... 21:52:03 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/tmp1TnCff/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:52:06 INFO - PROCESS | 1655 | 2017-04-03 21:52:06.224 plugin-container[1656:10522] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 21:52:06 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:52:06 INFO - PROCESS | 1655 | 2017-04-03 21:52:06.224 plugin-container[1656:10522] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e13, name = 'com.apple.CFPasteboardClient' 21:52:06 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:52:06 INFO - PROCESS | 1655 | 2017-04-03 21:52:06.224 plugin-container[1656:10522] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 21:52:16 INFO - PROCESS | 1655 | RSS: Main: 220913664 21:52:16 INFO - PROCESS | 1655 | 21:52:18 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection) 21:52:19 INFO - PROCESS | 1655 | RSS: Main: 235311104 21:52:19 INFO - PROCESS | 1655 | 21:52:22 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-dft) 21:52:22 INFO - PROCESS | 1655 | RSS: Main: 243269632 21:52:22 INFO - PROCESS | 1655 | 21:52:26 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-fft) 21:52:26 INFO - PROCESS | 1655 | RSS: Main: 246960128 21:52:26 INFO - PROCESS | 1655 | 21:52:28 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator) 21:52:29 INFO - PROCESS | 1655 | RSS: Main: 248623104 21:52:29 INFO - PROCESS | 1655 | 21:52:31 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:52:31 INFO - PROCESS | 1655 | RSS: Main: 248823808 21:52:31 INFO - PROCESS | 1655 | 21:52:34 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom) 21:52:35 INFO - PROCESS | 1655 | RSS: Main: 252547072 21:52:35 INFO - PROCESS | 1655 | 21:52:38 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate) 21:52:39 INFO - PROCESS | 1655 | RSS: Main: 253071360 21:52:39 INFO - PROCESS | 1655 | 21:52:42 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial) 21:52:43 INFO - PROCESS | 1655 | RSS: Main: 252313600 21:52:43 INFO - PROCESS | 1655 | 21:52:44 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:52:45 INFO - PROCESS | 1655 | RSS: Main: 251445248 21:52:45 INFO - PROCESS | 1655 | 21:52:47 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:52:47 INFO - PROCESS | 1655 | RSS: Main: 251535360 21:52:47 INFO - PROCESS | 1655 | 21:52:50 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:52:50 INFO - PROCESS | 1655 | RSS: Main: 251531264 21:52:50 INFO - PROCESS | 1655 | 21:52:53 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:52:54 INFO - PROCESS | 1655 | RSS: Main: 252002304 21:52:54 INFO - PROCESS | 1655 | 21:52:57 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:52:58 INFO - PROCESS | 1655 | RSS: Main: 251985920 21:52:58 INFO - PROCESS | 1655 | 21:52:59 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49240/tests/kraken/driver.html?testName=ai-astar) 21:53:00 INFO - PROCESS | 1655 | RSS: Main: 253902848 21:53:00 INFO - PROCESS | 1655 | 21:53:00 INFO - PROCESS | 1655 | __start_tp_report 21:53:00 INFO - PROCESS | 1655 | _x_x_mozilla_page_load 21:53:00 INFO - PROCESS | 1655 | _x_x_mozilla_page_load_details 21:53:00 INFO - PROCESS | 1655 | |i|pagename|runs| 21:53:00 INFO - PROCESS | 1655 | |0;ai-astar;109;123;120;122;123;120;120;121;121;120 21:53:00 INFO - PROCESS | 1655 | |1;audio-beat-detection;132;112;112;149;112;120;141;98;160;98 21:53:00 INFO - PROCESS | 1655 | |2;audio-dft;197;203;193;200;196;201;197;191;200;198 21:53:00 INFO - PROCESS | 1655 | |3;audio-fft;89;52;53;83;75;86;87;87;73;64 21:53:00 INFO - PROCESS | 1655 | |4;audio-oscillator;90;69;85;72;74;70;82;69;73;82 21:53:00 INFO - PROCESS | 1655 | |5;imaging-gaussian-blur;89;88;89;89;91;90;88;83;135;137 21:53:00 INFO - PROCESS | 1655 | |6;imaging-darkroom;103;108;154;150;154;98;97;97;98;97 21:53:00 INFO - PROCESS | 1655 | |7;imaging-desaturate;91;90;91;91;91;110;145;99;98;114 21:53:00 INFO - PROCESS | 1655 | |8;json-parse-financial;66;65;63;67;68;69;69;68;67;69 21:53:00 INFO - PROCESS | 1655 | |9;json-stringify-tinderbox;57;55;69;61;66;64;57;54;56;58 21:53:00 INFO - PROCESS | 1655 | |10;stanford-crypto-aes;86;82;86;81;85;84;86;86;89;85 21:53:00 INFO - PROCESS | 1655 | |11;stanford-crypto-ccm;141;136;158;146;147;143;144;157;207;152 21:53:00 INFO - PROCESS | 1655 | |12;stanford-crypto-pbkdf2;171;224;183;169;171;168;163;166;209;231 21:53:00 INFO - PROCESS | 1655 | |13;stanford-crypto-sha256-iterative;68;65;66;65;67;63;65;71;79;86 21:53:00 INFO - PROCESS | 1655 | __end_tp_report 21:53:00 INFO - PROCESS | 1655 | __start_cc_report 21:53:00 INFO - PROCESS | 1655 | _x_x_mozilla_cycle_collect,1033 21:53:00 INFO - PROCESS | 1655 | __end_cc_report 21:53:00 INFO - PROCESS | 1655 | __startTimestamp1491281580088__endTimestamp 21:53:00 INFO - PROCESS | 1655 | 21:53:00 INFO - PROCESS | 1655 | ------- Summary: start ------- 21:53:00 INFO - PROCESS | 1655 | Number of tests: 14 21:53:00 INFO - PROCESS | 1655 | 21:53:00 INFO - PROCESS | 1655 | [#0] ai-astar Cycles:10 Average:119.90 Median:121.00 stddev:4.01 (3.3%) stddev-sans-first:1.27 21:53:00 INFO - PROCESS | 1655 | Values: 109.0 123.0 120.0 122.0 123.0 120.0 120.0 121.0 121.0 120.0 21:53:00 INFO - PROCESS | 1655 | 21:53:00 INFO - PROCESS | 1655 | [#1] audio-beat-detection Cycles:10 Average:123.40 Median:145.00 stddev:21.27 (14.7%) stddev-sans-first:22.33 21:53:00 INFO - PROCESS | 1655 | Values: 132.0 112.0 112.0 149.0 112.0 120.0 141.0 98.0 160.0 98.0 21:53:00 INFO - PROCESS | 1655 | 21:53:00 INFO - PROCESS | 1655 | [#2] audio-dft Cycles:10 Average:197.60 Median:199.00 stddev:3.66 (1.8%) stddev-sans-first:3.87 21:53:00 INFO - PROCESS | 1655 | Values: 197.0 203.0 193.0 200.0 196.0 201.0 197.0 191.0 200.0 198.0 21:53:00 INFO - PROCESS | 1655 | 21:53:00 INFO - PROCESS | 1655 | [#3] audio-fft Cycles:10 Average:74.90 Median:84.50 stddev:14.17 (16.8%) stddev-sans-first:14.08 21:53:00 INFO - PROCESS | 1655 | Values: 89.0 52.0 53.0 83.0 75.0 86.0 87.0 87.0 73.0 64.0 21:53:00 INFO - PROCESS | 1655 | 21:53:00 INFO - PROCESS | 1655 | [#4] audio-oscillator Cycles:10 Average:76.60 Median:78.00 stddev:7.52 (9.6%) stddev-sans-first:6.21 21:53:00 INFO - PROCESS | 1655 | Values: 90.0 69.0 85.0 72.0 74.0 70.0 82.0 69.0 73.0 82.0 21:53:00 INFO - PROCESS | 1655 | 21:53:00 INFO - PROCESS | 1655 | [#5] imaging-gaussian-blur Cycles:10 Average:97.90 Median:89.00 stddev:20.20 (22.7%) stddev-sans-first:21.16 21:53:00 INFO - PROCESS | 1655 | Values: 89.0 88.0 89.0 89.0 91.0 90.0 88.0 83.0 135.0 137.0 21:53:00 INFO - PROCESS | 1655 | 21:53:00 INFO - PROCESS | 1655 | [#6] imaging-darkroom Cycles:10 Average:115.60 Median:97.00 stddev:25.83 (26.6%) stddev-sans-first:27.00 21:53:00 INFO - PROCESS | 1655 | Values: 103.0 108.0 154.0 150.0 154.0 98.0 97.0 97.0 98.0 97.0 21:53:00 INFO - PROCESS | 1655 | 21:53:00 INFO - PROCESS | 1655 | [#7] imaging-desaturate Cycles:10 Average:102.00 Median:91.00 stddev:17.35 (19.1%) stddev-sans-first:17.94 21:53:00 INFO - PROCESS | 1655 | Values: 91.0 90.0 91.0 91.0 91.0 110.0 145.0 99.0 98.0 114.0 21:53:00 INFO - PROCESS | 1655 | 21:53:00 INFO - PROCESS | 1655 | [#8] json-parse-financial Cycles:10 Average:67.10 Median:68.00 stddev:1.97 (2.9%) stddev-sans-first:2.05 21:53:00 INFO - PROCESS | 1655 | Values: 66.0 65.0 63.0 67.0 68.0 69.0 69.0 68.0 67.0 69.0 21:53:00 INFO - PROCESS | 1655 | 21:53:00 INFO - PROCESS | 1655 | [#9] json-stringify-tinderbox Cycles:10 Average:59.70 Median:59.50 stddev:5.08 (8.5%) stddev-sans-first:5.29 21:53:00 INFO - PROCESS | 1655 | Values: 57.0 55.0 69.0 61.0 66.0 64.0 57.0 54.0 56.0 58.0 21:53:00 INFO - PROCESS | 1655 | 21:53:00 INFO - PROCESS | 1655 | [#10] stanford-crypto-aes Cycles:10 Average:85.00 Median:86.00 stddev:2.26 (2.6%) stddev-sans-first:2.37 21:53:00 INFO - PROCESS | 1655 | Values: 86.0 82.0 86.0 81.0 85.0 84.0 86.0 86.0 89.0 85.0 21:53:00 INFO - PROCESS | 1655 | 21:53:00 INFO - PROCESS | 1655 | [#11] stanford-crypto-ccm Cycles:10 Average:153.10 Median:149.50 stddev:20.16 (13.5%) stddev-sans-first:20.90 21:53:00 INFO - PROCESS | 1655 | Values: 141.0 136.0 158.0 146.0 147.0 143.0 144.0 157.0 207.0 152.0 21:53:00 INFO - PROCESS | 1655 | 21:53:00 INFO - PROCESS | 1655 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:185.50 Median:177.00 stddev:25.81 (14.6%) stddev-sans-first:26.84 21:53:00 INFO - PROCESS | 1655 | Values: 171.0 224.0 183.0 169.0 171.0 168.0 163.0 166.0 209.0 231.0 21:53:00 INFO - PROCESS | 1655 | 21:53:00 INFO - PROCESS | 1655 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.50 Median:67.50 stddev:7.37 (10.9%) stddev-sans-first:7.79 21:53:00 INFO - PROCESS | 1655 | Values: 68.0 65.0 66.0 65.0 67.0 63.0 65.0 71.0 79.0 86.0 21:53:00 INFO - PROCESS | 1655 | -------- Summary: end -------- 21:53:00 INFO - PROCESS | 1655 | 21:53:00 INFO - TEST-INFO | 1655: exit 0 21:53:00 INFO - TEST-OK | kraken | took 60526ms 21:53:00 INFO - SUITE-END | took 1177s 21:53:01 INFO - Completed test suite (00:19:37) 21:53:01 INFO - javascript benchmark 21:53:01 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7726.955898324577, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3156.1162874395177, 3441.4837774333864, 3450.4824276358545, 3484.059303987923, 3485.1809824691195, 6016.8796624067545, 6022.457978781908, 6022.698865056746, 6034.125051867488, 6085.604435711675, 6070.2412198475195, 6087.604305720125, 6113.572049956505, 6135.938640613593, 6139.201903752517, 5441.863953401177, 5445.401005889349, 5462.562994960394, 5560.388357280696, 5586.469285417878, 5564.944350556483, 5565.471280228392, 5577.8047768328115, 5580.553555715554, 5605.299337582802, 5321.855801002784, 5338.225957236198, 5550.750216240259, 5565.693886836242, 5578.2748242728385, 5457.345118585756, 5494.80768173112, 5519.337679478488, 5557.472040156179, 5570.721463926828, 5373.865653358658, 5502.889942201174, 5510.201020851994, 5512.228288039678, 5617.662940223599, 4706.623470122382, 5347.919781203285, 5610.719464026783, 6097.02447608385, 6098.908516372258, 6291.811245662591, 6301.96849015752, 6321.683915804191, 6326.746930122789, 6335.271443783978, 6515.902261466034, 6526.28210896801, 6535.967320163416, 6559.376859198416, 6566.967165164191, 5137.33214682089, 6155.169052177988, 6217.222847144107, 6226.439620434183, 6233.657148856766, 5185.507376799198, 5858.7070646468355, 6274.309825919146, 6400.455961243347, 6447.323031081763, 6243.906341404882, 6253.5622506424115, 6260.0, 6288.937110628835, 6313.968430157912, 5742.109972954198, 5867.2959244890035, 6507.414332710079, 6529.347065293433, 6546.803595892131, 6250.812475625738, 6267.2165979252595, 6397.45621622158, 6419.325970773001, 6426.678666066678, 6310.652914089769, 6312.589681670676, 6339.873202535923, 6341.968290158519, 6382.936170638234, 5221.242919776687, 5224.111900976843, 5241.580673546107, 5248.212768084742, 5280.0, 4626.467956185082, 4663.766811659403, 4685.133250348628, 4697.31888876111, 4710.26990816424, 5857.355690874086, 5877.794277200277, 5981.312149102909, 5992.880142397127, 6147.108669242934, 6310.05349197624, 6325.051242313598, 6445.645489498029, 6447.903281450782, 6473.417392434703, 5845.678487683133, 5943.465088142088, 6075.453209211192, 6120.969395152996, 6161.876762464725, 5702.6863522506665, 5764.394738552475, 5776.1624564437925, 5781.306243250753, 5806.983777838861, 6324.019776934582, 6422.582532135396, 6739.42714869222, 6767.39093481589, 6769.255381908085, 6351.110844481868, 6364.77723279683, 6398.840028999312, 6404.839879003062, 6408.967955160194, 6714.630695311805, 6726.697298621476, 6737.898931516129, 6743.865122697617, 6754.223264324469, 6255.562110652393, 6395.680215989275, 6404.327545607831, 6415.390537898892, 6443.130177425987, 6012.398760123952, 6320.652364120017, 6387.552871298964, 6398.5521013528605, 6415.615063096229, 6241.812745617639, 6331.461825744863, 6337.556371053982, 6350.4919606432295, 6365.140706004815, 6350.079238510482, 6350.872982540231, 6351.364863513611, 6352.205974253218, 6352.6506042166275, 6021.54838387114, 6027.939720602913, 6190.1952746142715, 6213.37866213375, 6314.0, 6290.025046117859, 6330.082138090043, 6339.334369891095, 6344.809655710152, 6350.7459701611415, 6292.653904035322, 6309.2744334400295, 6311.495080393465, 6312.305646378986, 6330.113784070142], "value": 5921.294727945838, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [756.3873262657248, 812.8171161488666, 835.1106072033284, 957.8802649668792, 957.9952100239498, 38720.22559548811, 39746.00634984127, 41591.16817663645, 41658.95852603686, 41762.37356439648, 38065.8096709516, 38094.61905380945, 38113.61886381135, 38191.61808381915, 38289.425658615146, 7871.370290376756, 7993.2806047455715, 10668.039876411121, 10944.3980581068, 11071.0, 10721.30311529752, 10811.675649730521, 10824.40465774382, 10845.349279043283, 10848.457577121113, 9225.538723063853, 10824.40465774382, 10834.0, 10850.945745271263, 10882.40146791926, 10740.624078157267, 10808.51361688726, 10823.080038196762, 10833.0, 10854.945725271402, 10577.365358078463, 10581.788364232749, 10626.840597391045, 10631.46842657864, 10637.521311540922, 34944.0, 35165.94502164939, 35222.29555408878, 35232.59069637211, 35289.823550882335, 3271.656476070026, 3678.9632103678887, 3690.815459227055, 3706.425504046877, 3734.458503517002, 25029.873655685537, 25157.496850062897, 25918.740812591823, 26724.06465773708, 26768.59847102295, 3061.2652963288874, 3071.9078427647205, 3217.3404452087266, 3231.676832316773, 3235.983820080908, 327204.0, 331696.0, 333611.0, 334718.0, 335176.0, 37449.25101497955, 45867.0, 45928.0, 45937.31094033592, 46007.76996114998, 47215.52784472111, 47225.5277447228, 47322.76338618285, 47572.0, 48179.03641927142, 13889.513867014606, 14161.937854660735, 14187.219702916438, 14192.503262385768, 14245.216513091673, 11880.0, 12062.215955962834, 12073.87926120745, 12091.39543022863, 12130.57542986009, 11732.765344693229, 12155.027597792156, 12176.330301833485, 12197.634070977885, 12309.50761969529, 5220.737039350406, 5549.861253468614, 5555.833325000256, 5598.608097433222, 5619.213310136584, 1084.38732116354, 1086.413336798136, 1099.7910397024539, 1103.5199688135526, 1151.0503834336705, 27341.726582734314, 27610.723892761216, 27945.860270698515, 28153.43693126126, 28269.57595636067, 986.6349450703142, 1109.3842917181041, 1145.7536629624672, 1166.4984056855633, 1199.0347770045198, 14393.92803035978, 14564.49024284145, 14703.044302120541, 15173.62065948338, 15243.542693719206, 508108.0, 510217.89782102447, 510336.0, 511276.0, 511690.0, 50359.24461133086, 50694.74652626787, 50723.49276507262, 50829.0, 50835.49164508308, 1378.4761790519537, 1385.300423286234, 1412.6044707481922, 1424.9073810202303, 1425.016738450466, 22574.0, 27800.165995020183, 28149.0, 28388.716112839433, 28501.42997140087, 43416.78291608522, 43460.0, 43578.0, 43589.346159808265, 43655.126897462505, 487560.0, 488391.6741248813, 488876.0, 491216.0, 491365.0, 30474.390512189188, 30774.692253077184, 30828.53757193599, 30896.69103308938, 31149.532757009118, 38269.0, 39008.0, 39263.803680981415, 39639.40540891947, 39714.0, 23159.536809264057, 25134.49193048423, 26705.33236669099, 28387.432251354443, 28733.56899646465, 22472.0, 27850.44299113966, 28407.7216525261, 28606.284842878264, 28716.13851584456, 27764.0, 28952.710472895, 32636.673633263363, 32651.34697306088, 36010.4598431029, 24875.0, 28935.421291574472, 29064.0, 29317.706822931497, 29514.0, 23268.80323732893, 23495.295141145794, 23808.40478988039, 24085.916133773673, 24154.154604589457], "value": 18299.372583685406, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [417.17815902671725, 427.6899248045167, 432.49378657116574, 439.99560004399956, 442.70781284352313, 19276.3253286135, 19851.10670019849, 20875.582488350243, 20893.268735594258, 21123.0, 20186.293479728214, 21661.104338615867, 21987.0, 22364.8881755591, 22567.661485077737, 5608.9439105609035, 6696.732130714766, 6967.930320696804, 7021.157461104674, 7072.469564782637, 6842.281560436158, 6880.415164711006, 6912.758053468129, 6915.239323674392, 6927.792166234995, 6618.0, 6868.0, 6914.308569143096, 6919.550229235084, 6945.0, 6534.477241820643, 6852.588844669336, 6890.517663763539, 6913.827154321132, 6923.792286231396, 5699.658020518782, 6600.306967768363, 6622.602643841385, 6625.469962402996, 6641.402273795382, 26150.82321295527, 26465.867670661715, 26611.201663950113, 26634.801433935518, 26658.73341266582, 2696.0563802669103, 2950.9409811803857, 2960.4967155583604, 2973.3012742005826, 2979.8212107273416, 15020.948533602652, 16283.0, 16365.427210047592, 17024.233909474104, 17058.0, 2081.9791802081936, 2098.6327392706366, 2149.6668016457475, 2170.305502239284, 2248.5502899420017, 120678.79321206763, 122653.0, 126645.0, 127223.0, 128900.0, 21250.78749212519, 21971.780282197295, 22013.339599812032, 22272.329575281918, 22272.331830045125, 33939.32121357559, 34383.96848094561, 34686.65313346885, 34711.479327810106, 35144.47283290753, 10611.09805666527, 10850.348979061282, 10912.672619821418, 11033.393163376091, 11178.105751539857, 8920.73237802867, 8939.284857211407, 8965.372423930263, 9106.043865394038, 9125.908740912639, 10434.16526677864, 10506.366361274133, 10550.261481696361, 10678.786424271471, 10682.252242342962, 4142.2543942090715, 4446.444194475691, 4531.818727250873, 4553.290791978768, 4557.6581756368405, 1013.878334599838, 1021.5045702834119, 1024.9494268374976, 1037.5434808684154, 1065.4246706778408, 17232.451668365942, 22119.44701382478, 22191.335649826327, 22312.995915183856, 22344.776552234594, 979.6081567373108, 1020.5254556631202, 1032.726327523207, 1045.0646671229208, 1045.2787576572293, 10062.899371006197, 11789.7031326554, 11802.11484138693, 11842.0, 11843.348615826213, 605597.0, 615145.0, 619182.0, 633916.0, 637319.0, 123208.0, 127200.0, 152472.0, 152892.23553882158, 156429.99999999773, 1178.3754610056683, 1236.4436003798328, 1280.5006047641427, 1281.653953432568, 1286.7683816913045, 19139.277465028215, 19477.220911163397, 19747.703784442958, 19909.502262443555, 19924.50188745235, 64148.67925660342, 64193.0, 64384.678076609314, 64469.0, 65099.34900651122, 61615.0, 89957.55021224852, 91347.54326228326, 92035.53982230046, 92426.0, 10170.237232207763, 11756.353400563052, 11776.882231177578, 11874.940625296818, 11966.760664786481, 10664.733381665521, 11046.0, 12381.380930953597, 12559.183653062522, 12726.981841452847, 10774.784504310026, 10812.459377031275, 10890.836637450602, 11005.559777608805, 11175.720606984565, 10042.799144017225, 10313.22650801178, 10486.161107111566, 10576.735581610521, 10646.946765266124, 9621.797275340583, 11169.609063682732, 11385.373804440504, 11424.543018279175, 11539.711507212387, 9389.953050234704, 11497.367644779617, 11513.309201447604, 11637.359945203096, 11797.0, 11235.719107022063, 11273.605423810128, 11365.943170284427, 11481.196316257781, 11755.412229388667], "value": 11610.012546040276, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1271.2499625221121, 1503.4437258214462, 1552.0, 1580.1230317173963, 1580.265176692838, 8133.308668763162, 9489.193418559413, 10747.516361763719, 11363.545458181663, 11382.2032457728, 10668.839967400496, 10999.945000275007, 11001.449927503612, 11027.724306892333, 11059.0, 2329.1847853251397, 2342.3090188394463, 2357.846739961897, 2358.445765245166, 2359.1035406545575, 2284.497410569672, 2305.4005958450757, 2311.05246848792, 2322.6516022596575, 2322.7328857181474, 2294.977050229493, 2306.1582522379376, 2310.6418505131733, 2313.0400883633274, 2316.1546035696933, 2248.4491299631695, 2260.886955652211, 2263.9320820375415, 2265.8300627452923, 2268.092762894839, 2217.1020736601704, 2232.3303009097367, 2265.150568536799, 2298.586254474194, 2318.7681231876845, 9532.903716072671, 11186.0, 11207.383593902332, 11243.775124497464, 11250.3812290324, 5332.14685650298, 5508.586855985777, 5512.503874651261, 5518.724063796794, 5529.474699903543, 3110.237991692027, 3181.809091454497, 3373.409653310661, 3378.4932260160926, 3382.6109997350372, 1175.3829239649253, 1186.323795436593, 1818.7999320074782, 1860.7674040744907, 1953.9609207815906, 15202.391904323813, 15475.14886681232, 15536.0, 15600.531984040497, 15779.132147731985, 4306.74159550428, 4360.732419142092, 4530.184566777945, 4530.728156310566, 4534.6372290216705, 11729.941350293195, 11773.293602383885, 11805.055595552336, 11831.585894493652, 11833.349165795791, 5447.155690867922, 5459.563234941195, 5471.562275017989, 5473.343198816167, 5498.340199176125, 4309.0, 4381.0, 4979.103761322925, 5059.013492368953, 5077.619178561623, 3176.872925083017, 3669.834857431422, 3776.773393596393, 3802.562705288927, 3836.3861782114727, 964.5900492290748, 966.0822218892081, 972.1202311907734, 978.515634760798, 985.3152059318704, 916.935814492979, 920.9585568649293, 930.6603089872148, 931.231733819588, 957.2772556719632, 6513.446357059608, 6543.640099794462, 6556.475481961432, 6604.504662150358, 6704.765333213314, 944.1455482788087, 945.6548359848606, 951.4481600671594, 980.3578655980344, 988.3149230561875, 3926.0773718176206, 4029.1135950090934, 4096.73371230869, 4293.0, 4381.364702118175, 12780.93609531965, 14067.788983165261, 14071.663191996744, 14083.929580352033, 14150.504732334319, 13590.25253611041, 13696.452141914211, 13921.860781392259, 14012.71974560503, 14063.156210627396, 10072.949635251776, 10186.541605627617, 10236.18110551169, 10243.84634230487, 10304.896951030543, 6417.96791016042, 6496.285408604956, 6528.053432252392, 6538.823011857911, 6696.1629796275465, 11928.0, 12014.63956081319, 12043.217190882564, 12104.334261615695, 12136.029117670741, 12451.501939922302, 12594.0, 12595.992320614512, 12793.488260469849, 12868.678283042998, 9113.817723645623, 10347.12049475803, 10549.261551691308, 10587.4706264688, 10707.625233116803, 10635.787284254426, 10868.565257389615, 10939.28894621847, 10941.56233750673, 10977.28647637933, 7891.250331218525, 8916.509591972244, 8935.195832375117, 8938.50838203905, 9097.953735320523, 8769.123087691434, 8807.295416366804, 8863.867041994246, 8947.19475247231, 9056.230220431074, 7895.447318687637, 7935.444518883623, 7950.0, 8032.236937490929, 8036.116027237116, 5456.099743542271, 5754.856128596819, 5968.253968253968, 6031.819045428644, 6080.8783824324155, 5944.791932282349, 6008.909866352095, 6011.489023432882, 6013.368596297326, 6152.446279834837], "value": 5100.251775737933, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [318.89954664280737, 393.1037235103962, 397.7792325259481, 398.4837692579734, 401.8934982229708, 4943.159662857314, 4956.33089532913, 5303.973480132604, 5353.598480113992, 5355.446920393086, 4665.253559430492, 5054.1660625996665, 5281.2342210379475, 5305.867353316159, 5338.874680306901, 3747.7001839852815, 4300.806463709125, 4385.868423947287, 4432.8005239764125, 4479.3281007848755, 4018.327812005013, 4245.363195520666, 4401.975091457934, 4428.914915845607, 4429.667774916878, 3736.8229007862437, 4119.157781131025, 4437.7781110944325, 4471.329300604919, 4482.731036137826, 3499.7725147865435, 4384.693071484998, 4389.253826849412, 4393.946207385884, 4434.157510073076, 3843.7117216208617, 3963.603639636013, 4011.6991225657903, 4016.7305472407998, 4044.4337792709043, 4234.809433575497, 4378.387025816388, 4953.860612059247, 5095.847124586231, 5141.4523978454745, 4585.380973568559, 4621.792019359137, 4695.740770337244, 4927.768057985503, 5089.0, 5175.835437026676, 5597.320803758856, 5641.576881733846, 5708.971455142698, 5727.942720572824, 3919.451276821247, 4219.78901054946, 4423.557644235583, 4573.291139873325, 4629.768511574442, 4809.2305231162845, 5496.972515137439, 5539.224508568804, 5618.106675062346, 5909.527237820964, 4108.589141085868, 4384.846530371422, 4520.88697782558, 4630.652701047435, 4674.672772905864, 4294.561321957117, 4439.624800731658, 4576.009435895397, 4590.357349971006, 4803.543663351976, 4938.530839570235, 5047.671901326402, 5076.13705670037, 5133.281340612317, 5134.768935397916, 5303.575713942875, 5565.610407271533, 5678.801241956512, 5687.744051517609, 5703.885922281531, 1454.7963285140088, 1540.8382119877251, 1607.5579215715538, 1609.9517014489584, 1620.965688061499, 997.7256254530063, 1194.988050119505, 1200.8498937632796, 1206.8414322250562, 1209.667341481082, 4611.7863425555925, 4642.373279607277, 5278.076336641073, 5299.549538289211, 5325.201219817059, 5116.462771409023, 5467.371252305956, 5773.769049237983, 5888.440598143169, 5917.05327147663, 4843.98276361968, 5008.222081161135, 5080.0, 5250.028744682246, 5335.291238374902, 2657.9202623921315, 2767.6540432445945, 2982.1799005273288, 3008.969910300913, 3017.706543027271, 5087.516685914832, 5293.991319851995, 6000.639961602318, 6010.248718910137, 6036.486898613667, 5024.84925452237, 5061.4179369373, 5435.0, 6021.133448630939, 6126.509879209652, 4974.477679843637, 5469.031148786434, 5646.97176514123, 5938.317093534212, 6079.568172730943, 5456.443869369121, 5684.617652496758, 5739.71301434935, 5774.671825480247, 5866.530677545871, 5275.472452754694, 5759.712014399347, 5793.04414771558, 5799.82600521985, 5801.796937107181, 5536.446355364431, 5815.331236907809, 5841.086472063813, 5871.853203669943, 5895.29256489224, 3913.96086039136, 4440.533743956838, 4580.0, 4598.471175814824, 4620.5148459411275, 4794.616430685606, 5307.7611507483025, 5836.241288632451, 5853.824385268449, 5879.412058794086, 5215.895682086413, 5371.355437347544, 5569.245687608391, 5661.660300381825, 5678.74445649955, 4962.925556116589, 5170.7331703732825, 5357.785688572413, 5619.494245517923, 5765.538756899354, 5029.968856384359, 5065.974670126626, 5361.812336568358, 5579.497845193952, 5594.244776955059, 4953.603711702983, 5090.49095090488, 5317.707526086102, 5516.284270851964, 5680.517156041617, 4828.565429111397, 5516.806911758069, 5559.497516564538, 5570.749316280696, 5577.3028371453565], "value": 4301.9918132420735, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [714.4141803720951, 716.5843810589859, 764.2586690909816, 820.4912953968538, 822.5980137997049, 12316.81524777128, 16402.83597164028, 17791.822081779195, 18653.787503812244, 18921.905390473064, 15463.84536154641, 17886.731699024524, 17971.191296391662, 18010.729839052423, 18097.457076287734, 932.4731526687417, 955.6273053509102, 957.9233661307079, 961.2694352292273, 962.4946902876011, 883.0595415882098, 943.6980166346772, 959.52023988006, 960.3661583354988, 960.7309953213112, 981.9165370943479, 997.6607953295878, 1001.6694490818013, 1001.8146642871092, 1003.6437064841986, 972.3339512434005, 995.775950411159, 996.6511720897664, 998.770282834952, 1002.5839276700161, 727.6507276507253, 741.7218543046357, 742.9479936404454, 743.8214828441189, 750.1598210004775, 16124.677506449805, 16389.672206555922, 16428.917855410768, 16974.660506789794, 17059.06175160366, 13874.0, 13938.651533711636, 14035.438582456689, 14202.644933876632, 14395.78406323906, 11613.361265130414, 11769.470373833197, 11880.465379057963, 11910.166288359818, 12009.099317551132, 3030.9848450757827, 3123.5470856725647, 3146.496560550324, 3468.924633363665, 3676.613955534684, 83279.0, 89975.0, 111566.0, 118655.0, 119565.40217298857, 10648.574057037787, 10740.624078157227, 10772.676819695422, 11233.438328083563, 11469.197156198987, 18567.90716046411, 22512.437189070406, 22651.20720774798, 22926.312210633707, 23022.42443938915, 7584.469087163846, 7616.8476630467085, 7679.577623230776, 7739.535627862347, 7973.521588704696, 4145.274576949082, 4166.395872598457, 4236.364545318165, 4360.4767427908855, 4377.124575084932, 3478.9563131061086, 3537.2925414917117, 3552.342816578941, 3563.7149028077692, 3576.2489877126095, 628.6259675493075, 645.6932956845461, 646.3859333633068, 655.1744801550082, 661.9338066193342, 674.6930146783202, 686.4302628818069, 700.3661686174021, 704.7357241034612, 728.621117019147, 8688.826223475495, 9280.860787088199, 10362.585496580203, 10374.533146008447, 10467.581296748198, 669.2335447136014, 682.3347236444426, 706.4595584377955, 717.9138503379628, 718.5783265263599, 4713.835015774432, 4927.728974906415, 4929.507049295114, 4930.580900623487, 4947.529984651453, 104530.11494023094, 104817.47591261995, 105683.94316056895, 106628.0, 108562.0, 59658.55800123719, 73644.63177684078, 76434.61782691162, 79991.60004199942, 89411.55294223488, 61072.0, 66774.33064173434, 67602.32397675961, 74571.5085698283, 76193.0, 10416.947915260374, 11046.392448415414, 11068.778624427627, 11341.376224307742, 11389.373584452604, 46967.0, 49927.001459971325, 51242.48757512377, 52288.0, 52337.0, 64830.0, 65178.348216517225, 66041.33958660543, 66120.66939665271, 71015.0, 13125.146865453715, 13764.380602872694, 14185.537763837054, 14392.424303027761, 14778.408863645334, 14934.327955241823, 15117.470888518848, 15136.848631513985, 15935.123568203397, 16433.342666293214, 14004.929975350058, 14218.360173791998, 14703.0, 15004.849951500346, 15249.847501525286, 13400.262985535499, 13968.161910285415, 14363.928180359031, 14746.557603271918, 15525.922370388076, 13301.534446294334, 14032.438702452197, 14049.0, 14071.366788494746, 14322.641933951734, 9704.70885873425, 9731.639052280178, 9994.250431217542, 10071.446070466194, 10191.133753630727, 9415.858762118436, 9650.179734722627, 10739.315171207198, 11696.590619328623, 11883.16817822744], "value": 7710.17374333897, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1527.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 123.0, 120.0, 122.0, 123.0, 120.0, 120.0, 121.0, 121.0, 120.0], "value": 119.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 112.0, 112.0, 149.0, 112.0, 120.0, 141.0, 98.0, 160.0, 98.0], "value": 123.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 203.0, 193.0, 200.0, 196.0, 201.0, 197.0, 191.0, 200.0, 198.0], "value": 197.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 52.0, 53.0, 83.0, 75.0, 86.0, 87.0, 87.0, 73.0, 64.0], "value": 74.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 69.0, 85.0, 72.0, 74.0, 70.0, 82.0, 69.0, 73.0, 82.0], "value": 76.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 88.0, 89.0, 89.0, 91.0, 90.0, 88.0, 83.0, 135.0, 137.0], "value": 97.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 108.0, 154.0, 150.0, 154.0, 98.0, 97.0, 97.0, 98.0, 97.0], "value": 115.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 90.0, 91.0, 91.0, 91.0, 110.0, 145.0, 99.0, 98.0, 114.0], "value": 102.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 63.0, 67.0, 68.0, 69.0, 69.0, 68.0, 67.0, 69.0], "value": 67.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 55.0, 69.0, 61.0, 66.0, 64.0, 57.0, 54.0, 56.0, 58.0], "value": 59.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 82.0, 86.0, 81.0, 85.0, 84.0, 86.0, 86.0, 89.0, 85.0], "value": 85.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 136.0, 158.0, 146.0, 147.0, 143.0, 144.0, 157.0, 207.0, 152.0], "value": 153.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 224.0, 183.0, 169.0, 171.0, 168.0, 163.0, 166.0, 209.0, 231.0], "value": 185.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 65.0, 66.0, 65.0, 67.0, 63.0, 65.0, 71.0, 79.0, 86.0], "value": 69.5, "unit": "score"}]}]} 21:53:01 INFO - Return code: 0 21:53:01 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 21:53:01 ERROR - # TBPL SUCCESS # 21:53:01 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:53:01 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:53:01 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:53:01 INFO - 2017-04-03 21:53:01.411 screenresolution[1660:10906] starting screenresolution argv=screenresolution get 21:53:01 INFO - 2017-04-03 21:53:01.413 screenresolution[1660:10906] Display 0: 1600x1200x32@60 21:53:01 INFO - 2017-04-03 21:53:01.419 screenresolution[1661:10909] starting screenresolution argv=screenresolution list 21:53:01 INFO - Available Modes on Display 0 21:53:01 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:53:01 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:53:01 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:53:01 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:53:01 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:53:01 INFO - Intel Iris: 21:53:01 INFO - Chipset Model: Intel Iris 21:53:01 INFO - Type: GPU 21:53:01 INFO - Bus: Built-In 21:53:01 INFO - VRAM (Dynamic, Max): 1536 MB 21:53:01 INFO - Vendor: Intel (0x8086) 21:53:01 INFO - Device ID: 0x0a2e 21:53:01 INFO - Revision ID: 0x0009 21:53:01 INFO - Displays: 21:53:01 INFO - Display: 21:53:01 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:53:01 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:53:01 INFO - Display Serial Number: 1600x1200 60 21:53:01 INFO - Main Display: Yes 21:53:01 INFO - Mirror: Off 21:53:01 INFO - Online: Yes 21:53:01 INFO - Rotation: Supported 21:53:01 INFO - Return code: 0 21:53:01 INFO - Running post-action listener: _resource_record_post_action 21:53:01 INFO - [mozharness: 2017-04-04 04:53:01.656423Z] Finished run-tests step (success) 21:53:01 INFO - Running post-run listener: _resource_record_post_run 21:53:01 INFO - Total resource usage - Wall time: 1198s; CPU: 55.0%; Read bytes: 34652672; Write bytes: 514496512; Read time: 314; Write time: 1664 21:53:01 INFO - TinderboxPrint: CPU usage
54.8% 21:53:01 INFO - TinderboxPrint: I/O read bytes / time
34,652,672 / 314 21:53:01 INFO - TinderboxPrint: I/O write bytes / time
514,496,512 / 1,664 21:53:01 INFO - TinderboxPrint: CPU idle
2,166.8 (45.2%) 21:53:01 INFO - TinderboxPrint: CPU system
962.6 (20.1%) 21:53:01 INFO - TinderboxPrint: CPU user
1,664.3 (34.7%) 21:53:01 INFO - TinderboxPrint: Swap in / out
238,870,528 / 0 21:53:01 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 203850240; Write bytes: 199258112; Read time: 15636; Write time: 367 21:53:01 INFO - run-tests - Wall time: 1179s; CPU: 55.0%; Read bytes: 32629248; Write bytes: 304396288; Read time: 293; Write time: 1262 21:53:02 INFO - Running post-run listener: _upload_blobber_files 21:53:02 INFO - Blob upload gear active. 21:53:02 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:53:02 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 21:53:02 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'] 21:53:02 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 21:53:02 INFO - (blobuploader) - INFO - Open directory for files ... 21:53:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 21:53:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:53:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:53:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 21:53:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:53:03 INFO - (blobuploader) - INFO - Done attempting. 21:53:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 21:53:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:53:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:53:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 21:53:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:53:04 INFO - (blobuploader) - INFO - Done attempting. 21:53:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 21:53:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:53:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:53:05 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 21:53:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:53:05 INFO - (blobuploader) - INFO - Done attempting. 21:53:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:53:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:53:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:53:07 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:53:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:53:07 INFO - (blobuploader) - INFO - Done attempting. 21:53:07 INFO - (blobuploader) - INFO - Iteration through files over. 21:53:07 INFO - Return code: 0 21:53:07 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:53:07 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:53:07 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/afe1b64bea577d37afe9a874ee3466bf5f57cc3a29778d1c247ffefd1d5ddb011b4f73dfd58d48321e07b36ae4e8bc9f84b72516803c8dd47ff4e195be9b130d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1fda828f699e03d9f4a4fb364bf6ae2b91f9f51570007990a7d3eac154c93b0fd5a9c5ba2404e6ffce1fde9dffb32486ce2dabd861803849f2faddd8689eff51", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9431bce062976a3942991c81aa16695f43888d29063cf1ac2ce6f937581f6167f226b1622b4a9625a852573a7c2f5494aca2cef6fe9b28f5b15a50379746c4cd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2fe0b0aeaaa5c3b9819c63764cfb4671f4a8621b18e93809b63ac82da38e5df3cc435a18ac0bed90118b352ee773c33cc4b35c079e444c9689f5f266fcfaea63"} 21:53:07 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:53:07 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:53:07 INFO - Contents: 21:53:07 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/afe1b64bea577d37afe9a874ee3466bf5f57cc3a29778d1c247ffefd1d5ddb011b4f73dfd58d48321e07b36ae4e8bc9f84b72516803c8dd47ff4e195be9b130d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1fda828f699e03d9f4a4fb364bf6ae2b91f9f51570007990a7d3eac154c93b0fd5a9c5ba2404e6ffce1fde9dffb32486ce2dabd861803849f2faddd8689eff51", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9431bce062976a3942991c81aa16695f43888d29063cf1ac2ce6f937581f6167f226b1622b4a9625a852573a7c2f5494aca2cef6fe9b28f5b15a50379746c4cd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2fe0b0aeaaa5c3b9819c63764cfb4671f4a8621b18e93809b63ac82da38e5df3cc435a18ac0bed90118b352ee773c33cc4b35c079e444c9689f5f266fcfaea63"} 21:53:07 INFO - Running post-run listener: copy_logs_to_upload_dir 21:53:07 INFO - Copying logs to upload dir... 21:53:07 INFO - mkdir: /builds/slave/test/build/upload/logs 21:53:07 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1281.142675 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2017-04-03 21:53:07.702533) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-03 21:53:07.705864) ========= 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.RH0K1cuKUI/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.F8qx1bnCvA/Listeners TMPDIR=/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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/afe1b64bea577d37afe9a874ee3466bf5f57cc3a29778d1c247ffefd1d5ddb011b4f73dfd58d48321e07b36ae4e8bc9f84b72516803c8dd47ff4e195be9b130d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1fda828f699e03d9f4a4fb364bf6ae2b91f9f51570007990a7d3eac154c93b0fd5a9c5ba2404e6ffce1fde9dffb32486ce2dabd861803849f2faddd8689eff51", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9431bce062976a3942991c81aa16695f43888d29063cf1ac2ce6f937581f6167f226b1622b4a9625a852573a7c2f5494aca2cef6fe9b28f5b15a50379746c4cd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2fe0b0aeaaa5c3b9819c63764cfb4671f4a8621b18e93809b63ac82da38e5df3cc435a18ac0bed90118b352ee773c33cc4b35c079e444c9689f5f266fcfaea63"} build_url:https://queue.taskcluster.net/v1/task/PmyUCak6ShatAiOjN46MCw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011399 build_url: 'https://queue.taskcluster.net/v1/task/PmyUCak6ShatAiOjN46MCw/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/afe1b64bea577d37afe9a874ee3466bf5f57cc3a29778d1c247ffefd1d5ddb011b4f73dfd58d48321e07b36ae4e8bc9f84b72516803c8dd47ff4e195be9b130d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1fda828f699e03d9f4a4fb364bf6ae2b91f9f51570007990a7d3eac154c93b0fd5a9c5ba2404e6ffce1fde9dffb32486ce2dabd861803849f2faddd8689eff51", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9431bce062976a3942991c81aa16695f43888d29063cf1ac2ce6f937581f6167f226b1622b4a9625a852573a7c2f5494aca2cef6fe9b28f5b15a50379746c4cd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2fe0b0aeaaa5c3b9819c63764cfb4671f4a8621b18e93809b63ac82da38e5df3cc435a18ac0bed90118b352ee773c33cc4b35c079e444c9689f5f266fcfaea63"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-03 21:53:07.742604) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-03 21:53:07.742979) ========= 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.RH0K1cuKUI/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.F8qx1bnCvA/Listeners TMPDIR=/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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.005198 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-03 21:53:07.793755) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-03 21:53:07.794133) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-03 21:53:07.794597) ========= ========= Total master_lag: 0.20 =========