builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0155 starttime: 1484013318.6 results: success (0) buildid: 20170109163507 builduid: 4c378b8be03f484594c8ce5f2c2f3949 revision: 845cc4dea57f6cc93f46810d24b1058b640c3b74 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-09 17:55:18.596049) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-09 17:55:18.596500) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-09 17:55:18.596773) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-09 17:55:18.623582) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 17:55:18.623982) ========= 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.RegKVGgKCU/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.3z0VqiHUzl/Listeners TMPDIR=/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/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-09 17:55:18-- 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% 24.9M=0s 2017-01-09 17:55:18 (24.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.121495 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 17:55:18.769978) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 17:55:18.770303) ========= 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.RegKVGgKCU/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.3z0VqiHUzl/Listeners TMPDIR=/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/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.027708 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 17:55:18.821180) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-01-09 17:55:18.821773) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 845cc4dea57f6cc93f46810d24b1058b640c3b74 --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 845cc4dea57f6cc93f46810d24b1058b640c3b74 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.RegKVGgKCU/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.3z0VqiHUzl/Listeners TMPDIR=/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/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-09 17:55:18,784 truncating revision to first 12 chars 2017-01-09 17:55:18,784 Setting DEBUG logging. 2017-01-09 17:55:18,784 attempt 1/10 2017-01-09 17:55:18,784 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/845cc4dea57f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-09 17:55:20,645 unpacking tar archive at: mozilla-central-845cc4dea57f/testing/mozharness/ program finished with exit code 0 elapsedTime=2.134292 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-01-09 17:55:20.980975) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-09 17:55:20.981286) ========= script_repo_revision: 845cc4dea57f6cc93f46810d24b1058b640c3b74 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-09 17:55:20.981675) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-09 17:55:20.982062) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-09 17:55:21.002578) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2017-01-09 17:55:21.003164) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.RegKVGgKCU/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.3z0VqiHUzl/Listeners TMPDIR=/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/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 17:55:21 INFO - MultiFileLogger online at 20170109 17:55:21 in /builds/slave/test 17:55:21 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 17:55:21 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:55:21 INFO - {'append_to_log': False, 17:55:21 INFO - 'base_work_dir': '/builds/slave/test', 17:55:21 INFO - 'blob_upload_branch': 'Firefox', 17:55:21 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:55:21 INFO - 'branch': 'Firefox', 17:55:21 INFO - 'buildbot_json_path': 'buildprops.json', 17:55:21 INFO - 'config_files': ('talos/mac_config.py',), 17:55:21 INFO - 'default_actions': ('clobber', 17:55:21 INFO - 'read-buildbot-config', 17:55:21 INFO - 'download-and-extract', 17:55:21 INFO - 'populate-webroot', 17:55:21 INFO - 'create-virtualenv', 17:55:21 INFO - 'install', 17:55:21 INFO - 'run-tests'), 17:55:21 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:55:21 INFO - 'download_minidump_stackwalk': True, 17:55:21 INFO - 'download_symbols': 'ondemand', 17:55:21 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:55:21 INFO - 'tooltool.py': '/tools/tooltool.py', 17:55:21 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:55:21 INFO - '/tools/misc-python/virtualenv.py')}, 17:55:21 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:55:21 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:55:21 INFO - 'installer_path': 'installer.exe', 17:55:21 INFO - 'log_level': 'info', 17:55:21 INFO - 'log_name': 'talos', 17:55:21 INFO - 'log_to_console': True, 17:55:21 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:55:21 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:55:21 INFO - 'opt_config_files': (), 17:55:21 INFO - 'pip_index': False, 17:55:21 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:55:21 INFO - 'cmd': ('bash', 17:55:21 INFO - '-c', 17:55:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:55:21 INFO - 'enabled': True, 17:55:21 INFO - 'halt_on_failure': False, 17:55:21 INFO - 'name': 'check_screen_resolution'},), 17:55:21 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:55:21 INFO - 'cmd': ('bash', 17:55:21 INFO - '-c', 17:55:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:55:21 INFO - 'enabled': True, 17:55:21 INFO - 'halt_on_failure': False, 17:55:21 INFO - 'name': 'check_screen_resolution'},), 17:55:21 INFO - 'run_cmd_checks_enabled': True, 17:55:21 INFO - 'sps_profile': False, 17:55:21 INFO - 'sps_profile_interval': 0, 17:55:21 INFO - 'suite': 'dromaeojs', 17:55:21 INFO - 'system_bits': '32', 17:55:21 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:55:21 INFO - 'title': 't-yosemite-r7-0155', 17:55:21 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:55:21 INFO - 'use_talos_json': True, 17:55:21 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:55:21 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:55:21 INFO - 'work_dir': 'build'} 17:55:21 INFO - [mozharness: 2017-01-10 01:55:21.076336Z] Running clobber step. 17:55:21 INFO - Running pre-action listener: _resource_record_pre_action 17:55:21 INFO - Running main action method: clobber 17:55:21 INFO - rmtree: /builds/slave/test/build 17:55:21 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:55:23 INFO - Running post-action listener: _resource_record_post_action 17:55:23 INFO - [mozharness: 2017-01-10 01:55:23.688112Z] Finished clobber step (success) 17:55:23 INFO - [mozharness: 2017-01-10 01:55:23.688237Z] Running read-buildbot-config step. 17:55:23 INFO - Running pre-action listener: _resource_record_pre_action 17:55:23 INFO - Running main action method: read_buildbot_config 17:55:23 INFO - Using buildbot properties: 17:55:23 INFO - { 17:55:23 INFO - "project": "", 17:55:23 INFO - "product": "firefox", 17:55:23 INFO - "script_repo_revision": "production", 17:55:23 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 17:55:23 INFO - "repository": "", 17:55:23 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 17:55:23 INFO - "buildid": "20170109163507", 17:55:23 INFO - "pgo_build": "False", 17:55:23 INFO - "basedir": "/builds/slave/test", 17:55:23 INFO - "buildnumber": 57, 17:55:23 INFO - "slavename": "t-yosemite-r7-0155", 17:55:23 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 17:55:23 INFO - "platform": "yosemite_r7", 17:55:23 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 17:55:23 INFO - "branch": "mozilla-central", 17:55:23 INFO - "repo_path": "mozilla-central", 17:55:23 INFO - "revision": "845cc4dea57f6cc93f46810d24b1058b640c3b74", 17:55:23 INFO - "stage_platform": "macosx64", 17:55:23 INFO - "builduid": "4c378b8be03f484594c8ce5f2c2f3949", 17:55:23 INFO - "slavebuilddir": "test" 17:55:23 INFO - } 17:55:23 INFO - Found installer url https://queue.taskcluster.net/v1/task/Xt1nRQoyTk6VhuqcNwTerQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 17:55:23 INFO - Running post-action listener: _resource_record_post_action 17:55:23 INFO - [mozharness: 2017-01-10 01:55:23.691525Z] Finished read-buildbot-config step (success) 17:55:23 INFO - [mozharness: 2017-01-10 01:55:23.691662Z] Running download-and-extract step. 17:55:23 INFO - Running pre-action listener: _resource_record_pre_action 17:55:23 INFO - Running main action method: download_and_extract 17:55:23 INFO - mkdir: /builds/slave/test/build/tests 17:55:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:55:23 INFO - trying https://queue.taskcluster.net/v1/task/Xt1nRQoyTk6VhuqcNwTerQ/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 17:55:23 INFO - Downloading https://queue.taskcluster.net/v1/task/Xt1nRQoyTk6VhuqcNwTerQ/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 17:55:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Xt1nRQoyTk6VhuqcNwTerQ/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 17:55:24 INFO - Downloaded 1199 bytes. 17:55:24 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 17:55:24 INFO - Using the following test package requirements: 17:55:24 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 17:55:24 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 17:55:24 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 17:55:24 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 17:55:24 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 17:55:24 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 17:55:24 INFO - u'jsshell-mac.zip'], 17:55:24 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 17:55:24 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 17:55:24 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 17:55:24 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 17:55:24 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 17:55:24 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 17:55:24 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 17:55:24 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 17:55:24 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 17:55:24 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 17:55:24 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 17:55:24 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 17:55:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Xt1nRQoyTk6VhuqcNwTerQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 17:55:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Xt1nRQoyTk6VhuqcNwTerQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:55:24 INFO - Fetch https://queue.taskcluster.net/v1/task/Xt1nRQoyTk6VhuqcNwTerQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 17:55:25 INFO - Expected file size: 16306193 17:55:25 INFO - Obtained file size: 16306193 17:55:32 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 17:55:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Xt1nRQoyTk6VhuqcNwTerQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 17:55:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Xt1nRQoyTk6VhuqcNwTerQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:55:32 INFO - Fetch https://queue.taskcluster.net/v1/task/Xt1nRQoyTk6VhuqcNwTerQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 17:55:33 INFO - Expected file size: 16306193 17:55:33 INFO - Obtained file size: 16306193 17:55:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Xt1nRQoyTk6VhuqcNwTerQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 17:55:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Xt1nRQoyTk6VhuqcNwTerQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 17:55:40 INFO - Fetch https://queue.taskcluster.net/v1/task/Xt1nRQoyTk6VhuqcNwTerQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 17:55:41 INFO - Expected file size: 14403537 17:55:41 INFO - Obtained file size: 14403537 17:55:41 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')]} 17:55:41 INFO - trying https://queue.taskcluster.net/v1/task/Xt1nRQoyTk6VhuqcNwTerQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 17:55:41 INFO - Downloading https://queue.taskcluster.net/v1/task/Xt1nRQoyTk6VhuqcNwTerQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 17:55:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Xt1nRQoyTk6VhuqcNwTerQ/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 17:55:43 INFO - Downloaded 69768355 bytes. 17:55:43 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Xt1nRQoyTk6VhuqcNwTerQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 17:55:43 INFO - mkdir: /builds/slave/test/properties 17:55:43 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:55:43 INFO - Writing to file /builds/slave/test/properties/build_url 17:55:43 INFO - Contents: 17:55:43 INFO - build_url:https://queue.taskcluster.net/v1/task/Xt1nRQoyTk6VhuqcNwTerQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 17:55:44 INFO - Running post-action listener: _resource_record_post_action 17:55:44 INFO - Running post-action listener: set_extra_try_arguments 17:55:44 INFO - [mozharness: 2017-01-10 01:55:44.459242Z] Finished download-and-extract step (success) 17:55:44 INFO - [mozharness: 2017-01-10 01:55:44.459404Z] Running populate-webroot step. 17:55:44 INFO - Running pre-action listener: _resource_record_pre_action 17:55:44 INFO - Running main action method: populate_webroot 17:55:44 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:55:44 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:55:44 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:55:44 INFO - '--tppagecycles', 17:55:44 INFO - '7'], 17:55:44 INFO - 'tests': ['tsvgm']}}, 17:55:44 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:55:44 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:55:44 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:55:44 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:55:44 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:55:44 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:55:44 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 17:55:44 INFO - 'talos_options': ['--disable-e10s'], 17:55:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:55:44 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 17:55:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:55:44 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 17:55:44 INFO - 'talos_options': ['--disable-e10s'], 17:55:44 INFO - 'tests': ['damp', 'tps']}, 17:55:44 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 17:55:44 INFO - 'tests': ['damp', 'tps']}, 17:55:44 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:55:44 INFO - 'tests': ['dromaeo_dom']}, 17:55:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:55:44 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:55:44 INFO - 'tests': ['basic_compositor_video']}, 17:55:44 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:55:44 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:55:44 INFO - 'tests': ['a11yr', 17:55:44 INFO - 'ts_paint', 17:55:44 INFO - 'tpaint', 17:55:44 INFO - 'sessionrestore', 17:55:44 INFO - 'sessionrestore_no_auto_restore', 17:55:44 INFO - 'tabpaint']}, 17:55:44 INFO - 'other-e10s': {'tests': ['a11yr', 17:55:44 INFO - 'ts_paint', 17:55:44 INFO - 'tpaint', 17:55:44 INFO - 'sessionrestore', 17:55:44 INFO - 'sessionrestore_no_auto_restore', 17:55:44 INFO - 'tabpaint']}, 17:55:44 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:55:44 INFO - 'ts_paint', 17:55:44 INFO - 'tpaint', 17:55:44 INFO - 'sessionrestore', 17:55:44 INFO - 'sessionrestore_no_auto_restore', 17:55:44 INFO - 'tabpaint']}, 17:55:44 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:55:44 INFO - 'ts_paint', 17:55:44 INFO - 'tpaint', 17:55:44 INFO - 'sessionrestore', 17:55:44 INFO - 'sessionrestore_no_auto_restore', 17:55:44 INFO - 'tabpaint']}, 17:55:44 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:55:44 INFO - 'tests': ['a11yr', 17:55:44 INFO - 'ts_paint', 17:55:44 INFO - 'tpaint', 17:55:44 INFO - 'sessionrestore', 17:55:44 INFO - 'sessionrestore_no_auto_restore', 17:55:44 INFO - 'tabpaint']}, 17:55:44 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:55:44 INFO - 'tests': ['a11yr', 17:55:44 INFO - 'ts_paint', 17:55:44 INFO - 'tpaint', 17:55:44 INFO - 'sessionrestore', 17:55:44 INFO - 'sessionrestore_no_auto_restore', 17:55:44 INFO - 'tabpaint']}, 17:55:44 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:55:44 INFO - 'tests': ['tsvgx', 17:55:44 INFO - 'tsvgr_opacity', 17:55:44 INFO - 'tart', 17:55:44 INFO - 'tscrollx', 17:55:44 INFO - 'cart', 17:55:44 INFO - 'tsvg_static']}, 17:55:44 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:55:44 INFO - 'tsvgr_opacity', 17:55:44 INFO - 'tart', 17:55:44 INFO - 'tscrollx', 17:55:44 INFO - 'cart', 17:55:44 INFO - 'tsvg_static']}, 17:55:44 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 17:55:44 INFO - 'talos_options': ['--disable-e10s'], 17:55:44 INFO - 'tests': ['tp5o']}, 17:55:44 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 17:55:44 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 17:55:44 INFO - 'talos_options': ['--disable-e10s', 17:55:44 INFO - '--xperf_path', 17:55:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:55:44 INFO - 'tests': ['tp5n']}, 17:55:44 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 17:55:44 INFO - 'talos_options': ['--xperf_path', 17:55:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:55:44 INFO - 'tests': ['tp5n']}}, 17:55:44 INFO - 'talos.zip': {'path': '', 17:55:44 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:55:44 INFO - Running post-action listener: _resource_record_post_action 17:55:44 INFO - [mozharness: 2017-01-10 01:55:44.477477Z] Finished populate-webroot step (success) 17:55:44 INFO - [mozharness: 2017-01-10 01:55:44.477589Z] Running create-virtualenv step. 17:55:44 INFO - Running pre-action listener: _resource_record_pre_action 17:55:44 INFO - Running main action method: create_virtualenv 17:55:44 INFO - Creating virtualenv /builds/slave/test/build/venv 17:55:44 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 17:55:44 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:55:44 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:55:44 INFO - Using real prefix '/tools/python27' 17:55:44 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:55:45 INFO - Installing distribute.............................................................................................................................................................................................done. 17:55:48 INFO - Installing pip.................done. 17:55:48 INFO - Return code: 0 17:55:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:55:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:55:48 INFO - Reading from file tmpfile_stdout 17:55:48 INFO - Output received: 17:55:48 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) 17:55:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:55:48 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')]} 17:55:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:55:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:55:48 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')]} 17:55:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:55:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:55:48 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 0x10cef2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cef5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa53d78890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccd0d30>, '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 0x10cbf7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ccc5be8>, '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.RegKVGgKCU/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.3z0VqiHUzl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/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 17:55:48 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 17:55:48 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 17:55:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RegKVGgKCU/Render', 17:55:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:55:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:55:48 INFO - 'HOME': '/Users/cltbld', 17:55:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:55:48 INFO - 'LOGNAME': 'cltbld', 17:55:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:55:48 INFO - 'MOZ_NO_REMOTE': '1', 17:55:48 INFO - 'NO_EM_RESTART': '1', 17:55:48 INFO - 'PAGER': '/bin/cat', 17:55:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:55:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:55:48 INFO - 'PWD': '/builds/slave/test', 17:55:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:55:48 INFO - 'SHELL': '/bin/bash', 17:55:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3z0VqiHUzl/Listeners', 17:55:48 INFO - 'TMPDIR': '/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/', 17:55:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:55:48 INFO - 'USER': 'cltbld', 17:55:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:55:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:55:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:55:48 INFO - 'XPC_FLAGS': '0x0', 17:55:48 INFO - 'XPC_SERVICE_NAME': '0', 17:55:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:55:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:55:48 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 17:55:48 INFO - Cleaning up... 17:55:48 INFO - Return code: 0 17:55:48 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:55:48 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')]} 17:55:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:55:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:55:48 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')]} 17:55:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:55:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:55:48 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 0x10cef2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cef5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa53d78890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccd0d30>, '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 0x10cbf7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ccc5be8>, '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.RegKVGgKCU/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.3z0VqiHUzl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/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 17:55:48 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 17:55:48 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 17:55:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RegKVGgKCU/Render', 17:55:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:55:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:55:48 INFO - 'HOME': '/Users/cltbld', 17:55:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:55:48 INFO - 'LOGNAME': 'cltbld', 17:55:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:55:48 INFO - 'MOZ_NO_REMOTE': '1', 17:55:48 INFO - 'NO_EM_RESTART': '1', 17:55:48 INFO - 'PAGER': '/bin/cat', 17:55:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:55:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:55:48 INFO - 'PWD': '/builds/slave/test', 17:55:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:55:48 INFO - 'SHELL': '/bin/bash', 17:55:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3z0VqiHUzl/Listeners', 17:55:48 INFO - 'TMPDIR': '/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/', 17:55:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:55:48 INFO - 'USER': 'cltbld', 17:55:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:55:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:55:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:55:48 INFO - 'XPC_FLAGS': '0x0', 17:55:48 INFO - 'XPC_SERVICE_NAME': '0', 17:55:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:55:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:55:48 INFO - Downloading/unpacking psutil>=3.1.1 17:55:48 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 17:55:48 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 17:55:48 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 17:55:48 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 17:55:48 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 17:55:48 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 17:55:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:55:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:55:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:55:52 INFO - Installing collected packages: psutil 17:55:52 INFO - Running setup.py install for psutil 17:55:52 INFO - building 'psutil._psutil_osx' extension 17:55:52 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 17:55:53 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 17:55:53 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 17:55:53 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 17:55:53 INFO - building 'psutil._psutil_posix' extension 17:55:53 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 17:55:53 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:55:53 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:55:53 INFO - ^ 17:55:53 INFO - 1 warning generated. 17:55:53 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 17:55:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:55:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:55:53 INFO - Successfully installed psutil 17:55:53 INFO - Cleaning up... 17:55:53 INFO - Return code: 0 17:55:53 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:55:53 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')]} 17:55:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:55:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:55:53 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')]} 17:55:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:55:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:55:53 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 0x10cef2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cef5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa53d78890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccd0d30>, '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 0x10cbf7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ccc5be8>, '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.RegKVGgKCU/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.3z0VqiHUzl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/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 17:55:53 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 17:55:53 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 17:55:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RegKVGgKCU/Render', 17:55:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:55:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:55:53 INFO - 'HOME': '/Users/cltbld', 17:55:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:55:53 INFO - 'LOGNAME': 'cltbld', 17:55:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:55:53 INFO - 'MOZ_NO_REMOTE': '1', 17:55:53 INFO - 'NO_EM_RESTART': '1', 17:55:53 INFO - 'PAGER': '/bin/cat', 17:55:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:55:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:55:53 INFO - 'PWD': '/builds/slave/test', 17:55:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:55:53 INFO - 'SHELL': '/bin/bash', 17:55:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3z0VqiHUzl/Listeners', 17:55:53 INFO - 'TMPDIR': '/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/', 17:55:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:55:53 INFO - 'USER': 'cltbld', 17:55:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:55:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:55:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:55:53 INFO - 'XPC_FLAGS': '0x0', 17:55:53 INFO - 'XPC_SERVICE_NAME': '0', 17:55:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:55:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:55:54 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:55:54 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 17:55:54 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 17:55:54 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 17:55:54 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 17:55:54 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 17:55:54 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 17:55:56 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:55:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:55:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:55:56 INFO - Installing collected packages: mozsystemmonitor 17:55:56 INFO - Running setup.py install for mozsystemmonitor 17:55:57 INFO - Successfully installed mozsystemmonitor 17:55:57 INFO - Cleaning up... 17:55:57 INFO - Return code: 0 17:55:57 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:55:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:55:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:55:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:55:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:55:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:55:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:55:57 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 0x10cef2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cef5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa53d78890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccd0d30>, '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 0x10cbf7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ccc5be8>, '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.RegKVGgKCU/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.3z0VqiHUzl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/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 17:55:57 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 17:55:57 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 17:55:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RegKVGgKCU/Render', 17:55:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:55:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:55:57 INFO - 'HOME': '/Users/cltbld', 17:55:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:55:57 INFO - 'LOGNAME': 'cltbld', 17:55:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:55:57 INFO - 'MOZ_NO_REMOTE': '1', 17:55:57 INFO - 'NO_EM_RESTART': '1', 17:55:57 INFO - 'PAGER': '/bin/cat', 17:55:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:55:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:55:57 INFO - 'PWD': '/builds/slave/test', 17:55:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:55:57 INFO - 'SHELL': '/bin/bash', 17:55:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3z0VqiHUzl/Listeners', 17:55:57 INFO - 'TMPDIR': '/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/', 17:55:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:55:57 INFO - 'USER': 'cltbld', 17:55:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:55:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:55:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:55:57 INFO - 'XPC_FLAGS': '0x0', 17:55:57 INFO - 'XPC_SERVICE_NAME': '0', 17:55:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:55:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:55:57 INFO - Downloading/unpacking jsonschema==2.5.1 17:55:57 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 17:55:57 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 17:55:57 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 17:55:57 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 17:55:57 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 17:55:57 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 17:55:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:55:59 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:55:59 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 17:55:59 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 17:55:59 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 17:55:59 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 17:55:59 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 17:55:59 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 17:56:00 INFO - Downloading functools32-3.2.3-2.tar.gz 17:56:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:56:00 INFO - warning: no files found matching '*.txt' 17:56:00 INFO - no previously-included directories found matching 'build' 17:56:00 INFO - no previously-included directories found matching 'dist' 17:56:00 INFO - no previously-included directories found matching '.git*' 17:56:00 INFO - Installing collected packages: jsonschema, functools32 17:56:00 INFO - Running setup.py install for functools32 17:56:00 INFO - warning: no files found matching '*.txt' 17:56:00 INFO - no previously-included directories found matching 'build' 17:56:00 INFO - no previously-included directories found matching 'dist' 17:56:00 INFO - no previously-included directories found matching '.git*' 17:56:00 INFO - Successfully installed jsonschema functools32 17:56:00 INFO - Cleaning up... 17:56:00 INFO - Return code: 0 17:56:00 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:56:00 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')]} 17:56:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:56:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:56:00 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')]} 17:56:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:56:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:56:00 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 0x10cef2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cef5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa53d78890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccd0d30>, '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 0x10cbf7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ccc5be8>, '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.RegKVGgKCU/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.3z0VqiHUzl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/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 17:56:00 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 17:56:00 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 17:56:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RegKVGgKCU/Render', 17:56:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:56:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:56:00 INFO - 'HOME': '/Users/cltbld', 17:56:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:56:00 INFO - 'LOGNAME': 'cltbld', 17:56:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:56:00 INFO - 'MOZ_NO_REMOTE': '1', 17:56:00 INFO - 'NO_EM_RESTART': '1', 17:56:00 INFO - 'PAGER': '/bin/cat', 17:56:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:56:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:56:00 INFO - 'PWD': '/builds/slave/test', 17:56:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:56:00 INFO - 'SHELL': '/bin/bash', 17:56:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3z0VqiHUzl/Listeners', 17:56:00 INFO - 'TMPDIR': '/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/', 17:56:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:56:00 INFO - 'USER': 'cltbld', 17:56:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:56:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:56:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:56:00 INFO - 'XPC_FLAGS': '0x0', 17:56:00 INFO - 'XPC_SERVICE_NAME': '0', 17:56:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:56:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:56:00 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:56:00 INFO - Cleaning up... 17:56:00 INFO - Return code: 0 17:56:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:56:00 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')]} 17:56:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:56:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:56:00 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')]} 17:56:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:56:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:56:00 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 0x10cef2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cef5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa53d78890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccd0d30>, '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 0x10cbf7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ccc5be8>, '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.RegKVGgKCU/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.3z0VqiHUzl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/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 17:56:00 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 17:56:00 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 17:56:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RegKVGgKCU/Render', 17:56:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:56:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:56:00 INFO - 'HOME': '/Users/cltbld', 17:56:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:56:00 INFO - 'LOGNAME': 'cltbld', 17:56:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:56:00 INFO - 'MOZ_NO_REMOTE': '1', 17:56:00 INFO - 'NO_EM_RESTART': '1', 17:56:00 INFO - 'PAGER': '/bin/cat', 17:56:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:56:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:56:00 INFO - 'PWD': '/builds/slave/test', 17:56:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:56:00 INFO - 'SHELL': '/bin/bash', 17:56:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3z0VqiHUzl/Listeners', 17:56:00 INFO - 'TMPDIR': '/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/', 17:56:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:56:00 INFO - 'USER': 'cltbld', 17:56:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:56:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:56:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:56:00 INFO - 'XPC_FLAGS': '0x0', 17:56:00 INFO - 'XPC_SERVICE_NAME': '0', 17:56:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:56:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:56:01 INFO - Downloading/unpacking blobuploader==1.2.4 17:56:01 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 17:56:01 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 17:56:01 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 17:56:01 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 17:56:01 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 17:56:01 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 17:56:03 INFO - Downloading blobuploader-1.2.4.tar.gz 17:56:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:56:04 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:56:04 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 17:56:04 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 17:56:04 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 17:56:04 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 17:56:04 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 17:56:04 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 17:56:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:56:04 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:56:04 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 17:56:04 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 17:56:04 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 17:56:04 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 17:56:04 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 17:56:04 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 17:56:05 INFO - Downloading docopt-0.6.1.tar.gz 17:56:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:56:05 INFO - Installing collected packages: blobuploader, requests, docopt 17:56:05 INFO - Running setup.py install for blobuploader 17:56:05 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:56:05 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:56:05 INFO - Running setup.py install for requests 17:56:05 INFO - Running setup.py install for docopt 17:56:06 INFO - Successfully installed blobuploader requests docopt 17:56:06 INFO - Cleaning up... 17:56:06 INFO - Return code: 0 17:56:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:56:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:56:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:56:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:56:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:56:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:56:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:56:06 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 0x10cef2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cef5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa53d78890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccd0d30>, '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 0x10cbf7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ccc5be8>, '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.RegKVGgKCU/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.3z0VqiHUzl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/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 17:56:06 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 17:56:06 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 17:56:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RegKVGgKCU/Render', 17:56:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:56:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:56:06 INFO - 'HOME': '/Users/cltbld', 17:56:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:56:06 INFO - 'LOGNAME': 'cltbld', 17:56:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:56:06 INFO - 'MOZ_NO_REMOTE': '1', 17:56:06 INFO - 'NO_EM_RESTART': '1', 17:56:06 INFO - 'PAGER': '/bin/cat', 17:56:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:56:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:56:06 INFO - 'PWD': '/builds/slave/test', 17:56:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:56:06 INFO - 'SHELL': '/bin/bash', 17:56:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3z0VqiHUzl/Listeners', 17:56:06 INFO - 'TMPDIR': '/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/', 17:56:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:56:06 INFO - 'USER': 'cltbld', 17:56:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:56:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:56:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:56:06 INFO - 'XPC_FLAGS': '0x0', 17:56:06 INFO - 'XPC_SERVICE_NAME': '0', 17:56:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:56:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:56:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:56:06 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-GbECPO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:56:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:56:06 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-LksLH4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:56:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:56:06 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-ickhEy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:56:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:56:06 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-Cr7tT5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:56:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:56:06 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-hk9P9i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:56:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:56:06 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-Qd4VTE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:56:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:56:07 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-Z9hJIy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:56:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:56:07 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-HblqU2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:56:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:56:07 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-1ZYAR9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:56:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:56:07 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-5PIvnk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:56:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:56:07 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-uIs4ZT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:56:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:56:07 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-1lDlCk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:56:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:56:07 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-lc6vnL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:56:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:56:07 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-06fRYZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:56:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:56:07 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-kaudjt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:56:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:56:07 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-ypZ3SM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:56:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:56:08 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-OcMjBr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:56:08 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:56:08 INFO - Running setup.py install for manifestparser 17:56:08 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:56:08 INFO - Running setup.py install for mozcrash 17:56:08 INFO - Running setup.py install for mozdebug 17:56:08 INFO - Running setup.py install for mozdevice 17:56:08 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:56:08 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:56:08 INFO - Running setup.py install for mozfile 17:56:08 INFO - Running setup.py install for mozhttpd 17:56:09 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:56:09 INFO - Running setup.py install for mozinfo 17:56:09 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:56:09 INFO - Running setup.py install for mozInstall 17:56:09 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:56:09 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:56:09 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:56:09 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:56:09 INFO - Running setup.py install for mozleak 17:56:09 INFO - Running setup.py install for mozlog 17:56:09 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:56:09 INFO - Running setup.py install for moznetwork 17:56:09 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:56:09 INFO - Running setup.py install for mozprocess 17:56:10 INFO - Running setup.py install for mozprofile 17:56:10 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:56:10 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:56:10 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:56:10 INFO - Running setup.py install for mozrunner 17:56:10 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:56:10 INFO - Running setup.py install for mozscreenshot 17:56:10 INFO - Running setup.py install for moztest 17:56:10 INFO - Running setup.py install for mozversion 17:56:10 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:56:10 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:56:10 INFO - Cleaning up... 17:56:10 INFO - Return code: 0 17:56:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:56:10 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')]} 17:56:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:56:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:56:10 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')]} 17:56:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:56:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:56:10 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 0x10cef2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cef5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa53d78890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccd0d30>, '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 0x10cbf7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ccc5be8>, '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.RegKVGgKCU/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.3z0VqiHUzl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/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 17:56:10 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 17:56:10 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 17:56:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RegKVGgKCU/Render', 17:56:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:56:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:56:10 INFO - 'HOME': '/Users/cltbld', 17:56:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:56:10 INFO - 'LOGNAME': 'cltbld', 17:56:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:56:10 INFO - 'MOZ_NO_REMOTE': '1', 17:56:10 INFO - 'NO_EM_RESTART': '1', 17:56:10 INFO - 'PAGER': '/bin/cat', 17:56:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:56:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:56:10 INFO - 'PWD': '/builds/slave/test', 17:56:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:56:10 INFO - 'SHELL': '/bin/bash', 17:56:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3z0VqiHUzl/Listeners', 17:56:10 INFO - 'TMPDIR': '/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/', 17:56:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:56:10 INFO - 'USER': 'cltbld', 17:56:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:56:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:56:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:56:10 INFO - 'XPC_FLAGS': '0x0', 17:56:10 INFO - 'XPC_SERVICE_NAME': '0', 17:56:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:56:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:56:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:56:11 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-HZJSIv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:56:11 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)) 17:56:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:56:11 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-zanKQZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:56:11 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)) 17:56:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:56:11 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-HChWED-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:56:11 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)) 17:56:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:56:11 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-9ArS3M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:56:11 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)) 17:56:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:56:11 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-qp_676-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:56:11 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)) 17:56:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:56:11 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-BgpnzQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:56:11 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)) 17:56:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:56:11 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-ntCDhT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:56:11 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)) 17:56:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:56:11 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-fP_gMk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:56:11 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)) 17:56:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:56:11 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-8LGPVn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:56:12 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)) 17:56:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:56:12 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-otaH4W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:56:12 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)) 17:56:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:56:12 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-rBJ04Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:56:12 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)) 17:56:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:56:12 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-7YCcEC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:56:12 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)) 17:56:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:56:12 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-n6oJzS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:56:12 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)) 17:56:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:56:12 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-ZYTKnY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:56:12 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)) 17:56:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:56:12 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-BXjBtx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:56:12 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)) 17:56:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:56:12 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-5_ZY35-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:56:12 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)) 17:56:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:56:12 INFO - Running setup.py (path:/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/pip-MvqtEz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:56:12 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)) 17:56:12 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)) 17:56:12 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)) 17:56:12 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)) 17:56:12 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)) 17:56:12 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)) 17:56:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:56:12 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 17:56:12 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 17:56:12 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 17:56:12 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 17:56:12 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 17:56:12 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 17:56:15 INFO - Downloading blessings-1.6.tar.gz 17:56:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:56:15 INFO - Installing collected packages: blessings 17:56:15 INFO - Running setup.py install for blessings 17:56:15 INFO - Successfully installed blessings 17:56:15 INFO - Cleaning up... 17:56:15 INFO - Return code: 0 17:56:15 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:56:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:56:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:56:16 INFO - Reading from file tmpfile_stdout 17:56:16 INFO - Current package versions: 17:56:16 INFO - blessings == 1.6 17:56:16 INFO - blobuploader == 1.2.4 17:56:16 INFO - docopt == 0.6.1 17:56:16 INFO - functools32 == 3.2.3-2 17:56:16 INFO - jsonschema == 2.5.1 17:56:16 INFO - manifestparser == 1.1 17:56:16 INFO - mozInstall == 1.12 17:56:16 INFO - mozcrash == 1.0 17:56:16 INFO - mozdebug == 0.1 17:56:16 INFO - mozdevice == 0.49 17:56:16 INFO - mozfile == 1.2 17:56:16 INFO - mozhttpd == 0.7 17:56:16 INFO - mozinfo == 0.9 17:56:16 INFO - mozleak == 0.1 17:56:16 INFO - mozlog == 3.4 17:56:16 INFO - moznetwork == 0.27 17:56:16 INFO - mozprocess == 0.24 17:56:16 INFO - mozprofile == 0.28 17:56:16 INFO - mozrunner == 6.13 17:56:16 INFO - mozscreenshot == 0.1 17:56:16 INFO - mozsystemmonitor == 0.3 17:56:16 INFO - moztest == 0.8 17:56:16 INFO - mozversion == 1.4 17:56:16 INFO - psutil == 3.1.1 17:56:16 INFO - requests == 1.2.3 17:56:16 INFO - wsgiref == 0.1.2 17:56:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:56:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:56:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:56:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:56:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:56:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:56:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:56:17 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 0x10cef2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cef5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa53d78890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccd0d30>, '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 0x10cbf7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ccc5be8>, '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.RegKVGgKCU/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.3z0VqiHUzl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/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 17:56:17 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 17:56:17 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 17:56:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RegKVGgKCU/Render', 17:56:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:56:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:56:17 INFO - 'HOME': '/Users/cltbld', 17:56:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:56:17 INFO - 'LOGNAME': 'cltbld', 17:56:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:56:17 INFO - 'MOZ_NO_REMOTE': '1', 17:56:17 INFO - 'NO_EM_RESTART': '1', 17:56:17 INFO - 'PAGER': '/bin/cat', 17:56:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:56:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:56:17 INFO - 'PWD': '/builds/slave/test', 17:56:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:56:17 INFO - 'SHELL': '/bin/bash', 17:56:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3z0VqiHUzl/Listeners', 17:56:17 INFO - 'TMPDIR': '/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/', 17:56:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:56:17 INFO - 'USER': 'cltbld', 17:56:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:56:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:56:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:56:17 INFO - 'XPC_FLAGS': '0x0', 17:56:17 INFO - 'XPC_SERVICE_NAME': '0', 17:56:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:56:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:56:17 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)) 17:56:17 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)) 17:56:17 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)) 17:56:17 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)) 17:56:17 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)) 17:56:17 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)) 17:56:17 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)) 17:56:17 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)) 17:56:17 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)) 17:56:17 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)) 17:56:17 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)) 17:56:17 INFO - Cleaning up... 17:56:17 INFO - Return code: 0 17:56:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:56:17 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')]} 17:56:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:56:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:56:17 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')]} 17:56:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:56:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:56:17 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 0x10cef2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cef5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa53d78890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccd0d30>, '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 0x10cbf7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ccc5be8>, '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.RegKVGgKCU/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.3z0VqiHUzl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/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 17:56:17 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 17:56:17 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 17:56:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RegKVGgKCU/Render', 17:56:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:56:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:56:17 INFO - 'HOME': '/Users/cltbld', 17:56:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:56:17 INFO - 'LOGNAME': 'cltbld', 17:56:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:56:17 INFO - 'MOZ_NO_REMOTE': '1', 17:56:17 INFO - 'NO_EM_RESTART': '1', 17:56:17 INFO - 'PAGER': '/bin/cat', 17:56:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:56:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:56:17 INFO - 'PWD': '/builds/slave/test', 17:56:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:56:17 INFO - 'SHELL': '/bin/bash', 17:56:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3z0VqiHUzl/Listeners', 17:56:17 INFO - 'TMPDIR': '/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/', 17:56:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:56:17 INFO - 'USER': 'cltbld', 17:56:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:56:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:56:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:56:17 INFO - 'XPC_FLAGS': '0x0', 17:56:17 INFO - 'XPC_SERVICE_NAME': '0', 17:56:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:56:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:56:17 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:56:17 INFO - Cleaning up... 17:56:17 INFO - Return code: 0 17:56:17 INFO - Running post-action listener: _resource_record_post_action 17:56:17 INFO - Running post-action listener: _start_resource_monitoring 17:56:17 INFO - Starting resource monitoring. 17:56:17 INFO - [mozharness: 2017-01-10 01:56:17.963597Z] Finished create-virtualenv step (success) 17:56:17 INFO - [mozharness: 2017-01-10 01:56:17.964560Z] Running install step. 17:56:17 INFO - Running pre-action listener: _resource_record_pre_action 17:56:17 INFO - Running main action method: install 17:56:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:56:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:56:18 INFO - Reading from file tmpfile_stdout 17:56:18 INFO - Detecting whether we're running mozinstall >=1.0... 17:56:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:56:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:56:18 INFO - Reading from file tmpfile_stdout 17:56:18 INFO - Output received: 17:56:18 INFO - Usage: mozinstall [options] installer 17:56:18 INFO - Options: 17:56:18 INFO - -h, --help show this help message and exit 17:56:18 INFO - -d DEST, --destination=DEST 17:56:18 INFO - Directory to install application into. [default: 17:56:18 INFO - "/builds/slave/test"] 17:56:18 INFO - --app=APP Application being installed. [default: firefox] 17:56:18 INFO - mkdir: /builds/slave/test/build/application 17:56:18 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'] 17:56:18 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 17:56:40 INFO - Reading from file tmpfile_stdout 17:56:40 INFO - Output received: 17:56:40 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 17:56:40 INFO - Running post-action listener: _resource_record_post_action 17:56:40 INFO - [mozharness: 2017-01-10 01:56:40.086503Z] Finished install step (success) 17:56:40 INFO - [mozharness: 2017-01-10 01:56:40.086664Z] Running run-tests step. 17:56:40 INFO - Running pre-action listener: _resource_record_pre_action 17:56:40 INFO - Running main action method: run_tests 17:56:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:56:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:56:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:56:40 INFO - 2017-01-09 17:56:40.101 screenresolution[1624:7641] starting screenresolution argv=screenresolution get 17:56:40 INFO - 2017-01-09 17:56:40.103 screenresolution[1624:7641] Display 0: 1600x1200x32@60 17:56:40 INFO - 2017-01-09 17:56:40.109 screenresolution[1625:7643] starting screenresolution argv=screenresolution list 17:56:40 INFO - Available Modes on Display 0 17:56:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:56:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:56:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:56:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:56:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:56:40 INFO - Intel Iris: 17:56:40 INFO - Chipset Model: Intel Iris 17:56:40 INFO - Type: GPU 17:56:40 INFO - Bus: Built-In 17:56:40 INFO - VRAM (Dynamic, Max): 1536 MB 17:56:40 INFO - Vendor: Intel (0x8086) 17:56:40 INFO - Device ID: 0x0a2e 17:56:40 INFO - Revision ID: 0x0009 17:56:40 INFO - Displays: 17:56:40 INFO - Display: 17:56:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:56:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:56:40 INFO - Display Serial Number: 1600x1200 60 17:56:40 INFO - Main Display: Yes 17:56:40 INFO - Mirror: Off 17:56:40 INFO - Online: Yes 17:56:40 INFO - Rotation: Supported 17:56:40 INFO - Return code: 0 17:56:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:56:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:56:40 INFO - Python 2.7.3 17:56:40 INFO - Return code: 0 17:56:40 INFO - grabbing minidump binary from tooltool 17:56: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')]} 17:56:40 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 0x10ccd0d30>, '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 0x10cbf7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ccc5be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:56:40 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 17:56:40 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 17:56:40 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 17:56:40 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:56:40 INFO - Return code: 0 17:56:40 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:56:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:56:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:56:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:56:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:56:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Xt1nRQoyTk6VhuqcNwTerQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0155', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 17:56:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Xt1nRQoyTk6VhuqcNwTerQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0155 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 17:56:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RegKVGgKCU/Render', 17:56:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:56:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:56:40 INFO - 'HOME': '/Users/cltbld', 17:56:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:56:40 INFO - 'LOGNAME': 'cltbld', 17:56:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:56:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:56:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:56:40 INFO - 'MOZ_NO_REMOTE': '1', 17:56:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:56:40 INFO - 'NO_EM_RESTART': '1', 17:56:40 INFO - 'PAGER': '/bin/cat', 17:56:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:56:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:56:40 INFO - 'PWD': '/builds/slave/test', 17:56:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:56:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:56:40 INFO - 'SHELL': '/bin/bash', 17:56:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3z0VqiHUzl/Listeners', 17:56:40 INFO - 'TMPDIR': '/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/', 17:56:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:56:40 INFO - 'USER': 'cltbld', 17:56:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:56:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:56:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:56:40 INFO - 'XPC_FLAGS': '0x0', 17:56:40 INFO - 'XPC_SERVICE_NAME': '0', 17:56:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:56:40 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Xt1nRQoyTk6VhuqcNwTerQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0155', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 17:56:40 INFO - mozversion application_buildid: 20170109163507 17:56:40 INFO - mozversion application_changeset: 845cc4dea57f6cc93f46810d24b1058b640c3b74 17:56:40 INFO - mozversion application_display_name: Nightly 17:56:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:56:40 INFO - mozversion application_name: Firefox 17:56:40 INFO - mozversion application_remotingname: firefox 17:56:40 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 17:56:40 INFO - mozversion application_vendor: Mozilla 17:56:40 INFO - mozversion application_version: 53.0a1 17:56:40 INFO - mozversion platform_buildid: 20170109163507 17:56:40 INFO - mozversion platform_changeset: 845cc4dea57f6cc93f46810d24b1058b640c3b74 17:56:40 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 17:56:40 INFO - mozversion platform_version: 53.0a1 17:56:40 INFO - using testdate: 1484013400 17:56:40 INFO - actual date: 1484013400 17:56:40 INFO - starting webserver on 'localhost:49250' 17:56:40 INFO - SUITE-START | Running 2 tests 17:56:40 INFO - TEST-START | dromaeo_css 17:56:40 INFO - operating with platform_type : mac_ 17:56:40 INFO - Initialising browser for dromaeo_css test... 17:56:40 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/tmpnkkS_i/profile http://localhost:49250/getInfo.html) 17:56:41 INFO - PROCESS | 1635 | 1484013401285 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4906 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2060 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3761 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2794 < callProvider()@resource://gre/modules/AddonManager.jsm:267 < _startProvider()@resource://gre/modules/AddonManager.jsm:973 < startup()@resource://gre/modules/AddonManager.jsm:1155 < startup()@resource://gre/modules/AddonManager.jsm:3124 < observe()@resource://gre/components/addonManager.js:65 17:56:43 INFO - PROCESS | 1635 | __metrics Screen width/height:1600/1200 17:56:43 INFO - PROCESS | 1635 | colorDepth:24 17:56:43 INFO - PROCESS | 1635 | Browser inner width/height: 1024/689 17:56:43 INFO - PROCESS | 1635 | __metrics 17:56:43 INFO - PROCESS | 1635 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 17:56:43 INFO - PROCESS | 1635 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:56:43 INFO - TEST-INFO | 1635: exit 0 17:56:43 INFO - Browser initialized. 17:56:43 INFO - Running cycle 1/1 for dromaeo_css test... 17:56:43 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/tmpnkkS_i/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:56:56 INFO - PROCESS | 1637 | RSS: Main: 226844672 17:56:56 INFO - PROCESS | 1637 | 18:00:00 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49250/tests/dromaeo/cssquery-ext.html) 18:00:02 INFO - PROCESS | 1637 | RSS: Main: 412459008 18:00:02 INFO - PROCESS | 1637 | 18:01:45 INFO - PROCESS | 1637 | ************************* 18:01:45 INFO - PROCESS | 1637 | A coding exception was thrown and uncaught in a Task. 18:01:45 INFO - PROCESS | 1637 | 18:01:45 INFO - PROCESS | 1637 | Full message: ReferenceError: fetch is not defined 18:01:45 INFO - PROCESS | 1637 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 18:01:45 INFO - PROCESS | 1637 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 18:01:45 INFO - PROCESS | 1637 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 18:01:45 INFO - PROCESS | 1637 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 18:01:45 INFO - PROCESS | 1637 | TaskImpl@resource://gre/modules/Task.jsm:277:3 18:01:45 INFO - PROCESS | 1637 | asyncFunction@resource://gre/modules/Task.jsm:252:14 18:01:45 INFO - PROCESS | 1637 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 18:01:45 INFO - PROCESS | 1637 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 18:01:45 INFO - PROCESS | 1637 | TaskImpl@resource://gre/modules/Task.jsm:277:3 18:01:45 INFO - PROCESS | 1637 | asyncFunction@resource://gre/modules/Task.jsm:252:14 18:01:45 INFO - PROCESS | 1637 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 18:01:45 INFO - PROCESS | 1637 | 18:01:45 INFO - PROCESS | 1637 | ************************* 18:03:05 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-ext.html (next: http://localhost:49250/tests/dromaeo/cssquery-jquery.html) 18:03:05 INFO - PROCESS | 1637 | RSS: Main: 367431680 18:03:05 INFO - PROCESS | 1637 | 18:06:08 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49250/tests/dromaeo/cssquery-mootools.html) 18:06:08 INFO - PROCESS | 1637 | RSS: Main: 344756224 18:06:08 INFO - PROCESS | 1637 | 18:09:11 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49250/tests/dromaeo/cssquery-prototype.html) 18:09:11 INFO - PROCESS | 1637 | RSS: Main: 341463040 18:09:11 INFO - PROCESS | 1637 | 18:12:15 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49250/tests/dromaeo/cssquery-yui.html) 18:12:15 INFO - PROCESS | 1637 | RSS: Main: 328151040 18:12:15 INFO - PROCESS | 1637 | 18:15:18 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-yui.html (next: http://localhost:49250/tests/dromaeo/cssquery-dojo.html) 18:15:19 INFO - PROCESS | 1637 | RSS: Main: 457351168 18:15:19 INFO - PROCESS | 1637 | 18:15:19 INFO - PROCESS | 1637 | __start_tp_report 18:15:19 INFO - PROCESS | 1637 | _x_x_mozilla_page_load 18:15:19 INFO - PROCESS | 1637 | _x_x_mozilla_page_load_details 18:15:19 INFO - PROCESS | 1637 | |i|pagename|runs| 18:15:19 INFO - PROCESS | 1637 | |0;dojo.html;2796.4826507096195;3304.1244070321363;3304.686054824791;3315.8010519368854;3380.3746306933217;4604.631629469643;4849.10291596055;5438.700871452069;5578.776848926043;5592.636478628886;5240.868978275545;5471.452854714536;5566.972165139179;5592.0773072443;5617.662940223589;5031.672941258825;5061.240813877911;5068.594512438996;5100.974495127519;5131.97360527894;5067.493250674921;5177.534021938025;5178.171492561191;5189.922151167735;5195.272661827347;4619.514950930155;5038.39539255288;5144.4083930348115;5164.819231326923;5211.114110601188;5061.240813877911;5078.085944529983;5114.335136432277;5124.538791508745;5124.590032797404;5066.46802085783;5098.949010509921;5158.613104017214;5159.69041857486;5166.896662066738;4681.695689780154;5536.2249285100115;5566.777328906839;5582.972085139589;5607.579431542651;5943.970280148615;5985.880282394328;6139.570230083896;6145.539084568631;6154.199954005995;6112.086414657059;6122.733612273343;6145;6192.411720886508;6259.186305780219;5654.547636189095;5705.971470142623;5708.315002199763;5715.914261286084;5728.054870946289;6288.811335659938;6338.366163383717;6358.809235722843;6361.236651601837;6362.84092897672;5812.7674893003805;5871.6770577618645;5875.441833025856;5937.406259374028;6043.7280322385595;6107.370879189147;6111.105587097633;6116.405802871558;6119.593952096689;6127.7393367763425;5954.761809527657;6016.879662406727;6020.397960204032;6020.69896505173;6026.246719160105;6035.969820150871;6079.908801367983;6086.147939288503;6096.298925623521;6103.23709536308;4794.496577859274;4798.424189097331;4801.807927682923;4805.111054454937;4807.254875494304;3818.491695780151;4066.552679205285;4249.638780703613;4256.36154576816;4359.651227901761;5633.408492108351;5665.575081868877;5665.886682266331;5673.6028478006965;5704.258446401941;6189.195404597373;6189.8452538686;6203.689815509207;6203.844903877439;6211.223597050369;5467.234587157801;5546.972265138648;5706.42935706426;5706.572007099484;5750.108733146369;5222.817201397933;5236.162214045735;5280.471952804689;5290.92063619046;5345.33183352081;6292.716827742671;6326;6329.620222786648;6335.778247761306;6343.429091381706;6159.9836027055035;6175.166352542349;6180.721867515883;6181.876362472815;6182.350853160533;6147.784827531015;6150.138980542817;6158.876822463436;6245.156903818108;6248.812535623939;5969.880602388015;5972.074328479092;5982;5982.52139828804;6024.216851809237;5799.333076696234;6087.726052327567;6090.908636370546;6091.939080609137;6101.969490152521;5792.536597072309;5810.883782324246;5843.590948633555;5849.415058494116;5892.469677729025;5853.0635098384055;5856.297244330707;5856.326522449973;5866.530677545871;5884.440978107073;5770.538356931519;5770.740316685843;5824.475797178275;5838.708064596838;5899.852503687443;5979.342272350125;5999;6007.369226231182;6018.398160183947;6034.607750496204;5824.446677565625;5830.300363956352;5837.29952405714;5838.036723940502;5850.64896106235 18:15:19 INFO - PROCESS | 1637 | |1;ext.html;444.0541646293395;469.58207195595924;499.35333742803067;513.304472439844;515.3789683431465;27903.581446278324;39617.60382396175;41373.17253654925;41585.37621935666;43286.78356608212;42254.36618450718;42476.15047699044;42498.93752656185;42564.574354256445;42663.78668106655;9186.173244408019;9424.952875235615;9437.433753974748;9441.81116377672;9458;8512.319014478859;8721.738347849574;11079.224454288204;11431.371274579895;11438.428078596078;11304.265222760536;11356.148288878367;11368.033717134054;11374;11377.829332560019;11378.032867206297;11440.370779607118;11451.198416110876;11464.426778661116;11482.598109066184;8414.78963025923;8651.870221946676;10957.945210273898;11254.043406310473;11282.943585282103;39775.801120994496;40299;40316.193676126604;40481.39277910833;40482;4008.4588580541836;4020.6582440492593;4052.959470405288;4054.452648892391;4140.37894315852;30027.849860750557;30893.845530772425;31178.688213117806;31209.37581248385;31653.841730791428;2690.340866487723;3122.2506598416444;3274.6889045540634;3278.606567211926;3314.453115235983;360257;362753.18623406714;364434;364476;364485;59810.40189598048;60009.69995149996;60121.398786012454;60128.69935650294;60138.39861601416;64010;64010.35989640137;64031;64088.35911640917;64103.679481602296;15780.92109539445;15977.840221597635;16054.43809466663;16062.75905861413;16069.357225711074;11321.603743868925;12403;12529.248245105322;12545;12563.937180314222;14490.275486225857;16300;16613.91693041527;16623.00261984285;17016.65966680684;6957.860842783116;7076.398506127037;7131.92868071323;7139.286071392819;7224.277572242839;693.6843736100062;870.2168048756043;873.895132584094;893.097971049245;900.617237673986;26566.6015009775;29307.560386594218;29661;29991.550126748116;30332.393352132833;1063.5586231713908;1077.8922107789315;1089.8637670291214;1093.33853018924;1205.6804946689135;14671.89187027634;14980.40078396852;15569.688606227812;15630.296636651377;15865.04809711421;202053;231513.84243078675;232021;232174;232321.83839080695;60116.699416503514;60415.093773593435;60477.69761151166;60484.69757651183;60595.697021514614;1446.6094154578204;1454.5127382326828;1462.7878957551095;1464.3044553836842;1474.2702362330715;26183.90528757733;26945.865270673523;29250;29478.85260573769;29661;49683.751581243305;50057.74855628639;50163.99672006613;50245;50432.49567504425;447155;447783;447980;448384;449828;24485.51028979446;25016.37459063538;25115;39469.80265098656;39648.603513965645;36050.63949360473;38155;41317.38023929583;43044;43597.78201109101;35662.8216858914;35683.46474802828;36039.81980090083;36808.631913681595;36818.815905920295;34784;35306.64693353033;35533.64466355303;36130.45804312885;36337.454938176474;34940;35796.4630530537;36055.45916811198;36094.63905360913;36261.274774503836;29993;31277.218069548442;31412.37175256528;31486.685133148374;31491.527627085154;21834;21852.45368865791;22303.327250455957;23521.529569408172;23926.64110038316 18:15:19 INFO - PROCESS | 1637 | |2;jquery.html;389.62012038262696;395.873320537428;397.7494178667439;401.9899502512436;421.3637407514652;13479.528216512424;19108.617827643455;20198.798012019895;20243.291484798017;20251;17101.31594736212;17624.471265862012;20492.69260961083;21009.47476313093;21353.57292854142;5122.667026643257;5620.971895140518;5855.736491857877;5941.88116237675;5944.7324870380935;5695.601307908448;5871;5887.646741195542;5916.3787802280785;5929.140274660172;5902.793402230923;5904.114382842565;5905.438983296602;5913.3199682036475;5918.822435326947;5670.404607516192;5896.88206235875;5897.587168898179;5917.852053698692;6011.639301641915;5890.911636325459;5901.3508514063415;5903.970480147572;5906;5909.23179986599;13391.933040334834;13635.454581816715;13775.931120344434;13792.310384480836;13819;2622.3050891513763;2785.331520435101;2842.104737007836;2971.5691224772504;2980.2251414632133;7102.715891364339;10179.491025448771;10505.369677819355;10720.571177152904;10768.24622276441;1834.2754611928215;1890.395073576456;1899.477643647994;1901.1729897494633;1905.2283825050877;113011.86988130095;136722.632773672;147316;148415.51584484125;149020.2548987259;10049.69850904474;10456.692913385827;11683.357415342069;11778.823317650242;12070.517179312905;15779.447719329997;15793.842061579468;15798.447054353272;15810.841891580936;15820;6513.804585862432;7817.687292508236;8186.918130818734;8238;8513.40406171562;8288.58557072144;8408.831823363498;8458.450200736932;8574.314054875595;8579.699710510227;4312.568743125725;4343.370211319404;4363.934540981888;4380.474343078788;4424.889377765518;1734.6010417604023;2035.7672303947159;2040.2349119080345;2043.4074118505466;2058.7014882841904;881.8060026794224;1033.984490232632;1035.9067683908333;1041.2555023158327;1082.3614067700096;21545.353639390843;21892.562148756937;21907.67138492924;21908.342749717533;22040;1045.310095337089;1048.528162326956;1062.9574817007388;1070.4380200394698;1077.6659235636978;7612.3148916597775;9169.495677737788;10759.89240107589;11245.212835101463;11272.66182014541;346955;347226;347483;588669;591825;127227;149717;151202;158680.38283224238;161151;1043.2332235806828;1082.6481393547128;1094.9014588686898;1100.4772732951783;1106.0543235533503;16129.838701612834;19277.614447711247;20004.399868003984;20093.598128037065;20122.89938550298;48675.269870952674;58030.709846450496;62504.81233157;62881.8821270904;63656;79274.20725792668;80518.7922181156;80588;81935.18064819275;83497.16502835137;7121.181064177686;8442.788930276793;8752.862127923328;8796.07641197665;8900.020997690379;12885.935570322088;13372.799408009083;13571.932140339235;13597.86402135966;14266;11626.53493860236;11637.825432618349;11687;11930.76138477243;12050.879491204976;11522.942385288356;11603.303801771921;11728.530858765555;11942;12093.75812483763;11707.063434925356;11946.641600751991;11962.889648691229;11999.64001079969;12022;6998.475114366544;8340.749777506684;8452.281556067803;8522.892024036835;8743.825123497621;8476.576171191293;10605.41670208146;11493.712657183303;11663.475143618727;11800.822987655363 18:15:19 INFO - PROCESS | 1637 | |3;mootools.html;1441.0777102654297;1460.4085345435099;1465.4211586423362;1479.1642721862147;1490.2027415332795;6655.833604159892;8607.91392086079;8614.52620105894;8631.827363452736;8660.956695216522;7600.885986710204;7832.7650170494835;7833.099193592739;8108.837823243532;8465.153484651531;2282.9200977965734;2343.7070366204225;2344.941376465585;2351.43565544269;2351.776581224781;2342.0982921575232;2356.8350215484925;2369.526094781042;2375.168690958168;2377.4413012941945;1929.440462265948;2284.4060544258527;2333.5099629077913;2356.893939772706;2360.0795689680954;1781.6971114910496;2116.481462041802;2132.797384248494;2138.967915481269;2145.8497905146723;2327.674125622414;2356.4108972756812;2358.5990381635165;2358.858468491896;2360.1031607988853;8385.287250583708;8462.576871156418;8476.49141051539;8482.321414286904;8483.915160848373;5123.077845987721;5275.261463395128;5284.5243928046275;5285.709285989269;5286.735663216824;3350.530925670408;3380.4364353366323;3530.1527633368064;3580.946285805715;3587.6232995535356;1174.5947648061406;1191.6901605582611;1195.491915935724;1206.7646808872273;1240.640214337841;10914.181436392222;11014.06380457662;11132.49903754333;11156.665300041012;11169.609063682814;4460.620847227957;4667.183242932541;4711.764411779466;4772.403449568804;4794.184988551954;9557.474338911288;9568.4737339447;9572.042795720512;9576.521173941275;9606.471644059504;5155.61332900034;5293.994141113176;5302.496262855023;5303;5304.549113325411;4269.978650106729;4534.229181039232;4721.811127554928;4782.736949467812;4819.855404337875;3853.325668008087;3859.150986782903;3860.3630400984084;3949.5260568731937;3984.2429938312207;1260.9558665446666;1285.7878450055823;1286.523986125121;1293.4826069572246;1301;780.3484090784145;897.6274845938992;915.8580420034905;927.6104036304769;952.957116929726;5228.9477105229225;5729.169270455761;5829.62107463022;5870.677112758839;5956.136360227742;554.5175697143511;564.7543318656398;577.0853197681638;581.0818906128308;593.801076639322;3702.3150717116882;3878.185581028016;3881.0438600868106;3914.941275880864;3923.1761330120426;8471.195236452528;9844.606215751433;10053.648122315684;10288.794224115625;10293.53679084443;10227;10236.59053637861;10242.180625549938;10243.48782560869;10249;7508.249175082448;7861.095973962953;7869.976903002573;7922.286994170552;7959.890628983324;5345.465453454779;5638.091814932479;5980.910286345796;6014.36849130835;6054.152418661303;9124.771880703036;9266.814663706553;9367.765805854908;9436.52817359143;9594.472304023346;9452.763680908032;9535.856962145712;9574.760630984003;9643.807123857343;9676.75808104803;6529.824631566364;6808.285130061272;6834;6927.82680432973;7266.890996634949;7967.84064318722;8106.83786324282;8131.186881311822;8142.552159631277;8146.307563857138;6964;7018.227994920452;7202.675879585327;7237.167725711611;7298.744543940937;6950.582965022115;7006.579605223703;7097.183823860116;7130.750423735352;7136.571805691675;5306.28365170697;5330.813421530228;6453.257875344396;6479.8056058318325;6502.6748662567625;4516.390287311171;4518.096380723934;4530.660200485043;4531.886702832456;4668.766561671835;4781.42622885256;4858.12553740316;4863.562279394871;4893.9021219576125;4898.804047838189 18:15:19 INFO - PROCESS | 1637 | |4;prototype.html;293.33266817989073;314.3398862388982;323.7086622040162;354.1162374422135;362.706207971543;4570.840020599284;5020.539935431635;5136.460671629477;5250;5302.734863256841;4365.890852728676;4803.751024733569;4985.6260780441435;5012.624053196016;5044.821762289753;4040.313146765057;4072.9389059164137;4076.347784354504;4078.9796051019853;4083.1629515949157;3447.741574325366;4053.8783836484804;4063.5123785145825;4099.3441049432095;4114.897127571805;3650.123970247135;3669.9633003670024;3904.531456225257;4054.864637901392;4064.9187016259657;3547.705087643005;3895.6365272154803;3909.7849618270984;3910.882673519799;3939.9212015759813;2860.4708128996203;3419.7093247074026;3423.691867731916;3425.160835595295;3441.63862794408;5058.848234552969;5062.924056139161;5081.618878584121;5124.923126153073;5171.21593050397;3653.6163702811086;3711.4247291669562;4348.543402942677;4505.7296562206375;4527.683062185648;5242.135047717122;5415.729213539307;5462.535684466825;5471.890562188734;5493.258410114663;3356.983215083909;3760.806700528918;3773.8301776420126;3777.54669439669;3804.923901521954;5605.915911261334;5711.914321285184;5727.479908840139;5755.381301507804;5757.309122905236;3007.548867669867;3803.668715949451;3813.7902415366866;3821.6942644588366;3917.3928041153667;3607.8917632471066;3638.43604241343;3895.7078219133678;3898.8808952837617;3900.531936167621;4334.349847522897;4399.38408622793;4488.708233964782;4508.977455112704;4535.931961020521;4811.759412029454;5408.242846001563;5536.2249285100115;5544.972275138599;5555.416681248492;1246.5823948977013;1249.731307768834;1253.7775668723104;1255.0336241094305;1265.7278685082752;694.0248950224942;822.6832669422196;827.5737994932615;829.3330405884216;830.6718846055895;4426.955730442654;4624.722516649012;4826.734529600835;5124.9256840810895;5178.093833579184;4461.821527138878;5068.82259120929;5576.247206627135;5621.912169486621;5627.184058311604;4924.876878078005;4977.570992471294;5094.745262736922;5106.361704786902;5129.256257842667;2428.8220213196937;2604.551721381895;2739.4794988952435;2782.5826126080847;2788.3308006078396;4786.473487916326;4884.340614017062;4928.67963582366;5064.569511591482;5374.811881584125;5308.946910530923;5420.647657902224;5531.3362396511875;5553.305836770403;5801.767929282866;4776.426828780569;5840.795572154954;5859.824205273849;5863.970680146743;5881.3530511643685;4643.349931009594;5584.5986225921615;5681.573881958952;5702.629329093762;5722.513586345207;5355.973220133875;5491.835244942658;5499.752511136929;5555.138953462219;5570.554355651457;5209.895802083861;5311.025825537711;5718.6267698904485;5763.971180144073;5767.307923049093;3596.5324507813816;3785.886423407302;3810.657040866335;3811.485449464287;3841.9039524012123;4798.2802579613335;5013.222950442687;5121.7695203715175;5652.943470565242;5694.4590263924865;4658.86023419298;5019.874503137305;5486.396496385474;5491.90161967616;5524.756929690788;4563.060699202892;5034.874128146679;5118.41138269089;5165.9483405165465;5512.118061110203;4431.8670439886855;4858.732769697701;4934.777934992862;5034.119029169808;5377.112776391851;4862.3922009748785;5328.333958255218;5330.055758738583;5495.945040549544;5517.44825517445;5173.697704521603;5316.548093412103;5355.732213389393;5393.9730301348245;5414.783408663609 18:15:19 INFO - PROCESS | 1637 | |5;yui.html;698.6297262450896;706.7243774927782;773.8568364852499;821.1419067074906;831.4096991136295;15157.01479403838;15827.920860395681;15834.683306333867;15838.524844254676;15897.364105435769;14171.78742318866;14407.63980900478;14486.42054317826;14516.491922782705;14678.706425871476;862.7066797288904;880.3089574683875;880.6477409036372;881.8677198420224;882.0429833630518;675.3921470676381;863.5595846118493;873.9431936924111;877.3639111644039;877.9868301975475;729.8942102714392;737.3842841227581;898.5417437107089;927.9907200927972;936.5223735894708;922.2575826459697;924.0482303227619;924.9260059195249;927.23966347595;927.5640448988964;686.3308274432438;689.5759108148503;690.6305126757211;691.9654017299115;693.774523279936;12056.336901470415;12550.68623284416;12561.434735436836;12585.622431327074;12701.428435720414;13190.53833115846;14487.348069336906;14787.40850365984;14813.117628307351;14934.25328733559;11893.702657433547;11913.404329783563;12043.096767742454;12102.757944841054;12102.757944841142;3716.721245906541;3749.9814283803944;3767.3783825668734;3767.8492860285555;3884.3396622574232;112285;113925.86074139236;114870.66164045193;114930;116679;11465.101760519416;11465.197436179387;11524.193306468467;11537.077033837273;11939.820902686466;24114.51770964571;24142.03431862741;24150.51698966046;24228.515429691306;24252.757472425405;7619.390448764197;7687.154413014564;7740.922590774133;7773.766786996399;7845.686172553148;4235.216484950294;4312.870613881589;4321.546237645065;4473.127740090651;4481.977590112028;3579.3736096183065;3603.4594810778594;3694.0026192927758;3699.741018128705;3711.498947642088;573.9655620662774;650.0152269311997;656.5863505991194;656.7705315086401;663.5322097920954;506.6225661881848;543.1301973439246;557.743438018508;587.2805812879188;596.2844586496221;7461.962690186514;9672.661456849117;10342.431166285776;10393.688189354332;10517.684469465928;629.8236493781749;676.2020815437733;678.8268991407159;685.4995853027218;703.7536862098327;4985.626078044089;5046.823361182341;5055.51972562606;5063.771940346236;5083.974580127149;83565.58217208875;105297.94702053035;110324.89675103307;112402.43798781118;114895.42552287185;87781.68327475093;88792.5560372207;88918;90725.54637226772;91207.54396227976;66742.66514669679;86552.70170947567;88164;88507.55746221228;89828.20343593092;9584.520773961414;9873.111419972238;9941.900580994386;10354.896451035393;10737.409442480739;46224.7688761554;46911;50025.99948001093;50920.74539627278;51193.7440312796;69539;69748.65125674542;70390;70677.64661176661;71503.64248178726;12873.549425770429;13157.144785588906;13196.239071864473;13406.128601640861;13645.522406715718;14595.635109122357;14608.853911461174;14887.47893823711;14907.65628635226;14964.775528367301;13622.86377136216;13943.790843137158;14000;14104.929475352557;14202.928985355007;11476.483558239732;13479.730405392034;13493.743601976028;14034;14472.565823025327;11105;13206.537771177962;13748.450061997408;13933.164010159422;14097.718045638825;10343.586256549956;11040.558377665126;11190.440477976232;11778.941105294418;11940.164188506722;9239.05723905724;10323;10485.790284194425;10993.309418203784;11088.778224435628 18:15:19 INFO - PROCESS | 1637 | __end_tp_report 18:15:19 INFO - PROCESS | 1637 | __start_cc_report 18:15:19 INFO - PROCESS | 1637 | _x_x_mozilla_cycle_collect,1784 18:15:19 INFO - PROCESS | 1637 | __end_cc_report 18:15:19 INFO - PROCESS | 1637 | __startTimestamp1484014519073__endTimestamp 18:15:19 INFO - PROCESS | 1637 | 18:15:19 INFO - PROCESS | 1637 | ------- Summary: start ------- 18:15:19 INFO - PROCESS | 1637 | Number of tests: 6 18:15:19 INFO - PROCESS | 1637 | 18:15:19 INFO - PROCESS | 1637 | [#0] dojo.html Cycles:165 Average:5607.23 Median:5824.48 stddev:667.98 (11.5%) stddev-sans-first:632.59 18:15:19 INFO - PROCESS | 1637 | Values: 2796.5 3304.1 3304.7 3315.8 3380.4 4604.6 4849.1 5438.7 5578.8 5592.6 5240.9 5471.5 5567.0 5592.1 5617.7 5031.7 5061.2 5068.6 5101.0 5132.0 5067.5 5177.5 5178.2 5189.9 5195.3 4619.5 5038.4 5144.4 5164.8 5211.1 5061.2 5078.1 5114.3 5124.5 5124.6 5066.5 5098.9 5158.6 5159.7 5166.9 4681.7 5536.2 5566.8 5583.0 5607.6 5944.0 5985.9 6139.6 6145.5 6154.2 6112.1 6122.7 6145.0 6192.4 6259.2 5654.5 5706.0 5708.3 5715.9 5728.1 6288.8 6338.4 6358.8 6361.2 6362.8 5812.8 5871.7 5875.4 5937.4 6043.7 6107.4 6111.1 6116.4 6119.6 6127.7 5954.8 6016.9 6020.4 6020.7 6026.2 6036.0 6079.9 6086.1 6096.3 6103.2 4794.5 4798.4 4801.8 4805.1 4807.3 3818.5 4066.6 4249.6 4256.4 4359.7 5633.4 5665.6 5665.9 5673.6 5704.3 6189.2 6189.8 6203.7 6203.8 6211.2 5467.2 5547.0 5706.4 5706.6 5750.1 5222.8 5236.2 5280.5 5290.9 5345.3 6292.7 6326.0 6329.6 6335.8 6343.4 6160.0 6175.2 6180.7 6181.9 6182.4 6147.8 6150.1 6158.9 6245.2 6248.8 5969.9 5972.1 5982.0 5982.5 6024.2 5799.3 6087.7 6090.9 6091.9 6102.0 5792.5 5810.9 5843.6 5849.4 5892.5 5853.1 5856.3 5856.3 5866.5 5884.4 5770.5 5770.7 5824.5 5838.7 5899.9 5979.3 5999.0 6007.4 6018.4 6034.6 5824.4 5830.3 5837.3 5838.0 5850.6 18:15:19 INFO - PROCESS | 1637 | 18:15:19 INFO - PROCESS | 1637 | [#1] ext.html Cycles:180 Average:51259.27 Median:35739.96 stddev:94617.84 (264.7%) stddev-sans-first:94806.35 18:15:19 INFO - PROCESS | 1637 | Values: 444.1 469.6 499.4 513.3 515.4 27903.6 39617.6 41373.2 41585.4 43286.8 42254.4 42476.2 42498.9 42564.6 42663.8 9186.2 9425.0 9437.4 9441.8 9458.0 8512.3 8721.7 11079.2 11431.4 11438.4 11304.3 11356.1 11368.0 11374.0 11377.8 11378.0 11440.4 11451.2 11464.4 11482.6 8414.8 8651.9 10957.9 11254.0 11282.9 39775.8 40299.0 40316.2 40481.4 40482.0 4008.5 4020.7 4053.0 4054.5 4140.4 30027.8 30893.8 31178.7 31209.4 31653.8 2690.3 3122.3 3274.7 3278.6 3314.5 360257.0 362753.2 364434.0 364476.0 364485.0 59810.4 60009.7 60121.4 60128.7 60138.4 64010.0 64010.4 64031.0 64088.4 64103.7 15780.9 15977.8 16054.4 16062.8 16069.4 11321.6 12403.0 12529.2 12545.0 12563.9 14490.3 16300.0 16613.9 16623.0 17016.7 6957.9 7076.4 7131.9 7139.3 7224.3 693.7 870.2 873.9 893.1 900.6 26566.6 29307.6 29661.0 29991.6 30332.4 1063.6 1077.9 1089.9 1093.3 1205.7 14671.9 14980.4 15569.7 15630.3 15865.0 202053.0 231513.8 232021.0 232174.0 232321.8 60116.7 60415.1 60477.7 60484.7 60595.7 1446.6 1454.5 1462.8 1464.3 1474.3 26183.9 26945.9 29250.0 29478.9 29661.0 49683.8 50057.7 50164.0 50245.0 50432.5 447155.0 447783.0 447980.0 448384.0 449828.0 24485.5 25016.4 25115.0 39469.8 39648.6 36050.6 38155.0 41317.4 43044.0 43597.8 35662.8 35683.5 36039.8 36808.6 36818.8 34784.0 35306.6 35533.6 36130.5 36337.5 34940.0 35796.5 36055.5 36094.6 36261.3 29993.0 31277.2 31412.4 31486.7 31491.5 21834.0 21852.5 22303.3 23521.5 23926.6 18:15:19 INFO - PROCESS | 1637 | 18:15:19 INFO - PROCESS | 1637 | [#2] jquery.html Cycles:180 Average:31933.49 Median:11268.05 stddev:80268.39 (712.4%) stddev-sans-first:80458.62 18:15:19 INFO - PROCESS | 1637 | Values: 389.6 395.9 397.7 402.0 421.4 13479.5 19108.6 20198.8 20243.3 20251.0 17101.3 17624.5 20492.7 21009.5 21353.6 5122.7 5621.0 5855.7 5941.9 5944.7 5695.6 5871.0 5887.6 5916.4 5929.1 5902.8 5904.1 5905.4 5913.3 5918.8 5670.4 5896.9 5897.6 5917.9 6011.6 5890.9 5901.4 5904.0 5906.0 5909.2 13391.9 13635.5 13775.9 13792.3 13819.0 2622.3 2785.3 2842.1 2971.6 2980.2 7102.7 10179.5 10505.4 10720.6 10768.2 1834.3 1890.4 1899.5 1901.2 1905.2 113011.9 136722.6 147316.0 148415.5 149020.3 10049.7 10456.7 11683.4 11778.8 12070.5 15779.4 15793.8 15798.4 15810.8 15820.0 6513.8 7817.7 8186.9 8238.0 8513.4 8288.6 8408.8 8458.5 8574.3 8579.7 4312.6 4343.4 4363.9 4380.5 4424.9 1734.6 2035.8 2040.2 2043.4 2058.7 881.8 1034.0 1035.9 1041.3 1082.4 21545.4 21892.6 21907.7 21908.3 22040.0 1045.3 1048.5 1063.0 1070.4 1077.7 7612.3 9169.5 10759.9 11245.2 11272.7 346955.0 347226.0 347483.0 588669.0 591825.0 127227.0 149717.0 151202.0 158680.4 161151.0 1043.2 1082.6 1094.9 1100.5 1106.1 16129.8 19277.6 20004.4 20093.6 20122.9 48675.3 58030.7 62504.8 62881.9 63656.0 79274.2 80518.8 80588.0 81935.2 83497.2 7121.2 8442.8 8752.9 8796.1 8900.0 12885.9 13372.8 13571.9 13597.9 14266.0 11626.5 11637.8 11687.0 11930.8 12050.9 11522.9 11603.3 11728.5 11942.0 12093.8 11707.1 11946.6 11962.9 11999.6 12022.0 6998.5 8340.7 8452.3 8522.9 8743.8 8476.6 10605.4 11493.7 11663.5 11800.8 18:15:19 INFO - PROCESS | 1637 | 18:15:19 INFO - PROCESS | 1637 | [#3] mootools.html Cycles:180 Average:5447.47 Median:5252.10 stddev:3018.06 (57.5%) stddev-sans-first:3011.51 18:15:19 INFO - PROCESS | 1637 | Values: 1441.1 1460.4 1465.4 1479.2 1490.2 6655.8 8607.9 8614.5 8631.8 8661.0 7600.9 7832.8 7833.1 8108.8 8465.2 2282.9 2343.7 2344.9 2351.4 2351.8 2342.1 2356.8 2369.5 2375.2 2377.4 1929.4 2284.4 2333.5 2356.9 2360.1 1781.7 2116.5 2132.8 2139.0 2145.8 2327.7 2356.4 2358.6 2358.9 2360.1 8385.3 8462.6 8476.5 8482.3 8483.9 5123.1 5275.3 5284.5 5285.7 5286.7 3350.5 3380.4 3530.2 3580.9 3587.6 1174.6 1191.7 1195.5 1206.8 1240.6 10914.2 11014.1 11132.5 11156.7 11169.6 4460.6 4667.2 4711.8 4772.4 4794.2 9557.5 9568.5 9572.0 9576.5 9606.5 5155.6 5294.0 5302.5 5303.0 5304.5 4270.0 4534.2 4721.8 4782.7 4819.9 3853.3 3859.2 3860.4 3949.5 3984.2 1261.0 1285.8 1286.5 1293.5 1301.0 780.3 897.6 915.9 927.6 953.0 5228.9 5729.2 5829.6 5870.7 5956.1 554.5 564.8 577.1 581.1 593.8 3702.3 3878.2 3881.0 3914.9 3923.2 8471.2 9844.6 10053.6 10288.8 10293.5 10227.0 10236.6 10242.2 10243.5 10249.0 7508.2 7861.1 7870.0 7922.3 7959.9 5345.5 5638.1 5980.9 6014.4 6054.2 9124.8 9266.8 9367.8 9436.5 9594.5 9452.8 9535.9 9574.8 9643.8 9676.8 6529.8 6808.3 6834.0 6927.8 7266.9 7967.8 8106.8 8131.2 8142.6 8146.3 6964.0 7018.2 7202.7 7237.2 7298.7 6950.6 7006.6 7097.2 7130.8 7136.6 5306.3 5330.8 6453.3 6479.8 6502.7 4516.4 4518.1 4530.7 4531.9 4668.8 4781.4 4858.1 4863.6 4893.9 4898.8 18:15:19 INFO - PROCESS | 1637 | 18:15:19 INFO - PROCESS | 1637 | [#4] prototype.html Cycles:180 Average:4392.00 Median:4956.17 stddev:1350.29 (27.2%) stddev-sans-first:1318.57 18:15:19 INFO - PROCESS | 1637 | Values: 293.3 314.3 323.7 354.1 362.7 4570.8 5020.5 5136.5 5250.0 5302.7 4365.9 4803.8 4985.6 5012.6 5044.8 4040.3 4072.9 4076.3 4079.0 4083.2 3447.7 4053.9 4063.5 4099.3 4114.9 3650.1 3670.0 3904.5 4054.9 4064.9 3547.7 3895.6 3909.8 3910.9 3939.9 2860.5 3419.7 3423.7 3425.2 3441.6 5058.8 5062.9 5081.6 5124.9 5171.2 3653.6 3711.4 4348.5 4505.7 4527.7 5242.1 5415.7 5462.5 5471.9 5493.3 3357.0 3760.8 3773.8 3777.5 3804.9 5605.9 5711.9 5727.5 5755.4 5757.3 3007.5 3803.7 3813.8 3821.7 3917.4 3607.9 3638.4 3895.7 3898.9 3900.5 4334.3 4399.4 4488.7 4509.0 4535.9 4811.8 5408.2 5536.2 5545.0 5555.4 1246.6 1249.7 1253.8 1255.0 1265.7 694.0 822.7 827.6 829.3 830.7 4427.0 4624.7 4826.7 5124.9 5178.1 4461.8 5068.8 5576.2 5621.9 5627.2 4924.9 4977.6 5094.7 5106.4 5129.3 2428.8 2604.6 2739.5 2782.6 2788.3 4786.5 4884.3 4928.7 5064.6 5374.8 5308.9 5420.6 5531.3 5553.3 5801.8 4776.4 5840.8 5859.8 5864.0 5881.4 4643.3 5584.6 5681.6 5702.6 5722.5 5356.0 5491.8 5499.8 5555.1 5570.6 5209.9 5311.0 5718.6 5764.0 5767.3 3596.5 3785.9 3810.7 3811.5 3841.9 4798.3 5013.2 5121.8 5652.9 5694.5 4658.9 5019.9 5486.4 5491.9 5524.8 4563.1 5034.9 5118.4 5165.9 5512.1 4431.9 4858.7 4934.8 5034.1 5377.1 4862.4 5328.3 5330.1 5495.9 5517.4 5173.7 5316.5 5355.7 5394.0 5414.8 18:15:19 INFO - PROCESS | 1637 | 18:15:19 INFO - PROCESS | 1637 | [#5] yui.html Cycles:180 Average:21085.14 Median:3767.61 stddev:30830.88 (818.3%) stddev-sans-first:30879.37 18:15:19 INFO - PROCESS | 1637 | Values: 698.6 706.7 773.9 821.1 831.4 15157.0 15827.9 15834.7 15838.5 15897.4 14171.8 14407.6 14486.4 14516.5 14678.7 862.7 880.3 880.6 881.9 882.0 675.4 863.6 873.9 877.4 878.0 729.9 737.4 898.5 928.0 936.5 922.3 924.0 924.9 927.2 927.6 686.3 689.6 690.6 692.0 693.8 12056.3 12550.7 12561.4 12585.6 12701.4 13190.5 14487.3 14787.4 14813.1 14934.3 11893.7 11913.4 12043.1 12102.8 12102.8 3716.7 3750.0 3767.4 3767.8 3884.3 112285.0 113925.9 114870.7 114930.0 116679.0 11465.1 11465.2 11524.2 11537.1 11939.8 24114.5 24142.0 24150.5 24228.5 24252.8 7619.4 7687.2 7740.9 7773.8 7845.7 4235.2 4312.9 4321.5 4473.1 4482.0 3579.4 3603.5 3694.0 3699.7 3711.5 574.0 650.0 656.6 656.8 663.5 506.6 543.1 557.7 587.3 596.3 7462.0 9672.7 10342.4 10393.7 10517.7 629.8 676.2 678.8 685.5 703.8 4985.6 5046.8 5055.5 5063.8 5084.0 83565.6 105297.9 110324.9 112402.4 114895.4 87781.7 88792.6 88918.0 90725.5 91207.5 66742.7 86552.7 88164.0 88507.6 89828.2 9584.5 9873.1 9941.9 10354.9 10737.4 46224.8 46911.0 50026.0 50920.7 51193.7 69539.0 69748.7 70390.0 70677.6 71503.6 12873.5 13157.1 13196.2 13406.1 13645.5 14595.6 14608.9 14887.5 14907.7 14964.8 13622.9 13943.8 14000.0 14104.9 14202.9 11476.5 13479.7 13493.7 14034.0 14472.6 11105.0 13206.5 13748.5 13933.2 14097.7 10343.6 11040.6 11190.4 11778.9 11940.2 9239.1 10323.0 10485.8 10993.3 11088.8 18:15:19 INFO - PROCESS | 1637 | -------- Summary: end -------- 18:15:19 INFO - PROCESS | 1637 | 18:15:19 INFO - TEST-INFO | 1637: exit 0 18:15:19 INFO - TEST-OK | dromaeo_css | took 1119003ms 18:15:19 INFO - TEST-START | kraken 18:15:19 INFO - operating with platform_type : mac_ 18:15:19 INFO - Initialising browser for kraken test... 18:15:19 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/tmpoJntxd/profile http://localhost:49250/getInfo.html) 18:15:20 INFO - PROCESS | 1647 | 1484014520180 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4906 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2060 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3761 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2794 < callProvider()@resource://gre/modules/AddonManager.jsm:267 < _startProvider()@resource://gre/modules/AddonManager.jsm:973 < startup()@resource://gre/modules/AddonManager.jsm:1155 < startup()@resource://gre/modules/AddonManager.jsm:3124 < observe()@resource://gre/components/addonManager.js:65 18:15:21 INFO - PROCESS | 1647 | __metrics Screen width/height:1600/1200 18:15:21 INFO - PROCESS | 1647 | colorDepth:24 18:15:21 INFO - PROCESS | 1647 | Browser inner width/height: 1024/689 18:15:21 INFO - PROCESS | 1647 | __metrics 18:15:21 INFO - PROCESS | 1647 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:15:21 INFO - PROCESS | 1647 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:15:22 INFO - TEST-INFO | 1647: exit 0 18:15:22 INFO - Browser initialized. 18:15:22 INFO - Running cycle 1/1 for kraken test... 18:15:22 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/tmpoJntxd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:15:34 INFO - PROCESS | 1648 | RSS: Main: 233537536 18:15:34 INFO - PROCESS | 1648 | 18:15:37 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49250/tests/kraken/driver.html?testName=audio-beat-detection) 18:15:38 INFO - PROCESS | 1648 | RSS: Main: 286965760 18:15:38 INFO - PROCESS | 1648 | 18:15:41 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49250/tests/kraken/driver.html?testName=audio-dft) 18:15:41 INFO - PROCESS | 1648 | RSS: Main: 286011392 18:15:41 INFO - PROCESS | 1648 | 18:15:44 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49250/tests/kraken/driver.html?testName=audio-fft) 18:15:45 INFO - PROCESS | 1648 | RSS: Main: 379174912 18:15:45 INFO - PROCESS | 1648 | 18:15:47 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49250/tests/kraken/driver.html?testName=audio-oscillator) 18:15:48 INFO - PROCESS | 1648 | RSS: Main: 283742208 18:15:48 INFO - PROCESS | 1648 | 18:15:50 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49250/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:15:50 INFO - PROCESS | 1648 | RSS: Main: 285843456 18:15:50 INFO - PROCESS | 1648 | 18:15:53 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49250/tests/kraken/driver.html?testName=imaging-darkroom) 18:15:54 INFO - PROCESS | 1648 | RSS: Main: 326692864 18:15:54 INFO - PROCESS | 1648 | 18:15:57 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49250/tests/kraken/driver.html?testName=imaging-desaturate) 18:15:57 INFO - PROCESS | 1648 | RSS: Main: 330784768 18:15:57 INFO - PROCESS | 1648 | 18:16:00 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49250/tests/kraken/driver.html?testName=json-parse-financial) 18:16:01 INFO - PROCESS | 1648 | RSS: Main: 327733248 18:16:01 INFO - PROCESS | 1648 | 18:16:02 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49250/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:16:03 INFO - PROCESS | 1648 | RSS: Main: 303222784 18:16:03 INFO - PROCESS | 1648 | 18:16:05 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:16:05 INFO - PROCESS | 1648 | RSS: Main: 306323456 18:16:05 INFO - PROCESS | 1648 | 18:16:08 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:16:08 INFO - PROCESS | 1648 | RSS: Main: 308101120 18:16:08 INFO - PROCESS | 1648 | 18:16:11 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:16:12 INFO - PROCESS | 1648 | RSS: Main: 325525504 18:16:12 INFO - PROCESS | 1648 | 18:16:15 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:16:16 INFO - PROCESS | 1648 | RSS: Main: 319557632 18:16:16 INFO - PROCESS | 1648 | 18:16:17 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49250/tests/kraken/driver.html?testName=ai-astar) 18:16:18 INFO - PROCESS | 1648 | RSS: Main: 363499520 18:16:18 INFO - PROCESS | 1648 | 18:16:18 INFO - PROCESS | 1648 | __start_tp_report 18:16:18 INFO - PROCESS | 1648 | _x_x_mozilla_page_load 18:16:18 INFO - PROCESS | 1648 | _x_x_mozilla_page_load_details 18:16:18 INFO - PROCESS | 1648 | |i|pagename|runs| 18:16:18 INFO - PROCESS | 1648 | |0;ai-astar;155;166;125;112;112;109;113;112;112;112 18:16:18 INFO - PROCESS | 1648 | |1;audio-beat-detection;141;110;107;144;127;141;140;105;125;115 18:16:18 INFO - PROCESS | 1648 | |2;audio-dft;186;187;182;187;184;182;181;183;183;185 18:16:18 INFO - PROCESS | 1648 | |3;audio-fft;79;58;60;87;60;59;58;66;89;73 18:16:18 INFO - PROCESS | 1648 | |4;audio-oscillator;88;71;87;73;95;99;117;69;69;84 18:16:18 INFO - PROCESS | 1648 | |5;imaging-gaussian-blur;87;85;87;85;92;85;88;83;87;83 18:16:18 INFO - PROCESS | 1648 | |6;imaging-darkroom;93;93;93;102;93;94;94;93;93;93 18:16:18 INFO - PROCESS | 1648 | |7;imaging-desaturate;126;130;86;88;88;87;85;85;85;87 18:16:18 INFO - PROCESS | 1648 | |8;json-parse-financial;56;54;54;54;55;55;55;55;55;55 18:16:18 INFO - PROCESS | 1648 | |9;json-stringify-tinderbox;65;71;84;74;71;69;70;73;71;72 18:16:18 INFO - PROCESS | 1648 | |10;stanford-crypto-aes;88;90;99;108;81;90;85;87;88;92 18:16:18 INFO - PROCESS | 1648 | |11;stanford-crypto-ccm;139;139;149;139;147;149;152;140;198;183 18:16:18 INFO - PROCESS | 1648 | |12;stanford-crypto-pbkdf2;180;160;213;236;177;171;172;174;172;174 18:16:18 INFO - PROCESS | 1648 | |13;stanford-crypto-sha256-iterative;67;67;68;67;68;67;66;66;68;69 18:16:18 INFO - PROCESS | 1648 | __end_tp_report 18:16:18 INFO - PROCESS | 1648 | __start_cc_report 18:16:18 INFO - PROCESS | 1648 | _x_x_mozilla_cycle_collect,1996 18:16:18 INFO - PROCESS | 1648 | __end_cc_report 18:16:18 INFO - PROCESS | 1648 | __startTimestamp1484014578215__endTimestamp 18:16:18 INFO - PROCESS | 1648 | 18:16:18 INFO - PROCESS | 1648 | ------- Summary: start ------- 18:16:18 INFO - PROCESS | 1648 | Number of tests: 14 18:16:18 INFO - PROCESS | 1648 | 18:16:18 INFO - PROCESS | 1648 | [#0] ai-astar Cycles:10 Average:122.80 Median:112.50 stddev:20.49 (18.2%) stddev-sans-first:18.12 18:16:18 INFO - PROCESS | 1648 | Values: 155.0 166.0 125.0 112.0 112.0 109.0 113.0 112.0 112.0 112.0 18:16:18 INFO - PROCESS | 1648 | 18:16:18 INFO - PROCESS | 1648 | [#1] audio-beat-detection Cycles:10 Average:125.50 Median:133.50 stddev:15.45 (11.6%) stddev-sans-first:15.34 18:16:18 INFO - PROCESS | 1648 | Values: 141.0 110.0 107.0 144.0 127.0 141.0 140.0 105.0 125.0 115.0 18:16:18 INFO - PROCESS | 1648 | 18:16:18 INFO - PROCESS | 1648 | [#2] audio-dft Cycles:10 Average:184.00 Median:184.50 stddev:2.16 (1.2%) stddev-sans-first:2.17 18:16:18 INFO - PROCESS | 1648 | Values: 186.0 187.0 182.0 187.0 184.0 182.0 181.0 183.0 183.0 185.0 18:16:18 INFO - PROCESS | 1648 | 18:16:18 INFO - PROCESS | 1648 | [#3] audio-fft Cycles:10 Average:68.90 Median:69.50 stddev:12.26 (17.6%) stddev-sans-first:12.45 18:16:18 INFO - PROCESS | 1648 | Values: 79.0 58.0 60.0 87.0 60.0 59.0 58.0 66.0 89.0 73.0 18:16:18 INFO - PROCESS | 1648 | 18:16:18 INFO - PROCESS | 1648 | [#4] audio-oscillator Cycles:10 Average:85.20 Median:85.50 stddev:15.58 (18.2%) stddev-sans-first:16.50 18:16:18 INFO - PROCESS | 1648 | Values: 88.0 71.0 87.0 73.0 95.0 99.0 117.0 69.0 69.0 84.0 18:16:18 INFO - PROCESS | 1648 | 18:16:18 INFO - PROCESS | 1648 | [#5] imaging-gaussian-blur Cycles:10 Average:86.20 Median:87.00 stddev:2.66 (3.1%) stddev-sans-first:2.80 18:16:18 INFO - PROCESS | 1648 | Values: 87.0 85.0 87.0 85.0 92.0 85.0 88.0 83.0 87.0 83.0 18:16:18 INFO - PROCESS | 1648 | 18:16:18 INFO - PROCESS | 1648 | [#6] imaging-darkroom Cycles:10 Average:94.10 Median:93.00 stddev:2.81 (3.0%) stddev-sans-first:2.95 18:16:18 INFO - PROCESS | 1648 | Values: 93.0 93.0 93.0 102.0 93.0 94.0 94.0 93.0 93.0 93.0 18:16:18 INFO - PROCESS | 1648 | 18:16:18 INFO - PROCESS | 1648 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:86.50 stddev:17.61 (20.4%) stddev-sans-first:14.59 18:16:18 INFO - PROCESS | 1648 | Values: 126.0 130.0 86.0 88.0 88.0 87.0 85.0 85.0 85.0 87.0 18:16:18 INFO - PROCESS | 1648 | 18:16:18 INFO - PROCESS | 1648 | [#8] json-parse-financial Cycles:10 Average:54.80 Median:55.00 stddev:0.63 (1.1%) stddev-sans-first:0.50 18:16:18 INFO - PROCESS | 1648 | Values: 56.0 54.0 54.0 54.0 55.0 55.0 55.0 55.0 55.0 55.0 18:16:18 INFO - PROCESS | 1648 | 18:16:18 INFO - PROCESS | 1648 | [#9] json-stringify-tinderbox Cycles:10 Average:72.00 Median:71.50 stddev:4.88 (6.8%) stddev-sans-first:4.47 18:16:18 INFO - PROCESS | 1648 | Values: 65.0 71.0 84.0 74.0 71.0 69.0 70.0 73.0 71.0 72.0 18:16:18 INFO - PROCESS | 1648 | 18:16:18 INFO - PROCESS | 1648 | [#10] stanford-crypto-aes Cycles:10 Average:90.80 Median:89.00 stddev:7.64 (8.6%) stddev-sans-first:8.04 18:16:18 INFO - PROCESS | 1648 | Values: 88.0 90.0 99.0 108.0 81.0 90.0 85.0 87.0 88.0 92.0 18:16:18 INFO - PROCESS | 1648 | 18:16:18 INFO - PROCESS | 1648 | [#11] stanford-crypto-ccm Cycles:10 Average:153.50 Median:149.00 stddev:20.41 (13.7%) stddev-sans-first:20.96 18:16:18 INFO - PROCESS | 1648 | Values: 139.0 139.0 149.0 139.0 147.0 149.0 152.0 140.0 198.0 183.0 18:16:18 INFO - PROCESS | 1648 | 18:16:18 INFO - PROCESS | 1648 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:182.90 Median:175.50 stddev:23.17 (13.2%) stddev-sans-first:24.55 18:16:18 INFO - PROCESS | 1648 | Values: 180.0 160.0 213.0 236.0 177.0 171.0 172.0 174.0 172.0 174.0 18:16:18 INFO - PROCESS | 1648 | 18:16:18 INFO - PROCESS | 1648 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.30 Median:67.50 stddev:0.95 (1.4%) stddev-sans-first:1.00 18:16:18 INFO - PROCESS | 1648 | Values: 67.0 67.0 68.0 67.0 68.0 67.0 66.0 66.0 68.0 69.0 18:16:18 INFO - PROCESS | 1648 | -------- Summary: end -------- 18:16:18 INFO - PROCESS | 1648 | 18:16:19 INFO - TEST-INFO | 1648: exit 0 18:16:19 INFO - TEST-OK | kraken | took 59525ms 18:16:19 INFO - SUITE-END | took 1178s 18:16:19 INFO - Completed test suite (00:19:38) 18:16:19 INFO - javascript benchmark 18:16:19 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2796.4826507096195, 3304.1244070321363, 3304.686054824791, 3315.8010519368854, 3380.3746306933217, 4604.631629469643, 4849.10291596055, 5438.700871452069, 5578.776848926043, 5592.636478628886, 5240.868978275545, 5471.452854714536, 5566.972165139179, 5592.0773072443, 5617.662940223589, 5031.672941258825, 5061.240813877911, 5068.594512438996, 5100.974495127519, 5131.97360527894, 5067.493250674921, 5177.534021938025, 5178.171492561191, 5189.922151167735, 5195.272661827347, 4619.514950930155, 5038.39539255288, 5144.4083930348115, 5164.819231326923, 5211.114110601188, 5061.240813877911, 5078.085944529983, 5114.335136432277, 5124.538791508745, 5124.590032797404, 5066.46802085783, 5098.949010509921, 5158.613104017214, 5159.69041857486, 5166.896662066738, 4681.695689780154, 5536.2249285100115, 5566.777328906839, 5582.972085139589, 5607.579431542651, 5943.970280148615, 5985.880282394328, 6139.570230083896, 6145.539084568631, 6154.199954005995, 6112.086414657059, 6122.733612273343, 6145.0, 6192.411720886508, 6259.186305780219, 5654.547636189095, 5705.971470142623, 5708.315002199763, 5715.914261286084, 5728.054870946289, 6288.811335659938, 6338.366163383717, 6358.809235722843, 6361.236651601837, 6362.84092897672, 5812.7674893003805, 5871.6770577618645, 5875.441833025856, 5937.406259374028, 6043.7280322385595, 6107.370879189147, 6111.105587097633, 6116.405802871558, 6119.593952096689, 6127.7393367763425, 5954.761809527657, 6016.879662406727, 6020.397960204032, 6020.69896505173, 6026.246719160105, 6035.969820150871, 6079.908801367983, 6086.147939288503, 6096.298925623521, 6103.23709536308, 4794.496577859274, 4798.424189097331, 4801.807927682923, 4805.111054454937, 4807.254875494304, 3818.491695780151, 4066.552679205285, 4249.638780703613, 4256.36154576816, 4359.651227901761, 5633.408492108351, 5665.575081868877, 5665.886682266331, 5673.6028478006965, 5704.258446401941, 6189.195404597373, 6189.8452538686, 6203.689815509207, 6203.844903877439, 6211.223597050369, 5467.234587157801, 5546.972265138648, 5706.42935706426, 5706.572007099484, 5750.108733146369, 5222.817201397933, 5236.162214045735, 5280.471952804689, 5290.92063619046, 5345.33183352081, 6292.716827742671, 6326.0, 6329.620222786648, 6335.778247761306, 6343.429091381706, 6159.9836027055035, 6175.166352542349, 6180.721867515883, 6181.876362472815, 6182.350853160533, 6147.784827531015, 6150.138980542817, 6158.876822463436, 6245.156903818108, 6248.812535623939, 5969.880602388015, 5972.074328479092, 5982.0, 5982.52139828804, 6024.216851809237, 5799.333076696234, 6087.726052327567, 6090.908636370546, 6091.939080609137, 6101.969490152521, 5792.536597072309, 5810.883782324246, 5843.590948633555, 5849.415058494116, 5892.469677729025, 5853.0635098384055, 5856.297244330707, 5856.326522449973, 5866.530677545871, 5884.440978107073, 5770.538356931519, 5770.740316685843, 5824.475797178275, 5838.708064596838, 5899.852503687443, 5979.342272350125, 5999.0, 6007.369226231182, 6018.398160183947, 6034.607750496204, 5824.446677565625, 5830.300363956352, 5837.29952405714, 5838.036723940502, 5850.64896106235], "value": 5561.69826538798, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [444.0541646293395, 469.58207195595924, 499.35333742803067, 513.304472439844, 515.3789683431465, 27903.581446278324, 39617.60382396175, 41373.17253654925, 41585.37621935666, 43286.78356608212, 42254.36618450718, 42476.15047699044, 42498.93752656185, 42564.574354256445, 42663.78668106655, 9186.173244408019, 9424.952875235615, 9437.433753974748, 9441.81116377672, 9458.0, 8512.319014478859, 8721.738347849574, 11079.224454288204, 11431.371274579895, 11438.428078596078, 11304.265222760536, 11356.148288878367, 11368.033717134054, 11374.0, 11377.829332560019, 11378.032867206297, 11440.370779607118, 11451.198416110876, 11464.426778661116, 11482.598109066184, 8414.78963025923, 8651.870221946676, 10957.945210273898, 11254.043406310473, 11282.943585282103, 39775.801120994496, 40299.0, 40316.193676126604, 40481.39277910833, 40482.0, 4008.4588580541836, 4020.6582440492593, 4052.959470405288, 4054.452648892391, 4140.37894315852, 30027.849860750557, 30893.845530772425, 31178.688213117806, 31209.37581248385, 31653.841730791428, 2690.340866487723, 3122.2506598416444, 3274.6889045540634, 3278.606567211926, 3314.453115235983, 360257.0, 362753.18623406714, 364434.0, 364476.0, 364485.0, 59810.40189598048, 60009.69995149996, 60121.398786012454, 60128.69935650294, 60138.39861601416, 64010.0, 64010.35989640137, 64031.0, 64088.35911640917, 64103.679481602296, 15780.92109539445, 15977.840221597635, 16054.43809466663, 16062.75905861413, 16069.357225711074, 11321.603743868925, 12403.0, 12529.248245105322, 12545.0, 12563.937180314222, 14490.275486225857, 16300.0, 16613.91693041527, 16623.00261984285, 17016.65966680684, 6957.860842783116, 7076.398506127037, 7131.92868071323, 7139.286071392819, 7224.277572242839, 693.6843736100062, 870.2168048756043, 873.895132584094, 893.097971049245, 900.617237673986, 26566.6015009775, 29307.560386594218, 29661.0, 29991.550126748116, 30332.393352132833, 1063.5586231713908, 1077.8922107789315, 1089.8637670291214, 1093.33853018924, 1205.6804946689135, 14671.89187027634, 14980.40078396852, 15569.688606227812, 15630.296636651377, 15865.04809711421, 202053.0, 231513.84243078675, 232021.0, 232174.0, 232321.83839080695, 60116.699416503514, 60415.093773593435, 60477.69761151166, 60484.69757651183, 60595.697021514614, 1446.6094154578204, 1454.5127382326828, 1462.7878957551095, 1464.3044553836842, 1474.2702362330715, 26183.90528757733, 26945.865270673523, 29250.0, 29478.85260573769, 29661.0, 49683.751581243305, 50057.74855628639, 50163.99672006613, 50245.0, 50432.49567504425, 447155.0, 447783.0, 447980.0, 448384.0, 449828.0, 24485.51028979446, 25016.37459063538, 25115.0, 39469.80265098656, 39648.603513965645, 36050.63949360473, 38155.0, 41317.38023929583, 43044.0, 43597.78201109101, 35662.8216858914, 35683.46474802828, 36039.81980090083, 36808.631913681595, 36818.815905920295, 34784.0, 35306.64693353033, 35533.64466355303, 36130.45804312885, 36337.454938176474, 34940.0, 35796.4630530537, 36055.45916811198, 36094.63905360913, 36261.274774503836, 29993.0, 31277.218069548442, 31412.37175256528, 31486.685133148374, 31491.527627085154, 21834.0, 21852.45368865791, 22303.327250455957, 23521.529569408172, 23926.64110038316], "value": 19008.833683690147, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [389.62012038262696, 395.873320537428, 397.7494178667439, 401.9899502512436, 421.3637407514652, 13479.528216512424, 19108.617827643455, 20198.798012019895, 20243.291484798017, 20251.0, 17101.31594736212, 17624.471265862012, 20492.69260961083, 21009.47476313093, 21353.57292854142, 5122.667026643257, 5620.971895140518, 5855.736491857877, 5941.88116237675, 5944.7324870380935, 5695.601307908448, 5871.0, 5887.646741195542, 5916.3787802280785, 5929.140274660172, 5902.793402230923, 5904.114382842565, 5905.438983296602, 5913.3199682036475, 5918.822435326947, 5670.404607516192, 5896.88206235875, 5897.587168898179, 5917.852053698692, 6011.639301641915, 5890.911636325459, 5901.3508514063415, 5903.970480147572, 5906.0, 5909.23179986599, 13391.933040334834, 13635.454581816715, 13775.931120344434, 13792.310384480836, 13819.0, 2622.3050891513763, 2785.331520435101, 2842.104737007836, 2971.5691224772504, 2980.2251414632133, 7102.715891364339, 10179.491025448771, 10505.369677819355, 10720.571177152904, 10768.24622276441, 1834.2754611928215, 1890.395073576456, 1899.477643647994, 1901.1729897494633, 1905.2283825050877, 113011.86988130095, 136722.632773672, 147316.0, 148415.51584484125, 149020.2548987259, 10049.69850904474, 10456.692913385827, 11683.357415342069, 11778.823317650242, 12070.517179312905, 15779.447719329997, 15793.842061579468, 15798.447054353272, 15810.841891580936, 15820.0, 6513.804585862432, 7817.687292508236, 8186.918130818734, 8238.0, 8513.40406171562, 8288.58557072144, 8408.831823363498, 8458.450200736932, 8574.314054875595, 8579.699710510227, 4312.568743125725, 4343.370211319404, 4363.934540981888, 4380.474343078788, 4424.889377765518, 1734.6010417604023, 2035.7672303947159, 2040.2349119080345, 2043.4074118505466, 2058.7014882841904, 881.8060026794224, 1033.984490232632, 1035.9067683908333, 1041.2555023158327, 1082.3614067700096, 21545.353639390843, 21892.562148756937, 21907.67138492924, 21908.342749717533, 22040.0, 1045.310095337089, 1048.528162326956, 1062.9574817007388, 1070.4380200394698, 1077.6659235636978, 7612.3148916597775, 9169.495677737788, 10759.89240107589, 11245.212835101463, 11272.66182014541, 346955.0, 347226.0, 347483.0, 588669.0, 591825.0, 127227.0, 149717.0, 151202.0, 158680.38283224238, 161151.0, 1043.2332235806828, 1082.6481393547128, 1094.9014588686898, 1100.4772732951783, 1106.0543235533503, 16129.838701612834, 19277.614447711247, 20004.399868003984, 20093.598128037065, 20122.89938550298, 48675.269870952674, 58030.709846450496, 62504.81233157, 62881.8821270904, 63656.0, 79274.20725792668, 80518.7922181156, 80588.0, 81935.18064819275, 83497.16502835137, 7121.181064177686, 8442.788930276793, 8752.862127923328, 8796.07641197665, 8900.020997690379, 12885.935570322088, 13372.799408009083, 13571.932140339235, 13597.86402135966, 14266.0, 11626.53493860236, 11637.825432618349, 11687.0, 11930.76138477243, 12050.879491204976, 11522.942385288356, 11603.303801771921, 11728.530858765555, 11942.0, 12093.75812483763, 11707.063434925356, 11946.641600751991, 11962.889648691229, 11999.64001079969, 12022.0, 6998.475114366544, 8340.749777506684, 8452.281556067803, 8522.892024036835, 8743.825123497621, 8476.576171191293, 10605.41670208146, 11493.712657183303, 11663.475143618727, 11800.822987655363], "value": 9641.237996058262, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1441.0777102654297, 1460.4085345435099, 1465.4211586423362, 1479.1642721862147, 1490.2027415332795, 6655.833604159892, 8607.91392086079, 8614.52620105894, 8631.827363452736, 8660.956695216522, 7600.885986710204, 7832.7650170494835, 7833.099193592739, 8108.837823243532, 8465.153484651531, 2282.9200977965734, 2343.7070366204225, 2344.941376465585, 2351.43565544269, 2351.776581224781, 2342.0982921575232, 2356.8350215484925, 2369.526094781042, 2375.168690958168, 2377.4413012941945, 1929.440462265948, 2284.4060544258527, 2333.5099629077913, 2356.893939772706, 2360.0795689680954, 1781.6971114910496, 2116.481462041802, 2132.797384248494, 2138.967915481269, 2145.8497905146723, 2327.674125622414, 2356.4108972756812, 2358.5990381635165, 2358.858468491896, 2360.1031607988853, 8385.287250583708, 8462.576871156418, 8476.49141051539, 8482.321414286904, 8483.915160848373, 5123.077845987721, 5275.261463395128, 5284.5243928046275, 5285.709285989269, 5286.735663216824, 3350.530925670408, 3380.4364353366323, 3530.1527633368064, 3580.946285805715, 3587.6232995535356, 1174.5947648061406, 1191.6901605582611, 1195.491915935724, 1206.7646808872273, 1240.640214337841, 10914.181436392222, 11014.06380457662, 11132.49903754333, 11156.665300041012, 11169.609063682814, 4460.620847227957, 4667.183242932541, 4711.764411779466, 4772.403449568804, 4794.184988551954, 9557.474338911288, 9568.4737339447, 9572.042795720512, 9576.521173941275, 9606.471644059504, 5155.61332900034, 5293.994141113176, 5302.496262855023, 5303.0, 5304.549113325411, 4269.978650106729, 4534.229181039232, 4721.811127554928, 4782.736949467812, 4819.855404337875, 3853.325668008087, 3859.150986782903, 3860.3630400984084, 3949.5260568731937, 3984.2429938312207, 1260.9558665446666, 1285.7878450055823, 1286.523986125121, 1293.4826069572246, 1301.0, 780.3484090784145, 897.6274845938992, 915.8580420034905, 927.6104036304769, 952.957116929726, 5228.9477105229225, 5729.169270455761, 5829.62107463022, 5870.677112758839, 5956.136360227742, 554.5175697143511, 564.7543318656398, 577.0853197681638, 581.0818906128308, 593.801076639322, 3702.3150717116882, 3878.185581028016, 3881.0438600868106, 3914.941275880864, 3923.1761330120426, 8471.195236452528, 9844.606215751433, 10053.648122315684, 10288.794224115625, 10293.53679084443, 10227.0, 10236.59053637861, 10242.180625549938, 10243.48782560869, 10249.0, 7508.249175082448, 7861.095973962953, 7869.976903002573, 7922.286994170552, 7959.890628983324, 5345.465453454779, 5638.091814932479, 5980.910286345796, 6014.36849130835, 6054.152418661303, 9124.771880703036, 9266.814663706553, 9367.765805854908, 9436.52817359143, 9594.472304023346, 9452.763680908032, 9535.856962145712, 9574.760630984003, 9643.807123857343, 9676.75808104803, 6529.824631566364, 6808.285130061272, 6834.0, 6927.82680432973, 7266.890996634949, 7967.84064318722, 8106.83786324282, 8131.186881311822, 8142.552159631277, 8146.307563857138, 6964.0, 7018.227994920452, 7202.675879585327, 7237.167725711611, 7298.744543940937, 6950.582965022115, 7006.579605223703, 7097.183823860116, 7130.750423735352, 7136.571805691675, 5306.28365170697, 5330.813421530228, 6453.257875344396, 6479.8056058318325, 6502.6748662567625, 4516.390287311171, 4518.096380723934, 4530.660200485043, 4531.886702832456, 4668.766561671835, 4781.42622885256, 4858.12553740316, 4863.562279394871, 4893.9021219576125, 4898.804047838189], "value": 4370.019255852493, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [293.33266817989073, 314.3398862388982, 323.7086622040162, 354.1162374422135, 362.706207971543, 4570.840020599284, 5020.539935431635, 5136.460671629477, 5250.0, 5302.734863256841, 4365.890852728676, 4803.751024733569, 4985.6260780441435, 5012.624053196016, 5044.821762289753, 4040.313146765057, 4072.9389059164137, 4076.347784354504, 4078.9796051019853, 4083.1629515949157, 3447.741574325366, 4053.8783836484804, 4063.5123785145825, 4099.3441049432095, 4114.897127571805, 3650.123970247135, 3669.9633003670024, 3904.531456225257, 4054.864637901392, 4064.9187016259657, 3547.705087643005, 3895.6365272154803, 3909.7849618270984, 3910.882673519799, 3939.9212015759813, 2860.4708128996203, 3419.7093247074026, 3423.691867731916, 3425.160835595295, 3441.63862794408, 5058.848234552969, 5062.924056139161, 5081.618878584121, 5124.923126153073, 5171.21593050397, 3653.6163702811086, 3711.4247291669562, 4348.543402942677, 4505.7296562206375, 4527.683062185648, 5242.135047717122, 5415.729213539307, 5462.535684466825, 5471.890562188734, 5493.258410114663, 3356.983215083909, 3760.806700528918, 3773.8301776420126, 3777.54669439669, 3804.923901521954, 5605.915911261334, 5711.914321285184, 5727.479908840139, 5755.381301507804, 5757.309122905236, 3007.548867669867, 3803.668715949451, 3813.7902415366866, 3821.6942644588366, 3917.3928041153667, 3607.8917632471066, 3638.43604241343, 3895.7078219133678, 3898.8808952837617, 3900.531936167621, 4334.349847522897, 4399.38408622793, 4488.708233964782, 4508.977455112704, 4535.931961020521, 4811.759412029454, 5408.242846001563, 5536.2249285100115, 5544.972275138599, 5555.416681248492, 1246.5823948977013, 1249.731307768834, 1253.7775668723104, 1255.0336241094305, 1265.7278685082752, 694.0248950224942, 822.6832669422196, 827.5737994932615, 829.3330405884216, 830.6718846055895, 4426.955730442654, 4624.722516649012, 4826.734529600835, 5124.9256840810895, 5178.093833579184, 4461.821527138878, 5068.82259120929, 5576.247206627135, 5621.912169486621, 5627.184058311604, 4924.876878078005, 4977.570992471294, 5094.745262736922, 5106.361704786902, 5129.256257842667, 2428.8220213196937, 2604.551721381895, 2739.4794988952435, 2782.5826126080847, 2788.3308006078396, 4786.473487916326, 4884.340614017062, 4928.67963582366, 5064.569511591482, 5374.811881584125, 5308.946910530923, 5420.647657902224, 5531.3362396511875, 5553.305836770403, 5801.767929282866, 4776.426828780569, 5840.795572154954, 5859.824205273849, 5863.970680146743, 5881.3530511643685, 4643.349931009594, 5584.5986225921615, 5681.573881958952, 5702.629329093762, 5722.513586345207, 5355.973220133875, 5491.835244942658, 5499.752511136929, 5555.138953462219, 5570.554355651457, 5209.895802083861, 5311.025825537711, 5718.6267698904485, 5763.971180144073, 5767.307923049093, 3596.5324507813816, 3785.886423407302, 3810.657040866335, 3811.485449464287, 3841.9039524012123, 4798.2802579613335, 5013.222950442687, 5121.7695203715175, 5652.943470565242, 5694.4590263924865, 4658.86023419298, 5019.874503137305, 5486.396496385474, 5491.90161967616, 5524.756929690788, 4563.060699202892, 5034.874128146679, 5118.41138269089, 5165.9483405165465, 5512.118061110203, 4431.8670439886855, 4858.732769697701, 4934.777934992862, 5034.119029169808, 5377.112776391851, 4862.3922009748785, 5328.333958255218, 5330.055758738583, 5495.945040549544, 5517.44825517445, 5173.697704521603, 5316.548093412103, 5355.732213389393, 5393.9730301348245, 5414.783408663609], "value": 3964.008224288438, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [698.6297262450896, 706.7243774927782, 773.8568364852499, 821.1419067074906, 831.4096991136295, 15157.01479403838, 15827.920860395681, 15834.683306333867, 15838.524844254676, 15897.364105435769, 14171.78742318866, 14407.63980900478, 14486.42054317826, 14516.491922782705, 14678.706425871476, 862.7066797288904, 880.3089574683875, 880.6477409036372, 881.8677198420224, 882.0429833630518, 675.3921470676381, 863.5595846118493, 873.9431936924111, 877.3639111644039, 877.9868301975475, 729.8942102714392, 737.3842841227581, 898.5417437107089, 927.9907200927972, 936.5223735894708, 922.2575826459697, 924.0482303227619, 924.9260059195249, 927.23966347595, 927.5640448988964, 686.3308274432438, 689.5759108148503, 690.6305126757211, 691.9654017299115, 693.774523279936, 12056.336901470415, 12550.68623284416, 12561.434735436836, 12585.622431327074, 12701.428435720414, 13190.53833115846, 14487.348069336906, 14787.40850365984, 14813.117628307351, 14934.25328733559, 11893.702657433547, 11913.404329783563, 12043.096767742454, 12102.757944841054, 12102.757944841142, 3716.721245906541, 3749.9814283803944, 3767.3783825668734, 3767.8492860285555, 3884.3396622574232, 112285.0, 113925.86074139236, 114870.66164045193, 114930.0, 116679.0, 11465.101760519416, 11465.197436179387, 11524.193306468467, 11537.077033837273, 11939.820902686466, 24114.51770964571, 24142.03431862741, 24150.51698966046, 24228.515429691306, 24252.757472425405, 7619.390448764197, 7687.154413014564, 7740.922590774133, 7773.766786996399, 7845.686172553148, 4235.216484950294, 4312.870613881589, 4321.546237645065, 4473.127740090651, 4481.977590112028, 3579.3736096183065, 3603.4594810778594, 3694.0026192927758, 3699.741018128705, 3711.498947642088, 573.9655620662774, 650.0152269311997, 656.5863505991194, 656.7705315086401, 663.5322097920954, 506.6225661881848, 543.1301973439246, 557.743438018508, 587.2805812879188, 596.2844586496221, 7461.962690186514, 9672.661456849117, 10342.431166285776, 10393.688189354332, 10517.684469465928, 629.8236493781749, 676.2020815437733, 678.8268991407159, 685.4995853027218, 703.7536862098327, 4985.626078044089, 5046.823361182341, 5055.51972562606, 5063.771940346236, 5083.974580127149, 83565.58217208875, 105297.94702053035, 110324.89675103307, 112402.43798781118, 114895.42552287185, 87781.68327475093, 88792.5560372207, 88918.0, 90725.54637226772, 91207.54396227976, 66742.66514669679, 86552.70170947567, 88164.0, 88507.55746221228, 89828.20343593092, 9584.520773961414, 9873.111419972238, 9941.900580994386, 10354.896451035393, 10737.409442480739, 46224.7688761554, 46911.0, 50025.99948001093, 50920.74539627278, 51193.7440312796, 69539.0, 69748.65125674542, 70390.0, 70677.64661176661, 71503.64248178726, 12873.549425770429, 13157.144785588906, 13196.239071864473, 13406.128601640861, 13645.522406715718, 14595.635109122357, 14608.853911461174, 14887.47893823711, 14907.65628635226, 14964.775528367301, 13622.86377136216, 13943.790843137158, 14000.0, 14104.929475352557, 14202.928985355007, 11476.483558239732, 13479.730405392034, 13493.743601976028, 14034.0, 14472.565823025327, 11105.0, 13206.537771177962, 13748.450061997408, 13933.164010159422, 14097.718045638825, 10343.586256549956, 11040.558377665126, 11190.440477976232, 11778.941105294418, 11940.164188506722, 9239.05723905724, 10323.0, 10485.790284194425, 10993.309418203784, 11088.778224435628], "value": 7556.621617865959, "unit": "score"}], "name": "dromaeo_css", "value": 7148.49890597967, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 166.0, 125.0, 112.0, 112.0, 109.0, 113.0, 112.0, 112.0, 112.0], "value": 122.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 110.0, 107.0, 144.0, 127.0, 141.0, 140.0, 105.0, 125.0, 115.0], "value": 125.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 187.0, 182.0, 187.0, 184.0, 182.0, 181.0, 183.0, 183.0, 185.0], "value": 184.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 58.0, 60.0, 87.0, 60.0, 59.0, 58.0, 66.0, 89.0, 73.0], "value": 68.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 71.0, 87.0, 73.0, 95.0, 99.0, 117.0, 69.0, 69.0, 84.0], "value": 85.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 87.0, 85.0, 92.0, 85.0, 88.0, 83.0, 87.0, 83.0], "value": 86.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 93.0, 102.0, 93.0, 94.0, 94.0, 93.0, 93.0, 93.0], "value": 94.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 130.0, 86.0, 88.0, 88.0, 87.0, 85.0, 85.0, 85.0, 87.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 54.0, 54.0, 54.0, 55.0, 55.0, 55.0, 55.0, 55.0, 55.0], "value": 54.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 71.0, 84.0, 74.0, 71.0, 69.0, 70.0, 73.0, 71.0, 72.0], "value": 72.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 90.0, 99.0, 108.0, 81.0, 90.0, 85.0, 87.0, 88.0, 92.0], "value": 90.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 139.0, 149.0, 139.0, 147.0, 149.0, 152.0, 140.0, 198.0, 183.0], "value": 153.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 160.0, 213.0, 236.0, 177.0, 171.0, 172.0, 174.0, 172.0, 174.0], "value": 182.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 68.0, 67.0, 68.0, 67.0, 66.0, 66.0, 68.0, 69.0], "value": 67.3, "unit": "score"}], "name": "kraken", "value": 1482.7000000000003, "alertThreshold": 2.0}]} 18:16:19 INFO - Return code: 0 18:16:19 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:16:19 INFO - # TBPL SUCCESS # 18:16:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:16:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:16:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:16:19 INFO - 2017-01-09 18:16:19.512 screenresolution[1652:10004] starting screenresolution argv=screenresolution get 18:16:19 INFO - 2017-01-09 18:16:19.514 screenresolution[1652:10004] Display 0: 1600x1200x32@60 18:16:19 INFO - 2017-01-09 18:16:19.520 screenresolution[1653:10007] starting screenresolution argv=screenresolution list 18:16:19 INFO - Available Modes on Display 0 18:16:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:16:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:16:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:16:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:16:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:16:19 INFO - Intel Iris: 18:16:19 INFO - Chipset Model: Intel Iris 18:16:19 INFO - Type: GPU 18:16:19 INFO - Bus: Built-In 18:16:19 INFO - VRAM (Dynamic, Max): 1536 MB 18:16:19 INFO - Vendor: Intel (0x8086) 18:16:19 INFO - Device ID: 0x0a2e 18:16:19 INFO - Revision ID: 0x0009 18:16:19 INFO - Displays: 18:16:19 INFO - Display: 18:16:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:16:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:16:19 INFO - Display Serial Number: 1600x1200 60 18:16:19 INFO - Main Display: Yes 18:16:19 INFO - Mirror: Off 18:16:19 INFO - Online: Yes 18:16:19 INFO - Rotation: Supported 18:16:19 INFO - Return code: 0 18:16:19 INFO - Running post-action listener: _resource_record_post_action 18:16:19 INFO - [mozharness: 2017-01-10 02:16:19.760946Z] Finished run-tests step (success) 18:16:19 INFO - Running post-run listener: _resource_record_post_run 18:16:19 INFO - Total resource usage - Wall time: 1201s; CPU: 54.0%; Read bytes: 38446080; Write bytes: 549932032; Read time: 431; Write time: 1788 18:16:19 INFO - TinderboxPrint: CPU usage
53.6% 18:16:19 INFO - TinderboxPrint: I/O read bytes / time
38,446,080 / 431 18:16:19 INFO - TinderboxPrint: I/O write bytes / time
549,932,032 / 1,788 18:16:19 INFO - TinderboxPrint: CPU idle
2,227.4 (46.3%) 18:16:19 INFO - TinderboxPrint: CPU system
959.9 (20.0%) 18:16:19 INFO - TinderboxPrint: CPU user
1,618.3 (33.7%) 18:16:19 INFO - TinderboxPrint: Swap in / out
287,727,616 / 0 18:16:19 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 250792448; Write bytes: 250380288; Read time: 18357; Write time: 463 18:16:19 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 34436096; Write bytes: 298040320; Read time: 345; Write time: 1316 18:16:20 INFO - Running post-run listener: _upload_blobber_files 18:16:20 INFO - Blob upload gear active. 18:16:20 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:16:20 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 18:16:20 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'] 18:16:20 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 18:16:20 INFO - (blobuploader) - INFO - Open directory for files ... 18:16:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:16:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:16:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:16:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:16:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:16:21 INFO - (blobuploader) - INFO - Done attempting. 18:16:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:16:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:16:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:16:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:16:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:16:22 INFO - (blobuploader) - INFO - Done attempting. 18:16:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:16:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:16:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:16:23 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:16:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:16:23 INFO - (blobuploader) - INFO - Done attempting. 18:16:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:16:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:16:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:16:25 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:16:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:16:25 INFO - (blobuploader) - INFO - Done attempting. 18:16:25 INFO - (blobuploader) - INFO - Iteration through files over. 18:16:25 INFO - Return code: 0 18:16:25 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:16:25 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:16:25 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6d879b032a0c4ea3d6ff4cd790b8056fe3e93551f080a8f964076f6591f8b8ad2f3f50a192287c906d716dfdfe82e5d3ceb13b417e4a75a5b05fa22c34252d51", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cc3f2ec2fc0420260ecd21aa095d709fd5fa8d897b08418aa393d2a92ef58d31b442acc76cbe848663d07f8741375f0108eb678baee6f06d4a398dfa224c0a50", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6e68c757a11f85e7e8d1ea721ec54c1094507cf10fcfe0931ed23f1acdb02c64ea8fdf578a7dac3d6a541e7cdeb3c9b657f013429d1dfab773c0ec43f7c79e06", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2874b95e1232d9f63eeaf7a92f442786b66d6503ce8dd4d574341c55b239d09789fd90a019b1764ca5e4e741675354d2aca3e3315b4336ff59dd035ccd9f8485"} 18:16:25 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:16:25 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:16:25 INFO - Contents: 18:16:25 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6d879b032a0c4ea3d6ff4cd790b8056fe3e93551f080a8f964076f6591f8b8ad2f3f50a192287c906d716dfdfe82e5d3ceb13b417e4a75a5b05fa22c34252d51", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cc3f2ec2fc0420260ecd21aa095d709fd5fa8d897b08418aa393d2a92ef58d31b442acc76cbe848663d07f8741375f0108eb678baee6f06d4a398dfa224c0a50", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6e68c757a11f85e7e8d1ea721ec54c1094507cf10fcfe0931ed23f1acdb02c64ea8fdf578a7dac3d6a541e7cdeb3c9b657f013429d1dfab773c0ec43f7c79e06", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2874b95e1232d9f63eeaf7a92f442786b66d6503ce8dd4d574341c55b239d09789fd90a019b1764ca5e4e741675354d2aca3e3315b4336ff59dd035ccd9f8485"} 18:16:25 INFO - Running post-run listener: copy_logs_to_upload_dir 18:16:25 INFO - Copying logs to upload dir... 18:16:25 INFO - mkdir: /builds/slave/test/build/upload/logs 18:16:25 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1264.857183 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2017-01-09 18:16:25.916970) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-09 18:16:25.920448) ========= 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.RegKVGgKCU/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.3z0VqiHUzl/Listeners TMPDIR=/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6d879b032a0c4ea3d6ff4cd790b8056fe3e93551f080a8f964076f6591f8b8ad2f3f50a192287c906d716dfdfe82e5d3ceb13b417e4a75a5b05fa22c34252d51", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cc3f2ec2fc0420260ecd21aa095d709fd5fa8d897b08418aa393d2a92ef58d31b442acc76cbe848663d07f8741375f0108eb678baee6f06d4a398dfa224c0a50", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6e68c757a11f85e7e8d1ea721ec54c1094507cf10fcfe0931ed23f1acdb02c64ea8fdf578a7dac3d6a541e7cdeb3c9b657f013429d1dfab773c0ec43f7c79e06", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2874b95e1232d9f63eeaf7a92f442786b66d6503ce8dd4d574341c55b239d09789fd90a019b1764ca5e4e741675354d2aca3e3315b4336ff59dd035ccd9f8485"} build_url:https://queue.taskcluster.net/v1/task/Xt1nRQoyTk6VhuqcNwTerQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011033 build_url: 'https://queue.taskcluster.net/v1/task/Xt1nRQoyTk6VhuqcNwTerQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6d879b032a0c4ea3d6ff4cd790b8056fe3e93551f080a8f964076f6591f8b8ad2f3f50a192287c906d716dfdfe82e5d3ceb13b417e4a75a5b05fa22c34252d51", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cc3f2ec2fc0420260ecd21aa095d709fd5fa8d897b08418aa393d2a92ef58d31b442acc76cbe848663d07f8741375f0108eb678baee6f06d4a398dfa224c0a50", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6e68c757a11f85e7e8d1ea721ec54c1094507cf10fcfe0931ed23f1acdb02c64ea8fdf578a7dac3d6a541e7cdeb3c9b657f013429d1dfab773c0ec43f7c79e06", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2874b95e1232d9f63eeaf7a92f442786b66d6503ce8dd4d574341c55b239d09789fd90a019b1764ca5e4e741675354d2aca3e3315b4336ff59dd035ccd9f8485"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-09 18:16:25.955363) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 18:16:25.955707) ========= 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.RegKVGgKCU/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.3z0VqiHUzl/Listeners TMPDIR=/var/folders/ds/nncns7yd2ll46rh0x60cww8m00000w/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.005038 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 18:16:26.005766) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-09 18:16:26.006377) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-09 18:16:26.006985) ========= ========= Total master_lag: 0.20 =========