builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0068 starttime: 1484103718.68 results: success (0) buildid: 20170110175705 builduid: 6f17ec4efa8d47ff9e7a2e115f6bc257 revision: b079c9833e3ed047e1b984e26b8d62d739baa40b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-10 19:01:58.679561) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-10 19:01:58.680182) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-10 19:01:58.680471) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-10 19:01:58.707813) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-10 19:01:58.708235) ========= 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.DF2y2GY89h/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.8JEQNrFWeD/Listeners TMPDIR=/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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-10 19:01:58-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 15.6M=0.001s 2017-01-10 19:01:58 (15.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.176669 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-10 19:01:58.910142) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-10 19:01:58.910503) ========= 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.DF2y2GY89h/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.8JEQNrFWeD/Listeners TMPDIR=/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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.026740 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-10 19:01:58.959049) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-10 19:01:58.959424) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev b079c9833e3ed047e1b984e26b8d62d739baa40b --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev b079c9833e3ed047e1b984e26b8d62d739baa40b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.DF2y2GY89h/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.8JEQNrFWeD/Listeners TMPDIR=/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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-10 19:01:59,053 truncating revision to first 12 chars 2017-01-10 19:01:59,053 Setting DEBUG logging. 2017-01-10 19:01:59,053 attempt 1/10 2017-01-10 19:01:59,053 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/b079c9833e3e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-10 19:01:59,971 unpacking tar archive at: mozilla-central-b079c9833e3e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.190011 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-10 19:02:00.173878) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-10 19:02:00.174253) ========= script_repo_revision: b079c9833e3ed047e1b984e26b8d62d739baa40b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-10 19:02:00.174696) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-10 19:02:00.174999) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-10 19:02:00.195316) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2017-01-10 19:02:00.195771) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.DF2y2GY89h/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.8JEQNrFWeD/Listeners TMPDIR=/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 19:02:00 INFO - MultiFileLogger online at 20170110 19:02:00 in /builds/slave/test 19:02:00 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 19:02:00 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:02:00 INFO - {'append_to_log': False, 19:02:00 INFO - 'base_work_dir': '/builds/slave/test', 19:02:00 INFO - 'blob_upload_branch': 'Firefox', 19:02:00 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:02:00 INFO - 'branch': 'Firefox', 19:02:00 INFO - 'buildbot_json_path': 'buildprops.json', 19:02:00 INFO - 'config_files': ('talos/mac_config.py',), 19:02:00 INFO - 'default_actions': ('clobber', 19:02:00 INFO - 'read-buildbot-config', 19:02:00 INFO - 'download-and-extract', 19:02:00 INFO - 'populate-webroot', 19:02:00 INFO - 'create-virtualenv', 19:02:00 INFO - 'install', 19:02:00 INFO - 'run-tests'), 19:02:00 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:02:00 INFO - 'download_minidump_stackwalk': True, 19:02:00 INFO - 'download_symbols': 'ondemand', 19:02:00 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:02:00 INFO - 'tooltool.py': '/tools/tooltool.py', 19:02:00 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:02:00 INFO - '/tools/misc-python/virtualenv.py')}, 19:02:00 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:02:00 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:02:00 INFO - 'installer_path': 'installer.exe', 19:02:00 INFO - 'log_level': 'info', 19:02:00 INFO - 'log_name': 'talos', 19:02:00 INFO - 'log_to_console': True, 19:02:00 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:02:00 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:02:00 INFO - 'opt_config_files': (), 19:02:00 INFO - 'pip_index': False, 19:02:00 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:02:00 INFO - 'cmd': ('bash', 19:02:00 INFO - '-c', 19:02:00 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:02:00 INFO - 'enabled': True, 19:02:00 INFO - 'halt_on_failure': False, 19:02:00 INFO - 'name': 'check_screen_resolution'},), 19:02:00 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:02:00 INFO - 'cmd': ('bash', 19:02:00 INFO - '-c', 19:02:00 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:02:00 INFO - 'enabled': True, 19:02:00 INFO - 'halt_on_failure': False, 19:02:00 INFO - 'name': 'check_screen_resolution'},), 19:02:00 INFO - 'run_cmd_checks_enabled': True, 19:02:00 INFO - 'sps_profile': False, 19:02:00 INFO - 'sps_profile_interval': 0, 19:02:00 INFO - 'suite': 'dromaeojs-e10s', 19:02:00 INFO - 'system_bits': '32', 19:02:00 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:02:00 INFO - 'title': 't-yosemite-r7-0068', 19:02:00 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:02:00 INFO - 'use_talos_json': True, 19:02:00 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:02:00 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:02:00 INFO - 'work_dir': 'build'} 19:02:00 INFO - [mozharness: 2017-01-11 03:02:00.404916Z] Running clobber step. 19:02:00 INFO - Running pre-action listener: _resource_record_pre_action 19:02:00 INFO - Running main action method: clobber 19:02:00 INFO - rmtree: /builds/slave/test/build 19:02:00 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:02:02 INFO - Running post-action listener: _resource_record_post_action 19:02:02 INFO - [mozharness: 2017-01-11 03:02:02.743953Z] Finished clobber step (success) 19:02:02 INFO - [mozharness: 2017-01-11 03:02:02.744077Z] Running read-buildbot-config step. 19:02:02 INFO - Running pre-action listener: _resource_record_pre_action 19:02:02 INFO - Running main action method: read_buildbot_config 19:02:02 INFO - Using buildbot properties: 19:02:02 INFO - { 19:02:02 INFO - "project": "", 19:02:02 INFO - "product": "firefox", 19:02:02 INFO - "script_repo_revision": "production", 19:02:02 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 19:02:02 INFO - "repository": "", 19:02:02 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 19:02:02 INFO - "buildid": "20170110175705", 19:02:02 INFO - "pgo_build": "False", 19:02:02 INFO - "basedir": "/builds/slave/test", 19:02:02 INFO - "buildnumber": 48, 19:02:02 INFO - "slavename": "t-yosemite-r7-0068", 19:02:02 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 19:02:02 INFO - "platform": "yosemite_r7", 19:02:02 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 19:02:02 INFO - "branch": "mozilla-central", 19:02:02 INFO - "repo_path": "mozilla-central", 19:02:02 INFO - "revision": "b079c9833e3ed047e1b984e26b8d62d739baa40b", 19:02:02 INFO - "stage_platform": "macosx64", 19:02:02 INFO - "builduid": "6f17ec4efa8d47ff9e7a2e115f6bc257", 19:02:02 INFO - "slavebuilddir": "test" 19:02:02 INFO - } 19:02:02 INFO - Found installer url https://queue.taskcluster.net/v1/task/U4Ky-ZJDTfqNz3Xfsauw8g/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 19:02:02 INFO - Running post-action listener: _resource_record_post_action 19:02:02 INFO - [mozharness: 2017-01-11 03:02:02.747312Z] Finished read-buildbot-config step (success) 19:02:02 INFO - [mozharness: 2017-01-11 03:02:02.747417Z] Running download-and-extract step. 19:02:02 INFO - Running pre-action listener: _resource_record_pre_action 19:02:02 INFO - Running main action method: download_and_extract 19:02:02 INFO - mkdir: /builds/slave/test/build/tests 19:02:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:02:02 INFO - trying https://queue.taskcluster.net/v1/task/U4Ky-ZJDTfqNz3Xfsauw8g/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 19:02:02 INFO - Downloading https://queue.taskcluster.net/v1/task/U4Ky-ZJDTfqNz3Xfsauw8g/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 19:02:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/U4Ky-ZJDTfqNz3Xfsauw8g/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 19:02:04 INFO - Downloaded 1199 bytes. 19:02:04 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 19:02:04 INFO - Using the following test package requirements: 19:02:04 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 19:02:04 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 19:02:04 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 19:02:04 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 19:02:04 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 19:02:04 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 19:02:04 INFO - u'jsshell-mac.zip'], 19:02:04 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 19:02:04 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 19:02:04 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 19:02:04 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 19:02:04 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 19:02:04 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 19:02:04 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 19:02:04 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 19:02:04 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 19:02:04 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 19:02:04 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 19:02:04 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 19:02:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/U4Ky-ZJDTfqNz3Xfsauw8g/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 19:02:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/U4Ky-ZJDTfqNz3Xfsauw8g/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:02:04 INFO - Fetch https://queue.taskcluster.net/v1/task/U4Ky-ZJDTfqNz3Xfsauw8g/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 19:02:04 INFO - Expected file size: 16311358 19:02:04 INFO - Obtained file size: 16311358 19:02:11 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 19:02:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/U4Ky-ZJDTfqNz3Xfsauw8g/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 19:02:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/U4Ky-ZJDTfqNz3Xfsauw8g/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:02:11 INFO - Fetch https://queue.taskcluster.net/v1/task/U4Ky-ZJDTfqNz3Xfsauw8g/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 19:02:12 INFO - Expected file size: 16311358 19:02:12 INFO - Obtained file size: 16311358 19:02:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/U4Ky-ZJDTfqNz3Xfsauw8g/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 19:02:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/U4Ky-ZJDTfqNz3Xfsauw8g/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 19:02:19 INFO - Fetch https://queue.taskcluster.net/v1/task/U4Ky-ZJDTfqNz3Xfsauw8g/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 19:02:20 INFO - Expected file size: 14403529 19:02:20 INFO - Obtained file size: 14403529 19:02: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')]} 19:02:20 INFO - trying https://queue.taskcluster.net/v1/task/U4Ky-ZJDTfqNz3Xfsauw8g/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 19:02:20 INFO - Downloading https://queue.taskcluster.net/v1/task/U4Ky-ZJDTfqNz3Xfsauw8g/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 19:02:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/U4Ky-ZJDTfqNz3Xfsauw8g/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 19:02:22 INFO - Downloaded 69829564 bytes. 19:02:22 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/U4Ky-ZJDTfqNz3Xfsauw8g/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 19:02:22 INFO - mkdir: /builds/slave/test/properties 19:02:22 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:02:22 INFO - Writing to file /builds/slave/test/properties/build_url 19:02:22 INFO - Contents: 19:02:22 INFO - build_url:https://queue.taskcluster.net/v1/task/U4Ky-ZJDTfqNz3Xfsauw8g/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 19:02:23 INFO - Running post-action listener: _resource_record_post_action 19:02:23 INFO - Running post-action listener: set_extra_try_arguments 19:02:23 INFO - [mozharness: 2017-01-11 03:02:23.335932Z] Finished download-and-extract step (success) 19:02:23 INFO - [mozharness: 2017-01-11 03:02:23.336079Z] Running populate-webroot step. 19:02:23 INFO - Running pre-action listener: _resource_record_pre_action 19:02:23 INFO - Running main action method: populate_webroot 19:02:23 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:02:23 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:02:23 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:02:23 INFO - '--tppagecycles', 19:02:23 INFO - '7'], 19:02:23 INFO - 'tests': ['tsvgm']}}, 19:02:23 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:02:23 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:02:23 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:02:23 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:02:23 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:02:23 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:02:23 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 19:02:23 INFO - 'talos_options': ['--disable-e10s'], 19:02:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:02:23 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 19:02:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:02:23 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 19:02:23 INFO - 'talos_options': ['--disable-e10s'], 19:02:23 INFO - 'tests': ['damp', 'tps']}, 19:02:23 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 19:02:23 INFO - 'tests': ['damp', 'tps']}, 19:02:23 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:02:23 INFO - 'tests': ['dromaeo_dom']}, 19:02:23 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:02:23 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:02:23 INFO - 'tests': ['basic_compositor_video']}, 19:02:23 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:02:23 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:02:23 INFO - 'tests': ['a11yr', 19:02:23 INFO - 'ts_paint', 19:02:23 INFO - 'tpaint', 19:02:23 INFO - 'sessionrestore', 19:02:23 INFO - 'sessionrestore_no_auto_restore', 19:02:23 INFO - 'tabpaint']}, 19:02:23 INFO - 'other-e10s': {'tests': ['a11yr', 19:02:23 INFO - 'ts_paint', 19:02:23 INFO - 'tpaint', 19:02:23 INFO - 'sessionrestore', 19:02:23 INFO - 'sessionrestore_no_auto_restore', 19:02:23 INFO - 'tabpaint']}, 19:02:23 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:02:23 INFO - 'ts_paint', 19:02:23 INFO - 'tpaint', 19:02:23 INFO - 'sessionrestore', 19:02:23 INFO - 'sessionrestore_no_auto_restore', 19:02:23 INFO - 'tabpaint']}, 19:02:23 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:02:23 INFO - 'ts_paint', 19:02:23 INFO - 'tpaint', 19:02:23 INFO - 'sessionrestore', 19:02:23 INFO - 'sessionrestore_no_auto_restore', 19:02:23 INFO - 'tabpaint']}, 19:02:23 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:02:23 INFO - 'tests': ['a11yr', 19:02:23 INFO - 'ts_paint', 19:02:23 INFO - 'tpaint', 19:02:23 INFO - 'sessionrestore', 19:02:23 INFO - 'sessionrestore_no_auto_restore', 19:02:23 INFO - 'tabpaint']}, 19:02:23 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:02:23 INFO - 'tests': ['a11yr', 19:02:23 INFO - 'ts_paint', 19:02:23 INFO - 'tpaint', 19:02:23 INFO - 'sessionrestore', 19:02:23 INFO - 'sessionrestore_no_auto_restore', 19:02:23 INFO - 'tabpaint']}, 19:02:23 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:02:23 INFO - 'tests': ['tsvgx', 19:02:23 INFO - 'tsvgr_opacity', 19:02:23 INFO - 'tart', 19:02:23 INFO - 'tscrollx', 19:02:23 INFO - 'cart', 19:02:23 INFO - 'tsvg_static']}, 19:02:23 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:02:23 INFO - 'tsvgr_opacity', 19:02:23 INFO - 'tart', 19:02:23 INFO - 'tscrollx', 19:02:23 INFO - 'cart', 19:02:23 INFO - 'tsvg_static']}, 19:02:23 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 19:02:23 INFO - 'talos_options': ['--disable-e10s'], 19:02:23 INFO - 'tests': ['tp5o']}, 19:02:23 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 19:02:23 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 19:02:23 INFO - 'talos_options': ['--disable-e10s', 19:02:23 INFO - '--xperf_path', 19:02:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:02:23 INFO - 'tests': ['tp5n']}, 19:02:23 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 19:02:23 INFO - 'talos_options': ['--xperf_path', 19:02:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:02:23 INFO - 'tests': ['tp5n']}}, 19:02:23 INFO - 'talos.zip': {'path': '', 19:02:23 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:02:23 INFO - Running post-action listener: _resource_record_post_action 19:02:23 INFO - [mozharness: 2017-01-11 03:02:23.354272Z] Finished populate-webroot step (success) 19:02:23 INFO - [mozharness: 2017-01-11 03:02:23.354410Z] Running create-virtualenv step. 19:02:23 INFO - Running pre-action listener: _resource_record_pre_action 19:02:23 INFO - Running main action method: create_virtualenv 19:02:23 INFO - Creating virtualenv /builds/slave/test/build/venv 19:02:23 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 19:02:23 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:02:23 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:02:23 INFO - Using real prefix '/tools/python27' 19:02:23 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:02:24 INFO - Installing distribute.............................................................................................................................................................................................done. 19:02:27 INFO - Installing pip.................done. 19:02:27 INFO - Return code: 0 19:02:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 19:02:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 19:02:27 INFO - Reading from file tmpfile_stdout 19:02:27 INFO - Output received: 19:02:27 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) 19:02:27 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:02:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:02:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:02:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:02:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:02:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02:27 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 0x10b40ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b40d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5b85adf70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b1e8d30>, '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 0x10b10fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b1ddbe8>, '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.DF2y2GY89h/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.8JEQNrFWeD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 19:02:27 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 19:02:27 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 19:02:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DF2y2GY89h/Render', 19:02:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:02:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:02:27 INFO - 'HOME': '/Users/cltbld', 19:02:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:02:27 INFO - 'LOGNAME': 'cltbld', 19:02:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:02:27 INFO - 'MOZ_NO_REMOTE': '1', 19:02:27 INFO - 'NO_EM_RESTART': '1', 19:02:27 INFO - 'PAGER': '/bin/cat', 19:02:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:02:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:02:27 INFO - 'PWD': '/builds/slave/test', 19:02:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:02:27 INFO - 'SHELL': '/bin/bash', 19:02:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8JEQNrFWeD/Listeners', 19:02:27 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 19:02:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:02:27 INFO - 'USER': 'cltbld', 19:02:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:02:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:02:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:02:27 INFO - 'XPC_FLAGS': '0x0', 19:02:27 INFO - 'XPC_SERVICE_NAME': '0', 19:02:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:02:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:02:27 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 19:02:27 INFO - Cleaning up... 19:02:27 INFO - Return code: 0 19:02:27 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:02:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:02:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:02:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:02:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:02:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02:27 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 0x10b40ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b40d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5b85adf70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b1e8d30>, '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 0x10b10fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b1ddbe8>, '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.DF2y2GY89h/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.8JEQNrFWeD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 19:02:27 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 19:02:27 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 19:02:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DF2y2GY89h/Render', 19:02:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:02:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:02:27 INFO - 'HOME': '/Users/cltbld', 19:02:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:02:27 INFO - 'LOGNAME': 'cltbld', 19:02:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:02:27 INFO - 'MOZ_NO_REMOTE': '1', 19:02:27 INFO - 'NO_EM_RESTART': '1', 19:02:27 INFO - 'PAGER': '/bin/cat', 19:02:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:02:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:02:27 INFO - 'PWD': '/builds/slave/test', 19:02:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:02:27 INFO - 'SHELL': '/bin/bash', 19:02:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8JEQNrFWeD/Listeners', 19:02:27 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 19:02:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:02:27 INFO - 'USER': 'cltbld', 19:02:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:02:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:02:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:02:27 INFO - 'XPC_FLAGS': '0x0', 19:02:27 INFO - 'XPC_SERVICE_NAME': '0', 19:02:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:02:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:02:27 INFO - Downloading/unpacking psutil>=3.1.1 19:02:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:02:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:02:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:02:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:02:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:02:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:02:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:02:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:02:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:02:31 INFO - Installing collected packages: psutil 19:02:31 INFO - Running setup.py install for psutil 19:02:31 INFO - building 'psutil._psutil_osx' extension 19:02:31 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 19:02:32 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 19:02:32 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 19:02:32 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 19:02:32 INFO - building 'psutil._psutil_posix' extension 19:02:32 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 19:02:32 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:02:32 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:02:32 INFO - ^ 19:02:32 INFO - 1 warning generated. 19:02:32 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 19:02:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:02:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:02:33 INFO - Successfully installed psutil 19:02:33 INFO - Cleaning up... 19:02:33 INFO - Return code: 0 19:02:33 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:02:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:02:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:02:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:02:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:02:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b40ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b40d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5b85adf70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b1e8d30>, '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 0x10b10fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b1ddbe8>, '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.DF2y2GY89h/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.8JEQNrFWeD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 19:02:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 19:02:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 19:02:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DF2y2GY89h/Render', 19:02:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:02:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:02:33 INFO - 'HOME': '/Users/cltbld', 19:02:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:02:33 INFO - 'LOGNAME': 'cltbld', 19:02:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:02:33 INFO - 'MOZ_NO_REMOTE': '1', 19:02:33 INFO - 'NO_EM_RESTART': '1', 19:02:33 INFO - 'PAGER': '/bin/cat', 19:02:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:02:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:02:33 INFO - 'PWD': '/builds/slave/test', 19:02:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:02:33 INFO - 'SHELL': '/bin/bash', 19:02:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8JEQNrFWeD/Listeners', 19:02:33 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 19:02:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:02:33 INFO - 'USER': 'cltbld', 19:02:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:02:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:02:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:02:33 INFO - 'XPC_FLAGS': '0x0', 19:02:33 INFO - 'XPC_SERVICE_NAME': '0', 19:02:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:02:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:02:33 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:02:33 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 19:02:33 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 19:02:33 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 19:02:33 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 19:02:33 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 19:02:33 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 19:02:35 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:02:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:02:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:02:36 INFO - Installing collected packages: mozsystemmonitor 19:02:36 INFO - Running setup.py install for mozsystemmonitor 19:02:36 INFO - Successfully installed mozsystemmonitor 19:02:36 INFO - Cleaning up... 19:02:36 INFO - Return code: 0 19:02:36 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:02: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')]} 19:02:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:02:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02: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')]} 19:02:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:02:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02: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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b40ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b40d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5b85adf70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b1e8d30>, '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 0x10b10fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b1ddbe8>, '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.DF2y2GY89h/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.8JEQNrFWeD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 19:02: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==2.5.1'] in /builds/slave/test/build 19:02: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==2.5.1 19:02:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DF2y2GY89h/Render', 19:02:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:02:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:02:36 INFO - 'HOME': '/Users/cltbld', 19:02:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:02:36 INFO - 'LOGNAME': 'cltbld', 19:02:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:02:36 INFO - 'MOZ_NO_REMOTE': '1', 19:02:36 INFO - 'NO_EM_RESTART': '1', 19:02:36 INFO - 'PAGER': '/bin/cat', 19:02:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:02:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:02:36 INFO - 'PWD': '/builds/slave/test', 19:02:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:02:36 INFO - 'SHELL': '/bin/bash', 19:02:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8JEQNrFWeD/Listeners', 19:02:36 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 19:02:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:02:36 INFO - 'USER': 'cltbld', 19:02:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:02:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:02:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:02:36 INFO - 'XPC_FLAGS': '0x0', 19:02:36 INFO - 'XPC_SERVICE_NAME': '0', 19:02:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:02:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:02:36 INFO - Downloading/unpacking jsonschema==2.5.1 19:02:36 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 19:02:36 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 19:02:36 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 19:02:36 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 19:02:36 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 19:02:36 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 19:02:39 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:02:39 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:02:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:02:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:02:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:02:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:02:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:02:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:02:39 INFO - Downloading functools32-3.2.3-2.tar.gz 19:02:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:02:39 INFO - warning: no files found matching '*.txt' 19:02:39 INFO - no previously-included directories found matching 'build' 19:02:39 INFO - no previously-included directories found matching 'dist' 19:02:39 INFO - no previously-included directories found matching '.git*' 19:02:39 INFO - Installing collected packages: jsonschema, functools32 19:02:39 INFO - Running setup.py install for functools32 19:02:39 INFO - warning: no files found matching '*.txt' 19:02:39 INFO - no previously-included directories found matching 'build' 19:02:39 INFO - no previously-included directories found matching 'dist' 19:02:39 INFO - no previously-included directories found matching '.git*' 19:02:39 INFO - Successfully installed jsonschema functools32 19:02:39 INFO - Cleaning up... 19:02:40 INFO - Return code: 0 19:02:40 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:02:40 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')]} 19:02:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:02:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02:40 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')]} 19:02:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:02:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02:40 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 0x10b40ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b40d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5b85adf70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b1e8d30>, '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 0x10b10fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b1ddbe8>, '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.DF2y2GY89h/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.8JEQNrFWeD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 19:02:40 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 19:02:40 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 19:02:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DF2y2GY89h/Render', 19:02:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:02:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:02:40 INFO - 'HOME': '/Users/cltbld', 19:02:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:02:40 INFO - 'LOGNAME': 'cltbld', 19:02:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:02:40 INFO - 'MOZ_NO_REMOTE': '1', 19:02:40 INFO - 'NO_EM_RESTART': '1', 19:02:40 INFO - 'PAGER': '/bin/cat', 19:02:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:02:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:02:40 INFO - 'PWD': '/builds/slave/test', 19:02:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:02:40 INFO - 'SHELL': '/bin/bash', 19:02:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8JEQNrFWeD/Listeners', 19:02:40 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 19:02:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:02:40 INFO - 'USER': 'cltbld', 19:02:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:02:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:02:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:02:40 INFO - 'XPC_FLAGS': '0x0', 19:02:40 INFO - 'XPC_SERVICE_NAME': '0', 19:02:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:02:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:02:40 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:02:40 INFO - Cleaning up... 19:02:40 INFO - Return code: 0 19:02:40 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:02:40 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')]} 19:02:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:02:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02:40 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')]} 19:02:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:02:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02:40 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 0x10b40ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b40d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5b85adf70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b1e8d30>, '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 0x10b10fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b1ddbe8>, '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.DF2y2GY89h/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.8JEQNrFWeD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 19:02:40 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 19:02:40 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 19:02:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DF2y2GY89h/Render', 19:02:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:02:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:02:40 INFO - 'HOME': '/Users/cltbld', 19:02:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:02:40 INFO - 'LOGNAME': 'cltbld', 19:02:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:02:40 INFO - 'MOZ_NO_REMOTE': '1', 19:02:40 INFO - 'NO_EM_RESTART': '1', 19:02:40 INFO - 'PAGER': '/bin/cat', 19:02:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:02:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:02:40 INFO - 'PWD': '/builds/slave/test', 19:02:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:02:40 INFO - 'SHELL': '/bin/bash', 19:02:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8JEQNrFWeD/Listeners', 19:02:40 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 19:02:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:02:40 INFO - 'USER': 'cltbld', 19:02:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:02:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:02:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:02:40 INFO - 'XPC_FLAGS': '0x0', 19:02:40 INFO - 'XPC_SERVICE_NAME': '0', 19:02:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:02:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:02:40 INFO - Downloading/unpacking blobuploader==1.2.4 19:02:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:02:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:02:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:02:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:02:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:02:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:02:43 INFO - Downloading blobuploader-1.2.4.tar.gz 19:02:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:02:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:02:43 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 19:02:43 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 19:02:43 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 19:02:43 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 19:02:43 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 19:02:43 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 19:02:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:02:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:02:43 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 19:02:43 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 19:02:43 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 19:02:43 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 19:02:43 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 19:02:43 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 19:02:44 INFO - Downloading docopt-0.6.1.tar.gz 19:02:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:02:44 INFO - Installing collected packages: blobuploader, requests, docopt 19:02:44 INFO - Running setup.py install for blobuploader 19:02:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:02:44 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:02:44 INFO - Running setup.py install for requests 19:02:45 INFO - Running setup.py install for docopt 19:02:45 INFO - Successfully installed blobuploader requests docopt 19:02:45 INFO - Cleaning up... 19:02:45 INFO - Return code: 0 19:02:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:02:45 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')]} 19:02:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:02:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02:45 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')]} 19:02:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:02:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02:45 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 0x10b40ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b40d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5b85adf70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b1e8d30>, '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 0x10b10fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b1ddbe8>, '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.DF2y2GY89h/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.8JEQNrFWeD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 19:02:45 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 19:02:45 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 19:02:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DF2y2GY89h/Render', 19:02:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:02:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:02:45 INFO - 'HOME': '/Users/cltbld', 19:02:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:02:45 INFO - 'LOGNAME': 'cltbld', 19:02:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:02:45 INFO - 'MOZ_NO_REMOTE': '1', 19:02:45 INFO - 'NO_EM_RESTART': '1', 19:02:45 INFO - 'PAGER': '/bin/cat', 19:02:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:02:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:02:45 INFO - 'PWD': '/builds/slave/test', 19:02:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:02:45 INFO - 'SHELL': '/bin/bash', 19:02:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8JEQNrFWeD/Listeners', 19:02:45 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 19:02:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:02:45 INFO - 'USER': 'cltbld', 19:02:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:02:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:02:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:02:45 INFO - 'XPC_FLAGS': '0x0', 19:02:45 INFO - 'XPC_SERVICE_NAME': '0', 19:02:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:02:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:02:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:02:45 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-_24e1e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:02:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:02:45 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-tCP1Q0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:02:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:02:45 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-qCOzvL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:02:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:02:45 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-4t0WIP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:02:46 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-W4R7pG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:02:46 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-otkgfx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:02:46 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-9aloUN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:02:46 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-lL85sD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:02:46 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-14nSQ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:02:46 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-PKGPeg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:02:46 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-lw9cRU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:02:46 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-5W51po-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:02:46 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-ufglcQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:02:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:02:47 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-0dMClj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:02:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:02:47 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-GeVtMd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:02:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:02:47 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-AR2eQ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:02:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:02:47 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-uRxmol-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:02:47 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:02:47 INFO - Running setup.py install for manifestparser 19:02:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:02:47 INFO - Running setup.py install for mozcrash 19:02:47 INFO - Running setup.py install for mozdebug 19:02:47 INFO - Running setup.py install for mozdevice 19:02:48 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:02:48 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:02:48 INFO - Running setup.py install for mozfile 19:02:48 INFO - Running setup.py install for mozhttpd 19:02:48 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:02:48 INFO - Running setup.py install for mozinfo 19:02:48 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:02:48 INFO - Running setup.py install for mozInstall 19:02:48 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:02:48 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:02:48 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:02:48 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:02:48 INFO - Running setup.py install for mozleak 19:02:48 INFO - Running setup.py install for mozlog 19:02:48 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:02:48 INFO - Running setup.py install for moznetwork 19:02:49 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:02:49 INFO - Running setup.py install for mozprocess 19:02:49 INFO - Running setup.py install for mozprofile 19:02:49 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:02:49 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:02:49 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:02:49 INFO - Running setup.py install for mozrunner 19:02:49 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:02:49 INFO - Running setup.py install for mozscreenshot 19:02:49 INFO - Running setup.py install for moztest 19:02:49 INFO - Running setup.py install for mozversion 19:02:49 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:02:49 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:02:49 INFO - Cleaning up... 19:02:49 INFO - Return code: 0 19:02:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:02:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:02:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:02:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:02:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:02:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02:50 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 0x10b40ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b40d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5b85adf70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b1e8d30>, '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 0x10b10fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b1ddbe8>, '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.DF2y2GY89h/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.8JEQNrFWeD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 19:02:50 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 19:02:50 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 19:02:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DF2y2GY89h/Render', 19:02:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:02:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:02:50 INFO - 'HOME': '/Users/cltbld', 19:02:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:02:50 INFO - 'LOGNAME': 'cltbld', 19:02:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:02:50 INFO - 'MOZ_NO_REMOTE': '1', 19:02:50 INFO - 'NO_EM_RESTART': '1', 19:02:50 INFO - 'PAGER': '/bin/cat', 19:02:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:02:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:02:50 INFO - 'PWD': '/builds/slave/test', 19:02:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:02:50 INFO - 'SHELL': '/bin/bash', 19:02:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8JEQNrFWeD/Listeners', 19:02:50 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 19:02:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:02:50 INFO - 'USER': 'cltbld', 19:02:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:02:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:02:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:02:50 INFO - 'XPC_FLAGS': '0x0', 19:02:50 INFO - 'XPC_SERVICE_NAME': '0', 19:02:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:02:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:02:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:02:50 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-9FXANL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:02:50 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)) 19:02:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:02:50 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-YeGjCX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:02:50 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)) 19:02:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:02:50 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-5kssoM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:02:50 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)) 19:02:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:02:50 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-s6P9Kp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:02:50 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)) 19:02:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:02:50 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-QWTaXz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:02:50 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)) 19:02:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:02:50 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-R6K8WM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:02:50 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)) 19:02:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:02:50 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-erG4M3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:02:51 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)) 19:02:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:02:51 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-uZt7nN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:02:51 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)) 19:02:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:02:51 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-GmSQM6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:02:51 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)) 19:02:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:02:51 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-C7pMrf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:02:51 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)) 19:02:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:02:51 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-9aPu3C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:02:51 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)) 19:02:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:02:51 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-lW83Yo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:02:51 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)) 19:02:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:02:51 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-ixadUn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:02:51 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)) 19:02:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:02:51 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-Z9cx70-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:02:51 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)) 19:02:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:02:51 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-iwS1tP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:02:51 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)) 19:02:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:02:51 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-rs0wf2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:02:51 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)) 19:02:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:02:51 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-rlsoPM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:02:52 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)) 19:02:52 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)) 19:02:52 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)) 19:02:52 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)) 19:02:52 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)) 19:02:52 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)) 19:02:52 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:02:52 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 19:02:52 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 19:02:52 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 19:02:52 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 19:02:52 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 19:02:52 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 19:02:54 INFO - Downloading blessings-1.6.tar.gz 19:02:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:02:54 INFO - Installing collected packages: blessings 19:02:54 INFO - Running setup.py install for blessings 19:02:54 INFO - Successfully installed blessings 19:02:54 INFO - Cleaning up... 19:02:55 INFO - Return code: 0 19:02:55 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:02:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:02:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:02:55 INFO - Reading from file tmpfile_stdout 19:02:55 INFO - Current package versions: 19:02:55 INFO - blessings == 1.6 19:02:55 INFO - blobuploader == 1.2.4 19:02:55 INFO - docopt == 0.6.1 19:02:55 INFO - functools32 == 3.2.3-2 19:02:55 INFO - jsonschema == 2.5.1 19:02:55 INFO - manifestparser == 1.1 19:02:55 INFO - mozInstall == 1.12 19:02:55 INFO - mozcrash == 1.0 19:02:55 INFO - mozdebug == 0.1 19:02:55 INFO - mozdevice == 0.49 19:02:55 INFO - mozfile == 1.2 19:02:55 INFO - mozhttpd == 0.7 19:02:55 INFO - mozinfo == 0.9 19:02:55 INFO - mozleak == 0.1 19:02:55 INFO - mozlog == 3.4 19:02:55 INFO - moznetwork == 0.27 19:02:55 INFO - mozprocess == 0.24 19:02:55 INFO - mozprofile == 0.28 19:02:55 INFO - mozrunner == 6.13 19:02:55 INFO - mozscreenshot == 0.1 19:02:55 INFO - mozsystemmonitor == 0.3 19:02:55 INFO - moztest == 0.8 19:02:55 INFO - mozversion == 1.4 19:02:55 INFO - psutil == 3.1.1 19:02:55 INFO - requests == 1.2.3 19:02:55 INFO - wsgiref == 0.1.2 19:02:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:02:55 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')]} 19:02:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:02:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02:55 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')]} 19:02:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:02:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02:55 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 0x10b40ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b40d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5b85adf70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b1e8d30>, '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 0x10b10fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b1ddbe8>, '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.DF2y2GY89h/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.8JEQNrFWeD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 19:02:55 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 19:02:55 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 19:02:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DF2y2GY89h/Render', 19:02:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:02:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:02:55 INFO - 'HOME': '/Users/cltbld', 19:02:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:02:55 INFO - 'LOGNAME': 'cltbld', 19:02:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:02:55 INFO - 'MOZ_NO_REMOTE': '1', 19:02:55 INFO - 'NO_EM_RESTART': '1', 19:02:55 INFO - 'PAGER': '/bin/cat', 19:02:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:02:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:02:55 INFO - 'PWD': '/builds/slave/test', 19:02:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:02:55 INFO - 'SHELL': '/bin/bash', 19:02:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8JEQNrFWeD/Listeners', 19:02:55 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 19:02:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:02:55 INFO - 'USER': 'cltbld', 19:02:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:02:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:02:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:02:55 INFO - 'XPC_FLAGS': '0x0', 19:02:55 INFO - 'XPC_SERVICE_NAME': '0', 19:02:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:02:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:02:55 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)) 19:02:55 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)) 19:02:55 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)) 19:02:55 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)) 19:02:55 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)) 19:02:55 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)) 19:02:55 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)) 19:02:55 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)) 19:02:55 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)) 19:02:55 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)) 19:02:55 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)) 19:02:55 INFO - Cleaning up... 19:02:55 INFO - Return code: 0 19:02:55 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:02:55 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')]} 19:02:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:02:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02:55 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')]} 19:02:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:02:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02:55 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 0x10b40ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b40d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5b85adf70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b1e8d30>, '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 0x10b10fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b1ddbe8>, '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.DF2y2GY89h/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.8JEQNrFWeD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 19:02:55 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 19:02:55 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 19:02:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DF2y2GY89h/Render', 19:02:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:02:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:02:55 INFO - 'HOME': '/Users/cltbld', 19:02:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:02:55 INFO - 'LOGNAME': 'cltbld', 19:02:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:02:55 INFO - 'MOZ_NO_REMOTE': '1', 19:02:55 INFO - 'NO_EM_RESTART': '1', 19:02:55 INFO - 'PAGER': '/bin/cat', 19:02:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:02:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:02:55 INFO - 'PWD': '/builds/slave/test', 19:02:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:02:55 INFO - 'SHELL': '/bin/bash', 19:02:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8JEQNrFWeD/Listeners', 19:02:55 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 19:02:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:02:55 INFO - 'USER': 'cltbld', 19:02:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:02:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:02:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:02:55 INFO - 'XPC_FLAGS': '0x0', 19:02:55 INFO - 'XPC_SERVICE_NAME': '0', 19:02:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:02:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:02:55 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:02:55 INFO - Cleaning up... 19:02:55 INFO - Return code: 0 19:02:55 INFO - Running post-action listener: _resource_record_post_action 19:02:55 INFO - Running post-action listener: _start_resource_monitoring 19:02:55 INFO - Starting resource monitoring. 19:02:55 INFO - [mozharness: 2017-01-11 03:02:55.838613Z] Finished create-virtualenv step (success) 19:02:55 INFO - [mozharness: 2017-01-11 03:02:55.839609Z] Running install step. 19:02:55 INFO - Running pre-action listener: _resource_record_pre_action 19:02:55 INFO - Running main action method: install 19:02:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:02:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:02:56 INFO - Reading from file tmpfile_stdout 19:02:56 INFO - Detecting whether we're running mozinstall >=1.0... 19:02:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:02:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:02:56 INFO - Reading from file tmpfile_stdout 19:02:56 INFO - Output received: 19:02:56 INFO - Usage: mozinstall [options] installer 19:02:56 INFO - Options: 19:02:56 INFO - -h, --help show this help message and exit 19:02:56 INFO - -d DEST, --destination=DEST 19:02:56 INFO - Directory to install application into. [default: 19:02:56 INFO - "/builds/slave/test"] 19:02:56 INFO - --app=APP Application being installed. [default: firefox] 19:02:56 INFO - mkdir: /builds/slave/test/build/application 19:02:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 19:02:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 19:03:17 INFO - Reading from file tmpfile_stdout 19:03:17 INFO - Output received: 19:03:17 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 19:03:17 INFO - Running post-action listener: _resource_record_post_action 19:03:17 INFO - [mozharness: 2017-01-11 03:03:17.862606Z] Finished install step (success) 19:03:17 INFO - [mozharness: 2017-01-11 03:03:17.862706Z] Running run-tests step. 19:03:17 INFO - Running pre-action listener: _resource_record_pre_action 19:03:17 INFO - Running main action method: run_tests 19:03:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:03:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:03:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:03:17 INFO - 2017-01-10 19:03:17.875 screenresolution[1622:7803] starting screenresolution argv=screenresolution get 19:03:17 INFO - 2017-01-10 19:03:17.877 screenresolution[1622:7803] Display 0: 1600x1200x32@60 19:03:17 INFO - 2017-01-10 19:03:17.882 screenresolution[1623:7805] starting screenresolution argv=screenresolution list 19:03:17 INFO - Available Modes on Display 0 19:03:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:03:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:03:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:03:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:03:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:03:18 INFO - Intel Iris: 19:03:18 INFO - Chipset Model: Intel Iris 19:03:18 INFO - Type: GPU 19:03:18 INFO - Bus: Built-In 19:03:18 INFO - VRAM (Dynamic, Max): 1536 MB 19:03:18 INFO - Vendor: Intel (0x8086) 19:03:18 INFO - Device ID: 0x0a2e 19:03:18 INFO - Revision ID: 0x0009 19:03:18 INFO - Displays: 19:03:18 INFO - Display: 19:03:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:03:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:03:18 INFO - Display Serial Number: 1600x1200 60 19:03:18 INFO - Main Display: Yes 19:03:18 INFO - Mirror: Off 19:03:18 INFO - Online: Yes 19:03:18 INFO - Rotation: Supported 19:03:18 INFO - Return code: 0 19:03:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:03:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:03:18 INFO - Python 2.7.3 19:03:18 INFO - Return code: 0 19:03:18 INFO - grabbing minidump binary from tooltool 19:03:18 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')]} 19:03:18 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 0x10b1e8d30>, '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 0x10b10fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b1ddbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:03:18 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 19:03:18 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 19:03:18 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 19:03:18 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:03:18 INFO - Return code: 0 19:03:18 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:03:18 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:03:18 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:03:18 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:03:18 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:03:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/U4Ky-ZJDTfqNz3Xfsauw8g/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0068', '--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 19:03:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/U4Ky-ZJDTfqNz3Xfsauw8g/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0068 --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 19:03:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DF2y2GY89h/Render', 19:03:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:03:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:03:18 INFO - 'HOME': '/Users/cltbld', 19:03:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:03:18 INFO - 'LOGNAME': 'cltbld', 19:03:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:03:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:03:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:03:18 INFO - 'MOZ_NO_REMOTE': '1', 19:03:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:03:18 INFO - 'NO_EM_RESTART': '1', 19:03:18 INFO - 'PAGER': '/bin/cat', 19:03:18 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:03:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:03:18 INFO - 'PWD': '/builds/slave/test', 19:03:18 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:03:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:03:18 INFO - 'SHELL': '/bin/bash', 19:03:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8JEQNrFWeD/Listeners', 19:03:18 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 19:03:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:03:18 INFO - 'USER': 'cltbld', 19:03:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:03:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:03:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:03:18 INFO - 'XPC_FLAGS': '0x0', 19:03:18 INFO - 'XPC_SERVICE_NAME': '0', 19:03:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:03:18 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/U4Ky-ZJDTfqNz3Xfsauw8g/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0068', '--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 19:03:18 INFO - mozversion application_buildid: 20170110175705 19:03:18 INFO - mozversion application_changeset: b079c9833e3ed047e1b984e26b8d62d739baa40b 19:03:18 INFO - mozversion application_display_name: Nightly 19:03:18 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:03:18 INFO - mozversion application_name: Firefox 19:03:18 INFO - mozversion application_remotingname: firefox 19:03:18 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 19:03:18 INFO - mozversion application_vendor: Mozilla 19:03:18 INFO - mozversion application_version: 53.0a1 19:03:18 INFO - mozversion platform_buildid: 20170110175705 19:03:18 INFO - mozversion platform_changeset: b079c9833e3ed047e1b984e26b8d62d739baa40b 19:03:18 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 19:03:18 INFO - mozversion platform_version: 53.0a1 19:03:18 INFO - using testdate: 1484103798 19:03:18 INFO - actual date: 1484103798 19:03:18 INFO - starting webserver on 'localhost:49244' 19:03:18 INFO - SUITE-START | Running 2 tests 19:03:18 INFO - TEST-START | dromaeo_css 19:03:18 INFO - operating with platform_type : mac_ 19:03:18 INFO - Initialising browser for dromaeo_css test... 19:03:18 INFO - TEST-INFO | started process 1633 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/tmpONY8N7/profile http://localhost:49244/getInfo.html) 19:03:21 INFO - PROCESS | 1633 | 2017-01-10 19:03:21.738 plugin-container[1638:8028] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d3f, name = 'com.apple.tsm.portname' 19:03:21 INFO - PROCESS | 1633 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:03:21 INFO - PROCESS | 1633 | 2017-01-10 19:03:21.742 plugin-container[1638:8028] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x930b, name = 'com.apple.CFPasteboardClient' 19:03:21 INFO - PROCESS | 1633 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:03:21 INFO - PROCESS | 1633 | 2017-01-10 19:03:21.742 plugin-container[1638:8028] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:03:21 INFO - PROCESS | 1633 | __metrics Screen width/height:1600/1200 19:03:21 INFO - PROCESS | 1633 | colorDepth:24 19:03:21 INFO - PROCESS | 1633 | Browser inner width/height: 1024/689 19:03:21 INFO - PROCESS | 1633 | __metrics 19:03:21 ERROR - PROCESS | 1633 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3252: TypeError: this.tabs is undefined 19:03:22 INFO - TEST-INFO | 1633: exit 0 19:03:22 INFO - Browser initialized. 19:03:22 INFO - Running cycle 1/1 for dromaeo_css test... 19:03:22 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/tmpONY8N7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:03:25 INFO - PROCESS | 1639 | 2017-01-10 19:03:25.096 plugin-container[1640:8148] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 19:03:25 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:03:25 INFO - PROCESS | 1639 | 2017-01-10 19:03:25.097 plugin-container[1640:8148] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c17, name = 'com.apple.CFPasteboardClient' 19:03:25 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:03:25 INFO - PROCESS | 1639 | 2017-01-10 19:03:25.097 plugin-container[1640:8148] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:03:34 INFO - PROCESS | 1639 | RSS: Main: 232783872 19:03:34 INFO - PROCESS | 1639 | 19:06:37 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49244/tests/dromaeo/cssquery-ext.html) 19:06:39 INFO - PROCESS | 1639 | RSS: Main: 261292032 19:06:39 INFO - PROCESS | 1639 | 19:08:22 INFO - PROCESS | 1639 | ************************* 19:08:22 INFO - PROCESS | 1639 | A coding exception was thrown and uncaught in a Task. 19:08:22 INFO - PROCESS | 1639 | 19:08:22 INFO - PROCESS | 1639 | Full message: ReferenceError: fetch is not defined 19:08:22 INFO - PROCESS | 1639 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 19:08:22 INFO - PROCESS | 1639 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 19:08:22 INFO - PROCESS | 1639 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 19:08:22 INFO - PROCESS | 1639 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 19:08:22 INFO - PROCESS | 1639 | TaskImpl@resource://gre/modules/Task.jsm:277:3 19:08:22 INFO - PROCESS | 1639 | asyncFunction@resource://gre/modules/Task.jsm:252:14 19:08:22 INFO - PROCESS | 1639 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 19:08:22 INFO - PROCESS | 1639 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 19:08:22 INFO - PROCESS | 1639 | TaskImpl@resource://gre/modules/Task.jsm:277:3 19:08:22 INFO - PROCESS | 1639 | asyncFunction@resource://gre/modules/Task.jsm:252:14 19:08:22 INFO - PROCESS | 1639 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 19:08:22 INFO - PROCESS | 1639 | 19:08:22 INFO - PROCESS | 1639 | ************************* 19:09:41 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-ext.html (next: http://localhost:49244/tests/dromaeo/cssquery-jquery.html) 19:09:42 INFO - PROCESS | 1639 | RSS: Main: 263868416 19:09:42 INFO - PROCESS | 1639 | 19:12:44 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49244/tests/dromaeo/cssquery-mootools.html) 19:12:44 INFO - PROCESS | 1639 | RSS: Main: 272379904 19:12:44 INFO - PROCESS | 1639 | 19:15:46 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49244/tests/dromaeo/cssquery-prototype.html) 19:15:47 INFO - PROCESS | 1639 | RSS: Main: 268652544 19:15:47 INFO - PROCESS | 1639 | 19:18:50 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49244/tests/dromaeo/cssquery-yui.html) 19:18:50 INFO - PROCESS | 1639 | RSS: Main: 273264640 19:18:50 INFO - PROCESS | 1639 | 19:21:53 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-yui.html (next: http://localhost:49244/tests/dromaeo/cssquery-dojo.html) 19:21:53 INFO - PROCESS | 1639 | RSS: Main: 275607552 19:21:53 INFO - PROCESS | 1639 | 19:21:53 INFO - PROCESS | 1639 | __start_tp_report 19:21:53 INFO - PROCESS | 1639 | _x_x_mozilla_page_load 19:21:53 INFO - PROCESS | 1639 | _x_x_mozilla_page_load_details 19:21:53 INFO - PROCESS | 1639 | |i|pagename|runs| 19:21:53 INFO - PROCESS | 1639 | |0;dojo.html;3246.204679853436;3338.1153994191545;3374.6287908330078;3374.8481318340673;3380.9492857607133;4616.19216637088;5536;5547.306586676666;5574.1917421973785;5584.357798853133;5551.472610102044;5554.2779438672915;5630.605857589971;5633.577481688869;5726.026575482178;4813.903721925577;5106.64253502255;5173.068847607429;5269.420363759983;5269.710165940872;5075.670081444694;5210.114280572293;5266.868328291805;5274.630775845692;5308.362996440414;5061.493850614946;5106.693598384071;5194.480551944813;5210.713410762407;5215.191645294966;5156.9742151289;5159.355080614923;5159.432462429129;5173.663711858717;5180.067587834188;5082.542571168613;5094.1849304111165;5131.512506311932;5134.460881607415;5142.871428214287;4255.3829694694095;4307.526172121095;5466.234727138204;5707.6290041147195;5723.198752174699;6159.3224745277985;6194.070889366586;6205.87588248237;6214.65819379934;6217.191765070557;6209.3790620938;6281.497480201619;6303.464205542535;6305.5901366411035;6318.684065796738;5627.268455100811;5655.406182350876;5684.346300175492;5755.107958266475;5763.7982670606325;6192.225971753531;6316.431521163117;6319.431251187415;6334.081558174131;6335.986242201226;5203.219517072469;6023.728932197974;6129.111278864629;6150.923588372017;6160.10678451622;5871.6770577618645;6222.751089956441;6261.499080073583;6269.529785266123;6297.2128483939505;4716.221823399101;5916.3787802281;5924.8815023700145;6102.731632724008;6138.324784273726;5706.9429305707245;5781.046127389018;6202.751889924352;6244.313125556185;6254;4913.926291105637;4926.187179115477;4935.457099719028;4976.90046199074;4992.251162325608;3980.0049987503126;4169.791510424467;4348.9565104349185;4386.605205531518;4401.471823381215;4771.4512831024185;5772.797952071657;5823.213866128019;5848.444397782204;5852.970735146297;6254.061890716338;6269.937300627026;6274.121622972787;6297.968510157511;6298.7480500780375;5505.091659876076;5746.281714785652;5762.74067666956;5772.047612144033;5778.595498315161;4942.986687729007;5305.389880163753;5367.812126575551;5372.57019438444;5373.704446255493;5787.363390027093;6243.750249990131;6257.937420625826;6296.905546416716;6340.239171299474;5156.329677141947;6196.690165491617;6280.403361680633;6324.557280990471;6328.0824280478155;6323.177986861679;6336.20797400325;6361.045843123568;6376.713047912763;6400.679966001589;6076.605020673642;6095.359987201457;6110.083487476914;6126.30393285589;6167.290761562478;6016.985749244264;6119.624418755179;6139.26328840542;6155.72299246526;6230.563860529901;5329.44040875718;5353.839384818306;5771.884562308646;6014.248218972629;6056.333803281547;5254.448282930391;5708.229389032427;5759.308882934075;6048.576599637983;6064.181335519648;5665.20687103814;5714.228579141763;5714.228579141763;5784.161296612052;5853.268341457318;6005.279366476051;6079.878402432015;6095.664738439428;6096.359882212304;6125.264968203844;5862.501687356623;5879.735411906389;5884.235049443545;5899.410058994066;5930.7924222652 19:21:53 INFO - PROCESS | 1639 | |1;ext.html;432.3363636817485;484.215570775344;489.2514452887084;499.91251530982066;551.1457241276021;37101.81449092754;39258.41112383317;39323.606763932316;39333.81998540041;39341.409878851766;39537;40157.59842401575;40212.994675133064;40229.79310620679;40368.19263614726;8475.110113438093;8675.349348798865;8972.551372431384;9269.907300927005;11138.888611113907;11103.111751059936;11218.831717524243;11224.607138750145;11233.494492747805;11245.775084498306;11178.776424471547;11182.27315224512;11192.496337664825;11210.439478026066;11228;10080.395176289447;11150.442477876115;11241.437928103604;11275.49260283281;11277;11227.157963152715;11287.097032237483;11313.038391736713;11327.433628318551;11328.09375249978;39616.405753913714;39680.20639587192;39926;39954.80022599897;40083.799581001906;3981;3997.8800635980965;4019.155977244782;4057.97971010146;4136;30560.69439305601;31039.689603103907;31081.844590776902;31108.688913110804;31139.688603113904;3141.607299087614;3281.0812972367776;3327.15157634804;3332.4668053111386;3339.048371214217;359514;469542;471530.6423467895;473777;476014;58713.41286587165;58902;59058;59113.04434778244;59115.113273300936;60415.39584604186;61382.38617613856;61532.69233653803;61577.38422615716;61632.691836541424;14003.579892603238;14872.479463218735;15497.07017578949;15622.765658514903;15722.528324150293;9769.316147869724;10298.227632927561;11961.521539138337;12017.33904635236;12302.81545776814;11976.981956533793;12313.753724925451;13395.732085358239;15287.61780955485;15741.370345186064;5803.303603567514;5988.9401105989255;6823.590584564843;6899.55152915069;6970.51206415546;745.7054463486892;753.3445902065129;875.6191056890273;891.5007595746403;911.3529394130242;22970;29414.411711765646;29739.256518587208;30319.29432227606;30512.389752204832;905.5608030105394;925.4077390470104;1035.5184839049878;1046.8327814486793;1061.395004847245;13620.18278903269;14106.85893141076;14149.575512734635;14385.78421323681;14452.916031297695;538585;544333;545656;545730;644325;44838;46160.5383946163;50057.749711251214;50086;50285.74857125764;1384.9446022159;1417.5038736442164;1442.1419255542935;1444.5594093801492;1445.819272590905;21632.81019543877;24285.028598855846;26524.204273871816;26532;26701.465970680867;43708.34437483377;47947;48988.755056224494;49080.75459622679;51893.740531298616;582324;587649;589285;591101;592776;24135;24473.755262447146;24500.264992050266;24516.387090322885;24590.877045614656;31257.531137032507;38419.42370864495;40740.79629601833;41735.956601085214;43124.35313470363;35991;36096;36622;36666.083347915446;36849.63150368462;29269.560956585243;29665.11004669863;33442.66557334493;35554;36356;34226.802061927716;34949.12627184341;35245.82377088098;35324.47013294854;35645.465318019735;28655.57016644794;31236.687633124286;31495.685043149275;31584.842075789475;31691.84154079307;23547.0581176751;23655.40861478408;23793.881030594737;24059.51880962406;24084 19:21:53 INFO - PROCESS | 1639 | |2;jquery.html;354.0793935767006;371.10563541864116;392.7957462119698;399.94800675912126;406.70107471008805;19095.331663391768;20636.277730279442;20726.170953161873;20913.686294705592;20947.685784713223;20494.590108197826;20811;21052.57894842102;21261.57476850462;21282.46793830155;5455.399906010336;5719.45665161811;5841.532677385799;5913.881722365551;5946.435088666592;4139.627433530982;4375.124975004996;5815.302163740357;5926.4073592640825;6280.717367718441;6471.773487927923;6564.409203171714;6580.506462015344;6583.3087525809815;6590.736370545173;6366;6521.902171467408;6538.8692226155445;6579.309172536862;6598.538102332838;5681.914771278434;5687.914681279743;5746.109353050284;5746.367899531048;5767.048437007888;24268.514629707308;24367.903444345047;24721.887515061866;25414.49171016588;26043.73956260432;2378.452955820171;2776.7500924916653;2783.178962206154;2791.818531795447;2827.816191947517;7610.467267291292;8891.688790892287;15921.761173582405;15956.601084972852;16202.83797162025;1807.9457616271534;1826.9634607307912;1841.1530695879912;1852.8425083867887;1877.4367689693174;104288;134678;135285;137599;138248.54378640614;8255.463394879434;11854.585089521825;12004.879951200377;12018.278903265656;12114.575989840314;15213.923930380277;15508.379664813507;15516.224188790515;15528.456504022532;15572.299246533934;8391.244787969112;8500;8515.233628973423;8553.615087321086;8633.481991080555;8127.75616731499;8191.180881911761;8210.63052162642;8271.54506502136;8330.875036874451;4747.976260118747;4779.665423420397;4785.856424307276;4817.78319975602;4833.250846118857;1660.5101495058746;1752.2903224194222;2034.9796502035085;2074.626567217915;2076.6605539426987;952.8570714392758;975.1564896364736;999.9900000999897;1003.7102323514249;1067.3489171605315;17744.46766597004;19756.60486790285;20685.965701714853;20973.161073557192;21206.575868482545;1027.2244455436098;1028.5937054863437;1057.8571892794375;1060.3796778884284;1065.3607835298758;9952;10242;10709.429736863252;10997.936952470227;12416.81374779379;630083;666830;728343;738411.8476445716;752432.4756752471;122597;156948;162023;166178;166778;1013.4426065664003;1019.7807471393537;1050.7898420315967;1051.4374809476892;1054.1198099586777;15982.360705571647;19340.516487087934;19946;20079.29722459707;20119.994000299634;61737;62347.6882615584;63171;64311;64685;95022.52488737513;95719.52140239533;96113;97046;97298;5965.6122352048715;6008.609440386361;8210.753677389688;8236.423450358658;8463.53450560225;11060.17048721333;11368.204225704452;11720.88279117232;13002.94503920065;13053;10999.835002474809;11349.319040857576;11387.145964052563;11424.600138995096;11540.480678369326;10974.670759877217;11039.33763974132;11160.162987775788;11394.715132121763;11457.484413201259;10804.919508049446;11254.437278135896;11376;11576.710582235512;11668.824967625662;7316.487845850739;8382.874256886274;8469;8472;8481.109483504144;9593.472359020321;10202.591896324064;10883.204271295464;11436.256643318158;11447.656570302905 19:21:53 INFO - PROCESS | 1639 | |3;mootools.html;1437.9209143497103;1444.3211690505466;1464.6558058856167;1468.5520916120583;1477.313049432014;7485.663145158467;7699.114601820792;7980.281774640282;8438.409311348201;8465.19580639839;6848.82877928052;7861.253180947815;8159.5104293742415;8160.959195204031;8285;1967.8130577595105;2256.0862850545554;2297.850639708422;2301.838871279011;2304.5966955782756;1924.5573518090848;2268.7731226877345;2282.2582660635276;2307.8153747700144;2317.884105794712;1882.7364169016348;2283.1666441748725;2295.82781291403;2299.218265789631;2301.7007788987407;2261.547690461906;2265.2184996176456;2281.931542053741;2284.9885750571307;2289.519200967799;2254.5265494246228;2293.9197128100436;2293.954120917589;2306.723193216808;2333.97666023341;8349.83300333996;8401.70594029206;8407.83184336316;8426.115257897894;8429.283510901581;4841.588464980446;5008.7996480140755;5073.670211436245;5120.948790512084;5161.870953226162;3261.902142935716;3278.541004259406;3513.139280876176;3514.1566024154017;3514.3322768674125;1225.859026211988;1244.5270797097046;1701.2684545645368;1739.1304347826087;1816.4913824129264;8377.916220837835;10552.630657926935;10605.628802991936;10705.25063245573;10731;4510.188166130128;4686.27362758773;4686.437627484724;4701.3653156824075;4707.8587642370785;8537.146285371413;8630.180132887366;8642.611082501302;8658.264047556027;8667.523286219319;5086.338775959101;5097.235414687752;5106.923396148986;5117.257997590403;5138.97430512845;4595.540445955445;4660.3688708951595;4708.940488390153;4724.291356296583;4766.8797832509335;3134.6708595597133;3572.678458938708;3623.3296840084668;3682.5949145593963;3717.0893131182493;1004.7691577817261;1115.9888401116048;1121.9887801122047;1130.909527237819;1132.3149494555676;852.4459101584021;881.2421317666801;899.3541818472311;903.1420150856712;926.7822061815468;4892.730899800474;5343.866403339947;5552.7778888844805;5745.913811292834;5775.6823374714795;551.5091568504034;568.2726110578466;569.1178673056828;574.0241589298209;587.8765459253519;3345.3142105868724;3619.4208926571623;3627.6758982971555;3907.9218415632095;4038.91922161561;9184.81630367389;9214.585343659552;9538.809223815484;9771.21830253578;9807.852882206773;9676.983916688787;9689.515524223902;9706.660266890625;9714.12572868431;9778.07108324708;5613.943860561424;7589.58257295854;7669.654865531064;7778.338841198448;7780.377569794403;5766.509846663081;5846;5889.175515427758;5913.556483263858;6010.819675409744;9200.401973871945;9203.677871274473;9251.398659087137;9261.120193581599;9280;8448.619812108347;8738.606762695566;8741.956290218763;9360.672376466791;9376.95311523438;5833.9416605835095;5870.295564532113;6111.480524155497;7158.498905076803;7181.569105853666;7818.569978651229;7884.172161922916;7951.801204969691;7964.641591128529;7967.28294453502;7130.18002929649;7146.749863754952;7157.105361829771;7189.317014883578;7285.453590980592;6247.250329960434;6958.304169583002;7023.473239506955;7085.610291434021;7139.535930164523;6317.273513546001;6363.681815909279;6404.071409645482;6422.2614399344675;6429.324920883427;4866.124097662313;4895.069936712013;4950.9257361140335;5010.724410157476;5112.182050871843;4422.739519237002;4960.156773348612;5024.748762561931;5059.671121377099;5202.089634313904 19:21:53 INFO - PROCESS | 1639 | |4;prototype.html;357.6941714833817;359.9820008999549;370.56088535085917;372.1422122008772;374.1656106881654;4415.3376993451;4771.475137734851;4839.120723169713;5337.4662533746605;5372.650777699447;4777.450593181785;5124.590032797377;5382.67703937764;5405.162199859018;5491.945080549193;3728.5339332583426;3734.346489364364;4245.320748680212;4246.426732391134;4281.529031806499;3290.4899740540254;3431.953254257463;4189.706720529549;4288.463942007249;4290.957090429087;3243.724283435911;4077.3272410052305;4082.2243773683053;4104.876853694379;4109.979450102745;3403.2512847173584;4076;4103.2409004334295;4105.794710264505;4108.52751933527;2886.942261154765;3338.914852672891;3559.0212691509782;3569.089882080079;3576.0344706929245;4669.509701481364;4894.657373983787;5284.10170271051;5320;5393.973030134864;4731.604176767827;4758.3576217210575;4776.80892764289;4786.497417771118;4790.137775200463;5424.511793938565;5547.86268814892;5826.611089453346;5853.326867410217;5860.267466566679;3815.8282877270312;3877.5734669186368;3922.6077392260545;3944.960550394488;3947;5513.365962914236;5551;5797.449242321973;6117.724702388403;6153.846153846145;3957.3470377387985;3985.442038114666;3997.060690737674;4000.6399424051974;4040.1717647882115;3732.4774531565604;3919.3141200290406;4005.8597949072227;4038.899027524277;4048.3320252158064;4714.57568818808;4714.929276060861;4743.407074115736;4743.525647435298;4795.520447955247;5810;5870.501007414332;5883.911741323884;5922.3485416604135;5964;1046.5604446132643;1084.7017070305571;1242.7887259165782;1263.368315842079;1309.4173092973535;741.2372736457601;752.1500704204215;758.6548120605112;872.4154816273112;881.1716986033108;5128.564072053917;5146.58110687976;5146.8640514186945;5172.637915345889;5239.851660819046;4901.877453063702;5336.332958380202;5684.870634991918;5688.829335119953;5695.572832037614;4678.274867395559;4885.5358740920265;5111.642185047085;5112.948870511322;5161.716105614152;2623.737626237361;2734.480448714738;2806.6070750095005;2858.3140046389135;3039.6656367799524;5928.881422371528;5933.732982015819;5939.673317967553;5939.851503712442;5947.732352044169;5052.595792336604;5283.392409872837;5436.809711660073;5879.52963762898;5888.61723987948;5189.584833213409;5899.436649287942;5911.349751527328;5911.911321330097;5989.610675306091;4887.584555312838;4957.752112394293;5596.916046259391;5669.943300566942;5693.715314234189;4699.402203250911;4699.600533954515;4827.565519103157;5037.471065538066;5635.718214089197;5161.793528258828;5465.770201704744;5650.773969041356;5694.572907032072;6003.849903752441;3201.6158061032825;3599.874004409833;3742.419924911534;3790.298794722985;3852.788096654711;4758.501786562083;5208.967893343955;5584.553235741049;5603.719814009365;5721.656700597978;4700;4946.689127381319;4954.504549545017;5676.77292908279;5795;5750.9712451439145;5756.740946657326;5771.538276937751;5805.970970145122;5839.445252700986;5051.8380772421815;5280.973595132;5287.762050707651;5379.220013098157;5508.834734957797;5029.421616514148;5192.883530040908;5238.921416178837;5498.285222920995;5789.797357092481;4817.710937343771;5200.791968321225;5577.470140336662;5580.2466666999435;5676.801311954227 19:21:53 INFO - PROCESS | 1639 | |5;yui.html;709.8970649255862;717.616075399661;739.5267029101375;776.2974508070195;805.3516918880301;14660.193689347096;15495.84504154958;15535.145566993824;15548.922255388736;15558.922205388972;14467.710645787078;14575.781363279559;14609.853901460981;14633.853661463409;14690.265486725675;667.883120453921;695.151914664109;815.5759005317232;848.9278411335044;858.090424150401;859.2352806002663;860.8923884514436;862.8015556422026;870.3472395703222;870.9477431354107;891.3671293381674;909.2044461096541;920.1442658327752;920.3189639666665;921.6313474610142;904.5206040798388;909.7725568607782;910.4764760262842;911.4804561400005;912.2428384440898;677.7492327838681;683.6205905722322;684.449018540075;684.5139950635055;686.5708931917552;12403.255804651659;15011.849881501154;15032.473863414838;15034;15362.846371536365;14306.92846535771;14533.05535140223;14607.269636518238;14621.488247911271;14845.406183752637;11287.322760634388;11669.533218671242;11846.17076804624;11876.049916006785;11969.820452693217;3878.5151856017997;4530.411046563959;4558.863234102949;4596.4254468191475;4615.376924115235;86625;116148.41925790317;117356;119900.40049799782;121417;10586.682399528027;10625.628103016357;10655.520501577448;10682.03861652455;10941;20773.961301935145;20820.479488012617;20823.479413014797;20865.85237811935;20903.163873444893;7507.1366792818435;7528.811779705442;7542.886856697044;7712.997310349619;7754.806129846799;4231.344141658048;4243.936340954888;4270.7864606769535;4322.913541729211;4399.36209249657;3407.131181548739;3485.35520928629;3486.2156014896445;3487.093355727529;3494.0740703713536;629.8584811640368;637.2671427858;639.1148259660404;643.6781609195402;650.9283978762332;636.522608043967;638.6200210874522;644.6647743173618;647.5893906411955;658.8221180281394;7931.929189559336;8204.507729536246;8423.241908228289;9079.45523268606;9689.951550242204;601.6570554783818;619.6684773646168;621.7916997805695;658.1312667279241;666.8596699643567;3854.8650797221962;4113.526944401372;4532;4685.1800934836265;4713.717176969393;86646.56676716702;112482.87517124887;114087.85912140939;115256;116097.8390216087;71876;95358;100365.4945175823;101214.49392753137;103840.96159038464;88264;89159.55420222988;89969;90079.09920900837;92173.53913230391;8258.752237432887;8848.247898928663;9017.954910225408;9239.260859131387;9252.768680783034;50460.747696261285;53754.73122634362;55150;55398;56408.71795640996;73544.63227683827;74833.25166748263;76189;76195.61902190454;78450.21549784657;10961.890381096087;11056.391898445663;11229.21395502307;11328.830067549157;11476.885231147582;11990.880091198977;12080.51677932873;12121.212121212093;12141.635750927486;12301.384930753606;11746.823797643214;11855.881441185478;12131.575394861138;12242;12288.877111228774;11154.386508742096;11164.49759760829;11221.943890280823;11537.394200083132;11639.41802909868;11507.136964727846;11512.481938312629;11514.481848317013;11745.060395168537;11800.17398782077;9429.575669095044;9950.45272510019;9965.900340996497;9966.700998970042;10068.69793906184;9326.860097098684;9518.286128540249;11178;11479.4260286987;11570.59502917394 19:21:53 INFO - PROCESS | 1639 | __end_tp_report 19:21:53 INFO - PROCESS | 1639 | __start_cc_report 19:21:53 INFO - PROCESS | 1639 | _x_x_mozilla_cycle_collect,395 19:21:53 INFO - PROCESS | 1639 | __end_cc_report 19:21:53 INFO - PROCESS | 1639 | __startTimestamp1484104913604__endTimestamp 19:21:53 INFO - PROCESS | 1639 | 19:21:53 INFO - PROCESS | 1639 | ------- Summary: start ------- 19:21:53 INFO - PROCESS | 1639 | Number of tests: 6 19:21:53 INFO - PROCESS | 1639 | 19:21:53 INFO - PROCESS | 1639 | [#0] dojo.html Cycles:165 Average:5628.44 Median:5772.05 stddev:677.25 (11.7%) stddev-sans-first:653.03 19:21:53 INFO - PROCESS | 1639 | Values: 3246.2 3338.1 3374.6 3374.8 3380.9 4616.2 5536.0 5547.3 5574.2 5584.4 5551.5 5554.3 5630.6 5633.6 5726.0 4813.9 5106.6 5173.1 5269.4 5269.7 5075.7 5210.1 5266.9 5274.6 5308.4 5061.5 5106.7 5194.5 5210.7 5215.2 5157.0 5159.4 5159.4 5173.7 5180.1 5082.5 5094.2 5131.5 5134.5 5142.9 4255.4 4307.5 5466.2 5707.6 5723.2 6159.3 6194.1 6205.9 6214.7 6217.2 6209.4 6281.5 6303.5 6305.6 6318.7 5627.3 5655.4 5684.3 5755.1 5763.8 6192.2 6316.4 6319.4 6334.1 6336.0 5203.2 6023.7 6129.1 6150.9 6160.1 5871.7 6222.8 6261.5 6269.5 6297.2 4716.2 5916.4 5924.9 6102.7 6138.3 5706.9 5781.0 6202.8 6244.3 6254.0 4913.9 4926.2 4935.5 4976.9 4992.3 3980.0 4169.8 4349.0 4386.6 4401.5 4771.5 5772.8 5823.2 5848.4 5853.0 6254.1 6269.9 6274.1 6298.0 6298.7 5505.1 5746.3 5762.7 5772.0 5778.6 4943.0 5305.4 5367.8 5372.6 5373.7 5787.4 6243.8 6257.9 6296.9 6340.2 5156.3 6196.7 6280.4 6324.6 6328.1 6323.2 6336.2 6361.0 6376.7 6400.7 6076.6 6095.4 6110.1 6126.3 6167.3 6017.0 6119.6 6139.3 6155.7 6230.6 5329.4 5353.8 5771.9 6014.2 6056.3 5254.4 5708.2 5759.3 6048.6 6064.2 5665.2 5714.2 5714.2 5784.2 5853.3 6005.3 6079.9 6095.7 6096.4 6125.3 5862.5 5879.7 5884.2 5899.4 5930.8 19:21:53 INFO - PROCESS | 1639 | 19:21:53 INFO - PROCESS | 1639 | [#1] ext.html Cycles:180 Average:65871.77 Median:35439.24 stddev:143975.68 (406.3%) stddev-sans-first:144295.74 19:21:53 INFO - PROCESS | 1639 | Values: 432.3 484.2 489.3 499.9 551.1 37101.8 39258.4 39323.6 39333.8 39341.4 39537.0 40157.6 40213.0 40229.8 40368.2 8475.1 8675.3 8972.6 9269.9 11138.9 11103.1 11218.8 11224.6 11233.5 11245.8 11178.8 11182.3 11192.5 11210.4 11228.0 10080.4 11150.4 11241.4 11275.5 11277.0 11227.2 11287.1 11313.0 11327.4 11328.1 39616.4 39680.2 39926.0 39954.8 40083.8 3981.0 3997.9 4019.2 4058.0 4136.0 30560.7 31039.7 31081.8 31108.7 31139.7 3141.6 3281.1 3327.2 3332.5 3339.0 359514.0 469542.0 471530.6 473777.0 476014.0 58713.4 58902.0 59058.0 59113.0 59115.1 60415.4 61382.4 61532.7 61577.4 61632.7 14003.6 14872.5 15497.1 15622.8 15722.5 9769.3 10298.2 11961.5 12017.3 12302.8 11977.0 12313.8 13395.7 15287.6 15741.4 5803.3 5988.9 6823.6 6899.6 6970.5 745.7 753.3 875.6 891.5 911.4 22970.0 29414.4 29739.3 30319.3 30512.4 905.6 925.4 1035.5 1046.8 1061.4 13620.2 14106.9 14149.6 14385.8 14452.9 538585.0 544333.0 545656.0 545730.0 644325.0 44838.0 46160.5 50057.7 50086.0 50285.7 1384.9 1417.5 1442.1 1444.6 1445.8 21632.8 24285.0 26524.2 26532.0 26701.5 43708.3 47947.0 48988.8 49080.8 51893.7 582324.0 587649.0 589285.0 591101.0 592776.0 24135.0 24473.8 24500.3 24516.4 24590.9 31257.5 38419.4 40740.8 41736.0 43124.4 35991.0 36096.0 36622.0 36666.1 36849.6 29269.6 29665.1 33442.7 35554.0 36356.0 34226.8 34949.1 35245.8 35324.5 35645.5 28655.6 31236.7 31495.7 31584.8 31691.8 23547.1 23655.4 23793.9 24059.5 24084.0 19:21:53 INFO - PROCESS | 1639 | 19:21:53 INFO - PROCESS | 1639 | [#2] jquery.html Cycles:180 Average:39925.35 Median:21272.02 stddev:117941.41 (554.4%) stddev-sans-first:118234.84 19:21:53 INFO - PROCESS | 1639 | Values: 354.1 371.1 392.8 399.9 406.7 19095.3 20636.3 20726.2 20913.7 20947.7 20494.6 20811.0 21052.6 21261.6 21282.5 5455.4 5719.5 5841.5 5913.9 5946.4 4139.6 4375.1 5815.3 5926.4 6280.7 6471.8 6564.4 6580.5 6583.3 6590.7 6366.0 6521.9 6538.9 6579.3 6598.5 5681.9 5687.9 5746.1 5746.4 5767.0 24268.5 24367.9 24721.9 25414.5 26043.7 2378.5 2776.8 2783.2 2791.8 2827.8 7610.5 8891.7 15921.8 15956.6 16202.8 1807.9 1827.0 1841.2 1852.8 1877.4 104288.0 134678.0 135285.0 137599.0 138248.5 8255.5 11854.6 12004.9 12018.3 12114.6 15213.9 15508.4 15516.2 15528.5 15572.3 8391.2 8500.0 8515.2 8553.6 8633.5 8127.8 8191.2 8210.6 8271.5 8330.9 4748.0 4779.7 4785.9 4817.8 4833.3 1660.5 1752.3 2035.0 2074.6 2076.7 952.9 975.2 1000.0 1003.7 1067.3 17744.5 19756.6 20686.0 20973.2 21206.6 1027.2 1028.6 1057.9 1060.4 1065.4 9952.0 10242.0 10709.4 10997.9 12416.8 630083.0 666830.0 728343.0 738411.8 752432.5 122597.0 156948.0 162023.0 166178.0 166778.0 1013.4 1019.8 1050.8 1051.4 1054.1 15982.4 19340.5 19946.0 20079.3 20120.0 61737.0 62347.7 63171.0 64311.0 64685.0 95022.5 95719.5 96113.0 97046.0 97298.0 5965.6 6008.6 8210.8 8236.4 8463.5 11060.2 11368.2 11720.9 13002.9 13053.0 10999.8 11349.3 11387.1 11424.6 11540.5 10974.7 11039.3 11160.2 11394.7 11457.5 10804.9 11254.4 11376.0 11576.7 11668.8 7316.5 8382.9 8469.0 8472.0 8481.1 9593.5 10202.6 10883.2 11436.3 11447.7 19:21:53 INFO - PROCESS | 1639 | 19:21:53 INFO - PROCESS | 1639 | [#3] mootools.html Cycles:180 Average:5300.11 Median:2947.69 stddev:2858.30 (97.0%) stddev-sans-first:2851.58 19:21:53 INFO - PROCESS | 1639 | Values: 1437.9 1444.3 1464.7 1468.6 1477.3 7485.7 7699.1 7980.3 8438.4 8465.2 6848.8 7861.3 8159.5 8161.0 8285.0 1967.8 2256.1 2297.9 2301.8 2304.6 1924.6 2268.8 2282.3 2307.8 2317.9 1882.7 2283.2 2295.8 2299.2 2301.7 2261.5 2265.2 2281.9 2285.0 2289.5 2254.5 2293.9 2294.0 2306.7 2334.0 8349.8 8401.7 8407.8 8426.1 8429.3 4841.6 5008.8 5073.7 5120.9 5161.9 3261.9 3278.5 3513.1 3514.2 3514.3 1225.9 1244.5 1701.3 1739.1 1816.5 8377.9 10552.6 10605.6 10705.3 10731.0 4510.2 4686.3 4686.4 4701.4 4707.9 8537.1 8630.2 8642.6 8658.3 8667.5 5086.3 5097.2 5106.9 5117.3 5139.0 4595.5 4660.4 4708.9 4724.3 4766.9 3134.7 3572.7 3623.3 3682.6 3717.1 1004.8 1116.0 1122.0 1130.9 1132.3 852.4 881.2 899.4 903.1 926.8 4892.7 5343.9 5552.8 5745.9 5775.7 551.5 568.3 569.1 574.0 587.9 3345.3 3619.4 3627.7 3907.9 4038.9 9184.8 9214.6 9538.8 9771.2 9807.9 9677.0 9689.5 9706.7 9714.1 9778.1 5613.9 7589.6 7669.7 7778.3 7780.4 5766.5 5846.0 5889.2 5913.6 6010.8 9200.4 9203.7 9251.4 9261.1 9280.0 8448.6 8738.6 8742.0 9360.7 9377.0 5833.9 5870.3 6111.5 7158.5 7181.6 7818.6 7884.2 7951.8 7964.6 7967.3 7130.2 7146.7 7157.1 7189.3 7285.5 6247.3 6958.3 7023.5 7085.6 7139.5 6317.3 6363.7 6404.1 6422.3 6429.3 4866.1 4895.1 4950.9 5010.7 5112.2 4422.7 4960.2 5024.7 5059.7 5202.1 19:21:53 INFO - PROCESS | 1639 | 19:21:53 INFO - PROCESS | 1639 | [#4] prototype.html Cycles:180 Average:4535.38 Median:5044.65 stddev:1405.83 (27.9%) stddev-sans-first:1374.36 19:21:53 INFO - PROCESS | 1639 | Values: 357.7 360.0 370.6 372.1 374.2 4415.3 4771.5 4839.1 5337.5 5372.7 4777.5 5124.6 5382.7 5405.2 5491.9 3728.5 3734.3 4245.3 4246.4 4281.5 3290.5 3432.0 4189.7 4288.5 4291.0 3243.7 4077.3 4082.2 4104.9 4110.0 3403.3 4076.0 4103.2 4105.8 4108.5 2886.9 3338.9 3559.0 3569.1 3576.0 4669.5 4894.7 5284.1 5320.0 5394.0 4731.6 4758.4 4776.8 4786.5 4790.1 5424.5 5547.9 5826.6 5853.3 5860.3 3815.8 3877.6 3922.6 3945.0 3947.0 5513.4 5551.0 5797.4 6117.7 6153.8 3957.3 3985.4 3997.1 4000.6 4040.2 3732.5 3919.3 4005.9 4038.9 4048.3 4714.6 4714.9 4743.4 4743.5 4795.5 5810.0 5870.5 5883.9 5922.3 5964.0 1046.6 1084.7 1242.8 1263.4 1309.4 741.2 752.2 758.7 872.4 881.2 5128.6 5146.6 5146.9 5172.6 5239.9 4901.9 5336.3 5684.9 5688.8 5695.6 4678.3 4885.5 5111.6 5112.9 5161.7 2623.7 2734.5 2806.6 2858.3 3039.7 5928.9 5933.7 5939.7 5939.9 5947.7 5052.6 5283.4 5436.8 5879.5 5888.6 5189.6 5899.4 5911.3 5911.9 5989.6 4887.6 4957.8 5596.9 5669.9 5693.7 4699.4 4699.6 4827.6 5037.5 5635.7 5161.8 5465.8 5650.8 5694.6 6003.8 3201.6 3599.9 3742.4 3790.3 3852.8 4758.5 5209.0 5584.6 5603.7 5721.7 4700.0 4946.7 4954.5 5676.8 5795.0 5751.0 5756.7 5771.5 5806.0 5839.4 5051.8 5281.0 5287.8 5379.2 5508.8 5029.4 5192.9 5238.9 5498.3 5789.8 4817.7 5200.8 5577.5 5580.2 5676.8 19:21:53 INFO - PROCESS | 1639 | 19:21:53 INFO - PROCESS | 1639 | [#5] yui.html Cycles:180 Average:21265.29 Median:4577.64 stddev:32187.01 (703.1%) stddev-sans-first:32240.29 19:21:53 INFO - PROCESS | 1639 | Values: 709.9 717.6 739.5 776.3 805.4 14660.2 15495.8 15535.1 15548.9 15558.9 14467.7 14575.8 14609.9 14633.9 14690.3 667.9 695.2 815.6 848.9 858.1 859.2 860.9 862.8 870.3 870.9 891.4 909.2 920.1 920.3 921.6 904.5 909.8 910.5 911.5 912.2 677.7 683.6 684.4 684.5 686.6 12403.3 15011.8 15032.5 15034.0 15362.8 14306.9 14533.1 14607.3 14621.5 14845.4 11287.3 11669.5 11846.2 11876.0 11969.8 3878.5 4530.4 4558.9 4596.4 4615.4 86625.0 116148.4 117356.0 119900.4 121417.0 10586.7 10625.6 10655.5 10682.0 10941.0 20774.0 20820.5 20823.5 20865.9 20903.2 7507.1 7528.8 7542.9 7713.0 7754.8 4231.3 4243.9 4270.8 4322.9 4399.4 3407.1 3485.4 3486.2 3487.1 3494.1 629.9 637.3 639.1 643.7 650.9 636.5 638.6 644.7 647.6 658.8 7931.9 8204.5 8423.2 9079.5 9690.0 601.7 619.7 621.8 658.1 666.9 3854.9 4113.5 4532.0 4685.2 4713.7 86646.6 112482.9 114087.9 115256.0 116097.8 71876.0 95358.0 100365.5 101214.5 103841.0 88264.0 89159.6 89969.0 90079.1 92173.5 8258.8 8848.2 9018.0 9239.3 9252.8 50460.7 53754.7 55150.0 55398.0 56408.7 73544.6 74833.3 76189.0 76195.6 78450.2 10961.9 11056.4 11229.2 11328.8 11476.9 11990.9 12080.5 12121.2 12141.6 12301.4 11746.8 11855.9 12131.6 12242.0 12288.9 11154.4 11164.5 11221.9 11537.4 11639.4 11507.1 11512.5 11514.5 11745.1 11800.2 9429.6 9950.5 9965.9 9966.7 10068.7 9326.9 9518.3 11178.0 11479.4 11570.6 19:21:53 INFO - PROCESS | 1639 | -------- Summary: end -------- 19:21:53 INFO - PROCESS | 1639 | 19:21:54 INFO - TEST-INFO | 1639: exit 0 19:21:54 INFO - TEST-OK | dromaeo_css | took 1115771ms 19:21:54 INFO - TEST-START | kraken 19:21:54 INFO - operating with platform_type : mac_ 19:21:54 INFO - Initialising browser for kraken test... 19:21:54 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/tmpJLKb9d/profile http://localhost:49244/getInfo.html) 19:21:57 INFO - PROCESS | 1647 | 2017-01-10 19:21:57.071 plugin-container[1648:10348] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b3b, name = 'com.apple.tsm.portname' 19:21:57 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:21:57 INFO - PROCESS | 1647 | 2017-01-10 19:21:57.074 plugin-container[1648:10348] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 19:21:57 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:21:57 INFO - PROCESS | 1647 | 2017-01-10 19:21:57.074 plugin-container[1648:10348] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:21:57 INFO - PROCESS | 1647 | __metrics Screen width/height:1600/1200 19:21:57 INFO - PROCESS | 1647 | colorDepth:24 19:21:57 INFO - PROCESS | 1647 | Browser inner width/height: 1024/689 19:21:57 INFO - PROCESS | 1647 | __metrics 19:21:57 ERROR - PROCESS | 1647 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3252: TypeError: this.tabs is undefined 19:21:57 INFO - TEST-INFO | 1647: exit 0 19:21:57 INFO - Browser initialized. 19:21:57 INFO - Running cycle 1/1 for kraken test... 19:21:57 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/tmpJLKb9d/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:22:00 INFO - PROCESS | 1649 | 2017-01-10 19:22:00.205 plugin-container[1650:10466] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 19:22:00 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:22:00 INFO - PROCESS | 1649 | 2017-01-10 19:22:00.206 plugin-container[1650:10466] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4723, name = 'com.apple.CFPasteboardClient' 19:22:00 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:22:00 INFO - PROCESS | 1649 | 2017-01-10 19:22:00.206 plugin-container[1650:10466] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:22:10 INFO - PROCESS | 1649 | RSS: Main: 233521152 19:22:10 INFO - PROCESS | 1649 | 19:22:13 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-beat-detection) 19:22:14 INFO - PROCESS | 1649 | RSS: Main: 246108160 19:22:14 INFO - PROCESS | 1649 | 19:22:16 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-dft) 19:22:17 INFO - PROCESS | 1649 | RSS: Main: 253665280 19:22:17 INFO - PROCESS | 1649 | 19:22:20 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-fft) 19:22:21 INFO - PROCESS | 1649 | RSS: Main: 256212992 19:22:21 INFO - PROCESS | 1649 | 19:22:23 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-oscillator) 19:22:23 INFO - PROCESS | 1649 | RSS: Main: 258449408 19:22:23 INFO - PROCESS | 1649 | 19:22:25 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:22:26 INFO - PROCESS | 1649 | RSS: Main: 258826240 19:22:26 INFO - PROCESS | 1649 | 19:22:29 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-darkroom) 19:22:30 INFO - PROCESS | 1649 | RSS: Main: 264138752 19:22:30 INFO - PROCESS | 1649 | 19:22:33 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-desaturate) 19:22:33 INFO - PROCESS | 1649 | RSS: Main: 264228864 19:22:33 INFO - PROCESS | 1649 | 19:22:36 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49244/tests/kraken/driver.html?testName=json-parse-financial) 19:22:37 INFO - PROCESS | 1649 | RSS: Main: 263880704 19:22:37 INFO - PROCESS | 1649 | 19:22:39 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49244/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:22:39 INFO - PROCESS | 1649 | RSS: Main: 263217152 19:22:39 INFO - PROCESS | 1649 | 19:22:41 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:22:42 INFO - PROCESS | 1649 | RSS: Main: 263266304 19:22:42 INFO - PROCESS | 1649 | 19:22:44 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:22:45 INFO - PROCESS | 1649 | RSS: Main: 263405568 19:22:45 INFO - PROCESS | 1649 | 19:22:48 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:22:49 INFO - PROCESS | 1649 | RSS: Main: 263622656 19:22:49 INFO - PROCESS | 1649 | 19:22:52 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:22:52 INFO - PROCESS | 1649 | RSS: Main: 272179200 19:22:52 INFO - PROCESS | 1649 | 19:22:54 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49244/tests/kraken/driver.html?testName=ai-astar) 19:22:54 INFO - PROCESS | 1649 | RSS: Main: 273723392 19:22:54 INFO - PROCESS | 1649 | 19:22:54 INFO - PROCESS | 1649 | __start_tp_report 19:22:54 INFO - PROCESS | 1649 | _x_x_mozilla_page_load 19:22:54 INFO - PROCESS | 1649 | _x_x_mozilla_page_load_details 19:22:54 INFO - PROCESS | 1649 | |i|pagename|runs| 19:22:54 INFO - PROCESS | 1649 | |0;ai-astar;140;104;169;172;170;117;118;119;133;175 19:22:54 INFO - PROCESS | 1649 | |1;audio-beat-detection;144;113;110;164;112;115;115;113;131;114 19:22:54 INFO - PROCESS | 1649 | |2;audio-dft;199;200;250;276;226;195;195;197;193;196 19:22:54 INFO - PROCESS | 1649 | |3;audio-fft;87;54;64;109;58;82;53;74;95;53 19:22:54 INFO - PROCESS | 1649 | |4;audio-oscillator;81;57;81;80;105;101;112;72;71;83 19:22:54 INFO - PROCESS | 1649 | |5;imaging-gaussian-blur;80;133;139;88;88;114;90;91;137;85 19:22:54 INFO - PROCESS | 1649 | |6;imaging-darkroom;95;116;154;106;95;95;111;95;95;96 19:22:54 INFO - PROCESS | 1649 | |7;imaging-desaturate;90;92;92;127;98;124;154;98;107;152 19:22:54 INFO - PROCESS | 1649 | |8;json-parse-financial;61;60;62;61;62;63;62;64;63;62 19:22:54 INFO - PROCESS | 1649 | |9;json-stringify-tinderbox;68;74;82;81;73;69;71;70;72;85 19:22:54 INFO - PROCESS | 1649 | |10;stanford-crypto-aes;88;86;92;86;105;113;109;102;88;87 19:22:54 INFO - PROCESS | 1649 | |11;stanford-crypto-ccm;139;167;156;151;135;140;143;164;221;187 19:22:54 INFO - PROCESS | 1649 | |12;stanford-crypto-pbkdf2;178;163;214;237;208;174;174;175;174;174 19:22:54 INFO - PROCESS | 1649 | |13;stanford-crypto-sha256-iterative;67;69;67;69;70;66;70;67;66;68 19:22:54 INFO - PROCESS | 1649 | __end_tp_report 19:22:54 INFO - PROCESS | 1649 | __start_cc_report 19:22:54 INFO - PROCESS | 1649 | _x_x_mozilla_cycle_collect,924 19:22:54 INFO - PROCESS | 1649 | __end_cc_report 19:22:54 INFO - PROCESS | 1649 | __startTimestamp1484104974805__endTimestamp 19:22:54 INFO - PROCESS | 1649 | 19:22:54 INFO - PROCESS | 1649 | ------- Summary: start ------- 19:22:54 INFO - PROCESS | 1649 | Number of tests: 14 19:22:54 INFO - PROCESS | 1649 | 19:22:54 INFO - PROCESS | 1649 | [#0] ai-astar Cycles:10 Average:141.70 Median:154.50 stddev:27.41 (17.7%) stddev-sans-first:29.06 19:22:54 INFO - PROCESS | 1649 | Values: 140.0 104.0 169.0 172.0 170.0 117.0 118.0 119.0 133.0 175.0 19:22:54 INFO - PROCESS | 1649 | 19:22:54 INFO - PROCESS | 1649 | [#1] audio-beat-detection Cycles:10 Average:123.10 Median:115.00 stddev:17.90 (15.6%) stddev-sans-first:17.32 19:22:54 INFO - PROCESS | 1649 | Values: 144.0 113.0 110.0 164.0 112.0 115.0 115.0 113.0 131.0 114.0 19:22:54 INFO - PROCESS | 1649 | 19:22:54 INFO - PROCESS | 1649 | [#2] audio-dft Cycles:10 Average:212.70 Median:199.50 stddev:28.80 (14.4%) stddev-sans-first:30.12 19:22:54 INFO - PROCESS | 1649 | Values: 199.0 200.0 250.0 276.0 226.0 195.0 195.0 197.0 193.0 196.0 19:22:54 INFO - PROCESS | 1649 | 19:22:54 INFO - PROCESS | 1649 | [#3] audio-fft Cycles:10 Average:72.90 Median:69.00 stddev:19.79 (28.7%) stddev-sans-first:20.32 19:22:54 INFO - PROCESS | 1649 | Values: 87.0 54.0 64.0 109.0 58.0 82.0 53.0 74.0 95.0 53.0 19:22:54 INFO - PROCESS | 1649 | 19:22:54 INFO - PROCESS | 1649 | [#4] audio-oscillator Cycles:10 Average:84.30 Median:76.00 stddev:16.96 (22.3%) stddev-sans-first:17.95 19:22:54 INFO - PROCESS | 1649 | Values: 81.0 57.0 81.0 80.0 105.0 101.0 112.0 72.0 71.0 83.0 19:22:54 INFO - PROCESS | 1649 | 19:22:54 INFO - PROCESS | 1649 | [#5] imaging-gaussian-blur Cycles:10 Average:104.50 Median:86.50 stddev:23.73 (27.4%) stddev-sans-first:23.45 19:22:54 INFO - PROCESS | 1649 | Values: 80.0 133.0 139.0 88.0 88.0 114.0 90.0 91.0 137.0 85.0 19:22:54 INFO - PROCESS | 1649 | 19:22:54 INFO - PROCESS | 1649 | [#6] imaging-darkroom Cycles:10 Average:105.80 Median:95.00 stddev:18.66 (19.6%) stddev-sans-first:19.38 19:22:54 INFO - PROCESS | 1649 | Values: 95.0 116.0 154.0 106.0 95.0 95.0 111.0 95.0 95.0 96.0 19:22:54 INFO - PROCESS | 1649 | 19:22:54 INFO - PROCESS | 1649 | [#7] imaging-desaturate Cycles:10 Average:113.40 Median:91.00 stddev:24.53 (27.0%) stddev-sans-first:24.51 19:22:54 INFO - PROCESS | 1649 | Values: 90.0 92.0 92.0 127.0 98.0 124.0 154.0 98.0 107.0 152.0 19:22:54 INFO - PROCESS | 1649 | 19:22:54 INFO - PROCESS | 1649 | [#8] json-parse-financial Cycles:10 Average:62.00 Median:62.00 stddev:1.15 (1.9%) stddev-sans-first:1.17 19:22:54 INFO - PROCESS | 1649 | Values: 61.0 60.0 62.0 61.0 62.0 63.0 62.0 64.0 63.0 62.0 19:22:54 INFO - PROCESS | 1649 | 19:22:54 INFO - PROCESS | 1649 | [#9] json-stringify-tinderbox Cycles:10 Average:74.50 Median:73.50 stddev:5.99 (8.1%) stddev-sans-first:5.87 19:22:54 INFO - PROCESS | 1649 | Values: 68.0 74.0 82.0 81.0 73.0 69.0 71.0 70.0 72.0 85.0 19:22:54 INFO - PROCESS | 1649 | 19:22:54 INFO - PROCESS | 1649 | [#10] stanford-crypto-aes Cycles:10 Average:95.60 Median:86.50 stddev:10.53 (12.2%) stddev-sans-first:10.81 19:22:54 INFO - PROCESS | 1649 | Values: 88.0 86.0 92.0 86.0 105.0 113.0 109.0 102.0 88.0 87.0 19:22:54 INFO - PROCESS | 1649 | 19:22:54 INFO - PROCESS | 1649 | [#11] stanford-crypto-ccm Cycles:10 Average:160.30 Median:160.00 stddev:26.60 (16.6%) stddev-sans-first:27.07 19:22:54 INFO - PROCESS | 1649 | Values: 139.0 167.0 156.0 151.0 135.0 140.0 143.0 164.0 221.0 187.0 19:22:54 INFO - PROCESS | 1649 | 19:22:54 INFO - PROCESS | 1649 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:187.10 Median:176.50 stddev:23.91 (13.5%) stddev-sans-first:25.14 19:22:54 INFO - PROCESS | 1649 | Values: 178.0 163.0 214.0 237.0 208.0 174.0 174.0 175.0 174.0 174.0 19:22:54 INFO - PROCESS | 1649 | 19:22:54 INFO - PROCESS | 1649 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.90 Median:68.50 stddev:1.52 (2.2%) stddev-sans-first:1.58 19:22:54 INFO - PROCESS | 1649 | Values: 67.0 69.0 67.0 69.0 70.0 66.0 70.0 67.0 66.0 68.0 19:22:54 INFO - PROCESS | 1649 | -------- Summary: end -------- 19:22:54 INFO - PROCESS | 1649 | 19:22:55 INFO - TEST-INFO | 1649: exit 0 19:22:55 INFO - TEST-OK | kraken | took 61475ms 19:22:55 INFO - SUITE-END | took 1177s 19:22:55 INFO - Completed test suite (00:19:37) 19:22:55 INFO - javascript benchmark 19:22:56 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7184.893504565103, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3246.204679853436, 3338.1153994191545, 3374.6287908330078, 3374.8481318340673, 3380.9492857607133, 4616.19216637088, 5536.0, 5547.306586676666, 5574.1917421973785, 5584.357798853133, 5551.472610102044, 5554.2779438672915, 5630.605857589971, 5633.577481688869, 5726.026575482178, 4813.903721925577, 5106.64253502255, 5173.068847607429, 5269.420363759983, 5269.710165940872, 5075.670081444694, 5210.114280572293, 5266.868328291805, 5274.630775845692, 5308.362996440414, 5061.493850614946, 5106.693598384071, 5194.480551944813, 5210.713410762407, 5215.191645294966, 5156.9742151289, 5159.355080614923, 5159.432462429129, 5173.663711858717, 5180.067587834188, 5082.542571168613, 5094.1849304111165, 5131.512506311932, 5134.460881607415, 5142.871428214287, 4255.3829694694095, 4307.526172121095, 5466.234727138204, 5707.6290041147195, 5723.198752174699, 6159.3224745277985, 6194.070889366586, 6205.87588248237, 6214.65819379934, 6217.191765070557, 6209.3790620938, 6281.497480201619, 6303.464205542535, 6305.5901366411035, 6318.684065796738, 5627.268455100811, 5655.406182350876, 5684.346300175492, 5755.107958266475, 5763.7982670606325, 6192.225971753531, 6316.431521163117, 6319.431251187415, 6334.081558174131, 6335.986242201226, 5203.219517072469, 6023.728932197974, 6129.111278864629, 6150.923588372017, 6160.10678451622, 5871.6770577618645, 6222.751089956441, 6261.499080073583, 6269.529785266123, 6297.2128483939505, 4716.221823399101, 5916.3787802281, 5924.8815023700145, 6102.731632724008, 6138.324784273726, 5706.9429305707245, 5781.046127389018, 6202.751889924352, 6244.313125556185, 6254.0, 4913.926291105637, 4926.187179115477, 4935.457099719028, 4976.90046199074, 4992.251162325608, 3980.0049987503126, 4169.791510424467, 4348.9565104349185, 4386.605205531518, 4401.471823381215, 4771.4512831024185, 5772.797952071657, 5823.213866128019, 5848.444397782204, 5852.970735146297, 6254.061890716338, 6269.937300627026, 6274.121622972787, 6297.968510157511, 6298.7480500780375, 5505.091659876076, 5746.281714785652, 5762.74067666956, 5772.047612144033, 5778.595498315161, 4942.986687729007, 5305.389880163753, 5367.812126575551, 5372.57019438444, 5373.704446255493, 5787.363390027093, 6243.750249990131, 6257.937420625826, 6296.905546416716, 6340.239171299474, 5156.329677141947, 6196.690165491617, 6280.403361680633, 6324.557280990471, 6328.0824280478155, 6323.177986861679, 6336.20797400325, 6361.045843123568, 6376.713047912763, 6400.679966001589, 6076.605020673642, 6095.359987201457, 6110.083487476914, 6126.30393285589, 6167.290761562478, 6016.985749244264, 6119.624418755179, 6139.26328840542, 6155.72299246526, 6230.563860529901, 5329.44040875718, 5353.839384818306, 5771.884562308646, 6014.248218972629, 6056.333803281547, 5254.448282930391, 5708.229389032427, 5759.308882934075, 6048.576599637983, 6064.181335519648, 5665.20687103814, 5714.228579141763, 5714.228579141763, 5784.161296612052, 5853.268341457318, 6005.279366476051, 6079.878402432015, 6095.664738439428, 6096.359882212304, 6125.264968203844, 5862.501687356623, 5879.735411906389, 5884.235049443545, 5899.410058994066, 5930.7924222652], "value": 5586.807863051929, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [432.3363636817485, 484.215570775344, 489.2514452887084, 499.91251530982066, 551.1457241276021, 37101.81449092754, 39258.41112383317, 39323.606763932316, 39333.81998540041, 39341.409878851766, 39537.0, 40157.59842401575, 40212.994675133064, 40229.79310620679, 40368.19263614726, 8475.110113438093, 8675.349348798865, 8972.551372431384, 9269.907300927005, 11138.888611113907, 11103.111751059936, 11218.831717524243, 11224.607138750145, 11233.494492747805, 11245.775084498306, 11178.776424471547, 11182.27315224512, 11192.496337664825, 11210.439478026066, 11228.0, 10080.395176289447, 11150.442477876115, 11241.437928103604, 11275.49260283281, 11277.0, 11227.157963152715, 11287.097032237483, 11313.038391736713, 11327.433628318551, 11328.09375249978, 39616.405753913714, 39680.20639587192, 39926.0, 39954.80022599897, 40083.799581001906, 3981.0, 3997.8800635980965, 4019.155977244782, 4057.97971010146, 4136.0, 30560.69439305601, 31039.689603103907, 31081.844590776902, 31108.688913110804, 31139.688603113904, 3141.607299087614, 3281.0812972367776, 3327.15157634804, 3332.4668053111386, 3339.048371214217, 359514.0, 469542.0, 471530.6423467895, 473777.0, 476014.0, 58713.41286587165, 58902.0, 59058.0, 59113.04434778244, 59115.113273300936, 60415.39584604186, 61382.38617613856, 61532.69233653803, 61577.38422615716, 61632.691836541424, 14003.579892603238, 14872.479463218735, 15497.07017578949, 15622.765658514903, 15722.528324150293, 9769.316147869724, 10298.227632927561, 11961.521539138337, 12017.33904635236, 12302.81545776814, 11976.981956533793, 12313.753724925451, 13395.732085358239, 15287.61780955485, 15741.370345186064, 5803.303603567514, 5988.9401105989255, 6823.590584564843, 6899.55152915069, 6970.51206415546, 745.7054463486892, 753.3445902065129, 875.6191056890273, 891.5007595746403, 911.3529394130242, 22970.0, 29414.411711765646, 29739.256518587208, 30319.29432227606, 30512.389752204832, 905.5608030105394, 925.4077390470104, 1035.5184839049878, 1046.8327814486793, 1061.395004847245, 13620.18278903269, 14106.85893141076, 14149.575512734635, 14385.78421323681, 14452.916031297695, 538585.0, 544333.0, 545656.0, 545730.0, 644325.0, 44838.0, 46160.5383946163, 50057.749711251214, 50086.0, 50285.74857125764, 1384.9446022159, 1417.5038736442164, 1442.1419255542935, 1444.5594093801492, 1445.819272590905, 21632.81019543877, 24285.028598855846, 26524.204273871816, 26532.0, 26701.465970680867, 43708.34437483377, 47947.0, 48988.755056224494, 49080.75459622679, 51893.740531298616, 582324.0, 587649.0, 589285.0, 591101.0, 592776.0, 24135.0, 24473.755262447146, 24500.264992050266, 24516.387090322885, 24590.877045614656, 31257.531137032507, 38419.42370864495, 40740.79629601833, 41735.956601085214, 43124.35313470363, 35991.0, 36096.0, 36622.0, 36666.083347915446, 36849.63150368462, 29269.560956585243, 29665.11004669863, 33442.66557334493, 35554.0, 36356.0, 34226.802061927716, 34949.12627184341, 35245.82377088098, 35324.47013294854, 35645.465318019735, 28655.57016644794, 31236.687633124286, 31495.685043149275, 31584.842075789475, 31691.84154079307, 23547.0581176751, 23655.40861478408, 23793.881030594737, 24059.51880962406, 24084.0], "value": 19076.281071559726, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [354.0793935767006, 371.10563541864116, 392.7957462119698, 399.94800675912126, 406.70107471008805, 19095.331663391768, 20636.277730279442, 20726.170953161873, 20913.686294705592, 20947.685784713223, 20494.590108197826, 20811.0, 21052.57894842102, 21261.57476850462, 21282.46793830155, 5455.399906010336, 5719.45665161811, 5841.532677385799, 5913.881722365551, 5946.435088666592, 4139.627433530982, 4375.124975004996, 5815.302163740357, 5926.4073592640825, 6280.717367718441, 6471.773487927923, 6564.409203171714, 6580.506462015344, 6583.3087525809815, 6590.736370545173, 6366.0, 6521.902171467408, 6538.8692226155445, 6579.309172536862, 6598.538102332838, 5681.914771278434, 5687.914681279743, 5746.109353050284, 5746.367899531048, 5767.048437007888, 24268.514629707308, 24367.903444345047, 24721.887515061866, 25414.49171016588, 26043.73956260432, 2378.452955820171, 2776.7500924916653, 2783.178962206154, 2791.818531795447, 2827.816191947517, 7610.467267291292, 8891.688790892287, 15921.761173582405, 15956.601084972852, 16202.83797162025, 1807.9457616271534, 1826.9634607307912, 1841.1530695879912, 1852.8425083867887, 1877.4367689693174, 104288.0, 134678.0, 135285.0, 137599.0, 138248.54378640614, 8255.463394879434, 11854.585089521825, 12004.879951200377, 12018.278903265656, 12114.575989840314, 15213.923930380277, 15508.379664813507, 15516.224188790515, 15528.456504022532, 15572.299246533934, 8391.244787969112, 8500.0, 8515.233628973423, 8553.615087321086, 8633.481991080555, 8127.75616731499, 8191.180881911761, 8210.63052162642, 8271.54506502136, 8330.875036874451, 4747.976260118747, 4779.665423420397, 4785.856424307276, 4817.78319975602, 4833.250846118857, 1660.5101495058746, 1752.2903224194222, 2034.9796502035085, 2074.626567217915, 2076.6605539426987, 952.8570714392758, 975.1564896364736, 999.9900000999897, 1003.7102323514249, 1067.3489171605315, 17744.46766597004, 19756.60486790285, 20685.965701714853, 20973.161073557192, 21206.575868482545, 1027.2244455436098, 1028.5937054863437, 1057.8571892794375, 1060.3796778884284, 1065.3607835298758, 9952.0, 10242.0, 10709.429736863252, 10997.936952470227, 12416.81374779379, 630083.0, 666830.0, 728343.0, 738411.8476445716, 752432.4756752471, 122597.0, 156948.0, 162023.0, 166178.0, 166778.0, 1013.4426065664003, 1019.7807471393537, 1050.7898420315967, 1051.4374809476892, 1054.1198099586777, 15982.360705571647, 19340.516487087934, 19946.0, 20079.29722459707, 20119.994000299634, 61737.0, 62347.6882615584, 63171.0, 64311.0, 64685.0, 95022.52488737513, 95719.52140239533, 96113.0, 97046.0, 97298.0, 5965.6122352048715, 6008.609440386361, 8210.753677389688, 8236.423450358658, 8463.53450560225, 11060.17048721333, 11368.204225704452, 11720.88279117232, 13002.94503920065, 13053.0, 10999.835002474809, 11349.319040857576, 11387.145964052563, 11424.600138995096, 11540.480678369326, 10974.670759877217, 11039.33763974132, 11160.162987775788, 11394.715132121763, 11457.484413201259, 10804.919508049446, 11254.437278135896, 11376.0, 11576.710582235512, 11668.824967625662, 7316.487845850739, 8382.874256886274, 8469.0, 8472.0, 8481.109483504144, 9593.472359020321, 10202.591896324064, 10883.204271295464, 11436.256643318158, 11447.656570302905], "value": 9997.738743222151, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1437.9209143497103, 1444.3211690505466, 1464.6558058856167, 1468.5520916120583, 1477.313049432014, 7485.663145158467, 7699.114601820792, 7980.281774640282, 8438.409311348201, 8465.19580639839, 6848.82877928052, 7861.253180947815, 8159.5104293742415, 8160.959195204031, 8285.0, 1967.8130577595105, 2256.0862850545554, 2297.850639708422, 2301.838871279011, 2304.5966955782756, 1924.5573518090848, 2268.7731226877345, 2282.2582660635276, 2307.8153747700144, 2317.884105794712, 1882.7364169016348, 2283.1666441748725, 2295.82781291403, 2299.218265789631, 2301.7007788987407, 2261.547690461906, 2265.2184996176456, 2281.931542053741, 2284.9885750571307, 2289.519200967799, 2254.5265494246228, 2293.9197128100436, 2293.954120917589, 2306.723193216808, 2333.97666023341, 8349.83300333996, 8401.70594029206, 8407.83184336316, 8426.115257897894, 8429.283510901581, 4841.588464980446, 5008.7996480140755, 5073.670211436245, 5120.948790512084, 5161.870953226162, 3261.902142935716, 3278.541004259406, 3513.139280876176, 3514.1566024154017, 3514.3322768674125, 1225.859026211988, 1244.5270797097046, 1701.2684545645368, 1739.1304347826087, 1816.4913824129264, 8377.916220837835, 10552.630657926935, 10605.628802991936, 10705.25063245573, 10731.0, 4510.188166130128, 4686.27362758773, 4686.437627484724, 4701.3653156824075, 4707.8587642370785, 8537.146285371413, 8630.180132887366, 8642.611082501302, 8658.264047556027, 8667.523286219319, 5086.338775959101, 5097.235414687752, 5106.923396148986, 5117.257997590403, 5138.97430512845, 4595.540445955445, 4660.3688708951595, 4708.940488390153, 4724.291356296583, 4766.8797832509335, 3134.6708595597133, 3572.678458938708, 3623.3296840084668, 3682.5949145593963, 3717.0893131182493, 1004.7691577817261, 1115.9888401116048, 1121.9887801122047, 1130.909527237819, 1132.3149494555676, 852.4459101584021, 881.2421317666801, 899.3541818472311, 903.1420150856712, 926.7822061815468, 4892.730899800474, 5343.866403339947, 5552.7778888844805, 5745.913811292834, 5775.6823374714795, 551.5091568504034, 568.2726110578466, 569.1178673056828, 574.0241589298209, 587.8765459253519, 3345.3142105868724, 3619.4208926571623, 3627.6758982971555, 3907.9218415632095, 4038.91922161561, 9184.81630367389, 9214.585343659552, 9538.809223815484, 9771.21830253578, 9807.852882206773, 9676.983916688787, 9689.515524223902, 9706.660266890625, 9714.12572868431, 9778.07108324708, 5613.943860561424, 7589.58257295854, 7669.654865531064, 7778.338841198448, 7780.377569794403, 5766.509846663081, 5846.0, 5889.175515427758, 5913.556483263858, 6010.819675409744, 9200.401973871945, 9203.677871274473, 9251.398659087137, 9261.120193581599, 9280.0, 8448.619812108347, 8738.606762695566, 8741.956290218763, 9360.672376466791, 9376.95311523438, 5833.9416605835095, 5870.295564532113, 6111.480524155497, 7158.498905076803, 7181.569105853666, 7818.569978651229, 7884.172161922916, 7951.801204969691, 7964.641591128529, 7967.28294453502, 7130.18002929649, 7146.749863754952, 7157.105361829771, 7189.317014883578, 7285.453590980592, 6247.250329960434, 6958.304169583002, 7023.473239506955, 7085.610291434021, 7139.535930164523, 6317.273513546001, 6363.681815909279, 6404.071409645482, 6422.2614399344675, 6429.324920883427, 4866.124097662313, 4895.069936712013, 4950.9257361140335, 5010.724410157476, 5112.182050871843, 4422.739519237002, 4960.156773348612, 5024.748762561931, 5059.671121377099, 5202.089634313904], "value": 4292.758148412327, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [357.6941714833817, 359.9820008999549, 370.56088535085917, 372.1422122008772, 374.1656106881654, 4415.3376993451, 4771.475137734851, 4839.120723169713, 5337.4662533746605, 5372.650777699447, 4777.450593181785, 5124.590032797377, 5382.67703937764, 5405.162199859018, 5491.945080549193, 3728.5339332583426, 3734.346489364364, 4245.320748680212, 4246.426732391134, 4281.529031806499, 3290.4899740540254, 3431.953254257463, 4189.706720529549, 4288.463942007249, 4290.957090429087, 3243.724283435911, 4077.3272410052305, 4082.2243773683053, 4104.876853694379, 4109.979450102745, 3403.2512847173584, 4076.0, 4103.2409004334295, 4105.794710264505, 4108.52751933527, 2886.942261154765, 3338.914852672891, 3559.0212691509782, 3569.089882080079, 3576.0344706929245, 4669.509701481364, 4894.657373983787, 5284.10170271051, 5320.0, 5393.973030134864, 4731.604176767827, 4758.3576217210575, 4776.80892764289, 4786.497417771118, 4790.137775200463, 5424.511793938565, 5547.86268814892, 5826.611089453346, 5853.326867410217, 5860.267466566679, 3815.8282877270312, 3877.5734669186368, 3922.6077392260545, 3944.960550394488, 3947.0, 5513.365962914236, 5551.0, 5797.449242321973, 6117.724702388403, 6153.846153846145, 3957.3470377387985, 3985.442038114666, 3997.060690737674, 4000.6399424051974, 4040.1717647882115, 3732.4774531565604, 3919.3141200290406, 4005.8597949072227, 4038.899027524277, 4048.3320252158064, 4714.57568818808, 4714.929276060861, 4743.407074115736, 4743.525647435298, 4795.520447955247, 5810.0, 5870.501007414332, 5883.911741323884, 5922.3485416604135, 5964.0, 1046.5604446132643, 1084.7017070305571, 1242.7887259165782, 1263.368315842079, 1309.4173092973535, 741.2372736457601, 752.1500704204215, 758.6548120605112, 872.4154816273112, 881.1716986033108, 5128.564072053917, 5146.58110687976, 5146.8640514186945, 5172.637915345889, 5239.851660819046, 4901.877453063702, 5336.332958380202, 5684.870634991918, 5688.829335119953, 5695.572832037614, 4678.274867395559, 4885.5358740920265, 5111.642185047085, 5112.948870511322, 5161.716105614152, 2623.737626237361, 2734.480448714738, 2806.6070750095005, 2858.3140046389135, 3039.6656367799524, 5928.881422371528, 5933.732982015819, 5939.673317967553, 5939.851503712442, 5947.732352044169, 5052.595792336604, 5283.392409872837, 5436.809711660073, 5879.52963762898, 5888.61723987948, 5189.584833213409, 5899.436649287942, 5911.349751527328, 5911.911321330097, 5989.610675306091, 4887.584555312838, 4957.752112394293, 5596.916046259391, 5669.943300566942, 5693.715314234189, 4699.402203250911, 4699.600533954515, 4827.565519103157, 5037.471065538066, 5635.718214089197, 5161.793528258828, 5465.770201704744, 5650.773969041356, 5694.572907032072, 6003.849903752441, 3201.6158061032825, 3599.874004409833, 3742.419924911534, 3790.298794722985, 3852.788096654711, 4758.501786562083, 5208.967893343955, 5584.553235741049, 5603.719814009365, 5721.656700597978, 4700.0, 4946.689127381319, 4954.504549545017, 5676.77292908279, 5795.0, 5750.9712451439145, 5756.740946657326, 5771.538276937751, 5805.970970145122, 5839.445252700986, 5051.8380772421815, 5280.973595132, 5287.762050707651, 5379.220013098157, 5508.834734957797, 5029.421616514148, 5192.883530040908, 5238.921416178837, 5498.285222920995, 5789.797357092481, 4817.710937343771, 5200.791968321225, 5577.470140336662, 5580.2466666999435, 5676.801311954227], "value": 4090.378343244816, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [709.8970649255862, 717.616075399661, 739.5267029101375, 776.2974508070195, 805.3516918880301, 14660.193689347096, 15495.84504154958, 15535.145566993824, 15548.922255388736, 15558.922205388972, 14467.710645787078, 14575.781363279559, 14609.853901460981, 14633.853661463409, 14690.265486725675, 667.883120453921, 695.151914664109, 815.5759005317232, 848.9278411335044, 858.090424150401, 859.2352806002663, 860.8923884514436, 862.8015556422026, 870.3472395703222, 870.9477431354107, 891.3671293381674, 909.2044461096541, 920.1442658327752, 920.3189639666665, 921.6313474610142, 904.5206040798388, 909.7725568607782, 910.4764760262842, 911.4804561400005, 912.2428384440898, 677.7492327838681, 683.6205905722322, 684.449018540075, 684.5139950635055, 686.5708931917552, 12403.255804651659, 15011.849881501154, 15032.473863414838, 15034.0, 15362.846371536365, 14306.92846535771, 14533.05535140223, 14607.269636518238, 14621.488247911271, 14845.406183752637, 11287.322760634388, 11669.533218671242, 11846.17076804624, 11876.049916006785, 11969.820452693217, 3878.5151856017997, 4530.411046563959, 4558.863234102949, 4596.4254468191475, 4615.376924115235, 86625.0, 116148.41925790317, 117356.0, 119900.40049799782, 121417.0, 10586.682399528027, 10625.628103016357, 10655.520501577448, 10682.03861652455, 10941.0, 20773.961301935145, 20820.479488012617, 20823.479413014797, 20865.85237811935, 20903.163873444893, 7507.1366792818435, 7528.811779705442, 7542.886856697044, 7712.997310349619, 7754.806129846799, 4231.344141658048, 4243.936340954888, 4270.7864606769535, 4322.913541729211, 4399.36209249657, 3407.131181548739, 3485.35520928629, 3486.2156014896445, 3487.093355727529, 3494.0740703713536, 629.8584811640368, 637.2671427858, 639.1148259660404, 643.6781609195402, 650.9283978762332, 636.522608043967, 638.6200210874522, 644.6647743173618, 647.5893906411955, 658.8221180281394, 7931.929189559336, 8204.507729536246, 8423.241908228289, 9079.45523268606, 9689.951550242204, 601.6570554783818, 619.6684773646168, 621.7916997805695, 658.1312667279241, 666.8596699643567, 3854.8650797221962, 4113.526944401372, 4532.0, 4685.1800934836265, 4713.717176969393, 86646.56676716702, 112482.87517124887, 114087.85912140939, 115256.0, 116097.8390216087, 71876.0, 95358.0, 100365.4945175823, 101214.49392753137, 103840.96159038464, 88264.0, 89159.55420222988, 89969.0, 90079.09920900837, 92173.53913230391, 8258.752237432887, 8848.247898928663, 9017.954910225408, 9239.260859131387, 9252.768680783034, 50460.747696261285, 53754.73122634362, 55150.0, 55398.0, 56408.71795640996, 73544.63227683827, 74833.25166748263, 76189.0, 76195.61902190454, 78450.21549784657, 10961.890381096087, 11056.391898445663, 11229.21395502307, 11328.830067549157, 11476.885231147582, 11990.880091198977, 12080.51677932873, 12121.212121212093, 12141.635750927486, 12301.384930753606, 11746.823797643214, 11855.881441185478, 12131.575394861138, 12242.0, 12288.877111228774, 11154.386508742096, 11164.49759760829, 11221.943890280823, 11537.394200083132, 11639.41802909868, 11507.136964727846, 11512.481938312629, 11514.481848317013, 11745.060395168537, 11800.17398782077, 9429.575669095044, 9950.45272510019, 9965.900340996497, 9966.700998970042, 10068.69793906184, 9326.860097098684, 9518.286128540249, 11178.0, 11479.4260286987, 11570.59502917394], "value": 7352.164394656561, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1605.7999999999997, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 104.0, 169.0, 172.0, 170.0, 117.0, 118.0, 119.0, 133.0, 175.0], "value": 141.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 113.0, 110.0, 164.0, 112.0, 115.0, 115.0, 113.0, 131.0, 114.0], "value": 123.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 200.0, 250.0, 276.0, 226.0, 195.0, 195.0, 197.0, 193.0, 196.0], "value": 212.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 54.0, 64.0, 109.0, 58.0, 82.0, 53.0, 74.0, 95.0, 53.0], "value": 72.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 57.0, 81.0, 80.0, 105.0, 101.0, 112.0, 72.0, 71.0, 83.0], "value": 84.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 133.0, 139.0, 88.0, 88.0, 114.0, 90.0, 91.0, 137.0, 85.0], "value": 104.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 116.0, 154.0, 106.0, 95.0, 95.0, 111.0, 95.0, 95.0, 96.0], "value": 105.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 92.0, 92.0, 127.0, 98.0, 124.0, 154.0, 98.0, 107.0, 152.0], "value": 113.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 60.0, 62.0, 61.0, 62.0, 63.0, 62.0, 64.0, 63.0, 62.0], "value": 62.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 74.0, 82.0, 81.0, 73.0, 69.0, 71.0, 70.0, 72.0, 85.0], "value": 74.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 92.0, 86.0, 105.0, 113.0, 109.0, 102.0, 88.0, 87.0], "value": 95.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 167.0, 156.0, 151.0, 135.0, 140.0, 143.0, 164.0, 221.0, 187.0], "value": 160.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 163.0, 214.0, 237.0, 208.0, 174.0, 174.0, 175.0, 174.0, 174.0], "value": 187.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 69.0, 67.0, 69.0, 70.0, 66.0, 70.0, 67.0, 66.0, 68.0], "value": 67.9, "unit": "score"}]}]} 19:22:56 INFO - Return code: 0 19:22:56 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:22:56 ERROR - # TBPL SUCCESS # 19:22:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:22:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:22:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:22:56 INFO - 2017-01-10 19:22:56.148 screenresolution[1654:10869] starting screenresolution argv=screenresolution get 19:22:56 INFO - 2017-01-10 19:22:56.150 screenresolution[1654:10869] Display 0: 1600x1200x32@60 19:22:56 INFO - 2017-01-10 19:22:56.156 screenresolution[1655:10872] starting screenresolution argv=screenresolution list 19:22:56 INFO - Available Modes on Display 0 19:22:56 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:22:56 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:22:56 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:22:56 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:22:56 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:22:56 INFO - Intel Iris: 19:22:56 INFO - Chipset Model: Intel Iris 19:22:56 INFO - Type: GPU 19:22:56 INFO - Bus: Built-In 19:22:56 INFO - VRAM (Dynamic, Max): 1536 MB 19:22:56 INFO - Vendor: Intel (0x8086) 19:22:56 INFO - Device ID: 0x0a2e 19:22:56 INFO - Revision ID: 0x0009 19:22:56 INFO - Displays: 19:22:56 INFO - Display: 19:22:56 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:22:56 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:22:56 INFO - Display Serial Number: 1600x1200 60 19:22:56 INFO - Main Display: Yes 19:22:56 INFO - Mirror: Off 19:22:56 INFO - Online: Yes 19:22:56 INFO - Rotation: Supported 19:22:56 INFO - Return code: 0 19:22:56 INFO - Running post-action listener: _resource_record_post_action 19:22:56 INFO - [mozharness: 2017-01-11 03:22:56.389971Z] Finished run-tests step (success) 19:22:56 INFO - Running post-run listener: _resource_record_post_run 19:22:56 INFO - Total resource usage - Wall time: 1200s; CPU: 55.0%; Read bytes: 39594496; Write bytes: 552341504; Read time: 390; Write time: 1755 19:22:56 INFO - TinderboxPrint: CPU usage
54.6% 19:22:56 INFO - TinderboxPrint: I/O read bytes / time
39,594,496 / 390 19:22:56 INFO - TinderboxPrint: I/O write bytes / time
552,341,504 / 1,755 19:22:56 INFO - TinderboxPrint: CPU idle
2,182.0 (45.4%) 19:22:56 INFO - TinderboxPrint: CPU system
968.2 (20.2%) 19:22:56 INFO - TinderboxPrint: CPU user
1,651.5 (34.4%) 19:22:56 INFO - TinderboxPrint: Swap in / out
289,701,888 / 0 19:22:56 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 352256; Write bytes: 251971584; Read time: 6; Write time: 478 19:22:56 INFO - run-tests - Wall time: 1179s; CPU: 55.0%; Read bytes: 35199488; Write bytes: 298776576; Read time: 351; Write time: 1266 19:22:56 INFO - Running post-run listener: _upload_blobber_files 19:22:56 INFO - Blob upload gear active. 19:22:56 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:22:56 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 19:22:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:22:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:22:57 INFO - (blobuploader) - INFO - Open directory for files ... 19:22:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 19:22:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:22:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:22:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 19:22:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:22:57 INFO - (blobuploader) - INFO - Done attempting. 19:22:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 19:22:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:22:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:22:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 19:22:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:22:58 INFO - (blobuploader) - INFO - Done attempting. 19:22:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 19:22:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:22:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:22:59 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 19:22:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:22:59 INFO - (blobuploader) - INFO - Done attempting. 19:22:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:22:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:22:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:23:03 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:23:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:23:03 INFO - (blobuploader) - INFO - Done attempting. 19:23:03 INFO - (blobuploader) - INFO - Iteration through files over. 19:23:03 INFO - Return code: 0 19:23:03 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:23:03 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:23:03 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c8ffdcc03eabb7a5dd49ec868d7b45f1f10f982cdf995f968289032cf254d49ac3b37a034b6f29a26f1e1165057f341eb39de98000c78e8f3b29fc9442aa2152", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4a5b8e7667123577b8e5941f7658bd2585969340a9a66316d939bdf2cd214e5588f3e2ad2f3a32c0d936942a2089c72abec17643ef7af47dbcc7ec74a1e089df", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/db9ff54e8326f3b3626478e715e29e2d72cca7bafb2dd50b52d58ddb9d13b78589ec1242e625529655fc42c0683177cbbb220dcf19220b1197603a49c116cb0e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/56b103cb45cba4d795521f8f7741a2e567b6e49492bfc58930a6774c8e84005b5f9f18a12a4ff1ee6a5e227d0577f9a7a8f415e678fb25bd93d0ddd37d8d6c5d"} 19:23:03 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:23:03 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:23:03 INFO - Contents: 19:23:03 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c8ffdcc03eabb7a5dd49ec868d7b45f1f10f982cdf995f968289032cf254d49ac3b37a034b6f29a26f1e1165057f341eb39de98000c78e8f3b29fc9442aa2152", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4a5b8e7667123577b8e5941f7658bd2585969340a9a66316d939bdf2cd214e5588f3e2ad2f3a32c0d936942a2089c72abec17643ef7af47dbcc7ec74a1e089df", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/db9ff54e8326f3b3626478e715e29e2d72cca7bafb2dd50b52d58ddb9d13b78589ec1242e625529655fc42c0683177cbbb220dcf19220b1197603a49c116cb0e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/56b103cb45cba4d795521f8f7741a2e567b6e49492bfc58930a6774c8e84005b5f9f18a12a4ff1ee6a5e227d0577f9a7a8f415e678fb25bd93d0ddd37d8d6c5d"} 19:23:03 INFO - Running post-run listener: copy_logs_to_upload_dir 19:23:03 INFO - Copying logs to upload dir... 19:23:03 INFO - mkdir: /builds/slave/test/build/upload/logs 19:23:03 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1263.002300 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2017-01-10 19:23:03.236704) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-10 19:23:03.240302) ========= 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.DF2y2GY89h/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.8JEQNrFWeD/Listeners TMPDIR=/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c8ffdcc03eabb7a5dd49ec868d7b45f1f10f982cdf995f968289032cf254d49ac3b37a034b6f29a26f1e1165057f341eb39de98000c78e8f3b29fc9442aa2152", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4a5b8e7667123577b8e5941f7658bd2585969340a9a66316d939bdf2cd214e5588f3e2ad2f3a32c0d936942a2089c72abec17643ef7af47dbcc7ec74a1e089df", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/db9ff54e8326f3b3626478e715e29e2d72cca7bafb2dd50b52d58ddb9d13b78589ec1242e625529655fc42c0683177cbbb220dcf19220b1197603a49c116cb0e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/56b103cb45cba4d795521f8f7741a2e567b6e49492bfc58930a6774c8e84005b5f9f18a12a4ff1ee6a5e227d0577f9a7a8f415e678fb25bd93d0ddd37d8d6c5d"} build_url:https://queue.taskcluster.net/v1/task/U4Ky-ZJDTfqNz3Xfsauw8g/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011098 build_url: 'https://queue.taskcluster.net/v1/task/U4Ky-ZJDTfqNz3Xfsauw8g/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c8ffdcc03eabb7a5dd49ec868d7b45f1f10f982cdf995f968289032cf254d49ac3b37a034b6f29a26f1e1165057f341eb39de98000c78e8f3b29fc9442aa2152", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4a5b8e7667123577b8e5941f7658bd2585969340a9a66316d939bdf2cd214e5588f3e2ad2f3a32c0d936942a2089c72abec17643ef7af47dbcc7ec74a1e089df", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/db9ff54e8326f3b3626478e715e29e2d72cca7bafb2dd50b52d58ddb9d13b78589ec1242e625529655fc42c0683177cbbb220dcf19220b1197603a49c116cb0e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/56b103cb45cba4d795521f8f7741a2e567b6e49492bfc58930a6774c8e84005b5f9f18a12a4ff1ee6a5e227d0577f9a7a8f415e678fb25bd93d0ddd37d8d6c5d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-10 19:23:03.274160) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-10 19:23:03.274561) ========= 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.DF2y2GY89h/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.8JEQNrFWeD/Listeners TMPDIR=/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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.005125 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-10 19:23:03.323845) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-10 19:23:03.324163) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-10 19:23:03.324507) ========= ========= Total master_lag: 0.18 =========