builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0114 starttime: 1485792218.9 results: success (0) buildid: 20170130063643 builduid: 344ff77a52b54ab1a2acb6e994a94feb revision: dacf10cb5b872db5f5b0ed87ed96014a2b61952c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-30 08:03:38.904225) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-30 08:03:38.904909) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-30 08:03:38.905392) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-30 08:03:38.931842) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-30 08:03:38.932237) ========= 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.Tg4sZnkiHX/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.MQUGY54SQy/Listeners TMPDIR=/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-01-30 08:03:38-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 15.4M=0.001s 2017-01-30 08:03:39 (15.4 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.131036 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-30 08:03:39.087098) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-30 08:03:39.087421) ========= 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.Tg4sZnkiHX/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.MQUGY54SQy/Listeners TMPDIR=/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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.027689 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-30 08:03:39.136638) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-30 08:03:39.136984) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev dacf10cb5b872db5f5b0ed87ed96014a2b61952c --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 dacf10cb5b872db5f5b0ed87ed96014a2b61952c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Tg4sZnkiHX/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.MQUGY54SQy/Listeners TMPDIR=/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-01-30 08:03:39,241 truncating revision to first 12 chars 2017-01-30 08:03:39,241 Setting DEBUG logging. 2017-01-30 08:03:39,241 attempt 1/10 2017-01-30 08:03:39,241 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/dacf10cb5b87?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-30 08:03:40,254 unpacking tar archive at: mozilla-aurora-dacf10cb5b87/testing/mozharness/ program finished with exit code 0 elapsedTime=1.284508 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-30 08:03:40.444651) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-30 08:03:40.444969) ========= script_repo_revision: dacf10cb5b872db5f5b0ed87ed96014a2b61952c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-30 08:03:40.445371) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-30 08:03:40.445637) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-30 08:03:40.465146) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 59 secs) (at 2017-01-30 08:03:40.465543) ========= /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.Tg4sZnkiHX/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.MQUGY54SQy/Listeners TMPDIR=/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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 08:03:40 INFO - MultiFileLogger online at 20170130 08:03:40 in /builds/slave/test 08:03:40 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 08:03:40 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:03:40 INFO - {'append_to_log': False, 08:03:40 INFO - 'base_work_dir': '/builds/slave/test', 08:03:40 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 08:03:40 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:03:40 INFO - 'branch': 'Mozilla-Aurora', 08:03:40 INFO - 'buildbot_json_path': 'buildprops.json', 08:03:40 INFO - 'config_files': ('talos/mac_config.py',), 08:03:40 INFO - 'default_actions': ('clobber', 08:03:40 INFO - 'read-buildbot-config', 08:03:40 INFO - 'download-and-extract', 08:03:40 INFO - 'populate-webroot', 08:03:40 INFO - 'create-virtualenv', 08:03:40 INFO - 'install', 08:03:40 INFO - 'run-tests'), 08:03:40 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:03:40 INFO - 'download_minidump_stackwalk': True, 08:03:40 INFO - 'download_symbols': 'ondemand', 08:03:40 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:03:40 INFO - 'tooltool.py': '/tools/tooltool.py', 08:03:40 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:03:40 INFO - '/tools/misc-python/virtualenv.py')}, 08:03:40 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:03:40 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:03:40 INFO - 'installer_path': 'installer.exe', 08:03:40 INFO - 'log_level': 'info', 08:03:40 INFO - 'log_name': 'talos', 08:03:40 INFO - 'log_to_console': True, 08:03:40 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:03:40 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:03:40 INFO - 'opt_config_files': (), 08:03:40 INFO - 'pip_index': False, 08:03:40 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:03:40 INFO - 'cmd': ('bash', 08:03:40 INFO - '-c', 08:03:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:03:40 INFO - 'enabled': True, 08:03:40 INFO - 'halt_on_failure': False, 08:03:40 INFO - 'name': 'check_screen_resolution'},), 08:03:40 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:03:40 INFO - 'cmd': ('bash', 08:03:40 INFO - '-c', 08:03:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:03:40 INFO - 'enabled': True, 08:03:40 INFO - 'halt_on_failure': False, 08:03:40 INFO - 'name': 'check_screen_resolution'},), 08:03:40 INFO - 'run_cmd_checks_enabled': True, 08:03:40 INFO - 'sps_profile': False, 08:03:40 INFO - 'sps_profile_interval': 0, 08:03:40 INFO - 'suite': 'dromaeojs-e10s', 08:03:40 INFO - 'system_bits': '32', 08:03:40 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:03:40 INFO - 'title': 't-yosemite-r7-0114', 08:03:40 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:03:40 INFO - 'use_talos_json': True, 08:03:40 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:03:40 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:03:40 INFO - 'work_dir': 'build'} 08:03:40 INFO - [mozharness: 2017-01-30 16:03:40.678247Z] Running clobber step. 08:03:40 INFO - Running pre-action listener: _resource_record_pre_action 08:03:40 INFO - Running main action method: clobber 08:03:40 INFO - rmtree: /builds/slave/test/build 08:03:40 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:03:43 INFO - Running post-action listener: _resource_record_post_action 08:03:43 INFO - [mozharness: 2017-01-30 16:03:43.705298Z] Finished clobber step (success) 08:03:43 INFO - [mozharness: 2017-01-30 16:03:43.705422Z] Running read-buildbot-config step. 08:03:43 INFO - Running pre-action listener: _resource_record_pre_action 08:03:43 INFO - Running main action method: read_buildbot_config 08:03:43 INFO - Using buildbot properties: 08:03:43 INFO - { 08:03:43 INFO - "project": "", 08:03:43 INFO - "product": "firefox", 08:03:43 INFO - "script_repo_revision": "production", 08:03:43 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 08:03:43 INFO - "repository": "", 08:03:43 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 08:03:43 INFO - "buildid": "20170130063643", 08:03:43 INFO - "pgo_build": "False", 08:03:43 INFO - "basedir": "/builds/slave/test", 08:03:43 INFO - "buildnumber": 47, 08:03:43 INFO - "slavename": "t-yosemite-r7-0114", 08:03:43 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 08:03:43 INFO - "platform": "yosemite_r7", 08:03:43 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 08:03:43 INFO - "branch": "mozilla-aurora", 08:03:43 INFO - "repo_path": "releases/mozilla-aurora", 08:03:43 INFO - "revision": "dacf10cb5b872db5f5b0ed87ed96014a2b61952c", 08:03:43 INFO - "stage_platform": "macosx64", 08:03:43 INFO - "builduid": "344ff77a52b54ab1a2acb6e994a94feb", 08:03:43 INFO - "slavebuilddir": "test" 08:03:43 INFO - } 08:03:43 INFO - Found installer url https://queue.taskcluster.net/v1/task/dr7R0axpRbG8zI2bnKzKYw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg. 08:03:43 INFO - Running post-action listener: _resource_record_post_action 08:03:43 INFO - [mozharness: 2017-01-30 16:03:43.708654Z] Finished read-buildbot-config step (success) 08:03:43 INFO - [mozharness: 2017-01-30 16:03:43.708772Z] Running download-and-extract step. 08:03:43 INFO - Running pre-action listener: _resource_record_pre_action 08:03:43 INFO - Running main action method: download_and_extract 08:03:43 INFO - mkdir: /builds/slave/test/build/tests 08:03:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:03:43 INFO - trying https://queue.taskcluster.net/v1/task/dr7R0axpRbG8zI2bnKzKYw/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json 08:03:43 INFO - Downloading https://queue.taskcluster.net/v1/task/dr7R0axpRbG8zI2bnKzKYw/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 08:03:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dr7R0axpRbG8zI2bnKzKYw/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json'}, attempt #1 08:03:45 INFO - Downloaded 1199 bytes. 08:03:45 INFO - Reading from file /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 08:03:45 INFO - Using the following test package requirements: 08:03:45 INFO - {u'common': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 08:03:45 INFO - u'cppunittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 08:03:45 INFO - u'firefox-53.0a2.en-US.mac.cppunittest.tests.zip'], 08:03:45 INFO - u'gtest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 08:03:45 INFO - u'firefox-53.0a2.en-US.mac.gtest.tests.zip'], 08:03:45 INFO - u'jittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 08:03:45 INFO - u'jsshell-mac.zip'], 08:03:45 INFO - u'mochitest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 08:03:45 INFO - u'firefox-53.0a2.en-US.mac.mochitest.tests.zip'], 08:03:45 INFO - u'mozbase': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 08:03:45 INFO - u'reftest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 08:03:45 INFO - u'firefox-53.0a2.en-US.mac.reftest.tests.zip'], 08:03:45 INFO - u'talos': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 08:03:45 INFO - u'firefox-53.0a2.en-US.mac.talos.tests.zip'], 08:03:45 INFO - u'web-platform': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 08:03:45 INFO - u'firefox-53.0a2.en-US.mac.web-platform.tests.zip'], 08:03:45 INFO - u'xpcshell': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 08:03:45 INFO - u'firefox-53.0a2.en-US.mac.xpcshell.tests.zip']} 08:03:45 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip'] for test suite category: common 08:03:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dr7R0axpRbG8zI2bnKzKYw/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 08:03:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dr7R0axpRbG8zI2bnKzKYw/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:03:45 INFO - Fetch https://queue.taskcluster.net/v1/task/dr7R0axpRbG8zI2bnKzKYw/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 08:03:45 INFO - Expected file size: 16351792 08:03:45 INFO - Obtained file size: 16351792 08:03:52 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip', u'firefox-53.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 08:03:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dr7R0axpRbG8zI2bnKzKYw/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 08:03:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dr7R0axpRbG8zI2bnKzKYw/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:03:52 INFO - Fetch https://queue.taskcluster.net/v1/task/dr7R0axpRbG8zI2bnKzKYw/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 08:03:53 INFO - Expected file size: 16351792 08:03:53 INFO - Obtained file size: 16351792 08:03:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dr7R0axpRbG8zI2bnKzKYw/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip 08:03:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dr7R0axpRbG8zI2bnKzKYw/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip'}, attempt #1 08:03:59 INFO - Fetch https://queue.taskcluster.net/v1/task/dr7R0axpRbG8zI2bnKzKYw/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip into memory 08:04:01 INFO - Expected file size: 14403485 08:04:01 INFO - Obtained file size: 14403485 08:04: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')]} 08:04:01 INFO - trying https://queue.taskcluster.net/v1/task/dr7R0axpRbG8zI2bnKzKYw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 08:04:01 INFO - Downloading https://queue.taskcluster.net/v1/task/dr7R0axpRbG8zI2bnKzKYw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg 08:04:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dr7R0axpRbG8zI2bnKzKYw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg'}, attempt #1 08:04:03 INFO - Downloaded 73869548 bytes. 08:04:03 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dr7R0axpRbG8zI2bnKzKYw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 08:04:03 INFO - mkdir: /builds/slave/test/properties 08:04:03 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:04:03 INFO - Writing to file /builds/slave/test/properties/build_url 08:04:03 INFO - Contents: 08:04:03 INFO - build_url:https://queue.taskcluster.net/v1/task/dr7R0axpRbG8zI2bnKzKYw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 08:04:04 INFO - Running post-action listener: _resource_record_post_action 08:04:04 INFO - Running post-action listener: set_extra_try_arguments 08:04:04 INFO - [mozharness: 2017-01-30 16:04:04.175904Z] Finished download-and-extract step (success) 08:04:04 INFO - [mozharness: 2017-01-30 16:04:04.176054Z] Running populate-webroot step. 08:04:04 INFO - Running pre-action listener: _resource_record_pre_action 08:04:04 INFO - Running main action method: populate_webroot 08:04:04 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:04:04 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:04:04 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:04:04 INFO - '--tppagecycles', 08:04:04 INFO - '7'], 08:04:04 INFO - 'tests': ['tsvgm']}}, 08:04:04 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:04:04 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:04:04 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:04:04 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:04:04 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:04:04 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:04:04 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:04:04 INFO - 'talos_options': ['--disable-e10s'], 08:04:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:04:04 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:04:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:04:04 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:04:04 INFO - 'talos_options': ['--disable-e10s'], 08:04:04 INFO - 'tests': ['damp', 'tps']}, 08:04:04 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:04:04 INFO - 'tests': ['damp', 'tps']}, 08:04:04 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:04:04 INFO - 'tests': ['dromaeo_dom']}, 08:04:04 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:04:04 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:04:04 INFO - 'tests': ['basic_compositor_video']}, 08:04:04 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:04:04 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:04:04 INFO - 'tests': ['a11yr', 08:04:04 INFO - 'ts_paint', 08:04:04 INFO - 'tpaint', 08:04:04 INFO - 'sessionrestore', 08:04:04 INFO - 'sessionrestore_no_auto_restore', 08:04:04 INFO - 'tabpaint']}, 08:04:04 INFO - 'other-e10s': {'tests': ['a11yr', 08:04:04 INFO - 'ts_paint', 08:04:04 INFO - 'tpaint', 08:04:04 INFO - 'sessionrestore', 08:04:04 INFO - 'sessionrestore_no_auto_restore', 08:04:04 INFO - 'tabpaint']}, 08:04:04 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:04:04 INFO - 'ts_paint', 08:04:04 INFO - 'tpaint', 08:04:04 INFO - 'sessionrestore', 08:04:04 INFO - 'sessionrestore_no_auto_restore', 08:04:04 INFO - 'tabpaint']}, 08:04:04 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:04:04 INFO - 'ts_paint', 08:04:04 INFO - 'tpaint', 08:04:04 INFO - 'sessionrestore', 08:04:04 INFO - 'sessionrestore_no_auto_restore', 08:04:04 INFO - 'tabpaint']}, 08:04:04 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:04:04 INFO - 'tests': ['a11yr', 08:04:04 INFO - 'ts_paint', 08:04:04 INFO - 'tpaint', 08:04:04 INFO - 'sessionrestore', 08:04:04 INFO - 'sessionrestore_no_auto_restore', 08:04:04 INFO - 'tabpaint']}, 08:04:04 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:04:04 INFO - 'tests': ['a11yr', 08:04:04 INFO - 'ts_paint', 08:04:04 INFO - 'tpaint', 08:04:04 INFO - 'sessionrestore', 08:04:04 INFO - 'sessionrestore_no_auto_restore', 08:04:04 INFO - 'tabpaint']}, 08:04:04 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:04:04 INFO - 'tests': ['tsvgx', 08:04:04 INFO - 'tsvgr_opacity', 08:04:04 INFO - 'tart', 08:04:04 INFO - 'tscrollx', 08:04:04 INFO - 'cart', 08:04:04 INFO - 'tsvg_static']}, 08:04:04 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:04:04 INFO - 'tsvgr_opacity', 08:04:04 INFO - 'tart', 08:04:04 INFO - 'tscrollx', 08:04:04 INFO - 'cart', 08:04:04 INFO - 'tsvg_static']}, 08:04:04 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:04:04 INFO - 'talos_options': ['--disable-e10s'], 08:04:04 INFO - 'tests': ['tp5o']}, 08:04:04 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:04:04 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:04:04 INFO - 'talos_options': ['--disable-e10s', 08:04:04 INFO - '--xperf_path', 08:04:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:04:04 INFO - 'tests': ['tp5n']}, 08:04:04 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:04:04 INFO - 'talos_options': ['--xperf_path', 08:04:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:04:04 INFO - 'tests': ['tp5n']}}, 08:04:04 INFO - 'talos.zip': {'path': '', 08:04:04 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:04:04 INFO - Running post-action listener: _resource_record_post_action 08:04:04 INFO - [mozharness: 2017-01-30 16:04:04.195103Z] Finished populate-webroot step (success) 08:04:04 INFO - [mozharness: 2017-01-30 16:04:04.195231Z] Running create-virtualenv step. 08:04:04 INFO - Running pre-action listener: _resource_record_pre_action 08:04:04 INFO - Running main action method: create_virtualenv 08:04:04 INFO - Creating virtualenv /builds/slave/test/build/venv 08:04:04 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 08:04:04 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:04:04 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:04:04 INFO - Using real prefix '/tools/python27' 08:04:04 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:04:05 INFO - Installing distribute.............................................................................................................................................................................................done. 08:04:07 INFO - Installing pip.................done. 08:04:07 INFO - Return code: 0 08:04:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:04:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:04:08 INFO - Reading from file tmpfile_stdout 08:04:08 INFO - Output received: 08:04:08 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) 08:04:08 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:04:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:04:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:04:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:04:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:04:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:04:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:04:08 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 0x1020abb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1020ae768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff7f85918d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e89d30>, '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 0x101db0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101e7ebe8>, '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.Tg4sZnkiHX/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.MQUGY54SQy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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 08:04:08 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 08:04:08 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 08:04:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Tg4sZnkiHX/Render', 08:04:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:04:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:04:08 INFO - 'HOME': '/Users/cltbld', 08:04:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:04:08 INFO - 'LOGNAME': 'cltbld', 08:04:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:04:08 INFO - 'MOZ_NO_REMOTE': '1', 08:04:08 INFO - 'NO_EM_RESTART': '1', 08:04:08 INFO - 'PAGER': '/bin/cat', 08:04:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:04:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:04:08 INFO - 'PWD': '/builds/slave/test', 08:04:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:04:08 INFO - 'SHELL': '/bin/bash', 08:04:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MQUGY54SQy/Listeners', 08:04:08 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 08:04:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:04:08 INFO - 'USER': 'cltbld', 08:04:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:04:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:04:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:04:08 INFO - 'XPC_FLAGS': '0x0', 08:04:08 INFO - 'XPC_SERVICE_NAME': '0', 08:04:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:04:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:04:08 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 08:04:08 INFO - Cleaning up... 08:04:08 INFO - Return code: 0 08:04:08 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:04:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:04:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:04:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:04:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:04:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:04:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:04:08 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 0x1020abb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1020ae768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff7f85918d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e89d30>, '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 0x101db0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101e7ebe8>, '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.Tg4sZnkiHX/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.MQUGY54SQy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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 08:04:08 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 08:04:08 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 08:04:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Tg4sZnkiHX/Render', 08:04:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:04:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:04:08 INFO - 'HOME': '/Users/cltbld', 08:04:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:04:08 INFO - 'LOGNAME': 'cltbld', 08:04:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:04:08 INFO - 'MOZ_NO_REMOTE': '1', 08:04:08 INFO - 'NO_EM_RESTART': '1', 08:04:08 INFO - 'PAGER': '/bin/cat', 08:04:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:04:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:04:08 INFO - 'PWD': '/builds/slave/test', 08:04:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:04:08 INFO - 'SHELL': '/bin/bash', 08:04:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MQUGY54SQy/Listeners', 08:04:08 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 08:04:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:04:08 INFO - 'USER': 'cltbld', 08:04:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:04:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:04:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:04:08 INFO - 'XPC_FLAGS': '0x0', 08:04:08 INFO - 'XPC_SERVICE_NAME': '0', 08:04:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:04:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:04:08 INFO - Downloading/unpacking psutil>=3.1.1 08:04:08 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 08:04:08 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 08:04:08 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 08:04:08 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 08:04:08 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 08:04:08 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 08:04:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:04:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:04:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:04:12 INFO - Installing collected packages: psutil 08:04:12 INFO - Running setup.py install for psutil 08:04:12 INFO - building 'psutil._psutil_osx' extension 08:04:12 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 08:04:12 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 08:04:12 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 08:04:12 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 08:04:13 INFO - building 'psutil._psutil_posix' extension 08:04:13 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 08:04:13 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:04:13 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:04:13 INFO - ^ 08:04:13 INFO - 1 warning generated. 08:04:13 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 08:04:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:04:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:04:13 INFO - Successfully installed psutil 08:04:13 INFO - Cleaning up... 08:04:13 INFO - Return code: 0 08:04:13 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:04:13 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')]} 08:04:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:04:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:04:13 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')]} 08:04:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:04:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:04:13 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 0x1020abb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1020ae768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff7f85918d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e89d30>, '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 0x101db0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101e7ebe8>, '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.Tg4sZnkiHX/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.MQUGY54SQy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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 08:04:13 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 08:04:13 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 08:04:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Tg4sZnkiHX/Render', 08:04:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:04:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:04:13 INFO - 'HOME': '/Users/cltbld', 08:04:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:04:13 INFO - 'LOGNAME': 'cltbld', 08:04:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:04:13 INFO - 'MOZ_NO_REMOTE': '1', 08:04:13 INFO - 'NO_EM_RESTART': '1', 08:04:13 INFO - 'PAGER': '/bin/cat', 08:04:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:04:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:04:13 INFO - 'PWD': '/builds/slave/test', 08:04:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:04:13 INFO - 'SHELL': '/bin/bash', 08:04:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MQUGY54SQy/Listeners', 08:04:13 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 08:04:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:04:13 INFO - 'USER': 'cltbld', 08:04:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:04:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:04:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:04:13 INFO - 'XPC_FLAGS': '0x0', 08:04:13 INFO - 'XPC_SERVICE_NAME': '0', 08:04:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:04:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:04:13 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:04:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:04:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:04:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:04:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:04:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:04:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:04:16 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:04:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:04:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:04:16 INFO - Installing collected packages: mozsystemmonitor 08:04:16 INFO - Running setup.py install for mozsystemmonitor 08:04:16 INFO - Successfully installed mozsystemmonitor 08:04:16 INFO - Cleaning up... 08:04:16 INFO - Return code: 0 08:04:16 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:04:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:04:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:04:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:04:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:04:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:04:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:04:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1020abb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1020ae768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff7f85918d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e89d30>, '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 0x101db0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101e7ebe8>, '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.Tg4sZnkiHX/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.MQUGY54SQy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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 08:04:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:04:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:04:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Tg4sZnkiHX/Render', 08:04:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:04:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:04:16 INFO - 'HOME': '/Users/cltbld', 08:04:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:04:16 INFO - 'LOGNAME': 'cltbld', 08:04:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:04:16 INFO - 'MOZ_NO_REMOTE': '1', 08:04:16 INFO - 'NO_EM_RESTART': '1', 08:04:16 INFO - 'PAGER': '/bin/cat', 08:04:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:04:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:04:16 INFO - 'PWD': '/builds/slave/test', 08:04:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:04:16 INFO - 'SHELL': '/bin/bash', 08:04:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MQUGY54SQy/Listeners', 08:04:16 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 08:04:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:04:16 INFO - 'USER': 'cltbld', 08:04:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:04:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:04:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:04:16 INFO - 'XPC_FLAGS': '0x0', 08:04:16 INFO - 'XPC_SERVICE_NAME': '0', 08:04:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:04:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:04:16 INFO - Downloading/unpacking jsonschema==2.5.1 08:04:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:04:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:04:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:04:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:04:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:04:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:04:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:04:19 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:04:19 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 08:04:19 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 08:04:19 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 08:04:19 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 08:04:19 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 08:04:19 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 08:04:19 INFO - Downloading functools32-3.2.3-2.tar.gz 08:04:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:04:19 INFO - warning: no files found matching '*.txt' 08:04:19 INFO - no previously-included directories found matching 'build' 08:04:19 INFO - no previously-included directories found matching 'dist' 08:04:19 INFO - no previously-included directories found matching '.git*' 08:04:19 INFO - Installing collected packages: jsonschema, functools32 08:04:20 INFO - Running setup.py install for functools32 08:04:20 INFO - warning: no files found matching '*.txt' 08:04:20 INFO - no previously-included directories found matching 'build' 08:04:20 INFO - no previously-included directories found matching 'dist' 08:04:20 INFO - no previously-included directories found matching '.git*' 08:04:20 INFO - Successfully installed jsonschema functools32 08:04:20 INFO - Cleaning up... 08:04:20 INFO - Return code: 0 08:04:20 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:04:20 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')]} 08:04:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:04:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:04:20 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')]} 08:04:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:04:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:04:20 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 0x1020abb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1020ae768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff7f85918d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e89d30>, '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 0x101db0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101e7ebe8>, '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.Tg4sZnkiHX/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.MQUGY54SQy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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 08:04:20 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 08:04:20 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 08:04:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Tg4sZnkiHX/Render', 08:04:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:04:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:04:20 INFO - 'HOME': '/Users/cltbld', 08:04:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:04:20 INFO - 'LOGNAME': 'cltbld', 08:04:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:04:20 INFO - 'MOZ_NO_REMOTE': '1', 08:04:20 INFO - 'NO_EM_RESTART': '1', 08:04:20 INFO - 'PAGER': '/bin/cat', 08:04:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:04:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:04:20 INFO - 'PWD': '/builds/slave/test', 08:04:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:04:20 INFO - 'SHELL': '/bin/bash', 08:04:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MQUGY54SQy/Listeners', 08:04:20 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 08:04:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:04:20 INFO - 'USER': 'cltbld', 08:04:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:04:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:04:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:04:20 INFO - 'XPC_FLAGS': '0x0', 08:04:20 INFO - 'XPC_SERVICE_NAME': '0', 08:04:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:04:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:04:20 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:04:20 INFO - Cleaning up... 08:04:20 INFO - Return code: 0 08:04:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:04:20 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')]} 08:04:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:04:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:04:20 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')]} 08:04:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:04:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:04:20 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 0x1020abb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1020ae768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff7f85918d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e89d30>, '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 0x101db0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101e7ebe8>, '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.Tg4sZnkiHX/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.MQUGY54SQy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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 08:04:20 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 08:04:20 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 08:04:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Tg4sZnkiHX/Render', 08:04:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:04:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:04:20 INFO - 'HOME': '/Users/cltbld', 08:04:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:04:20 INFO - 'LOGNAME': 'cltbld', 08:04:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:04:20 INFO - 'MOZ_NO_REMOTE': '1', 08:04:20 INFO - 'NO_EM_RESTART': '1', 08:04:20 INFO - 'PAGER': '/bin/cat', 08:04:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:04:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:04:20 INFO - 'PWD': '/builds/slave/test', 08:04:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:04:20 INFO - 'SHELL': '/bin/bash', 08:04:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MQUGY54SQy/Listeners', 08:04:20 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 08:04:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:04:20 INFO - 'USER': 'cltbld', 08:04:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:04:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:04:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:04:20 INFO - 'XPC_FLAGS': '0x0', 08:04:20 INFO - 'XPC_SERVICE_NAME': '0', 08:04:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:04:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:04:20 INFO - Downloading/unpacking blobuploader==1.2.4 08:04:20 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 08:04:20 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 08:04:20 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 08:04:20 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 08:04:20 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 08:04:20 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 08:04:23 INFO - Downloading blobuploader-1.2.4.tar.gz 08:04:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:04:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:04:23 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 08:04:23 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 08:04:23 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 08:04:23 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 08:04:23 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 08:04:23 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 08:04:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:04:24 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:04:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:04:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:04:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:04:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:04:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:04:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:04:24 INFO - Downloading docopt-0.6.1.tar.gz 08:04:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:04:24 INFO - Installing collected packages: blobuploader, requests, docopt 08:04:24 INFO - Running setup.py install for blobuploader 08:04:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:04:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:04:24 INFO - Running setup.py install for requests 08:04:25 INFO - Running setup.py install for docopt 08:04:25 INFO - Successfully installed blobuploader requests docopt 08:04:25 INFO - Cleaning up... 08:04:25 INFO - Return code: 0 08:04:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:04:25 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')]} 08:04:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:04:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:04:25 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')]} 08:04:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:04:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:04:25 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 0x1020abb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1020ae768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff7f85918d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e89d30>, '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 0x101db0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101e7ebe8>, '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.Tg4sZnkiHX/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.MQUGY54SQy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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 08:04:25 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 08:04:25 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 08:04:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Tg4sZnkiHX/Render', 08:04:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:04:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:04:25 INFO - 'HOME': '/Users/cltbld', 08:04:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:04:25 INFO - 'LOGNAME': 'cltbld', 08:04:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:04:25 INFO - 'MOZ_NO_REMOTE': '1', 08:04:25 INFO - 'NO_EM_RESTART': '1', 08:04:25 INFO - 'PAGER': '/bin/cat', 08:04:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:04:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:04:25 INFO - 'PWD': '/builds/slave/test', 08:04:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:04:25 INFO - 'SHELL': '/bin/bash', 08:04:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MQUGY54SQy/Listeners', 08:04:25 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 08:04:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:04:25 INFO - 'USER': 'cltbld', 08:04:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:04:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:04:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:04:25 INFO - 'XPC_FLAGS': '0x0', 08:04:25 INFO - 'XPC_SERVICE_NAME': '0', 08:04:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:04:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:04:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:04:25 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-Ib7Xos-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:04:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:04:26 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-45PnVV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:04:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:04:26 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-vm1olb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:04:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:04:26 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-T8z0WS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:04:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:04:26 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-L9sujk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:04:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:04:26 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-P9UKxP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:04:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:04:26 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-qKW1pL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:04:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:04:26 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-OWF45y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:04:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:04:26 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-noIkKd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:04:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:04:26 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-GOD2SU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:04:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:04:26 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-rftQYk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:04:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:04:27 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-D68IoZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:04:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:04:27 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-nZ4DI1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:04:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:04:27 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-eFoZ90-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:04:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:04:27 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-Mg6l_U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:04:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:04:27 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-oUELDe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:04:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:04:27 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-2pUIwq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:04:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:04:27 INFO - Running setup.py install for manifestparser 08:04:27 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:04:27 INFO - Running setup.py install for mozcrash 08:04:27 INFO - Running setup.py install for mozdebug 08:04:28 INFO - Running setup.py install for mozdevice 08:04:28 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:04:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:04:28 INFO - Running setup.py install for mozfile 08:04:28 INFO - Running setup.py install for mozhttpd 08:04:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:04:28 INFO - Running setup.py install for mozinfo 08:04:28 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:04:28 INFO - Running setup.py install for mozInstall 08:04:28 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:04:28 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:04:28 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:04:28 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:04:28 INFO - Running setup.py install for mozleak 08:04:28 INFO - Running setup.py install for mozlog 08:04:29 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:04:29 INFO - Running setup.py install for moznetwork 08:04:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:04:29 INFO - Running setup.py install for mozprocess 08:04:29 INFO - Running setup.py install for mozprofile 08:04:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:04:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:04:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:04:29 INFO - Running setup.py install for mozrunner 08:04:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:04:29 INFO - Running setup.py install for mozscreenshot 08:04:29 INFO - Running setup.py install for moztest 08:04:30 INFO - Running setup.py install for mozversion 08:04:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:04:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:04:30 INFO - Cleaning up... 08:04:30 INFO - Return code: 0 08:04:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:04:30 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')]} 08:04:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:04:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:04:30 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')]} 08:04:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:04:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:04:30 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 0x1020abb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1020ae768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff7f85918d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e89d30>, '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 0x101db0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101e7ebe8>, '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.Tg4sZnkiHX/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.MQUGY54SQy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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 08:04:30 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 08:04:30 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 08:04:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Tg4sZnkiHX/Render', 08:04:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:04:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:04:30 INFO - 'HOME': '/Users/cltbld', 08:04:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:04:30 INFO - 'LOGNAME': 'cltbld', 08:04:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:04:30 INFO - 'MOZ_NO_REMOTE': '1', 08:04:30 INFO - 'NO_EM_RESTART': '1', 08:04:30 INFO - 'PAGER': '/bin/cat', 08:04:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:04:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:04:30 INFO - 'PWD': '/builds/slave/test', 08:04:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:04:30 INFO - 'SHELL': '/bin/bash', 08:04:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MQUGY54SQy/Listeners', 08:04:30 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 08:04:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:04:30 INFO - 'USER': 'cltbld', 08:04:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:04:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:04:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:04:30 INFO - 'XPC_FLAGS': '0x0', 08:04:30 INFO - 'XPC_SERVICE_NAME': '0', 08:04:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:04:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:04:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:04:30 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-c_0SO5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:04:30 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)) 08:04:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:04:30 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-CmJOh4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:04:30 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)) 08:04:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:04:30 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-FrFFUk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:04:30 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)) 08:04:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:04:30 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-kZEV1i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:04:30 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)) 08:04:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:04:30 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-wHAxTa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:04:31 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)) 08:04:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:04:31 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-JU1tsl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:04:31 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)) 08:04:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:04:31 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-EGOeDl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:04:31 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)) 08:04:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:04:31 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-YJnQXB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:04:31 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)) 08:04:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:04:31 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-RbPaOa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:04:31 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)) 08:04:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:04:31 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-bCDvfj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:04:31 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)) 08:04:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:04:31 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-OycYdb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:04:31 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)) 08:04:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:04:31 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-aZ0P6B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:04:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 08:04:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:04:31 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-5KS1IR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:04:31 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)) 08:04:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:04:31 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-KtB0aD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:04:32 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)) 08:04:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:04:32 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-oVxKnF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:04:32 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)) 08:04:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:04:32 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-jv6sdu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:04:32 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)) 08:04:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:04:32 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-TCgSi5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:04:32 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)) 08:04:32 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)) 08:04:32 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)) 08:04:32 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)) 08:04:32 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)) 08:04:32 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)) 08:04:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:04:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:04:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:04:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:04:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:04:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:04:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:04:34 INFO - Downloading blessings-1.6.tar.gz 08:04:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:04:35 INFO - Installing collected packages: blessings 08:04:35 INFO - Running setup.py install for blessings 08:04:35 INFO - Successfully installed blessings 08:04:35 INFO - Cleaning up... 08:04:35 INFO - Return code: 0 08:04:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:04:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:04:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:04:35 INFO - Reading from file tmpfile_stdout 08:04:35 INFO - Current package versions: 08:04:35 INFO - blessings == 1.6 08:04:35 INFO - blobuploader == 1.2.4 08:04:35 INFO - docopt == 0.6.1 08:04:35 INFO - functools32 == 3.2.3-2 08:04:35 INFO - jsonschema == 2.5.1 08:04:35 INFO - manifestparser == 1.1 08:04:35 INFO - mozInstall == 1.12 08:04:35 INFO - mozcrash == 1.0 08:04:35 INFO - mozdebug == 0.1 08:04:35 INFO - mozdevice == 0.49 08:04:35 INFO - mozfile == 1.2 08:04:35 INFO - mozhttpd == 0.7 08:04:35 INFO - mozinfo == 0.9 08:04:35 INFO - mozleak == 0.1 08:04:35 INFO - mozlog == 3.4 08:04:35 INFO - moznetwork == 0.27 08:04:35 INFO - mozprocess == 0.24 08:04:35 INFO - mozprofile == 0.28 08:04:35 INFO - mozrunner == 6.13 08:04:35 INFO - mozscreenshot == 0.1 08:04:35 INFO - mozsystemmonitor == 0.3 08:04:35 INFO - moztest == 0.8 08:04:35 INFO - mozversion == 1.4 08:04:35 INFO - psutil == 3.1.1 08:04:35 INFO - requests == 1.2.3 08:04:35 INFO - wsgiref == 0.1.2 08:04:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:04:35 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')]} 08:04:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:04:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:04:35 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')]} 08:04:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:04:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:04:36 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 0x1020abb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1020ae768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff7f85918d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e89d30>, '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 0x101db0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101e7ebe8>, '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.Tg4sZnkiHX/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.MQUGY54SQy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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 08:04:36 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 08:04:36 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 08:04:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Tg4sZnkiHX/Render', 08:04:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:04:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:04:36 INFO - 'HOME': '/Users/cltbld', 08:04:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:04:36 INFO - 'LOGNAME': 'cltbld', 08:04:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:04:36 INFO - 'MOZ_NO_REMOTE': '1', 08:04:36 INFO - 'NO_EM_RESTART': '1', 08:04:36 INFO - 'PAGER': '/bin/cat', 08:04:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:04:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:04:36 INFO - 'PWD': '/builds/slave/test', 08:04:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:04:36 INFO - 'SHELL': '/bin/bash', 08:04:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MQUGY54SQy/Listeners', 08:04:36 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 08:04:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:04:36 INFO - 'USER': 'cltbld', 08:04:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:04:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:04:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:04:36 INFO - 'XPC_FLAGS': '0x0', 08:04:36 INFO - 'XPC_SERVICE_NAME': '0', 08:04:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:04:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:04:36 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)) 08:04:36 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)) 08:04:36 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)) 08:04:36 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)) 08:04:36 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)) 08:04:36 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)) 08:04:36 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)) 08:04:36 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)) 08:04:36 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)) 08:04:36 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)) 08:04:36 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)) 08:04:36 INFO - Cleaning up... 08:04:36 INFO - Return code: 0 08:04:36 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:04:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:04:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:04:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:04:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:04:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:04:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:04:36 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 0x1020abb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1020ae768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff7f85918d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101e89d30>, '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 0x101db0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101e7ebe8>, '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.Tg4sZnkiHX/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.MQUGY54SQy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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 08:04:36 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 08:04:36 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 08:04:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Tg4sZnkiHX/Render', 08:04:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:04:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:04:36 INFO - 'HOME': '/Users/cltbld', 08:04:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:04:36 INFO - 'LOGNAME': 'cltbld', 08:04:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:04:36 INFO - 'MOZ_NO_REMOTE': '1', 08:04:36 INFO - 'NO_EM_RESTART': '1', 08:04:36 INFO - 'PAGER': '/bin/cat', 08:04:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:04:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:04:36 INFO - 'PWD': '/builds/slave/test', 08:04:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:04:36 INFO - 'SHELL': '/bin/bash', 08:04:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MQUGY54SQy/Listeners', 08:04:36 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 08:04:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:04:36 INFO - 'USER': 'cltbld', 08:04:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:04:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:04:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:04:36 INFO - 'XPC_FLAGS': '0x0', 08:04:36 INFO - 'XPC_SERVICE_NAME': '0', 08:04:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:04:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:04:37 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:04:37 INFO - Cleaning up... 08:04:37 INFO - Return code: 0 08:04:37 INFO - Running post-action listener: _resource_record_post_action 08:04:37 INFO - Running post-action listener: _start_resource_monitoring 08:04:37 INFO - Starting resource monitoring. 08:04:37 INFO - [mozharness: 2017-01-30 16:04:37.225961Z] Finished create-virtualenv step (success) 08:04:37 INFO - [mozharness: 2017-01-30 16:04:37.226818Z] Running install step. 08:04:37 INFO - Running pre-action listener: _resource_record_pre_action 08:04:37 INFO - Running main action method: install 08:04:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:04:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:04:37 INFO - Reading from file tmpfile_stdout 08:04:37 INFO - Detecting whether we're running mozinstall >=1.0... 08:04:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:04:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:04:37 INFO - Reading from file tmpfile_stdout 08:04:37 INFO - Output received: 08:04:37 INFO - Usage: mozinstall [options] installer 08:04:37 INFO - Options: 08:04:37 INFO - -h, --help show this help message and exit 08:04:37 INFO - -d DEST, --destination=DEST 08:04:37 INFO - Directory to install application into. [default: 08:04:37 INFO - "/builds/slave/test"] 08:04:37 INFO - --app=APP Application being installed. [default: firefox] 08:04:37 INFO - mkdir: /builds/slave/test/build/application 08:04:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:04:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 08:04:57 INFO - Reading from file tmpfile_stdout 08:04:57 INFO - Output received: 08:04:57 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 08:04:57 INFO - Running post-action listener: _resource_record_post_action 08:04:57 INFO - [mozharness: 2017-01-30 16:04:57.557560Z] Finished install step (success) 08:04:57 INFO - [mozharness: 2017-01-30 16:04:57.557709Z] Running run-tests step. 08:04:57 INFO - Running pre-action listener: _resource_record_pre_action 08:04:57 INFO - Running main action method: run_tests 08:04:57 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:04:57 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:04:57 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:04:57 INFO - 2017-01-30 08:04:57.572 screenresolution[1623:7345] starting screenresolution argv=screenresolution get 08:04:57 INFO - 2017-01-30 08:04:57.574 screenresolution[1623:7345] Display 0: 1600x1200x32@60 08:04:57 INFO - 2017-01-30 08:04:57.580 screenresolution[1624:7348] starting screenresolution argv=screenresolution list 08:04:57 INFO - Available Modes on Display 0 08:04:57 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:04:57 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:04:57 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:04:57 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:04:57 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:04:57 INFO - Intel Iris: 08:04:57 INFO - Chipset Model: Intel Iris 08:04:57 INFO - Type: GPU 08:04:57 INFO - Bus: Built-In 08:04:57 INFO - VRAM (Dynamic, Max): 1536 MB 08:04:57 INFO - Vendor: Intel (0x8086) 08:04:57 INFO - Device ID: 0x0a2e 08:04:57 INFO - Revision ID: 0x0009 08:04:57 INFO - Displays: 08:04:57 INFO - Display: 08:04:57 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:04:57 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:04:57 INFO - Display Serial Number: 1600x1200 60 08:04:57 INFO - Main Display: Yes 08:04:57 INFO - Mirror: Off 08:04:57 INFO - Online: Yes 08:04:57 INFO - Rotation: Supported 08:04:57 INFO - Return code: 0 08:04:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:04:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:04:57 INFO - Python 2.7.3 08:04:57 INFO - Return code: 0 08:04:57 INFO - grabbing minidump binary from tooltool 08:04:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:04:57 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 0x101e89d30>, '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 0x101db0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101e7ebe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:04:57 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 08:04:57 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 08:04:57 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 08:04:57 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:04:57 INFO - Return code: 0 08:04:57 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:04:57 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:04:57 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:04:57 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:04:57 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:04:57 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/dr7R0axpRbG8zI2bnKzKYw/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0114', '--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 08:04:57 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/dr7R0axpRbG8zI2bnKzKYw/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0114 --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 08:04:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Tg4sZnkiHX/Render', 08:04:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:04:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:04:57 INFO - 'HOME': '/Users/cltbld', 08:04:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:04:57 INFO - 'LOGNAME': 'cltbld', 08:04:57 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:04:57 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:04:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:04:57 INFO - 'MOZ_NO_REMOTE': '1', 08:04:57 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:04:57 INFO - 'NO_EM_RESTART': '1', 08:04:57 INFO - 'PAGER': '/bin/cat', 08:04:57 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:04:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:04:57 INFO - 'PWD': '/builds/slave/test', 08:04:57 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:04:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:04:57 INFO - 'SHELL': '/bin/bash', 08:04:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MQUGY54SQy/Listeners', 08:04:57 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 08:04:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:04:57 INFO - 'USER': 'cltbld', 08:04:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:04:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:04:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:04:57 INFO - 'XPC_FLAGS': '0x0', 08:04:57 INFO - 'XPC_SERVICE_NAME': '0', 08:04:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:04:57 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/dr7R0axpRbG8zI2bnKzKYw/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0114', '--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 08:04:58 INFO - mozversion application_buildid: 20170130063643 08:04:58 INFO - mozversion application_changeset: dacf10cb5b872db5f5b0ed87ed96014a2b61952c 08:04:58 INFO - mozversion application_display_name: FirefoxDeveloperEdition 08:04:58 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:04:58 INFO - mozversion application_name: Firefox 08:04:58 INFO - mozversion application_remotingname: firefox-dev 08:04:58 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:04:58 INFO - mozversion application_vendor: Mozilla 08:04:58 INFO - mozversion application_version: 53.0a2 08:04:58 INFO - mozversion platform_buildid: 20170130063643 08:04:58 INFO - mozversion platform_changeset: dacf10cb5b872db5f5b0ed87ed96014a2b61952c 08:04:58 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:04:58 INFO - mozversion platform_version: 53.0a2 08:04:58 INFO - using testdate: 1485792298 08:04:58 INFO - actual date: 1485792298 08:04:58 INFO - starting webserver on 'localhost:49241' 08:04:58 INFO - SUITE-START | Running 2 tests 08:04:58 INFO - TEST-START | dromaeo_css 08:04:58 INFO - operating with platform_type : mac_ 08:04:58 INFO - Initialising browser for dromaeo_css test... 08:04:58 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/tmpTeGK13/profile http://localhost:49241/getInfo.html) 08:05:01 INFO - PROCESS | 1634 | 2017-01-30 08:05:01.367 plugin-container[1636:7518] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c47, name = 'com.apple.tsm.portname' 08:05:01 INFO - PROCESS | 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:05:01 INFO - PROCESS | 1634 | 2017-01-30 08:05:01.371 plugin-container[1636:7518] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x950b, name = 'com.apple.CFPasteboardClient' 08:05:01 INFO - PROCESS | 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:05:01 INFO - PROCESS | 1634 | 2017-01-30 08:05:01.371 plugin-container[1636:7518] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:05:01 INFO - PROCESS | 1634 | __metrics Screen width/height:1600/1200 08:05:01 INFO - PROCESS | 1634 | colorDepth:24 08:05:01 INFO - PROCESS | 1634 | Browser inner width/height: 1024/689 08:05:01 INFO - PROCESS | 1634 | __metrics 08:05:01 ERROR - PROCESS | 1634 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3280: TypeError: this.tabs is undefined 08:05:02 INFO - TEST-INFO | 1634: exit 0 08:05:02 INFO - Browser initialized. 08:05:02 INFO - Running cycle 1/1 for dromaeo_css test... 08:05:02 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/tmpTeGK13/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:05:04 INFO - PROCESS | 1637 | 2017-01-30 08:05:04.721 plugin-container[1638:7654] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 08:05:04 INFO - PROCESS | 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:05:04 INFO - PROCESS | 1637 | 2017-01-30 08:05:04.721 plugin-container[1638:7654] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f17, name = 'com.apple.CFPasteboardClient' 08:05:04 INFO - PROCESS | 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:05:04 INFO - PROCESS | 1637 | 2017-01-30 08:05:04.721 plugin-container[1638:7654] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:05:14 INFO - PROCESS | 1637 | RSS: Main: 218177536 08:05:14 INFO - PROCESS | 1637 | 08:08:16 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 08:08:18 INFO - PROCESS | 1637 | RSS: Main: 239747072 08:08:18 INFO - PROCESS | 1637 | 08:10:02 INFO - PROCESS | 1637 | ************************* 08:10:02 INFO - PROCESS | 1637 | A coding exception was thrown and uncaught in a Task. 08:10:02 INFO - PROCESS | 1637 | 08:10:02 ERROR - PROCESS | 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 08:10:02 INFO - PROCESS | 1637 | Full stack: 08:10:02 INFO - PROCESS | 1637 | ************************* 08:11:20 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 08:11:21 INFO - PROCESS | 1637 | RSS: Main: 248475648 08:11:21 INFO - PROCESS | 1637 | 08:14:23 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 08:14:23 INFO - PROCESS | 1637 | RSS: Main: 255803392 08:14:23 INFO - PROCESS | 1637 | 08:17:25 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 08:17:26 INFO - PROCESS | 1637 | RSS: Main: 252231680 08:17:26 INFO - PROCESS | 1637 | 08:20:29 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 08:20:29 INFO - PROCESS | 1637 | RSS: Main: 256577536 08:20:29 INFO - PROCESS | 1637 | 08:23:32 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 08:23:32 INFO - PROCESS | 1637 | RSS: Main: 257851392 08:23:32 INFO - PROCESS | 1637 | 08:23:32 INFO - PROCESS | 1637 | __start_tp_report 08:23:32 INFO - PROCESS | 1637 | _x_x_mozilla_page_load 08:23:32 INFO - PROCESS | 1637 | _x_x_mozilla_page_load_details 08:23:32 INFO - PROCESS | 1637 | |i|pagename|runs| 08:23:32 INFO - PROCESS | 1637 | |0;dojo.html;3403.200247941734;3417.5044618530296;3437.4500079987206;3448.6896179343885;3462.792232466052;5785.537157027438;5817.505512031472;5819.82540523785;5829.125631155329;5863.58954873159;4463.933041004388;5119.948800511994;5851.180834683148;5888.1462187982825;5931.673757943322;4345.435093437856;4510.436195475566;4661.671423644262;5308.867278318035;5322.946770532303;5286.259923610698;5327.920081198766;5353.437889021655;5389.487998640123;5410.026195284847;5222.112240919015;5232.607554433394;5253.001929633358;5348.304720386344;5367.221752845854;5251.55361794248;5299.920501192485;5325.813596524142;5338.3060202173465;5347.919781203285;5158.3552055993005;5261.105612045962;5283.89038301957;5293.761780719877;5315.468453154692;4358.433403657505;5664.376918538957;5705.942940570582;5711.600187986843;5833.445822646841;6199.442050215457;6253.46845518127;6284.12022316876;6295.024271237966;6296.212973378328;6297.275813281485;6310.368963103699;6338.968305158444;6343.873122537523;6357.777477788255;5836.737346819404;6057.394260573951;6074.149619053336;6105.145279660851;6108.877822443526;6146.446819786241;6330.050492426173;6332.620042797447;6381.680915954184;6474.708638111297;6245.718942647501;6272.184615999982;6280.214973128359;6291.748330066746;6320.936790632127;6263;6339.0808332791485;6349.14286571307;6357.1735674362035;6360.77737279193;6188.350223226586;6272.435480806749;6274.592151510228;6283.717232724538;6314.589551679126;6306.779262725782;6318.778842740482;6319.747210111544;6324.620522768649;6333.714982825784;4575.9084818303445;5040.697558146449;5100.56645185156;5110.48895110486;5131.820386286537;4434.13534360803;4474.284114541658;4476.37330773692;4478.529754375809;4597.333386658983;5916.2013128228;5919.348871624118;5945;5964.314103878023;5990.700464976734;5731.8567035824435;6294.496440284766;6310.7475700971445;6318.210223722035;6328.936710632926;5791.276090488689;5923.851903702442;5966.403359663999;6018.879622407528;6057.212562366864;5554.944450555443;5567.749451274703;5568.303962004749;5571.359293681278;5580.079286917694;6483.870322593521;6494.1232933553365;6499.025146228104;6517.478601711947;6518.999999999905;5207.453217412117;6223.066540019034;6240.282367527792;6256.124142620128;6276.152719382825;6425.61446313204;6432.678366081771;6437.871242575216;6452.22573291208;6466.579672321284;6298.559100862895;6307.716152773044;6329.145565348619;6329.873402531832;6340.461060809883;5166.199239117943;6168.228971378578;6229.1590635265475;6274.058891166362;6307.7476900925285;6152.046432802923;6250.062490626442;6268.937310626835;6315.400036996478;6322.936770632235;5376.14169952256;6076.210092688099;6255.96872015637;6280.340564240689;6289.40250676185;5427.185922111715;5624.184493248538;5659.85850353728;5883.470487656132;6167.599106058092;4548.727076375428;6131.3460828618745;6159.414855588712;6248.343923887926;6265.780697675559;6019.0369540875;6117.1741814856205;6129.785457508966;6133.110698948717;6162.969185154046 08:23:32 INFO - PROCESS | 1637 | |1;ext.html;471.51669538722814;488.7092180152808;500.9198528235483;504.6744849572026;535.8312131678518;30182.396352072974;41395.58604413955;41516.58483415165;41628.37557436637;41701.58298417015;42164.57835421645;42462.93842653935;42494.57505424945;42734.14531709364;42804.57195428045;8581.442206256574;8748.212660860521;9283.95358023209;9509.191718703918;9543.522823858813;11195.2723073;11496.885031149664;11518.424078796068;11614.94192529036;11675.182737208357;11411.429428528581;11433.771324573503;11456.198066135374;11461.942690286578;11491;11423.143264255132;11426.5429382825;11477.368744719037;11515.942420287845;11517.193796434252;9662.275329350327;11379.374134422604;11440.313581185072;11449.713757156054;11462.254953428;41128.794356028026;41434.79282603568;41638.375424368656;41639;41755.791221044004;4005.499312585927;4071.2875246831686;4073.7555746655;4124.773137477438;4229.767362795045;29898.551521726975;30025.399492010256;30794.152350858796;30998.225044373845;31208.063758087068;3245.350929814023;3327.2014716468116;3327.916802079943;3329.417351963397;3401.4557670772806;351245;426538.8673056646;427370;429194;430290;66382.66808665925;66607.66696166489;67041.66479167574;67083.32916670869;67410;55485.44514554803;68365.65817170883;68374;68801.65599171972;69393.26516837119;16784.328626855033;17378.739318910226;17423.041732704824;17424.47726568205;17426.867253628472;11562.653120406401;11973.40132993347;12987.805182922264;13100.410481528354;13198.802017969738;18728.531786705167;19074.2370305189;19167.616647666968;19208;19216.423507294803;5714.857128571819;7452.925470745332;7552.546847189186;7580.810479738051;7607.467477276648;794.7814351053506;882.1795729971188;884.2616415293173;890.5814267294361;893.7363477774072;23417.648735268984;27407.725922740916;30997.605107770203;31196.220094497818;31380.686193137775;957.3394357893031;1014.9748753758756;1028.573142146016;1079.1690398393193;1084.4957094950885;14803;14851.2574371281;14973.550793476214;14992.025518341274;15000.174990375634;631361.3727725511;635351;635911;636777;637554;45011.54988450139;54207.72896135573;54315.72842135843;54625.72687136539;54742.63143421446;1437.7771445425976;1454.8181477315336;1480.2598700649676;1480.3190532355022;1485.0940926035325;26555.07057252987;28666.71333286724;28795.856020719762;28939.710602894545;29004.854975724986;47685.76157119192;47803;48176.5182348172;48380;51033.72415689489;527130;528149;529225;529343.3532832311;530117;24982.500349992533;25356;25932.8703356482;25981.350466237738;26780.866095669397;42359.7882010588;42418.363724544775;42524.14951700887;42682.57317426786;43342.13315733731;30399.088027359216;35659;36322.455163172046;36826;36859.63140368669;32146;36445.635543644225;36452.63547364492;37103.8144809285;37133.25733485369;36034;36078;36213.27573448463;36347.09132271714;36737;30589.847050765493;31944.68055319417;32359.838200808845;32491.67508324981;32495.350092997534;23957.28128156158;24327.37488501646;24503.264902052968;24515.264542063767;24518.264452066465 08:23:32 INFO - PROCESS | 1637 | |2;jquery.html;389.99220015599707;391.108273137247;398.7866491427087;404.37725902110753;418.04355615718424;17968.37110701126;20542.486437839063;20847.27034553791;21419.35741927743;21646.134154633834;21152.682709759327;21268.723876567416;21734.78265217351;22345.553088938253;22468.32595022152;5690.63010904291;5948.40515948406;5958.195643588104;5971.880562388749;5988.101784732282;5705.372409035002;5822.417758224165;5870.647761134345;5876.588638795286;6013.75944962201;6064.727087281083;6075.574709770318;6088.238970128734;6099.054646529795;6109.5723299369065;5568.888622227573;5904.61619994699;6042.365551617083;6517.902231466532;6564.146660934048;5843.328017277982;5849.88300233993;5873.177755114288;5892.882142357129;5893.79371721992;12271.509139634492;13291.335433228396;22407.887960560256;25031.62452563213;26211.344716382053;2218.3788539208886;2724.5776904579625;2742.6708794944734;2756.738109879578;2892.956605650917;10502.78994420115;10724.195685323632;14637;15310.693786124215;15352;1864.3288416170167;1872.241742094454;1873.8594605404649;1884.9340273090377;1898.1932678611536;132102;135035.999999999;136037.3198134013;140001.2999935004;141139;11764.470598822902;11900.345480998629;11980.161388702707;12067.57763478274;12505.124641275024;11869.643910682693;13350.132241404277;13822.447102116004;16112.194390280674;16149.192540372935;8183.263506284344;8477.897873276435;8717.171868672465;9099.772505687411;9110.316726245557;7205.711771529185;8562.357823163287;8872.911270887338;8931.419457735226;9013.63945442188;4835.854924352275;4894.486078961729;4898.387701537308;4948.208286674115;5005.674631149037;1785.3751187084417;2033.8779673219653;2083.5728675621463;2089.3389755144694;2095.1514636572215;990.6879333010079;991.1723710701501;998.0917365197636;1012.2104758288547;1053.3206082077018;21670.34988950334;21940.561188776133;21957.902104894692;22218.55562888733;22349.888250558644;991.965281215154;994.8806143262855;1006.8540061691014;1012.964546240878;1024.8257796174669;11101.056410205061;11461.083113350913;11721.88278117225;12656.746865062647;12678.556250531186;442776;703134;747552;748863;768057.1597141979;144306;145607;146563;155255;159640;1044.38903241603;1052.315994603514;1092.7377429416883;1094.8959848814509;1113.1929351220301;19245.518862028563;20291.59416811685;20535.794642053985;20836.687449687965;21085.472863178544;59049;59610;59716;60088;60378.69810650919;68805.65597171983;83620;84158;84416;85285;8325.209105135002;8637.69768058115;8661.090585488684;8668.219860212612;8711.956440217758;9402.482863442576;13036.283004434468;13638.590842274747;13652.795208072086;13656;11429.771404571695;11466.656000320003;11545.769084618092;11810.114241432098;11917.284962902253;9671;11145.609903653332;11473.254238474807;11559.595414160463;11769.47037383337;11133.443327833738;11541.769164616493;11827;11849.940750296193;12046.277223366626;7437.181909989788;7974.800629984296;8357.540335281617;8880.245179159843;8906.465612063297;11143.498542565767;11170.609028683957;11336.6032188873;11539.076873849905;11741.76516469683 08:23:32 INFO - PROCESS | 1637 | |3;mootools.html;1439.7984282200487;1441.4810668159466;1466.6699992501688;1467.9706405871882;1476.8596983286586;7765.184655611164;7986.560739159344;8208.261256486914;8652.351073669477;8657.567121643908;8103.959480202606;8396.07643159252;8402.873956890651;8492.830143397128;8495.95752021239;2139.684094282013;2182.9235976740815;2354.5761762882676;2356.1164563288767;2363.2201373546773;2352.1532248390567;2370.5969985102574;2373.6083546214854;2389.5220955808823;2389.928302150938;1948.83434908033;2313.0747700919683;2319.895604697793;2342.0397636968846;2343.4375749820006;2298.195631528968;2304.974286442534;2308.68832707584;2331.94170145746;2335.0192918973985;2300.0339857259823;2307.411610039429;2329.2429960262984;2347.178487529351;2352.4001379648253;8472.618732157007;8501.82996340076;8507.914920850773;8513.702020429255;8545.359098067607;5058.2412638104215;5193.974030129825;5208.088584497736;5228.660137091077;5231.241469986869;2968.4805159097073;3000.339925216471;3309.966900331014;3513.350030244413;3561.0919215600115;1199.7180662544295;1212.4665147335234;1243.5958313548042;1248.1013670157472;1268.0806415348798;10737;11019.449027548591;11033.337999720043;11035.779284414348;11038.392888391136;4459.843905463358;4664.370310008173;4738.8341408050555;4739.952600473951;4741.288806679026;9583.664571739955;9604.51977401127;9620.230381569598;9621.470819105016;9647.807043859224;5258.8159414420315;5293.523582877559;5295.311609490742;5301.390340110859;5308.362996440452;4071.6131967463043;4715.858524244278;4769.833055843029;4785.832495862628;4803.279508073817;3892.0464486201063;3895.9220815584094;3972.0864201233876;3990.1221731219084;3990.86031988879;1271.7520083583613;1290.5031562848183;1298.6104168749337;1304.8956083513297;1305.9030414451715;795.6419611174995;799.1648727080216;808.7614153824636;816.5794615772882;818.7912082419064;5607.607467477319;5625.184348269478;5627.915581266284;5657.830265092054;5845.415458454206;580.1674596953385;584.4681339980597;592.5792687192055;593.4836692077834;601.6841158391879;3524.2951409718157;3873.3028054949814;3886.203328317688;3955.980220098881;3990.880273591797;9805.068518490874;10187.592496300213;10195.439250841127;10200.6429774959;10210.285280030326;10084.546195421224;10126.493675316353;10127.088562029305;10135.695929122137;10170.593176272867;7650.081990161217;7759.534427934229;7916.168802275678;7946.9999999998845;7957.204279572113;5262.158054711381;5507.972460137674;5586.022446072003;5658.264425624642;5700.828975130753;8140.389470789596;9131;9216.078392160998;9251.982281948845;9309.069093090637;8452.915470845213;8879.28965682731;9204.125608067223;9542.761430964281;9559.952200238955;6680.832979175365;6929.203141638776;7228.421726261991;7314.4514161439165;7625.199354067743;7842.843143137219;7980.281774640311;8066.072401673882;8084.717034903751;8100.392470564613;5218.8173413931845;7075.610841403566;7256.7822965311125;7256.854862902818;7259.564426134449;6989.790306290819;7008.748501713377;7088.681009354695;7129.429645628234;7143.607101609461;5408;6518.967405162944;6572.8356791080605;6583.111279977142;6596.076549283201;5102.438731739431;5121.436641969455;5155.149400348901;5171;5171.586273098218;4373.584509471595;5073.720945348041;5100.132977393778;5108.361454818148;5208.557272631869 08:23:32 INFO - PROCESS | 1637 | |4;prototype.html;316.7861693356984;376.48609647830705;378.26616364253346;379.26612005768857;383.02711113771005;4532.892205124621;4643.071385722852;5012.067940254946;5232.8749318896425;5341.946580534199;4649.070635686195;5583.944160558393;5602.747876345564;5608.327000759904;5614.9438505614935;3707.8887633370905;3906.7498400511777;3949.8025098745093;4284.228838809013;4332.328489084197;4358.2808836541935;4363.716358436692;4373.453318335208;4380.824767009316;4391.5608439155985;4316.460442444694;4373.759443230621;4412.8455504057365;4427.225235583776;4430.136123455927;3635.563732352108;3683.2081102562943;4226.350305946247;4281.7002809803325;4361.890952726176;3838.4626152338697;3842.8847134585726;3847.7306588538813;3851.0564911596557;3852.383618621035;4357.716748411343;5437;5477.233187353773;5479.890402191973;5488.396276409591;4499.907542992524;4936.950630493685;4998.750062496861;5064.898702025939;5080.720560369178;5030.899382012376;5949.345571987078;5956.55325850563;5986.221791167164;6018.247719035121;3989.601039896016;4283.807228674717;4287.721298115613;4291.069018941457;4400.185965596375;5282.3925248596515;5902.557308201902;6111.052786818094;6156.7537298508005;6216.756648670284;3377.088186189715;3618.1366596202975;3944.9802750986637;4044.7775372354804;4058.173821780211;3833.5974722654278;3942.64516193544;4111.712180147361;4122.546519882811;4141.689373297015;4399.494058183286;4621.353010578522;4834.984653222863;4865.859022030366;4874.731889746027;5242.9213561796605;5796.594238403356;5856.355800861902;5980.641161530322;5987.43119403656;1175.8530183727034;1245.3461932485336;1333.3933060457468;1345.2601069411785;1349.3590544491287;854.6196942360714;891.38494438837;897.6319708919311;907.111031189438;910.7085732565648;4658.906821863544;4752.405949256343;5191.870203244949;5362.785488580413;5429.778299882495;5114.974425127925;5452.454754524516;5883.323417807007;5950.583459157818;5978.206538038658;4798.568128868418;4972.303877457159;5131;5264;5309.33633295838;3024.5765592817024;3052.8778848845795;3072.741534971794;3080.738137258358;3114.5483904833673;5341;5708.71456427177;5804.680742559115;6123.969380153071;6135.588814572673;5197.688138711689;5261.23748544242;5552.750126244248;5679.744411501492;5968.46283834457;5278.287431196739;5388.8113916013535;6190.845228869224;6352.714127864349;6377.8519866424485;5827.067669172912;5881.853123609766;6058.030715085742;6077.787277445268;6154.599951003171;4747.62019038483;5349.063913814989;5982.820515384546;5995.130706047686;6028.367021462684;5304.787808487772;6267.9373206267355;6337.6446902367425;6339.651319177489;6359.936400635935;3705.9629403705617;3798.35427977239;4033.334499807499;4047.777372244555;4082.8162732677697;4885.316055752269;5676.971615141898;6026.197818014644;6057.818265452043;6101.328853826164;5204.947950520446;5363.597730170331;5754.2597311747695;5893.410658934244;5919.526437884872;4902.975485122552;5158.1372338351075;5270.262163297218;5396.784128634968;5902.675352855634;5721.456461636138;5802.47777700009;5811.593188476766;5811.658296479625;5841.912371314519;5897.3807750185615;5937.643741375531;5937.7921772737745;5947.405259474191;5977.027580898206;4196.769177695256;5688;5739.684317362585;5767.452092051249;5811.576163839886 08:23:32 INFO - PROCESS | 1637 | |5;yui.html;687.7352219395534;734.0640683129006;778.6807408962324;816.0696805641568;818.942674012819;14803.40786368544;15324;15733.921330393332;15762.92118539407;15844.1285729285;14244.21656808875;14373.281335933214;14382.352794124263;14399.712005759904;14402.49591264306;672.4989882537492;692.7090621938797;821.9917800821975;842.145222599061;853.189470003496;845.188618925832;848.3340577646549;849.6049337058266;855.422589751918;857.1642648417806;909.6497848328409;911.3210658059721;911.5260064766318;912.9269658427343;913.4975763330175;899.8695189197563;904.6607522179182;907.3421769217265;908.6910450446846;908.7092130518237;674.2650510943096;674.912261406019;676.0974099577071;677.8847595908708;678.2268214235775;14549.92725036368;14938;14964.02733822298;15466.458673946358;15727.213639318103;14243.572692819233;14556.199409032499;14583.635409114857;14672.486462973744;14674.486392976194;11519.36643484608;11951.462184201731;11978.820317695241;11982.580609678707;12382.5666101686;3130.6597671410045;3478.2000139967695;3661.3775658137915;3778.4332350147424;3787.6023017583307;89038.55480722555;93325.60011599831;96190;101216.49391753068;101786.98213017923;9770.853437198448;10016.697829282293;10042.548085336031;10667.67996960077;10851.78296434067;16684.74972875408;17992.550186245186;19940;20024.199032038556;20259.39221823318;7389.926100739031;7571.810704732426;7576.886346704804;7740.651670674833;7967.402444816662;4263.957360426418;4300.61294483498;4308.89227769302;4375.409319741794;4492.887677808016;3451.2062225688233;3527.876524321636;3548.5919119301093;3549.432090865449;3553.093961039918;652.8367908022994;655.8327626455224;662.0466528199378;671.7078071039114;679.1374953808734;660.7423104989058;668.5397736330351;676.3169199108837;679.0900193740412;690.4683393786756;9676.371035882645;10173.593056277814;10245.385276883411;10263.673677018423;10428.947855260827;604.5828378418877;620.4973971083436;661.7915356662636;699.192432740186;702.8418605813753;4298.548652391516;5043.823466178666;5147.562457191106;5169.896602067937;5173.22401639757;75162;79682.7291262621;100594;100991;102433.48783256036;84430.15569844224;86037.56981215179;87779.56110219409;87833.68249476263;88706;76018.61990690012;77159.61420192975;77362.22637773663;78340.60829695815;78516.21483785204;8347.624356904076;9783.0706082922;9900.722833748423;9969.950150249202;10013.74965625836;47315.05369892514;47329.52670473251;48613.27080093731;50317.496825032744;51108;67150.65698686097;67784.66107669627;68341;68609.65695171492;71738;12610.558630447891;12787.552435664707;12932.353382331034;13095.279759612931;13137.014723895938;14466.349014294592;14954.25228738537;15063.246837657854;15137;15231.314590843216;10778.892211078102;14135.929320353333;14183.858161418253;14647.121172729245;14660.486882959045;12433.37833108359;12651.177673451197;14058.507952221624;14325.641858953193;14377.792755685281;11554.826677600011;13234.272115033766;13572.389242483914;14171.929140354232;14210.786838197642;10972.451377431256;11662.825057624312;11738.823917641414;11939.283642981449;11968.46141923598;10990.780184396428;11123.202989509908;11260.493277802683;11265.887341126812;11273.210875238983 08:23:32 INFO - PROCESS | 1637 | __end_tp_report 08:23:32 INFO - PROCESS | 1637 | __start_cc_report 08:23:32 INFO - PROCESS | 1637 | _x_x_mozilla_cycle_collect,375 08:23:32 INFO - PROCESS | 1637 | __end_cc_report 08:23:32 INFO - PROCESS | 1637 | __startTimestamp1485793412566__endTimestamp 08:23:32 INFO - PROCESS | 1637 | 08:23:32 INFO - PROCESS | 1637 | ------- Summary: start ------- 08:23:32 INFO - PROCESS | 1637 | Number of tests: 6 08:23:32 INFO - PROCESS | 1637 | 08:23:32 INFO - PROCESS | 1637 | [#0] dojo.html Cycles:165 Average:5785.21 Median:6074.15 stddev:695.12 (11.4%) stddev-sans-first:671.66 08:23:32 INFO - PROCESS | 1637 | Values: 3403.2 3417.5 3437.5 3448.7 3462.8 5785.5 5817.5 5819.8 5829.1 5863.6 4463.9 5119.9 5851.2 5888.1 5931.7 4345.4 4510.4 4661.7 5308.9 5322.9 5286.3 5327.9 5353.4 5389.5 5410.0 5222.1 5232.6 5253.0 5348.3 5367.2 5251.6 5299.9 5325.8 5338.3 5347.9 5158.4 5261.1 5283.9 5293.8 5315.5 4358.4 5664.4 5705.9 5711.6 5833.4 6199.4 6253.5 6284.1 6295.0 6296.2 6297.3 6310.4 6339.0 6343.9 6357.8 5836.7 6057.4 6074.1 6105.1 6108.9 6146.4 6330.1 6332.6 6381.7 6474.7 6245.7 6272.2 6280.2 6291.7 6320.9 6263.0 6339.1 6349.1 6357.2 6360.8 6188.4 6272.4 6274.6 6283.7 6314.6 6306.8 6318.8 6319.7 6324.6 6333.7 4575.9 5040.7 5100.6 5110.5 5131.8 4434.1 4474.3 4476.4 4478.5 4597.3 5916.2 5919.3 5945.0 5964.3 5990.7 5731.9 6294.5 6310.7 6318.2 6328.9 5791.3 5923.9 5966.4 6018.9 6057.2 5554.9 5567.7 5568.3 5571.4 5580.1 6483.9 6494.1 6499.0 6517.5 6519.0 5207.5 6223.1 6240.3 6256.1 6276.2 6425.6 6432.7 6437.9 6452.2 6466.6 6298.6 6307.7 6329.1 6329.9 6340.5 5166.2 6168.2 6229.2 6274.1 6307.7 6152.0 6250.1 6268.9 6315.4 6322.9 5376.1 6076.2 6256.0 6280.3 6289.4 5427.2 5624.2 5659.9 5883.5 6167.6 4548.7 6131.3 6159.4 6248.3 6265.8 6019.0 6117.2 6129.8 6133.1 6163.0 08:23:32 INFO - PROCESS | 1637 | 08:23:32 INFO - PROCESS | 1637 | [#1] ext.html Cycles:180 Average:66433.99 Median:36267.87 stddev:142533.98 (393.0%) stddev-sans-first:142847.79 08:23:32 INFO - PROCESS | 1637 | Values: 471.5 488.7 500.9 504.7 535.8 30182.4 41395.6 41516.6 41628.4 41701.6 42164.6 42462.9 42494.6 42734.1 42804.6 8581.4 8748.2 9284.0 9509.2 9543.5 11195.3 11496.9 11518.4 11614.9 11675.2 11411.4 11433.8 11456.2 11461.9 11491.0 11423.1 11426.5 11477.4 11515.9 11517.2 9662.3 11379.4 11440.3 11449.7 11462.3 41128.8 41434.8 41638.4 41639.0 41755.8 4005.5 4071.3 4073.8 4124.8 4229.8 29898.6 30025.4 30794.2 30998.2 31208.1 3245.4 3327.2 3327.9 3329.4 3401.5 351245.0 426538.9 427370.0 429194.0 430290.0 66382.7 66607.7 67041.7 67083.3 67410.0 55485.4 68365.7 68374.0 68801.7 69393.3 16784.3 17378.7 17423.0 17424.5 17426.9 11562.7 11973.4 12987.8 13100.4 13198.8 18728.5 19074.2 19167.6 19208.0 19216.4 5714.9 7452.9 7552.5 7580.8 7607.5 794.8 882.2 884.3 890.6 893.7 23417.6 27407.7 30997.6 31196.2 31380.7 957.3 1015.0 1028.6 1079.2 1084.5 14803.0 14851.3 14973.6 14992.0 15000.2 631361.4 635351.0 635911.0 636777.0 637554.0 45011.5 54207.7 54315.7 54625.7 54742.6 1437.8 1454.8 1480.3 1480.3 1485.1 26555.1 28666.7 28795.9 28939.7 29004.9 47685.8 47803.0 48176.5 48380.0 51033.7 527130.0 528149.0 529225.0 529343.4 530117.0 24982.5 25356.0 25932.9 25981.4 26780.9 42359.8 42418.4 42524.1 42682.6 43342.1 30399.1 35659.0 36322.5 36826.0 36859.6 32146.0 36445.6 36452.6 37103.8 37133.3 36034.0 36078.0 36213.3 36347.1 36737.0 30589.8 31944.7 32359.8 32491.7 32495.4 23957.3 24327.4 24503.3 24515.3 24518.3 08:23:32 INFO - PROCESS | 1637 | 08:23:32 INFO - PROCESS | 1637 | [#2] jquery.html Cycles:180 Average:38994.17 Median:12088.14 stddev:115905.39 (958.8%) stddev-sans-first:116194.29 08:23:32 INFO - PROCESS | 1637 | Values: 390.0 391.1 398.8 404.4 418.0 17968.4 20542.5 20847.3 21419.4 21646.1 21152.7 21268.7 21734.8 22345.6 22468.3 5690.6 5948.4 5958.2 5971.9 5988.1 5705.4 5822.4 5870.6 5876.6 6013.8 6064.7 6075.6 6088.2 6099.1 6109.6 5568.9 5904.6 6042.4 6517.9 6564.1 5843.3 5849.9 5873.2 5892.9 5893.8 12271.5 13291.3 22407.9 25031.6 26211.3 2218.4 2724.6 2742.7 2756.7 2893.0 10502.8 10724.2 14637.0 15310.7 15352.0 1864.3 1872.2 1873.9 1884.9 1898.2 132102.0 135036.0 136037.3 140001.3 141139.0 11764.5 11900.3 11980.2 12067.6 12505.1 11869.6 13350.1 13822.4 16112.2 16149.2 8183.3 8477.9 8717.2 9099.8 9110.3 7205.7 8562.4 8872.9 8931.4 9013.6 4835.9 4894.5 4898.4 4948.2 5005.7 1785.4 2033.9 2083.6 2089.3 2095.2 990.7 991.2 998.1 1012.2 1053.3 21670.3 21940.6 21957.9 22218.6 22349.9 992.0 994.9 1006.9 1013.0 1024.8 11101.1 11461.1 11721.9 12656.7 12678.6 442776.0 703134.0 747552.0 748863.0 768057.2 144306.0 145607.0 146563.0 155255.0 159640.0 1044.4 1052.3 1092.7 1094.9 1113.2 19245.5 20291.6 20535.8 20836.7 21085.5 59049.0 59610.0 59716.0 60088.0 60378.7 68805.7 83620.0 84158.0 84416.0 85285.0 8325.2 8637.7 8661.1 8668.2 8712.0 9402.5 13036.3 13638.6 13652.8 13656.0 11429.8 11466.7 11545.8 11810.1 11917.3 9671.0 11145.6 11473.3 11559.6 11769.5 11133.4 11541.8 11827.0 11849.9 12046.3 7437.2 7974.8 8357.5 8880.2 8906.5 11143.5 11170.6 11336.6 11539.1 11741.8 08:23:32 INFO - PROCESS | 1637 | 08:23:32 INFO - PROCESS | 1637 | [#3] mootools.html Cycles:180 Average:5457.25 Median:5208.32 stddev:3002.37 (57.6%) stddev-sans-first:2995.61 08:23:32 INFO - PROCESS | 1637 | Values: 1439.8 1441.5 1466.7 1468.0 1476.9 7765.2 7986.6 8208.3 8652.4 8657.6 8104.0 8396.1 8402.9 8492.8 8496.0 2139.7 2182.9 2354.6 2356.1 2363.2 2352.2 2370.6 2373.6 2389.5 2389.9 1948.8 2313.1 2319.9 2342.0 2343.4 2298.2 2305.0 2308.7 2331.9 2335.0 2300.0 2307.4 2329.2 2347.2 2352.4 8472.6 8501.8 8507.9 8513.7 8545.4 5058.2 5194.0 5208.1 5228.7 5231.2 2968.5 3000.3 3310.0 3513.4 3561.1 1199.7 1212.5 1243.6 1248.1 1268.1 10737.0 11019.4 11033.3 11035.8 11038.4 4459.8 4664.4 4738.8 4740.0 4741.3 9583.7 9604.5 9620.2 9621.5 9647.8 5258.8 5293.5 5295.3 5301.4 5308.4 4071.6 4715.9 4769.8 4785.8 4803.3 3892.0 3895.9 3972.1 3990.1 3990.9 1271.8 1290.5 1298.6 1304.9 1305.9 795.6 799.2 808.8 816.6 818.8 5607.6 5625.2 5627.9 5657.8 5845.4 580.2 584.5 592.6 593.5 601.7 3524.3 3873.3 3886.2 3956.0 3990.9 9805.1 10187.6 10195.4 10200.6 10210.3 10084.5 10126.5 10127.1 10135.7 10170.6 7650.1 7759.5 7916.2 7947.0 7957.2 5262.2 5508.0 5586.0 5658.3 5700.8 8140.4 9131.0 9216.1 9252.0 9309.1 8452.9 8879.3 9204.1 9542.8 9560.0 6680.8 6929.2 7228.4 7314.5 7625.2 7842.8 7980.3 8066.1 8084.7 8100.4 5218.8 7075.6 7256.8 7256.9 7259.6 6989.8 7008.7 7088.7 7129.4 7143.6 5408.0 6519.0 6572.8 6583.1 6596.1 5102.4 5121.4 5155.1 5171.0 5171.6 4373.6 5073.7 5100.1 5108.4 5208.6 08:23:32 INFO - PROCESS | 1637 | 08:23:32 INFO - PROCESS | 1637 | [#4] prototype.html Cycles:180 Average:4717.11 Median:5237.90 stddev:1438.65 (27.5%) stddev-sans-first:1404.27 08:23:32 INFO - PROCESS | 1637 | Values: 316.8 376.5 378.3 379.3 383.0 4532.9 4643.1 5012.1 5232.9 5341.9 4649.1 5583.9 5602.7 5608.3 5614.9 3707.9 3906.7 3949.8 4284.2 4332.3 4358.3 4363.7 4373.5 4380.8 4391.6 4316.5 4373.8 4412.8 4427.2 4430.1 3635.6 3683.2 4226.4 4281.7 4361.9 3838.5 3842.9 3847.7 3851.1 3852.4 4357.7 5437.0 5477.2 5479.9 5488.4 4499.9 4937.0 4998.8 5064.9 5080.7 5030.9 5949.3 5956.6 5986.2 6018.2 3989.6 4283.8 4287.7 4291.1 4400.2 5282.4 5902.6 6111.1 6156.8 6216.8 3377.1 3618.1 3945.0 4044.8 4058.2 3833.6 3942.6 4111.7 4122.5 4141.7 4399.5 4621.4 4835.0 4865.9 4874.7 5242.9 5796.6 5856.4 5980.6 5987.4 1175.9 1245.3 1333.4 1345.3 1349.4 854.6 891.4 897.6 907.1 910.7 4658.9 4752.4 5191.9 5362.8 5429.8 5115.0 5452.5 5883.3 5950.6 5978.2 4798.6 4972.3 5131.0 5264.0 5309.3 3024.6 3052.9 3072.7 3080.7 3114.5 5341.0 5708.7 5804.7 6124.0 6135.6 5197.7 5261.2 5552.8 5679.7 5968.5 5278.3 5388.8 6190.8 6352.7 6377.9 5827.1 5881.9 6058.0 6077.8 6154.6 4747.6 5349.1 5982.8 5995.1 6028.4 5304.8 6267.9 6337.6 6339.7 6359.9 3706.0 3798.4 4033.3 4047.8 4082.8 4885.3 5677.0 6026.2 6057.8 6101.3 5204.9 5363.6 5754.3 5893.4 5919.5 4903.0 5158.1 5270.3 5396.8 5902.7 5721.5 5802.5 5811.6 5811.7 5841.9 5897.4 5937.6 5937.8 5947.4 5977.0 4196.8 5688.0 5739.7 5767.5 5811.6 08:23:32 INFO - PROCESS | 1637 | 08:23:32 INFO - PROCESS | 1637 | [#5] yui.html Cycles:180 Average:19804.17 Median:4299.58 stddev:27697.70 (644.2%) stddev-sans-first:27738.21 08:23:32 INFO - PROCESS | 1637 | Values: 687.7 734.1 778.7 816.1 818.9 14803.4 15324.0 15733.9 15762.9 15844.1 14244.2 14373.3 14382.4 14399.7 14402.5 672.5 692.7 822.0 842.1 853.2 845.2 848.3 849.6 855.4 857.2 909.6 911.3 911.5 912.9 913.5 899.9 904.7 907.3 908.7 908.7 674.3 674.9 676.1 677.9 678.2 14549.9 14938.0 14964.0 15466.5 15727.2 14243.6 14556.2 14583.6 14672.5 14674.5 11519.4 11951.5 11978.8 11982.6 12382.6 3130.7 3478.2 3661.4 3778.4 3787.6 89038.6 93325.6 96190.0 101216.5 101787.0 9770.9 10016.7 10042.5 10667.7 10851.8 16684.7 17992.6 19940.0 20024.2 20259.4 7389.9 7571.8 7576.9 7740.7 7967.4 4264.0 4300.6 4308.9 4375.4 4492.9 3451.2 3527.9 3548.6 3549.4 3553.1 652.8 655.8 662.0 671.7 679.1 660.7 668.5 676.3 679.1 690.5 9676.4 10173.6 10245.4 10263.7 10428.9 604.6 620.5 661.8 699.2 702.8 4298.5 5043.8 5147.6 5169.9 5173.2 75162.0 79682.7 100594.0 100991.0 102433.5 84430.2 86037.6 87779.6 87833.7 88706.0 76018.6 77159.6 77362.2 78340.6 78516.2 8347.6 9783.1 9900.7 9970.0 10013.7 47315.1 47329.5 48613.3 50317.5 51108.0 67150.7 67784.7 68341.0 68609.7 71738.0 12610.6 12787.6 12932.4 13095.3 13137.0 14466.3 14954.3 15063.2 15137.0 15231.3 10778.9 14135.9 14183.9 14647.1 14660.5 12433.4 12651.2 14058.5 14325.6 14377.8 11554.8 13234.3 13572.4 14171.9 14210.8 10972.5 11662.8 11738.8 11939.3 11968.5 10990.8 11123.2 11260.5 11265.9 11273.2 08:23:32 INFO - PROCESS | 1637 | -------- Summary: end -------- 08:23:32 INFO - PROCESS | 1637 | 08:23:33 INFO - TEST-INFO | 1637: exit 0 08:23:33 INFO - TEST-OK | dromaeo_css | took 1115073ms 08:23:33 INFO - TEST-START | kraken 08:23:33 INFO - operating with platform_type : mac_ 08:23:33 INFO - Initialising browser for kraken test... 08:23:33 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/tmpM9Ds5R/profile http://localhost:49241/getInfo.html) 08:23:35 INFO - PROCESS | 1648 | 2017-01-30 08:23:35.838 plugin-container[1649:9876] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 08:23:35 INFO - PROCESS | 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:23:35 INFO - PROCESS | 1648 | 2017-01-30 08:23:35.841 plugin-container[1649:9876] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.CFPasteboardClient' 08:23:35 INFO - PROCESS | 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:23:35 INFO - PROCESS | 1648 | 2017-01-30 08:23:35.841 plugin-container[1649:9876] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:23:35 INFO - PROCESS | 1648 | __metrics Screen width/height:1600/1200 08:23:35 INFO - PROCESS | 1648 | colorDepth:24 08:23:35 INFO - PROCESS | 1648 | Browser inner width/height: 1024/689 08:23:35 INFO - PROCESS | 1648 | __metrics 08:23:36 ERROR - PROCESS | 1648 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3280: TypeError: this.tabs is undefined 08:23:36 INFO - TEST-INFO | 1648: exit 0 08:23:36 INFO - Browser initialized. 08:23:36 INFO - Running cycle 1/1 for kraken test... 08:23:36 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/tmpM9Ds5R/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:23:39 INFO - PROCESS | 1650 | 2017-01-30 08:23:39.454 plugin-container[1651:10002] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x863b, name = 'com.apple.tsm.portname' 08:23:39 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:23:39 INFO - PROCESS | 1650 | 2017-01-30 08:23:39.454 plugin-container[1651:10002] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x460b, name = 'com.apple.CFPasteboardClient' 08:23:39 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:23:39 INFO - PROCESS | 1650 | 2017-01-30 08:23:39.454 plugin-container[1651:10002] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:23:48 INFO - PROCESS | 1650 | RSS: Main: 218361856 08:23:48 INFO - PROCESS | 1650 | 08:23:51 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 08:23:52 INFO - PROCESS | 1650 | RSS: Main: 232730624 08:23:52 INFO - PROCESS | 1650 | 08:23:55 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 08:23:55 INFO - PROCESS | 1650 | RSS: Main: 240549888 08:23:55 INFO - PROCESS | 1650 | 08:23:59 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 08:23:59 INFO - PROCESS | 1650 | RSS: Main: 244338688 08:23:59 INFO - PROCESS | 1650 | 08:24:01 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 08:24:02 INFO - PROCESS | 1650 | RSS: Main: 244772864 08:24:02 INFO - PROCESS | 1650 | 08:24:04 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:24:05 INFO - PROCESS | 1650 | RSS: Main: 244858880 08:24:05 INFO - PROCESS | 1650 | 08:24:07 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 08:24:08 INFO - PROCESS | 1650 | RSS: Main: 248885248 08:24:08 INFO - PROCESS | 1650 | 08:24:11 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 08:24:12 INFO - PROCESS | 1650 | RSS: Main: 249180160 08:24:12 INFO - PROCESS | 1650 | 08:24:15 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 08:24:15 INFO - PROCESS | 1650 | RSS: Main: 249716736 08:24:15 INFO - PROCESS | 1650 | 08:24:17 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:24:18 INFO - PROCESS | 1650 | RSS: Main: 248561664 08:24:18 INFO - PROCESS | 1650 | 08:24:20 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:24:20 INFO - PROCESS | 1650 | RSS: Main: 248623104 08:24:20 INFO - PROCESS | 1650 | 08:24:22 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:24:23 INFO - PROCESS | 1650 | RSS: Main: 248590336 08:24:23 INFO - PROCESS | 1650 | 08:24:26 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:24:27 INFO - PROCESS | 1650 | RSS: Main: 248659968 08:24:27 INFO - PROCESS | 1650 | 08:24:30 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:24:30 INFO - PROCESS | 1650 | RSS: Main: 249028608 08:24:30 INFO - PROCESS | 1650 | 08:24:32 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 08:24:32 INFO - PROCESS | 1650 | RSS: Main: 251031552 08:24:32 INFO - PROCESS | 1650 | 08:24:32 INFO - PROCESS | 1650 | __start_tp_report 08:24:32 INFO - PROCESS | 1650 | _x_x_mozilla_page_load 08:24:32 INFO - PROCESS | 1650 | _x_x_mozilla_page_load_details 08:24:32 INFO - PROCESS | 1650 | |i|pagename|runs| 08:24:32 INFO - PROCESS | 1650 | |0;ai-astar;115;121;119;119;120;120;120;119;119;120 08:24:32 INFO - PROCESS | 1650 | |1;audio-beat-detection;141;109;111;142;112;114;112;111;128;119 08:24:32 INFO - PROCESS | 1650 | |2;audio-dft;196;199;195;247;269;206;195;196;195;192 08:24:32 INFO - PROCESS | 1650 | |3;audio-fft;79;63;82;114;89;82;54;87;97;84 08:24:32 INFO - PROCESS | 1650 | |4;audio-oscillator;83;72;92;82;70;71;80;70;73;82 08:24:32 INFO - PROCESS | 1650 | |5;imaging-gaussian-blur;88;87;88;87;88;84;89;101;87;86 08:24:32 INFO - PROCESS | 1650 | |6;imaging-darkroom;112;99;103;113;132;99;100;120;171;118 08:24:32 INFO - PROCESS | 1650 | |7;imaging-desaturate;108;88;93;92;141;145;93;92;95;94 08:24:32 INFO - PROCESS | 1650 | |8;json-parse-financial;57;69;56;58;61;59;60;61;60;76 08:24:32 INFO - PROCESS | 1650 | |9;json-stringify-tinderbox;61;91;104;101;65;113;112;105;71;73 08:24:32 INFO - PROCESS | 1650 | |10;stanford-crypto-aes;93;82;86;81;97;71;101;74;84;97 08:24:32 INFO - PROCESS | 1650 | |11;stanford-crypto-ccm;140;141;142;138;143;139;162;167;199;151 08:24:32 INFO - PROCESS | 1650 | |12;stanford-crypto-pbkdf2;168;172;234;230;194;169;168;170;170;174 08:24:32 INFO - PROCESS | 1650 | |13;stanford-crypto-sha256-iterative;68;68;66;67;70;68;85;60;62;84 08:24:32 INFO - PROCESS | 1650 | __end_tp_report 08:24:32 INFO - PROCESS | 1650 | __start_cc_report 08:24:32 INFO - PROCESS | 1650 | _x_x_mozilla_cycle_collect,952 08:24:32 INFO - PROCESS | 1650 | __end_cc_report 08:24:32 INFO - PROCESS | 1650 | __startTimestamp1485793472859__endTimestamp 08:24:32 INFO - PROCESS | 1650 | 08:24:32 INFO - PROCESS | 1650 | ------- Summary: start ------- 08:24:32 INFO - PROCESS | 1650 | Number of tests: 14 08:24:32 INFO - PROCESS | 1650 | 08:24:32 INFO - PROCESS | 1650 | [#0] ai-astar Cycles:10 Average:119.20 Median:120.00 stddev:1.62 (1.3%) stddev-sans-first:0.71 08:24:32 INFO - PROCESS | 1650 | Values: 115.0 121.0 119.0 119.0 120.0 120.0 120.0 119.0 119.0 120.0 08:24:32 INFO - PROCESS | 1650 | 08:24:32 INFO - PROCESS | 1650 | [#1] audio-beat-detection Cycles:10 Average:119.90 Median:116.50 stddev:12.64 (10.8%) stddev-sans-first:10.85 08:24:32 INFO - PROCESS | 1650 | Values: 141.0 109.0 111.0 142.0 112.0 114.0 112.0 111.0 128.0 119.0 08:24:32 INFO - PROCESS | 1650 | 08:24:32 INFO - PROCESS | 1650 | [#2] audio-dft Cycles:10 Average:209.00 Median:197.50 stddev:26.60 (13.5%) stddev-sans-first:27.79 08:24:32 INFO - PROCESS | 1650 | Values: 196.0 199.0 195.0 247.0 269.0 206.0 195.0 196.0 195.0 192.0 08:24:32 INFO - PROCESS | 1650 | 08:24:32 INFO - PROCESS | 1650 | [#3] audio-fft Cycles:10 Average:83.10 Median:83.00 stddev:16.56 (20.0%) stddev-sans-first:17.50 08:24:32 INFO - PROCESS | 1650 | Values: 79.0 63.0 82.0 114.0 89.0 82.0 54.0 87.0 97.0 84.0 08:24:32 INFO - PROCESS | 1650 | 08:24:32 INFO - PROCESS | 1650 | [#4] audio-oscillator Cycles:10 Average:77.50 Median:81.00 stddev:7.40 (9.1%) stddev-sans-first:7.57 08:24:32 INFO - PROCESS | 1650 | Values: 83.0 72.0 92.0 82.0 70.0 71.0 80.0 70.0 73.0 82.0 08:24:32 INFO - PROCESS | 1650 | 08:24:32 INFO - PROCESS | 1650 | [#5] imaging-gaussian-blur Cycles:10 Average:88.50 Median:87.50 stddev:4.60 (5.3%) stddev-sans-first:4.88 08:24:32 INFO - PROCESS | 1650 | Values: 88.0 87.0 88.0 87.0 88.0 84.0 89.0 101.0 87.0 86.0 08:24:32 INFO - PROCESS | 1650 | 08:24:32 INFO - PROCESS | 1650 | [#6] imaging-darkroom Cycles:10 Average:116.70 Median:126.00 stddev:21.92 (17.4%) stddev-sans-first:23.18 08:24:32 INFO - PROCESS | 1650 | Values: 112.0 99.0 103.0 113.0 132.0 99.0 100.0 120.0 171.0 118.0 08:24:32 INFO - PROCESS | 1650 | 08:24:32 INFO - PROCESS | 1650 | [#7] imaging-desaturate Cycles:10 Average:104.10 Median:92.50 stddev:21.17 (22.9%) stddev-sans-first:22.41 08:24:32 INFO - PROCESS | 1650 | Values: 108.0 88.0 93.0 92.0 141.0 145.0 93.0 92.0 95.0 94.0 08:24:32 INFO - PROCESS | 1650 | 08:24:32 INFO - PROCESS | 1650 | [#8] json-parse-financial Cycles:10 Average:61.70 Median:60.50 stddev:6.15 (10.2%) stddev-sans-first:6.28 08:24:32 INFO - PROCESS | 1650 | Values: 57.0 69.0 56.0 58.0 61.0 59.0 60.0 61.0 60.0 76.0 08:24:32 INFO - PROCESS | 1650 | 08:24:32 INFO - PROCESS | 1650 | [#9] json-stringify-tinderbox Cycles:10 Average:89.60 Median:63.00 stddev:20.19 (32.1%) stddev-sans-first:18.58 08:24:32 INFO - PROCESS | 1650 | Values: 61.0 91.0 104.0 101.0 65.0 113.0 112.0 105.0 71.0 73.0 08:24:32 INFO - PROCESS | 1650 | 08:24:32 INFO - PROCESS | 1650 | [#10] stanford-crypto-aes Cycles:10 Average:86.60 Median:85.00 stddev:10.15 (11.9%) stddev-sans-first:10.49 08:24:32 INFO - PROCESS | 1650 | Values: 93.0 82.0 86.0 81.0 97.0 71.0 101.0 74.0 84.0 97.0 08:24:32 INFO - PROCESS | 1650 | 08:24:32 INFO - PROCESS | 1650 | [#11] stanford-crypto-ccm Cycles:10 Average:152.20 Median:147.00 stddev:19.28 (13.1%) stddev-sans-first:19.94 08:24:32 INFO - PROCESS | 1650 | Values: 140.0 141.0 142.0 138.0 143.0 139.0 162.0 167.0 199.0 151.0 08:24:32 INFO - PROCESS | 1650 | 08:24:32 INFO - PROCESS | 1650 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:184.90 Median:173.00 stddev:25.99 (15.0%) stddev-sans-first:26.84 08:24:32 INFO - PROCESS | 1650 | Values: 168.0 172.0 234.0 230.0 194.0 169.0 168.0 170.0 170.0 174.0 08:24:32 INFO - PROCESS | 1650 | 08:24:32 INFO - PROCESS | 1650 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.80 Median:68.00 stddev:8.31 (12.2%) stddev-sans-first:8.79 08:24:32 INFO - PROCESS | 1650 | Values: 68.0 68.0 66.0 67.0 70.0 68.0 85.0 60.0 62.0 84.0 08:24:32 INFO - PROCESS | 1650 | -------- Summary: end -------- 08:24:32 INFO - PROCESS | 1650 | 08:24:33 INFO - TEST-INFO | 1650: exit 0 08:24:33 INFO - TEST-OK | kraken | took 60700ms 08:24:33 INFO - SUITE-END | took 1175s 08:24:34 INFO - Completed test suite (00:19:35) 08:24:34 INFO - javascript benchmark 08:24:34 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7377.599975808125, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3403.200247941734, 3417.5044618530296, 3437.4500079987206, 3448.6896179343885, 3462.792232466052, 5785.537157027438, 5817.505512031472, 5819.82540523785, 5829.125631155329, 5863.58954873159, 4463.933041004388, 5119.948800511994, 5851.180834683148, 5888.1462187982825, 5931.673757943322, 4345.435093437856, 4510.436195475566, 4661.671423644262, 5308.867278318035, 5322.946770532303, 5286.259923610698, 5327.920081198766, 5353.437889021655, 5389.487998640123, 5410.026195284847, 5222.112240919015, 5232.607554433394, 5253.001929633358, 5348.304720386344, 5367.221752845854, 5251.55361794248, 5299.920501192485, 5325.813596524142, 5338.3060202173465, 5347.919781203285, 5158.3552055993005, 5261.105612045962, 5283.89038301957, 5293.761780719877, 5315.468453154692, 4358.433403657505, 5664.376918538957, 5705.942940570582, 5711.600187986843, 5833.445822646841, 6199.442050215457, 6253.46845518127, 6284.12022316876, 6295.024271237966, 6296.212973378328, 6297.275813281485, 6310.368963103699, 6338.968305158444, 6343.873122537523, 6357.777477788255, 5836.737346819404, 6057.394260573951, 6074.149619053336, 6105.145279660851, 6108.877822443526, 6146.446819786241, 6330.050492426173, 6332.620042797447, 6381.680915954184, 6474.708638111297, 6245.718942647501, 6272.184615999982, 6280.214973128359, 6291.748330066746, 6320.936790632127, 6263.0, 6339.0808332791485, 6349.14286571307, 6357.1735674362035, 6360.77737279193, 6188.350223226586, 6272.435480806749, 6274.592151510228, 6283.717232724538, 6314.589551679126, 6306.779262725782, 6318.778842740482, 6319.747210111544, 6324.620522768649, 6333.714982825784, 4575.9084818303445, 5040.697558146449, 5100.56645185156, 5110.48895110486, 5131.820386286537, 4434.13534360803, 4474.284114541658, 4476.37330773692, 4478.529754375809, 4597.333386658983, 5916.2013128228, 5919.348871624118, 5945.0, 5964.314103878023, 5990.700464976734, 5731.8567035824435, 6294.496440284766, 6310.7475700971445, 6318.210223722035, 6328.936710632926, 5791.276090488689, 5923.851903702442, 5966.403359663999, 6018.879622407528, 6057.212562366864, 5554.944450555443, 5567.749451274703, 5568.303962004749, 5571.359293681278, 5580.079286917694, 6483.870322593521, 6494.1232933553365, 6499.025146228104, 6517.478601711947, 6518.999999999905, 5207.453217412117, 6223.066540019034, 6240.282367527792, 6256.124142620128, 6276.152719382825, 6425.61446313204, 6432.678366081771, 6437.871242575216, 6452.22573291208, 6466.579672321284, 6298.559100862895, 6307.716152773044, 6329.145565348619, 6329.873402531832, 6340.461060809883, 5166.199239117943, 6168.228971378578, 6229.1590635265475, 6274.058891166362, 6307.7476900925285, 6152.046432802923, 6250.062490626442, 6268.937310626835, 6315.400036996478, 6322.936770632235, 5376.14169952256, 6076.210092688099, 6255.96872015637, 6280.340564240689, 6289.40250676185, 5427.185922111715, 5624.184493248538, 5659.85850353728, 5883.470487656132, 6167.599106058092, 4548.727076375428, 6131.3460828618745, 6159.414855588712, 6248.343923887926, 6265.780697675559, 6019.0369540875, 6117.1741814856205, 6129.785457508966, 6133.110698948717, 6162.969185154046], "value": 5742.419369959498, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [471.51669538722814, 488.7092180152808, 500.9198528235483, 504.6744849572026, 535.8312131678518, 30182.396352072974, 41395.58604413955, 41516.58483415165, 41628.37557436637, 41701.58298417015, 42164.57835421645, 42462.93842653935, 42494.57505424945, 42734.14531709364, 42804.57195428045, 8581.442206256574, 8748.212660860521, 9283.95358023209, 9509.191718703918, 9543.522823858813, 11195.2723073, 11496.885031149664, 11518.424078796068, 11614.94192529036, 11675.182737208357, 11411.429428528581, 11433.771324573503, 11456.198066135374, 11461.942690286578, 11491.0, 11423.143264255132, 11426.5429382825, 11477.368744719037, 11515.942420287845, 11517.193796434252, 9662.275329350327, 11379.374134422604, 11440.313581185072, 11449.713757156054, 11462.254953428, 41128.794356028026, 41434.79282603568, 41638.375424368656, 41639.0, 41755.791221044004, 4005.499312585927, 4071.2875246831686, 4073.7555746655, 4124.773137477438, 4229.767362795045, 29898.551521726975, 30025.399492010256, 30794.152350858796, 30998.225044373845, 31208.063758087068, 3245.350929814023, 3327.2014716468116, 3327.916802079943, 3329.417351963397, 3401.4557670772806, 351245.0, 426538.8673056646, 427370.0, 429194.0, 430290.0, 66382.66808665925, 66607.66696166489, 67041.66479167574, 67083.32916670869, 67410.0, 55485.44514554803, 68365.65817170883, 68374.0, 68801.65599171972, 69393.26516837119, 16784.328626855033, 17378.739318910226, 17423.041732704824, 17424.47726568205, 17426.867253628472, 11562.653120406401, 11973.40132993347, 12987.805182922264, 13100.410481528354, 13198.802017969738, 18728.531786705167, 19074.2370305189, 19167.616647666968, 19208.0, 19216.423507294803, 5714.857128571819, 7452.925470745332, 7552.546847189186, 7580.810479738051, 7607.467477276648, 794.7814351053506, 882.1795729971188, 884.2616415293173, 890.5814267294361, 893.7363477774072, 23417.648735268984, 27407.725922740916, 30997.605107770203, 31196.220094497818, 31380.686193137775, 957.3394357893031, 1014.9748753758756, 1028.573142146016, 1079.1690398393193, 1084.4957094950885, 14803.0, 14851.2574371281, 14973.550793476214, 14992.025518341274, 15000.174990375634, 631361.3727725511, 635351.0, 635911.0, 636777.0, 637554.0, 45011.54988450139, 54207.72896135573, 54315.72842135843, 54625.72687136539, 54742.63143421446, 1437.7771445425976, 1454.8181477315336, 1480.2598700649676, 1480.3190532355022, 1485.0940926035325, 26555.07057252987, 28666.71333286724, 28795.856020719762, 28939.710602894545, 29004.854975724986, 47685.76157119192, 47803.0, 48176.5182348172, 48380.0, 51033.72415689489, 527130.0, 528149.0, 529225.0, 529343.3532832311, 530117.0, 24982.500349992533, 25356.0, 25932.8703356482, 25981.350466237738, 26780.866095669397, 42359.7882010588, 42418.363724544775, 42524.14951700887, 42682.57317426786, 43342.13315733731, 30399.088027359216, 35659.0, 36322.455163172046, 36826.0, 36859.63140368669, 32146.0, 36445.635543644225, 36452.63547364492, 37103.8144809285, 37133.25733485369, 36034.0, 36078.0, 36213.27573448463, 36347.09132271714, 36737.0, 30589.847050765493, 31944.68055319417, 32359.838200808845, 32491.67508324981, 32495.350092997534, 23957.28128156158, 24327.37488501646, 24503.264902052968, 24515.264542063767, 24518.264452066465], "value": 19975.65729732396, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [389.99220015599707, 391.108273137247, 398.7866491427087, 404.37725902110753, 418.04355615718424, 17968.37110701126, 20542.486437839063, 20847.27034553791, 21419.35741927743, 21646.134154633834, 21152.682709759327, 21268.723876567416, 21734.78265217351, 22345.553088938253, 22468.32595022152, 5690.63010904291, 5948.40515948406, 5958.195643588104, 5971.880562388749, 5988.101784732282, 5705.372409035002, 5822.417758224165, 5870.647761134345, 5876.588638795286, 6013.75944962201, 6064.727087281083, 6075.574709770318, 6088.238970128734, 6099.054646529795, 6109.5723299369065, 5568.888622227573, 5904.61619994699, 6042.365551617083, 6517.902231466532, 6564.146660934048, 5843.328017277982, 5849.88300233993, 5873.177755114288, 5892.882142357129, 5893.79371721992, 12271.509139634492, 13291.335433228396, 22407.887960560256, 25031.62452563213, 26211.344716382053, 2218.3788539208886, 2724.5776904579625, 2742.6708794944734, 2756.738109879578, 2892.956605650917, 10502.78994420115, 10724.195685323632, 14637.0, 15310.693786124215, 15352.0, 1864.3288416170167, 1872.241742094454, 1873.8594605404649, 1884.9340273090377, 1898.1932678611536, 132102.0, 135035.999999999, 136037.3198134013, 140001.2999935004, 141139.0, 11764.470598822902, 11900.345480998629, 11980.161388702707, 12067.57763478274, 12505.124641275024, 11869.643910682693, 13350.132241404277, 13822.447102116004, 16112.194390280674, 16149.192540372935, 8183.263506284344, 8477.897873276435, 8717.171868672465, 9099.772505687411, 9110.316726245557, 7205.711771529185, 8562.357823163287, 8872.911270887338, 8931.419457735226, 9013.63945442188, 4835.854924352275, 4894.486078961729, 4898.387701537308, 4948.208286674115, 5005.674631149037, 1785.3751187084417, 2033.8779673219653, 2083.5728675621463, 2089.3389755144694, 2095.1514636572215, 990.6879333010079, 991.1723710701501, 998.0917365197636, 1012.2104758288547, 1053.3206082077018, 21670.34988950334, 21940.561188776133, 21957.902104894692, 22218.55562888733, 22349.888250558644, 991.965281215154, 994.8806143262855, 1006.8540061691014, 1012.964546240878, 1024.8257796174669, 11101.056410205061, 11461.083113350913, 11721.88278117225, 12656.746865062647, 12678.556250531186, 442776.0, 703134.0, 747552.0, 748863.0, 768057.1597141979, 144306.0, 145607.0, 146563.0, 155255.0, 159640.0, 1044.38903241603, 1052.315994603514, 1092.7377429416883, 1094.8959848814509, 1113.1929351220301, 19245.518862028563, 20291.59416811685, 20535.794642053985, 20836.687449687965, 21085.472863178544, 59049.0, 59610.0, 59716.0, 60088.0, 60378.69810650919, 68805.65597171983, 83620.0, 84158.0, 84416.0, 85285.0, 8325.209105135002, 8637.69768058115, 8661.090585488684, 8668.219860212612, 8711.956440217758, 9402.482863442576, 13036.283004434468, 13638.590842274747, 13652.795208072086, 13656.0, 11429.771404571695, 11466.656000320003, 11545.769084618092, 11810.114241432098, 11917.284962902253, 9671.0, 11145.609903653332, 11473.254238474807, 11559.595414160463, 11769.47037383337, 11133.443327833738, 11541.769164616493, 11827.0, 11849.940750296193, 12046.277223366626, 7437.181909989788, 7974.800629984296, 8357.540335281617, 8880.245179159843, 8906.465612063297, 11143.498542565767, 11170.609028683957, 11336.6032188873, 11539.076873849905, 11741.76516469683], "value": 10098.28484496462, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1439.7984282200487, 1441.4810668159466, 1466.6699992501688, 1467.9706405871882, 1476.8596983286586, 7765.184655611164, 7986.560739159344, 8208.261256486914, 8652.351073669477, 8657.567121643908, 8103.959480202606, 8396.07643159252, 8402.873956890651, 8492.830143397128, 8495.95752021239, 2139.684094282013, 2182.9235976740815, 2354.5761762882676, 2356.1164563288767, 2363.2201373546773, 2352.1532248390567, 2370.5969985102574, 2373.6083546214854, 2389.5220955808823, 2389.928302150938, 1948.83434908033, 2313.0747700919683, 2319.895604697793, 2342.0397636968846, 2343.4375749820006, 2298.195631528968, 2304.974286442534, 2308.68832707584, 2331.94170145746, 2335.0192918973985, 2300.0339857259823, 2307.411610039429, 2329.2429960262984, 2347.178487529351, 2352.4001379648253, 8472.618732157007, 8501.82996340076, 8507.914920850773, 8513.702020429255, 8545.359098067607, 5058.2412638104215, 5193.974030129825, 5208.088584497736, 5228.660137091077, 5231.241469986869, 2968.4805159097073, 3000.339925216471, 3309.966900331014, 3513.350030244413, 3561.0919215600115, 1199.7180662544295, 1212.4665147335234, 1243.5958313548042, 1248.1013670157472, 1268.0806415348798, 10737.0, 11019.449027548591, 11033.337999720043, 11035.779284414348, 11038.392888391136, 4459.843905463358, 4664.370310008173, 4738.8341408050555, 4739.952600473951, 4741.288806679026, 9583.664571739955, 9604.51977401127, 9620.230381569598, 9621.470819105016, 9647.807043859224, 5258.8159414420315, 5293.523582877559, 5295.311609490742, 5301.390340110859, 5308.362996440452, 4071.6131967463043, 4715.858524244278, 4769.833055843029, 4785.832495862628, 4803.279508073817, 3892.0464486201063, 3895.9220815584094, 3972.0864201233876, 3990.1221731219084, 3990.86031988879, 1271.7520083583613, 1290.5031562848183, 1298.6104168749337, 1304.8956083513297, 1305.9030414451715, 795.6419611174995, 799.1648727080216, 808.7614153824636, 816.5794615772882, 818.7912082419064, 5607.607467477319, 5625.184348269478, 5627.915581266284, 5657.830265092054, 5845.415458454206, 580.1674596953385, 584.4681339980597, 592.5792687192055, 593.4836692077834, 601.6841158391879, 3524.2951409718157, 3873.3028054949814, 3886.203328317688, 3955.980220098881, 3990.880273591797, 9805.068518490874, 10187.592496300213, 10195.439250841127, 10200.6429774959, 10210.285280030326, 10084.546195421224, 10126.493675316353, 10127.088562029305, 10135.695929122137, 10170.593176272867, 7650.081990161217, 7759.534427934229, 7916.168802275678, 7946.9999999998845, 7957.204279572113, 5262.158054711381, 5507.972460137674, 5586.022446072003, 5658.264425624642, 5700.828975130753, 8140.389470789596, 9131.0, 9216.078392160998, 9251.982281948845, 9309.069093090637, 8452.915470845213, 8879.28965682731, 9204.125608067223, 9542.761430964281, 9559.952200238955, 6680.832979175365, 6929.203141638776, 7228.421726261991, 7314.4514161439165, 7625.199354067743, 7842.843143137219, 7980.281774640311, 8066.072401673882, 8084.717034903751, 8100.392470564613, 5218.8173413931845, 7075.610841403566, 7256.7822965311125, 7256.854862902818, 7259.564426134449, 6989.790306290819, 7008.748501713377, 7088.681009354695, 7129.429645628234, 7143.607101609461, 5408.0, 6518.967405162944, 6572.8356791080605, 6583.111279977142, 6596.076549283201, 5102.438731739431, 5121.436641969455, 5155.149400348901, 5171.0, 5171.586273098218, 4373.584509471595, 5073.720945348041, 5100.132977393778, 5108.361454818148, 5208.557272631869], "value": 4380.944267167234, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [316.7861693356984, 376.48609647830705, 378.26616364253346, 379.26612005768857, 383.02711113771005, 4532.892205124621, 4643.071385722852, 5012.067940254946, 5232.8749318896425, 5341.946580534199, 4649.070635686195, 5583.944160558393, 5602.747876345564, 5608.327000759904, 5614.9438505614935, 3707.8887633370905, 3906.7498400511777, 3949.8025098745093, 4284.228838809013, 4332.328489084197, 4358.2808836541935, 4363.716358436692, 4373.453318335208, 4380.824767009316, 4391.5608439155985, 4316.460442444694, 4373.759443230621, 4412.8455504057365, 4427.225235583776, 4430.136123455927, 3635.563732352108, 3683.2081102562943, 4226.350305946247, 4281.7002809803325, 4361.890952726176, 3838.4626152338697, 3842.8847134585726, 3847.7306588538813, 3851.0564911596557, 3852.383618621035, 4357.716748411343, 5437.0, 5477.233187353773, 5479.890402191973, 5488.396276409591, 4499.907542992524, 4936.950630493685, 4998.750062496861, 5064.898702025939, 5080.720560369178, 5030.899382012376, 5949.345571987078, 5956.55325850563, 5986.221791167164, 6018.247719035121, 3989.601039896016, 4283.807228674717, 4287.721298115613, 4291.069018941457, 4400.185965596375, 5282.3925248596515, 5902.557308201902, 6111.052786818094, 6156.7537298508005, 6216.756648670284, 3377.088186189715, 3618.1366596202975, 3944.9802750986637, 4044.7775372354804, 4058.173821780211, 3833.5974722654278, 3942.64516193544, 4111.712180147361, 4122.546519882811, 4141.689373297015, 4399.494058183286, 4621.353010578522, 4834.984653222863, 4865.859022030366, 4874.731889746027, 5242.9213561796605, 5796.594238403356, 5856.355800861902, 5980.641161530322, 5987.43119403656, 1175.8530183727034, 1245.3461932485336, 1333.3933060457468, 1345.2601069411785, 1349.3590544491287, 854.6196942360714, 891.38494438837, 897.6319708919311, 907.111031189438, 910.7085732565648, 4658.906821863544, 4752.405949256343, 5191.870203244949, 5362.785488580413, 5429.778299882495, 5114.974425127925, 5452.454754524516, 5883.323417807007, 5950.583459157818, 5978.206538038658, 4798.568128868418, 4972.303877457159, 5131.0, 5264.0, 5309.33633295838, 3024.5765592817024, 3052.8778848845795, 3072.741534971794, 3080.738137258358, 3114.5483904833673, 5341.0, 5708.71456427177, 5804.680742559115, 6123.969380153071, 6135.588814572673, 5197.688138711689, 5261.23748544242, 5552.750126244248, 5679.744411501492, 5968.46283834457, 5278.287431196739, 5388.8113916013535, 6190.845228869224, 6352.714127864349, 6377.8519866424485, 5827.067669172912, 5881.853123609766, 6058.030715085742, 6077.787277445268, 6154.599951003171, 4747.62019038483, 5349.063913814989, 5982.820515384546, 5995.130706047686, 6028.367021462684, 5304.787808487772, 6267.9373206267355, 6337.6446902367425, 6339.651319177489, 6359.936400635935, 3705.9629403705617, 3798.35427977239, 4033.334499807499, 4047.777372244555, 4082.8162732677697, 4885.316055752269, 5676.971615141898, 6026.197818014644, 6057.818265452043, 6101.328853826164, 5204.947950520446, 5363.597730170331, 5754.2597311747695, 5893.410658934244, 5919.526437884872, 4902.975485122552, 5158.1372338351075, 5270.262163297218, 5396.784128634968, 5902.675352855634, 5721.456461636138, 5802.47777700009, 5811.593188476766, 5811.658296479625, 5841.912371314519, 5897.3807750185615, 5937.643741375531, 5937.7921772737745, 5947.405259474191, 5977.027580898206, 4196.769177695256, 5688.0, 5739.684317362585, 5767.452092051249, 5811.576163839886], "value": 4267.336260414931, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [687.7352219395534, 734.0640683129006, 778.6807408962324, 816.0696805641568, 818.942674012819, 14803.40786368544, 15324.0, 15733.921330393332, 15762.92118539407, 15844.1285729285, 14244.21656808875, 14373.281335933214, 14382.352794124263, 14399.712005759904, 14402.49591264306, 672.4989882537492, 692.7090621938797, 821.9917800821975, 842.145222599061, 853.189470003496, 845.188618925832, 848.3340577646549, 849.6049337058266, 855.422589751918, 857.1642648417806, 909.6497848328409, 911.3210658059721, 911.5260064766318, 912.9269658427343, 913.4975763330175, 899.8695189197563, 904.6607522179182, 907.3421769217265, 908.6910450446846, 908.7092130518237, 674.2650510943096, 674.912261406019, 676.0974099577071, 677.8847595908708, 678.2268214235775, 14549.92725036368, 14938.0, 14964.02733822298, 15466.458673946358, 15727.213639318103, 14243.572692819233, 14556.199409032499, 14583.635409114857, 14672.486462973744, 14674.486392976194, 11519.36643484608, 11951.462184201731, 11978.820317695241, 11982.580609678707, 12382.5666101686, 3130.6597671410045, 3478.2000139967695, 3661.3775658137915, 3778.4332350147424, 3787.6023017583307, 89038.55480722555, 93325.60011599831, 96190.0, 101216.49391753068, 101786.98213017923, 9770.853437198448, 10016.697829282293, 10042.548085336031, 10667.67996960077, 10851.78296434067, 16684.74972875408, 17992.550186245186, 19940.0, 20024.199032038556, 20259.39221823318, 7389.926100739031, 7571.810704732426, 7576.886346704804, 7740.651670674833, 7967.402444816662, 4263.957360426418, 4300.61294483498, 4308.89227769302, 4375.409319741794, 4492.887677808016, 3451.2062225688233, 3527.876524321636, 3548.5919119301093, 3549.432090865449, 3553.093961039918, 652.8367908022994, 655.8327626455224, 662.0466528199378, 671.7078071039114, 679.1374953808734, 660.7423104989058, 668.5397736330351, 676.3169199108837, 679.0900193740412, 690.4683393786756, 9676.371035882645, 10173.593056277814, 10245.385276883411, 10263.673677018423, 10428.947855260827, 604.5828378418877, 620.4973971083436, 661.7915356662636, 699.192432740186, 702.8418605813753, 4298.548652391516, 5043.823466178666, 5147.562457191106, 5169.896602067937, 5173.22401639757, 75162.0, 79682.7291262621, 100594.0, 100991.0, 102433.48783256036, 84430.15569844224, 86037.56981215179, 87779.56110219409, 87833.68249476263, 88706.0, 76018.61990690012, 77159.61420192975, 77362.22637773663, 78340.60829695815, 78516.21483785204, 8347.624356904076, 9783.0706082922, 9900.722833748423, 9969.950150249202, 10013.74965625836, 47315.05369892514, 47329.52670473251, 48613.27080093731, 50317.496825032744, 51108.0, 67150.65698686097, 67784.66107669627, 68341.0, 68609.65695171492, 71738.0, 12610.558630447891, 12787.552435664707, 12932.353382331034, 13095.279759612931, 13137.014723895938, 14466.349014294592, 14954.25228738537, 15063.246837657854, 15137.0, 15231.314590843216, 10778.892211078102, 14135.929320353333, 14183.858161418253, 14647.121172729245, 14660.486882959045, 12433.37833108359, 12651.177673451197, 14058.507952221624, 14325.641858953193, 14377.792755685281, 11554.826677600011, 13234.272115033766, 13572.389242483914, 14171.929140354232, 14210.786838197642, 10972.451377431256, 11662.825057624312, 11738.823917641414, 11939.283642981449, 11968.46141923598, 10990.780184396428, 11123.202989509908, 11260.493277802683, 11265.887341126812, 11273.210875238983], "value": 7445.194084147814, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1562.8000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 121.0, 119.0, 119.0, 120.0, 120.0, 120.0, 119.0, 119.0, 120.0], "value": 119.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 109.0, 111.0, 142.0, 112.0, 114.0, 112.0, 111.0, 128.0, 119.0], "value": 119.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 199.0, 195.0, 247.0, 269.0, 206.0, 195.0, 196.0, 195.0, 192.0], "value": 209.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 63.0, 82.0, 114.0, 89.0, 82.0, 54.0, 87.0, 97.0, 84.0], "value": 83.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 72.0, 92.0, 82.0, 70.0, 71.0, 80.0, 70.0, 73.0, 82.0], "value": 77.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 88.0, 87.0, 88.0, 84.0, 89.0, 101.0, 87.0, 86.0], "value": 88.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 99.0, 103.0, 113.0, 132.0, 99.0, 100.0, 120.0, 171.0, 118.0], "value": 116.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 88.0, 93.0, 92.0, 141.0, 145.0, 93.0, 92.0, 95.0, 94.0], "value": 104.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 69.0, 56.0, 58.0, 61.0, 59.0, 60.0, 61.0, 60.0, 76.0], "value": 61.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 91.0, 104.0, 101.0, 65.0, 113.0, 112.0, 105.0, 71.0, 73.0], "value": 89.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 82.0, 86.0, 81.0, 97.0, 71.0, 101.0, 74.0, 84.0, 97.0], "value": 86.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 141.0, 142.0, 138.0, 143.0, 139.0, 162.0, 167.0, 199.0, 151.0], "value": 152.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 172.0, 234.0, 230.0, 194.0, 169.0, 168.0, 170.0, 170.0, 174.0], "value": 184.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 66.0, 67.0, 70.0, 68.0, 85.0, 60.0, 62.0, 84.0], "value": 69.8, "unit": "score"}]}]} 08:24:34 INFO - Return code: 0 08:24:34 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:24:34 ERROR - # TBPL SUCCESS # 08:24:34 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:24:34 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:24:34 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:24:34 INFO - 2017-01-30 08:24:34.161 screenresolution[1655:10385] starting screenresolution argv=screenresolution get 08:24:34 INFO - 2017-01-30 08:24:34.164 screenresolution[1655:10385] Display 0: 1600x1200x32@60 08:24:34 INFO - 2017-01-30 08:24:34.170 screenresolution[1656:10388] starting screenresolution argv=screenresolution list 08:24:34 INFO - Available Modes on Display 0 08:24:34 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:24:34 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:24:34 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:24:34 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:24:34 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:24:34 INFO - Intel Iris: 08:24:34 INFO - Chipset Model: Intel Iris 08:24:34 INFO - Type: GPU 08:24:34 INFO - Bus: Built-In 08:24:34 INFO - VRAM (Dynamic, Max): 1536 MB 08:24:34 INFO - Vendor: Intel (0x8086) 08:24:34 INFO - Device ID: 0x0a2e 08:24:34 INFO - Revision ID: 0x0009 08:24:34 INFO - Displays: 08:24:34 INFO - Display: 08:24:34 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:24:34 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:24:34 INFO - Display Serial Number: 1600x1200 60 08:24:34 INFO - Main Display: Yes 08:24:34 INFO - Mirror: Off 08:24:34 INFO - Online: Yes 08:24:34 INFO - Rotation: Supported 08:24:34 INFO - Return code: 0 08:24:34 INFO - Running post-action listener: _resource_record_post_action 08:24:34 INFO - [mozharness: 2017-01-30 16:24:34.416449Z] Finished run-tests step (success) 08:24:34 INFO - Running post-run listener: _resource_record_post_run 08:24:34 INFO - Total resource usage - Wall time: 1196s; CPU: 54.0%; Read bytes: 39827968; Write bytes: 510566400; Read time: 384; Write time: 1629 08:24:34 INFO - TinderboxPrint: CPU usage
54.3% 08:24:34 INFO - TinderboxPrint: I/O read bytes / time
39,827,968 / 384 08:24:34 INFO - TinderboxPrint: I/O write bytes / time
510,566,400 / 1,629 08:24:34 INFO - TinderboxPrint: CPU idle
2,188.0 (45.7%) 08:24:34 INFO - TinderboxPrint: CPU system
960.3 (20.1%) 08:24:34 INFO - TinderboxPrint: CPU user
1,637.4 (34.2%) 08:24:34 INFO - TinderboxPrint: Swap in / out
247,455,744 / 0 08:24:34 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 205349376; Write bytes: 202426368; Read time: 15676; Write time: 448 08:24:34 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 37734912; Write bytes: 297183232; Read time: 360; Write time: 1151 08:24:34 INFO - Running post-run listener: _upload_blobber_files 08:24:34 INFO - Blob upload gear active. 08:24:34 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:24:34 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 08:24:34 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'] 08:24:34 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 08:24:35 INFO - (blobuploader) - INFO - Open directory for files ... 08:24:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 08:24:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:24:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:24:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:24:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:24:35 INFO - (blobuploader) - INFO - Done attempting. 08:24:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 08:24:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:24:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:24:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:24:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:24:36 INFO - (blobuploader) - INFO - Done attempting. 08:24:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 08:24:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:24:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:24:37 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 08:24:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:24:37 INFO - (blobuploader) - INFO - Done attempting. 08:24:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:24:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:24:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:24:40 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:24:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:24:40 INFO - (blobuploader) - INFO - Done attempting. 08:24:40 INFO - (blobuploader) - INFO - Iteration through files over. 08:24:40 INFO - Return code: 0 08:24:40 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:24:40 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:24:40 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3a2f1cbfbf98052e2621bb58af604fb09c882683e057c55d2a6cadf9265ce40cf728cb75e26049e3a8f0d3cdc825b1de9fdd93376705a89da4bd598693106dea", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3814533eca81c197a3c27cd7e98d6def71dbb575a54de874069413eb108f7680172e8bfb367984692d56d83a518dcbd61de30ca930cb944d14b176b03490ecfa", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/05c2f8559467feea9f50eaa24838bf59b9ecbd1ff5c9861a04e01fabc80b84dbe2c630be5182eb4169c784a69f1803f7595332e2267c0d9cec5d16d3218b8529", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1bd8fcb396c547516f4709edbe78b4ff5b1a1b18b67f2d3846ef51b8d739162d1a98c2e698c29fecbc35e8ad579b75bbd6895b155618f801c202d42d765335cf"} 08:24:40 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:24:40 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:24:40 INFO - Contents: 08:24:40 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3a2f1cbfbf98052e2621bb58af604fb09c882683e057c55d2a6cadf9265ce40cf728cb75e26049e3a8f0d3cdc825b1de9fdd93376705a89da4bd598693106dea", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3814533eca81c197a3c27cd7e98d6def71dbb575a54de874069413eb108f7680172e8bfb367984692d56d83a518dcbd61de30ca930cb944d14b176b03490ecfa", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/05c2f8559467feea9f50eaa24838bf59b9ecbd1ff5c9861a04e01fabc80b84dbe2c630be5182eb4169c784a69f1803f7595332e2267c0d9cec5d16d3218b8529", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1bd8fcb396c547516f4709edbe78b4ff5b1a1b18b67f2d3846ef51b8d739162d1a98c2e698c29fecbc35e8ad579b75bbd6895b155618f801c202d42d765335cf"} 08:24:40 INFO - Running post-run listener: copy_logs_to_upload_dir 08:24:40 INFO - Copying logs to upload dir... 08:24:40 INFO - mkdir: /builds/slave/test/build/upload/logs 08:24:40 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1259.859140 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 59 secs) (at 2017-01-30 08:24:40.366565) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-30 08:24:40.369768) ========= 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.Tg4sZnkiHX/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.MQUGY54SQy/Listeners TMPDIR=/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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/3a2f1cbfbf98052e2621bb58af604fb09c882683e057c55d2a6cadf9265ce40cf728cb75e26049e3a8f0d3cdc825b1de9fdd93376705a89da4bd598693106dea", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3814533eca81c197a3c27cd7e98d6def71dbb575a54de874069413eb108f7680172e8bfb367984692d56d83a518dcbd61de30ca930cb944d14b176b03490ecfa", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/05c2f8559467feea9f50eaa24838bf59b9ecbd1ff5c9861a04e01fabc80b84dbe2c630be5182eb4169c784a69f1803f7595332e2267c0d9cec5d16d3218b8529", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1bd8fcb396c547516f4709edbe78b4ff5b1a1b18b67f2d3846ef51b8d739162d1a98c2e698c29fecbc35e8ad579b75bbd6895b155618f801c202d42d765335cf"} build_url:https://queue.taskcluster.net/v1/task/dr7R0axpRbG8zI2bnKzKYw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011129 build_url: 'https://queue.taskcluster.net/v1/task/dr7R0axpRbG8zI2bnKzKYw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3a2f1cbfbf98052e2621bb58af604fb09c882683e057c55d2a6cadf9265ce40cf728cb75e26049e3a8f0d3cdc825b1de9fdd93376705a89da4bd598693106dea", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3814533eca81c197a3c27cd7e98d6def71dbb575a54de874069413eb108f7680172e8bfb367984692d56d83a518dcbd61de30ca930cb944d14b176b03490ecfa", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/05c2f8559467feea9f50eaa24838bf59b9ecbd1ff5c9861a04e01fabc80b84dbe2c630be5182eb4169c784a69f1803f7595332e2267c0d9cec5d16d3218b8529", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1bd8fcb396c547516f4709edbe78b4ff5b1a1b18b67f2d3846ef51b8d739162d1a98c2e698c29fecbc35e8ad579b75bbd6895b155618f801c202d42d765335cf"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-30 08:24:40.404484) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-30 08:24:40.404821) ========= 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.Tg4sZnkiHX/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.MQUGY54SQy/Listeners TMPDIR=/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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.004997 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-30 08:24:40.456479) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-30 08:24:40.457216) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-30 08:24:40.457560) ========= ========= Total master_lag: 0.18 =========