builder: graphics_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0219 starttime: 1484315678.5 results: success (0) buildid: 20170113042016 builduid: 76ba237f55184f628d6f7afb33b09b7c revision: 648d37cd4daa9a4bb3a6fc0c3ccefc96623d78a2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-13 05:54:38.498987) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-13 05:54:38.499382) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-13 05:54:38.499659) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-13 05:54:38.599807) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-13 05:54:38.600169) ========= 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.cJpBM9BXil/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.6VrLj1zGFt/Listeners TMPDIR=/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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-13 05:54:38-- 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% 18.6M=0.001s 2017-01-13 05:54:39 (18.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.608774 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-13 05:54:39.232888) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-13 05:54:39.233195) ========= 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.cJpBM9BXil/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.6VrLj1zGFt/Listeners TMPDIR=/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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.024433 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-13 05:54:39.281623) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-13 05:54:39.281959) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev 648d37cd4daa9a4bb3a6fc0c3ccefc96623d78a2 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/graphics --rev 648d37cd4daa9a4bb3a6fc0c3ccefc96623d78a2 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.cJpBM9BXil/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.6VrLj1zGFt/Listeners TMPDIR=/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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-13 05:54:39,381 truncating revision to first 12 chars 2017-01-13 05:54:39,381 Setting DEBUG logging. 2017-01-13 05:54:39,381 attempt 1/10 2017-01-13 05:54:39,381 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/648d37cd4daa?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-13 05:54:40,600 unpacking tar archive at: graphics-648d37cd4daa/testing/mozharness/ program finished with exit code 0 elapsedTime=1.492366 ========= master_lag: 0.12 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-13 05:54:40.898146) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-13 05:54:40.898500) ========= script_repo_revision: 648d37cd4daa9a4bb3a6fc0c3ccefc96623d78a2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-13 05:54:40.898913) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-13 05:54:40.899239) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-13 05:54:40.919599) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2017-01-13 05:54:40.919936) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Graphics --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Graphics', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Graphics'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.cJpBM9BXil/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.6VrLj1zGFt/Listeners TMPDIR=/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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 05:54:41 INFO - MultiFileLogger online at 20170113 05:54:41 in /builds/slave/test 05:54:41 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Graphics --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 05:54:41 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:54:41 INFO - {'append_to_log': False, 05:54:41 INFO - 'base_work_dir': '/builds/slave/test', 05:54:41 INFO - 'blob_upload_branch': 'Graphics', 05:54:41 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:54:41 INFO - 'branch': 'Graphics', 05:54:41 INFO - 'buildbot_json_path': 'buildprops.json', 05:54:41 INFO - 'config_files': ('talos/mac_config.py',), 05:54:41 INFO - 'default_actions': ('clobber', 05:54:41 INFO - 'read-buildbot-config', 05:54:41 INFO - 'download-and-extract', 05:54:41 INFO - 'populate-webroot', 05:54:41 INFO - 'create-virtualenv', 05:54:41 INFO - 'install', 05:54:41 INFO - 'run-tests'), 05:54:41 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:54:41 INFO - 'download_minidump_stackwalk': True, 05:54:41 INFO - 'download_symbols': 'ondemand', 05:54:41 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:54:41 INFO - 'tooltool.py': '/tools/tooltool.py', 05:54:41 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:54:41 INFO - '/tools/misc-python/virtualenv.py')}, 05:54:41 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:54:41 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:54:41 INFO - 'installer_path': 'installer.exe', 05:54:41 INFO - 'log_level': 'info', 05:54:41 INFO - 'log_name': 'talos', 05:54:41 INFO - 'log_to_console': True, 05:54:41 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:54:41 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:54:41 INFO - 'opt_config_files': (), 05:54:41 INFO - 'pip_index': False, 05:54:41 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:54:41 INFO - 'cmd': ('bash', 05:54:41 INFO - '-c', 05:54:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:54:41 INFO - 'enabled': True, 05:54:41 INFO - 'halt_on_failure': False, 05:54:41 INFO - 'name': 'check_screen_resolution'},), 05:54:41 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:54:41 INFO - 'cmd': ('bash', 05:54:41 INFO - '-c', 05:54:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:54:41 INFO - 'enabled': True, 05:54:41 INFO - 'halt_on_failure': False, 05:54:41 INFO - 'name': 'check_screen_resolution'},), 05:54:41 INFO - 'run_cmd_checks_enabled': True, 05:54:41 INFO - 'sps_profile': False, 05:54:41 INFO - 'sps_profile_interval': 0, 05:54:41 INFO - 'suite': 'dromaeojs-e10s', 05:54:41 INFO - 'system_bits': '32', 05:54:41 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:54:41 INFO - 'title': 't-yosemite-r7-0219', 05:54:41 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:54:41 INFO - 'use_talos_json': True, 05:54:41 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:54:41 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:54:41 INFO - 'work_dir': 'build'} 05:54:41 INFO - [mozharness: 2017-01-13 13:54:41.128947Z] Running clobber step. 05:54:41 INFO - Running pre-action listener: _resource_record_pre_action 05:54:41 INFO - Running main action method: clobber 05:54:41 INFO - rmtree: /builds/slave/test/build 05:54:41 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:54:43 INFO - Running post-action listener: _resource_record_post_action 05:54:43 INFO - [mozharness: 2017-01-13 13:54:43.539524Z] Finished clobber step (success) 05:54:43 INFO - [mozharness: 2017-01-13 13:54:43.539689Z] Running read-buildbot-config step. 05:54:43 INFO - Running pre-action listener: _resource_record_pre_action 05:54:43 INFO - Running main action method: read_buildbot_config 05:54:43 INFO - Using buildbot properties: 05:54:43 INFO - { 05:54:43 INFO - "project": "", 05:54:43 INFO - "product": "firefox", 05:54:43 INFO - "script_repo_revision": "default", 05:54:43 INFO - "builddir": "graphics_yosemite_r7_test-dromaeojs-e10s", 05:54:43 INFO - "repository": "", 05:54:43 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 graphics talos dromaeojs-e10s", 05:54:43 INFO - "buildid": "20170113042016", 05:54:43 INFO - "pgo_build": "False", 05:54:43 INFO - "basedir": "/builds/slave/test", 05:54:43 INFO - "buildnumber": 2, 05:54:43 INFO - "slavename": "t-yosemite-r7-0219", 05:54:43 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 05:54:43 INFO - "platform": "yosemite_r7", 05:54:43 INFO - "scheduler": "tests-graphics-macosx64-talos-8-3600", 05:54:43 INFO - "branch": "graphics", 05:54:43 INFO - "repo_path": "projects/graphics", 05:54:43 INFO - "revision": "648d37cd4daa9a4bb3a6fc0c3ccefc96623d78a2", 05:54:43 INFO - "stage_platform": "macosx64", 05:54:43 INFO - "builduid": "76ba237f55184f628d6f7afb33b09b7c", 05:54:43 INFO - "slavebuilddir": "test" 05:54:43 INFO - } 05:54:43 INFO - Found installer url https://queue.taskcluster.net/v1/task/La1gTaTbSCGGL55v2602iQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 05:54:43 INFO - Running post-action listener: _resource_record_post_action 05:54:43 INFO - [mozharness: 2017-01-13 13:54:43.542903Z] Finished read-buildbot-config step (success) 05:54:43 INFO - [mozharness: 2017-01-13 13:54:43.543012Z] Running download-and-extract step. 05:54:43 INFO - Running pre-action listener: _resource_record_pre_action 05:54:43 INFO - Running main action method: download_and_extract 05:54:43 INFO - mkdir: /builds/slave/test/build/tests 05:54:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:54:43 INFO - trying https://queue.taskcluster.net/v1/task/La1gTaTbSCGGL55v2602iQ/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 05:54:43 INFO - Downloading https://queue.taskcluster.net/v1/task/La1gTaTbSCGGL55v2602iQ/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 05:54:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/La1gTaTbSCGGL55v2602iQ/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 05:54:52 INFO - Downloaded 1199 bytes. 05:54:52 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 05:54:52 INFO - Using the following test package requirements: 05:54:52 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 05:54:52 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:54:52 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 05:54:52 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:54:52 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 05:54:52 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:54:52 INFO - u'jsshell-mac.zip'], 05:54:52 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:54:52 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 05:54:52 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 05:54:52 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:54:52 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 05:54:52 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:54:52 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 05:54:52 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:54:52 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 05:54:52 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:54:52 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 05:54:52 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:54:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/La1gTaTbSCGGL55v2602iQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 05:54:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/La1gTaTbSCGGL55v2602iQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:54:52 INFO - Fetch https://queue.taskcluster.net/v1/task/La1gTaTbSCGGL55v2602iQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 05:54:53 INFO - Expected file size: 16314157 05:54:53 INFO - Obtained file size: 16314157 05:54:59 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 05:54:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/La1gTaTbSCGGL55v2602iQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 05:54:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/La1gTaTbSCGGL55v2602iQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:54:59 INFO - Fetch https://queue.taskcluster.net/v1/task/La1gTaTbSCGGL55v2602iQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 05:55:00 INFO - Expected file size: 16314157 05:55:00 INFO - Obtained file size: 16314157 05:55:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/La1gTaTbSCGGL55v2602iQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 05:55:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/La1gTaTbSCGGL55v2602iQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:55:06 INFO - Fetch https://queue.taskcluster.net/v1/task/La1gTaTbSCGGL55v2602iQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 05:55:08 INFO - Expected file size: 14403532 05:55:08 INFO - Obtained file size: 14403532 05:55:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:55:08 INFO - trying https://queue.taskcluster.net/v1/task/La1gTaTbSCGGL55v2602iQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 05:55:08 INFO - Downloading https://queue.taskcluster.net/v1/task/La1gTaTbSCGGL55v2602iQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 05:55:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/La1gTaTbSCGGL55v2602iQ/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 05:55:10 INFO - Downloaded 70116095 bytes. 05:55:10 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/La1gTaTbSCGGL55v2602iQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 05:55:10 INFO - mkdir: /builds/slave/test/properties 05:55:10 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:55:10 INFO - Writing to file /builds/slave/test/properties/build_url 05:55:10 INFO - Contents: 05:55:10 INFO - build_url:https://queue.taskcluster.net/v1/task/La1gTaTbSCGGL55v2602iQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 05:55:11 INFO - Running post-action listener: _resource_record_post_action 05:55:11 INFO - Running post-action listener: set_extra_try_arguments 05:55:11 INFO - [mozharness: 2017-01-13 13:55:11.168044Z] Finished download-and-extract step (success) 05:55:11 INFO - [mozharness: 2017-01-13 13:55:11.168175Z] Running populate-webroot step. 05:55:11 INFO - Running pre-action listener: _resource_record_pre_action 05:55:11 INFO - Running main action method: populate_webroot 05:55:11 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:55:11 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:55:11 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:55:11 INFO - '--tppagecycles', 05:55:11 INFO - '7'], 05:55:11 INFO - 'tests': ['tsvgm']}}, 05:55:11 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:55:11 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:55:11 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:55:11 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:55:11 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:55:11 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:55:11 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 05:55:11 INFO - 'talos_options': ['--disable-e10s'], 05:55:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:55:11 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:55:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:55:11 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 05:55:11 INFO - 'talos_options': ['--disable-e10s'], 05:55:11 INFO - 'tests': ['damp', 'tps']}, 05:55:11 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:55:11 INFO - 'tests': ['damp', 'tps']}, 05:55:11 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:55:11 INFO - 'tests': ['dromaeo_dom']}, 05:55:11 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:55:11 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:55:11 INFO - 'tests': ['basic_compositor_video']}, 05:55:11 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:55:11 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:55:11 INFO - 'tests': ['a11yr', 05:55:11 INFO - 'ts_paint', 05:55:11 INFO - 'tpaint', 05:55:11 INFO - 'sessionrestore', 05:55:11 INFO - 'sessionrestore_no_auto_restore', 05:55:11 INFO - 'tabpaint']}, 05:55:11 INFO - 'other-e10s': {'tests': ['a11yr', 05:55:11 INFO - 'ts_paint', 05:55:11 INFO - 'tpaint', 05:55:11 INFO - 'sessionrestore', 05:55:11 INFO - 'sessionrestore_no_auto_restore', 05:55:11 INFO - 'tabpaint']}, 05:55:11 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:55:11 INFO - 'ts_paint', 05:55:11 INFO - 'tpaint', 05:55:11 INFO - 'sessionrestore', 05:55:11 INFO - 'sessionrestore_no_auto_restore', 05:55:11 INFO - 'tabpaint']}, 05:55:11 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:55:11 INFO - 'ts_paint', 05:55:11 INFO - 'tpaint', 05:55:11 INFO - 'sessionrestore', 05:55:11 INFO - 'sessionrestore_no_auto_restore', 05:55:11 INFO - 'tabpaint']}, 05:55:11 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:55:11 INFO - 'tests': ['a11yr', 05:55:11 INFO - 'ts_paint', 05:55:11 INFO - 'tpaint', 05:55:11 INFO - 'sessionrestore', 05:55:11 INFO - 'sessionrestore_no_auto_restore', 05:55:11 INFO - 'tabpaint']}, 05:55:11 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:55:11 INFO - 'tests': ['a11yr', 05:55:11 INFO - 'ts_paint', 05:55:11 INFO - 'tpaint', 05:55:11 INFO - 'sessionrestore', 05:55:11 INFO - 'sessionrestore_no_auto_restore', 05:55:11 INFO - 'tabpaint']}, 05:55:11 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:55:11 INFO - 'tests': ['tsvgx', 05:55:11 INFO - 'tsvgr_opacity', 05:55:11 INFO - 'tart', 05:55:11 INFO - 'tscrollx', 05:55:11 INFO - 'cart', 05:55:11 INFO - 'tsvg_static']}, 05:55:11 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:55:11 INFO - 'tsvgr_opacity', 05:55:11 INFO - 'tart', 05:55:11 INFO - 'tscrollx', 05:55:11 INFO - 'cart', 05:55:11 INFO - 'tsvg_static']}, 05:55:11 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 05:55:11 INFO - 'talos_options': ['--disable-e10s'], 05:55:11 INFO - 'tests': ['tp5o']}, 05:55:11 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:55:11 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 05:55:11 INFO - 'talos_options': ['--disable-e10s', 05:55:11 INFO - '--xperf_path', 05:55:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:55:11 INFO - 'tests': ['tp5n']}, 05:55:11 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:55:11 INFO - 'talos_options': ['--xperf_path', 05:55:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:55:11 INFO - 'tests': ['tp5n']}}, 05:55:11 INFO - 'talos.zip': {'path': '', 05:55:11 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:55:11 INFO - Running post-action listener: _resource_record_post_action 05:55:11 INFO - [mozharness: 2017-01-13 13:55:11.186675Z] Finished populate-webroot step (success) 05:55:11 INFO - [mozharness: 2017-01-13 13:55:11.186809Z] Running create-virtualenv step. 05:55:11 INFO - Running pre-action listener: _resource_record_pre_action 05:55:11 INFO - Running main action method: create_virtualenv 05:55:11 INFO - Creating virtualenv /builds/slave/test/build/venv 05:55:11 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:55:11 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:55:11 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:55:11 INFO - Using real prefix '/tools/python27' 05:55:11 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:55:12 INFO - Installing distribute.............................................................................................................................................................................................done. 05:55:14 INFO - Installing pip.................done. 05:55:14 INFO - Return code: 0 05:55:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:55:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:55:15 INFO - Reading from file tmpfile_stdout 05:55:15 INFO - Output received: 05:55:15 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 05:55:15 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:55:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:55:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:55:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:55:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:55:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10aa15b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa18768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7925775c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a7f3d30>, '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 0x10a71adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a7e8be8>, '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.cJpBM9BXil/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.6VrLj1zGFt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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 05:55:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:55:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:55:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cJpBM9BXil/Render', 05:55:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:55:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:55:15 INFO - 'HOME': '/Users/cltbld', 05:55:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:55:15 INFO - 'LOGNAME': 'cltbld', 05:55:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:55:15 INFO - 'MOZ_NO_REMOTE': '1', 05:55:15 INFO - 'NO_EM_RESTART': '1', 05:55:15 INFO - 'PAGER': '/bin/cat', 05:55:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:55:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:55:15 INFO - 'PWD': '/builds/slave/test', 05:55:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:55:15 INFO - 'SHELL': '/bin/bash', 05:55:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6VrLj1zGFt/Listeners', 05:55:15 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 05:55:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:55:15 INFO - 'USER': 'cltbld', 05:55:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:55:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:55:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:55:15 INFO - 'XPC_FLAGS': '0x0', 05:55:15 INFO - 'XPC_SERVICE_NAME': '0', 05:55:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:55:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:55:15 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 05:55:15 INFO - Cleaning up... 05:55:15 INFO - Return code: 0 05:55:15 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:55:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:55:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:55:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:55:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:55:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10aa15b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa18768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7925775c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a7f3d30>, '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 0x10a71adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a7e8be8>, '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.cJpBM9BXil/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.6VrLj1zGFt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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 05:55:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:55:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:55:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cJpBM9BXil/Render', 05:55:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:55:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:55:15 INFO - 'HOME': '/Users/cltbld', 05:55:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:55:15 INFO - 'LOGNAME': 'cltbld', 05:55:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:55:15 INFO - 'MOZ_NO_REMOTE': '1', 05:55:15 INFO - 'NO_EM_RESTART': '1', 05:55:15 INFO - 'PAGER': '/bin/cat', 05:55:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:55:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:55:15 INFO - 'PWD': '/builds/slave/test', 05:55:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:55:15 INFO - 'SHELL': '/bin/bash', 05:55:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6VrLj1zGFt/Listeners', 05:55:15 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 05:55:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:55:15 INFO - 'USER': 'cltbld', 05:55:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:55:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:55:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:55:15 INFO - 'XPC_FLAGS': '0x0', 05:55:15 INFO - 'XPC_SERVICE_NAME': '0', 05:55:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:55:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:55:15 INFO - Downloading/unpacking psutil>=3.1.1 05:55:15 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 05:55:15 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 05:55:15 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 05:55:15 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 05:55:15 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 05:55:15 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 05:55:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:55:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:55:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:55:19 INFO - Installing collected packages: psutil 05:55:19 INFO - Running setup.py install for psutil 05:55:19 INFO - building 'psutil._psutil_osx' extension 05:55:19 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 05:55:20 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 05:55:20 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 05:55:20 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 05:55:20 INFO - building 'psutil._psutil_posix' extension 05:55:20 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 05:55:20 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:55:20 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:55:20 INFO - ^ 05:55:20 INFO - 1 warning generated. 05:55:20 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 05:55:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:55:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:55:20 INFO - Successfully installed psutil 05:55:20 INFO - Cleaning up... 05:55:20 INFO - Return code: 0 05:55:20 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:55:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:55:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:55:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:55:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:55:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10aa15b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa18768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7925775c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a7f3d30>, '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 0x10a71adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a7e8be8>, '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.cJpBM9BXil/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.6VrLj1zGFt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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 05:55:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:55:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:55:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cJpBM9BXil/Render', 05:55:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:55:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:55:20 INFO - 'HOME': '/Users/cltbld', 05:55:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:55:20 INFO - 'LOGNAME': 'cltbld', 05:55:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:55:20 INFO - 'MOZ_NO_REMOTE': '1', 05:55:20 INFO - 'NO_EM_RESTART': '1', 05:55:20 INFO - 'PAGER': '/bin/cat', 05:55:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:55:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:55:20 INFO - 'PWD': '/builds/slave/test', 05:55:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:55:20 INFO - 'SHELL': '/bin/bash', 05:55:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6VrLj1zGFt/Listeners', 05:55:20 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 05:55:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:55:20 INFO - 'USER': 'cltbld', 05:55:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:55:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:55:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:55:20 INFO - 'XPC_FLAGS': '0x0', 05:55:20 INFO - 'XPC_SERVICE_NAME': '0', 05:55:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:55:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:55:21 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:55:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:55:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:55:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:55:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:55:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:55:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:55:23 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:55:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:55:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:55:23 INFO - Installing collected packages: mozsystemmonitor 05:55:23 INFO - Running setup.py install for mozsystemmonitor 05:55:23 INFO - Successfully installed mozsystemmonitor 05:55:23 INFO - Cleaning up... 05:55:24 INFO - Return code: 0 05:55:24 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:55:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:55:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:55:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:55:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:55:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10aa15b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa18768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7925775c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a7f3d30>, '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 0x10a71adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a7e8be8>, '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.cJpBM9BXil/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.6VrLj1zGFt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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 05:55:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:55:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 05:55:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cJpBM9BXil/Render', 05:55:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:55:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:55:24 INFO - 'HOME': '/Users/cltbld', 05:55:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:55:24 INFO - 'LOGNAME': 'cltbld', 05:55:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:55:24 INFO - 'MOZ_NO_REMOTE': '1', 05:55:24 INFO - 'NO_EM_RESTART': '1', 05:55:24 INFO - 'PAGER': '/bin/cat', 05:55:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:55:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:55:24 INFO - 'PWD': '/builds/slave/test', 05:55:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:55:24 INFO - 'SHELL': '/bin/bash', 05:55:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6VrLj1zGFt/Listeners', 05:55:24 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 05:55:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:55:24 INFO - 'USER': 'cltbld', 05:55:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:55:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:55:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:55:24 INFO - 'XPC_FLAGS': '0x0', 05:55:24 INFO - 'XPC_SERVICE_NAME': '0', 05:55:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:55:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:55:24 INFO - Downloading/unpacking jsonschema==2.5.1 05:55:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:55:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:55:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:55:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:55:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:55:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:55:26 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:55:26 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:55:26 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 05:55:26 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 05:55:26 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 05:55:26 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 05:55:26 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 05:55:26 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 05:55:27 INFO - Downloading functools32-3.2.3-2.tar.gz 05:55:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:55:27 INFO - warning: no files found matching '*.txt' 05:55:27 INFO - no previously-included directories found matching 'build' 05:55:27 INFO - no previously-included directories found matching 'dist' 05:55:27 INFO - no previously-included directories found matching '.git*' 05:55:27 INFO - Installing collected packages: jsonschema, functools32 05:55:27 INFO - Running setup.py install for functools32 05:55:27 INFO - warning: no files found matching '*.txt' 05:55:27 INFO - no previously-included directories found matching 'build' 05:55:27 INFO - no previously-included directories found matching 'dist' 05:55:27 INFO - no previously-included directories found matching '.git*' 05:55:27 INFO - Successfully installed jsonschema functools32 05:55:27 INFO - Cleaning up... 05:55:27 INFO - Return code: 0 05:55:27 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:55:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:55:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:55:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:55:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:55:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10aa15b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa18768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7925775c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a7f3d30>, '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 0x10a71adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a7e8be8>, '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.cJpBM9BXil/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.6VrLj1zGFt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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 05:55:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:55:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 05:55:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cJpBM9BXil/Render', 05:55:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:55:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:55:27 INFO - 'HOME': '/Users/cltbld', 05:55:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:55:27 INFO - 'LOGNAME': 'cltbld', 05:55:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:55:27 INFO - 'MOZ_NO_REMOTE': '1', 05:55:27 INFO - 'NO_EM_RESTART': '1', 05:55:27 INFO - 'PAGER': '/bin/cat', 05:55:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:55:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:55:27 INFO - 'PWD': '/builds/slave/test', 05:55:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:55:27 INFO - 'SHELL': '/bin/bash', 05:55:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6VrLj1zGFt/Listeners', 05:55:27 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 05:55:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:55:27 INFO - 'USER': 'cltbld', 05:55:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:55:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:55:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:55:27 INFO - 'XPC_FLAGS': '0x0', 05:55:27 INFO - 'XPC_SERVICE_NAME': '0', 05:55:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:55:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:55:28 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:55:28 INFO - Cleaning up... 05:55:28 INFO - Return code: 0 05:55:28 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:55:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:55:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:55:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:55:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:55:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10aa15b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa18768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7925775c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a7f3d30>, '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 0x10a71adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a7e8be8>, '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.cJpBM9BXil/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.6VrLj1zGFt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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 05:55:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:55:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:55:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cJpBM9BXil/Render', 05:55:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:55:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:55:28 INFO - 'HOME': '/Users/cltbld', 05:55:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:55:28 INFO - 'LOGNAME': 'cltbld', 05:55:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:55:28 INFO - 'MOZ_NO_REMOTE': '1', 05:55:28 INFO - 'NO_EM_RESTART': '1', 05:55:28 INFO - 'PAGER': '/bin/cat', 05:55:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:55:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:55:28 INFO - 'PWD': '/builds/slave/test', 05:55:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:55:28 INFO - 'SHELL': '/bin/bash', 05:55:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6VrLj1zGFt/Listeners', 05:55:28 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 05:55:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:55:28 INFO - 'USER': 'cltbld', 05:55:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:55:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:55:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:55:28 INFO - 'XPC_FLAGS': '0x0', 05:55:28 INFO - 'XPC_SERVICE_NAME': '0', 05:55:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:55:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:55:28 INFO - Downloading/unpacking blobuploader==1.2.4 05:55:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:55:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:55:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:55:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:55:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:55:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:55:30 INFO - Downloading blobuploader-1.2.4.tar.gz 05:55:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:55:30 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:55:30 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 05:55:30 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 05:55:30 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 05:55:30 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 05:55:30 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 05:55:30 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 05:55:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:55:31 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:55:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:55:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:55:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:55:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:55:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:55:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:55:32 INFO - Downloading docopt-0.6.1.tar.gz 05:55:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:55:32 INFO - Installing collected packages: blobuploader, requests, docopt 05:55:32 INFO - Running setup.py install for blobuploader 05:55:32 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:55:32 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:55:32 INFO - Running setup.py install for requests 05:55:32 INFO - Running setup.py install for docopt 05:55:32 INFO - Successfully installed blobuploader requests docopt 05:55:32 INFO - Cleaning up... 05:55:33 INFO - Return code: 0 05:55:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:55:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:55:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:55:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:55:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:55:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10aa15b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa18768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7925775c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a7f3d30>, '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 0x10a71adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a7e8be8>, '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.cJpBM9BXil/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.6VrLj1zGFt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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 05:55:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:55:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:55:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cJpBM9BXil/Render', 05:55:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:55:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:55:33 INFO - 'HOME': '/Users/cltbld', 05:55:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:55:33 INFO - 'LOGNAME': 'cltbld', 05:55:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:55:33 INFO - 'MOZ_NO_REMOTE': '1', 05:55:33 INFO - 'NO_EM_RESTART': '1', 05:55:33 INFO - 'PAGER': '/bin/cat', 05:55:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:55:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:55:33 INFO - 'PWD': '/builds/slave/test', 05:55:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:55:33 INFO - 'SHELL': '/bin/bash', 05:55:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6VrLj1zGFt/Listeners', 05:55:33 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 05:55:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:55:33 INFO - 'USER': 'cltbld', 05:55:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:55:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:55:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:55:33 INFO - 'XPC_FLAGS': '0x0', 05:55:33 INFO - 'XPC_SERVICE_NAME': '0', 05:55:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:55:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:55:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:55:33 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-ujayEN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:55:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:55:33 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-D1P1m0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:55:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:55:33 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-i_Dq2q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:55:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:55:33 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-ocReNH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:55:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:55:33 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-aOmHvd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:55:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:55:33 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-RGCc7E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:55:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:55:33 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-_O2wZF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:55:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:55:34 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-9YogkA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:55:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:55:34 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-kDnYRT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:55:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:55:34 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-g6sykd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:55:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:55:34 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-lUhvUp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:55:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:55:34 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-vEZezF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:55:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:55:34 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-16sPRf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:55:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:55:34 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-c1vJDj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:55:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:55:34 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-JYKbDj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:55:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:55:34 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-soRPjl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:55:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:55:34 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-YBTs8U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:55:35 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:55:35 INFO - Running setup.py install for manifestparser 05:55:35 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:55:35 INFO - Running setup.py install for mozcrash 05:55:35 INFO - Running setup.py install for mozdebug 05:55:35 INFO - Running setup.py install for mozdevice 05:55:35 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:55:35 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:55:35 INFO - Running setup.py install for mozfile 05:55:35 INFO - Running setup.py install for mozhttpd 05:55:35 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:55:35 INFO - Running setup.py install for mozinfo 05:55:36 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:55:36 INFO - Running setup.py install for mozInstall 05:55:36 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:55:36 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:55:36 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:55:36 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:55:36 INFO - Running setup.py install for mozleak 05:55:36 INFO - Running setup.py install for mozlog 05:55:36 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:55:36 INFO - Running setup.py install for moznetwork 05:55:36 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:55:36 INFO - Running setup.py install for mozprocess 05:55:36 INFO - Running setup.py install for mozprofile 05:55:37 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:55:37 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:55:37 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:55:37 INFO - Running setup.py install for mozrunner 05:55:37 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:55:37 INFO - Running setup.py install for mozscreenshot 05:55:37 INFO - Running setup.py install for moztest 05:55:37 INFO - Running setup.py install for mozversion 05:55:37 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:55:37 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:55:37 INFO - Cleaning up... 05:55:37 INFO - Return code: 0 05:55:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:55:37 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')]} 05:55:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:55:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:37 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')]} 05:55:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:55:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:37 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 0x10aa15b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa18768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7925775c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a7f3d30>, '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 0x10a71adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a7e8be8>, '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.cJpBM9BXil/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.6VrLj1zGFt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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 05:55:37 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 05:55:37 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 05:55:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cJpBM9BXil/Render', 05:55:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:55:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:55:37 INFO - 'HOME': '/Users/cltbld', 05:55:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:55:37 INFO - 'LOGNAME': 'cltbld', 05:55:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:55:37 INFO - 'MOZ_NO_REMOTE': '1', 05:55:37 INFO - 'NO_EM_RESTART': '1', 05:55:37 INFO - 'PAGER': '/bin/cat', 05:55:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:55:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:55:37 INFO - 'PWD': '/builds/slave/test', 05:55:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:55:37 INFO - 'SHELL': '/bin/bash', 05:55:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6VrLj1zGFt/Listeners', 05:55:37 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 05:55:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:55:37 INFO - 'USER': 'cltbld', 05:55:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:55:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:55:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:55:37 INFO - 'XPC_FLAGS': '0x0', 05:55:37 INFO - 'XPC_SERVICE_NAME': '0', 05:55:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:55:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:55:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:55:37 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-pRvHes-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:55:38 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)) 05:55:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:55:38 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-8qCl96-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:55:38 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)) 05:55:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:55:38 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-rhydXR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:55:38 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)) 05:55:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:55:38 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-Zf4RTs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:55:38 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)) 05:55:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:55:38 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-VYQQn0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:55:38 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)) 05:55:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:55:38 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-QAcAhy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:55:38 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)) 05:55:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:55:38 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-quqNnp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:55:38 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)) 05:55:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:55:38 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-Et7ecz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:55:38 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)) 05:55:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:55:38 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-9beFy_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:55:38 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)) 05:55:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:55:38 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-dYjASi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:55:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:55:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:55:39 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-JMaxPh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:55:39 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)) 05:55:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:55:39 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-ZMkOKi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:55:39 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)) 05:55:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:55:39 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-OZ2ufz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:55:39 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)) 05:55:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:55:39 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-5qhidM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:55:39 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)) 05:55:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:55:39 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-fS2Hsn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:55:39 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)) 05:55:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:55:39 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-k1ittA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:55:39 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)) 05:55:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:55:39 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-eBoOSd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:55:39 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)) 05:55:39 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)) 05:55:39 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)) 05:55:39 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)) 05:55:39 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)) 05:55:39 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)) 05:55:39 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:55:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:55:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:55:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:55:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:55:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:55:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:55:42 INFO - Downloading blessings-1.6.tar.gz 05:55:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:55:42 INFO - Installing collected packages: blessings 05:55:42 INFO - Running setup.py install for blessings 05:55:43 INFO - Successfully installed blessings 05:55:43 INFO - Cleaning up... 05:55:43 INFO - Return code: 0 05:55:43 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:55:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:55:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:55:43 INFO - Reading from file tmpfile_stdout 05:55:43 INFO - Current package versions: 05:55:43 INFO - blessings == 1.6 05:55:43 INFO - blobuploader == 1.2.4 05:55:43 INFO - docopt == 0.6.1 05:55:43 INFO - functools32 == 3.2.3-2 05:55:43 INFO - jsonschema == 2.5.1 05:55:43 INFO - manifestparser == 1.1 05:55:43 INFO - mozInstall == 1.12 05:55:43 INFO - mozcrash == 1.0 05:55:43 INFO - mozdebug == 0.1 05:55:43 INFO - mozdevice == 0.49 05:55:43 INFO - mozfile == 1.2 05:55:43 INFO - mozhttpd == 0.7 05:55:43 INFO - mozinfo == 0.9 05:55:43 INFO - mozleak == 0.1 05:55:43 INFO - mozlog == 3.4 05:55:43 INFO - moznetwork == 0.27 05:55:43 INFO - mozprocess == 0.24 05:55:43 INFO - mozprofile == 0.28 05:55:43 INFO - mozrunner == 6.13 05:55:43 INFO - mozscreenshot == 0.1 05:55:43 INFO - mozsystemmonitor == 0.3 05:55:43 INFO - moztest == 0.8 05:55:43 INFO - mozversion == 1.4 05:55:43 INFO - psutil == 3.1.1 05:55:43 INFO - requests == 1.2.3 05:55:43 INFO - wsgiref == 0.1.2 05:55:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:55:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:55:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:55:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:55:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:55:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10aa15b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa18768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7925775c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a7f3d30>, '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 0x10a71adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a7e8be8>, '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.cJpBM9BXil/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.6VrLj1zGFt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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 05:55:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:55:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:55:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cJpBM9BXil/Render', 05:55:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:55:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:55:43 INFO - 'HOME': '/Users/cltbld', 05:55:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:55:43 INFO - 'LOGNAME': 'cltbld', 05:55:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:55:43 INFO - 'MOZ_NO_REMOTE': '1', 05:55:43 INFO - 'NO_EM_RESTART': '1', 05:55:43 INFO - 'PAGER': '/bin/cat', 05:55:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:55:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:55:43 INFO - 'PWD': '/builds/slave/test', 05:55:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:55:43 INFO - 'SHELL': '/bin/bash', 05:55:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6VrLj1zGFt/Listeners', 05:55:43 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 05:55:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:55:43 INFO - 'USER': 'cltbld', 05:55:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:55:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:55:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:55:43 INFO - 'XPC_FLAGS': '0x0', 05:55:43 INFO - 'XPC_SERVICE_NAME': '0', 05:55:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:55:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:55:43 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)) 05:55:43 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)) 05:55:43 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)) 05:55:43 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)) 05:55:43 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)) 05:55:43 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)) 05:55:43 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)) 05:55:43 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)) 05:55:43 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)) 05:55:43 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)) 05:55:43 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)) 05:55:43 INFO - Cleaning up... 05:55:43 INFO - Return code: 0 05:55:43 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:55:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:55:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:55:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:55:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:55:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:43 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 0x10aa15b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa18768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7925775c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a7f3d30>, '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 0x10a71adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a7e8be8>, '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.cJpBM9BXil/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.6VrLj1zGFt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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 05:55:43 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 05:55:43 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 05:55:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cJpBM9BXil/Render', 05:55:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:55:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:55:43 INFO - 'HOME': '/Users/cltbld', 05:55:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:55:43 INFO - 'LOGNAME': 'cltbld', 05:55:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:55:43 INFO - 'MOZ_NO_REMOTE': '1', 05:55:43 INFO - 'NO_EM_RESTART': '1', 05:55:43 INFO - 'PAGER': '/bin/cat', 05:55:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:55:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:55:43 INFO - 'PWD': '/builds/slave/test', 05:55:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:55:43 INFO - 'SHELL': '/bin/bash', 05:55:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6VrLj1zGFt/Listeners', 05:55:43 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 05:55:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:55:43 INFO - 'USER': 'cltbld', 05:55:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:55:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:55:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:55:43 INFO - 'XPC_FLAGS': '0x0', 05:55:43 INFO - 'XPC_SERVICE_NAME': '0', 05:55:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:55:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:55:43 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:55:43 INFO - Cleaning up... 05:55:44 INFO - Return code: 0 05:55:44 INFO - Running post-action listener: _resource_record_post_action 05:55:44 INFO - Running post-action listener: _start_resource_monitoring 05:55:44 INFO - Starting resource monitoring. 05:55:44 INFO - [mozharness: 2017-01-13 13:55:44.045267Z] Finished create-virtualenv step (success) 05:55:44 INFO - [mozharness: 2017-01-13 13:55:44.046063Z] Running install step. 05:55:44 INFO - Running pre-action listener: _resource_record_pre_action 05:55:44 INFO - Running main action method: install 05:55:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:55:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:55:44 INFO - Reading from file tmpfile_stdout 05:55:44 INFO - Detecting whether we're running mozinstall >=1.0... 05:55:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:55:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:55:44 INFO - Reading from file tmpfile_stdout 05:55:44 INFO - Output received: 05:55:44 INFO - Usage: mozinstall [options] installer 05:55:44 INFO - Options: 05:55:44 INFO - -h, --help show this help message and exit 05:55:44 INFO - -d DEST, --destination=DEST 05:55:44 INFO - Directory to install application into. [default: 05:55:44 INFO - "/builds/slave/test"] 05:55:44 INFO - --app=APP Application being installed. [default: firefox] 05:55:44 INFO - mkdir: /builds/slave/test/build/application 05:55:44 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'] 05:55:44 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 05:56:06 INFO - Reading from file tmpfile_stdout 05:56:06 INFO - Output received: 05:56:06 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:56:06 INFO - Running post-action listener: _resource_record_post_action 05:56:06 INFO - [mozharness: 2017-01-13 13:56:06.588723Z] Finished install step (success) 05:56:06 INFO - [mozharness: 2017-01-13 13:56:06.588855Z] Running run-tests step. 05:56:06 INFO - Running pre-action listener: _resource_record_pre_action 05:56:06 INFO - Running main action method: run_tests 05:56:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:56:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:56:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:56:06 INFO - 2017-01-13 05:56:06.603 screenresolution[1637:9631] starting screenresolution argv=screenresolution get 05:56:06 INFO - 2017-01-13 05:56:06.605 screenresolution[1637:9631] Display 0: 1600x1200x32@60 05:56:06 INFO - 2017-01-13 05:56:06.611 screenresolution[1638:9634] starting screenresolution argv=screenresolution list 05:56:06 INFO - Available Modes on Display 0 05:56:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:56:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:56:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:56:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:56:06 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:56:06 INFO - Intel Iris: 05:56:06 INFO - Chipset Model: Intel Iris 05:56:06 INFO - Type: GPU 05:56:06 INFO - Bus: Built-In 05:56:06 INFO - VRAM (Dynamic, Max): 1536 MB 05:56:06 INFO - Vendor: Intel (0x8086) 05:56:06 INFO - Device ID: 0x0a2e 05:56:06 INFO - Revision ID: 0x0009 05:56:06 INFO - Displays: 05:56:06 INFO - Display: 05:56:06 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:56:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:56:06 INFO - Display Serial Number: 1600x1200 60 05:56:06 INFO - Main Display: Yes 05:56:06 INFO - Mirror: Off 05:56:06 INFO - Online: Yes 05:56:06 INFO - Rotation: Supported 05:56:06 INFO - Return code: 0 05:56:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:56:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:56:06 INFO - Python 2.7.3 05:56:06 INFO - Return code: 0 05:56:06 INFO - grabbing minidump binary from tooltool 05: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')]} 05:56:06 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 0x10a7f3d30>, '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 0x10a71adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a7e8be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:56:06 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 05:56:06 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 05:56:06 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 05:56:06 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:56:06 INFO - Return code: 0 05:56:06 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:56:06 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:56:06 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:56:06 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:56:06 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:56:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/La1gTaTbSCGGL55v2602iQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0219', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 05:56:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Graphics --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/La1gTaTbSCGGL55v2602iQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0219 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 05:56:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cJpBM9BXil/Render', 05:56:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:56:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:56:06 INFO - 'HOME': '/Users/cltbld', 05:56:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:56:06 INFO - 'LOGNAME': 'cltbld', 05:56:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:56:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:56:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:56:06 INFO - 'MOZ_NO_REMOTE': '1', 05:56:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:56:06 INFO - 'NO_EM_RESTART': '1', 05:56:06 INFO - 'PAGER': '/bin/cat', 05:56:06 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:56:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:56:06 INFO - 'PWD': '/builds/slave/test', 05:56:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:56:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:56:06 INFO - 'SHELL': '/bin/bash', 05:56:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6VrLj1zGFt/Listeners', 05:56:06 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 05:56:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:56:06 INFO - 'USER': 'cltbld', 05:56:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:56:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:56:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:56:06 INFO - 'XPC_FLAGS': '0x0', 05:56:06 INFO - 'XPC_SERVICE_NAME': '0', 05:56:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:56:06 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/La1gTaTbSCGGL55v2602iQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0219', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 05:56:07 INFO - mozversion application_buildid: 20170113042016 05:56:07 INFO - mozversion application_changeset: 648d37cd4daa9a4bb3a6fc0c3ccefc96623d78a2 05:56:07 INFO - mozversion application_display_name: Nightly 05:56:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:56:07 INFO - mozversion application_name: Firefox 05:56:07 INFO - mozversion application_remotingname: firefox 05:56:07 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 05:56:07 INFO - mozversion application_vendor: Mozilla 05:56:07 INFO - mozversion application_version: 53.0a1 05:56:07 INFO - mozversion platform_buildid: 20170113042016 05:56:07 INFO - mozversion platform_changeset: 648d37cd4daa9a4bb3a6fc0c3ccefc96623d78a2 05:56:07 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 05:56:07 INFO - mozversion platform_version: 53.0a1 05:56:07 INFO - using testdate: 1484315767 05:56:07 INFO - actual date: 1484315767 05:56:07 INFO - starting webserver on 'localhost:49244' 05:56:07 INFO - SUITE-START | Running 2 tests 05:56:07 INFO - TEST-START | dromaeo_css 05:56:07 INFO - operating with platform_type : mac_ 05:56:07 INFO - Initialising browser for dromaeo_css test... 05:56:07 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/tmpp2oI85/profile http://localhost:49244/getInfo.html) 05:56:10 INFO - PROCESS | 1648 | 2017-01-13 05:56:10.107 plugin-container[1650:9807] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4953, name = 'com.apple.tsm.portname' 05:56:10 INFO - PROCESS | 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:56:10 INFO - PROCESS | 1648 | 2017-01-13 05:56:10.111 plugin-container[1650:9807] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x950b, name = 'com.apple.CFPasteboardClient' 05:56:10 INFO - PROCESS | 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:56:10 INFO - PROCESS | 1648 | 2017-01-13 05:56:10.111 plugin-container[1650:9807] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:56:10 INFO - PROCESS | 1648 | __metrics Screen width/height:1600/1200 05:56:10 INFO - PROCESS | 1648 | colorDepth:24 05:56:10 INFO - PROCESS | 1648 | Browser inner width/height: 1024/689 05:56:10 INFO - PROCESS | 1648 | __metrics 05:56:10 ERROR - PROCESS | 1648 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3242: TypeError: this.tabs is undefined 05:56:10 INFO - TEST-INFO | 1648: exit 0 05:56:10 INFO - Browser initialized. 05:56:10 INFO - Running cycle 1/1 for dromaeo_css test... 05:56:10 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/tmpp2oI85/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:56:13 INFO - PROCESS | 1652 | 2017-01-13 05:56:13.215 plugin-container[1653:9942] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4643, name = 'com.apple.tsm.portname' 05:56:13 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:56:13 INFO - PROCESS | 1652 | 2017-01-13 05:56:13.216 plugin-container[1653:9942] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 05:56:13 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:56:13 INFO - PROCESS | 1652 | 2017-01-13 05:56:13.216 plugin-container[1653:9942] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:56:22 INFO - PROCESS | 1652 | RSS: Main: 233795584 05:56:22 INFO - PROCESS | 1652 | 05:59:23 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49244/tests/dromaeo/cssquery-ext.html) 05:59:26 INFO - PROCESS | 1652 | RSS: Main: 249180160 05:59:26 INFO - PROCESS | 1652 | 06:01:11 INFO - PROCESS | 1652 | ************************* 06:01:11 INFO - PROCESS | 1652 | A coding exception was thrown and uncaught in a Task. 06:01:11 INFO - PROCESS | 1652 | 06:01:11 INFO - PROCESS | 1652 | Full message: ReferenceError: fetch is not defined 06:01:11 INFO - PROCESS | 1652 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 06:01:11 INFO - PROCESS | 1652 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 06:01:11 INFO - PROCESS | 1652 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 06:01:11 INFO - PROCESS | 1652 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 06:01:11 INFO - PROCESS | 1652 | TaskImpl@resource://gre/modules/Task.jsm:277:3 06:01:11 INFO - PROCESS | 1652 | asyncFunction@resource://gre/modules/Task.jsm:252:14 06:01:11 INFO - PROCESS | 1652 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 06:01:11 INFO - PROCESS | 1652 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 06:01:11 INFO - PROCESS | 1652 | TaskImpl@resource://gre/modules/Task.jsm:277:3 06:01:11 INFO - PROCESS | 1652 | asyncFunction@resource://gre/modules/Task.jsm:252:14 06:01:11 INFO - PROCESS | 1652 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 06:01:11 INFO - PROCESS | 1652 | 06:01:11 INFO - PROCESS | 1652 | ************************* 06:02:28 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-ext.html (next: http://localhost:49244/tests/dromaeo/cssquery-jquery.html) 06:02:29 INFO - PROCESS | 1652 | RSS: Main: 260448256 06:02:29 INFO - PROCESS | 1652 | 06:05:31 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49244/tests/dromaeo/cssquery-mootools.html) 06:05:31 INFO - PROCESS | 1652 | RSS: Main: 269819904 06:05:31 INFO - PROCESS | 1652 | 06:08:33 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49244/tests/dromaeo/cssquery-prototype.html) 06:08:34 INFO - PROCESS | 1652 | RSS: Main: 267681792 06:08:34 INFO - PROCESS | 1652 | 06:11:37 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49244/tests/dromaeo/cssquery-yui.html) 06:11:37 INFO - PROCESS | 1652 | RSS: Main: 272310272 06:11:37 INFO - PROCESS | 1652 | 06:14:40 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-yui.html (next: http://localhost:49244/tests/dromaeo/cssquery-dojo.html) 06:14:40 INFO - PROCESS | 1652 | RSS: Main: 274530304 06:14:40 INFO - PROCESS | 1652 | 06:14:40 INFO - PROCESS | 1652 | __start_tp_report 06:14:40 INFO - PROCESS | 1652 | _x_x_mozilla_page_load 06:14:40 INFO - PROCESS | 1652 | _x_x_mozilla_page_load_details 06:14:40 INFO - PROCESS | 1652 | |i|pagename|runs| 06:14:40 INFO - PROCESS | 1652 | |0;dojo.html;2794.7624451921574;3320.5683261176046;3323.6344002159744;3356.1945133168047;3367.393869103561;5426.782928682858;5467.261919640848;5484.862878428032;5498.546177596145;5505.339359276887;5438.8912221755545;5506.91739623906;5520.199571062203;5521.282233309664;5535.778568857241;5011.32347133137;5043.193089105744;5093.592512598983;5101.387833459971;5135.948640513603;5112.795488180468;5128.948710512903;5157.974210128944;5163.1480805667015;5169.870753231162;8.128822056789712;5143.665661731976;5167.844964651048;5197.974010129944;5227.32044834171;4447.977760111211;5050.797968081272;5106.795728170869;5125.769340379692;5136.871578210537;5037.244413337992;5067.746612669352;5071.264666623319;5084.694918304914;5096.592272618182;5417.349918009864;5418.8916221675345;5528.170774383834;5546.084895992157;5586.664800112007;5963;6033.788817391414;6082.695865206766;6107.969460152715;6169.660668663222;6195.690215489252;6199.907001394983;6202.34875338092;6205.441510264098;6217.378262173792;5703.77184912603;5741.966446039712;5769.5672824537905;5773.624714393551;5792.710364481759;6126.36500017443;6131.437835141539;6168.982117950488;6186.783462578788;6213.2233470816145;6020.789272375446;6032.276126864805;6047.909281360783;6062.939370606237;6091.634501929899;6118.174046503754;6130.049842274455;6140.6929653517145;6159.260888693386;6166.229221347332;4755.881102972384;5011.1481048221885;5852.531797456193;5943.881122377528;6101.176341193971;4859.684120532154;6067.1202675611785;6086.147939288503;6148.9692551536955;6166.044263139221;4739.573438390561;4849.878753031132;4860.4896485869185;4861.829835955725;4867.34290870735;4318.352247162888;4399.208142534375;4424.889377765581;4467.955320446754;4496.820127194876;5550.4449555044175;5609.8597535061135;5718.7426565804635;5788.102844059177;5829.562782791223;5331.5201631853315;6063.484603808637;6127.189528148541;6136.7948077883175;6195.31851496335;5741.741621627037;5768.278965129359;5780.046292361797;5792.102224155263;5822.912656310159;4784.521547845187;5338.439463856317;5350.30446042012;5366.248725178478;5377.650452720561;6097.359777223416;6104.368208816009;6128.28924408451;6196.442320191204;6210.347913469111;6126.908096378469;6130.47890929276;6138.508919286357;6194.5044396449075;6200.937990620217;6118.785842495491;6181.072839074175;6203.193584833943;6203.2556093269095;6236.906446403217;5073.34046573943;5905.40945905406;5986.521078313637;6089.056196289582;6105.175801266772;6107.219158344477;6108.633481991094;6119.418655227746;6122.9471041527795;6130.601510901777;5968.70156492165;5985.581009329305;6071.453569178795;6084.726187321669;6090.056041313426;6005.3694362091355;6019.939800601938;6020.4280593343565;6041.577089603686;6046.1535385045245;5470.343558772973;5696.772129114789;5849.883002340014;5865.472107510344;5871.794487192928;5941.970290148522;5952.642841429528;5977.581569290108;6033.42682445167;6046.969765151146;5107.846764597068;5377.059014672339;5806.158107074535;5812.099124635688;5836.9416305838095 06:14:40 INFO - PROCESS | 1652 | |1;ext.html;491.23858020068894;515.8736109653134;524.2162966365282;534.126702840855;536.7048123532059;38183.045423864416;41297.17405651885;41335;41353.172936541254;41397.551085712046;42128.36807447883;42540.574594254045;42604.14791704164;42604.57395426045;42649.57350426495;8441.788955276137;8453.619587118563;9275.304352173596;11293.378864162467;11624.186306958516;9675.467849268287;11263.15526335524;11365.943170284138;11440.656780296604;11479;11308.547658093708;11509.827352589718;11534.826977595343;11535;11542.480588373543;11466.369349685763;11470.254433461798;11483.827742583868;11495.655130346062;11525.077993760478;11366.772664546663;11476.254043487146;11481.13891458144;11493.252938558968;11494.540218391254;41565;41693.16613667709;41698;41759.7912010438;41853.372199417034;4043.858464953742;4061;4061.6141466560925;4157.272477316487;4227.239096962546;30567.69432305671;31178.844105779324;31384.686153138406;31445.37109257802;31688.36623267545;2879.9424011519864;2944.131481213047;3130.812151270931;3260.5272235525954;3277.4919887417486;447251.99999999674;448964;450315;453776;454621.72689136345;62378;62762.686186569685;62801.68599156975;62805.05792413117;62835.6858215706;68144.65927670429;68182.65908670425;68549.2297923609;68739.96890046653;68787.31212687909;14630.341634626468;16048.197590120448;16611.335546578;16620.418285360196;16648.08435536057;11379.601713940137;12350.567730129402;12637.557685481148;12668.746625067448;12854.871451285555;19323.4202973911;19339;19347.613047739247;19356.90321548383;19363;6830.521863469509;7256.201817800038;7530.736424225126;7629.389648828081;7695.23047695226;880.3925291548811;884.7964968057382;898.6944438890808;911.8768966189613;924.0620769918538;30166.84916575403;30202.69797301999;30266.092017239516;30546.69453305483;30745.538816917764;1054.8892366301427;1057.1701214546545;1090.7491277006175;1104.7569534702352;1215.2101134262623;13457.327133643474;15916.363345466283;16161.272742726605;16161.676766464605;16345.34618615266;517978.33140845725;578023;583896;589949;590963;52576.73711631494;61584.38415615876;61839.6908015466;61913.38086619076;62004.069938950954;1428.5928510374702;1454.403694485256;1463.2537405922842;1471.257015207335;1471.6173794813426;28834.855825720737;29420.85289573624;29966.25084372908;30320.894221912244;30551.2362190947;43796.34305485484;53526.73236633948;53751.193732094835;53794.462055380514;53873.461265386846;507029;511324;513287;513645;514638;28307.292317692223;33650.15874603057;36038.639613603526;41847.37228941507;43003.56996429995;47980.76009620069;48447.51552484571;48622.75688621676;49108;49341.25988110253;39888.2022359557;39937.600623993385;40314.39528407135;40879.97800054903;41113.58886411098;39643.2071358577;40478.392824108254;40495.987600310225;41102;41237.79381103075;37763.43354849734;38464.80767596256;38542.80728596339;38675.8397248083;38810.80594597009;32797.18007049843;33545.78765565318;34780.478292826134;34929.30141397107;35123.12192195216;22894.885525572932;23400.71296078664;25259;25543.23370298894;26141.215763527125 06:14:40 INFO - PROCESS | 1652 | |2;jquery.html;332.29387551453163;386.35478750486686;395.53327074052623;401.7288330376995;413.9939945931387;19909.303174388882;21430.89284553579;21504.677429838524;21813;21878.562428751415;20994.26520071798;21378.57242855142;21517.67723484149;21569.892150539225;21768.346949591538;5669.659820410779;5775.335836378828;5905.970470147643;5915.201447804534;5937.1984782054515;4163.50037995441;4361.934570981438;5847.678377689225;5973.790917317895;5976.3724808895095;6493.70778314977;6598.571092878948;6619.172603424572;6644.534882558223;6646.867062658744;6366.93633063668;6555.34446555343;6598.208215014229;6614.603123812539;6623.304553021935;5597.328320601554;5762.538996880239;5799.5650326225705;5813.389594092601;5816.505597024258;22900.31299061031;25785.484290314278;25996;26132.477350452886;26304;2759;2783;2786.3730660601404;2787.4982503149427;2795.566687163493;14571.489997850025;14979.700405991927;15054.247287635575;15543.455979040793;15669.13819739914;1794.8115447878047;1843.7234414837746;1849.8612604054615;1853.8331550160551;1868.6542989546977;127545.72454275431;127587.36206319001;127828.36085819604;129537.35231323783;130448.99999999905;10955.671329860117;11440.084793216523;11501.42492875353;11565.76868462626;11604.477798499087;14540.709185816224;14558.63603409902;14579.489717859824;14593.270336483134;14619;8482.745517634481;8728.519931403707;8744.125587441205;8862.512561809033;8894.688685896092;8629;8663.436876603;8677.305815534743;8791.120887911286;8853.778655533535;7085.751998680022;7154.284571542804;7173.094896566899;7208.2791720828545;7236.782896513113;1648.7881407165723;1680.2606852984648;1958.5397431603562;2002.2291417803958;2002.2391491233238;783.7962129846281;893.5621545442816;911.7583840282331;920.1166879795337;922.2437601166979;14400.927995359956;21218.363449096243;21304.04131814072;21331;21369;795.693657941685;838.455004247232;917.875119099283;925.5011469033636;931.9394239374419;10828.296160749525;11132.998030177323;11158.721031974266;11164.609238676607;11307.674634648272;650066;668514;708956;715227;718743;131446.68553314536;136305;140935;141594.2920285392;141634;1012.1245122968579;1013.0932815130417;1036.4506811389974;1039.54260125543;1042.4318746283293;17305.65388692194;18220.271189152285;18872.150753216414;18878.52803679919;18917.90541047286;51148.97702046013;55202.44797552134;55889.72055139698;56166.43833561612;57269.140962884754;54127.72936135294;76314;80262;82908.58545707233;93889;8150.103488616366;8215.835683286181;8254.257116859511;8610.483370997761;8686.218240358397;10243.7439064024;12103.515859365527;12558.686032849251;13319.733605327645;13501.662458438224;11331.716707082389;11374.488148033524;11564.710882227675;11830.994365478686;11857.466414011562;5322.627416080837;9598.616055357708;9943.602255909682;11267.605633802777;11535.884641153481;11384.089272857986;11575.594854180063;11817.586384476846;11912.702182445508;11959.940200298943;8371.33029357662;8429.072801991899;8513.787155321166;8526.48841069513;8553.486790792573;6753;10931.945340273247;10996.655651155497;11118;11196.198045108316 06:14:40 INFO - PROCESS | 1652 | |3;mootools.html;1430.713857228554;1432.1478720161513;1441.0201063276968;1459.24848702918;1467.8311994120677;5256.132738098218;6706.597604143749;6973.884178531436;7409.81475463113;7812.765617031491;6741.191057073146;7095.503314767969;7205.1714052883935;7421.703131874732;7697.9615101924555;1958.3733205374285;2131.7103152592613;2174.141214220386;2209.8895055247253;2221.711177546925;1856.3502774029116;1965.508622844289;2177.4991751897073;2195.868247905131;2216.26863135165;1983.652860749363;2209.657503087024;2212.4358288636377;2220.844540882139;2221.0116498158327;2175.7171567696178;2178.8801615911198;2184.0826852721893;2186.464316242531;2211.9889400552975;2176.1513009926143;2178.6949827024227;2235.2176738141684;2245.9101635934544;2262.7850354216325;7706;7748.922510774876;7780.02749656293;7788.610569471503;7804.141544430108;4685.2269375553;4823.686460380064;4828.61371090312;4830.347903033081;4861.246506791453;3173.841307934594;3176.809391436498;3233.417984762742;3235.7896736712037;3280.1963518937773;1164.5109054197258;1212.5149940023973;1212.727136394313;1216;1660.8173100958884;9413.952930235306;9582;9716.75708107296;9736.902630973671;9751.853722194173;3894.1432884765304;4078.0008897820226;4113.609207125318;4124.443200167999;4143.08852052548;7885.093214280317;7976.122626511079;7984.9201507985335;7985.720499782596;8000.119986801447;4720.457147428021;4722.952770472251;4727.338172655831;4731.71609703412;4741.905161896742;4267.743935363826;4359.825606975685;4360.847370342023;4363.52001279859;4426.136903303889;3378.989501246754;3381.3744457275484;3409.7954122752712;3435.381631306389;3465.0471120441575;895.4135041547796;973.0658567775015;1004.7990401919645;1049.7690508088208;1058.6136060337922;665.2781731821028;736.8452624948713;772.3396495995843;773.9690412383442;836.2724429746038;3988.3818008208195;4941.25881117828;5281.841544753664;5351.919721204185;5578.219049333096;888.5601627194451;925.4493576322078;970.1317320997667;972.8492083727034;1010.4897027001464;3458.4985177149674;3513;3575.3206890690685;3674.2835147146584;3703.4444833274683;6880.827979300558;7337.669804858688;8455.35120651467;8643.265322447536;8704.78238044054;8715.651373945098;8724.302055835646;8768.473891566526;8796.560171991376;8851.513166775889;6868.862622747517;7194;7250.057492525938;7281.417486601059;7285.125784905846;5189.792408303626;5358.383785864676;5396.811111611075;5425.457454254543;5465.918011229915;8214.794630134294;8224.054233763194;8258.834823303621;8266.917330826855;8520.403571749917;8220;8416.116307787594;8426.115257897833;8491.448055876348;8567.700130495652;6637.402633762985;6725.764598239038;6765.695543700644;6777.6272305023695;6783.321667833177;7180.138383394026;7211.639418028973;7285.963570182115;7291.562506249642;7310.086239220098;6604.075429439787;6629.370209830058;6690.0633911253435;6710.496712746466;6730.629815360006;6365.554411191358;6542.705578248895;6580.045893345534;6584.967075164594;6644.534882558174;5993.490553302844;6102.847428814138;6132.816015519542;6141.84645383869;6164.537659675452;4367.737935723867;4687.5781179693995;4785.377900872864;4786.449558300701;4832.806687732592;4666.883327916829;4701.294805779161;4765.380500535047;4818.229083346788;4943.332650092191 06:14:40 INFO - PROCESS | 1652 | |4;prototype.html;356.1541339319117;361.9529461170047;364.66450865204007;366.3424153644209;370.36852167055156;4898.436679781827;5469.808556700516;5474.644148130379;5481.972590137054;5507.917381239279;5213.635045546814;5426.403095659484;5428.864278393042;5428.864278393042;5446.972765136179;3172.1918506451334;3258.7393008559256;4065.5934406559404;4099.836006559749;4116.982678487535;3592.7305452091196;3916.2167566486814;3934.449177115206;4083.509978802533;4094.160697057111;3851.2490064437447;4045.231406032859;4061.4720086388875;4090.713650044498;4095.9590404095875;3600.31593997141;3826.2921359548573;4089.9386509202386;4094.303968325392;4115.8887100483;3501.299740052;3506.7019303359243;3513.7364697647777;3517.015235733973;3531.4526248431534;4649.8372556960685;5022.321986531844;5242.475752424765;5256.478159633055;5267.525092973935;3802.8478860845535;4678.391809064834;4685.625149987993;4686.718796872198;4690.671652984292;5015.648904576681;5631.831045068655;5657.264555607745;5708.229389032469;5745.597808153431;3595.8065579996182;3902.551206611248;3911.6870650347905;3945.309570825094;3964.781936993464;5267.789288428497;5769.480746732815;5936.554758393095;6054.909176362358;6063.575549711478;3888.755598208546;4033.4756481657782;4093.8771836844944;4288.614024737789;4301.462317210348;4355.107203023373;4357.975875669215;4388.893331201041;4390.297552391602;4407.2507673695545;4636.4436267647425;4919.950800492021;4937.703737775745;4957.801687932514;4965.280034395065;4536.368906034492;4956.249281648719;5025.497450254945;5784.1034639630925;5847.853803654943;1189.8334233207358;1239.522783728253;1243.6517775023008;1431.9427222911174;1432.8996970212193;801.8396320735876;929.6327950459531;936.6721647423484;938.202527851321;982.4989255479613;4635.629149668019;4675.532446755297;4902.901941961212;5107.778116519326;5110.66780659256;4722.126406614787;5381.246625472437;5694.515966142924;5709.3434255060365;5709.8287051388525;4615.723056616614;4792.808287668454;4924.60603151747;5114.721319670082;5128.999845030258;2949.2626843289177;2964.881404743786;2985.671376237597;2996.4306781711407;3008.2629755709554;4951.133551628451;5860.560457965585;5895.882082358329;5896.439838215363;5897.67562784051;5068.997745890352;5073.568746656576;5452.100403433389;5790.944214803684;5886.056342250702;4592.058627981256;5381.13901775714;5395.487428694346;5928.7767087342245;6102.237220347457;4832.511256702731;5264.815731449381;5853.414658534283;5873.471387575139;5954.940450595439;5145.4340022598235;5443.101888188563;5785.4214578541805;5789.9710501447225;5814.5639077068545;4901.3090483783035;5181.176258938812;5734.455226753452;6106.0690868382535;6167.691615419121;3905.8282515245883;3944.684425245916;3969.5633480316565;3970.7220494565104;3972.463717398114;4468.776561171993;5850.912236316543;5884.47039766423;5968.70156492165;5987.970060149672;4864.294677271847;4870.683405578626;5186.6369354144845;5759.308882934075;5807.477327040587;4819.662623616454;5719.714014299351;5733.369329373682;5761.827145185651;5782.074868021096;5324.680519168863;5396.406395296593;5462.262594549685;5610.43895610452;5646.520045795989;4916.508349165055;5705.828825135252;5761.279840019997;5764.769409223752;5772.3650398456975;5055.6713813602;5332.440093790251;5405.189221616789;5616.7191640416995;5645.786155976485 06:14:40 INFO - PROCESS | 1652 | |5;yui.html;700.4816435837475;707.3315716647767;767.6468824340803;788.4402074527086;853.7182729699198;17012.234449449774;17719.202635881386;17732.795945649337;17938.28246870127;17999.730004049932;16554.923929944543;16911.492655220332;16950.576235594115;16977.49067527973;16988.980661160338;735.9659678152204;838.5262326785347;847.5423271433434;874.1477059866643;880.6345366672823;514.8493117881532;669.0466085827676;763.1414658509177;848.0417128644654;873.05273777951;915.8992510823804;919.8528235482324;920.1994264989469;920.2270093121776;921.0007142250632;913.3880300198882;913.7624217703411;920.5259291464833;920.5305294299922;920.9961142354865;683.8666460040295;686.7767975408012;686.8900975843841;688.3357559954668;689.2005273882322;13326.333683315892;15261.160636165007;15287.312070956834;15731.134787586678;15938.203089845461;12124.636260912099;13853.722925541533;13903.79144312836;14128.576142715632;14151.504697335648;11369.886301136965;11649.650510484698;11676.941615291955;11930.04559635227;11992.460339284753;3691.8892433227047;3721.218544105741;3742.2889650966504;3752.8498860045565;3877.379619260933;104367;105408.47295763549;105610.94389056088;106298;106813.46593267062;10565.154787616972;10625.25623206368;10635.042846143884;10884.72788180286;10935.070519005814;21634.675479867816;22027;22577.4355641107;22654.66018009731;22672.319830404784;6773.762918297836;7647.961760191163;7726.7681969541;7741.45809793309;7916.643751031217;4302;4324.654027677778;4325.610695037399;4326.3510473428605;4327.32926396409;3516.367053930266;3553.502509648651;3562.4834399011997;3602.9819850900576;3610.187707765732;603.8520562462224;608.9360617135226;609.1015751766198;613.3988691082675;617.1144407774857;670.2325836916704;686.1731613405834;701.8771714949863;707.8831992721143;711.6157275071507;10287.331323463952;10426.957304269665;10428.895711042944;10445.216608754374;10586.20603454744;683.8905775075964;704.503325155764;708.7519368221184;712.9108861392326;718.4791026505742;4795.280707893844;5038.294638750578;5048.1418158913075;5048.570871475892;5058.822941197041;103639;104429;104845.95154048514;106114.4082838758;109207.36188957172;76209.23790762022;94787;97389;97896;98314;81780;81826.59086704528;82905.58547207346;83850.58074709587;83905;9754.804903902024;9814.362066465657;9814.65648702292;9826.263030272616;9838.409695418297;44887;47229.45779066652;48382;48899;49065.773355666395;49185;63653.363466364746;64327.03509447456;64631;65566;11658.41707914584;11674.241174323643;11774.52901883915;11923.344216068199;11979.28124312544;11766.941165294118;11922.225055371373;11963.76072478563;11986.46060927243;12043.578474753342;11802.232854864405;11894.524219031142;11958;12077.27536347822;12473.189242699196;11606.825897611712;11615.012723918224;11646;12030.338331391857;12123.575674851338;10544.789104218025;11446.198766086294;12620;12819.487220511075;12820.551280705131;11084.889151108708;11191.552337906392;11535.192536522363;11744.177907546731;11748.353840538852;10126.341787783771;10814.675559733221;10833.561082262851;10936.507857146607;11031.440703760669 06:14:40 INFO - PROCESS | 1652 | __end_tp_report 06:14:40 INFO - PROCESS | 1652 | __start_cc_report 06:14:40 INFO - PROCESS | 1652 | _x_x_mozilla_cycle_collect,376 06:14:40 INFO - PROCESS | 1652 | __end_cc_report 06:14:40 INFO - PROCESS | 1652 | __startTimestamp1484316880911__endTimestamp 06:14:40 INFO - PROCESS | 1652 | 06:14:40 INFO - PROCESS | 1652 | ------- Summary: start ------- 06:14:40 INFO - PROCESS | 1652 | Number of tests: 6 06:14:40 INFO - PROCESS | 1652 | 06:14:40 INFO - PROCESS | 1652 | [#0] dojo.html Cycles:165 Average:5567.05 Median:5812.10 stddev:778.74 (13.4%) stddev-sans-first:750.14 06:14:40 INFO - PROCESS | 1652 | Values: 2794.8 3320.6 3323.6 3356.2 3367.4 5426.8 5467.3 5484.9 5498.5 5505.3 5438.9 5506.9 5520.2 5521.3 5535.8 5011.3 5043.2 5093.6 5101.4 5135.9 5112.8 5128.9 5158.0 5163.1 5169.9 8.1 5143.7 5167.8 5198.0 5227.3 4448.0 5050.8 5106.8 5125.8 5136.9 5037.2 5067.7 5071.3 5084.7 5096.6 5417.3 5418.9 5528.2 5546.1 5586.7 5963.0 6033.8 6082.7 6108.0 6169.7 6195.7 6199.9 6202.3 6205.4 6217.4 5703.8 5742.0 5769.6 5773.6 5792.7 6126.4 6131.4 6169.0 6186.8 6213.2 6020.8 6032.3 6047.9 6062.9 6091.6 6118.2 6130.0 6140.7 6159.3 6166.2 4755.9 5011.1 5852.5 5943.9 6101.2 4859.7 6067.1 6086.1 6149.0 6166.0 4739.6 4849.9 4860.5 4861.8 4867.3 4318.4 4399.2 4424.9 4468.0 4496.8 5550.4 5609.9 5718.7 5788.1 5829.6 5331.5 6063.5 6127.2 6136.8 6195.3 5741.7 5768.3 5780.0 5792.1 5822.9 4784.5 5338.4 5350.3 5366.2 5377.7 6097.4 6104.4 6128.3 6196.4 6210.3 6126.9 6130.5 6138.5 6194.5 6200.9 6118.8 6181.1 6203.2 6203.3 6236.9 5073.3 5905.4 5986.5 6089.1 6105.2 6107.2 6108.6 6119.4 6122.9 6130.6 5968.7 5985.6 6071.5 6084.7 6090.1 6005.4 6019.9 6020.4 6041.6 6046.2 5470.3 5696.8 5849.9 5865.5 5871.8 5942.0 5952.6 5977.6 6033.4 6047.0 5107.8 5377.1 5806.2 5812.1 5836.9 06:14:40 INFO - PROCESS | 1652 | 06:14:40 INFO - PROCESS | 1652 | [#1] ext.html Cycles:180 Average:66538.82 Median:39765.70 stddev:136722.40 (343.8%) stddev-sans-first:137016.01 06:14:40 INFO - PROCESS | 1652 | Values: 491.2 515.9 524.2 534.1 536.7 38183.0 41297.2 41335.0 41353.2 41397.6 42128.4 42540.6 42604.1 42604.6 42649.6 8441.8 8453.6 9275.3 11293.4 11624.2 9675.5 11263.2 11365.9 11440.7 11479.0 11308.5 11509.8 11534.8 11535.0 11542.5 11466.4 11470.3 11483.8 11495.7 11525.1 11366.8 11476.3 11481.1 11493.3 11494.5 41565.0 41693.2 41698.0 41759.8 41853.4 4043.9 4061.0 4061.6 4157.3 4227.2 30567.7 31178.8 31384.7 31445.4 31688.4 2879.9 2944.1 3130.8 3260.5 3277.5 447252.0 448964.0 450315.0 453776.0 454621.7 62378.0 62762.7 62801.7 62805.1 62835.7 68144.7 68182.7 68549.2 68740.0 68787.3 14630.3 16048.2 16611.3 16620.4 16648.1 11379.6 12350.6 12637.6 12668.7 12854.9 19323.4 19339.0 19347.6 19356.9 19363.0 6830.5 7256.2 7530.7 7629.4 7695.2 880.4 884.8 898.7 911.9 924.1 30166.8 30202.7 30266.1 30546.7 30745.5 1054.9 1057.2 1090.7 1104.8 1215.2 13457.3 15916.4 16161.3 16161.7 16345.3 517978.3 578023.0 583896.0 589949.0 590963.0 52576.7 61584.4 61839.7 61913.4 62004.1 1428.6 1454.4 1463.3 1471.3 1471.6 28834.9 29420.9 29966.3 30320.9 30551.2 43796.3 53526.7 53751.2 53794.5 53873.5 507029.0 511324.0 513287.0 513645.0 514638.0 28307.3 33650.2 36038.6 41847.4 43003.6 47980.8 48447.5 48622.8 49108.0 49341.3 39888.2 39937.6 40314.4 40880.0 41113.6 39643.2 40478.4 40496.0 41102.0 41237.8 37763.4 38464.8 38542.8 38675.8 38810.8 32797.2 33545.8 34780.5 34929.3 35123.1 22894.9 23400.7 25259.0 25543.2 26141.2 06:14:40 INFO - PROCESS | 1652 | 06:14:40 INFO - PROCESS | 1652 | [#2] jquery.html Cycles:180 Average:38536.22 Median:14531.50 stddev:115271.38 (793.3%) stddev-sans-first:115559.05 06:14:40 INFO - PROCESS | 1652 | Values: 332.3 386.4 395.5 401.7 414.0 19909.3 21430.9 21504.7 21813.0 21878.6 20994.3 21378.6 21517.7 21569.9 21768.3 5669.7 5775.3 5906.0 5915.2 5937.2 4163.5 4361.9 5847.7 5973.8 5976.4 6493.7 6598.6 6619.2 6644.5 6646.9 6366.9 6555.3 6598.2 6614.6 6623.3 5597.3 5762.5 5799.6 5813.4 5816.5 22900.3 25785.5 25996.0 26132.5 26304.0 2759.0 2783.0 2786.4 2787.5 2795.6 14571.5 14979.7 15054.2 15543.5 15669.1 1794.8 1843.7 1849.9 1853.8 1868.7 127545.7 127587.4 127828.4 129537.4 130449.0 10955.7 11440.1 11501.4 11565.8 11604.5 14540.7 14558.6 14579.5 14593.3 14619.0 8482.7 8728.5 8744.1 8862.5 8894.7 8629.0 8663.4 8677.3 8791.1 8853.8 7085.8 7154.3 7173.1 7208.3 7236.8 1648.8 1680.3 1958.5 2002.2 2002.2 783.8 893.6 911.8 920.1 922.2 14400.9 21218.4 21304.0 21331.0 21369.0 795.7 838.5 917.9 925.5 931.9 10828.3 11133.0 11158.7 11164.6 11307.7 650066.0 668514.0 708956.0 715227.0 718743.0 131446.7 136305.0 140935.0 141594.3 141634.0 1012.1 1013.1 1036.5 1039.5 1042.4 17305.7 18220.3 18872.2 18878.5 18917.9 51149.0 55202.4 55889.7 56166.4 57269.1 54127.7 76314.0 80262.0 82908.6 93889.0 8150.1 8215.8 8254.3 8610.5 8686.2 10243.7 12103.5 12558.7 13319.7 13501.7 11331.7 11374.5 11564.7 11831.0 11857.5 5322.6 9598.6 9943.6 11267.6 11535.9 11384.1 11575.6 11817.6 11912.7 11959.9 8371.3 8429.1 8513.8 8526.5 8553.5 6753.0 10931.9 10996.7 11118.0 11196.2 06:14:40 INFO - PROCESS | 1652 | 06:14:40 INFO - PROCESS | 1652 | [#3] mootools.html Cycles:180 Average:4936.60 Median:5377.60 stddev:2587.79 (48.1%) stddev-sans-first:2581.63 06:14:40 INFO - PROCESS | 1652 | Values: 1430.7 1432.1 1441.0 1459.2 1467.8 5256.1 6706.6 6973.9 7409.8 7812.8 6741.2 7095.5 7205.2 7421.7 7698.0 1958.4 2131.7 2174.1 2209.9 2221.7 1856.4 1965.5 2177.5 2195.9 2216.3 1983.7 2209.7 2212.4 2220.8 2221.0 2175.7 2178.9 2184.1 2186.5 2212.0 2176.2 2178.7 2235.2 2245.9 2262.8 7706.0 7748.9 7780.0 7788.6 7804.1 4685.2 4823.7 4828.6 4830.3 4861.2 3173.8 3176.8 3233.4 3235.8 3280.2 1164.5 1212.5 1212.7 1216.0 1660.8 9414.0 9582.0 9716.8 9736.9 9751.9 3894.1 4078.0 4113.6 4124.4 4143.1 7885.1 7976.1 7984.9 7985.7 8000.1 4720.5 4723.0 4727.3 4731.7 4741.9 4267.7 4359.8 4360.8 4363.5 4426.1 3379.0 3381.4 3409.8 3435.4 3465.0 895.4 973.1 1004.8 1049.8 1058.6 665.3 736.8 772.3 774.0 836.3 3988.4 4941.3 5281.8 5351.9 5578.2 888.6 925.4 970.1 972.8 1010.5 3458.5 3513.0 3575.3 3674.3 3703.4 6880.8 7337.7 8455.4 8643.3 8704.8 8715.7 8724.3 8768.5 8796.6 8851.5 6868.9 7194.0 7250.1 7281.4 7285.1 5189.8 5358.4 5396.8 5425.5 5465.9 8214.8 8224.1 8258.8 8266.9 8520.4 8220.0 8416.1 8426.1 8491.4 8567.7 6637.4 6725.8 6765.7 6777.6 6783.3 7180.1 7211.6 7286.0 7291.6 7310.1 6604.1 6629.4 6690.1 6710.5 6730.6 6365.6 6542.7 6580.0 6585.0 6644.5 5993.5 6102.8 6132.8 6141.8 6164.5 4367.7 4687.6 4785.4 4786.4 4832.8 4666.9 4701.3 4765.4 4818.2 4943.3 06:14:40 INFO - PROCESS | 1652 | 06:14:40 INFO - PROCESS | 1652 | [#4] prototype.html Cycles:180 Average:4569.58 Median:5018.99 stddev:1379.91 (27.5%) stddev-sans-first:1347.05 06:14:40 INFO - PROCESS | 1652 | Values: 356.2 362.0 364.7 366.3 370.4 4898.4 5469.8 5474.6 5482.0 5507.9 5213.6 5426.4 5428.9 5428.9 5447.0 3172.2 3258.7 4065.6 4099.8 4117.0 3592.7 3916.2 3934.4 4083.5 4094.2 3851.2 4045.2 4061.5 4090.7 4096.0 3600.3 3826.3 4089.9 4094.3 4115.9 3501.3 3506.7 3513.7 3517.0 3531.5 4649.8 5022.3 5242.5 5256.5 5267.5 3802.8 4678.4 4685.6 4686.7 4690.7 5015.6 5631.8 5657.3 5708.2 5745.6 3595.8 3902.6 3911.7 3945.3 3964.8 5267.8 5769.5 5936.6 6054.9 6063.6 3888.8 4033.5 4093.9 4288.6 4301.5 4355.1 4358.0 4388.9 4390.3 4407.3 4636.4 4920.0 4937.7 4957.8 4965.3 4536.4 4956.2 5025.5 5784.1 5847.9 1189.8 1239.5 1243.7 1431.9 1432.9 801.8 929.6 936.7 938.2 982.5 4635.6 4675.5 4902.9 5107.8 5110.7 4722.1 5381.2 5694.5 5709.3 5709.8 4615.7 4792.8 4924.6 5114.7 5129.0 2949.3 2964.9 2985.7 2996.4 3008.3 4951.1 5860.6 5895.9 5896.4 5897.7 5069.0 5073.6 5452.1 5790.9 5886.1 4592.1 5381.1 5395.5 5928.8 6102.2 4832.5 5264.8 5853.4 5873.5 5954.9 5145.4 5443.1 5785.4 5790.0 5814.6 4901.3 5181.2 5734.5 6106.1 6167.7 3905.8 3944.7 3969.6 3970.7 3972.5 4468.8 5850.9 5884.5 5968.7 5988.0 4864.3 4870.7 5186.6 5759.3 5807.5 4819.7 5719.7 5733.4 5761.8 5782.1 5324.7 5396.4 5462.3 5610.4 5646.5 4916.5 5705.8 5761.3 5764.8 5772.4 5055.7 5332.4 5405.2 5616.7 5645.8 06:14:40 INFO - PROCESS | 1652 | 06:14:40 INFO - PROCESS | 1652 | [#5] yui.html Cycles:180 Average:20486.45 Median:3706.55 stddev:29876.61 (806.0%) stddev-sans-first:29923.48 06:14:40 INFO - PROCESS | 1652 | Values: 700.5 707.3 767.6 788.4 853.7 17012.2 17719.2 17732.8 17938.3 17999.7 16554.9 16911.5 16950.6 16977.5 16989.0 736.0 838.5 847.5 874.1 880.6 514.8 669.0 763.1 848.0 873.1 915.9 919.9 920.2 920.2 921.0 913.4 913.8 920.5 920.5 921.0 683.9 686.8 686.9 688.3 689.2 13326.3 15261.2 15287.3 15731.1 15938.2 12124.6 13853.7 13903.8 14128.6 14151.5 11369.9 11649.7 11676.9 11930.0 11992.5 3691.9 3721.2 3742.3 3752.8 3877.4 104367.0 105408.5 105610.9 106298.0 106813.5 10565.2 10625.3 10635.0 10884.7 10935.1 21634.7 22027.0 22577.4 22654.7 22672.3 6773.8 7648.0 7726.8 7741.5 7916.6 4302.0 4324.7 4325.6 4326.4 4327.3 3516.4 3553.5 3562.5 3603.0 3610.2 603.9 608.9 609.1 613.4 617.1 670.2 686.2 701.9 707.9 711.6 10287.3 10427.0 10428.9 10445.2 10586.2 683.9 704.5 708.8 712.9 718.5 4795.3 5038.3 5048.1 5048.6 5058.8 103639.0 104429.0 104846.0 106114.4 109207.4 76209.2 94787.0 97389.0 97896.0 98314.0 81780.0 81826.6 82905.6 83850.6 83905.0 9754.8 9814.4 9814.7 9826.3 9838.4 44887.0 47229.5 48382.0 48899.0 49065.8 49185.0 63653.4 64327.0 64631.0 65566.0 11658.4 11674.2 11774.5 11923.3 11979.3 11766.9 11922.2 11963.8 11986.5 12043.6 11802.2 11894.5 11958.0 12077.3 12473.2 11606.8 11615.0 11646.0 12030.3 12123.6 10544.8 11446.2 12620.0 12819.5 12820.6 11084.9 11191.6 11535.2 11744.2 11748.4 10126.3 10814.7 10833.6 10936.5 11031.4 06:14:40 INFO - PROCESS | 1652 | -------- Summary: end -------- 06:14:40 INFO - PROCESS | 1652 | 06:14:41 INFO - TEST-INFO | 1652: exit 0 06:14:41 INFO - TEST-OK | dromaeo_css | took 1114328ms 06:14:41 INFO - TEST-START | kraken 06:14:41 INFO - operating with platform_type : mac_ 06:14:41 INFO - Initialising browser for kraken test... 06:14:41 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/tmp4PG87S/profile http://localhost:49244/getInfo.html) 06:14:44 INFO - PROCESS | 1661 | 2017-01-13 06:14:44.085 plugin-container[1662:12071] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x454f, name = 'com.apple.tsm.portname' 06:14:44 INFO - PROCESS | 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:14:44 INFO - PROCESS | 1661 | 2017-01-13 06:14:44.088 plugin-container[1662:12071] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 06:14:44 INFO - PROCESS | 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:14:44 INFO - PROCESS | 1661 | 2017-01-13 06:14:44.089 plugin-container[1662:12071] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:14:44 INFO - PROCESS | 1661 | __metrics Screen width/height:1600/1200 06:14:44 INFO - PROCESS | 1661 | colorDepth:24 06:14:44 INFO - PROCESS | 1661 | Browser inner width/height: 1024/689 06:14:44 INFO - PROCESS | 1661 | __metrics 06:14:44 ERROR - PROCESS | 1661 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3242: TypeError: this.tabs is undefined 06:14:44 INFO - TEST-INFO | 1661: exit 0 06:14:44 INFO - Browser initialized. 06:14:44 INFO - Running cycle 1/1 for kraken test... 06:14:44 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/tmp4PG87S/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:14:47 INFO - PROCESS | 1663 | 2017-01-13 06:14:47.199 plugin-container[1664:12187] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4537, name = 'com.apple.tsm.portname' 06:14:47 INFO - PROCESS | 1663 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:14:47 INFO - PROCESS | 1663 | 2017-01-13 06:14:47.200 plugin-container[1664:12187] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f03, name = 'com.apple.CFPasteboardClient' 06:14:47 INFO - PROCESS | 1663 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:14:47 INFO - PROCESS | 1663 | 2017-01-13 06:14:47.200 plugin-container[1664:12187] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:14:56 INFO - PROCESS | 1663 | RSS: Main: 233877504 06:14:56 INFO - PROCESS | 1663 | 06:14:59 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-beat-detection) 06:15:00 INFO - PROCESS | 1663 | RSS: Main: 246444032 06:15:00 INFO - PROCESS | 1663 | 06:15:03 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-dft) 06:15:04 INFO - PROCESS | 1663 | RSS: Main: 254386176 06:15:04 INFO - PROCESS | 1663 | 06:15:07 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-fft) 06:15:07 INFO - PROCESS | 1663 | RSS: Main: 257089536 06:15:07 INFO - PROCESS | 1663 | 06:15:09 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-oscillator) 06:15:10 INFO - PROCESS | 1663 | RSS: Main: 258310144 06:15:10 INFO - PROCESS | 1663 | 06:15:12 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:15:13 INFO - PROCESS | 1663 | RSS: Main: 259956736 06:15:13 INFO - PROCESS | 1663 | 06:15:16 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-darkroom) 06:15:16 INFO - PROCESS | 1663 | RSS: Main: 265211904 06:15:16 INFO - PROCESS | 1663 | 06:15:19 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-desaturate) 06:15:20 INFO - PROCESS | 1663 | RSS: Main: 264404992 06:15:20 INFO - PROCESS | 1663 | 06:15:23 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49244/tests/kraken/driver.html?testName=json-parse-financial) 06:15:24 INFO - PROCESS | 1663 | RSS: Main: 264949760 06:15:24 INFO - PROCESS | 1663 | 06:15:25 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49244/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:15:26 INFO - PROCESS | 1663 | RSS: Main: 263790592 06:15:26 INFO - PROCESS | 1663 | 06:15:28 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:15:29 INFO - PROCESS | 1663 | RSS: Main: 263868416 06:15:29 INFO - PROCESS | 1663 | 06:15:31 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:15:32 INFO - PROCESS | 1663 | RSS: Main: 263897088 06:15:32 INFO - PROCESS | 1663 | 06:15:34 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:15:35 INFO - PROCESS | 1663 | RSS: Main: 264179712 06:15:35 INFO - PROCESS | 1663 | 06:15:38 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:15:39 INFO - PROCESS | 1663 | RSS: Main: 264286208 06:15:39 INFO - PROCESS | 1663 | 06:15:41 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49244/tests/kraken/driver.html?testName=ai-astar) 06:15:41 INFO - PROCESS | 1663 | RSS: Main: 265936896 06:15:41 INFO - PROCESS | 1663 | 06:15:41 INFO - PROCESS | 1663 | __start_tp_report 06:15:41 INFO - PROCESS | 1663 | _x_x_mozilla_page_load 06:15:41 INFO - PROCESS | 1663 | _x_x_mozilla_page_load_details 06:15:41 INFO - PROCESS | 1663 | |i|pagename|runs| 06:15:41 INFO - PROCESS | 1663 | |0;ai-astar;106;119;120;119;118;119;118;120;116;120 06:15:41 INFO - PROCESS | 1663 | |1;audio-beat-detection;148;113;112;152;116;112;121;145;146;114 06:15:41 INFO - PROCESS | 1663 | |2;audio-dft;193;196;212;219;197;198;199;195;199;193 06:15:41 INFO - PROCESS | 1663 | |3;audio-fft;87;78;73;82;62;80;49;61;77;62 06:15:41 INFO - PROCESS | 1663 | |4;audio-oscillator;91;69;81;70;71;70;84;71;85;114 06:15:41 INFO - PROCESS | 1663 | |5;imaging-gaussian-blur;84;133;81;132;135;88;97;100;93;84 06:15:41 INFO - PROCESS | 1663 | |6;imaging-darkroom;136;97;96;97;97;97;97;100;151;95 06:15:41 INFO - PROCESS | 1663 | |7;imaging-desaturate;142;146;92;92;108;125;91;89;94;92 06:15:41 INFO - PROCESS | 1663 | |8;json-parse-financial;61;62;61;61;62;62;63;63;62;62 06:15:41 INFO - PROCESS | 1663 | |9;json-stringify-tinderbox;76;68;76;84;69;71;70;85;110;107 06:15:41 INFO - PROCESS | 1663 | |10;stanford-crypto-aes;102;80;114;77;90;105;110;108;110;92 06:15:41 INFO - PROCESS | 1663 | |11;stanford-crypto-ccm;140;142;146;151;154;132;141;144;200;188 06:15:41 INFO - PROCESS | 1663 | |12;stanford-crypto-pbkdf2;175;176;175;185;208;173;205;237;226;189 06:15:41 INFO - PROCESS | 1663 | |13;stanford-crypto-sha256-iterative;69;65;68;67;69;68;68;67;69;68 06:15:41 INFO - PROCESS | 1663 | __end_tp_report 06:15:41 INFO - PROCESS | 1663 | __start_cc_report 06:15:41 INFO - PROCESS | 1663 | _x_x_mozilla_cycle_collect,907 06:15:41 INFO - PROCESS | 1663 | __end_cc_report 06:15:41 INFO - PROCESS | 1663 | __startTimestamp1484316941384__endTimestamp 06:15:41 INFO - PROCESS | 1663 | 06:15:41 INFO - PROCESS | 1663 | ------- Summary: start ------- 06:15:41 INFO - PROCESS | 1663 | Number of tests: 14 06:15:41 INFO - PROCESS | 1663 | 06:15:41 INFO - PROCESS | 1663 | [#0] ai-astar Cycles:10 Average:117.50 Median:119.00 stddev:4.22 (3.5%) stddev-sans-first:1.30 06:15:41 INFO - PROCESS | 1663 | Values: 106.0 119.0 120.0 119.0 118.0 119.0 118.0 120.0 116.0 120.0 06:15:41 INFO - PROCESS | 1663 | 06:15:41 INFO - PROCESS | 1663 | [#1] audio-beat-detection Cycles:10 Average:127.90 Median:133.00 stddev:17.37 (13.1%) stddev-sans-first:16.83 06:15:41 INFO - PROCESS | 1663 | Values: 148.0 113.0 112.0 152.0 116.0 112.0 121.0 145.0 146.0 114.0 06:15:41 INFO - PROCESS | 1663 | 06:15:41 INFO - PROCESS | 1663 | [#2] audio-dft Cycles:10 Average:200.10 Median:198.50 stddev:8.56 (4.3%) stddev-sans-first:8.68 06:15:41 INFO - PROCESS | 1663 | Values: 193.0 196.0 212.0 219.0 197.0 198.0 199.0 195.0 199.0 193.0 06:15:41 INFO - PROCESS | 1663 | 06:15:41 INFO - PROCESS | 1663 | [#3] audio-fft Cycles:10 Average:71.10 Median:77.50 stddev:11.99 (15.5%) stddev-sans-first:11.25 06:15:41 INFO - PROCESS | 1663 | Values: 87.0 78.0 73.0 82.0 62.0 80.0 49.0 61.0 77.0 62.0 06:15:41 INFO - PROCESS | 1663 | 06:15:41 INFO - PROCESS | 1663 | [#4] audio-oscillator Cycles:10 Average:80.60 Median:76.00 stddev:14.14 (18.6%) stddev-sans-first:14.48 06:15:41 INFO - PROCESS | 1663 | Values: 91.0 69.0 81.0 70.0 71.0 70.0 84.0 71.0 85.0 114.0 06:15:41 INFO - PROCESS | 1663 | 06:15:41 INFO - PROCESS | 1663 | [#5] imaging-gaussian-blur Cycles:10 Average:102.70 Median:84.00 stddev:21.96 (26.1%) stddev-sans-first:22.22 06:15:41 INFO - PROCESS | 1663 | Values: 84.0 133.0 81.0 132.0 135.0 88.0 97.0 100.0 93.0 84.0 06:15:41 INFO - PROCESS | 1663 | 06:15:41 INFO - PROCESS | 1663 | [#6] imaging-darkroom Cycles:10 Average:106.30 Median:97.00 stddev:19.96 (20.6%) stddev-sans-first:18.05 06:15:41 INFO - PROCESS | 1663 | Values: 136.0 97.0 96.0 97.0 97.0 97.0 97.0 100.0 151.0 95.0 06:15:41 INFO - PROCESS | 1663 | 06:15:41 INFO - PROCESS | 1663 | [#7] imaging-desaturate Cycles:10 Average:107.10 Median:91.50 stddev:22.35 (24.4%) stddev-sans-first:19.82 06:15:41 INFO - PROCESS | 1663 | Values: 142.0 146.0 92.0 92.0 108.0 125.0 91.0 89.0 94.0 92.0 06:15:41 INFO - PROCESS | 1663 | 06:15:41 INFO - PROCESS | 1663 | [#8] json-parse-financial Cycles:10 Average:61.90 Median:62.00 stddev:0.74 (1.2%) stddev-sans-first:0.71 06:15:41 INFO - PROCESS | 1663 | Values: 61.0 62.0 61.0 61.0 62.0 62.0 63.0 63.0 62.0 62.0 06:15:41 INFO - PROCESS | 1663 | 06:15:41 INFO - PROCESS | 1663 | [#9] json-stringify-tinderbox Cycles:10 Average:81.60 Median:73.50 stddev:15.36 (20.9%) stddev-sans-first:16.15 06:15:41 INFO - PROCESS | 1663 | Values: 76.0 68.0 76.0 84.0 69.0 71.0 70.0 85.0 110.0 107.0 06:15:41 INFO - PROCESS | 1663 | 06:15:41 INFO - PROCESS | 1663 | [#10] stanford-crypto-aes Cycles:10 Average:98.80 Median:95.50 stddev:13.20 (13.8%) stddev-sans-first:13.95 06:15:41 INFO - PROCESS | 1663 | Values: 102.0 80.0 114.0 77.0 90.0 105.0 110.0 108.0 110.0 92.0 06:15:41 INFO - PROCESS | 1663 | 06:15:41 INFO - PROCESS | 1663 | [#11] stanford-crypto-ccm Cycles:10 Average:153.80 Median:148.50 stddev:22.21 (15.0%) stddev-sans-first:22.98 06:15:41 INFO - PROCESS | 1663 | Values: 140.0 142.0 146.0 151.0 154.0 132.0 141.0 144.0 200.0 188.0 06:15:41 INFO - PROCESS | 1663 | 06:15:41 INFO - PROCESS | 1663 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:194.90 Median:197.00 stddev:23.03 (11.7%) stddev-sans-first:23.28 06:15:41 INFO - PROCESS | 1663 | Values: 175.0 176.0 175.0 185.0 208.0 173.0 205.0 237.0 226.0 189.0 06:15:41 INFO - PROCESS | 1663 | 06:15:41 INFO - PROCESS | 1663 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.80 Median:68.00 stddev:1.23 (1.8%) stddev-sans-first:1.22 06:15:41 INFO - PROCESS | 1663 | Values: 69.0 65.0 68.0 67.0 69.0 68.0 68.0 67.0 69.0 68.0 06:15:41 INFO - PROCESS | 1663 | -------- Summary: end -------- 06:15:41 INFO - PROCESS | 1663 | 06:15:42 INFO - TEST-INFO | 1663: exit 0 06:15:42 INFO - TEST-OK | kraken | took 60692ms 06:15:42 INFO - SUITE-END | took 1175s 06:15:42 INFO - Completed test suite (00:19:35) 06:15:42 INFO - javascript benchmark 06:15:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7230.679144456005, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2794.7624451921574, 3320.5683261176046, 3323.6344002159744, 3356.1945133168047, 3367.393869103561, 5426.782928682858, 5467.261919640848, 5484.862878428032, 5498.546177596145, 5505.339359276887, 5438.8912221755545, 5506.91739623906, 5520.199571062203, 5521.282233309664, 5535.778568857241, 5011.32347133137, 5043.193089105744, 5093.592512598983, 5101.387833459971, 5135.948640513603, 5112.795488180468, 5128.948710512903, 5157.974210128944, 5163.1480805667015, 5169.870753231162, 8.128822056789712, 5143.665661731976, 5167.844964651048, 5197.974010129944, 5227.32044834171, 4447.977760111211, 5050.797968081272, 5106.795728170869, 5125.769340379692, 5136.871578210537, 5037.244413337992, 5067.746612669352, 5071.264666623319, 5084.694918304914, 5096.592272618182, 5417.349918009864, 5418.8916221675345, 5528.170774383834, 5546.084895992157, 5586.664800112007, 5963.0, 6033.788817391414, 6082.695865206766, 6107.969460152715, 6169.660668663222, 6195.690215489252, 6199.907001394983, 6202.34875338092, 6205.441510264098, 6217.378262173792, 5703.77184912603, 5741.966446039712, 5769.5672824537905, 5773.624714393551, 5792.710364481759, 6126.36500017443, 6131.437835141539, 6168.982117950488, 6186.783462578788, 6213.2233470816145, 6020.789272375446, 6032.276126864805, 6047.909281360783, 6062.939370606237, 6091.634501929899, 6118.174046503754, 6130.049842274455, 6140.6929653517145, 6159.260888693386, 6166.229221347332, 4755.881102972384, 5011.1481048221885, 5852.531797456193, 5943.881122377528, 6101.176341193971, 4859.684120532154, 6067.1202675611785, 6086.147939288503, 6148.9692551536955, 6166.044263139221, 4739.573438390561, 4849.878753031132, 4860.4896485869185, 4861.829835955725, 4867.34290870735, 4318.352247162888, 4399.208142534375, 4424.889377765581, 4467.955320446754, 4496.820127194876, 5550.4449555044175, 5609.8597535061135, 5718.7426565804635, 5788.102844059177, 5829.562782791223, 5331.5201631853315, 6063.484603808637, 6127.189528148541, 6136.7948077883175, 6195.31851496335, 5741.741621627037, 5768.278965129359, 5780.046292361797, 5792.102224155263, 5822.912656310159, 4784.521547845187, 5338.439463856317, 5350.30446042012, 5366.248725178478, 5377.650452720561, 6097.359777223416, 6104.368208816009, 6128.28924408451, 6196.442320191204, 6210.347913469111, 6126.908096378469, 6130.47890929276, 6138.508919286357, 6194.5044396449075, 6200.937990620217, 6118.785842495491, 6181.072839074175, 6203.193584833943, 6203.2556093269095, 6236.906446403217, 5073.34046573943, 5905.40945905406, 5986.521078313637, 6089.056196289582, 6105.175801266772, 6107.219158344477, 6108.633481991094, 6119.418655227746, 6122.9471041527795, 6130.601510901777, 5968.70156492165, 5985.581009329305, 6071.453569178795, 6084.726187321669, 6090.056041313426, 6005.3694362091355, 6019.939800601938, 6020.4280593343565, 6041.577089603686, 6046.1535385045245, 5470.343558772973, 5696.772129114789, 5849.883002340014, 5865.472107510344, 5871.794487192928, 5941.970290148522, 5952.642841429528, 5977.581569290108, 6033.42682445167, 6046.969765151146, 5107.846764597068, 5377.059014672339, 5806.158107074535, 5812.099124635688, 5836.9416305838095], "value": 5520.3100029901825, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [491.23858020068894, 515.8736109653134, 524.2162966365282, 534.126702840855, 536.7048123532059, 38183.045423864416, 41297.17405651885, 41335.0, 41353.172936541254, 41397.551085712046, 42128.36807447883, 42540.574594254045, 42604.14791704164, 42604.57395426045, 42649.57350426495, 8441.788955276137, 8453.619587118563, 9275.304352173596, 11293.378864162467, 11624.186306958516, 9675.467849268287, 11263.15526335524, 11365.943170284138, 11440.656780296604, 11479.0, 11308.547658093708, 11509.827352589718, 11534.826977595343, 11535.0, 11542.480588373543, 11466.369349685763, 11470.254433461798, 11483.827742583868, 11495.655130346062, 11525.077993760478, 11366.772664546663, 11476.254043487146, 11481.13891458144, 11493.252938558968, 11494.540218391254, 41565.0, 41693.16613667709, 41698.0, 41759.7912010438, 41853.372199417034, 4043.858464953742, 4061.0, 4061.6141466560925, 4157.272477316487, 4227.239096962546, 30567.69432305671, 31178.844105779324, 31384.686153138406, 31445.37109257802, 31688.36623267545, 2879.9424011519864, 2944.131481213047, 3130.812151270931, 3260.5272235525954, 3277.4919887417486, 447251.99999999674, 448964.0, 450315.0, 453776.0, 454621.72689136345, 62378.0, 62762.686186569685, 62801.68599156975, 62805.05792413117, 62835.6858215706, 68144.65927670429, 68182.65908670425, 68549.2297923609, 68739.96890046653, 68787.31212687909, 14630.341634626468, 16048.197590120448, 16611.335546578, 16620.418285360196, 16648.08435536057, 11379.601713940137, 12350.567730129402, 12637.557685481148, 12668.746625067448, 12854.871451285555, 19323.4202973911, 19339.0, 19347.613047739247, 19356.90321548383, 19363.0, 6830.521863469509, 7256.201817800038, 7530.736424225126, 7629.389648828081, 7695.23047695226, 880.3925291548811, 884.7964968057382, 898.6944438890808, 911.8768966189613, 924.0620769918538, 30166.84916575403, 30202.69797301999, 30266.092017239516, 30546.69453305483, 30745.538816917764, 1054.8892366301427, 1057.1701214546545, 1090.7491277006175, 1104.7569534702352, 1215.2101134262623, 13457.327133643474, 15916.363345466283, 16161.272742726605, 16161.676766464605, 16345.34618615266, 517978.33140845725, 578023.0, 583896.0, 589949.0, 590963.0, 52576.73711631494, 61584.38415615876, 61839.6908015466, 61913.38086619076, 62004.069938950954, 1428.5928510374702, 1454.403694485256, 1463.2537405922842, 1471.257015207335, 1471.6173794813426, 28834.855825720737, 29420.85289573624, 29966.25084372908, 30320.894221912244, 30551.2362190947, 43796.34305485484, 53526.73236633948, 53751.193732094835, 53794.462055380514, 53873.461265386846, 507029.0, 511324.0, 513287.0, 513645.0, 514638.0, 28307.292317692223, 33650.15874603057, 36038.639613603526, 41847.37228941507, 43003.56996429995, 47980.76009620069, 48447.51552484571, 48622.75688621676, 49108.0, 49341.25988110253, 39888.2022359557, 39937.600623993385, 40314.39528407135, 40879.97800054903, 41113.58886411098, 39643.2071358577, 40478.392824108254, 40495.987600310225, 41102.0, 41237.79381103075, 37763.43354849734, 38464.80767596256, 38542.80728596339, 38675.8397248083, 38810.80594597009, 32797.18007049843, 33545.78765565318, 34780.478292826134, 34929.30141397107, 35123.12192195216, 22894.885525572932, 23400.71296078664, 25259.0, 25543.23370298894, 26141.215763527125], "value": 20708.007475040515, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [332.29387551453163, 386.35478750486686, 395.53327074052623, 401.7288330376995, 413.9939945931387, 19909.303174388882, 21430.89284553579, 21504.677429838524, 21813.0, 21878.562428751415, 20994.26520071798, 21378.57242855142, 21517.67723484149, 21569.892150539225, 21768.346949591538, 5669.659820410779, 5775.335836378828, 5905.970470147643, 5915.201447804534, 5937.1984782054515, 4163.50037995441, 4361.934570981438, 5847.678377689225, 5973.790917317895, 5976.3724808895095, 6493.70778314977, 6598.571092878948, 6619.172603424572, 6644.534882558223, 6646.867062658744, 6366.93633063668, 6555.34446555343, 6598.208215014229, 6614.603123812539, 6623.304553021935, 5597.328320601554, 5762.538996880239, 5799.5650326225705, 5813.389594092601, 5816.505597024258, 22900.31299061031, 25785.484290314278, 25996.0, 26132.477350452886, 26304.0, 2759.0, 2783.0, 2786.3730660601404, 2787.4982503149427, 2795.566687163493, 14571.489997850025, 14979.700405991927, 15054.247287635575, 15543.455979040793, 15669.13819739914, 1794.8115447878047, 1843.7234414837746, 1849.8612604054615, 1853.8331550160551, 1868.6542989546977, 127545.72454275431, 127587.36206319001, 127828.36085819604, 129537.35231323783, 130448.99999999905, 10955.671329860117, 11440.084793216523, 11501.42492875353, 11565.76868462626, 11604.477798499087, 14540.709185816224, 14558.63603409902, 14579.489717859824, 14593.270336483134, 14619.0, 8482.745517634481, 8728.519931403707, 8744.125587441205, 8862.512561809033, 8894.688685896092, 8629.0, 8663.436876603, 8677.305815534743, 8791.120887911286, 8853.778655533535, 7085.751998680022, 7154.284571542804, 7173.094896566899, 7208.2791720828545, 7236.782896513113, 1648.7881407165723, 1680.2606852984648, 1958.5397431603562, 2002.2291417803958, 2002.2391491233238, 783.7962129846281, 893.5621545442816, 911.7583840282331, 920.1166879795337, 922.2437601166979, 14400.927995359956, 21218.363449096243, 21304.04131814072, 21331.0, 21369.0, 795.693657941685, 838.455004247232, 917.875119099283, 925.5011469033636, 931.9394239374419, 10828.296160749525, 11132.998030177323, 11158.721031974266, 11164.609238676607, 11307.674634648272, 650066.0, 668514.0, 708956.0, 715227.0, 718743.0, 131446.68553314536, 136305.0, 140935.0, 141594.2920285392, 141634.0, 1012.1245122968579, 1013.0932815130417, 1036.4506811389974, 1039.54260125543, 1042.4318746283293, 17305.65388692194, 18220.271189152285, 18872.150753216414, 18878.52803679919, 18917.90541047286, 51148.97702046013, 55202.44797552134, 55889.72055139698, 56166.43833561612, 57269.140962884754, 54127.72936135294, 76314.0, 80262.0, 82908.58545707233, 93889.0, 8150.103488616366, 8215.835683286181, 8254.257116859511, 8610.483370997761, 8686.218240358397, 10243.7439064024, 12103.515859365527, 12558.686032849251, 13319.733605327645, 13501.662458438224, 11331.716707082389, 11374.488148033524, 11564.710882227675, 11830.994365478686, 11857.466414011562, 5322.627416080837, 9598.616055357708, 9943.602255909682, 11267.605633802777, 11535.884641153481, 11384.089272857986, 11575.594854180063, 11817.586384476846, 11912.702182445508, 11959.940200298943, 8371.33029357662, 8429.072801991899, 8513.787155321166, 8526.48841069513, 8553.486790792573, 6753.0, 10931.945340273247, 10996.655651155497, 11118.0, 11196.198045108316], "value": 9974.072256279418, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1430.713857228554, 1432.1478720161513, 1441.0201063276968, 1459.24848702918, 1467.8311994120677, 5256.132738098218, 6706.597604143749, 6973.884178531436, 7409.81475463113, 7812.765617031491, 6741.191057073146, 7095.503314767969, 7205.1714052883935, 7421.703131874732, 7697.9615101924555, 1958.3733205374285, 2131.7103152592613, 2174.141214220386, 2209.8895055247253, 2221.711177546925, 1856.3502774029116, 1965.508622844289, 2177.4991751897073, 2195.868247905131, 2216.26863135165, 1983.652860749363, 2209.657503087024, 2212.4358288636377, 2220.844540882139, 2221.0116498158327, 2175.7171567696178, 2178.8801615911198, 2184.0826852721893, 2186.464316242531, 2211.9889400552975, 2176.1513009926143, 2178.6949827024227, 2235.2176738141684, 2245.9101635934544, 2262.7850354216325, 7706.0, 7748.922510774876, 7780.02749656293, 7788.610569471503, 7804.141544430108, 4685.2269375553, 4823.686460380064, 4828.61371090312, 4830.347903033081, 4861.246506791453, 3173.841307934594, 3176.809391436498, 3233.417984762742, 3235.7896736712037, 3280.1963518937773, 1164.5109054197258, 1212.5149940023973, 1212.727136394313, 1216.0, 1660.8173100958884, 9413.952930235306, 9582.0, 9716.75708107296, 9736.902630973671, 9751.853722194173, 3894.1432884765304, 4078.0008897820226, 4113.609207125318, 4124.443200167999, 4143.08852052548, 7885.093214280317, 7976.122626511079, 7984.9201507985335, 7985.720499782596, 8000.119986801447, 4720.457147428021, 4722.952770472251, 4727.338172655831, 4731.71609703412, 4741.905161896742, 4267.743935363826, 4359.825606975685, 4360.847370342023, 4363.52001279859, 4426.136903303889, 3378.989501246754, 3381.3744457275484, 3409.7954122752712, 3435.381631306389, 3465.0471120441575, 895.4135041547796, 973.0658567775015, 1004.7990401919645, 1049.7690508088208, 1058.6136060337922, 665.2781731821028, 736.8452624948713, 772.3396495995843, 773.9690412383442, 836.2724429746038, 3988.3818008208195, 4941.25881117828, 5281.841544753664, 5351.919721204185, 5578.219049333096, 888.5601627194451, 925.4493576322078, 970.1317320997667, 972.8492083727034, 1010.4897027001464, 3458.4985177149674, 3513.0, 3575.3206890690685, 3674.2835147146584, 3703.4444833274683, 6880.827979300558, 7337.669804858688, 8455.35120651467, 8643.265322447536, 8704.78238044054, 8715.651373945098, 8724.302055835646, 8768.473891566526, 8796.560171991376, 8851.513166775889, 6868.862622747517, 7194.0, 7250.057492525938, 7281.417486601059, 7285.125784905846, 5189.792408303626, 5358.383785864676, 5396.811111611075, 5425.457454254543, 5465.918011229915, 8214.794630134294, 8224.054233763194, 8258.834823303621, 8266.917330826855, 8520.403571749917, 8220.0, 8416.116307787594, 8426.115257897833, 8491.448055876348, 8567.700130495652, 6637.402633762985, 6725.764598239038, 6765.695543700644, 6777.6272305023695, 6783.321667833177, 7180.138383394026, 7211.639418028973, 7285.963570182115, 7291.562506249642, 7310.086239220098, 6604.075429439787, 6629.370209830058, 6690.0633911253435, 6710.496712746466, 6730.629815360006, 6365.554411191358, 6542.705578248895, 6580.045893345534, 6584.967075164594, 6644.534882558174, 5993.490553302844, 6102.847428814138, 6132.816015519542, 6141.84645383869, 6164.537659675452, 4367.737935723867, 4687.5781179693995, 4785.377900872864, 4786.449558300701, 4832.806687732592, 4666.883327916829, 4701.294805779161, 4765.380500535047, 4818.229083346788, 4943.332650092191], "value": 4062.1798308709854, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [356.1541339319117, 361.9529461170047, 364.66450865204007, 366.3424153644209, 370.36852167055156, 4898.436679781827, 5469.808556700516, 5474.644148130379, 5481.972590137054, 5507.917381239279, 5213.635045546814, 5426.403095659484, 5428.864278393042, 5428.864278393042, 5446.972765136179, 3172.1918506451334, 3258.7393008559256, 4065.5934406559404, 4099.836006559749, 4116.982678487535, 3592.7305452091196, 3916.2167566486814, 3934.449177115206, 4083.509978802533, 4094.160697057111, 3851.2490064437447, 4045.231406032859, 4061.4720086388875, 4090.713650044498, 4095.9590404095875, 3600.31593997141, 3826.2921359548573, 4089.9386509202386, 4094.303968325392, 4115.8887100483, 3501.299740052, 3506.7019303359243, 3513.7364697647777, 3517.015235733973, 3531.4526248431534, 4649.8372556960685, 5022.321986531844, 5242.475752424765, 5256.478159633055, 5267.525092973935, 3802.8478860845535, 4678.391809064834, 4685.625149987993, 4686.718796872198, 4690.671652984292, 5015.648904576681, 5631.831045068655, 5657.264555607745, 5708.229389032469, 5745.597808153431, 3595.8065579996182, 3902.551206611248, 3911.6870650347905, 3945.309570825094, 3964.781936993464, 5267.789288428497, 5769.480746732815, 5936.554758393095, 6054.909176362358, 6063.575549711478, 3888.755598208546, 4033.4756481657782, 4093.8771836844944, 4288.614024737789, 4301.462317210348, 4355.107203023373, 4357.975875669215, 4388.893331201041, 4390.297552391602, 4407.2507673695545, 4636.4436267647425, 4919.950800492021, 4937.703737775745, 4957.801687932514, 4965.280034395065, 4536.368906034492, 4956.249281648719, 5025.497450254945, 5784.1034639630925, 5847.853803654943, 1189.8334233207358, 1239.522783728253, 1243.6517775023008, 1431.9427222911174, 1432.8996970212193, 801.8396320735876, 929.6327950459531, 936.6721647423484, 938.202527851321, 982.4989255479613, 4635.629149668019, 4675.532446755297, 4902.901941961212, 5107.778116519326, 5110.66780659256, 4722.126406614787, 5381.246625472437, 5694.515966142924, 5709.3434255060365, 5709.8287051388525, 4615.723056616614, 4792.808287668454, 4924.60603151747, 5114.721319670082, 5128.999845030258, 2949.2626843289177, 2964.881404743786, 2985.671376237597, 2996.4306781711407, 3008.2629755709554, 4951.133551628451, 5860.560457965585, 5895.882082358329, 5896.439838215363, 5897.67562784051, 5068.997745890352, 5073.568746656576, 5452.100403433389, 5790.944214803684, 5886.056342250702, 4592.058627981256, 5381.13901775714, 5395.487428694346, 5928.7767087342245, 6102.237220347457, 4832.511256702731, 5264.815731449381, 5853.414658534283, 5873.471387575139, 5954.940450595439, 5145.4340022598235, 5443.101888188563, 5785.4214578541805, 5789.9710501447225, 5814.5639077068545, 4901.3090483783035, 5181.176258938812, 5734.455226753452, 6106.0690868382535, 6167.691615419121, 3905.8282515245883, 3944.684425245916, 3969.5633480316565, 3970.7220494565104, 3972.463717398114, 4468.776561171993, 5850.912236316543, 5884.47039766423, 5968.70156492165, 5987.970060149672, 4864.294677271847, 4870.683405578626, 5186.6369354144845, 5759.308882934075, 5807.477327040587, 4819.662623616454, 5719.714014299351, 5733.369329373682, 5761.827145185651, 5782.074868021096, 5324.680519168863, 5396.406395296593, 5462.262594549685, 5610.43895610452, 5646.520045795989, 4916.508349165055, 5705.828825135252, 5761.279840019997, 5764.769409223752, 5772.3650398456975, 5055.6713813602, 5332.440093790251, 5405.189221616789, 5616.7191640416995, 5645.786155976485], "value": 4146.953684923735, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [700.4816435837475, 707.3315716647767, 767.6468824340803, 788.4402074527086, 853.7182729699198, 17012.234449449774, 17719.202635881386, 17732.795945649337, 17938.28246870127, 17999.730004049932, 16554.923929944543, 16911.492655220332, 16950.576235594115, 16977.49067527973, 16988.980661160338, 735.9659678152204, 838.5262326785347, 847.5423271433434, 874.1477059866643, 880.6345366672823, 514.8493117881532, 669.0466085827676, 763.1414658509177, 848.0417128644654, 873.05273777951, 915.8992510823804, 919.8528235482324, 920.1994264989469, 920.2270093121776, 921.0007142250632, 913.3880300198882, 913.7624217703411, 920.5259291464833, 920.5305294299922, 920.9961142354865, 683.8666460040295, 686.7767975408012, 686.8900975843841, 688.3357559954668, 689.2005273882322, 13326.333683315892, 15261.160636165007, 15287.312070956834, 15731.134787586678, 15938.203089845461, 12124.636260912099, 13853.722925541533, 13903.79144312836, 14128.576142715632, 14151.504697335648, 11369.886301136965, 11649.650510484698, 11676.941615291955, 11930.04559635227, 11992.460339284753, 3691.8892433227047, 3721.218544105741, 3742.2889650966504, 3752.8498860045565, 3877.379619260933, 104367.0, 105408.47295763549, 105610.94389056088, 106298.0, 106813.46593267062, 10565.154787616972, 10625.25623206368, 10635.042846143884, 10884.72788180286, 10935.070519005814, 21634.675479867816, 22027.0, 22577.4355641107, 22654.66018009731, 22672.319830404784, 6773.762918297836, 7647.961760191163, 7726.7681969541, 7741.45809793309, 7916.643751031217, 4302.0, 4324.654027677778, 4325.610695037399, 4326.3510473428605, 4327.32926396409, 3516.367053930266, 3553.502509648651, 3562.4834399011997, 3602.9819850900576, 3610.187707765732, 603.8520562462224, 608.9360617135226, 609.1015751766198, 613.3988691082675, 617.1144407774857, 670.2325836916704, 686.1731613405834, 701.8771714949863, 707.8831992721143, 711.6157275071507, 10287.331323463952, 10426.957304269665, 10428.895711042944, 10445.216608754374, 10586.20603454744, 683.8905775075964, 704.503325155764, 708.7519368221184, 712.9108861392326, 718.4791026505742, 4795.280707893844, 5038.294638750578, 5048.1418158913075, 5048.570871475892, 5058.822941197041, 103639.0, 104429.0, 104845.95154048514, 106114.4082838758, 109207.36188957172, 76209.23790762022, 94787.0, 97389.0, 97896.0, 98314.0, 81780.0, 81826.59086704528, 82905.58547207346, 83850.58074709587, 83905.0, 9754.804903902024, 9814.362066465657, 9814.65648702292, 9826.263030272616, 9838.409695418297, 44887.0, 47229.45779066652, 48382.0, 48899.0, 49065.773355666395, 49185.0, 63653.363466364746, 64327.03509447456, 64631.0, 65566.0, 11658.41707914584, 11674.241174323643, 11774.52901883915, 11923.344216068199, 11979.28124312544, 11766.941165294118, 11922.225055371373, 11963.76072478563, 11986.46060927243, 12043.578474753342, 11802.232854864405, 11894.524219031142, 11958.0, 12077.27536347822, 12473.189242699196, 11606.825897611712, 11615.012723918224, 11646.0, 12030.338331391857, 12123.575674851338, 10544.789104218025, 11446.198766086294, 12620.0, 12819.487220511075, 12820.551280705131, 11084.889151108708, 11191.552337906392, 11535.192536522363, 11744.177907546731, 11748.353840538852, 10126.341787783771, 10814.675559733221, 10833.561082262851, 10936.507857146607, 11031.440703760669], "value": 7439.763224024788, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1572.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 119.0, 120.0, 119.0, 118.0, 119.0, 118.0, 120.0, 116.0, 120.0], "value": 117.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 113.0, 112.0, 152.0, 116.0, 112.0, 121.0, 145.0, 146.0, 114.0], "value": 127.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [193.0, 196.0, 212.0, 219.0, 197.0, 198.0, 199.0, 195.0, 199.0, 193.0], "value": 200.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 78.0, 73.0, 82.0, 62.0, 80.0, 49.0, 61.0, 77.0, 62.0], "value": 71.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 69.0, 81.0, 70.0, 71.0, 70.0, 84.0, 71.0, 85.0, 114.0], "value": 80.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 133.0, 81.0, 132.0, 135.0, 88.0, 97.0, 100.0, 93.0, 84.0], "value": 102.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 97.0, 96.0, 97.0, 97.0, 97.0, 97.0, 100.0, 151.0, 95.0], "value": 106.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 146.0, 92.0, 92.0, 108.0, 125.0, 91.0, 89.0, 94.0, 92.0], "value": 107.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 62.0, 61.0, 61.0, 62.0, 62.0, 63.0, 63.0, 62.0, 62.0], "value": 61.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 68.0, 76.0, 84.0, 69.0, 71.0, 70.0, 85.0, 110.0, 107.0], "value": 81.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 80.0, 114.0, 77.0, 90.0, 105.0, 110.0, 108.0, 110.0, 92.0], "value": 98.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 142.0, 146.0, 151.0, 154.0, 132.0, 141.0, 144.0, 200.0, 188.0], "value": 153.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 176.0, 175.0, 185.0, 208.0, 173.0, 205.0, 237.0, 226.0, 189.0], "value": 194.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 65.0, 68.0, 67.0, 69.0, 68.0, 68.0, 67.0, 69.0, 68.0], "value": 67.8, "unit": "score"}]}]} 06:15:42 INFO - Return code: 0 06:15:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:15:42 ERROR - # TBPL SUCCESS # 06:15:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:15:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:15:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:15:42 INFO - 2017-01-13 06:15:42.708 screenresolution[1668:12573] starting screenresolution argv=screenresolution get 06:15:42 INFO - 2017-01-13 06:15:42.711 screenresolution[1668:12573] Display 0: 1600x1200x32@60 06:15:42 INFO - 2017-01-13 06:15:42.717 screenresolution[1669:12576] starting screenresolution argv=screenresolution list 06:15:42 INFO - Available Modes on Display 0 06:15:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:15:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:15:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:15:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:15:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:15:42 INFO - Intel Iris: 06:15:42 INFO - Chipset Model: Intel Iris 06:15:42 INFO - Type: GPU 06:15:42 INFO - Bus: Built-In 06:15:42 INFO - VRAM (Dynamic, Max): 1536 MB 06:15:42 INFO - Vendor: Intel (0x8086) 06:15:42 INFO - Device ID: 0x0a2e 06:15:42 INFO - Revision ID: 0x0009 06:15:42 INFO - Displays: 06:15:42 INFO - Display: 06:15:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:15:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:15:42 INFO - Display Serial Number: 1600x1200 60 06:15:42 INFO - Main Display: Yes 06:15:42 INFO - Mirror: Off 06:15:42 INFO - Online: Yes 06:15:42 INFO - Rotation: Supported 06:15:42 INFO - Return code: 0 06:15:42 INFO - Running post-action listener: _resource_record_post_action 06:15:42 INFO - [mozharness: 2017-01-13 14:15:42.955416Z] Finished run-tests step (success) 06:15:42 INFO - Running post-run listener: _resource_record_post_run 06:15:43 INFO - Total resource usage - Wall time: 1198s; CPU: 54.0%; Read bytes: 33305088; Write bytes: 557386240; Read time: 204; Write time: 1680 06:15:43 INFO - TinderboxPrint: CPU usage
54.5% 06:15:43 INFO - TinderboxPrint: I/O read bytes / time
33,305,088 / 204 06:15:43 INFO - TinderboxPrint: I/O write bytes / time
557,386,240 / 1,680 06:15:43 INFO - TinderboxPrint: CPU idle
2,181.6 (45.5%) 06:15:43 INFO - TinderboxPrint: CPU system
964.4 (20.1%) 06:15:43 INFO - TinderboxPrint: CPU user
1,647.7 (34.4%) 06:15:43 INFO - TinderboxPrint: Swap in / out
280,969,216 / 0 06:15:43 INFO - install - Wall time: 23s; CPU: 54.0%; Read bytes: 241766912; Write bytes: 235568640; Read time: 17704; Write time: 505 06:15:43 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 31379968; Write bytes: 302398464; Read time: 180; Write time: 1124 06:15:43 INFO - Running post-run listener: _upload_blobber_files 06:15:43 INFO - Blob upload gear active. 06:15:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:15:43 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 06:15:43 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', 'Graphics', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:15:43 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 Graphics -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:15:43 INFO - (blobuploader) - INFO - Open directory for files ... 06:15:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 06:15:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:15:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:15:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 06:15:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:15:45 INFO - (blobuploader) - INFO - Done attempting. 06:15:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 06:15:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:15:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:15:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 06:15:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:15:46 INFO - (blobuploader) - INFO - Done attempting. 06:15:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 06:15:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:15:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:15:47 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 06:15:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:15:47 INFO - (blobuploader) - INFO - Done attempting. 06:15:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:15:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:15:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:15:50 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:15:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:15:50 INFO - (blobuploader) - INFO - Done attempting. 06:15:50 INFO - (blobuploader) - INFO - Iteration through files over. 06:15:50 INFO - Return code: 0 06:15:50 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:15:50 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:15:50 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/fbe103b39d8212e96b05bce1987305a6342c40efc9aedaaaea940b389c88305e895316886416d665edee00e2f41721afde9b82d46013f8ccd4657186d279c4b4", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/cf7418c9f56e338b918dfb21031883533a19434b6119fddea96a03a4ef74437b506b10f46479ef3864a5f5289b9e59081b98aebe4e81f05697708ac36ba9d55e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/cf64b1bc0e05163383914459a0338c09436205cd7ce2654b1ecd3b05b565422863d505f8faf1230a081df98be4c42324441053001f7cd512e284cfa9d2e70ffe", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/55ede71a67b9fbc3801fa4cf6c249be1cf7c3d09541980daab8f3cf4096e39a4889846fcf3a95552cc1d71f5f471a1b169248c5a3dbfb1c4dd6cba79fed36a3e"} 06:15:50 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:15:50 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:15:50 INFO - Contents: 06:15:50 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/fbe103b39d8212e96b05bce1987305a6342c40efc9aedaaaea940b389c88305e895316886416d665edee00e2f41721afde9b82d46013f8ccd4657186d279c4b4", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/cf7418c9f56e338b918dfb21031883533a19434b6119fddea96a03a4ef74437b506b10f46479ef3864a5f5289b9e59081b98aebe4e81f05697708ac36ba9d55e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/cf64b1bc0e05163383914459a0338c09436205cd7ce2654b1ecd3b05b565422863d505f8faf1230a081df98be4c42324441053001f7cd512e284cfa9d2e70ffe", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/55ede71a67b9fbc3801fa4cf6c249be1cf7c3d09541980daab8f3cf4096e39a4889846fcf3a95552cc1d71f5f471a1b169248c5a3dbfb1c4dd6cba79fed36a3e"} 06:15:50 INFO - Running post-run listener: copy_logs_to_upload_dir 06:15:50 INFO - Copying logs to upload dir... 06:15:50 INFO - mkdir: /builds/slave/test/build/upload/logs 06:15:50 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.314278 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2017-01-13 06:15:50.272829) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-13 06:15:50.276409) ========= 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.cJpBM9BXil/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.6VrLj1zGFt/Listeners TMPDIR=/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/fbe103b39d8212e96b05bce1987305a6342c40efc9aedaaaea940b389c88305e895316886416d665edee00e2f41721afde9b82d46013f8ccd4657186d279c4b4", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/cf7418c9f56e338b918dfb21031883533a19434b6119fddea96a03a4ef74437b506b10f46479ef3864a5f5289b9e59081b98aebe4e81f05697708ac36ba9d55e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/cf64b1bc0e05163383914459a0338c09436205cd7ce2654b1ecd3b05b565422863d505f8faf1230a081df98be4c42324441053001f7cd512e284cfa9d2e70ffe", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/55ede71a67b9fbc3801fa4cf6c249be1cf7c3d09541980daab8f3cf4096e39a4889846fcf3a95552cc1d71f5f471a1b169248c5a3dbfb1c4dd6cba79fed36a3e"} build_url:https://queue.taskcluster.net/v1/task/La1gTaTbSCGGL55v2602iQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011144 build_url: 'https://queue.taskcluster.net/v1/task/La1gTaTbSCGGL55v2602iQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/fbe103b39d8212e96b05bce1987305a6342c40efc9aedaaaea940b389c88305e895316886416d665edee00e2f41721afde9b82d46013f8ccd4657186d279c4b4", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/cf7418c9f56e338b918dfb21031883533a19434b6119fddea96a03a4ef74437b506b10f46479ef3864a5f5289b9e59081b98aebe4e81f05697708ac36ba9d55e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/cf64b1bc0e05163383914459a0338c09436205cd7ce2654b1ecd3b05b565422863d505f8faf1230a081df98be4c42324441053001f7cd512e284cfa9d2e70ffe", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/55ede71a67b9fbc3801fa4cf6c249be1cf7c3d09541980daab8f3cf4096e39a4889846fcf3a95552cc1d71f5f471a1b169248c5a3dbfb1c4dd6cba79fed36a3e"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-13 06:15:50.310977) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-13 06:15:50.311305) ========= 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.cJpBM9BXil/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.6VrLj1zGFt/Listeners TMPDIR=/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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.005175 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-13 06:15:50.360043) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-13 06:15:50.360494) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-13 06:15:50.360957) ========= ========= Total master_lag: 0.28 =========