builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0061 starttime: 1483501857.84 results: success (0) buildid: 20170103184139 builduid: 47ca1b2e52974987962a5c9193d9e2d0 revision: 57ac9f63fc6953f4efeb0cc84a60192d3721251f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-03 19:50:57.840625) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-03 19:50:57.841087) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-03 19:50:57.841364) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-03 19:50:57.874293) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-03 19:50:57.874650) ========= 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.dTSKOfSnHe/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.j4It8B1yPl/Listeners TMPDIR=/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/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-03 19:50:57-- 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% 16.8M=0.001s 2017-01-03 19:50:58 (16.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.488256 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-03 19:50:58.391549) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-03 19:50:58.391868) ========= 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.dTSKOfSnHe/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.j4It8B1yPl/Listeners TMPDIR=/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/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.027694 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-03 19:50:58.441914) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-03 19:50:58.442304) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 57ac9f63fc6953f4efeb0cc84a60192d3721251f --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 57ac9f63fc6953f4efeb0cc84a60192d3721251f --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.dTSKOfSnHe/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.j4It8B1yPl/Listeners TMPDIR=/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/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-03 19:50:58,530 truncating revision to first 12 chars 2017-01-03 19:50:58,530 Setting DEBUG logging. 2017-01-03 19:50:58,530 attempt 1/10 2017-01-03 19:50:58,530 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/57ac9f63fc69?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-03 19:50:58,843 unpacking tar archive at: mozilla-central-57ac9f63fc69/testing/mozharness/ program finished with exit code 0 elapsedTime=0.601293 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-03 19:50:59.067996) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-03 19:50:59.068330) ========= script_repo_revision: 57ac9f63fc6953f4efeb0cc84a60192d3721251f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-03 19:50:59.068735) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-03 19:50:59.069002) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-03 19:50:59.090609) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2017-01-03 19:50:59.091196) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.dTSKOfSnHe/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.j4It8B1yPl/Listeners TMPDIR=/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 19:50:59 INFO - MultiFileLogger online at 20170103 19:50:59 in /builds/slave/test 19:50:59 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 19:50:59 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:50:59 INFO - {'append_to_log': False, 19:50:59 INFO - 'base_work_dir': '/builds/slave/test', 19:50:59 INFO - 'blob_upload_branch': 'Firefox', 19:50:59 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:50:59 INFO - 'branch': 'Firefox', 19:50:59 INFO - 'buildbot_json_path': 'buildprops.json', 19:50:59 INFO - 'config_files': ('talos/mac_config.py',), 19:50:59 INFO - 'default_actions': ('clobber', 19:50:59 INFO - 'read-buildbot-config', 19:50:59 INFO - 'download-and-extract', 19:50:59 INFO - 'populate-webroot', 19:50:59 INFO - 'create-virtualenv', 19:50:59 INFO - 'install', 19:50:59 INFO - 'run-tests'), 19:50:59 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:50:59 INFO - 'download_minidump_stackwalk': True, 19:50:59 INFO - 'download_symbols': 'ondemand', 19:50:59 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:50:59 INFO - 'tooltool.py': '/tools/tooltool.py', 19:50:59 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:50:59 INFO - '/tools/misc-python/virtualenv.py')}, 19:50:59 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:50:59 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:50:59 INFO - 'installer_path': 'installer.exe', 19:50:59 INFO - 'log_level': 'info', 19:50:59 INFO - 'log_name': 'talos', 19:50:59 INFO - 'log_to_console': True, 19:50:59 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:50:59 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:50:59 INFO - 'opt_config_files': (), 19:50:59 INFO - 'pip_index': False, 19:50:59 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:50:59 INFO - 'cmd': ('bash', 19:50:59 INFO - '-c', 19:50:59 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:50:59 INFO - 'enabled': True, 19:50:59 INFO - 'halt_on_failure': False, 19:50:59 INFO - 'name': 'check_screen_resolution'},), 19:50:59 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:50:59 INFO - 'cmd': ('bash', 19:50:59 INFO - '-c', 19:50:59 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:50:59 INFO - 'enabled': True, 19:50:59 INFO - 'halt_on_failure': False, 19:50:59 INFO - 'name': 'check_screen_resolution'},), 19:50:59 INFO - 'run_cmd_checks_enabled': True, 19:50:59 INFO - 'sps_profile': False, 19:50:59 INFO - 'sps_profile_interval': 0, 19:50:59 INFO - 'suite': 'dromaeojs', 19:50:59 INFO - 'system_bits': '32', 19:50:59 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:50:59 INFO - 'title': 't-yosemite-r7-0061', 19:50:59 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:50:59 INFO - 'use_talos_json': True, 19:50:59 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:50:59 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:50:59 INFO - 'work_dir': 'build'} 19:50:59 INFO - [mozharness: 2017-01-04 03:50:59.295107Z] Running clobber step. 19:50:59 INFO - Running pre-action listener: _resource_record_pre_action 19:50:59 INFO - Running main action method: clobber 19:50:59 INFO - rmtree: /builds/slave/test/build 19:50:59 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:51:01 INFO - Running post-action listener: _resource_record_post_action 19:51:01 INFO - [mozharness: 2017-01-04 03:51:01.672409Z] Finished clobber step (success) 19:51:01 INFO - [mozharness: 2017-01-04 03:51:01.672604Z] Running read-buildbot-config step. 19:51:01 INFO - Running pre-action listener: _resource_record_pre_action 19:51:01 INFO - Running main action method: read_buildbot_config 19:51:01 INFO - Using buildbot properties: 19:51:01 INFO - { 19:51:01 INFO - "project": "", 19:51:01 INFO - "product": "firefox", 19:51:01 INFO - "script_repo_revision": "production", 19:51:01 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 19:51:01 INFO - "repository": "", 19:51:01 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 19:51:01 INFO - "buildid": "20170103184139", 19:51:01 INFO - "pgo_build": "False", 19:51:01 INFO - "basedir": "/builds/slave/test", 19:51:01 INFO - "buildnumber": 53, 19:51:01 INFO - "slavename": "t-yosemite-r7-0061", 19:51:01 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 19:51:01 INFO - "platform": "yosemite_r7", 19:51:01 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 19:51:01 INFO - "branch": "mozilla-central", 19:51:01 INFO - "repo_path": "mozilla-central", 19:51:01 INFO - "revision": "57ac9f63fc6953f4efeb0cc84a60192d3721251f", 19:51:01 INFO - "stage_platform": "macosx64", 19:51:01 INFO - "builduid": "47ca1b2e52974987962a5c9193d9e2d0", 19:51:01 INFO - "slavebuilddir": "test" 19:51:01 INFO - } 19:51:01 INFO - Found installer url https://queue.taskcluster.net/v1/task/bSBvFKjPTp-MUye0C2jRNQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 19:51:01 INFO - Running post-action listener: _resource_record_post_action 19:51:01 INFO - [mozharness: 2017-01-04 03:51:01.676553Z] Finished read-buildbot-config step (success) 19:51:01 INFO - [mozharness: 2017-01-04 03:51:01.676735Z] Running download-and-extract step. 19:51:01 INFO - Running pre-action listener: _resource_record_pre_action 19:51:01 INFO - Running main action method: download_and_extract 19:51:01 INFO - mkdir: /builds/slave/test/build/tests 19:51:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:51:01 INFO - trying https://queue.taskcluster.net/v1/task/bSBvFKjPTp-MUye0C2jRNQ/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 19:51:01 INFO - Downloading https://queue.taskcluster.net/v1/task/bSBvFKjPTp-MUye0C2jRNQ/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 19:51:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bSBvFKjPTp-MUye0C2jRNQ/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 19:51:02 INFO - Downloaded 1199 bytes. 19:51:02 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 19:51:02 INFO - Using the following test package requirements: 19:51:02 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 19:51:02 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 19:51:02 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 19:51:02 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 19:51:02 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 19:51:02 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 19:51:02 INFO - u'jsshell-mac.zip'], 19:51:02 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 19:51:02 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 19:51:02 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 19:51:02 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 19:51:02 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 19:51:02 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 19:51:02 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 19:51:02 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 19:51:02 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 19:51:02 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 19:51:02 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 19:51:02 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 19:51:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bSBvFKjPTp-MUye0C2jRNQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 19:51:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bSBvFKjPTp-MUye0C2jRNQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:51:02 INFO - Fetch https://queue.taskcluster.net/v1/task/bSBvFKjPTp-MUye0C2jRNQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 19:51:04 INFO - Expected file size: 16300486 19:51:04 INFO - Obtained file size: 16300486 19:51:10 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 19:51:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bSBvFKjPTp-MUye0C2jRNQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 19:51:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bSBvFKjPTp-MUye0C2jRNQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:51:10 INFO - Fetch https://queue.taskcluster.net/v1/task/bSBvFKjPTp-MUye0C2jRNQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 19:51:11 INFO - Expected file size: 16300486 19:51:11 INFO - Obtained file size: 16300486 19:51:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bSBvFKjPTp-MUye0C2jRNQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 19:51:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bSBvFKjPTp-MUye0C2jRNQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 19:51:17 INFO - Fetch https://queue.taskcluster.net/v1/task/bSBvFKjPTp-MUye0C2jRNQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 19:51:19 INFO - Expected file size: 14403730 19:51:19 INFO - Obtained file size: 14403730 19:51:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:51:19 INFO - trying https://queue.taskcluster.net/v1/task/bSBvFKjPTp-MUye0C2jRNQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 19:51:19 INFO - Downloading https://queue.taskcluster.net/v1/task/bSBvFKjPTp-MUye0C2jRNQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 19:51:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bSBvFKjPTp-MUye0C2jRNQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 19:51:26 INFO - Downloaded 69673128 bytes. 19:51:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/bSBvFKjPTp-MUye0C2jRNQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 19:51:26 INFO - mkdir: /builds/slave/test/properties 19:51:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:51:26 INFO - Writing to file /builds/slave/test/properties/build_url 19:51:26 INFO - Contents: 19:51:26 INFO - build_url:https://queue.taskcluster.net/v1/task/bSBvFKjPTp-MUye0C2jRNQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 19:51:27 INFO - Running post-action listener: _resource_record_post_action 19:51:27 INFO - Running post-action listener: set_extra_try_arguments 19:51:27 INFO - [mozharness: 2017-01-04 03:51:27.447837Z] Finished download-and-extract step (success) 19:51:27 INFO - [mozharness: 2017-01-04 03:51:27.447975Z] Running populate-webroot step. 19:51:27 INFO - Running pre-action listener: _resource_record_pre_action 19:51:27 INFO - Running main action method: populate_webroot 19:51:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:51:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:51:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:51:27 INFO - '--tppagecycles', 19:51:27 INFO - '7'], 19:51:27 INFO - 'tests': ['tsvgm']}}, 19:51:27 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:51:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:51:27 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:51:27 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:51:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:51:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:51:27 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 19:51:27 INFO - 'talos_options': ['--disable-e10s'], 19:51:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:51:27 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 19:51:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:51:27 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 19:51:27 INFO - 'talos_options': ['--disable-e10s'], 19:51:27 INFO - 'tests': ['damp', 'tps']}, 19:51:27 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 19:51:27 INFO - 'tests': ['damp', 'tps']}, 19:51:27 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:51:27 INFO - 'tests': ['dromaeo_dom']}, 19:51:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:51:27 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:51:27 INFO - 'tests': ['basic_compositor_video']}, 19:51:27 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:51:27 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:51:27 INFO - 'tests': ['a11yr', 19:51:27 INFO - 'ts_paint', 19:51:27 INFO - 'tpaint', 19:51:27 INFO - 'sessionrestore', 19:51:27 INFO - 'sessionrestore_no_auto_restore', 19:51:27 INFO - 'tabpaint']}, 19:51:27 INFO - 'other-e10s': {'tests': ['a11yr', 19:51:27 INFO - 'ts_paint', 19:51:27 INFO - 'tpaint', 19:51:27 INFO - 'sessionrestore', 19:51:27 INFO - 'sessionrestore_no_auto_restore', 19:51:27 INFO - 'tabpaint']}, 19:51:27 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:51:27 INFO - 'ts_paint', 19:51:27 INFO - 'tpaint', 19:51:27 INFO - 'sessionrestore', 19:51:27 INFO - 'sessionrestore_no_auto_restore', 19:51:27 INFO - 'tabpaint']}, 19:51:27 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:51:27 INFO - 'ts_paint', 19:51:27 INFO - 'tpaint', 19:51:27 INFO - 'sessionrestore', 19:51:27 INFO - 'sessionrestore_no_auto_restore', 19:51:27 INFO - 'tabpaint']}, 19:51:27 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:51:27 INFO - 'tests': ['a11yr', 19:51:27 INFO - 'ts_paint', 19:51:27 INFO - 'tpaint', 19:51:27 INFO - 'sessionrestore', 19:51:27 INFO - 'sessionrestore_no_auto_restore', 19:51:27 INFO - 'tabpaint']}, 19:51:27 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:51:27 INFO - 'tests': ['a11yr', 19:51:27 INFO - 'ts_paint', 19:51:27 INFO - 'tpaint', 19:51:27 INFO - 'sessionrestore', 19:51:27 INFO - 'sessionrestore_no_auto_restore', 19:51:27 INFO - 'tabpaint']}, 19:51:27 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:51:27 INFO - 'tests': ['tsvgx', 19:51:27 INFO - 'tsvgr_opacity', 19:51:27 INFO - 'tart', 19:51:27 INFO - 'tscrollx', 19:51:27 INFO - 'cart', 19:51:27 INFO - 'tsvg_static']}, 19:51:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:51:27 INFO - 'tsvgr_opacity', 19:51:27 INFO - 'tart', 19:51:27 INFO - 'tscrollx', 19:51:27 INFO - 'cart', 19:51:27 INFO - 'tsvg_static']}, 19:51:27 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 19:51:27 INFO - 'talos_options': ['--disable-e10s'], 19:51:27 INFO - 'tests': ['tp5o']}, 19:51:27 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 19:51:27 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 19:51:27 INFO - 'talos_options': ['--disable-e10s', 19:51:27 INFO - '--xperf_path', 19:51:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:51:27 INFO - 'tests': ['tp5n']}, 19:51:27 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 19:51:27 INFO - 'talos_options': ['--xperf_path', 19:51:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:51:27 INFO - 'tests': ['tp5n']}}, 19:51:27 INFO - 'talos.zip': {'path': '', 19:51:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:51:27 INFO - Running post-action listener: _resource_record_post_action 19:51:27 INFO - [mozharness: 2017-01-04 03:51:27.466687Z] Finished populate-webroot step (success) 19:51:27 INFO - [mozharness: 2017-01-04 03:51:27.466802Z] Running create-virtualenv step. 19:51:27 INFO - Running pre-action listener: _resource_record_pre_action 19:51:27 INFO - Running main action method: create_virtualenv 19:51:27 INFO - Creating virtualenv /builds/slave/test/build/venv 19:51:27 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 19:51:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:51:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:51:27 INFO - Using real prefix '/tools/python27' 19:51:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:51:28 INFO - Installing distribute.............................................................................................................................................................................................done. 19:51:31 INFO - Installing pip.................done. 19:51:31 INFO - Return code: 0 19:51:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 19:51:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 19:51:31 INFO - Reading from file tmpfile_stdout 19:51:31 INFO - Output received: 19:51:31 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 19:51:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:51:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:51:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:51:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:51:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:51:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:51:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:51:31 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 0x1055ecb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1055ef768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe130c6e4c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053cad30>, '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 0x1052f1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053bfbe8>, '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.dTSKOfSnHe/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.j4It8B1yPl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:51:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 19:51:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 19:51:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dTSKOfSnHe/Render', 19:51:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:51:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:51:31 INFO - 'HOME': '/Users/cltbld', 19:51:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:51:31 INFO - 'LOGNAME': 'cltbld', 19:51:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:51:31 INFO - 'MOZ_NO_REMOTE': '1', 19:51:31 INFO - 'NO_EM_RESTART': '1', 19:51:31 INFO - 'PAGER': '/bin/cat', 19:51:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:51:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:51:31 INFO - 'PWD': '/builds/slave/test', 19:51:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:51:31 INFO - 'SHELL': '/bin/bash', 19:51:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.j4It8B1yPl/Listeners', 19:51:31 INFO - 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/', 19:51:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:51:31 INFO - 'USER': 'cltbld', 19:51:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:51:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:51:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:51:31 INFO - 'XPC_FLAGS': '0x0', 19:51:31 INFO - 'XPC_SERVICE_NAME': '0', 19:51:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:51:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:51:31 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 19:51:31 INFO - Cleaning up... 19:51:31 INFO - Return code: 0 19:51:31 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:51:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:51:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:51:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:51:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:51:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:51:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:51:31 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 0x1055ecb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1055ef768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe130c6e4c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053cad30>, '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 0x1052f1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053bfbe8>, '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.dTSKOfSnHe/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.j4It8B1yPl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:51:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 19:51:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 19:51:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dTSKOfSnHe/Render', 19:51:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:51:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:51:31 INFO - 'HOME': '/Users/cltbld', 19:51:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:51:31 INFO - 'LOGNAME': 'cltbld', 19:51:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:51:31 INFO - 'MOZ_NO_REMOTE': '1', 19:51:31 INFO - 'NO_EM_RESTART': '1', 19:51:31 INFO - 'PAGER': '/bin/cat', 19:51:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:51:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:51:31 INFO - 'PWD': '/builds/slave/test', 19:51:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:51:31 INFO - 'SHELL': '/bin/bash', 19:51:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.j4It8B1yPl/Listeners', 19:51:31 INFO - 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/', 19:51:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:51:31 INFO - 'USER': 'cltbld', 19:51:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:51:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:51:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:51:31 INFO - 'XPC_FLAGS': '0x0', 19:51:31 INFO - 'XPC_SERVICE_NAME': '0', 19:51:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:51:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:51:31 INFO - Downloading/unpacking psutil>=3.1.1 19:51: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 19:51: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 19:51: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 19:51: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 19:51: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 19:51: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 19:51:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:51:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:51:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:51:36 INFO - Installing collected packages: psutil 19:51:36 INFO - Running setup.py install for psutil 19:51:36 INFO - building 'psutil._psutil_osx' extension 19:51:36 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 19:51:36 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 19:51:36 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 19:51:36 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 19:51:36 INFO - building 'psutil._psutil_posix' extension 19:51:36 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 19:51:37 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:51:37 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:51:37 INFO - ^ 19:51:37 INFO - 1 warning generated. 19:51:37 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 19:51:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:51:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:51:37 INFO - Successfully installed psutil 19:51:37 INFO - Cleaning up... 19:51:37 INFO - Return code: 0 19:51:37 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:51: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')]} 19:51:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:51:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:51: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')]} 19:51:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:51:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:51:37 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 0x1055ecb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1055ef768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe130c6e4c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053cad30>, '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 0x1052f1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053bfbe8>, '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.dTSKOfSnHe/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.j4It8B1yPl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:51:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 19:51:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 19:51:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dTSKOfSnHe/Render', 19:51:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:51:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:51:37 INFO - 'HOME': '/Users/cltbld', 19:51:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:51:37 INFO - 'LOGNAME': 'cltbld', 19:51:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:51:37 INFO - 'MOZ_NO_REMOTE': '1', 19:51:37 INFO - 'NO_EM_RESTART': '1', 19:51:37 INFO - 'PAGER': '/bin/cat', 19:51:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:51:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:51:37 INFO - 'PWD': '/builds/slave/test', 19:51:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:51:37 INFO - 'SHELL': '/bin/bash', 19:51:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.j4It8B1yPl/Listeners', 19:51:37 INFO - 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/', 19:51:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:51:37 INFO - 'USER': 'cltbld', 19:51:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:51:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:51:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:51:37 INFO - 'XPC_FLAGS': '0x0', 19:51:37 INFO - 'XPC_SERVICE_NAME': '0', 19:51:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:51:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:51:37 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:51:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:51:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:51:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:51:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:51:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:51:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:51:40 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:51:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:51:40 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:51:40 INFO - Installing collected packages: mozsystemmonitor 19:51:40 INFO - Running setup.py install for mozsystemmonitor 19:51:40 INFO - Successfully installed mozsystemmonitor 19:51:40 INFO - Cleaning up... 19:51:40 INFO - Return code: 0 19:51:40 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:51:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:51:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:51:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:51:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:51:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:51:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:51:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1055ecb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1055ef768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe130c6e4c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053cad30>, '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 0x1052f1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053bfbe8>, '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.dTSKOfSnHe/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.j4It8B1yPl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:51:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 19:51:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 19:51:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dTSKOfSnHe/Render', 19:51:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:51:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:51:40 INFO - 'HOME': '/Users/cltbld', 19:51:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:51:40 INFO - 'LOGNAME': 'cltbld', 19:51:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:51:40 INFO - 'MOZ_NO_REMOTE': '1', 19:51:40 INFO - 'NO_EM_RESTART': '1', 19:51:40 INFO - 'PAGER': '/bin/cat', 19:51:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:51:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:51:40 INFO - 'PWD': '/builds/slave/test', 19:51:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:51:40 INFO - 'SHELL': '/bin/bash', 19:51:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.j4It8B1yPl/Listeners', 19:51:40 INFO - 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/', 19:51:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:51:40 INFO - 'USER': 'cltbld', 19:51:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:51:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:51:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:51:40 INFO - 'XPC_FLAGS': '0x0', 19:51:40 INFO - 'XPC_SERVICE_NAME': '0', 19:51:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:51:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:51:40 INFO - Downloading/unpacking jsonschema==2.5.1 19:51:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:51:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:51:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:51:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:51:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:51:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:51:43 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:51:43 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:51:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:51:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:51:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:51:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:51:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:51:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:51:43 INFO - Downloading functools32-3.2.3-2.tar.gz 19:51:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:51:43 INFO - warning: no files found matching '*.txt' 19:51:43 INFO - no previously-included directories found matching 'build' 19:51:43 INFO - no previously-included directories found matching 'dist' 19:51:43 INFO - no previously-included directories found matching '.git*' 19:51:43 INFO - Installing collected packages: jsonschema, functools32 19:51:43 INFO - Running setup.py install for functools32 19:51:43 INFO - warning: no files found matching '*.txt' 19:51:43 INFO - no previously-included directories found matching 'build' 19:51:43 INFO - no previously-included directories found matching 'dist' 19:51:43 INFO - no previously-included directories found matching '.git*' 19:51:44 INFO - Successfully installed jsonschema functools32 19:51:44 INFO - Cleaning up... 19:51:44 INFO - Return code: 0 19:51:44 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:51:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:51:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:51:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:51:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:51:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:51:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:51:44 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 0x1055ecb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1055ef768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe130c6e4c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053cad30>, '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 0x1052f1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053bfbe8>, '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.dTSKOfSnHe/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.j4It8B1yPl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:51:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 19:51:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 19:51:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dTSKOfSnHe/Render', 19:51:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:51:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:51:44 INFO - 'HOME': '/Users/cltbld', 19:51:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:51:44 INFO - 'LOGNAME': 'cltbld', 19:51:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:51:44 INFO - 'MOZ_NO_REMOTE': '1', 19:51:44 INFO - 'NO_EM_RESTART': '1', 19:51:44 INFO - 'PAGER': '/bin/cat', 19:51:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:51:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:51:44 INFO - 'PWD': '/builds/slave/test', 19:51:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:51:44 INFO - 'SHELL': '/bin/bash', 19:51:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.j4It8B1yPl/Listeners', 19:51:44 INFO - 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/', 19:51:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:51:44 INFO - 'USER': 'cltbld', 19:51:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:51:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:51:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:51:44 INFO - 'XPC_FLAGS': '0x0', 19:51:44 INFO - 'XPC_SERVICE_NAME': '0', 19:51:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:51:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:51:44 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:51:44 INFO - Cleaning up... 19:51:44 INFO - Return code: 0 19:51:44 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:51:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:51:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:51:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:51:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:51:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:51:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:51:44 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 0x1055ecb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1055ef768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe130c6e4c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053cad30>, '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 0x1052f1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053bfbe8>, '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.dTSKOfSnHe/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.j4It8B1yPl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:51:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 19:51:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 19:51:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dTSKOfSnHe/Render', 19:51:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:51:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:51:44 INFO - 'HOME': '/Users/cltbld', 19:51:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:51:44 INFO - 'LOGNAME': 'cltbld', 19:51:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:51:44 INFO - 'MOZ_NO_REMOTE': '1', 19:51:44 INFO - 'NO_EM_RESTART': '1', 19:51:44 INFO - 'PAGER': '/bin/cat', 19:51:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:51:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:51:44 INFO - 'PWD': '/builds/slave/test', 19:51:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:51:44 INFO - 'SHELL': '/bin/bash', 19:51:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.j4It8B1yPl/Listeners', 19:51:44 INFO - 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/', 19:51:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:51:44 INFO - 'USER': 'cltbld', 19:51:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:51:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:51:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:51:44 INFO - 'XPC_FLAGS': '0x0', 19:51:44 INFO - 'XPC_SERVICE_NAME': '0', 19:51:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:51:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:51:44 INFO - Downloading/unpacking blobuploader==1.2.4 19:51:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:51:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:51:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:51:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:51:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:51:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:51:47 INFO - Downloading blobuploader-1.2.4.tar.gz 19:51:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:51:47 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:51:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:51:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:51:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:51:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:51:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:51:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:51:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:51:47 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:51:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:51:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:51:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:51:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:51:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:51:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:51:48 INFO - Downloading docopt-0.6.1.tar.gz 19:51:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:51:48 INFO - Installing collected packages: blobuploader, requests, docopt 19:51:48 INFO - Running setup.py install for blobuploader 19:51:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:51:48 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:51:48 INFO - Running setup.py install for requests 19:51:49 INFO - Running setup.py install for docopt 19:51:49 INFO - Successfully installed blobuploader requests docopt 19:51:49 INFO - Cleaning up... 19:51:49 INFO - Return code: 0 19:51:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:51:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:51:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:51:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:51:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:51:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:51:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:51:49 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 0x1055ecb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1055ef768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe130c6e4c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053cad30>, '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 0x1052f1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053bfbe8>, '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.dTSKOfSnHe/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.j4It8B1yPl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:51:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 19:51:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:51:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dTSKOfSnHe/Render', 19:51:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:51:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:51:49 INFO - 'HOME': '/Users/cltbld', 19:51:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:51:49 INFO - 'LOGNAME': 'cltbld', 19:51:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:51:49 INFO - 'MOZ_NO_REMOTE': '1', 19:51:49 INFO - 'NO_EM_RESTART': '1', 19:51:49 INFO - 'PAGER': '/bin/cat', 19:51:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:51:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:51:49 INFO - 'PWD': '/builds/slave/test', 19:51:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:51:49 INFO - 'SHELL': '/bin/bash', 19:51:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.j4It8B1yPl/Listeners', 19:51:49 INFO - 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/', 19:51:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:51:49 INFO - 'USER': 'cltbld', 19:51:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:51:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:51:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:51:49 INFO - 'XPC_FLAGS': '0x0', 19:51:49 INFO - 'XPC_SERVICE_NAME': '0', 19:51:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:51:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:51:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:51:49 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-Gxzp1p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:51:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:51:49 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-mF1kkb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:51:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:51:49 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-h2ZVCx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:51:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:51:50 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-NVUc3A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:51:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:51:50 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-uhtFmB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:51:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:51:50 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-DCaQQ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:51:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:51:50 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-p4PYkl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:51:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:51:50 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-NJalRq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:51:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:51:50 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-0rHJGf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:51:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:51:50 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-W9lE2s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:51:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:51:50 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-zV6Jjn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:51:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:51:50 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-fq_u7X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:51:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:51:50 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-kYeHTF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:51:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:51:51 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-abouHJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:51:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:51:51 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-cfgAHA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:51:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:51:51 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-EpAC2Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:51:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:51:51 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-Ka9jB2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:51:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:51:51 INFO - Running setup.py install for manifestparser 19:51:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:51:51 INFO - Running setup.py install for mozcrash 19:51:51 INFO - Running setup.py install for mozdebug 19:51:51 INFO - Running setup.py install for mozdevice 19:51:52 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:51:52 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:51:52 INFO - Running setup.py install for mozfile 19:51:52 INFO - Running setup.py install for mozhttpd 19:51:52 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:51:52 INFO - Running setup.py install for mozinfo 19:51:52 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:51:52 INFO - Running setup.py install for mozInstall 19:51:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:51:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:51:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:51:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:51:52 INFO - Running setup.py install for mozleak 19:51:52 INFO - Running setup.py install for mozlog 19:51:52 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:51:52 INFO - Running setup.py install for moznetwork 19:51:53 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:51:53 INFO - Running setup.py install for mozprocess 19:51:53 INFO - Running setup.py install for mozprofile 19:51:53 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:51:53 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:51:53 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:51:53 INFO - Running setup.py install for mozrunner 19:51:53 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:51:53 INFO - Running setup.py install for mozscreenshot 19:51:53 INFO - Running setup.py install for moztest 19:51:53 INFO - Running setup.py install for mozversion 19:51:54 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:51:54 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:51:54 INFO - Cleaning up... 19:51:54 INFO - Return code: 0 19:51:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:51:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:51:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:51:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:51:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:51:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:51:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:51:54 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 0x1055ecb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1055ef768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe130c6e4c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053cad30>, '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 0x1052f1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053bfbe8>, '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.dTSKOfSnHe/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.j4It8B1yPl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:51:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 19:51:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:51:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dTSKOfSnHe/Render', 19:51:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:51:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:51:54 INFO - 'HOME': '/Users/cltbld', 19:51:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:51:54 INFO - 'LOGNAME': 'cltbld', 19:51:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:51:54 INFO - 'MOZ_NO_REMOTE': '1', 19:51:54 INFO - 'NO_EM_RESTART': '1', 19:51:54 INFO - 'PAGER': '/bin/cat', 19:51:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:51:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:51:54 INFO - 'PWD': '/builds/slave/test', 19:51:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:51:54 INFO - 'SHELL': '/bin/bash', 19:51:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.j4It8B1yPl/Listeners', 19:51:54 INFO - 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/', 19:51:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:51:54 INFO - 'USER': 'cltbld', 19:51:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:51:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:51:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:51:54 INFO - 'XPC_FLAGS': '0x0', 19:51:54 INFO - 'XPC_SERVICE_NAME': '0', 19:51:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:51:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:51:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:51:54 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-hgslYO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:51:54 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 19:51:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:51:54 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-xtjiyX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:51:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:51:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:51:54 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-bRVT4_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:51:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 19:51:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:51:54 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-hKmrKf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:51:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:51:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:51:54 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-KiVh84-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:51:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 19:51:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:51:54 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-xh1nTP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:51:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 19:51:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:51:54 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-yr3oPT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:51:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 19:51:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:51:55 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-dLHKli-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:51:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 19:51:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:51:55 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-giWl6t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:51:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 19:51:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:51:55 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-9DqpGH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:51:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:51:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:51:55 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-eMcnra-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:51:55 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 19:51:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:51:55 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-beJnEf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:51:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 19:51:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:51:55 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-M6C_Fz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:51:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 19:51:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:51:55 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-_RKD_v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:51:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 19:51:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:51:55 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-Amm0Yd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:51:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 19:51:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:51:55 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-tvijtc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:51:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 19:51:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:51:56 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-lPKB3H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:51:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 19:51:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:51:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:51:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 19:51:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:51:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:51:56 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:51:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:51:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:51:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:51:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:51:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:51:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:51:58 INFO - Downloading blessings-1.6.tar.gz 19:51:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:51:58 INFO - Installing collected packages: blessings 19:51:58 INFO - Running setup.py install for blessings 19:51:58 INFO - Successfully installed blessings 19:51:58 INFO - Cleaning up... 19:51:59 INFO - Return code: 0 19:51:59 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:51:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:51:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:51:59 INFO - Reading from file tmpfile_stdout 19:51:59 INFO - Current package versions: 19:51:59 INFO - blessings == 1.6 19:51:59 INFO - blobuploader == 1.2.4 19:51:59 INFO - docopt == 0.6.1 19:51:59 INFO - functools32 == 3.2.3-2 19:51:59 INFO - jsonschema == 2.5.1 19:51:59 INFO - manifestparser == 1.1 19:51:59 INFO - mozInstall == 1.12 19:51:59 INFO - mozcrash == 1.0 19:51:59 INFO - mozdebug == 0.1 19:51:59 INFO - mozdevice == 0.49 19:51:59 INFO - mozfile == 1.2 19:51:59 INFO - mozhttpd == 0.7 19:51:59 INFO - mozinfo == 0.9 19:51:59 INFO - mozleak == 0.1 19:51:59 INFO - mozlog == 3.4 19:51:59 INFO - moznetwork == 0.27 19:51:59 INFO - mozprocess == 0.23 19:51:59 INFO - mozprofile == 0.28 19:51:59 INFO - mozrunner == 6.13 19:51:59 INFO - mozscreenshot == 0.1 19:51:59 INFO - mozsystemmonitor == 0.3 19:51:59 INFO - moztest == 0.8 19:51:59 INFO - mozversion == 1.4 19:51:59 INFO - psutil == 3.1.1 19:51:59 INFO - requests == 1.2.3 19:51:59 INFO - wsgiref == 0.1.2 19:51:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:51:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:51:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:51:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:51:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:51:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:51:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:51:59 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 0x1055ecb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1055ef768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe130c6e4c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053cad30>, '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 0x1052f1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053bfbe8>, '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.dTSKOfSnHe/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.j4It8B1yPl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:51:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 19:51:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:51:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dTSKOfSnHe/Render', 19:51:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:51:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:51:59 INFO - 'HOME': '/Users/cltbld', 19:51:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:51:59 INFO - 'LOGNAME': 'cltbld', 19:51:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:51:59 INFO - 'MOZ_NO_REMOTE': '1', 19:51:59 INFO - 'NO_EM_RESTART': '1', 19:51:59 INFO - 'PAGER': '/bin/cat', 19:51:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:51:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:51:59 INFO - 'PWD': '/builds/slave/test', 19:51:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:51:59 INFO - 'SHELL': '/bin/bash', 19:51:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.j4It8B1yPl/Listeners', 19:51:59 INFO - 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/', 19:51:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:51:59 INFO - 'USER': 'cltbld', 19:51:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:51:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:51:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:51:59 INFO - 'XPC_FLAGS': '0x0', 19:51:59 INFO - 'XPC_SERVICE_NAME': '0', 19:51:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:51:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:51:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 19:51:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 19:51:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 19:51:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 19:51:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 19:51:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 19:51:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 19:51:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 19:51:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 19:51:59 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 19:51:59 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 19:51:59 INFO - Cleaning up... 19:51:59 INFO - Return code: 0 19:51:59 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:51:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:51:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:51:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:51:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:51:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:51:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:51:59 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 0x1055ecb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1055ef768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe130c6e4c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053cad30>, '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 0x1052f1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053bfbe8>, '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.dTSKOfSnHe/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.j4It8B1yPl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:51:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 19:51:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 19:51:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dTSKOfSnHe/Render', 19:51:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:51:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:51:59 INFO - 'HOME': '/Users/cltbld', 19:51:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:51:59 INFO - 'LOGNAME': 'cltbld', 19:51:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:51:59 INFO - 'MOZ_NO_REMOTE': '1', 19:51:59 INFO - 'NO_EM_RESTART': '1', 19:51:59 INFO - 'PAGER': '/bin/cat', 19:51:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:51:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:51:59 INFO - 'PWD': '/builds/slave/test', 19:51:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:51:59 INFO - 'SHELL': '/bin/bash', 19:51:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.j4It8B1yPl/Listeners', 19:51:59 INFO - 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/', 19:51:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:51:59 INFO - 'USER': 'cltbld', 19:51:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:51:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:51:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:51:59 INFO - 'XPC_FLAGS': '0x0', 19:51:59 INFO - 'XPC_SERVICE_NAME': '0', 19:51:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:51:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:51:59 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:51:59 INFO - Cleaning up... 19:51:59 INFO - Return code: 0 19:51:59 INFO - Running post-action listener: _resource_record_post_action 19:51:59 INFO - Running post-action listener: _start_resource_monitoring 19:51:59 INFO - Starting resource monitoring. 19:51:59 INFO - [mozharness: 2017-01-04 03:51:59.926903Z] Finished create-virtualenv step (success) 19:51:59 INFO - [mozharness: 2017-01-04 03:51:59.927836Z] Running install step. 19:51:59 INFO - Running pre-action listener: _resource_record_pre_action 19:51:59 INFO - Running main action method: install 19:51:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:51:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:52:00 INFO - Reading from file tmpfile_stdout 19:52:00 INFO - Detecting whether we're running mozinstall >=1.0... 19:52:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:52:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:52:00 INFO - Reading from file tmpfile_stdout 19:52:00 INFO - Output received: 19:52:00 INFO - Usage: mozinstall [options] installer 19:52:00 INFO - Options: 19:52:00 INFO - -h, --help show this help message and exit 19:52:00 INFO - -d DEST, --destination=DEST 19:52:00 INFO - Directory to install application into. [default: 19:52:00 INFO - "/builds/slave/test"] 19:52:00 INFO - --app=APP Application being installed. [default: firefox] 19:52:00 INFO - mkdir: /builds/slave/test/build/application 19:52:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 19:52:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 19:52:22 INFO - Reading from file tmpfile_stdout 19:52:22 INFO - Output received: 19:52:22 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 19:52:22 INFO - Running post-action listener: _resource_record_post_action 19:52:22 INFO - [mozharness: 2017-01-04 03:52:22.443100Z] Finished install step (success) 19:52:22 INFO - [mozharness: 2017-01-04 03:52:22.443258Z] Running run-tests step. 19:52:22 INFO - Running pre-action listener: _resource_record_pre_action 19:52:22 INFO - Running main action method: run_tests 19:52:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:52:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:52:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:52:22 INFO - 2017-01-03 19:52:22.458 screenresolution[1635:8269] starting screenresolution argv=screenresolution get 19:52:22 INFO - 2017-01-03 19:52:22.460 screenresolution[1635:8269] Display 0: 1600x1200x32@60 19:52:22 INFO - 2017-01-03 19:52:22.466 screenresolution[1636:8272] starting screenresolution argv=screenresolution list 19:52:22 INFO - Available Modes on Display 0 19:52:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:52:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:52:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:52:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:52:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:52:22 INFO - Intel Iris: 19:52:22 INFO - Chipset Model: Intel Iris 19:52:22 INFO - Type: GPU 19:52:22 INFO - Bus: Built-In 19:52:22 INFO - VRAM (Dynamic, Max): 1536 MB 19:52:22 INFO - Vendor: Intel (0x8086) 19:52:22 INFO - Device ID: 0x0a2e 19:52:22 INFO - Revision ID: 0x0009 19:52:22 INFO - Displays: 19:52:22 INFO - Display: 19:52:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:52:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:52:22 INFO - Display Serial Number: 1600x1200 60 19:52:22 INFO - Main Display: Yes 19:52:22 INFO - Mirror: Off 19:52:22 INFO - Online: Yes 19:52:22 INFO - Rotation: Supported 19:52:22 INFO - Return code: 0 19:52:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:52:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:52:22 INFO - Python 2.7.3 19:52:22 INFO - Return code: 0 19:52:22 INFO - grabbing minidump binary from tooltool 19:52:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:52:22 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 0x1053cad30>, '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 0x1052f1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053bfbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:52:22 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 19:52:22 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 19:52:22 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 19:52:22 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:52:22 INFO - Return code: 0 19:52:22 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:52:22 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:52:22 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:52:22 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:52:22 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:52:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bSBvFKjPTp-MUye0C2jRNQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0061', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 19:52:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/bSBvFKjPTp-MUye0C2jRNQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0061 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 19:52:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dTSKOfSnHe/Render', 19:52:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:52:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:52:22 INFO - 'HOME': '/Users/cltbld', 19:52:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:52:22 INFO - 'LOGNAME': 'cltbld', 19:52:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:52:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:52:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:52:22 INFO - 'MOZ_NO_REMOTE': '1', 19:52:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:52:22 INFO - 'NO_EM_RESTART': '1', 19:52:22 INFO - 'PAGER': '/bin/cat', 19:52:22 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:52:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:52:22 INFO - 'PWD': '/builds/slave/test', 19:52:22 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:52:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:52:22 INFO - 'SHELL': '/bin/bash', 19:52:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.j4It8B1yPl/Listeners', 19:52:22 INFO - 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/', 19:52:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:52:22 INFO - 'USER': 'cltbld', 19:52:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:52:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:52:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:52:22 INFO - 'XPC_FLAGS': '0x0', 19:52:22 INFO - 'XPC_SERVICE_NAME': '0', 19:52:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:52:22 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bSBvFKjPTp-MUye0C2jRNQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0061', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 19:52:23 INFO - mozversion application_buildid: 20170103184139 19:52:23 INFO - mozversion application_changeset: 57ac9f63fc6953f4efeb0cc84a60192d3721251f 19:52:23 INFO - mozversion application_display_name: Nightly 19:52:23 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:52:23 INFO - mozversion application_name: Firefox 19:52:23 INFO - mozversion application_remotingname: firefox 19:52:23 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 19:52:23 INFO - mozversion application_vendor: Mozilla 19:52:23 INFO - mozversion application_version: 53.0a1 19:52:23 INFO - mozversion platform_buildid: 20170103184139 19:52:23 INFO - mozversion platform_changeset: 57ac9f63fc6953f4efeb0cc84a60192d3721251f 19:52:23 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 19:52:23 INFO - mozversion platform_version: 53.0a1 19:52:23 INFO - using testdate: 1483501943 19:52:23 INFO - actual date: 1483501943 19:52:23 INFO - starting webserver on 'localhost:49242' 19:52:23 INFO - SUITE-START | Running 2 tests 19:52:23 INFO - TEST-START | dromaeo_css 19:52:23 INFO - operating with platform_type : mac_ 19:52:23 INFO - Initialising browser for dromaeo_css test... 19:52:23 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/tmpedKzrB/profile http://localhost:49242/getInfo.html) 19:52:23 INFO - PROCESS | 1646 | 1483501943581 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4901 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2060 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3756 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2789 < callProvider()@resource://gre/modules/AddonManager.jsm:260 < _startProvider()@resource://gre/modules/AddonManager.jsm:755 < startup()@resource://gre/modules/AddonManager.jsm:937 < startup()@resource://gre/modules/AddonManager.jsm:2886 < observe()@resource://gre/components/addonManager.js:65 19:52:25 INFO - PROCESS | 1646 | __metrics Screen width/height:1600/1200 19:52:25 INFO - PROCESS | 1646 | colorDepth:24 19:52:25 INFO - PROCESS | 1646 | Browser inner width/height: 1024/689 19:52:25 INFO - PROCESS | 1646 | __metrics 19:52:25 INFO - PROCESS | 1646 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 19:52:25 INFO - PROCESS | 1646 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:52:26 INFO - TEST-INFO | 1646: exit 0 19:52:26 INFO - Browser initialized. 19:52:26 INFO - Running cycle 1/1 for dromaeo_css test... 19:52:26 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/tmpedKzrB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:52:38 INFO - PROCESS | 1649 | RSS: Main: 233005056 19:52:38 INFO - PROCESS | 1649 | 19:53:29 INFO - PROCESS | 1649 | 1483502009603 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 19:55:43 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49242/tests/dromaeo/cssquery-ext.html) 19:55:45 INFO - PROCESS | 1649 | RSS: Main: 390098944 19:55:45 INFO - PROCESS | 1649 | 19:57:27 INFO - PROCESS | 1649 | ************************* 19:57:27 INFO - PROCESS | 1649 | A coding exception was thrown and uncaught in a Task. 19:57:27 INFO - PROCESS | 1649 | 19:57:27 INFO - PROCESS | 1649 | Full message: ReferenceError: fetch is not defined 19:57:27 INFO - PROCESS | 1649 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 19:57:27 INFO - PROCESS | 1649 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 19:57:27 INFO - PROCESS | 1649 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 19:57:27 INFO - PROCESS | 1649 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 19:57:27 INFO - PROCESS | 1649 | TaskImpl@resource://gre/modules/Task.jsm:277:3 19:57:27 INFO - PROCESS | 1649 | asyncFunction@resource://gre/modules/Task.jsm:252:14 19:57:27 INFO - PROCESS | 1649 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 19:57:27 INFO - PROCESS | 1649 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 19:57:27 INFO - PROCESS | 1649 | TaskImpl@resource://gre/modules/Task.jsm:277:3 19:57:27 INFO - PROCESS | 1649 | asyncFunction@resource://gre/modules/Task.jsm:252:14 19:57:27 INFO - PROCESS | 1649 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 19:57:27 INFO - PROCESS | 1649 | 19:57:27 INFO - PROCESS | 1649 | ************************* 19:58:48 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-ext.html (next: http://localhost:49242/tests/dromaeo/cssquery-jquery.html) 19:58:48 INFO - PROCESS | 1649 | RSS: Main: 361275392 19:58:48 INFO - PROCESS | 1649 | 20:01:51 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49242/tests/dromaeo/cssquery-mootools.html) 20:01:51 INFO - PROCESS | 1649 | RSS: Main: 357552128 20:01:51 INFO - PROCESS | 1649 | 20:04:53 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49242/tests/dromaeo/cssquery-prototype.html) 20:04:54 INFO - PROCESS | 1649 | RSS: Main: 344158208 20:04:54 INFO - PROCESS | 1649 | 20:07:57 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49242/tests/dromaeo/cssquery-yui.html) 20:07:58 INFO - PROCESS | 1649 | RSS: Main: 331386880 20:07:58 INFO - PROCESS | 1649 | 20:11:01 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-yui.html (next: http://localhost:49242/tests/dromaeo/cssquery-dojo.html) 20:11:02 INFO - PROCESS | 1649 | RSS: Main: 421146624 20:11:02 INFO - PROCESS | 1649 | 20:11:02 INFO - PROCESS | 1649 | __start_tp_report 20:11:02 INFO - PROCESS | 1649 | _x_x_mozilla_page_load 20:11:02 INFO - PROCESS | 1649 | _x_x_mozilla_page_load_details 20:11:02 INFO - PROCESS | 1649 | |i|pagename|runs| 20:11:02 INFO - PROCESS | 1649 | |0;dojo.html;3204.359128174365;3333.1000629829955;3372.15696075981;3387.983060084699;3426.3147370525903;4634.235351167062;5642.661440313584;5665.716714164296;5764.913526297109;5769.942300576999;5640.238567793347;5673.659580425177;5682.318121825387;5682.659040457575;5684.488396044355;5142.794288228476;5146.948530514703;5167;5182.974085129569;5213.687178769286;5211.557017653504;5222.451642577532;5229.869253268661;5232.3459567554055;5234.345706786652;4340.978295108536;5229.215617657363;5236.1098613235845;5246.50158234969;5251.133562962106;5231.869203269911;5235;5238.895222095537;5239.397469291042;5250.474952504738;5091.694498330112;5162.638615296931;5174.353205849269;5204.453532379083;5210;5708.571857110734;5710.457506536873;5750.5687073469235;5801.53587712982;5815.941840581582;6213.844653883644;6217.502599792051;6227.750889964396;6246.937530624681;6248.0627905814035;6118.537326064743;6140.933729673037;6177.011678131522;6189.442950134509;6238.812835614939;5740.339860916049;5765.971170144123;5799.88400231993;5815.912761308584;5867.501262392744;6209.991979715144;6249.187605611241;6256.124142620037;6293.653849038347;6297.811065668037;5940.019896716994;6147.84630384244;6149.200603921461;6157.876842463215;6172.783952561639;5115.923261151086;6113.780377411107;6139.969300153471;6213.906791398133;6223.408776166257;6165.229346331708;6188.288346840171;6216.968915155486;6226.439620434183;6246.187995560548;6253.53098517613;6261.060840873814;6263.123162757218;6263.217097862767;6274.498040156868;4834.154023046023;4861.927071093936;4876.4879687633;4943.060818444484;4972.254161875675;4394.582514661102;4438.1567502174485;4440.7335559866515;4467.2182368086105;4470.307102399133;5923.881522369528;5925.05199168131;5930.614510056919;5933.970330148321;5936.5547583931375;6114.236541168675;6284.905726414107;6300.338464461257;6321.525885558602;6334.398232167937;5733.569982251264;5783.363829978699;5826.970865145732;5888.998870192077;5894.793682221101;5264.15773476242;5402.850205007703;5418.051840927822;5422.864428389322;5425.077736784756;6218.409251121137;6252.937470625236;6268.937310626927;6309.463695585927;6309.589876658002;5220.7650655721345;6226;6236.095766113979;6313.779017734358;6316.084167795736;6182.783602573888;6196.969015154896;6208.503319734501;6227.657478838707;6289.433950944437;6227.470664993526;6237.438630523275;6247.68761561911;6250.624962502083;6254.4996400286955;6289.559730818982;6315.400036996478;6322.968385158045;6328.651924144032;6339.175907132043;5916.408359164049;5936.851578710567;5989.221401217814;6088.238970128734;6156.630602163885;5479.698616575967;5770.499670085892;6122.855605810045;6145.754169833335;6157;5894.941050589439;5909.231799866162;5929.792457263975;5947.078202878561;5963.791267305797;5946.5540084494705;6014.72933717975;6027.78902738402;6135.509159267337;6151.815445536641;5882.941170588239;5943.851403714942;5960.254968128984;5989.880202395841;6001.7299221536005 20:11:02 INFO - PROCESS | 1649 | |1;ext.html;494.96535242533014;500.57701242450116;500.9474005229451;508.9312942752727;510.2754089193344;31784.682153178463;39802.00494987627;41254.38118428218;41313.38029929546;43577.564224357746;42614.786926065324;42857;43003.354949675704;43049;43170;9395.154436100749;9577.80844383112;9581.281403894702;9583.089606487372;9593.184579310768;8447.577621118919;8615.224629783319;11278.943605282004;11411.885881141165;11489;11456.365036041716;11516.193866429354;11524.711882202928;11536;11545.134114941371;8516.403851730382;11497.367644779575;11543.595974140948;11553.075753939665;11557.133215008907;8401.831963360759;8597.527136007517;8598.613062412143;8614.913850861536;11052.889471105267;39398.81803545898;40083.799581001906;40085.398719019235;40086.39870401946;40148;3952.505936757905;3965.5637879833193;3977.880663580097;4010.8997275068064;4075.633193012643;29382.85308573465;30106.54840177399;30579.388412231852;30635.711000459953;30648.846755766303;3127.2494601295753;3247.983760081208;3263.5757351544385;3313.6686331366914;3321.96678033219;477011;477804;479636;481051.9999999965;483637.9999999965;58477.415225848046;60443;60646.69676651589;60717;60941;55044.44955550393;63228.68385658042;63324.68337658282;63370;63692.04461933075;14489.855101449062;14785.482508112165;15385.769213461806;15893.841061589468;15905.522834314988;10639.946800265949;10787.730306742395;12235.204711693712;12331.951784098452;12337.444814983348;14991.250437478082;16470.341186352653;16516.75224871628;16606.916965415337;16960.406385776438;5195.298634684269;6178.505719542426;6189.752409903554;6220.720067596969;6266.811995640047;773.89552410425;836.1513064239801;874.2481465939287;877.5217506458995;882.4572887674162;27176.1847144586;29529.704702952695;29796.851015745215;30231;30380.5442918352;1032.5972870580479;1063.8404239364002;1074.4949873559415;1078.8058149533003;1167.3521195736278;12517.749645007048;12811.935940320238;14870.628234294229;15376.15431151275;15676.68646627061;245210;245400;245905;246637.30044049353;246690;59544.40455595475;59573.40426595765;59698.40301597015;59846;59860.40139598635;1350.7298540291981;1404.529482623312;1415.3843078260988;1417.1567917089315;1422.7723564229673;28509.144725658265;29143.834246629896;29240.853795730884;29852.402951941272;30061.398772024;50634.49365506298;50996;50997.7450112747;51133.7443312781;51156;530398;534858;535906;536266;536786;38963.80518097391;39398.212035759694;39577.406338904366;39681.254186788894;39879.40180897231;45655.77172114118;45665.77167114143;46107;47222;47511.76244118757;26424.339391515365;29804.403911922072;34564.65435345614;38110;38475;28088.157355279374;33075.50386744249;34007;37820.05449863776;38458.80770596129;33678.49482257817;34373.62505499752;37409.4388584166;37482;40877.59122408857;39541.80229098836;39825;40984.79507602443;42148.94627634211;42484.575154248065;23392.76607233974;31683.20791980127;31702.524462133548;31750.52374214435;34164.633414663134 20:11:02 INFO - PROCESS | 1649 | |2;jquery.html;364.3387252137369;379.97910114943664;383.8483798899435;388.2681146039714;412.9876103716888;14843.332050057747;15431.922840385796;18928.337508187215;20806;20845.374638760863;19556.41330760076;20108.798912010876;20288;20382.898085509587;20427.285045023425;5242.685438873661;5438.320209973754;5473.781048758065;5474.178873169017;5500.4499550045075;3815.771053736785;4122.525909520414;5355.0896347621;5418.91871621926;6058.212432383806;6078.149059131725;6092.756289748405;6094.207752992105;6098.176746139259;6112.969435152818;5216.608754343401;5383.300170977787;5894.734736936805;6083.117947897552;6085.087236914476;3734.5705243897028;3907.8436862525464;3920.862769803072;5377.542908852753;5728.742206600713;19349.12928918202;24117;24779.637119958395;24809.50380992388;24979.250622481355;2162.3512946116102;2418.7823095921276;2624.1304391157955;2648.4570663014038;2658.2655288747883;8237.752867413927;9661.806763864683;9729.027097290214;9846.60613575456;10250.17998560121;1782.5008997480727;1801.072447689428;1820.6085691576375;1840.3650740494509;1857.8420834229107;96408.07183856354;98680;112842;119039;119810.4009479947;9578.760530986781;11024.779504409868;11078.501467433985;11352.432378381049;12068.939655301667;14453.277336133151;14694.338754756061;14918;14972.326245318987;14989.775153372708;8235.876461853077;8415.116412776693;8420.326373890073;8530.530820804915;8577;8023.438359314909;8095;8155.959220203979;8288.171182881664;8331.000279966443;4487.326900964816;4507.774611269423;4568.885777855514;4633.49031606523;4675.345451636774;1922.5962547864826;1970.103602860695;1990.4327266728912;1991.2333751505485;1993.3222704280324;939.3565407695613;942.8302905476943;949.2975198353168;972.295086062599;990.3859607043535;18428.170732317078;19463.610727785366;19579;19626.803731962496;19916.30292939769;994.8358520844124;1005.3415013166388;1008.5562352564995;1009.8586197932295;1013.3919648211014;9153.496557689257;10024.348417352849;10357;10373.636922707668;10401.323913945569;499461.56192037655;509372;510366;516652;517288;103159.4842025785;104768;105418.47290763498;112199.31701024491;121801.78198218082;1000.3897622450445;1001.9849702254472;1024.4416792847767;1027.799579082072;1028.388109075099;13118.573063983195;16672.083035433167;16772.32910683559;17182.914085429493;17542.64914701673;53218;53655.6586085351;54421.72789136187;54608.726956366554;55321.616959576335;52803.73598131985;53597.73201133969;76678.2332176671;77055.45889082299;78562;5837.387074357302;7400.259974002557;7404;7478.551286922802;7598.392128629807;9649.276304277348;12176.33030183313;12316.261024338568;12406.875931240933;12578.496860125493;8849;10989.835152472559;11138.3873886937;11182.440877955907;11456;7141.85716285661;9026.413283136575;9136.994930557501;10844.783104338027;11204.607838725606;9322.766930826783;9980.650677226262;11240.26938249044;11378.601748938747;11557.937146655975;8056.355491560778;8185.795355115932;8192.959035204785;8339.83320333602;8353.582320884054;8172.632231549714;9605.615775368908;11236.876312368699;11290.096792256763;11330.716732081764 20:11:02 INFO - PROCESS | 1649 | |3;mootools.html;1430.4135304525146;1437.417845772462;1453.070035177486;1454.2510607037377;1455.6215384000163;6949.895751563724;7368.115826100862;7867.7639670809895;8250.91749082509;8256.587170641467;7470.402367810576;7639.465237433382;7819.921800781991;7867.291943725064;8037.156098609635;2277.9886100569474;2313.2135074074813;2313.2135074074813;2313.6529520571967;2314.36355002374;2233.564454931289;2314.4445333120098;2327.3948773318975;2328.1851352026824;2332.9300120996313;2015.1435639853078;2088.467440802601;2089.665653495441;2222.3666255117296;2306.5041016181517;2295.483516208856;2310.8151347892126;2313.9537209255805;2317.5133222023396;2323.303009097281;2091.633964056299;2261.5187052480487;2299.7930186283315;2307.3654744945106;2338.707661542307;8069.031716194036;8091.312238459679;8182.427230093896;8185.058718247418;8191.590420478952;4986.4265609455015;5154;5162.174052151675;5163.509466600667;5176.327077479941;3247.8538465769097;3265.2163480764684;3379.7972121672533;3381.5942086949476;3448;1175.741336905888;1199.3343694249688;1223.1437993404652;1243.776120298355;1244.4897591987242;10569.894301056967;10636;10666.466676666136;10670.573177072985;10712.517936692868;3994.820233089518;4561.2473941799235;4611.538846115362;4619.953800461953;4645.303204519281;9125.908740912639;9201.49391783445;9216;9219.723408297761;9227.446353218695;5048.974755126201;5148.2277658350795;5149.897002060013;5154.226865970135;5160.535551800356;3939.8227079781477;4588.770561471913;4669.696469729524;4678.976605116953;4723.267893476448;3715.4426835974277;3787.905302367408;3814.4278358246484;3815.523059617548;3834.7507412018685;1252.3612957391613;1254.219009007212;1257.8742125787348;1268.7462507498537;1270.9364531773558;722.8156820010863;723.7720118162762;766.8696321625225;791.4736700094488;829.8713699376606;4893.828715994923;5251.684898906002;5500.0399712207145;5563.860903477364;5612.971935140298;976.5312649927932;988.7083310423442;990.8506132885818;998.5257002923698;1031.0668844695563;3641.4901913732097;3646.908827279339;3652.739804767351;3657.65252301031;3752.043228976648;9476.95261523688;9701.70894873155;9784.902150978542;9788.412695238309;9839.458829764288;9760.609575617038;9761.36551124189;9768.21854251658;9768.413895166314;9784.608615655436;7630.96184519085;7747.690092396241;7762.146163922077;7763.029621297338;7765.3011228988535;5407.134858422633;5494.505494505353;5513.779448822002;5699.601027928005;5732.684702341411;9111.95444022802;9155;9265.305102117234;9291.767705807139;9299.535023248945;8586.871196931927;8853.867191991996;9287.164155226063;9332.346735728433;9384.530773461436;6974.407175390149;7005.369516743517;7049.823754405976;7065.434765218667;7093.964530177316;7644.847103058019;7656;7749.147593764568;7749.806254843674;7755.069391673035;6787.117674702257;6961.199462061928;6971.825704357431;7040.683169257498;7082.397996170177;6838.589684618939;6845.965770171118;6877.621730804653;6918.135233095863;6955.756548520778;6117.632942023492;6230.345813689679;6312.14786003902;6331;6332.113504109337;4180.226658068267;4653.208954477679;4713.2458806590785;4750.358701575381;4754.857354279377;4707.905841883212;4798.784054717616;4807.855764327076;4848.224284114524;4917.336159618549 20:11:02 INFO - PROCESS | 1649 | |4;prototype.html;353.1189681744049;364.17877685818473;368.382958544438;373.352233874228;373.47526724951433;4044.817983190756;4446.444194475691;4913.108453245504;4968.882001549649;4980.626453016021;4454.888627784307;5274.235235890803;5282.471752824716;5293.735313234342;5315.591368287407;3348.983255083733;3465.4108801503803;3614.7288953328475;3901.609839016097;3952.4664170336923;4115.835366585333;4123.628873401393;4138;4149.315362965107;4150.875473735793;3509.2805974775238;4129.772862492562;4133.462649855514;4137;4161.9803148228575;3752.1745216052423;3910.9467529546932;4031.395290706388;4033.576474470197;4147.440095587087;3011.906724826247;3192.9680703192903;3594.867616700757;3649.357788994963;3669.5230719264655;4243.957560424387;4366.716163449398;5159.329287192679;5166.383531527946;5180.963807238567;3622.5652921649307;3983.24318379507;4759.976200118977;4779.330893674888;4794.232922732346;4908.582770464514;5534.280543529356;5563.860903477405;5639.379668236491;5662.150677398382;3703.666669999713;3825.827837747308;3842.4236364545263;3846.9997800572055;3847.8076096195077;4611.031683346501;5274.235235890851;5744.626599271076;5809.3609702932645;5877.67672777997;3410.334984677964;3841;3846.3268927938057;3853.5953724859046;3856.903577410531;3301.405746965569;3905.062784931596;3908.5895980922164;3941.5664276929515;3942.802859856996;4346.67399944999;4422.358757980075;4524.977375113103;4594.977025114853;4664.55686709762;4723.5984941279685;4925.039617274597;5017.874553136127;5487.835364939058;5652.9152062719095;1073.5974009746344;1182.7344741126913;1269.6127681057367;1272.0396100943728;1273.5924504158766;784.9882251766228;810.2707563193055;862.6247582301719;868.9695860644847;878.5431575580662;4299.172851538084;4397.834573837936;4419.602235798793;4575.771211439414;4887.216166099348;4972.701637901738;4997.450280469146;5077.521897500151;5517.5585953123655;5521.365043020024;4366.497852746911;4386.731884745885;5059.747012649426;5060.55882000236;5081.441041485434;2510.7740303372784;2633.552296109666;2706.932326691809;2895.7683385329124;2929.0041385928885;4903.485134060872;4910.410750709938;5438.619296649196;5728.255326807572;5729.742161602738;4295.978520107379;4994.425641051325;5135.845924622267;5646.322441307069;5977.880442391215;4963.585378167205;4973.55238028572;5890.998530249868;5962.493188079062;5985.431384018511;5670.319561652628;5724.942750572608;5726.997775389374;5741.282339707536;5757.8560535986935;4897.918534010529;5048.974755126201;5074.5686616637995;5269.785430887079;5636;4419.867403977886;4758.405199350082;4968.652194346361;4985.925211121764;5547.452547452547;3590.9102272443397;3695.8933407957206;3753.9249215016093;3775.867844625425;3844.2119365530557;5294.576433885357;5307.920381194208;5644.6613203207935;5790.971045144747;5795.7391917362975;4682.859514214579;5042.369703787027;5244.685318880879;5613.129964855454;5646.781334639216;4479.721865618391;4690.366800481891;5597.44025597437;5611.298587676541;5619.185218143264;4858.246971719389;5506.862328441821;5515.531179849597;5521.558275337883;5550.306211723535;5416.214648875812;5583.55331573465;5601.983286017112;5616.747246373841;5661.631993920382;4432.321173425984;5012.997400519837;5489.258950041693;5517.779288828402;5536.916946245729 20:11:02 INFO - PROCESS | 1649 | |5;yui.html;683.9247682754896;696.3245651717838;744.3747252308063;783.2627045309572;800.7998000499875;12023.398830058506;15562.299696513655;15579.376824927018;15613.531594052169;15625.218739063044;14315.570532884005;14438.855611443909;14452.494162704308;14457.493987710432;14457.566273011826;694.4305669351135;714.2107970692388;854.8931383577053;875.40035075973;879.2087121590588;863.0075413274722;870.0820634230884;872.2498651160029;879;881.0748713850463;927.8654595083709;928.5403725156057;929.1359036096428;929.5816882402912;933.0576118120713;922.0134456131941;922.9907700923039;923.3213588012806;924.8335299646061;924.9722508324761;533.8371796602034;551.5174222555264;664.6145235763294;681.2472218198868;683.2313647146959;14945.103293802298;14977.400903963937;14980.47568335114;15013;15027.624309392244;13325.267110308929;13415.86584134156;13431.328433578283;13433.261170635611;13468.5286014989;12032.27806331623;12135.878641213563;12188.51245950161;12332.260064396165;12700.047496437712;3797.3354662933875;4307.418498502693;4374.950011997098;4426.933595996063;4494.73031618104;80032;98202.50898745532;101700;102455.48772256241;104057.47971260171;9863.704088877344;10395.220358473145;10543.683689489328;10914.836277455686;11069.114470842474;20545.383638490868;20786.48033799137;20986;21018.579628407348;21062.157513699585;7278.599677017709;7338.229485903903;7339.77980660581;7419.740309089156;7461.813454663677;4094.6314831665295;4180.702688447604;4204.66362690984;4223.1975924574235;4345.413369195118;3329.8002119872885;3355.798652080883;3433.914152146216;3434;3435.209901722618;591.1428428778203;629.9559030867795;634.346622978333;682.068975847964;706.7243774927782;626.1922120464641;629.1632129268063;650.1580453312971;652.7160685101995;656.5699466849221;6982.022516847645;7845.37237021037;9073.729677845107;9486.256642373619;9717.465539395402;472.433080303637;569.6268943841791;571.7912961768924;572.201778518972;583.2592607388593;4214.957850421518;4476.932846007313;4564.2012647786505;4594.012287358167;4659;89094;89508.5524572386;95762.52118739362;102527.48736256271;104419.95580044255;89567.55216224007;90890.91090890858;91509.5424522873;91658.54170729103;93192.53403733074;82018;82558;83331.5833420829;83402.58298708468;83469.58265208635;8144.185581441809;9022.548872556477;9109.863352049591;9116.772080698036;9136.543172841464;51456.48543514517;52876;53059.67350816106;53135.20297195468;54315.72842135764;70626;72691.27308726845;72984;73847;74251;11457;11547.364894930859;11604.6518604442;11983.221090629082;12152.696182595506;12919.806202907152;12937.22376657366;13055;13069.15050521713;13331.133476324008;11948.283103013848;11969.260039545588;12210.206336588093;12212.267263964191;12582.559610413591;11799.646010619696;12017.63947081589;12025.338606376734;12136.939315303367;12422;11405.942970285096;11772.234804737665;11826.704332391759;12149.817752733892;12413.627591172279;9745.951270243604;9839.901600983898;9903.158231550398;10142.645007424704;10144.695659130239;8198.221168988937;9060.682876099305;9180.6786762463;9684.70945871625;9721.027897210222 20:11:02 INFO - PROCESS | 1649 | __end_tp_report 20:11:02 INFO - PROCESS | 1649 | __start_cc_report 20:11:02 INFO - PROCESS | 1649 | _x_x_mozilla_cycle_collect,1696 20:11:02 INFO - PROCESS | 1649 | __end_cc_report 20:11:02 INFO - PROCESS | 1649 | __startTimestamp1483503062067__endTimestamp 20:11:02 INFO - PROCESS | 1649 | 20:11:02 INFO - PROCESS | 1649 | ------- Summary: start ------- 20:11:02 INFO - PROCESS | 1649 | Number of tests: 6 20:11:02 INFO - PROCESS | 1649 | 20:11:02 INFO - PROCESS | 1649 | [#0] dojo.html Cycles:165 Average:5724.52 Median:5933.97 stddev:652.50 (11.0%) stddev-sans-first:623.83 20:11:02 INFO - PROCESS | 1649 | Values: 3204.4 3333.1 3372.2 3388.0 3426.3 4634.2 5642.7 5665.7 5764.9 5769.9 5640.2 5673.7 5682.3 5682.7 5684.5 5142.8 5146.9 5167.0 5183.0 5213.7 5211.6 5222.5 5229.9 5232.3 5234.3 4341.0 5229.2 5236.1 5246.5 5251.1 5231.9 5235.0 5238.9 5239.4 5250.5 5091.7 5162.6 5174.4 5204.5 5210.0 5708.6 5710.5 5750.6 5801.5 5815.9 6213.8 6217.5 6227.8 6246.9 6248.1 6118.5 6140.9 6177.0 6189.4 6238.8 5740.3 5766.0 5799.9 5815.9 5867.5 6210.0 6249.2 6256.1 6293.7 6297.8 5940.0 6147.8 6149.2 6157.9 6172.8 5115.9 6113.8 6140.0 6213.9 6223.4 6165.2 6188.3 6217.0 6226.4 6246.2 6253.5 6261.1 6263.1 6263.2 6274.5 4834.2 4861.9 4876.5 4943.1 4972.3 4394.6 4438.2 4440.7 4467.2 4470.3 5923.9 5925.1 5930.6 5934.0 5936.6 6114.2 6284.9 6300.3 6321.5 6334.4 5733.6 5783.4 5827.0 5889.0 5894.8 5264.2 5402.9 5418.1 5422.9 5425.1 6218.4 6252.9 6268.9 6309.5 6309.6 5220.8 6226.0 6236.1 6313.8 6316.1 6182.8 6197.0 6208.5 6227.7 6289.4 6227.5 6237.4 6247.7 6250.6 6254.5 6289.6 6315.4 6323.0 6328.7 6339.2 5916.4 5936.9 5989.2 6088.2 6156.6 5479.7 5770.5 6122.9 6145.8 6157.0 5894.9 5909.2 5929.8 5947.1 5963.8 5946.6 6014.7 6027.8 6135.5 6151.8 5882.9 5943.9 5960.3 5989.9 6001.7 20:11:02 INFO - PROCESS | 1649 | 20:11:02 INFO - PROCESS | 1649 | [#1] ext.html Cycles:180 Average:58131.05 Median:21675.66 stddev:116742.97 (538.6%) stddev-sans-first:116990.26 20:11:02 INFO - PROCESS | 1649 | Values: 495.0 500.6 500.9 508.9 510.3 31784.7 39802.0 41254.4 41313.4 43577.6 42614.8 42857.0 43003.4 43049.0 43170.0 9395.2 9577.8 9581.3 9583.1 9593.2 8447.6 8615.2 11278.9 11411.9 11489.0 11456.4 11516.2 11524.7 11536.0 11545.1 8516.4 11497.4 11543.6 11553.1 11557.1 8401.8 8597.5 8598.6 8614.9 11052.9 39398.8 40083.8 40085.4 40086.4 40148.0 3952.5 3965.6 3977.9 4010.9 4075.6 29382.9 30106.5 30579.4 30635.7 30648.8 3127.2 3248.0 3263.6 3313.7 3322.0 477011.0 477804.0 479636.0 481052.0 483638.0 58477.4 60443.0 60646.7 60717.0 60941.0 55044.4 63228.7 63324.7 63370.0 63692.0 14489.9 14785.5 15385.8 15893.8 15905.5 10639.9 10787.7 12235.2 12332.0 12337.4 14991.3 16470.3 16516.8 16606.9 16960.4 5195.3 6178.5 6189.8 6220.7 6266.8 773.9 836.2 874.2 877.5 882.5 27176.2 29529.7 29796.9 30231.0 30380.5 1032.6 1063.8 1074.5 1078.8 1167.4 12517.7 12811.9 14870.6 15376.2 15676.7 245210.0 245400.0 245905.0 246637.3 246690.0 59544.4 59573.4 59698.4 59846.0 59860.4 1350.7 1404.5 1415.4 1417.2 1422.8 28509.1 29143.8 29240.9 29852.4 30061.4 50634.5 50996.0 50997.7 51133.7 51156.0 530398.0 534858.0 535906.0 536266.0 536786.0 38963.8 39398.2 39577.4 39681.3 39879.4 45655.8 45665.8 46107.0 47222.0 47511.8 26424.3 29804.4 34564.7 38110.0 38475.0 28088.2 33075.5 34007.0 37820.1 38458.8 33678.5 34373.6 37409.4 37482.0 40877.6 39541.8 39825.0 40984.8 42148.9 42484.6 23392.8 31683.2 31702.5 31750.5 34164.6 20:11:02 INFO - PROCESS | 1649 | 20:11:02 INFO - PROCESS | 1649 | [#2] jquery.html Cycles:180 Average:31124.14 Median:2099.81 stddev:85411.75 (4067.6%) stddev-sans-first:85620.12 20:11:02 INFO - PROCESS | 1649 | Values: 364.3 380.0 383.8 388.3 413.0 14843.3 15431.9 18928.3 20806.0 20845.4 19556.4 20108.8 20288.0 20382.9 20427.3 5242.7 5438.3 5473.8 5474.2 5500.4 3815.8 4122.5 5355.1 5418.9 6058.2 6078.1 6092.8 6094.2 6098.2 6113.0 5216.6 5383.3 5894.7 6083.1 6085.1 3734.6 3907.8 3920.9 5377.5 5728.7 19349.1 24117.0 24779.6 24809.5 24979.3 2162.4 2418.8 2624.1 2648.5 2658.3 8237.8 9661.8 9729.0 9846.6 10250.2 1782.5 1801.1 1820.6 1840.4 1857.8 96408.1 98680.0 112842.0 119039.0 119810.4 9578.8 11024.8 11078.5 11352.4 12068.9 14453.3 14694.3 14918.0 14972.3 14989.8 8235.9 8415.1 8420.3 8530.5 8577.0 8023.4 8095.0 8156.0 8288.2 8331.0 4487.3 4507.8 4568.9 4633.5 4675.3 1922.6 1970.1 1990.4 1991.2 1993.3 939.4 942.8 949.3 972.3 990.4 18428.2 19463.6 19579.0 19626.8 19916.3 994.8 1005.3 1008.6 1009.9 1013.4 9153.5 10024.3 10357.0 10373.6 10401.3 499461.6 509372.0 510366.0 516652.0 517288.0 103159.5 104768.0 105418.5 112199.3 121801.8 1000.4 1002.0 1024.4 1027.8 1028.4 13118.6 16672.1 16772.3 17182.9 17542.6 53218.0 53655.7 54421.7 54608.7 55321.6 52803.7 53597.7 76678.2 77055.5 78562.0 5837.4 7400.3 7404.0 7478.6 7598.4 9649.3 12176.3 12316.3 12406.9 12578.5 8849.0 10989.8 11138.4 11182.4 11456.0 7141.9 9026.4 9137.0 10844.8 11204.6 9322.8 9980.7 11240.3 11378.6 11557.9 8056.4 8185.8 8193.0 8339.8 8353.6 8172.6 9605.6 11236.9 11290.1 11330.7 20:11:02 INFO - PROCESS | 1649 | 20:11:02 INFO - PROCESS | 1649 | [#3] mootools.html Cycles:180 Average:5315.48 Median:5169.92 stddev:2888.84 (55.9%) stddev-sans-first:2882.19 20:11:02 INFO - PROCESS | 1649 | Values: 1430.4 1437.4 1453.1 1454.3 1455.6 6949.9 7368.1 7867.8 8250.9 8256.6 7470.4 7639.5 7819.9 7867.3 8037.2 2278.0 2313.2 2313.2 2313.7 2314.4 2233.6 2314.4 2327.4 2328.2 2332.9 2015.1 2088.5 2089.7 2222.4 2306.5 2295.5 2310.8 2314.0 2317.5 2323.3 2091.6 2261.5 2299.8 2307.4 2338.7 8069.0 8091.3 8182.4 8185.1 8191.6 4986.4 5154.0 5162.2 5163.5 5176.3 3247.9 3265.2 3379.8 3381.6 3448.0 1175.7 1199.3 1223.1 1243.8 1244.5 10569.9 10636.0 10666.5 10670.6 10712.5 3994.8 4561.2 4611.5 4620.0 4645.3 9125.9 9201.5 9216.0 9219.7 9227.4 5049.0 5148.2 5149.9 5154.2 5160.5 3939.8 4588.8 4669.7 4679.0 4723.3 3715.4 3787.9 3814.4 3815.5 3834.8 1252.4 1254.2 1257.9 1268.7 1270.9 722.8 723.8 766.9 791.5 829.9 4893.8 5251.7 5500.0 5563.9 5613.0 976.5 988.7 990.9 998.5 1031.1 3641.5 3646.9 3652.7 3657.7 3752.0 9477.0 9701.7 9784.9 9788.4 9839.5 9760.6 9761.4 9768.2 9768.4 9784.6 7631.0 7747.7 7762.1 7763.0 7765.3 5407.1 5494.5 5513.8 5699.6 5732.7 9112.0 9155.0 9265.3 9291.8 9299.5 8586.9 8853.9 9287.2 9332.3 9384.5 6974.4 7005.4 7049.8 7065.4 7094.0 7644.8 7656.0 7749.1 7749.8 7755.1 6787.1 6961.2 6971.8 7040.7 7082.4 6838.6 6846.0 6877.6 6918.1 6955.8 6117.6 6230.3 6312.1 6331.0 6332.1 4180.2 4653.2 4713.2 4750.4 4754.9 4707.9 4798.8 4807.9 4848.2 4917.3 20:11:02 INFO - PROCESS | 1649 | 20:11:02 INFO - PROCESS | 1649 | [#4] prototype.html Cycles:180 Average:4381.31 Median:4872.73 stddev:1340.19 (27.5%) stddev-sans-first:1309.40 20:11:02 INFO - PROCESS | 1649 | Values: 353.1 364.2 368.4 373.4 373.5 4044.8 4446.4 4913.1 4968.9 4980.6 4454.9 5274.2 5282.5 5293.7 5315.6 3349.0 3465.4 3614.7 3901.6 3952.5 4115.8 4123.6 4138.0 4149.3 4150.9 3509.3 4129.8 4133.5 4137.0 4162.0 3752.2 3910.9 4031.4 4033.6 4147.4 3011.9 3193.0 3594.9 3649.4 3669.5 4244.0 4366.7 5159.3 5166.4 5181.0 3622.6 3983.2 4760.0 4779.3 4794.2 4908.6 5534.3 5563.9 5639.4 5662.2 3703.7 3825.8 3842.4 3847.0 3847.8 4611.0 5274.2 5744.6 5809.4 5877.7 3410.3 3841.0 3846.3 3853.6 3856.9 3301.4 3905.1 3908.6 3941.6 3942.8 4346.7 4422.4 4525.0 4595.0 4664.6 4723.6 4925.0 5017.9 5487.8 5652.9 1073.6 1182.7 1269.6 1272.0 1273.6 785.0 810.3 862.6 869.0 878.5 4299.2 4397.8 4419.6 4575.8 4887.2 4972.7 4997.5 5077.5 5517.6 5521.4 4366.5 4386.7 5059.7 5060.6 5081.4 2510.8 2633.6 2706.9 2895.8 2929.0 4903.5 4910.4 5438.6 5728.3 5729.7 4296.0 4994.4 5135.8 5646.3 5977.9 4963.6 4973.6 5891.0 5962.5 5985.4 5670.3 5724.9 5727.0 5741.3 5757.9 4897.9 5049.0 5074.6 5269.8 5636.0 4419.9 4758.4 4968.7 4985.9 5547.5 3590.9 3695.9 3753.9 3775.9 3844.2 5294.6 5307.9 5644.7 5791.0 5795.7 4682.9 5042.4 5244.7 5613.1 5646.8 4479.7 4690.4 5597.4 5611.3 5619.2 4858.2 5506.9 5515.5 5521.6 5550.3 5416.2 5583.6 5602.0 5616.7 5661.6 4432.3 5013.0 5489.3 5517.8 5536.9 20:11:02 INFO - PROCESS | 1649 | 20:11:02 INFO - PROCESS | 1649 | [#5] yui.html Cycles:180 Average:20091.18 Median:26834.52 stddev:29147.53 (108.6%) stddev-sans-first:29192.87 20:11:02 INFO - PROCESS | 1649 | Values: 683.9 696.3 744.4 783.3 800.8 12023.4 15562.3 15579.4 15613.5 15625.2 14315.6 14438.9 14452.5 14457.5 14457.6 694.4 714.2 854.9 875.4 879.2 863.0 870.1 872.2 879.0 881.1 927.9 928.5 929.1 929.6 933.1 922.0 923.0 923.3 924.8 925.0 533.8 551.5 664.6 681.2 683.2 14945.1 14977.4 14980.5 15013.0 15027.6 13325.3 13415.9 13431.3 13433.3 13468.5 12032.3 12135.9 12188.5 12332.3 12700.0 3797.3 4307.4 4375.0 4426.9 4494.7 80032.0 98202.5 101700.0 102455.5 104057.5 9863.7 10395.2 10543.7 10914.8 11069.1 20545.4 20786.5 20986.0 21018.6 21062.2 7278.6 7338.2 7339.8 7419.7 7461.8 4094.6 4180.7 4204.7 4223.2 4345.4 3329.8 3355.8 3433.9 3434.0 3435.2 591.1 630.0 634.3 682.1 706.7 626.2 629.2 650.2 652.7 656.6 6982.0 7845.4 9073.7 9486.3 9717.5 472.4 569.6 571.8 572.2 583.3 4215.0 4476.9 4564.2 4594.0 4659.0 89094.0 89508.6 95762.5 102527.5 104420.0 89567.6 90890.9 91509.5 91658.5 93192.5 82018.0 82558.0 83331.6 83402.6 83469.6 8144.2 9022.5 9109.9 9116.8 9136.5 51456.5 52876.0 53059.7 53135.2 54315.7 70626.0 72691.3 72984.0 73847.0 74251.0 11457.0 11547.4 11604.7 11983.2 12152.7 12919.8 12937.2 13055.0 13069.2 13331.1 11948.3 11969.3 12210.2 12212.3 12582.6 11799.6 12017.6 12025.3 12136.9 12422.0 11405.9 11772.2 11826.7 12149.8 12413.6 9746.0 9839.9 9903.2 10142.6 10144.7 8198.2 9060.7 9180.7 9684.7 9721.0 20:11:02 INFO - PROCESS | 1649 | -------- Summary: end -------- 20:11:02 INFO - PROCESS | 1649 | 20:11:02 INFO - TEST-INFO | 1649: exit 0 20:11:02 INFO - TEST-OK | dromaeo_css | took 1119681ms 20:11:02 INFO - TEST-START | kraken 20:11:02 INFO - operating with platform_type : mac_ 20:11:02 INFO - Initialising browser for kraken test... 20:11:02 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/tmpyC2DW2/profile http://localhost:49242/getInfo.html) 20:11:03 INFO - PROCESS | 1654 | 1483503063216 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4901 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2060 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3756 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2789 < callProvider()@resource://gre/modules/AddonManager.jsm:260 < _startProvider()@resource://gre/modules/AddonManager.jsm:755 < startup()@resource://gre/modules/AddonManager.jsm:937 < startup()@resource://gre/modules/AddonManager.jsm:2886 < observe()@resource://gre/components/addonManager.js:65 20:11:05 INFO - PROCESS | 1654 | __metrics Screen width/height:1600/1200 20:11:05 INFO - PROCESS | 1654 | colorDepth:24 20:11:05 INFO - PROCESS | 1654 | Browser inner width/height: 1024/689 20:11:05 INFO - PROCESS | 1654 | __metrics 20:11:05 INFO - PROCESS | 1654 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 20:11:05 INFO - PROCESS | 1654 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:11:06 INFO - TEST-INFO | 1654: exit 0 20:11:06 INFO - Browser initialized. 20:11:06 INFO - Running cycle 1/1 for kraken test... 20:11:06 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/tmpyC2DW2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:11:18 INFO - PROCESS | 1655 | RSS: Main: 224555008 20:11:18 INFO - PROCESS | 1655 | 20:11:20 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection) 20:11:21 INFO - PROCESS | 1655 | RSS: Main: 286150656 20:11:21 INFO - PROCESS | 1655 | 20:11:24 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-dft) 20:11:25 INFO - PROCESS | 1655 | RSS: Main: 289525760 20:11:25 INFO - PROCESS | 1655 | 20:11:28 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-fft) 20:11:29 INFO - PROCESS | 1655 | RSS: Main: 382656512 20:11:29 INFO - PROCESS | 1655 | 20:11:31 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator) 20:11:31 INFO - PROCESS | 1655 | RSS: Main: 283545600 20:11:31 INFO - PROCESS | 1655 | 20:11:33 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:11:34 INFO - PROCESS | 1655 | RSS: Main: 288288768 20:11:34 INFO - PROCESS | 1655 | 20:11:36 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom) 20:11:37 INFO - PROCESS | 1655 | RSS: Main: 330153984 20:11:37 INFO - PROCESS | 1655 | 20:11:40 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate) 20:11:41 INFO - PROCESS | 1655 | RSS: Main: 332558336 20:11:41 INFO - PROCESS | 1655 | 20:11:43 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial) 20:11:44 INFO - PROCESS | 1655 | RSS: Main: 330309632 20:11:44 INFO - PROCESS | 1655 | 20:11:46 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:11:46 INFO - PROCESS | 1655 | RSS: Main: 304205824 20:11:46 INFO - PROCESS | 1655 | 20:11:48 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:11:49 INFO - PROCESS | 1655 | RSS: Main: 303964160 20:11:49 INFO - PROCESS | 1655 | 20:11:51 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:11:52 INFO - PROCESS | 1655 | RSS: Main: 307826688 20:11:52 INFO - PROCESS | 1655 | 20:11:54 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:11:55 INFO - PROCESS | 1655 | RSS: Main: 325853184 20:11:55 INFO - PROCESS | 1655 | 20:11:58 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:11:59 INFO - PROCESS | 1655 | RSS: Main: 320262144 20:11:59 INFO - PROCESS | 1655 | 20:12:01 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49242/tests/kraken/driver.html?testName=ai-astar) 20:12:01 INFO - PROCESS | 1655 | RSS: Main: 364503040 20:12:01 INFO - PROCESS | 1655 | 20:12:01 INFO - PROCESS | 1655 | __start_tp_report 20:12:01 INFO - PROCESS | 1655 | _x_x_mozilla_page_load 20:12:01 INFO - PROCESS | 1655 | _x_x_mozilla_page_load_details 20:12:01 INFO - PROCESS | 1655 | |i|pagename|runs| 20:12:01 INFO - PROCESS | 1655 | |0;ai-astar;103;115;116;115;115;115;115;114;114;115 20:12:01 INFO - PROCESS | 1655 | |1;audio-beat-detection;140;112;109;157;145;145;123;108;126;114 20:12:01 INFO - PROCESS | 1655 | |2;audio-dft;187;183;186;185;184;181;198;183;183;187 20:12:01 INFO - PROCESS | 1655 | |3;audio-fft;80;58;58;88;60;59;60;58;74;60 20:12:01 INFO - PROCESS | 1655 | |4;audio-oscillator;89;69;86;68;69;68;83;68;69;85 20:12:01 INFO - PROCESS | 1655 | |5;imaging-gaussian-blur;87;85;88;85;88;84;88;85;88;84 20:12:01 INFO - PROCESS | 1655 | |6;imaging-darkroom;95;94;94;94;94;94;94;95;93;94 20:12:01 INFO - PROCESS | 1655 | |7;imaging-desaturate;85;87;87;85;88;87;83;88;85;86 20:12:01 INFO - PROCESS | 1655 | |8;json-parse-financial;54;54;55;53;54;55;55;55;55;54 20:12:01 INFO - PROCESS | 1655 | |9;json-stringify-tinderbox;67;74;69;76;71;69;70;71;71;72 20:12:01 INFO - PROCESS | 1655 | |10;stanford-crypto-aes;86;84;87;87;87;85;87;85;115;111 20:12:01 INFO - PROCESS | 1655 | |11;stanford-crypto-ccm;141;140;139;138;138;137;148;149;210;149 20:12:01 INFO - PROCESS | 1655 | |12;stanford-crypto-pbkdf2;170;171;175;171;172;173;173;171;171;172 20:12:01 INFO - PROCESS | 1655 | |13;stanford-crypto-sha256-iterative;68;67;66;66;64;69;66;66;65;66 20:12:01 INFO - PROCESS | 1655 | __end_tp_report 20:12:01 INFO - PROCESS | 1655 | __start_cc_report 20:12:01 INFO - PROCESS | 1655 | _x_x_mozilla_cycle_collect,1971 20:12:01 INFO - PROCESS | 1655 | __end_cc_report 20:12:01 INFO - PROCESS | 1655 | __startTimestamp1483503121270__endTimestamp 20:12:01 INFO - PROCESS | 1655 | 20:12:01 INFO - PROCESS | 1655 | ------- Summary: start ------- 20:12:01 INFO - PROCESS | 1655 | Number of tests: 14 20:12:01 INFO - PROCESS | 1655 | 20:12:01 INFO - PROCESS | 1655 | [#0] ai-astar Cycles:10 Average:113.70 Median:115.00 stddev:3.80 (3.3%) stddev-sans-first:0.60 20:12:01 INFO - PROCESS | 1655 | Values: 103.0 115.0 116.0 115.0 115.0 115.0 115.0 114.0 114.0 115.0 20:12:01 INFO - PROCESS | 1655 | 20:12:01 INFO - PROCESS | 1655 | [#1] audio-beat-detection Cycles:10 Average:127.90 Median:133.00 stddev:17.65 (13.3%) stddev-sans-first:18.17 20:12:01 INFO - PROCESS | 1655 | Values: 140.0 112.0 109.0 157.0 145.0 145.0 123.0 108.0 126.0 114.0 20:12:01 INFO - PROCESS | 1655 | 20:12:01 INFO - PROCESS | 1655 | [#2] audio-dft Cycles:10 Average:185.70 Median:185.50 stddev:4.74 (2.6%) stddev-sans-first:5.00 20:12:01 INFO - PROCESS | 1655 | Values: 187.0 183.0 186.0 185.0 184.0 181.0 198.0 183.0 183.0 187.0 20:12:01 INFO - PROCESS | 1655 | 20:12:01 INFO - PROCESS | 1655 | [#3] audio-fft Cycles:10 Average:65.50 Median:60.00 stddev:11.01 (18.3%) stddev-sans-first:10.35 20:12:01 INFO - PROCESS | 1655 | Values: 80.0 58.0 58.0 88.0 60.0 59.0 60.0 58.0 74.0 60.0 20:12:01 INFO - PROCESS | 1655 | 20:12:01 INFO - PROCESS | 1655 | [#4] audio-oscillator Cycles:10 Average:75.40 Median:76.00 stddev:9.03 (11.9%) stddev-sans-first:8.13 20:12:01 INFO - PROCESS | 1655 | Values: 89.0 69.0 86.0 68.0 69.0 68.0 83.0 68.0 69.0 85.0 20:12:01 INFO - PROCESS | 1655 | 20:12:01 INFO - PROCESS | 1655 | [#5] imaging-gaussian-blur Cycles:10 Average:86.20 Median:87.50 stddev:1.75 (2.0%) stddev-sans-first:1.83 20:12:01 INFO - PROCESS | 1655 | Values: 87.0 85.0 88.0 85.0 88.0 84.0 88.0 85.0 88.0 84.0 20:12:01 INFO - PROCESS | 1655 | 20:12:01 INFO - PROCESS | 1655 | [#6] imaging-darkroom Cycles:10 Average:94.10 Median:94.00 stddev:0.57 (0.6%) stddev-sans-first:0.50 20:12:01 INFO - PROCESS | 1655 | Values: 95.0 94.0 94.0 94.0 94.0 94.0 94.0 95.0 93.0 94.0 20:12:01 INFO - PROCESS | 1655 | 20:12:01 INFO - PROCESS | 1655 | [#7] imaging-desaturate Cycles:10 Average:86.10 Median:87.00 stddev:1.60 (1.8%) stddev-sans-first:1.64 20:12:01 INFO - PROCESS | 1655 | Values: 85.0 87.0 87.0 85.0 88.0 87.0 83.0 88.0 85.0 86.0 20:12:01 INFO - PROCESS | 1655 | 20:12:01 INFO - PROCESS | 1655 | [#8] json-parse-financial Cycles:10 Average:54.40 Median:55.00 stddev:0.70 (1.3%) stddev-sans-first:0.73 20:12:01 INFO - PROCESS | 1655 | Values: 54.0 54.0 55.0 53.0 54.0 55.0 55.0 55.0 55.0 54.0 20:12:01 INFO - PROCESS | 1655 | 20:12:01 INFO - PROCESS | 1655 | [#9] json-stringify-tinderbox Cycles:10 Average:71.00 Median:71.00 stddev:2.58 (3.6%) stddev-sans-first:2.30 20:12:01 INFO - PROCESS | 1655 | Values: 67.0 74.0 69.0 76.0 71.0 69.0 70.0 71.0 71.0 72.0 20:12:01 INFO - PROCESS | 1655 | 20:12:01 INFO - PROCESS | 1655 | [#10] stanford-crypto-aes Cycles:10 Average:91.40 Median:86.50 stddev:11.47 (13.3%) stddev-sans-first:12.00 20:12:01 INFO - PROCESS | 1655 | Values: 86.0 84.0 87.0 87.0 87.0 85.0 87.0 85.0 115.0 111.0 20:12:01 INFO - PROCESS | 1655 | 20:12:01 INFO - PROCESS | 1655 | [#11] stanford-crypto-ccm Cycles:10 Average:148.90 Median:144.50 stddev:21.99 (15.2%) stddev-sans-first:23.14 20:12:01 INFO - PROCESS | 1655 | Values: 141.0 140.0 139.0 138.0 138.0 137.0 148.0 149.0 210.0 149.0 20:12:01 INFO - PROCESS | 1655 | 20:12:01 INFO - PROCESS | 1655 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.90 Median:172.00 stddev:1.45 (0.8%) stddev-sans-first:1.36 20:12:01 INFO - PROCESS | 1655 | Values: 170.0 171.0 175.0 171.0 172.0 173.0 173.0 171.0 171.0 172.0 20:12:01 INFO - PROCESS | 1655 | 20:12:01 INFO - PROCESS | 1655 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.30 Median:66.00 stddev:1.42 (2.1%) stddev-sans-first:1.36 20:12:01 INFO - PROCESS | 1655 | Values: 68.0 67.0 66.0 66.0 64.0 69.0 66.0 66.0 65.0 66.0 20:12:01 INFO - PROCESS | 1655 | -------- Summary: end -------- 20:12:01 INFO - PROCESS | 1655 | 20:12:02 INFO - TEST-INFO | 1655: exit 0 20:12:02 INFO - TEST-OK | kraken | took 59450ms 20:12:02 INFO - SUITE-END | took 1179s 20:12:02 INFO - Completed test suite (00:19:39) 20:12:02 INFO - javascript benchmark 20:12:02 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3204.359128174365, 3333.1000629829955, 3372.15696075981, 3387.983060084699, 3426.3147370525903, 4634.235351167062, 5642.661440313584, 5665.716714164296, 5764.913526297109, 5769.942300576999, 5640.238567793347, 5673.659580425177, 5682.318121825387, 5682.659040457575, 5684.488396044355, 5142.794288228476, 5146.948530514703, 5167.0, 5182.974085129569, 5213.687178769286, 5211.557017653504, 5222.451642577532, 5229.869253268661, 5232.3459567554055, 5234.345706786652, 4340.978295108536, 5229.215617657363, 5236.1098613235845, 5246.50158234969, 5251.133562962106, 5231.869203269911, 5235.0, 5238.895222095537, 5239.397469291042, 5250.474952504738, 5091.694498330112, 5162.638615296931, 5174.353205849269, 5204.453532379083, 5210.0, 5708.571857110734, 5710.457506536873, 5750.5687073469235, 5801.53587712982, 5815.941840581582, 6213.844653883644, 6217.502599792051, 6227.750889964396, 6246.937530624681, 6248.0627905814035, 6118.537326064743, 6140.933729673037, 6177.011678131522, 6189.442950134509, 6238.812835614939, 5740.339860916049, 5765.971170144123, 5799.88400231993, 5815.912761308584, 5867.501262392744, 6209.991979715144, 6249.187605611241, 6256.124142620037, 6293.653849038347, 6297.811065668037, 5940.019896716994, 6147.84630384244, 6149.200603921461, 6157.876842463215, 6172.783952561639, 5115.923261151086, 6113.780377411107, 6139.969300153471, 6213.906791398133, 6223.408776166257, 6165.229346331708, 6188.288346840171, 6216.968915155486, 6226.439620434183, 6246.187995560548, 6253.53098517613, 6261.060840873814, 6263.123162757218, 6263.217097862767, 6274.498040156868, 4834.154023046023, 4861.927071093936, 4876.4879687633, 4943.060818444484, 4972.254161875675, 4394.582514661102, 4438.1567502174485, 4440.7335559866515, 4467.2182368086105, 4470.307102399133, 5923.881522369528, 5925.05199168131, 5930.614510056919, 5933.970330148321, 5936.5547583931375, 6114.236541168675, 6284.905726414107, 6300.338464461257, 6321.525885558602, 6334.398232167937, 5733.569982251264, 5783.363829978699, 5826.970865145732, 5888.998870192077, 5894.793682221101, 5264.15773476242, 5402.850205007703, 5418.051840927822, 5422.864428389322, 5425.077736784756, 6218.409251121137, 6252.937470625236, 6268.937310626927, 6309.463695585927, 6309.589876658002, 5220.7650655721345, 6226.0, 6236.095766113979, 6313.779017734358, 6316.084167795736, 6182.783602573888, 6196.969015154896, 6208.503319734501, 6227.657478838707, 6289.433950944437, 6227.470664993526, 6237.438630523275, 6247.68761561911, 6250.624962502083, 6254.4996400286955, 6289.559730818982, 6315.400036996478, 6322.968385158045, 6328.651924144032, 6339.175907132043, 5916.408359164049, 5936.851578710567, 5989.221401217814, 6088.238970128734, 6156.630602163885, 5479.698616575967, 5770.499670085892, 6122.855605810045, 6145.754169833335, 6157.0, 5894.941050589439, 5909.231799866162, 5929.792457263975, 5947.078202878561, 5963.791267305797, 5946.5540084494705, 6014.72933717975, 6027.78902738402, 6135.509159267337, 6151.815445536641, 5882.941170588239, 5943.851403714942, 5960.254968128984, 5989.880202395841, 6001.7299221536005], "value": 5682.945355738022, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [494.96535242533014, 500.57701242450116, 500.9474005229451, 508.9312942752727, 510.2754089193344, 31784.682153178463, 39802.00494987627, 41254.38118428218, 41313.38029929546, 43577.564224357746, 42614.786926065324, 42857.0, 43003.354949675704, 43049.0, 43170.0, 9395.154436100749, 9577.80844383112, 9581.281403894702, 9583.089606487372, 9593.184579310768, 8447.577621118919, 8615.224629783319, 11278.943605282004, 11411.885881141165, 11489.0, 11456.365036041716, 11516.193866429354, 11524.711882202928, 11536.0, 11545.134114941371, 8516.403851730382, 11497.367644779575, 11543.595974140948, 11553.075753939665, 11557.133215008907, 8401.831963360759, 8597.527136007517, 8598.613062412143, 8614.913850861536, 11052.889471105267, 39398.81803545898, 40083.799581001906, 40085.398719019235, 40086.39870401946, 40148.0, 3952.505936757905, 3965.5637879833193, 3977.880663580097, 4010.8997275068064, 4075.633193012643, 29382.85308573465, 30106.54840177399, 30579.388412231852, 30635.711000459953, 30648.846755766303, 3127.2494601295753, 3247.983760081208, 3263.5757351544385, 3313.6686331366914, 3321.96678033219, 477011.0, 477804.0, 479636.0, 481051.9999999965, 483637.9999999965, 58477.415225848046, 60443.0, 60646.69676651589, 60717.0, 60941.0, 55044.44955550393, 63228.68385658042, 63324.68337658282, 63370.0, 63692.04461933075, 14489.855101449062, 14785.482508112165, 15385.769213461806, 15893.841061589468, 15905.522834314988, 10639.946800265949, 10787.730306742395, 12235.204711693712, 12331.951784098452, 12337.444814983348, 14991.250437478082, 16470.341186352653, 16516.75224871628, 16606.916965415337, 16960.406385776438, 5195.298634684269, 6178.505719542426, 6189.752409903554, 6220.720067596969, 6266.811995640047, 773.89552410425, 836.1513064239801, 874.2481465939287, 877.5217506458995, 882.4572887674162, 27176.1847144586, 29529.704702952695, 29796.851015745215, 30231.0, 30380.5442918352, 1032.5972870580479, 1063.8404239364002, 1074.4949873559415, 1078.8058149533003, 1167.3521195736278, 12517.749645007048, 12811.935940320238, 14870.628234294229, 15376.15431151275, 15676.68646627061, 245210.0, 245400.0, 245905.0, 246637.30044049353, 246690.0, 59544.40455595475, 59573.40426595765, 59698.40301597015, 59846.0, 59860.40139598635, 1350.7298540291981, 1404.529482623312, 1415.3843078260988, 1417.1567917089315, 1422.7723564229673, 28509.144725658265, 29143.834246629896, 29240.853795730884, 29852.402951941272, 30061.398772024, 50634.49365506298, 50996.0, 50997.7450112747, 51133.7443312781, 51156.0, 530398.0, 534858.0, 535906.0, 536266.0, 536786.0, 38963.80518097391, 39398.212035759694, 39577.406338904366, 39681.254186788894, 39879.40180897231, 45655.77172114118, 45665.77167114143, 46107.0, 47222.0, 47511.76244118757, 26424.339391515365, 29804.403911922072, 34564.65435345614, 38110.0, 38475.0, 28088.157355279374, 33075.50386744249, 34007.0, 37820.05449863776, 38458.80770596129, 33678.49482257817, 34373.62505499752, 37409.4388584166, 37482.0, 40877.59122408857, 39541.80229098836, 39825.0, 40984.79507602443, 42148.94627634211, 42484.575154248065, 23392.76607233974, 31683.20791980127, 31702.524462133548, 31750.52374214435, 34164.633414663134], "value": 19525.674566454778, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [364.3387252137369, 379.97910114943664, 383.8483798899435, 388.2681146039714, 412.9876103716888, 14843.332050057747, 15431.922840385796, 18928.337508187215, 20806.0, 20845.374638760863, 19556.41330760076, 20108.798912010876, 20288.0, 20382.898085509587, 20427.285045023425, 5242.685438873661, 5438.320209973754, 5473.781048758065, 5474.178873169017, 5500.4499550045075, 3815.771053736785, 4122.525909520414, 5355.0896347621, 5418.91871621926, 6058.212432383806, 6078.149059131725, 6092.756289748405, 6094.207752992105, 6098.176746139259, 6112.969435152818, 5216.608754343401, 5383.300170977787, 5894.734736936805, 6083.117947897552, 6085.087236914476, 3734.5705243897028, 3907.8436862525464, 3920.862769803072, 5377.542908852753, 5728.742206600713, 19349.12928918202, 24117.0, 24779.637119958395, 24809.50380992388, 24979.250622481355, 2162.3512946116102, 2418.7823095921276, 2624.1304391157955, 2648.4570663014038, 2658.2655288747883, 8237.752867413927, 9661.806763864683, 9729.027097290214, 9846.60613575456, 10250.17998560121, 1782.5008997480727, 1801.072447689428, 1820.6085691576375, 1840.3650740494509, 1857.8420834229107, 96408.07183856354, 98680.0, 112842.0, 119039.0, 119810.4009479947, 9578.760530986781, 11024.779504409868, 11078.501467433985, 11352.432378381049, 12068.939655301667, 14453.277336133151, 14694.338754756061, 14918.0, 14972.326245318987, 14989.775153372708, 8235.876461853077, 8415.116412776693, 8420.326373890073, 8530.530820804915, 8577.0, 8023.438359314909, 8095.0, 8155.959220203979, 8288.171182881664, 8331.000279966443, 4487.326900964816, 4507.774611269423, 4568.885777855514, 4633.49031606523, 4675.345451636774, 1922.5962547864826, 1970.103602860695, 1990.4327266728912, 1991.2333751505485, 1993.3222704280324, 939.3565407695613, 942.8302905476943, 949.2975198353168, 972.295086062599, 990.3859607043535, 18428.170732317078, 19463.610727785366, 19579.0, 19626.803731962496, 19916.30292939769, 994.8358520844124, 1005.3415013166388, 1008.5562352564995, 1009.8586197932295, 1013.3919648211014, 9153.496557689257, 10024.348417352849, 10357.0, 10373.636922707668, 10401.323913945569, 499461.56192037655, 509372.0, 510366.0, 516652.0, 517288.0, 103159.4842025785, 104768.0, 105418.47290763498, 112199.31701024491, 121801.78198218082, 1000.3897622450445, 1001.9849702254472, 1024.4416792847767, 1027.799579082072, 1028.388109075099, 13118.573063983195, 16672.083035433167, 16772.32910683559, 17182.914085429493, 17542.64914701673, 53218.0, 53655.6586085351, 54421.72789136187, 54608.726956366554, 55321.616959576335, 52803.73598131985, 53597.73201133969, 76678.2332176671, 77055.45889082299, 78562.0, 5837.387074357302, 7400.259974002557, 7404.0, 7478.551286922802, 7598.392128629807, 9649.276304277348, 12176.33030183313, 12316.261024338568, 12406.875931240933, 12578.496860125493, 8849.0, 10989.835152472559, 11138.3873886937, 11182.440877955907, 11456.0, 7141.85716285661, 9026.413283136575, 9136.994930557501, 10844.783104338027, 11204.607838725606, 9322.766930826783, 9980.650677226262, 11240.26938249044, 11378.601748938747, 11557.937146655975, 8056.355491560778, 8185.795355115932, 8192.959035204785, 8339.83320333602, 8353.582320884054, 8172.632231549714, 9605.615775368908, 11236.876312368699, 11290.096792256763, 11330.716732081764], "value": 9106.663565562045, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1430.4135304525146, 1437.417845772462, 1453.070035177486, 1454.2510607037377, 1455.6215384000163, 6949.895751563724, 7368.115826100862, 7867.7639670809895, 8250.91749082509, 8256.587170641467, 7470.402367810576, 7639.465237433382, 7819.921800781991, 7867.291943725064, 8037.156098609635, 2277.9886100569474, 2313.2135074074813, 2313.2135074074813, 2313.6529520571967, 2314.36355002374, 2233.564454931289, 2314.4445333120098, 2327.3948773318975, 2328.1851352026824, 2332.9300120996313, 2015.1435639853078, 2088.467440802601, 2089.665653495441, 2222.3666255117296, 2306.5041016181517, 2295.483516208856, 2310.8151347892126, 2313.9537209255805, 2317.5133222023396, 2323.303009097281, 2091.633964056299, 2261.5187052480487, 2299.7930186283315, 2307.3654744945106, 2338.707661542307, 8069.031716194036, 8091.312238459679, 8182.427230093896, 8185.058718247418, 8191.590420478952, 4986.4265609455015, 5154.0, 5162.174052151675, 5163.509466600667, 5176.327077479941, 3247.8538465769097, 3265.2163480764684, 3379.7972121672533, 3381.5942086949476, 3448.0, 1175.741336905888, 1199.3343694249688, 1223.1437993404652, 1243.776120298355, 1244.4897591987242, 10569.894301056967, 10636.0, 10666.466676666136, 10670.573177072985, 10712.517936692868, 3994.820233089518, 4561.2473941799235, 4611.538846115362, 4619.953800461953, 4645.303204519281, 9125.908740912639, 9201.49391783445, 9216.0, 9219.723408297761, 9227.446353218695, 5048.974755126201, 5148.2277658350795, 5149.897002060013, 5154.226865970135, 5160.535551800356, 3939.8227079781477, 4588.770561471913, 4669.696469729524, 4678.976605116953, 4723.267893476448, 3715.4426835974277, 3787.905302367408, 3814.4278358246484, 3815.523059617548, 3834.7507412018685, 1252.3612957391613, 1254.219009007212, 1257.8742125787348, 1268.7462507498537, 1270.9364531773558, 722.8156820010863, 723.7720118162762, 766.8696321625225, 791.4736700094488, 829.8713699376606, 4893.828715994923, 5251.684898906002, 5500.0399712207145, 5563.860903477364, 5612.971935140298, 976.5312649927932, 988.7083310423442, 990.8506132885818, 998.5257002923698, 1031.0668844695563, 3641.4901913732097, 3646.908827279339, 3652.739804767351, 3657.65252301031, 3752.043228976648, 9476.95261523688, 9701.70894873155, 9784.902150978542, 9788.412695238309, 9839.458829764288, 9760.609575617038, 9761.36551124189, 9768.21854251658, 9768.413895166314, 9784.608615655436, 7630.96184519085, 7747.690092396241, 7762.146163922077, 7763.029621297338, 7765.3011228988535, 5407.134858422633, 5494.505494505353, 5513.779448822002, 5699.601027928005, 5732.684702341411, 9111.95444022802, 9155.0, 9265.305102117234, 9291.767705807139, 9299.535023248945, 8586.871196931927, 8853.867191991996, 9287.164155226063, 9332.346735728433, 9384.530773461436, 6974.407175390149, 7005.369516743517, 7049.823754405976, 7065.434765218667, 7093.964530177316, 7644.847103058019, 7656.0, 7749.147593764568, 7749.806254843674, 7755.069391673035, 6787.117674702257, 6961.199462061928, 6971.825704357431, 7040.683169257498, 7082.397996170177, 6838.589684618939, 6845.965770171118, 6877.621730804653, 6918.135233095863, 6955.756548520778, 6117.632942023492, 6230.345813689679, 6312.14786003902, 6331.0, 6332.113504109337, 4180.226658068267, 4653.208954477679, 4713.2458806590785, 4750.358701575381, 4754.857354279377, 4707.905841883212, 4798.784054717616, 4807.855764327076, 4848.224284114524, 4917.336159618549], "value": 4328.030261824087, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [353.1189681744049, 364.17877685818473, 368.382958544438, 373.352233874228, 373.47526724951433, 4044.817983190756, 4446.444194475691, 4913.108453245504, 4968.882001549649, 4980.626453016021, 4454.888627784307, 5274.235235890803, 5282.471752824716, 5293.735313234342, 5315.591368287407, 3348.983255083733, 3465.4108801503803, 3614.7288953328475, 3901.609839016097, 3952.4664170336923, 4115.835366585333, 4123.628873401393, 4138.0, 4149.315362965107, 4150.875473735793, 3509.2805974775238, 4129.772862492562, 4133.462649855514, 4137.0, 4161.9803148228575, 3752.1745216052423, 3910.9467529546932, 4031.395290706388, 4033.576474470197, 4147.440095587087, 3011.906724826247, 3192.9680703192903, 3594.867616700757, 3649.357788994963, 3669.5230719264655, 4243.957560424387, 4366.716163449398, 5159.329287192679, 5166.383531527946, 5180.963807238567, 3622.5652921649307, 3983.24318379507, 4759.976200118977, 4779.330893674888, 4794.232922732346, 4908.582770464514, 5534.280543529356, 5563.860903477405, 5639.379668236491, 5662.150677398382, 3703.666669999713, 3825.827837747308, 3842.4236364545263, 3846.9997800572055, 3847.8076096195077, 4611.031683346501, 5274.235235890851, 5744.626599271076, 5809.3609702932645, 5877.67672777997, 3410.334984677964, 3841.0, 3846.3268927938057, 3853.5953724859046, 3856.903577410531, 3301.405746965569, 3905.062784931596, 3908.5895980922164, 3941.5664276929515, 3942.802859856996, 4346.67399944999, 4422.358757980075, 4524.977375113103, 4594.977025114853, 4664.55686709762, 4723.5984941279685, 4925.039617274597, 5017.874553136127, 5487.835364939058, 5652.9152062719095, 1073.5974009746344, 1182.7344741126913, 1269.6127681057367, 1272.0396100943728, 1273.5924504158766, 784.9882251766228, 810.2707563193055, 862.6247582301719, 868.9695860644847, 878.5431575580662, 4299.172851538084, 4397.834573837936, 4419.602235798793, 4575.771211439414, 4887.216166099348, 4972.701637901738, 4997.450280469146, 5077.521897500151, 5517.5585953123655, 5521.365043020024, 4366.497852746911, 4386.731884745885, 5059.747012649426, 5060.55882000236, 5081.441041485434, 2510.7740303372784, 2633.552296109666, 2706.932326691809, 2895.7683385329124, 2929.0041385928885, 4903.485134060872, 4910.410750709938, 5438.619296649196, 5728.255326807572, 5729.742161602738, 4295.978520107379, 4994.425641051325, 5135.845924622267, 5646.322441307069, 5977.880442391215, 4963.585378167205, 4973.55238028572, 5890.998530249868, 5962.493188079062, 5985.431384018511, 5670.319561652628, 5724.942750572608, 5726.997775389374, 5741.282339707536, 5757.8560535986935, 4897.918534010529, 5048.974755126201, 5074.5686616637995, 5269.785430887079, 5636.0, 4419.867403977886, 4758.405199350082, 4968.652194346361, 4985.925211121764, 5547.452547452547, 3590.9102272443397, 3695.8933407957206, 3753.9249215016093, 3775.867844625425, 3844.2119365530557, 5294.576433885357, 5307.920381194208, 5644.6613203207935, 5790.971045144747, 5795.7391917362975, 4682.859514214579, 5042.369703787027, 5244.685318880879, 5613.129964855454, 5646.781334639216, 4479.721865618391, 4690.366800481891, 5597.44025597437, 5611.298587676541, 5619.185218143264, 4858.246971719389, 5506.862328441821, 5515.531179849597, 5521.558275337883, 5550.306211723535, 5416.214648875812, 5583.55331573465, 5601.983286017112, 5616.747246373841, 5661.631993920382, 4432.321173425984, 5012.997400519837, 5489.258950041693, 5517.779288828402, 5536.916946245729], "value": 3972.513042097868, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [683.9247682754896, 696.3245651717838, 744.3747252308063, 783.2627045309572, 800.7998000499875, 12023.398830058506, 15562.299696513655, 15579.376824927018, 15613.531594052169, 15625.218739063044, 14315.570532884005, 14438.855611443909, 14452.494162704308, 14457.493987710432, 14457.566273011826, 694.4305669351135, 714.2107970692388, 854.8931383577053, 875.40035075973, 879.2087121590588, 863.0075413274722, 870.0820634230884, 872.2498651160029, 879.0, 881.0748713850463, 927.8654595083709, 928.5403725156057, 929.1359036096428, 929.5816882402912, 933.0576118120713, 922.0134456131941, 922.9907700923039, 923.3213588012806, 924.8335299646061, 924.9722508324761, 533.8371796602034, 551.5174222555264, 664.6145235763294, 681.2472218198868, 683.2313647146959, 14945.103293802298, 14977.400903963937, 14980.47568335114, 15013.0, 15027.624309392244, 13325.267110308929, 13415.86584134156, 13431.328433578283, 13433.261170635611, 13468.5286014989, 12032.27806331623, 12135.878641213563, 12188.51245950161, 12332.260064396165, 12700.047496437712, 3797.3354662933875, 4307.418498502693, 4374.950011997098, 4426.933595996063, 4494.73031618104, 80032.0, 98202.50898745532, 101700.0, 102455.48772256241, 104057.47971260171, 9863.704088877344, 10395.220358473145, 10543.683689489328, 10914.836277455686, 11069.114470842474, 20545.383638490868, 20786.48033799137, 20986.0, 21018.579628407348, 21062.157513699585, 7278.599677017709, 7338.229485903903, 7339.77980660581, 7419.740309089156, 7461.813454663677, 4094.6314831665295, 4180.702688447604, 4204.66362690984, 4223.1975924574235, 4345.413369195118, 3329.8002119872885, 3355.798652080883, 3433.914152146216, 3434.0, 3435.209901722618, 591.1428428778203, 629.9559030867795, 634.346622978333, 682.068975847964, 706.7243774927782, 626.1922120464641, 629.1632129268063, 650.1580453312971, 652.7160685101995, 656.5699466849221, 6982.022516847645, 7845.37237021037, 9073.729677845107, 9486.256642373619, 9717.465539395402, 472.433080303637, 569.6268943841791, 571.7912961768924, 572.201778518972, 583.2592607388593, 4214.957850421518, 4476.932846007313, 4564.2012647786505, 4594.012287358167, 4659.0, 89094.0, 89508.5524572386, 95762.52118739362, 102527.48736256271, 104419.95580044255, 89567.55216224007, 90890.91090890858, 91509.5424522873, 91658.54170729103, 93192.53403733074, 82018.0, 82558.0, 83331.5833420829, 83402.58298708468, 83469.58265208635, 8144.185581441809, 9022.548872556477, 9109.863352049591, 9116.772080698036, 9136.543172841464, 51456.48543514517, 52876.0, 53059.67350816106, 53135.20297195468, 54315.72842135764, 70626.0, 72691.27308726845, 72984.0, 73847.0, 74251.0, 11457.0, 11547.364894930859, 11604.6518604442, 11983.221090629082, 12152.696182595506, 12919.806202907152, 12937.22376657366, 13055.0, 13069.15050521713, 13331.133476324008, 11948.283103013848, 11969.260039545588, 12210.206336588093, 12212.267263964191, 12582.559610413591, 11799.646010619696, 12017.63947081589, 12025.338606376734, 12136.939315303367, 12422.0, 11405.942970285096, 11772.234804737665, 11826.704332391759, 12149.817752733892, 12413.627591172279, 9745.951270243604, 9839.901600983898, 9903.158231550398, 10142.645007424704, 10144.695659130239, 8198.221168988937, 9060.682876099305, 9180.6786762463, 9684.70945871625, 9721.027897210222], "value": 7217.111055862247, "unit": "score"}], "name": "dromaeo_css", "value": 7074.856189809448, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 115.0, 116.0, 115.0, 115.0, 115.0, 115.0, 114.0, 114.0, 115.0], "value": 113.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 112.0, 109.0, 157.0, 145.0, 145.0, 123.0, 108.0, 126.0, 114.0], "value": 127.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 183.0, 186.0, 185.0, 184.0, 181.0, 198.0, 183.0, 183.0, 187.0], "value": 185.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 58.0, 58.0, 88.0, 60.0, 59.0, 60.0, 58.0, 74.0, 60.0], "value": 65.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 69.0, 86.0, 68.0, 69.0, 68.0, 83.0, 68.0, 69.0, 85.0], "value": 75.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 88.0, 85.0, 88.0, 84.0, 88.0, 85.0, 88.0, 84.0], "value": 86.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 95.0, 93.0, 94.0], "value": 94.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 87.0, 87.0, 85.0, 88.0, 87.0, 83.0, 88.0, 85.0, 86.0], "value": 86.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 55.0, 53.0, 54.0, 55.0, 55.0, 55.0, 55.0, 54.0], "value": 54.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 74.0, 69.0, 76.0, 71.0, 69.0, 70.0, 71.0, 71.0, 72.0], "value": 71.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 87.0, 87.0, 87.0, 85.0, 87.0, 85.0, 115.0, 111.0], "value": 91.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 140.0, 139.0, 138.0, 138.0, 137.0, 148.0, 149.0, 210.0, 149.0], "value": 148.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 171.0, 175.0, 171.0, 172.0, 173.0, 173.0, 171.0, 171.0, 172.0], "value": 171.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 66.0, 66.0, 64.0, 69.0, 66.0, 66.0, 65.0, 66.0], "value": 66.3, "unit": "score"}], "name": "kraken", "value": 1438.5000000000002, "alertThreshold": 2.0}]} 20:12:02 INFO - Return code: 0 20:12:02 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:12:02 INFO - # TBPL SUCCESS # 20:12:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:12:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:12:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:12:02 INFO - 2017-01-03 20:12:02.557 screenresolution[1659:10531] starting screenresolution argv=screenresolution get 20:12:02 INFO - 2017-01-03 20:12:02.559 screenresolution[1659:10531] Display 0: 1600x1200x32@60 20:12:02 INFO - 2017-01-03 20:12:02.566 screenresolution[1660:10534] starting screenresolution argv=screenresolution list 20:12:02 INFO - Available Modes on Display 0 20:12:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:12:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:12:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:12:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:12:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:12:02 INFO - Intel Iris: 20:12:02 INFO - Chipset Model: Intel Iris 20:12:02 INFO - Type: GPU 20:12:02 INFO - Bus: Built-In 20:12:02 INFO - VRAM (Dynamic, Max): 1536 MB 20:12:02 INFO - Vendor: Intel (0x8086) 20:12:02 INFO - Device ID: 0x0a2e 20:12:02 INFO - Revision ID: 0x0009 20:12:02 INFO - Displays: 20:12:02 INFO - Display: 20:12:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:12:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:12:02 INFO - Display Serial Number: 1600x1200 60 20:12:02 INFO - Main Display: Yes 20:12:02 INFO - Mirror: Off 20:12:02 INFO - Online: Yes 20:12:02 INFO - Rotation: Supported 20:12:02 INFO - Return code: 0 20:12:02 INFO - Running post-action listener: _resource_record_post_action 20:12:02 INFO - [mozharness: 2017-01-04 04:12:02.800894Z] Finished run-tests step (success) 20:12:02 INFO - Running post-run listener: _resource_record_post_run 20:12:02 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 32713728; Write bytes: 552041472; Read time: 305; Write time: 1601 20:12:02 INFO - TinderboxPrint: CPU usage
53.6% 20:12:02 INFO - TinderboxPrint: I/O read bytes / time
32,713,728 / 305 20:12:02 INFO - TinderboxPrint: I/O write bytes / time
552,041,472 / 1,601 20:12:02 INFO - TinderboxPrint: CPU idle
2,231.1 (46.4%) 20:12:02 INFO - TinderboxPrint: CPU system
962.2 (20.0%) 20:12:02 INFO - TinderboxPrint: CPU user
1,616.5 (33.6%) 20:12:02 INFO - TinderboxPrint: Swap in / out
277,770,240 / 0 20:12:02 INFO - install - Wall time: 23s; CPU: 54.0%; Read bytes: 239546880; Write bytes: 233416192; Read time: 17673; Write time: 456 20:12:02 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 30698496; Write bytes: 299197952; Read time: 284; Write time: 1100 20:12:03 INFO - Running post-run listener: _upload_blobber_files 20:12:03 INFO - Blob upload gear active. 20:12:03 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:12:03 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 20:12:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 20:12:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 20:12:03 INFO - (blobuploader) - INFO - Open directory for files ... 20:12:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 20:12:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:12:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:12:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 20:12:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:12:04 INFO - (blobuploader) - INFO - Done attempting. 20:12:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 20:12:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:12:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:12:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 20:12:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:12:05 INFO - (blobuploader) - INFO - Done attempting. 20:12:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 20:12:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:12:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:12:06 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 20:12:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:12:06 INFO - (blobuploader) - INFO - Done attempting. 20:12:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:12:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:12:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:12:08 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:12:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:12:08 INFO - (blobuploader) - INFO - Done attempting. 20:12:08 INFO - (blobuploader) - INFO - Iteration through files over. 20:12:08 INFO - Return code: 0 20:12:08 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:12:08 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:12:08 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eac1f0584cb84d4a15a89b2befdc6924dba84ace8fd2ca10f2dce5419f15cc5a262db4e208c08f5836cdf4d13703f971954ff6a21e3cbc3fffd04ae934061869", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3057487ead04c5446890c0b286e4dcaf6b26ffabd90d3baea9bb6a3def6870e5428eac712f55b62bbe48a58bd31b6ca934d5a00edece3135a47994c054265e28", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/46cb16286b3b86bd48959c60de321d11633afd175b0db5e64bb0d2a204722e3dfaf15fa6c1f03faeea3cf723b8bc79947e8d39c842ba48d40e2f3ba93355ffc8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d7e993b54a5a01f095465fa07d02d673019c102eaf542a52943302e9490539ef4e48129a9addd100b8f4e723311d8c402aec4731222a898d2dc3e0cd671ea384"} 20:12:08 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:12:08 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:12:08 INFO - Contents: 20:12:08 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eac1f0584cb84d4a15a89b2befdc6924dba84ace8fd2ca10f2dce5419f15cc5a262db4e208c08f5836cdf4d13703f971954ff6a21e3cbc3fffd04ae934061869", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3057487ead04c5446890c0b286e4dcaf6b26ffabd90d3baea9bb6a3def6870e5428eac712f55b62bbe48a58bd31b6ca934d5a00edece3135a47994c054265e28", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/46cb16286b3b86bd48959c60de321d11633afd175b0db5e64bb0d2a204722e3dfaf15fa6c1f03faeea3cf723b8bc79947e8d39c842ba48d40e2f3ba93355ffc8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d7e993b54a5a01f095465fa07d02d673019c102eaf542a52943302e9490539ef4e48129a9addd100b8f4e723311d8c402aec4731222a898d2dc3e0cd671ea384"} 20:12:08 INFO - Running post-run listener: copy_logs_to_upload_dir 20:12:08 INFO - Copying logs to upload dir... 20:12:08 INFO - mkdir: /builds/slave/test/build/upload/logs 20:12:08 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.331675 ========= master_lag: 0.67 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2017-01-03 20:12:09.094159) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 5 secs) (at 2017-01-03 20:12:09.098055) ========= 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.dTSKOfSnHe/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.j4It8B1yPl/Listeners TMPDIR=/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eac1f0584cb84d4a15a89b2befdc6924dba84ace8fd2ca10f2dce5419f15cc5a262db4e208c08f5836cdf4d13703f971954ff6a21e3cbc3fffd04ae934061869", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3057487ead04c5446890c0b286e4dcaf6b26ffabd90d3baea9bb6a3def6870e5428eac712f55b62bbe48a58bd31b6ca934d5a00edece3135a47994c054265e28", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/46cb16286b3b86bd48959c60de321d11633afd175b0db5e64bb0d2a204722e3dfaf15fa6c1f03faeea3cf723b8bc79947e8d39c842ba48d40e2f3ba93355ffc8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d7e993b54a5a01f095465fa07d02d673019c102eaf542a52943302e9490539ef4e48129a9addd100b8f4e723311d8c402aec4731222a898d2dc3e0cd671ea384"} build_url:https://queue.taskcluster.net/v1/task/bSBvFKjPTp-MUye0C2jRNQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011150 build_url: 'https://queue.taskcluster.net/v1/task/bSBvFKjPTp-MUye0C2jRNQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eac1f0584cb84d4a15a89b2befdc6924dba84ace8fd2ca10f2dce5419f15cc5a262db4e208c08f5836cdf4d13703f971954ff6a21e3cbc3fffd04ae934061869", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3057487ead04c5446890c0b286e4dcaf6b26ffabd90d3baea9bb6a3def6870e5428eac712f55b62bbe48a58bd31b6ca934d5a00edece3135a47994c054265e28", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/46cb16286b3b86bd48959c60de321d11633afd175b0db5e64bb0d2a204722e3dfaf15fa6c1f03faeea3cf723b8bc79947e8d39c842ba48d40e2f3ba93355ffc8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d7e993b54a5a01f095465fa07d02d673019c102eaf542a52943302e9490539ef4e48129a9addd100b8f4e723311d8c402aec4731222a898d2dc3e0cd671ea384"}' ========= master_lag: 5.90 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 5 secs) (at 2017-01-03 20:12:15.012698) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 1 secs) (at 2017-01-03 20:12:15.013016) ========= 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.dTSKOfSnHe/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.j4It8B1yPl/Listeners TMPDIR=/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/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.005147 ========= master_lag: 1.27 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 1 secs) (at 2017-01-03 20:12:16.289602) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-03 20:12:16.289993) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-03 20:12:17.116018) ========= ========= Total master_lag: 7.92 =========