builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0116 starttime: 1483532735.69 results: success (0) buildid: 20170104030214 builduid: 09ccf1c21bbb405c8ec963d0ad5a51c1 revision: 57ac9f63fc6953f4efeb0cc84a60192d3721251f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-04 04:25:35.688875) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-04 04:25:35.689327) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-04 04:25:35.689619) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-04 04:25:35.717336) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 04:25:35.717672) ========= 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.5DJj4IlMo1/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.wH92rz8V4x/Listeners TMPDIR=/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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-04 04:25:36-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 14.2M=0.001s 2017-01-04 04:25:36 (14.2 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.164996 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 04:25:35.907545) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 04:25:35.907860) ========= 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.5DJj4IlMo1/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.wH92rz8V4x/Listeners TMPDIR=/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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.026527 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 04:25:35.957448) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-04 04:25:35.957778) ========= 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.5DJj4IlMo1/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.wH92rz8V4x/Listeners TMPDIR=/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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-04 04:25:36,782 truncating revision to first 12 chars 2017-01-04 04:25:36,782 Setting DEBUG logging. 2017-01-04 04:25:36,782 attempt 1/10 2017-01-04 04:25:36,782 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-04 04:25:38,332 unpacking tar archive at: mozilla-central-57ac9f63fc69/testing/mozharness/ program finished with exit code 0 elapsedTime=1.826459 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-04 04:25:37.808645) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-04 04:25:37.808997) ========= script_repo_revision: 57ac9f63fc6953f4efeb0cc84a60192d3721251f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-04 04:25:37.809497) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-04 04:25:37.809847) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-04 04:25:37.830988) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2017-01-04 04:25:37.831330) ========= /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.5DJj4IlMo1/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.wH92rz8V4x/Listeners TMPDIR=/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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 04:25:38 INFO - MultiFileLogger online at 20170104 04:25:38 in /builds/slave/test 04:25:38 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 04:25:38 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:25:38 INFO - {'append_to_log': False, 04:25:38 INFO - 'base_work_dir': '/builds/slave/test', 04:25:38 INFO - 'blob_upload_branch': 'Firefox', 04:25:38 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:25:38 INFO - 'branch': 'Firefox', 04:25:38 INFO - 'buildbot_json_path': 'buildprops.json', 04:25:38 INFO - 'config_files': ('talos/mac_config.py',), 04:25:38 INFO - 'default_actions': ('clobber', 04:25:38 INFO - 'read-buildbot-config', 04:25:38 INFO - 'download-and-extract', 04:25:38 INFO - 'populate-webroot', 04:25:38 INFO - 'create-virtualenv', 04:25:38 INFO - 'install', 04:25:38 INFO - 'run-tests'), 04:25:38 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:25:38 INFO - 'download_minidump_stackwalk': True, 04:25:38 INFO - 'download_symbols': 'ondemand', 04:25:38 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:25:38 INFO - 'tooltool.py': '/tools/tooltool.py', 04:25:38 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:25:38 INFO - '/tools/misc-python/virtualenv.py')}, 04:25:38 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:25:38 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:25:38 INFO - 'installer_path': 'installer.exe', 04:25:38 INFO - 'log_level': 'info', 04:25:38 INFO - 'log_name': 'talos', 04:25:38 INFO - 'log_to_console': True, 04:25:38 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:25:38 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:25:38 INFO - 'opt_config_files': (), 04:25:38 INFO - 'pip_index': False, 04:25:38 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:25:38 INFO - 'cmd': ('bash', 04:25:38 INFO - '-c', 04:25:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:25:38 INFO - 'enabled': True, 04:25:38 INFO - 'halt_on_failure': False, 04:25:38 INFO - 'name': 'check_screen_resolution'},), 04:25:38 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:25:38 INFO - 'cmd': ('bash', 04:25:38 INFO - '-c', 04:25:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:25:38 INFO - 'enabled': True, 04:25:38 INFO - 'halt_on_failure': False, 04:25:38 INFO - 'name': 'check_screen_resolution'},), 04:25:38 INFO - 'run_cmd_checks_enabled': True, 04:25:38 INFO - 'sps_profile': False, 04:25:38 INFO - 'sps_profile_interval': 0, 04:25:38 INFO - 'suite': 'dromaeojs', 04:25:38 INFO - 'system_bits': '32', 04:25:38 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:25:38 INFO - 'title': 't-yosemite-r7-0116', 04:25:38 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:25:38 INFO - 'use_talos_json': True, 04:25:38 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:25:38 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:25:38 INFO - 'work_dir': 'build'} 04:25:38 INFO - [mozharness: 2017-01-04 12:25:38.768281Z] Running clobber step. 04:25:38 INFO - Running pre-action listener: _resource_record_pre_action 04:25:38 INFO - Running main action method: clobber 04:25:38 INFO - rmtree: /builds/slave/test/build 04:25:38 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:25:41 INFO - Running post-action listener: _resource_record_post_action 04:25:41 INFO - [mozharness: 2017-01-04 12:25:41.196274Z] Finished clobber step (success) 04:25:41 INFO - [mozharness: 2017-01-04 12:25:41.196405Z] Running read-buildbot-config step. 04:25:41 INFO - Running pre-action listener: _resource_record_pre_action 04:25:41 INFO - Running main action method: read_buildbot_config 04:25:41 INFO - Using buildbot properties: 04:25:41 INFO - { 04:25:41 INFO - "project": "", 04:25:41 INFO - "product": "firefox", 04:25:41 INFO - "script_repo_revision": "production", 04:25:41 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 04:25:41 INFO - "repository": "", 04:25:41 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 04:25:41 INFO - "buildid": "20170104030214", 04:25:41 INFO - "stage_platform": "macosx64", 04:25:41 INFO - "basedir": "/builds/slave/test", 04:25:41 INFO - "nightly_build": "True", 04:25:41 INFO - "buildnumber": 101, 04:25:41 INFO - "slavename": "t-yosemite-r7-0116", 04:25:41 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 04:25:41 INFO - "platform": "yosemite_r7", 04:25:41 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 04:25:41 INFO - "branch": "mozilla-central", 04:25:41 INFO - "repo_path": "mozilla-central", 04:25:41 INFO - "revision": "57ac9f63fc6953f4efeb0cc84a60192d3721251f", 04:25:41 INFO - "pgo_build": "False", 04:25:41 INFO - "builduid": "09ccf1c21bbb405c8ec963d0ad5a51c1", 04:25:41 INFO - "slavebuilddir": "test" 04:25:41 INFO - } 04:25:41 INFO - Found installer url https://queue.taskcluster.net/v1/task/NKpy0AF2QGeD4x017M9vVg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 04:25:41 INFO - Running post-action listener: _resource_record_post_action 04:25:41 INFO - [mozharness: 2017-01-04 12:25:41.199808Z] Finished read-buildbot-config step (success) 04:25:41 INFO - [mozharness: 2017-01-04 12:25:41.199921Z] Running download-and-extract step. 04:25:41 INFO - Running pre-action listener: _resource_record_pre_action 04:25:41 INFO - Running main action method: download_and_extract 04:25:41 INFO - mkdir: /builds/slave/test/build/tests 04:25:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:25:41 INFO - trying https://queue.taskcluster.net/v1/task/NKpy0AF2QGeD4x017M9vVg/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 04:25:41 INFO - Downloading https://queue.taskcluster.net/v1/task/NKpy0AF2QGeD4x017M9vVg/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 04:25:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NKpy0AF2QGeD4x017M9vVg/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 04:25:41 INFO - Downloaded 1199 bytes. 04:25:41 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 04:25:41 INFO - Using the following test package requirements: 04:25:41 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 04:25:41 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:25:41 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 04:25:41 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:25:41 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 04:25:41 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:25:41 INFO - u'jsshell-mac.zip'], 04:25:41 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:25:41 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 04:25:41 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 04:25:41 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:25:41 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 04:25:41 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:25:41 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 04:25:41 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:25:41 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 04:25:41 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:25:41 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 04:25:41 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:25:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NKpy0AF2QGeD4x017M9vVg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 04:25:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NKpy0AF2QGeD4x017M9vVg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:25:41 INFO - Fetch https://queue.taskcluster.net/v1/task/NKpy0AF2QGeD4x017M9vVg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 04:25:43 INFO - Expected file size: 16300572 04:25:43 INFO - Obtained file size: 16300572 04:25:50 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 04:25:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NKpy0AF2QGeD4x017M9vVg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 04:25:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NKpy0AF2QGeD4x017M9vVg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:25:50 INFO - Fetch https://queue.taskcluster.net/v1/task/NKpy0AF2QGeD4x017M9vVg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 04:25:51 INFO - Expected file size: 16300572 04:25:51 INFO - Obtained file size: 16300572 04:25:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NKpy0AF2QGeD4x017M9vVg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 04:25:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NKpy0AF2QGeD4x017M9vVg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:25:57 INFO - Fetch https://queue.taskcluster.net/v1/task/NKpy0AF2QGeD4x017M9vVg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 04:25:59 INFO - Expected file size: 14403730 04:25:59 INFO - Obtained file size: 14403730 04:25: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')]} 04:25:59 INFO - trying https://queue.taskcluster.net/v1/task/NKpy0AF2QGeD4x017M9vVg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 04:25:59 INFO - Downloading https://queue.taskcluster.net/v1/task/NKpy0AF2QGeD4x017M9vVg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 04:25:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NKpy0AF2QGeD4x017M9vVg/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 04:26:03 INFO - Downloaded 69746518 bytes. 04:26:03 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NKpy0AF2QGeD4x017M9vVg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 04:26:03 INFO - mkdir: /builds/slave/test/properties 04:26:03 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:26:03 INFO - Writing to file /builds/slave/test/properties/build_url 04:26:03 INFO - Contents: 04:26:03 INFO - build_url:https://queue.taskcluster.net/v1/task/NKpy0AF2QGeD4x017M9vVg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 04:26:03 INFO - Running post-action listener: _resource_record_post_action 04:26:03 INFO - Running post-action listener: set_extra_try_arguments 04:26:03 INFO - [mozharness: 2017-01-04 12:26:03.895747Z] Finished download-and-extract step (success) 04:26:03 INFO - [mozharness: 2017-01-04 12:26:03.895866Z] Running populate-webroot step. 04:26:03 INFO - Running pre-action listener: _resource_record_pre_action 04:26:03 INFO - Running main action method: populate_webroot 04:26:03 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:26:03 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:26:03 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:26:03 INFO - '--tppagecycles', 04:26:03 INFO - '7'], 04:26:03 INFO - 'tests': ['tsvgm']}}, 04:26:03 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:26:03 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:26:03 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:26:03 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:26:03 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:26:03 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:26:03 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:26:03 INFO - 'talos_options': ['--disable-e10s'], 04:26:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:26:03 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:26:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:26:03 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:26:03 INFO - 'talos_options': ['--disable-e10s'], 04:26:03 INFO - 'tests': ['damp', 'tps']}, 04:26:03 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:26:03 INFO - 'tests': ['damp', 'tps']}, 04:26:03 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:26:03 INFO - 'tests': ['dromaeo_dom']}, 04:26:03 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:26:03 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:26:03 INFO - 'tests': ['basic_compositor_video']}, 04:26:03 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:26:03 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:26:03 INFO - 'tests': ['a11yr', 04:26:03 INFO - 'ts_paint', 04:26:03 INFO - 'tpaint', 04:26:03 INFO - 'sessionrestore', 04:26:03 INFO - 'sessionrestore_no_auto_restore', 04:26:03 INFO - 'tabpaint']}, 04:26:03 INFO - 'other-e10s': {'tests': ['a11yr', 04:26:03 INFO - 'ts_paint', 04:26:03 INFO - 'tpaint', 04:26:03 INFO - 'sessionrestore', 04:26:03 INFO - 'sessionrestore_no_auto_restore', 04:26:03 INFO - 'tabpaint']}, 04:26:03 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:26:03 INFO - 'ts_paint', 04:26:03 INFO - 'tpaint', 04:26:03 INFO - 'sessionrestore', 04:26:03 INFO - 'sessionrestore_no_auto_restore', 04:26:03 INFO - 'tabpaint']}, 04:26:03 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:26:03 INFO - 'ts_paint', 04:26:03 INFO - 'tpaint', 04:26:03 INFO - 'sessionrestore', 04:26:03 INFO - 'sessionrestore_no_auto_restore', 04:26:03 INFO - 'tabpaint']}, 04:26:03 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:26:03 INFO - 'tests': ['a11yr', 04:26:03 INFO - 'ts_paint', 04:26:03 INFO - 'tpaint', 04:26:03 INFO - 'sessionrestore', 04:26:03 INFO - 'sessionrestore_no_auto_restore', 04:26:03 INFO - 'tabpaint']}, 04:26:03 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:26:03 INFO - 'tests': ['a11yr', 04:26:03 INFO - 'ts_paint', 04:26:03 INFO - 'tpaint', 04:26:03 INFO - 'sessionrestore', 04:26:03 INFO - 'sessionrestore_no_auto_restore', 04:26:03 INFO - 'tabpaint']}, 04:26:03 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:26:03 INFO - 'tests': ['tsvgx', 04:26:03 INFO - 'tsvgr_opacity', 04:26:03 INFO - 'tart', 04:26:03 INFO - 'tscrollx', 04:26:03 INFO - 'cart', 04:26:03 INFO - 'tsvg_static']}, 04:26:03 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:26:03 INFO - 'tsvgr_opacity', 04:26:03 INFO - 'tart', 04:26:03 INFO - 'tscrollx', 04:26:03 INFO - 'cart', 04:26:03 INFO - 'tsvg_static']}, 04:26:03 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:26:03 INFO - 'talos_options': ['--disable-e10s'], 04:26:03 INFO - 'tests': ['tp5o']}, 04:26:03 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:26:03 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:26:03 INFO - 'talos_options': ['--disable-e10s', 04:26:03 INFO - '--xperf_path', 04:26:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:26:03 INFO - 'tests': ['tp5n']}, 04:26:03 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:26:03 INFO - 'talos_options': ['--xperf_path', 04:26:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:26:03 INFO - 'tests': ['tp5n']}}, 04:26:03 INFO - 'talos.zip': {'path': '', 04:26:03 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:26:03 INFO - Running post-action listener: _resource_record_post_action 04:26:03 INFO - [mozharness: 2017-01-04 12:26:03.913997Z] Finished populate-webroot step (success) 04:26:03 INFO - [mozharness: 2017-01-04 12:26:03.914104Z] Running create-virtualenv step. 04:26:03 INFO - Running pre-action listener: _resource_record_pre_action 04:26:03 INFO - Running main action method: create_virtualenv 04:26:03 INFO - Creating virtualenv /builds/slave/test/build/venv 04:26:03 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 04:26:03 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:26:04 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:26:04 INFO - Using real prefix '/tools/python27' 04:26:04 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:26:04 INFO - Installing distribute.............................................................................................................................................................................................done. 04:26:07 INFO - Installing pip.................done. 04:26:07 INFO - Return code: 0 04:26:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:26:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:26:07 INFO - Reading from file tmpfile_stdout 04:26:07 INFO - Output received: 04:26:07 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) 04:26:07 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:26:07 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')]} 04:26:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:26:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:26:07 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')]} 04:26:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:26:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:26:07 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 0x101648b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10164b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb2bd62300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101426d30>, '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 0x10134ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10141bbe8>, '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.5DJj4IlMo1/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.wH92rz8V4x/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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 04:26:07 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 04:26:07 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 04:26:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5DJj4IlMo1/Render', 04:26:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:26:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:26:07 INFO - 'HOME': '/Users/cltbld', 04:26:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:26:07 INFO - 'LOGNAME': 'cltbld', 04:26:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:26:07 INFO - 'MOZ_NO_REMOTE': '1', 04:26:07 INFO - 'NO_EM_RESTART': '1', 04:26:07 INFO - 'PAGER': '/bin/cat', 04:26:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:26:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:26:07 INFO - 'PWD': '/builds/slave/test', 04:26:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:26:07 INFO - 'SHELL': '/bin/bash', 04:26:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wH92rz8V4x/Listeners', 04:26:07 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 04:26:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:26:07 INFO - 'USER': 'cltbld', 04:26:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:26:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:26:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:26:07 INFO - 'XPC_FLAGS': '0x0', 04:26:07 INFO - 'XPC_SERVICE_NAME': '0', 04:26:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:26:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:26:08 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 04:26:08 INFO - Cleaning up... 04:26:08 INFO - Return code: 0 04:26:08 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:26:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:26:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:26:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:26:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:26:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:26:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:26:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101648b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10164b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb2bd62300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101426d30>, '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 0x10134ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10141bbe8>, '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.5DJj4IlMo1/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.wH92rz8V4x/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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 04:26:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:26:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:26:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5DJj4IlMo1/Render', 04:26:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:26:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:26:08 INFO - 'HOME': '/Users/cltbld', 04:26:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:26:08 INFO - 'LOGNAME': 'cltbld', 04:26:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:26:08 INFO - 'MOZ_NO_REMOTE': '1', 04:26:08 INFO - 'NO_EM_RESTART': '1', 04:26:08 INFO - 'PAGER': '/bin/cat', 04:26:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:26:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:26:08 INFO - 'PWD': '/builds/slave/test', 04:26:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:26:08 INFO - 'SHELL': '/bin/bash', 04:26:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wH92rz8V4x/Listeners', 04:26:08 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 04:26:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:26:08 INFO - 'USER': 'cltbld', 04:26:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:26:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:26:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:26:08 INFO - 'XPC_FLAGS': '0x0', 04:26:08 INFO - 'XPC_SERVICE_NAME': '0', 04:26:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:26:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:26:08 INFO - Downloading/unpacking psutil>=3.1.1 04:26:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:26:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:26:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:26:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:26:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:26:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:26:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:26:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:26:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:26:11 INFO - Installing collected packages: psutil 04:26:11 INFO - Running setup.py install for psutil 04:26:11 INFO - building 'psutil._psutil_osx' extension 04:26:11 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 04:26:12 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 04:26:12 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 04:26:12 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 04:26:12 INFO - building 'psutil._psutil_posix' extension 04:26:12 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 04:26:12 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:26:12 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:26:12 INFO - ^ 04:26:12 INFO - 1 warning generated. 04:26:12 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 04:26:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:26:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:26:13 INFO - Successfully installed psutil 04:26:13 INFO - Cleaning up... 04:26:13 INFO - Return code: 0 04:26:13 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:26:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:26:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:26:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:26:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:26:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:26:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:26:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101648b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10164b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb2bd62300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101426d30>, '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 0x10134ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10141bbe8>, '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.5DJj4IlMo1/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.wH92rz8V4x/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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 04:26:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:26:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 04:26:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5DJj4IlMo1/Render', 04:26:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:26:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:26:13 INFO - 'HOME': '/Users/cltbld', 04:26:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:26:13 INFO - 'LOGNAME': 'cltbld', 04:26:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:26:13 INFO - 'MOZ_NO_REMOTE': '1', 04:26:13 INFO - 'NO_EM_RESTART': '1', 04:26:13 INFO - 'PAGER': '/bin/cat', 04:26:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:26:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:26:13 INFO - 'PWD': '/builds/slave/test', 04:26:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:26:13 INFO - 'SHELL': '/bin/bash', 04:26:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wH92rz8V4x/Listeners', 04:26:13 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 04:26:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:26:13 INFO - 'USER': 'cltbld', 04:26:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:26:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:26:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:26:13 INFO - 'XPC_FLAGS': '0x0', 04:26:13 INFO - 'XPC_SERVICE_NAME': '0', 04:26:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:26:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:26:13 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:26:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:26:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:26:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:26:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:26:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:26:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:26:16 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:26:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:26:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:26:16 INFO - Installing collected packages: mozsystemmonitor 04:26:16 INFO - Running setup.py install for mozsystemmonitor 04:26:16 INFO - Successfully installed mozsystemmonitor 04:26:16 INFO - Cleaning up... 04:26:16 INFO - Return code: 0 04:26:16 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:26:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:26:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:26:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:26:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:26:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:26:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:26:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101648b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10164b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb2bd62300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101426d30>, '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 0x10134ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10141bbe8>, '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.5DJj4IlMo1/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.wH92rz8V4x/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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 04:26:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 04:26:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 04:26:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5DJj4IlMo1/Render', 04:26:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:26:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:26:16 INFO - 'HOME': '/Users/cltbld', 04:26:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:26:16 INFO - 'LOGNAME': 'cltbld', 04:26:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:26:16 INFO - 'MOZ_NO_REMOTE': '1', 04:26:16 INFO - 'NO_EM_RESTART': '1', 04:26:16 INFO - 'PAGER': '/bin/cat', 04:26:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:26:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:26:16 INFO - 'PWD': '/builds/slave/test', 04:26:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:26:16 INFO - 'SHELL': '/bin/bash', 04:26:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wH92rz8V4x/Listeners', 04:26:16 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 04:26:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:26:16 INFO - 'USER': 'cltbld', 04:26:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:26:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:26:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:26:16 INFO - 'XPC_FLAGS': '0x0', 04:26:16 INFO - 'XPC_SERVICE_NAME': '0', 04:26:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:26:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:26:16 INFO - Downloading/unpacking jsonschema==2.5.1 04:26:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:26:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:26:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:26:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:26:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:26:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:26:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:26:19 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:26:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:26:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:26:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:26:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:26:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:26:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:26:19 INFO - Downloading functools32-3.2.3-2.tar.gz 04:26:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:26:19 INFO - warning: no files found matching '*.txt' 04:26:19 INFO - no previously-included directories found matching 'build' 04:26:19 INFO - no previously-included directories found matching 'dist' 04:26:19 INFO - no previously-included directories found matching '.git*' 04:26:19 INFO - Installing collected packages: jsonschema, functools32 04:26:19 INFO - Running setup.py install for functools32 04:26:19 INFO - warning: no files found matching '*.txt' 04:26:19 INFO - no previously-included directories found matching 'build' 04:26:19 INFO - no previously-included directories found matching 'dist' 04:26:19 INFO - no previously-included directories found matching '.git*' 04:26:19 INFO - Successfully installed jsonschema functools32 04:26:19 INFO - Cleaning up... 04:26:20 INFO - Return code: 0 04:26:20 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:26:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:26:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:26:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:26:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:26:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:26:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:26:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101648b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10164b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb2bd62300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101426d30>, '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 0x10134ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10141bbe8>, '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.5DJj4IlMo1/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.wH92rz8V4x/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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 04:26:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 04:26:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 04:26:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5DJj4IlMo1/Render', 04:26:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:26:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:26:20 INFO - 'HOME': '/Users/cltbld', 04:26:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:26:20 INFO - 'LOGNAME': 'cltbld', 04:26:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:26:20 INFO - 'MOZ_NO_REMOTE': '1', 04:26:20 INFO - 'NO_EM_RESTART': '1', 04:26:20 INFO - 'PAGER': '/bin/cat', 04:26:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:26:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:26:20 INFO - 'PWD': '/builds/slave/test', 04:26:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:26:20 INFO - 'SHELL': '/bin/bash', 04:26:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wH92rz8V4x/Listeners', 04:26:20 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 04:26:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:26:20 INFO - 'USER': 'cltbld', 04:26:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:26:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:26:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:26:20 INFO - 'XPC_FLAGS': '0x0', 04:26:20 INFO - 'XPC_SERVICE_NAME': '0', 04:26:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:26:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:26:20 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:26:20 INFO - Cleaning up... 04:26:20 INFO - Return code: 0 04:26:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:26:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:26:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:26:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:26:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:26:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:26:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:26:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101648b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10164b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb2bd62300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101426d30>, '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 0x10134ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10141bbe8>, '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.5DJj4IlMo1/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.wH92rz8V4x/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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 04:26:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:26:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:26:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5DJj4IlMo1/Render', 04:26:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:26:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:26:20 INFO - 'HOME': '/Users/cltbld', 04:26:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:26:20 INFO - 'LOGNAME': 'cltbld', 04:26:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:26:20 INFO - 'MOZ_NO_REMOTE': '1', 04:26:20 INFO - 'NO_EM_RESTART': '1', 04:26:20 INFO - 'PAGER': '/bin/cat', 04:26:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:26:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:26:20 INFO - 'PWD': '/builds/slave/test', 04:26:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:26:20 INFO - 'SHELL': '/bin/bash', 04:26:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wH92rz8V4x/Listeners', 04:26:20 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 04:26:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:26:20 INFO - 'USER': 'cltbld', 04:26:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:26:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:26:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:26:20 INFO - 'XPC_FLAGS': '0x0', 04:26:20 INFO - 'XPC_SERVICE_NAME': '0', 04:26:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:26:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:26:20 INFO - Downloading/unpacking blobuploader==1.2.4 04:26:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:26:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:26:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:26:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:26:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:26:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:26:23 INFO - Downloading blobuploader-1.2.4.tar.gz 04:26:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:26:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:26:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:26:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:26:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:26:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:26:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:26:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:26:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:26:23 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:26:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:26:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:26:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:26:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:26:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:26:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:26:24 INFO - Downloading docopt-0.6.1.tar.gz 04:26:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:26:24 INFO - Installing collected packages: blobuploader, requests, docopt 04:26:24 INFO - Running setup.py install for blobuploader 04:26:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:26:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:26:24 INFO - Running setup.py install for requests 04:26:25 INFO - Running setup.py install for docopt 04:26:25 INFO - Successfully installed blobuploader requests docopt 04:26:25 INFO - Cleaning up... 04:26:25 INFO - Return code: 0 04:26:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:26:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:26:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:26:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:26:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:26:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:26:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:26:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101648b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10164b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb2bd62300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101426d30>, '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 0x10134ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10141bbe8>, '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.5DJj4IlMo1/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.wH92rz8V4x/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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 04:26:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:26:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:26:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5DJj4IlMo1/Render', 04:26:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:26:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:26:25 INFO - 'HOME': '/Users/cltbld', 04:26:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:26:25 INFO - 'LOGNAME': 'cltbld', 04:26:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:26:25 INFO - 'MOZ_NO_REMOTE': '1', 04:26:25 INFO - 'NO_EM_RESTART': '1', 04:26:25 INFO - 'PAGER': '/bin/cat', 04:26:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:26:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:26:25 INFO - 'PWD': '/builds/slave/test', 04:26:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:26:25 INFO - 'SHELL': '/bin/bash', 04:26:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wH92rz8V4x/Listeners', 04:26:25 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 04:26:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:26:25 INFO - 'USER': 'cltbld', 04:26:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:26:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:26:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:26:25 INFO - 'XPC_FLAGS': '0x0', 04:26:25 INFO - 'XPC_SERVICE_NAME': '0', 04:26:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:26:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:26:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:26:25 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-BoFyuT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:26:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:26:25 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-1pHXXw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:26:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:26:25 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-MSGM7R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:26:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:26:26 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-ufwNUP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:26:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:26:26 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-54Stgg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:26:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:26:26 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-VXglm2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:26:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:26:26 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-LWKQsI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:26:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:26:26 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-MabXwD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:26:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:26:26 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-ObdRWY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:26:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:26:26 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-jj3IRx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:26:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:26:26 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-QE1Jub-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:26:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:26:26 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-8pPpba-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:26:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:26:26 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-1mBsw5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:26:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:26:27 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-NF6eu3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:26:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:26:27 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-gnskC5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:26:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:26:27 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-S36K4z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:26:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:26:27 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-AKQeeY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:26:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:26:27 INFO - Running setup.py install for manifestparser 04:26:27 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:26:27 INFO - Running setup.py install for mozcrash 04:26:27 INFO - Running setup.py install for mozdebug 04:26:27 INFO - Running setup.py install for mozdevice 04:26:28 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:26:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:26:28 INFO - Running setup.py install for mozfile 04:26:28 INFO - Running setup.py install for mozhttpd 04:26:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:26:28 INFO - Running setup.py install for mozinfo 04:26:28 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:26:28 INFO - Running setup.py install for mozInstall 04:26:28 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:26:28 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:26:28 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:26:28 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:26:28 INFO - Running setup.py install for mozleak 04:26:28 INFO - Running setup.py install for mozlog 04:26:29 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:26:29 INFO - Running setup.py install for moznetwork 04:26:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:26:29 INFO - Running setup.py install for mozprocess 04:26:29 INFO - Running setup.py install for mozprofile 04:26:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:26:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:26:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:26:29 INFO - Running setup.py install for mozrunner 04:26:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:26:29 INFO - Running setup.py install for mozscreenshot 04:26:29 INFO - Running setup.py install for moztest 04:26:29 INFO - Running setup.py install for mozversion 04:26:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:26:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:26:30 INFO - Cleaning up... 04:26:30 INFO - Return code: 0 04:26:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:26:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:26:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:26:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:26:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:26:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:26:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:26:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101648b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10164b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb2bd62300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101426d30>, '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 0x10134ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10141bbe8>, '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.5DJj4IlMo1/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.wH92rz8V4x/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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 04:26:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:26:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:26:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5DJj4IlMo1/Render', 04:26:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:26:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:26:30 INFO - 'HOME': '/Users/cltbld', 04:26:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:26:30 INFO - 'LOGNAME': 'cltbld', 04:26:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:26:30 INFO - 'MOZ_NO_REMOTE': '1', 04:26:30 INFO - 'NO_EM_RESTART': '1', 04:26:30 INFO - 'PAGER': '/bin/cat', 04:26:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:26:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:26:30 INFO - 'PWD': '/builds/slave/test', 04:26:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:26:30 INFO - 'SHELL': '/bin/bash', 04:26:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wH92rz8V4x/Listeners', 04:26:30 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 04:26:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:26:30 INFO - 'USER': 'cltbld', 04:26:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:26:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:26:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:26:30 INFO - 'XPC_FLAGS': '0x0', 04:26:30 INFO - 'XPC_SERVICE_NAME': '0', 04:26:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:26:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:26:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:26:31 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-5QqRdU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:26:31 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)) 04:26:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:26:31 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-XYgl8v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:26:31 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)) 04:26:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:26:31 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-PxWJwE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:26:31 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)) 04:26:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:26:31 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-8bTblZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:26:31 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)) 04:26:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:26:31 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-cY9Q6t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:26:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 04:26:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:26:31 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-nV6fLw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:26:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 04:26:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:26:31 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-d80mxb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:26:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 04:26:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:26:31 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-A_0S4f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:26:32 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)) 04:26:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:26:32 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-3phPdD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:26:32 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)) 04:26:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:26:32 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-p_oqoK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:26:32 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)) 04:26:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:26:32 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-4zOAyK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:26:32 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)) 04:26:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:26:32 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-WSo3DJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:26:32 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)) 04:26:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:26:32 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-WDDxxJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:26:32 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)) 04:26:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:26:32 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-sqEEqh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:26:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 04:26:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:26:32 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-3k2XDe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:26:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 04:26:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:26:32 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-LYNezi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:26:32 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 04:26:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:26:32 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-p9QgKb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:26:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 04:26:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:26:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:26:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:26:32 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:26:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:26:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:26:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:26:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:26:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:26:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:26:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:26:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:26:35 INFO - Downloading blessings-1.6.tar.gz 04:26:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:26:35 INFO - Installing collected packages: blessings 04:26:35 INFO - Running setup.py install for blessings 04:26:36 INFO - Successfully installed blessings 04:26:36 INFO - Cleaning up... 04:26:36 INFO - Return code: 0 04:26:36 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:26:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:26:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:26:36 INFO - Reading from file tmpfile_stdout 04:26:36 INFO - Current package versions: 04:26:36 INFO - blessings == 1.6 04:26:36 INFO - blobuploader == 1.2.4 04:26:36 INFO - docopt == 0.6.1 04:26:36 INFO - functools32 == 3.2.3-2 04:26:36 INFO - jsonschema == 2.5.1 04:26:36 INFO - manifestparser == 1.1 04:26:36 INFO - mozInstall == 1.12 04:26:36 INFO - mozcrash == 1.0 04:26:36 INFO - mozdebug == 0.1 04:26:36 INFO - mozdevice == 0.49 04:26:36 INFO - mozfile == 1.2 04:26:36 INFO - mozhttpd == 0.7 04:26:36 INFO - mozinfo == 0.9 04:26:36 INFO - mozleak == 0.1 04:26:36 INFO - mozlog == 3.4 04:26:36 INFO - moznetwork == 0.27 04:26:36 INFO - mozprocess == 0.23 04:26:36 INFO - mozprofile == 0.28 04:26:36 INFO - mozrunner == 6.13 04:26:36 INFO - mozscreenshot == 0.1 04:26:36 INFO - mozsystemmonitor == 0.3 04:26:36 INFO - moztest == 0.8 04:26:36 INFO - mozversion == 1.4 04:26:36 INFO - psutil == 3.1.1 04:26:36 INFO - requests == 1.2.3 04:26:36 INFO - wsgiref == 0.1.2 04:26:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:26:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:26:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:26:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:26:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:26:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:26:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:26:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101648b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10164b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb2bd62300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101426d30>, '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 0x10134ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10141bbe8>, '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.5DJj4IlMo1/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.wH92rz8V4x/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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 04:26:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 04:26:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:26:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5DJj4IlMo1/Render', 04:26:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:26:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:26:36 INFO - 'HOME': '/Users/cltbld', 04:26:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:26:36 INFO - 'LOGNAME': 'cltbld', 04:26:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:26:36 INFO - 'MOZ_NO_REMOTE': '1', 04:26:36 INFO - 'NO_EM_RESTART': '1', 04:26:36 INFO - 'PAGER': '/bin/cat', 04:26:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:26:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:26:36 INFO - 'PWD': '/builds/slave/test', 04:26:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:26:36 INFO - 'SHELL': '/bin/bash', 04:26:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wH92rz8V4x/Listeners', 04:26:36 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 04:26:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:26:36 INFO - 'USER': 'cltbld', 04:26:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:26:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:26:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:26:36 INFO - 'XPC_FLAGS': '0x0', 04:26:36 INFO - 'XPC_SERVICE_NAME': '0', 04:26:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:26:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:26:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:26:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 04:26:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 04:26:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:26:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 04:26:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 04:26:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 04:26:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 04:26:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 04:26:36 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:26:36 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:26:36 INFO - Cleaning up... 04:26:36 INFO - Return code: 0 04:26:36 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:26:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:26:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:26:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:26:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:26:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:26:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:26:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101648b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10164b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb2bd62300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101426d30>, '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 0x10134ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10141bbe8>, '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.5DJj4IlMo1/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.wH92rz8V4x/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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 04:26:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:26:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:26:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5DJj4IlMo1/Render', 04:26:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:26:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:26:36 INFO - 'HOME': '/Users/cltbld', 04:26:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:26:36 INFO - 'LOGNAME': 'cltbld', 04:26:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:26:36 INFO - 'MOZ_NO_REMOTE': '1', 04:26:36 INFO - 'NO_EM_RESTART': '1', 04:26:36 INFO - 'PAGER': '/bin/cat', 04:26:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:26:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:26:36 INFO - 'PWD': '/builds/slave/test', 04:26:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:26:36 INFO - 'SHELL': '/bin/bash', 04:26:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wH92rz8V4x/Listeners', 04:26:36 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 04:26:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:26:36 INFO - 'USER': 'cltbld', 04:26:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:26:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:26:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:26:36 INFO - 'XPC_FLAGS': '0x0', 04:26:36 INFO - 'XPC_SERVICE_NAME': '0', 04:26:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:26:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:26:36 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:26:36 INFO - Cleaning up... 04:26:37 INFO - Return code: 0 04:26:37 INFO - Running post-action listener: _resource_record_post_action 04:26:37 INFO - Running post-action listener: _start_resource_monitoring 04:26:37 INFO - Starting resource monitoring. 04:26:37 INFO - [mozharness: 2017-01-04 12:26:37.045574Z] Finished create-virtualenv step (success) 04:26:37 INFO - [mozharness: 2017-01-04 12:26:37.046493Z] Running install step. 04:26:37 INFO - Running pre-action listener: _resource_record_pre_action 04:26:37 INFO - Running main action method: install 04:26:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:26:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:26:37 INFO - Reading from file tmpfile_stdout 04:26:37 INFO - Detecting whether we're running mozinstall >=1.0... 04:26:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:26:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:26:37 INFO - Reading from file tmpfile_stdout 04:26:37 INFO - Output received: 04:26:37 INFO - Usage: mozinstall [options] installer 04:26:37 INFO - Options: 04:26:37 INFO - -h, --help show this help message and exit 04:26:37 INFO - -d DEST, --destination=DEST 04:26:37 INFO - Directory to install application into. [default: 04:26:37 INFO - "/builds/slave/test"] 04:26:37 INFO - --app=APP Application being installed. [default: firefox] 04:26:37 INFO - mkdir: /builds/slave/test/build/application 04:26:37 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'] 04:26:37 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 04:26:59 INFO - Reading from file tmpfile_stdout 04:26:59 INFO - Output received: 04:26:59 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 04:26:59 INFO - Running post-action listener: _resource_record_post_action 04:26:59 INFO - [mozharness: 2017-01-04 12:26:59.309697Z] Finished install step (success) 04:26:59 INFO - [mozharness: 2017-01-04 12:26:59.309830Z] Running run-tests step. 04:26:59 INFO - Running pre-action listener: _resource_record_pre_action 04:26:59 INFO - Running main action method: run_tests 04:26:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:26:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:26:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:26:59 INFO - 2017-01-04 04:26:59.324 screenresolution[1623:7448] starting screenresolution argv=screenresolution get 04:26:59 INFO - 2017-01-04 04:26:59.327 screenresolution[1623:7448] Display 0: 1600x1200x32@60 04:26:59 INFO - 2017-01-04 04:26:59.332 screenresolution[1624:7450] starting screenresolution argv=screenresolution list 04:26:59 INFO - Available Modes on Display 0 04:26:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:26:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:26:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:26:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:26:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:26:59 INFO - Intel Iris: 04:26:59 INFO - Chipset Model: Intel Iris 04:26:59 INFO - Type: GPU 04:26:59 INFO - Bus: Built-In 04:26:59 INFO - VRAM (Dynamic, Max): 1536 MB 04:26:59 INFO - Vendor: Intel (0x8086) 04:26:59 INFO - Device ID: 0x0a2e 04:26:59 INFO - Revision ID: 0x0009 04:26:59 INFO - Displays: 04:26:59 INFO - Display: 04:26:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:26:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:26:59 INFO - Display Serial Number: 1600x1200 60 04:26:59 INFO - Main Display: Yes 04:26:59 INFO - Mirror: Off 04:26:59 INFO - Online: Yes 04:26:59 INFO - Rotation: Supported 04:26:59 INFO - Return code: 0 04:26:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:26:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:26:59 INFO - Python 2.7.3 04:26:59 INFO - Return code: 0 04:26:59 INFO - grabbing minidump binary from tooltool 04:26: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')]} 04:26:59 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 0x101426d30>, '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 0x10134ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10141bbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:26:59 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 04:26:59 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 04:26:59 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 04:26:59 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:26:59 INFO - Return code: 0 04:26:59 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:26:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:26:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:26:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:26:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:26:59 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/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NKpy0AF2QGeD4x017M9vVg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0116', '--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 04:26:59 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/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/NKpy0AF2QGeD4x017M9vVg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0116 --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 04:26:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5DJj4IlMo1/Render', 04:26:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:26:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:26:59 INFO - 'HOME': '/Users/cltbld', 04:26:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:26:59 INFO - 'LOGNAME': 'cltbld', 04:26:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:26:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:26:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:26:59 INFO - 'MOZ_NO_REMOTE': '1', 04:26:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:26:59 INFO - 'NO_EM_RESTART': '1', 04:26:59 INFO - 'PAGER': '/bin/cat', 04:26:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:26:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:26:59 INFO - 'PWD': '/builds/slave/test', 04:26:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:26:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:26:59 INFO - 'SHELL': '/bin/bash', 04:26:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wH92rz8V4x/Listeners', 04:26:59 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 04:26:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:26:59 INFO - 'USER': 'cltbld', 04:26:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:26:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:26:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:26:59 INFO - 'XPC_FLAGS': '0x0', 04:26:59 INFO - 'XPC_SERVICE_NAME': '0', 04:26:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:26:59 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/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NKpy0AF2QGeD4x017M9vVg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0116', '--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 04:26:59 INFO - mozversion application_buildid: 20170104030214 04:26:59 INFO - mozversion application_changeset: 57ac9f63fc6953f4efeb0cc84a60192d3721251f 04:26:59 INFO - mozversion application_display_name: Nightly 04:26:59 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:26:59 INFO - mozversion application_name: Firefox 04:26:59 INFO - mozversion application_remotingname: firefox 04:26:59 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:26:59 INFO - mozversion application_vendor: Mozilla 04:26:59 INFO - mozversion application_version: 53.0a1 04:26:59 INFO - mozversion platform_buildid: 20170104030214 04:26:59 INFO - mozversion platform_changeset: 57ac9f63fc6953f4efeb0cc84a60192d3721251f 04:26:59 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:26:59 INFO - mozversion platform_version: 53.0a1 04:26:59 INFO - using testdate: 1483532819 04:26:59 INFO - actual date: 1483532819 04:26:59 INFO - starting webserver on 'localhost:49241' 04:26:59 INFO - SUITE-START | Running 2 tests 04:26:59 INFO - TEST-START | dromaeo_css 04:26:59 INFO - operating with platform_type : mac_ 04:26:59 INFO - Initialising browser for dromaeo_css test... 04:26:59 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/tmpzWQHRs/profile http://localhost:49241/getInfo.html) 04:27:00 INFO - PROCESS | 1634 | 1483532820578 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/FirefoxNightly.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/FirefoxNightly.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 04:27:02 INFO - PROCESS | 1634 | __metrics Screen width/height:1600/1200 04:27:02 INFO - PROCESS | 1634 | colorDepth:24 04:27:02 INFO - PROCESS | 1634 | Browser inner width/height: 1024/689 04:27:02 INFO - PROCESS | 1634 | __metrics 04:27:02 INFO - PROCESS | 1634 | 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" 04:27:02 INFO - PROCESS | 1634 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:27:03 INFO - TEST-INFO | 1634: exit 0 04:27:03 INFO - Browser initialized. 04:27:03 INFO - Running cycle 1/1 for dromaeo_css test... 04:27:03 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/tmpzWQHRs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:27:15 INFO - PROCESS | 1637 | RSS: Main: 232857600 04:27:15 INFO - PROCESS | 1637 | 04:28:06 INFO - PROCESS | 1637 | 1483532886869 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] 04:30:20 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 04:30:21 INFO - PROCESS | 1637 | RSS: Main: 328257536 04:30:21 INFO - PROCESS | 1637 | 04:32:03 INFO - PROCESS | 1637 | ************************* 04:32:03 INFO - PROCESS | 1637 | A coding exception was thrown and uncaught in a Task. 04:32:03 INFO - PROCESS | 1637 | 04:32:03 INFO - PROCESS | 1637 | Full message: ReferenceError: fetch is not defined 04:32:03 INFO - PROCESS | 1637 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 04:32:03 INFO - PROCESS | 1637 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 04:32:03 INFO - PROCESS | 1637 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 04:32:03 INFO - PROCESS | 1637 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 04:32:03 INFO - PROCESS | 1637 | TaskImpl@resource://gre/modules/Task.jsm:277:3 04:32:03 INFO - PROCESS | 1637 | asyncFunction@resource://gre/modules/Task.jsm:252:14 04:32:03 INFO - PROCESS | 1637 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 04:32:03 INFO - PROCESS | 1637 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 04:32:03 INFO - PROCESS | 1637 | TaskImpl@resource://gre/modules/Task.jsm:277:3 04:32:03 INFO - PROCESS | 1637 | asyncFunction@resource://gre/modules/Task.jsm:252:14 04:32:03 INFO - PROCESS | 1637 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 04:32:03 INFO - PROCESS | 1637 | 04:32:03 INFO - PROCESS | 1637 | ************************* 04:33:24 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 04:33:25 INFO - PROCESS | 1637 | RSS: Main: 366694400 04:33:25 INFO - PROCESS | 1637 | 04:36:27 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 04:36:28 INFO - PROCESS | 1637 | RSS: Main: 336470016 04:36:28 INFO - PROCESS | 1637 | 04:39:30 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 04:39:31 INFO - PROCESS | 1637 | RSS: Main: 326762496 04:39:31 INFO - PROCESS | 1637 | 04:42:34 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 04:42:35 INFO - PROCESS | 1637 | RSS: Main: 324014080 04:42:35 INFO - PROCESS | 1637 | 04:45:39 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 04:45:39 INFO - PROCESS | 1637 | RSS: Main: 374714368 04:45:39 INFO - PROCESS | 1637 | 04:45:39 INFO - PROCESS | 1637 | __start_tp_report 04:45:39 INFO - PROCESS | 1637 | _x_x_mozilla_page_load 04:45:39 INFO - PROCESS | 1637 | _x_x_mozilla_page_load_details 04:45:39 INFO - PROCESS | 1637 | |i|pagename|runs| 04:45:39 INFO - PROCESS | 1637 | |0;dojo.html;3290.884819031333;3293.02855657581;3298.7361011119115;3327.683870032346;3359.664033596641;4595.655325850558;5528.087865502197;5569.721513924308;5575.9999999999945;5585.106382978719;5616.129499927508;5623.578231632634;5662.773489060443;5669.546436285098;5670.574706896979;5105.948940510594;5160.045391602565;5166.380034395859;5168.896622067557;5212.322398088243;5223.817166399177;5239.764210610512;5267.65760225586;5335.733213339317;5341.732913354317;5111.514406131411;5269.604779641542;5292.682439053649;5294.655847369909;5300.443453437391;5241.423443421201;5317.2821669074565;5320.14877619579;5324.467553244684;5333.253344531769;5042.218456139304;5151.9484805152215;5209.635325527214;5226.843194704165;5250.949810038008;4726.66913316068;5550.72246387679;5622.353429355636;5632.098864181711;5643.858903527444;6123.4795042421;6175.44421002112;6197.039458883835;6199;6223.066540018988;6224.813255602339;6291.245050593912;6300.936990630081;6311.96844015777;6322.683865806691;5640.85897852557;5681;5682.801101961411;5758.798442054592;5772.989726797836;6232.470240029648;6277.748890044347;6278.277998030194;6285.842853928597;6300.369963003663;6071.150038994544;6134.38656134383;6139.10982907476;6139.754409823557;6154.415330543591;6098.176746139215;6104.708921120657;6208.2239720034995;6271.153394291803;6274.247090349096;6105.206323177958;6112.51099912006;6112.786052488142;6116.021436570128;6128.662923539159;6195.380461953769;6200.131981522591;6201;6208.441240288395;6223.906641400383;4772.737499437564;4786.2820576913045;4820.9517904820505;4829.444613869379;4916.508349165055;3797.867074652374;4433.911321773547;4450.46594408673;4454.933176002362;4462.44219472566;5696.231008813757;5702.315722113373;5730.914036289459;5753.482186603226;5795.391483894215;6249.593776404519;6250.281217659936;6277.497800175975;6288.182536270347;6291.622502649856;5766.365699773021;5779.162021506858;5796.478316951495;5804;5846.8538286542325;5201.037808005531;5230.712310822942;5235.397929238186;5359.276497672842;5383.031054410244;6225.844353891189;6250.874982500233;6269.843253918689;6301.590396624205;6307.211598550273;6298.433141017331;6306.621602703853;6318.462930650946;6321.620702757849;6328.019157030483;6215.223097112861;6304.3380445054445;6327.620342779448;6330.841728956813;6333;5275.472452754694;5791.305043394819;6060.818175454743;6063.757449702139;6173.722182501709;6206.441420272197;6250.249970003629;6265.8120256392385;6267.2165979252595;6272.372762723691;6030.969845150747;6044.7279872406725;6046.667433291212;6075.2405949256345;6103.145559621567;5834.766609335579;5836.912446313394;6025.939740602713;6036.939630603813;6066.36296723624;5926.170336153028;5928.466438020599;5954.136650185786;5968.373320801427;6002.249718785152;5868.3251426084835;5920.79277225295;5947.583669143291;6076.210092687922;6103.633781973096;5761.48146666802;5762.596618236851;5766.365699772938;5813.09896965971;5851.385604511635 04:45:39 INFO - PROCESS | 1637 | |1;ext.html;466.7572862111702;479.094511373504;482.7803349475989;487.1134535146031;503.15470010382563;30133.54799678004;38752.22495550091;39823.80088099559;39824.004399890015;41971.95070123248;33360.16599585012;41250.79374603123;41586;41699.37450938238;41770.582294177046;9251.629934802617;9457.19613832825;9467.100625440606;9473.715788526355;9493.145616894479;8405.159484051606;8635.740927772145;9224.953875230649;9402.670906518273;11317.603883864067;11223.831642525369;11279.984801367875;11284.830727539094;11303.321800691985;11313.660590182308;11103.44482775862;11303.434828258554;11327.943360283187;11336.149788765793;11345.659630211025;8307.169283071706;8535.018472875638;8539;8654.826903461895;11106.500207490602;31497;38240.80879595612;38990.610093898984;39050.02374940621;40397.990050248685;3405.0976491229844;3912.980435097806;3941.7437866538585;3957.703172262092;4037.596240375968;28844.278893027633;29866.850665746748;30104.548431773543;30341;30691.539626905393;3178.523221516768;3178.634457037447;3214.517822326646;3295.8681652733862;3325.068980685388;458428;460099;460612.6969365132;460896;463355;58580.41419585835;59026.40973590295;59042;59086;59098.40901591015;60932;61228;61268.69365653143;61309.38690613037;61375;14990.625234369229;15485;15508.379664813507;15528.689426211638;15557.533274001798;10824.458777061116;11865;11955.641330759918;11962.700932476757;11982.820257696141;14248.786268205986;14603.123812571279;15612.4535641252;15850.920745396199;16278.860479766303;5799.681017543991;6678.732850686015;6680.131582894192;6695.765648202289;6789.864202715918;776.7087342246657;799.8000499875031;808.4098608016182;815.9714409995622;832.98334033319;24163.758362416153;28405.715942840307;29105.563416548768;29205.977790777222;29222.707772922426;1035.3942943378208;1052.6263176572359;1070.4273213830718;1087.6573879228017;1161.988380116188;12507.312097834525;14972.52062200157;15015;15250.161241131844;15276.479380902027;228302.85848570982;229334;230335;230837;591046.2535245069;56958.430415695315;57011.71494142586;57081.42918570761;57148;57323.713381432826;1368.3021658953805;1401.8107555480083;1405.4940221520244;1411.3931009665941;1417.1780367386891;26495.470090598465;28815;29273.560896586994;29300.853495732386;29387.85306073456;40686;48215;48220.517794823005;48403;48478.27282590693;511140;517250;580590.3586748887;602843;603385;21610.78589674998;23156.421089472224;23459.765402345758;23497.41256468602;23565.410864728517;33797.49303760396;37433;42333.36499952441;43385.56614433941;43954.56045439633;30850;33645.15887102842;35057.474137888465;35301.47047794234;36573;34472;34981.65018349784;35176.82411587926;35836.28327433489;35905.46141807823;33856.15359616029;34517;34612.78855240055;34672.82663586666;34695.479567805996;29611.111666650035;30732.924347647724;30764.846175768977;30825.537616935315;31063.534046989764;21448.356549303546;22824.88587557118;23210.071597135924;23489.530209395376;23571.410714731584 04:45:39 INFO - PROCESS | 1637 | |2;jquery.html;363.9108418437483;383.5742326018121;385.96333348331905;390.35786131813177;415.7152350639812;12769;14459.783103253447;18291.63416731666;19549.827010379388;19906.303279385225;19472.415827525198;19479.026048697542;19496.512587185327;20222.191112355526;20464;5612.298462692163;5954.553408494358;5955.821325360224;5964.105384192384;5964.463198312151;4107.527634322061;4328;5859.4140585941495;6143.600665956721;6433.549651524395;6451;6478.514111441661;6487.967560162217;6489.837754056162;6513.869722605545;6148.784792532262;6318.684065796715;6376.202974628171;6383.712732926983;6393.520485963525;5375.3280839895015;5516;5520.696361700105;5524.944750552483;5616.943830561724;23210.76789232103;23370.883145584332;23498.177563785357;23703.170389036473;23735.88132059346;2698.473797609467;2714.253580265423;2739.9726002739917;2743.8490883001427;2811.74694277514;12760.680982975407;13698;14073.225972571505;15702.214889255605;15828.52514424569;1758.5515693498205;1796.389227662588;1805.873588848781;1806.7470554122433;1808.97286540702;87270;110851.44574277158;111328;111869;111914.44042779815;9159.862602060974;9851.802963940681;11064.391458469701;11133.721656958642;11272.71818204535;12885.54900578494;12935.676608084874;13300.334983250992;13660.93169534146;13701.657458563615;8142.592870356459;8145.5927203639585;8230.382721295926;8256.669733210725;8378.53918034514;8043.7989050273045;8072.354211663171;8234.917650823416;8235.876461853077;8243.793905152419;6741.426978706767;6852.862942741217;6868.278830722802;6870.656467176621;6876.724931002704;1870.484907225152;1920.6254780317984;1922.2407149175997;1923.7499125113645;1927.8264956154014;1002.9669440476321;1011.5296387179997;1013.2906965124442;1021.667957913681;1024.2471783239314;17705.38031168903;18462.35381761632;18713.532161695795;20317.288894888607;20702.482437939172;995.3579940938055;1019.3679918450608;1034.363866222268;1050.4432650695144;1061.1086687182803;9406.90593094074;10964.232503724823;10997.835032474519;11035.83446248307;11071.225014249087;534833;538907.305463478;542385.2880735571;551824;551895;108567;110302;110769.44615276872;131188.99999999808;135791;897.9191872731485;964.4599024546277;1005.3415013166388;1017.3590637898077;1027.8817935937464;18114.818851811313;18278;18371.63256734831;18853.434396968114;18884.025820892715;51730.22404663858;52304.7384763089;53136;53852;53885;71543;77908;78470.82293765484;79973;81304.59347703223;7221.4222862171955;7840.568768717775;7980.6408711606955;8199.754007379788;8310.875336870073;11784.116191285517;12685.112042157329;12732.363381831057;12909.419076141785;13157.947364211032;10945.507452164513;11100.056495197676;11163.274387164809;11393.430328483377;11503;10497.370157790252;10816.67549973502;10949.56201751921;11304;11312.886871131182;11214.607488737855;11409.543618255177;11454.541818327174;11663.358515281741;11698.70753231142;7987.880181797394;8002.1997800219515;8084.555349455836;8441.746747597335;8454.66181352739;7110.5022648414115;9848.803023939337;10306;10824.56701731922;11336.263142895685 04:45:39 INFO - PROCESS | 1637 | |3;mootools.html;1267.28398454873;1320.887724543414;1424.3092100331337;1436.0450300550135;1453.4404254362073;7492.512986655864;7729.304362607364;7734.883976740346;7804.18056104108;7814.179511151318;7430.665620047097;7606.771796846104;7615.504992175504;7624.65689043993;8044.954155959732;2279.122537822938;2295.3228797504776;2330.941726456835;2331.1957374705785;2332.4052366646483;2300.8389412741117;2335.474518233401;2337.345543247893;2355.3993731598416;2356.846804957681;2009.1260301768705;2092.853500254975;2239.4513344230686;2319.072371051576;2346.577616029114;2274.4768703198274;2290.7365652949957;2297.173017713622;2298.689676893623;2309.722833260003;2016.7378240828746;2249.741279752833;2307.711536057993;2316.872572008539;2318.8956496957676;8302.83394332116;8317.334613230987;8384.287335576484;8429.83140337196;8429.957850210709;5004.824831130929;5170.508801663836;5176.637635365526;5180.067587834188;5186.273921650972;3300.85146168423;3487.075924879909;3529.699975502085;3535.982320088409;3571.321448924696;1130.152385710717;1176.9411529423494;1208.9818652720217;1234.8950339221087;1246.426643743879;9382.812343753223;10638.734031649194;10786;10831.891681083245;10879.728006799814;4526.886827829265;4594.908101837945;4640.7215567065405;4687.507811679793;4690.765461726968;9369.812603747887;9414.952925235468;9440.339176257596;9444.811103777885;9446.716598502057;5242.213667949838;5253.080710875582;5253.605979551549;5254.737263136828;5255.658382205144;4108.650764684976;4173.603507666767;4792.95207047925;4817.253325734517;4825.9034819304115;3721.2557488502407;3759.8872033838484;3800.82896269663;3847.634474724897;3853.9979605302824;1247.7192631657804;1254.0845183016268;1260.2419624623783;1274.6112435707014;1276.90423218259;682.4506272450626;767.8195624028349;794.301015106714;796.3072127249214;806.6491076381793;5282.656627319212;5305.575553955674;5328.520433161034;5408.4050754416985;5465.480779325958;550.3340957441461;568.5622071005303;572.7394035713824;577.9595428319977;578.7164289498115;3474.166200111955;3510.613832478425;3670.596234414212;3713.498677678479;3768.3028639701743;9432.858507122399;9883.863355714186;9909.950450247703;9912.603495860085;9919.008099190167;9122.771930701785;9470.431774093577;9658.855117173249;9867.16129129032;9892.851607225897;7587.127480339832;7656.425768067305;7661.731839385595;7673.040869891263;7685.193054729285;5369.436209197978;5475.206095116266;5493.23094766725;5567.47109024642;5661.688607126647;8963.610640350757;9249.213816825646;9255.167034966886;9383.108604682544;9415.105564971316;9358.625654973725;9392.859107113261;9403.294752893697;9435.386699864486;9532.189763869872;6883.380495755406;7044.15470143586;7085.964570177116;7140.7143714252925;7245.456590755609;7847.529148251123;7894.723684671009;7967.442279040411;7988.281054704872;7994.080680721559;5561.9976808349775;6963.89477707907;7098.148222213367;7168.569885806868;7286.6720997556295;7042.260562640849;7048;7062.293770622897;7073.964630176816;7099.396551292992;6200.875982480416;6207.068939659087;6304.5586808922935;6318.557700960889;6405.03924411342;4158.875233742992;4694.413198350207;4735.408073990751;4749.382580264543;4795.856124316276;4693.436787585511;4754.595859351994;4790.95209047919;4809.110314591811;4951.405831300267 04:45:39 INFO - PROCESS | 1637 | |4;prototype.html;347.88867562380034;358.32098173960344;364.4351255553891;367.79587329032375;370.4313878196966;5188.143956247224;5282.471752824716;5302.628815982883;5323.22813192087;5370.328708911381;4415.425994620695;4448.888777780557;4644.97677511612;4678.97660511697;4918.262260660903;3043.812912963946;3612.422012478004;3810.695144388449;3970.940435893464;3976.145128797308;4040.7777572233517;4067.776272305022;4092.877213683594;4095.692823038269;4125;3493.615702272748;3640.981795091021;3923.9773090444132;4057.797110144496;4086.734362266458;3523.6300188480227;3568.571771387424;3766.4538641897047;3911.706622003361;4052.189562087565;3121.7034381733924;3535.4520049392386;3618.240169564395;3635.4910312556262;3649.872254471107;4147.751134931884;5033.546980771712;5088.643794934356;5098.974505127488;5153.768080436352;4304.698671093025;4384.846530371454;4531.3007622141695;4757.595604373632;4783.545563171493;5519.862003449946;5537;5542.528885044736;5573.972130139324;5605.635633683838;3781.489498917666;3791.620837916214;3795.7532760370486;3799.3161230978412;3831.444440556104;4564.3838081859185;5455.563554915637;5535.944640553583;5650.576206784466;5810;3731.347014272546;3762.8523300393645;3780.432935059708;3804.2771873343977;3820.3887378019385;3804.2201348723447;3819.618038196158;3853.460515527828;3874.767513949172;3899.74651647642;4324.394584758136;4486.327050942385;4503.81984720614;4511.842085526991;4536.478304994902;4923.310736496894;5030.094582975026;5457.635591102225;5586.888262234733;5631.324241091013;1079.8704155501232;1193.4032983508246;1208.9335086570322;1228.4287806170173;1254.4166962362544;732.7948174511146;848.9320854331638;850.7617866997251;863.6027427383349;867.7526904832218;4635.3278774578175;4760.95239047612;4889.313186950496;4939.431965323962;4958.454569997298;5475.645472446807;5490.529261832192;5492.80775172867;5494.862628434241;5513.42109078549;3705.6109108543214;4187.267228235047;4692.032331986997;4738.228136540162;4883.975580122148;2543.4150145466647;2620.213935819285;2712.769414599781;2788.60959465675;2891.542189139257;4922.843131864009;5466.289382380344;5626.690532020778;5671.043489995945;5714.685622306893;4845.297431872359;5055.747212639353;5134.122583545646;5646.774129034792;5677.233573467612;5021.949780502221;5072.82245121419;5446.863828404163;5640.717964101696;5649.576281778883;4791.856244312537;4911.017796440798;4938.703677779345;4972.676776009548;5119.87200319995;4754.7384893829785;4756.405449318835;4932.210846264724;5003.574696150868;5281.313429254173;5098.97450512745;5628.577856660685;5658.773649053992;5723.484886360247;5793.102069179284;3247.7564182686865;3806.4670946068127;3819.78991155478;3834.750741201813;3866.2654095721723;4819.036192761391;4959.628027897994;5627.493525582717;5781.335146458211;5812.534997200299;4601.976990115028;5509.57578665386;5530.6128570999645;5540.80607178763;5583.74873130702;4653.86038418848;5230.686158830483;5482.945170548403;5504.146857237135;5572.359178694502;5312.282841816305;5345.518903298722;5348.652337598043;5416.485433883775;5476;5188.922166167586;5244.951009798132;5446.883388905344;5451.836444906659;5560.666360018412;4976.303317535476;5058.974705126451;5207.973960130326;5373.623846330719;5389.487998640123 04:45:39 INFO - PROCESS | 1637 | |5;yui.html;699.3251512290642;700.7057036044861;723.6454137472637;770.1335997003371;781.2032922138742;14968.401263949443;15596.298166582503;15665.608359791011;15778.053316801;15952.680946381066;13962.581122566315;14218.71562568748;14324.427022919097;14428;14528.854711452883;862.9827403451927;876.9386142969995;880.3133555826465;881.8985816631106;882.9337799665019;693.9236683964759;711.0329951266274;841.2134654098437;865.9134086591354;868.1578868497547;912.3522299167599;912.8585069314233;913.7943962608393;915.1397686175007;915.5102020419077;903.2187158108255;905.6060613633091;909.4543274035592;909.8180363927241;912.0332447605526;529.0556356902948;543.4429709547705;546.2707285773497;546.2870953405804;546.8386825886373;14448.927755361156;14975.026623269452;15039.548813535612;15142.394304227817;15345.386184552604;14218.573442796733;14271.357788899524;14343.713125737428;14379.496717614833;14408.855911440856;9857.802843943153;11644.35956022427;11664.125190610737;11717;12022.90360161609;3723.6090210528046;3743.2513497300647;3747.0445036515516;3852.287326844543;3858.614138586147;91376;92805.53597232039;93573.53213233958;94634.5268273661;95631.52184239104;10926.508307126198;10980.505877235544;10997;11244.26912250701;11537.711557211136;22627.43431414195;22766.886165569067;22858.885705571367;22947.770522294562;23051.7694823053;6817.625030623264;7557.4331925105835;7781.649825757854;7833.216678332121;8070.9192908071345;4266.722663026893;4285.121550082225;4319.244132276902;4338.739675619473;4387.736735795798;3500.1949551603275;3546.680798728127;3591.012471570287;3599.7300202484917;3607.3687104756564;645.4287955159624;649.4382359259207;660.9636469994197;668.1714673804545;672.4553111979312;580.1935309919215;603.6860832367145;658.9209294884548;664.9368310010541;679.4734081087098;8859.867101993475;8882.467051976902;9415.293852961055;9430.717078487656;9561.952190239144;630.2940706408759;642.8071578526517;682.3381320119477;688.5902887781762;688.5937296994797;4550.726956382628;4602.585767280961;4603.355530225771;4612.86161415158;4713.410823647044;74946;90631.54684226536;96317.51841240888;100443;102764.48617756863;85902.57048714842;87245.1275487237;87292;89032;89942;62263.06605400832;66553.33446665472;67278;72739.27260727431;80343;9742.95128524353;9800.36297640651;10068.647597334058;10134.543945522617;10192.490375481344;43190;45375.54624453713;47433;49776;51097.23354149616;48764;57732.26803195911;57862.71068644798;57870.4212957865;58391.08044597838;10661.466926653482;11985.34080625574;12438.564650237198;13160.47358105665;13668.52160174389;14585.270736462922;14712.779308310597;14770.556883293519;14956.700865982837;15170.848291516944;12120.454579543766;13333.86666133365;13964.441422342992;14231.786523201954;14355.354009069408;12544.49822007146;13636.93181534086;13984.930075349965;14405.711885762017;14617.707645847237;12244.265344079384;13407.463701452223;13706.38321275525;13725.725485490433;13813.51652692151;9272.72181834546;10865.891341086804;11195.60815371458;11343.716407089889;11927;10444.373337599769;11356.886431135583;11385.601503947322;11406.543738250377;11603.36181510025 04:45:39 INFO - PROCESS | 1637 | __end_tp_report 04:45:39 INFO - PROCESS | 1637 | __start_cc_report 04:45:39 INFO - PROCESS | 1637 | _x_x_mozilla_cycle_collect,1038 04:45:39 INFO - PROCESS | 1637 | __end_cc_report 04:45:39 INFO - PROCESS | 1637 | __startTimestamp1483533939279__endTimestamp 04:45:39 INFO - PROCESS | 1637 | 04:45:39 INFO - PROCESS | 1637 | ------- Summary: start ------- 04:45:39 INFO - PROCESS | 1637 | Number of tests: 6 04:45:39 INFO - PROCESS | 1637 | 04:45:39 INFO - PROCESS | 1637 | [#0] dojo.html Cycles:165 Average:5698.27 Median:5846.85 stddev:657.53 (11.2%) stddev-sans-first:631.84 04:45:39 INFO - PROCESS | 1637 | Values: 3290.9 3293.0 3298.7 3327.7 3359.7 4595.7 5528.1 5569.7 5576.0 5585.1 5616.1 5623.6 5662.8 5669.5 5670.6 5105.9 5160.0 5166.4 5168.9 5212.3 5223.8 5239.8 5267.7 5335.7 5341.7 5111.5 5269.6 5292.7 5294.7 5300.4 5241.4 5317.3 5320.1 5324.5 5333.3 5042.2 5151.9 5209.6 5226.8 5250.9 4726.7 5550.7 5622.4 5632.1 5643.9 6123.5 6175.4 6197.0 6199.0 6223.1 6224.8 6291.2 6300.9 6312.0 6322.7 5640.9 5681.0 5682.8 5758.8 5773.0 6232.5 6277.7 6278.3 6285.8 6300.4 6071.2 6134.4 6139.1 6139.8 6154.4 6098.2 6104.7 6208.2 6271.2 6274.2 6105.2 6112.5 6112.8 6116.0 6128.7 6195.4 6200.1 6201.0 6208.4 6223.9 4772.7 4786.3 4821.0 4829.4 4916.5 3797.9 4433.9 4450.5 4454.9 4462.4 5696.2 5702.3 5730.9 5753.5 5795.4 6249.6 6250.3 6277.5 6288.2 6291.6 5766.4 5779.2 5796.5 5804.0 5846.9 5201.0 5230.7 5235.4 5359.3 5383.0 6225.8 6250.9 6269.8 6301.6 6307.2 6298.4 6306.6 6318.5 6321.6 6328.0 6215.2 6304.3 6327.6 6330.8 6333.0 5275.5 5791.3 6060.8 6063.8 6173.7 6206.4 6250.2 6265.8 6267.2 6272.4 6031.0 6044.7 6046.7 6075.2 6103.1 5834.8 5836.9 6025.9 6036.9 6066.4 5926.2 5928.5 5954.1 5968.4 6002.2 5868.3 5920.8 5947.6 6076.2 6103.6 5761.5 5762.6 5766.4 5813.1 5851.4 04:45:39 INFO - PROCESS | 1637 | 04:45:39 INFO - PROCESS | 1637 | [#1] ext.html Cycles:180 Average:58270.65 Median:34564.89 stddev:123845.67 (358.3%) stddev-sans-first:124117.05 04:45:39 INFO - PROCESS | 1637 | Values: 466.8 479.1 482.8 487.1 503.2 30133.5 38752.2 39823.8 39824.0 41972.0 33360.2 41250.8 41586.0 41699.4 41770.6 9251.6 9457.2 9467.1 9473.7 9493.1 8405.2 8635.7 9225.0 9402.7 11317.6 11223.8 11280.0 11284.8 11303.3 11313.7 11103.4 11303.4 11327.9 11336.1 11345.7 8307.2 8535.0 8539.0 8654.8 11106.5 31497.0 38240.8 38990.6 39050.0 40398.0 3405.1 3913.0 3941.7 3957.7 4037.6 28844.3 29866.9 30104.5 30341.0 30691.5 3178.5 3178.6 3214.5 3295.9 3325.1 458428.0 460099.0 460612.7 460896.0 463355.0 58580.4 59026.4 59042.0 59086.0 59098.4 60932.0 61228.0 61268.7 61309.4 61375.0 14990.6 15485.0 15508.4 15528.7 15557.5 10824.5 11865.0 11955.6 11962.7 11982.8 14248.8 14603.1 15612.5 15850.9 16278.9 5799.7 6678.7 6680.1 6695.8 6789.9 776.7 799.8 808.4 816.0 833.0 24163.8 28405.7 29105.6 29206.0 29222.7 1035.4 1052.6 1070.4 1087.7 1162.0 12507.3 14972.5 15015.0 15250.2 15276.5 228302.9 229334.0 230335.0 230837.0 591046.3 56958.4 57011.7 57081.4 57148.0 57323.7 1368.3 1401.8 1405.5 1411.4 1417.2 26495.5 28815.0 29273.6 29300.9 29387.9 40686.0 48215.0 48220.5 48403.0 48478.3 511140.0 517250.0 580590.4 602843.0 603385.0 21610.8 23156.4 23459.8 23497.4 23565.4 33797.5 37433.0 42333.4 43385.6 43954.6 30850.0 33645.2 35057.5 35301.5 36573.0 34472.0 34981.7 35176.8 35836.3 35905.5 33856.2 34517.0 34612.8 34672.8 34695.5 29611.1 30732.9 30764.8 30825.5 31063.5 21448.4 22824.9 23210.1 23489.5 23571.4 04:45:39 INFO - PROCESS | 1637 | 04:45:39 INFO - PROCESS | 1637 | [#2] jquery.html Cycles:180 Average:32852.43 Median:21956.63 stddev:90856.58 (413.8%) stddev-sans-first:91078.71 04:45:39 INFO - PROCESS | 1637 | Values: 363.9 383.6 386.0 390.4 415.7 12769.0 14459.8 18291.6 19549.8 19906.3 19472.4 19479.0 19496.5 20222.2 20464.0 5612.3 5954.6 5955.8 5964.1 5964.5 4107.5 4328.0 5859.4 6143.6 6433.5 6451.0 6478.5 6488.0 6489.8 6513.9 6148.8 6318.7 6376.2 6383.7 6393.5 5375.3 5516.0 5520.7 5524.9 5616.9 23210.8 23370.9 23498.2 23703.2 23735.9 2698.5 2714.3 2740.0 2743.8 2811.7 12760.7 13698.0 14073.2 15702.2 15828.5 1758.6 1796.4 1805.9 1806.7 1809.0 87270.0 110851.4 111328.0 111869.0 111914.4 9159.9 9851.8 11064.4 11133.7 11272.7 12885.5 12935.7 13300.3 13660.9 13701.7 8142.6 8145.6 8230.4 8256.7 8378.5 8043.8 8072.4 8234.9 8235.9 8243.8 6741.4 6852.9 6868.3 6870.7 6876.7 1870.5 1920.6 1922.2 1923.7 1927.8 1003.0 1011.5 1013.3 1021.7 1024.2 17705.4 18462.4 18713.5 20317.3 20702.5 995.4 1019.4 1034.4 1050.4 1061.1 9406.9 10964.2 10997.8 11035.8 11071.2 534833.0 538907.3 542385.3 551824.0 551895.0 108567.0 110302.0 110769.4 131189.0 135791.0 897.9 964.5 1005.3 1017.4 1027.9 18114.8 18278.0 18371.6 18853.4 18884.0 51730.2 52304.7 53136.0 53852.0 53885.0 71543.0 77908.0 78470.8 79973.0 81304.6 7221.4 7840.6 7980.6 8199.8 8310.9 11784.1 12685.1 12732.4 12909.4 13157.9 10945.5 11100.1 11163.3 11393.4 11503.0 10497.4 10816.7 10949.6 11304.0 11312.9 11214.6 11409.5 11454.5 11663.4 11698.7 7987.9 8002.2 8084.6 8441.7 8454.7 7110.5 9848.8 10306.0 10824.6 11336.3 04:45:39 INFO - PROCESS | 1637 | 04:45:39 INFO - PROCESS | 1637 | [#3] mootools.html Cycles:180 Average:5332.10 Median:5317.05 stddev:2927.90 (55.1%) stddev-sans-first:2920.17 04:45:39 INFO - PROCESS | 1637 | Values: 1267.3 1320.9 1424.3 1436.0 1453.4 7492.5 7729.3 7734.9 7804.2 7814.2 7430.7 7606.8 7615.5 7624.7 8045.0 2279.1 2295.3 2330.9 2331.2 2332.4 2300.8 2335.5 2337.3 2355.4 2356.8 2009.1 2092.9 2239.5 2319.1 2346.6 2274.5 2290.7 2297.2 2298.7 2309.7 2016.7 2249.7 2307.7 2316.9 2318.9 8302.8 8317.3 8384.3 8429.8 8430.0 5004.8 5170.5 5176.6 5180.1 5186.3 3300.9 3487.1 3529.7 3536.0 3571.3 1130.2 1176.9 1209.0 1234.9 1246.4 9382.8 10638.7 10786.0 10831.9 10879.7 4526.9 4594.9 4640.7 4687.5 4690.8 9369.8 9415.0 9440.3 9444.8 9446.7 5242.2 5253.1 5253.6 5254.7 5255.7 4108.7 4173.6 4793.0 4817.3 4825.9 3721.3 3759.9 3800.8 3847.6 3854.0 1247.7 1254.1 1260.2 1274.6 1276.9 682.5 767.8 794.3 796.3 806.6 5282.7 5305.6 5328.5 5408.4 5465.5 550.3 568.6 572.7 578.0 578.7 3474.2 3510.6 3670.6 3713.5 3768.3 9432.9 9883.9 9910.0 9912.6 9919.0 9122.8 9470.4 9658.9 9867.2 9892.9 7587.1 7656.4 7661.7 7673.0 7685.2 5369.4 5475.2 5493.2 5567.5 5661.7 8963.6 9249.2 9255.2 9383.1 9415.1 9358.6 9392.9 9403.3 9435.4 9532.2 6883.4 7044.2 7086.0 7140.7 7245.5 7847.5 7894.7 7967.4 7988.3 7994.1 5562.0 6963.9 7098.1 7168.6 7286.7 7042.3 7048.0 7062.3 7074.0 7099.4 6200.9 6207.1 6304.6 6318.6 6405.0 4158.9 4694.4 4735.4 4749.4 4795.9 4693.4 4754.6 4791.0 4809.1 4951.4 04:45:39 INFO - PROCESS | 1637 | 04:45:39 INFO - PROCESS | 1637 | [#4] prototype.html Cycles:180 Average:4378.41 Median:4900.17 stddev:1332.03 (27.2%) stddev-sans-first:1300.96 04:45:39 INFO - PROCESS | 1637 | Values: 347.9 358.3 364.4 367.8 370.4 5188.1 5282.5 5302.6 5323.2 5370.3 4415.4 4448.9 4645.0 4679.0 4918.3 3043.8 3612.4 3810.7 3970.9 3976.1 4040.8 4067.8 4092.9 4095.7 4125.0 3493.6 3641.0 3924.0 4057.8 4086.7 3523.6 3568.6 3766.5 3911.7 4052.2 3121.7 3535.5 3618.2 3635.5 3649.9 4147.8 5033.5 5088.6 5099.0 5153.8 4304.7 4384.8 4531.3 4757.6 4783.5 5519.9 5537.0 5542.5 5574.0 5605.6 3781.5 3791.6 3795.8 3799.3 3831.4 4564.4 5455.6 5535.9 5650.6 5810.0 3731.3 3762.9 3780.4 3804.3 3820.4 3804.2 3819.6 3853.5 3874.8 3899.7 4324.4 4486.3 4503.8 4511.8 4536.5 4923.3 5030.1 5457.6 5586.9 5631.3 1079.9 1193.4 1208.9 1228.4 1254.4 732.8 848.9 850.8 863.6 867.8 4635.3 4761.0 4889.3 4939.4 4958.5 5475.6 5490.5 5492.8 5494.9 5513.4 3705.6 4187.3 4692.0 4738.2 4884.0 2543.4 2620.2 2712.8 2788.6 2891.5 4922.8 5466.3 5626.7 5671.0 5714.7 4845.3 5055.7 5134.1 5646.8 5677.2 5021.9 5072.8 5446.9 5640.7 5649.6 4791.9 4911.0 4938.7 4972.7 5119.9 4754.7 4756.4 4932.2 5003.6 5281.3 5099.0 5628.6 5658.8 5723.5 5793.1 3247.8 3806.5 3819.8 3834.8 3866.3 4819.0 4959.6 5627.5 5781.3 5812.5 4602.0 5509.6 5530.6 5540.8 5583.7 4653.9 5230.7 5482.9 5504.1 5572.4 5312.3 5345.5 5348.7 5416.5 5476.0 5188.9 5245.0 5446.9 5451.8 5560.7 4976.3 5059.0 5208.0 5373.6 5389.5 04:45:39 INFO - PROCESS | 1637 | 04:45:39 INFO - PROCESS | 1637 | [#5] yui.html Cycles:180 Average:19235.18 Median:4576.66 stddev:26636.62 (582.0%) stddev-sans-first:26674.98 04:45:39 INFO - PROCESS | 1637 | Values: 699.3 700.7 723.6 770.1 781.2 14968.4 15596.3 15665.6 15778.1 15952.7 13962.6 14218.7 14324.4 14428.0 14528.9 863.0 876.9 880.3 881.9 882.9 693.9 711.0 841.2 865.9 868.2 912.4 912.9 913.8 915.1 915.5 903.2 905.6 909.5 909.8 912.0 529.1 543.4 546.3 546.3 546.8 14448.9 14975.0 15039.5 15142.4 15345.4 14218.6 14271.4 14343.7 14379.5 14408.9 9857.8 11644.4 11664.1 11717.0 12022.9 3723.6 3743.3 3747.0 3852.3 3858.6 91376.0 92805.5 93573.5 94634.5 95631.5 10926.5 10980.5 10997.0 11244.3 11537.7 22627.4 22766.9 22858.9 22947.8 23051.8 6817.6 7557.4 7781.6 7833.2 8070.9 4266.7 4285.1 4319.2 4338.7 4387.7 3500.2 3546.7 3591.0 3599.7 3607.4 645.4 649.4 661.0 668.2 672.5 580.2 603.7 658.9 664.9 679.5 8859.9 8882.5 9415.3 9430.7 9562.0 630.3 642.8 682.3 688.6 688.6 4550.7 4602.6 4603.4 4612.9 4713.4 74946.0 90631.5 96317.5 100443.0 102764.5 85902.6 87245.1 87292.0 89032.0 89942.0 62263.1 66553.3 67278.0 72739.3 80343.0 9743.0 9800.4 10068.6 10134.5 10192.5 43190.0 45375.5 47433.0 49776.0 51097.2 48764.0 57732.3 57862.7 57870.4 58391.1 10661.5 11985.3 12438.6 13160.5 13668.5 14585.3 14712.8 14770.6 14956.7 15170.8 12120.5 13333.9 13964.4 14231.8 14355.4 12544.5 13636.9 13984.9 14405.7 14617.7 12244.3 13407.5 13706.4 13725.7 13813.5 9272.7 10865.9 11195.6 11343.7 11927.0 10444.4 11356.9 11385.6 11406.5 11603.4 04:45:39 INFO - PROCESS | 1637 | -------- Summary: end -------- 04:45:39 INFO - PROCESS | 1637 | 04:45:39 INFO - TEST-INFO | 1637: exit 0 04:45:39 INFO - TEST-OK | dromaeo_css | took 1120021ms 04:45:39 INFO - TEST-START | kraken 04:45:39 INFO - operating with platform_type : mac_ 04:45:39 INFO - Initialising browser for kraken test... 04:45:39 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/tmpQq9pIc/profile http://localhost:49241/getInfo.html) 04:45:40 INFO - PROCESS | 1648 | 1483533940452 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/FirefoxNightly.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/FirefoxNightly.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 04:45:42 INFO - PROCESS | 1648 | __metrics Screen width/height:1600/1200 04:45:42 INFO - PROCESS | 1648 | colorDepth:24 04:45:42 INFO - PROCESS | 1648 | Browser inner width/height: 1024/689 04:45:42 INFO - PROCESS | 1648 | __metrics 04:45:42 INFO - PROCESS | 1648 | 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" 04:45:42 INFO - PROCESS | 1648 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:45:42 INFO - TEST-INFO | 1648: exit 0 04:45:42 INFO - Browser initialized. 04:45:42 INFO - Running cycle 1/1 for kraken test... 04:45:42 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/tmpQq9pIc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:45:54 INFO - PROCESS | 1650 | RSS: Main: 234070016 04:45:54 INFO - PROCESS | 1650 | 04:45:57 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 04:45:58 INFO - PROCESS | 1650 | RSS: Main: 287870976 04:45:58 INFO - PROCESS | 1650 | 04:46:01 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 04:46:02 INFO - PROCESS | 1650 | RSS: Main: 287588352 04:46:02 INFO - PROCESS | 1650 | 04:46:05 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 04:46:05 INFO - PROCESS | 1650 | RSS: Main: 380440576 04:46:05 INFO - PROCESS | 1650 | 04:46:07 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 04:46:08 INFO - PROCESS | 1650 | RSS: Main: 281079808 04:46:08 INFO - PROCESS | 1650 | 04:46:10 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:46:11 INFO - PROCESS | 1650 | RSS: Main: 285671424 04:46:11 INFO - PROCESS | 1650 | 04:46:14 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 04:46:14 INFO - PROCESS | 1650 | RSS: Main: 327426048 04:46:14 INFO - PROCESS | 1650 | 04:46:17 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 04:46:18 INFO - PROCESS | 1650 | RSS: Main: 329371648 04:46:18 INFO - PROCESS | 1650 | 04:46:20 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 04:46:21 INFO - PROCESS | 1650 | RSS: Main: 327651328 04:46:21 INFO - PROCESS | 1650 | 04:46:23 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:46:23 INFO - PROCESS | 1650 | RSS: Main: 303595520 04:46:23 INFO - PROCESS | 1650 | 04:46:25 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:46:26 INFO - PROCESS | 1650 | RSS: Main: 306106368 04:46:26 INFO - PROCESS | 1650 | 04:46:28 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:46:29 INFO - PROCESS | 1650 | RSS: Main: 308568064 04:46:29 INFO - PROCESS | 1650 | 04:46:32 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:46:33 INFO - PROCESS | 1650 | RSS: Main: 325361664 04:46:33 INFO - PROCESS | 1650 | 04:46:35 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:46:36 INFO - PROCESS | 1650 | RSS: Main: 318455808 04:46:36 INFO - PROCESS | 1650 | 04:46:38 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 04:46:38 INFO - PROCESS | 1650 | RSS: Main: 362434560 04:46:38 INFO - PROCESS | 1650 | 04:46:38 INFO - PROCESS | 1650 | __start_tp_report 04:46:38 INFO - PROCESS | 1650 | _x_x_mozilla_page_load 04:46:38 INFO - PROCESS | 1650 | _x_x_mozilla_page_load_details 04:46:38 INFO - PROCESS | 1650 | |i|pagename|runs| 04:46:38 INFO - PROCESS | 1650 | |0;ai-astar;114;114;116;114;114;120;154;152;113;113 04:46:38 INFO - PROCESS | 1650 | |1;audio-beat-detection;143;109;113;158;138;133;124;111;130;113 04:46:38 INFO - PROCESS | 1650 | |2;audio-dft;184;186;185;186;186;183;182;184;185;185 04:46:38 INFO - PROCESS | 1650 | |3;audio-fft;78;59;59;88;62;61;59;59;75;57 04:46:38 INFO - PROCESS | 1650 | |4;audio-oscillator;90;67;86;70;68;68;83;70;69;85 04:46:38 INFO - PROCESS | 1650 | |5;imaging-gaussian-blur;89;87;90;87;90;86;97;107;90;87 04:46:38 INFO - PROCESS | 1650 | |6;imaging-darkroom;95;96;95;95;94;94;95;95;95;94 04:46:38 INFO - PROCESS | 1650 | |7;imaging-desaturate;88;88;85;88;85;85;86;85;88;85 04:46:38 INFO - PROCESS | 1650 | |8;json-parse-financial;55;55;55;55;55;55;56;55;55;57 04:46:38 INFO - PROCESS | 1650 | |9;json-stringify-tinderbox;65;65;77;77;73;71;71;71;70;70 04:46:38 INFO - PROCESS | 1650 | |10;stanford-crypto-aes;75;83;77;104;100;98;99;96;93;87 04:46:38 INFO - PROCESS | 1650 | |11;stanford-crypto-ccm;138;159;147;150;140;140;140;140;205;180 04:46:38 INFO - PROCESS | 1650 | |12;stanford-crypto-pbkdf2;208;172;173;170;172;170;181;168;170;171 04:46:38 INFO - PROCESS | 1650 | |13;stanford-crypto-sha256-iterative;69;82;82;84;83;70;66;65;65;67 04:46:38 INFO - PROCESS | 1650 | __end_tp_report 04:46:38 INFO - PROCESS | 1650 | __start_cc_report 04:46:38 INFO - PROCESS | 1650 | _x_x_mozilla_cycle_collect,1994 04:46:38 INFO - PROCESS | 1650 | __end_cc_report 04:46:38 INFO - PROCESS | 1650 | __startTimestamp1483533998662__endTimestamp 04:46:38 INFO - PROCESS | 1650 | 04:46:38 INFO - PROCESS | 1650 | ------- Summary: start ------- 04:46:38 INFO - PROCESS | 1650 | Number of tests: 14 04:46:38 INFO - PROCESS | 1650 | 04:46:38 INFO - PROCESS | 1650 | [#0] ai-astar Cycles:10 Average:122.40 Median:115.00 stddev:16.26 (14.1%) stddev-sans-first:16.96 04:46:38 INFO - PROCESS | 1650 | Values: 114.0 114.0 116.0 114.0 114.0 120.0 154.0 152.0 113.0 113.0 04:46:38 INFO - PROCESS | 1650 | 04:46:38 INFO - PROCESS | 1650 | [#1] audio-beat-detection Cycles:10 Average:127.20 Median:131.50 stddev:16.21 (12.3%) stddev-sans-first:16.15 04:46:38 INFO - PROCESS | 1650 | Values: 143.0 109.0 113.0 158.0 138.0 133.0 124.0 111.0 130.0 113.0 04:46:38 INFO - PROCESS | 1650 | 04:46:38 INFO - PROCESS | 1650 | [#2] audio-dft Cycles:10 Average:184.60 Median:185.00 stddev:1.35 (0.7%) stddev-sans-first:1.41 04:46:38 INFO - PROCESS | 1650 | Values: 184.0 186.0 185.0 186.0 186.0 183.0 182.0 184.0 185.0 185.0 04:46:38 INFO - PROCESS | 1650 | 04:46:38 INFO - PROCESS | 1650 | [#3] audio-fft Cycles:10 Average:65.70 Median:61.50 stddev:10.68 (17.4%) stddev-sans-first:10.36 04:46:38 INFO - PROCESS | 1650 | Values: 78.0 59.0 59.0 88.0 62.0 61.0 59.0 59.0 75.0 57.0 04:46:38 INFO - PROCESS | 1650 | 04:46:38 INFO - PROCESS | 1650 | [#4] audio-oscillator Cycles:10 Average:75.60 Median:76.50 stddev:9.16 (12.0%) stddev-sans-first:8.09 04:46:38 INFO - PROCESS | 1650 | Values: 90.0 67.0 86.0 70.0 68.0 68.0 83.0 70.0 69.0 85.0 04:46:38 INFO - PROCESS | 1650 | 04:46:38 INFO - PROCESS | 1650 | [#5] imaging-gaussian-blur Cycles:10 Average:91.00 Median:89.50 stddev:6.43 (7.2%) stddev-sans-first:6.78 04:46:38 INFO - PROCESS | 1650 | Values: 89.0 87.0 90.0 87.0 90.0 86.0 97.0 107.0 90.0 87.0 04:46:38 INFO - PROCESS | 1650 | 04:46:38 INFO - PROCESS | 1650 | [#6] imaging-darkroom Cycles:10 Average:94.80 Median:95.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 04:46:38 INFO - PROCESS | 1650 | Values: 95.0 96.0 95.0 95.0 94.0 94.0 95.0 95.0 95.0 94.0 04:46:38 INFO - PROCESS | 1650 | 04:46:38 INFO - PROCESS | 1650 | [#7] imaging-desaturate Cycles:10 Average:86.30 Median:87.00 stddev:1.49 (1.7%) stddev-sans-first:1.45 04:46:38 INFO - PROCESS | 1650 | Values: 88.0 88.0 85.0 88.0 85.0 85.0 86.0 85.0 88.0 85.0 04:46:38 INFO - PROCESS | 1650 | 04:46:38 INFO - PROCESS | 1650 | [#8] json-parse-financial Cycles:10 Average:55.30 Median:55.00 stddev:0.67 (1.2%) stddev-sans-first:0.71 04:46:38 INFO - PROCESS | 1650 | Values: 55.0 55.0 55.0 55.0 55.0 55.0 56.0 55.0 55.0 57.0 04:46:38 INFO - PROCESS | 1650 | 04:46:38 INFO - PROCESS | 1650 | [#9] json-stringify-tinderbox Cycles:10 Average:71.00 Median:71.00 stddev:4.08 (5.7%) stddev-sans-first:3.71 04:46:38 INFO - PROCESS | 1650 | Values: 65.0 65.0 77.0 77.0 73.0 71.0 71.0 71.0 70.0 70.0 04:46:38 INFO - PROCESS | 1650 | 04:46:38 INFO - PROCESS | 1650 | [#10] stanford-crypto-aes Cycles:10 Average:91.20 Median:90.00 stddev:10.13 (11.3%) stddev-sans-first:8.89 04:46:38 INFO - PROCESS | 1650 | Values: 75.0 83.0 77.0 104.0 100.0 98.0 99.0 96.0 93.0 87.0 04:46:38 INFO - PROCESS | 1650 | 04:46:38 INFO - PROCESS | 1650 | [#11] stanford-crypto-ccm Cycles:10 Average:153.90 Median:148.50 stddev:22.13 (14.9%) stddev-sans-first:22.71 04:46:38 INFO - PROCESS | 1650 | Values: 138.0 159.0 147.0 150.0 140.0 140.0 140.0 140.0 205.0 180.0 04:46:38 INFO - PROCESS | 1650 | 04:46:38 INFO - PROCESS | 1650 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.50 Median:172.00 stddev:11.95 (6.9%) stddev-sans-first:3.72 04:46:38 INFO - PROCESS | 1650 | Values: 208.0 172.0 173.0 170.0 172.0 170.0 181.0 168.0 170.0 171.0 04:46:38 INFO - PROCESS | 1650 | 04:46:38 INFO - PROCESS | 1650 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.30 Median:76.00 stddev:8.30 (10.9%) stddev-sans-first:8.66 04:46:38 INFO - PROCESS | 1650 | Values: 69.0 82.0 82.0 84.0 83.0 70.0 66.0 65.0 65.0 67.0 04:46:38 INFO - PROCESS | 1650 | -------- Summary: end -------- 04:46:38 INFO - PROCESS | 1650 | 04:46:39 INFO - TEST-INFO | 1650: exit 0 04:46:39 INFO - TEST-OK | kraken | took 59727ms 04:46:39 INFO - SUITE-END | took 1179s 04:46:39 INFO - Completed test suite (00:19:39) 04:46:39 INFO - javascript benchmark 04:46:39 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3290.884819031333, 3293.02855657581, 3298.7361011119115, 3327.683870032346, 3359.664033596641, 4595.655325850558, 5528.087865502197, 5569.721513924308, 5575.9999999999945, 5585.106382978719, 5616.129499927508, 5623.578231632634, 5662.773489060443, 5669.546436285098, 5670.574706896979, 5105.948940510594, 5160.045391602565, 5166.380034395859, 5168.896622067557, 5212.322398088243, 5223.817166399177, 5239.764210610512, 5267.65760225586, 5335.733213339317, 5341.732913354317, 5111.514406131411, 5269.604779641542, 5292.682439053649, 5294.655847369909, 5300.443453437391, 5241.423443421201, 5317.2821669074565, 5320.14877619579, 5324.467553244684, 5333.253344531769, 5042.218456139304, 5151.9484805152215, 5209.635325527214, 5226.843194704165, 5250.949810038008, 4726.66913316068, 5550.72246387679, 5622.353429355636, 5632.098864181711, 5643.858903527444, 6123.4795042421, 6175.44421002112, 6197.039458883835, 6199.0, 6223.066540018988, 6224.813255602339, 6291.245050593912, 6300.936990630081, 6311.96844015777, 6322.683865806691, 5640.85897852557, 5681.0, 5682.801101961411, 5758.798442054592, 5772.989726797836, 6232.470240029648, 6277.748890044347, 6278.277998030194, 6285.842853928597, 6300.369963003663, 6071.150038994544, 6134.38656134383, 6139.10982907476, 6139.754409823557, 6154.415330543591, 6098.176746139215, 6104.708921120657, 6208.2239720034995, 6271.153394291803, 6274.247090349096, 6105.206323177958, 6112.51099912006, 6112.786052488142, 6116.021436570128, 6128.662923539159, 6195.380461953769, 6200.131981522591, 6201.0, 6208.441240288395, 6223.906641400383, 4772.737499437564, 4786.2820576913045, 4820.9517904820505, 4829.444613869379, 4916.508349165055, 3797.867074652374, 4433.911321773547, 4450.46594408673, 4454.933176002362, 4462.44219472566, 5696.231008813757, 5702.315722113373, 5730.914036289459, 5753.482186603226, 5795.391483894215, 6249.593776404519, 6250.281217659936, 6277.497800175975, 6288.182536270347, 6291.622502649856, 5766.365699773021, 5779.162021506858, 5796.478316951495, 5804.0, 5846.8538286542325, 5201.037808005531, 5230.712310822942, 5235.397929238186, 5359.276497672842, 5383.031054410244, 6225.844353891189, 6250.874982500233, 6269.843253918689, 6301.590396624205, 6307.211598550273, 6298.433141017331, 6306.621602703853, 6318.462930650946, 6321.620702757849, 6328.019157030483, 6215.223097112861, 6304.3380445054445, 6327.620342779448, 6330.841728956813, 6333.0, 5275.472452754694, 5791.305043394819, 6060.818175454743, 6063.757449702139, 6173.722182501709, 6206.441420272197, 6250.249970003629, 6265.8120256392385, 6267.2165979252595, 6272.372762723691, 6030.969845150747, 6044.7279872406725, 6046.667433291212, 6075.2405949256345, 6103.145559621567, 5834.766609335579, 5836.912446313394, 6025.939740602713, 6036.939630603813, 6066.36296723624, 5926.170336153028, 5928.466438020599, 5954.136650185786, 5968.373320801427, 6002.249718785152, 5868.3251426084835, 5920.79277225295, 5947.583669143291, 6076.210092687922, 6103.633781973096, 5761.48146666802, 5762.596618236851, 5766.365699772938, 5813.09896965971, 5851.385604511635], "value": 5655.030269103996, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [466.7572862111702, 479.094511373504, 482.7803349475989, 487.1134535146031, 503.15470010382563, 30133.54799678004, 38752.22495550091, 39823.80088099559, 39824.004399890015, 41971.95070123248, 33360.16599585012, 41250.79374603123, 41586.0, 41699.37450938238, 41770.582294177046, 9251.629934802617, 9457.19613832825, 9467.100625440606, 9473.715788526355, 9493.145616894479, 8405.159484051606, 8635.740927772145, 9224.953875230649, 9402.670906518273, 11317.603883864067, 11223.831642525369, 11279.984801367875, 11284.830727539094, 11303.321800691985, 11313.660590182308, 11103.44482775862, 11303.434828258554, 11327.943360283187, 11336.149788765793, 11345.659630211025, 8307.169283071706, 8535.018472875638, 8539.0, 8654.826903461895, 11106.500207490602, 31497.0, 38240.80879595612, 38990.610093898984, 39050.02374940621, 40397.990050248685, 3405.0976491229844, 3912.980435097806, 3941.7437866538585, 3957.703172262092, 4037.596240375968, 28844.278893027633, 29866.850665746748, 30104.548431773543, 30341.0, 30691.539626905393, 3178.523221516768, 3178.634457037447, 3214.517822326646, 3295.8681652733862, 3325.068980685388, 458428.0, 460099.0, 460612.6969365132, 460896.0, 463355.0, 58580.41419585835, 59026.40973590295, 59042.0, 59086.0, 59098.40901591015, 60932.0, 61228.0, 61268.69365653143, 61309.38690613037, 61375.0, 14990.625234369229, 15485.0, 15508.379664813507, 15528.689426211638, 15557.533274001798, 10824.458777061116, 11865.0, 11955.641330759918, 11962.700932476757, 11982.820257696141, 14248.786268205986, 14603.123812571279, 15612.4535641252, 15850.920745396199, 16278.860479766303, 5799.681017543991, 6678.732850686015, 6680.131582894192, 6695.765648202289, 6789.864202715918, 776.7087342246657, 799.8000499875031, 808.4098608016182, 815.9714409995622, 832.98334033319, 24163.758362416153, 28405.715942840307, 29105.563416548768, 29205.977790777222, 29222.707772922426, 1035.3942943378208, 1052.6263176572359, 1070.4273213830718, 1087.6573879228017, 1161.988380116188, 12507.312097834525, 14972.52062200157, 15015.0, 15250.161241131844, 15276.479380902027, 228302.85848570982, 229334.0, 230335.0, 230837.0, 591046.2535245069, 56958.430415695315, 57011.71494142586, 57081.42918570761, 57148.0, 57323.713381432826, 1368.3021658953805, 1401.8107555480083, 1405.4940221520244, 1411.3931009665941, 1417.1780367386891, 26495.470090598465, 28815.0, 29273.560896586994, 29300.853495732386, 29387.85306073456, 40686.0, 48215.0, 48220.517794823005, 48403.0, 48478.27282590693, 511140.0, 517250.0, 580590.3586748887, 602843.0, 603385.0, 21610.78589674998, 23156.421089472224, 23459.765402345758, 23497.41256468602, 23565.410864728517, 33797.49303760396, 37433.0, 42333.36499952441, 43385.56614433941, 43954.56045439633, 30850.0, 33645.15887102842, 35057.474137888465, 35301.47047794234, 36573.0, 34472.0, 34981.65018349784, 35176.82411587926, 35836.28327433489, 35905.46141807823, 33856.15359616029, 34517.0, 34612.78855240055, 34672.82663586666, 34695.479567805996, 29611.111666650035, 30732.924347647724, 30764.846175768977, 30825.537616935315, 31063.534046989764, 21448.356549303546, 22824.88587557118, 23210.071597135924, 23489.530209395376, 23571.410714731584], "value": 18655.771221006526, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [363.9108418437483, 383.5742326018121, 385.96333348331905, 390.35786131813177, 415.7152350639812, 12769.0, 14459.783103253447, 18291.63416731666, 19549.827010379388, 19906.303279385225, 19472.415827525198, 19479.026048697542, 19496.512587185327, 20222.191112355526, 20464.0, 5612.298462692163, 5954.553408494358, 5955.821325360224, 5964.105384192384, 5964.463198312151, 4107.527634322061, 4328.0, 5859.4140585941495, 6143.600665956721, 6433.549651524395, 6451.0, 6478.514111441661, 6487.967560162217, 6489.837754056162, 6513.869722605545, 6148.784792532262, 6318.684065796715, 6376.202974628171, 6383.712732926983, 6393.520485963525, 5375.3280839895015, 5516.0, 5520.696361700105, 5524.944750552483, 5616.943830561724, 23210.76789232103, 23370.883145584332, 23498.177563785357, 23703.170389036473, 23735.88132059346, 2698.473797609467, 2714.253580265423, 2739.9726002739917, 2743.8490883001427, 2811.74694277514, 12760.680982975407, 13698.0, 14073.225972571505, 15702.214889255605, 15828.52514424569, 1758.5515693498205, 1796.389227662588, 1805.873588848781, 1806.7470554122433, 1808.97286540702, 87270.0, 110851.44574277158, 111328.0, 111869.0, 111914.44042779815, 9159.862602060974, 9851.802963940681, 11064.391458469701, 11133.721656958642, 11272.71818204535, 12885.54900578494, 12935.676608084874, 13300.334983250992, 13660.93169534146, 13701.657458563615, 8142.592870356459, 8145.5927203639585, 8230.382721295926, 8256.669733210725, 8378.53918034514, 8043.7989050273045, 8072.354211663171, 8234.917650823416, 8235.876461853077, 8243.793905152419, 6741.426978706767, 6852.862942741217, 6868.278830722802, 6870.656467176621, 6876.724931002704, 1870.484907225152, 1920.6254780317984, 1922.2407149175997, 1923.7499125113645, 1927.8264956154014, 1002.9669440476321, 1011.5296387179997, 1013.2906965124442, 1021.667957913681, 1024.2471783239314, 17705.38031168903, 18462.35381761632, 18713.532161695795, 20317.288894888607, 20702.482437939172, 995.3579940938055, 1019.3679918450608, 1034.363866222268, 1050.4432650695144, 1061.1086687182803, 9406.90593094074, 10964.232503724823, 10997.835032474519, 11035.83446248307, 11071.225014249087, 534833.0, 538907.305463478, 542385.2880735571, 551824.0, 551895.0, 108567.0, 110302.0, 110769.44615276872, 131188.99999999808, 135791.0, 897.9191872731485, 964.4599024546277, 1005.3415013166388, 1017.3590637898077, 1027.8817935937464, 18114.818851811313, 18278.0, 18371.63256734831, 18853.434396968114, 18884.025820892715, 51730.22404663858, 52304.7384763089, 53136.0, 53852.0, 53885.0, 71543.0, 77908.0, 78470.82293765484, 79973.0, 81304.59347703223, 7221.4222862171955, 7840.568768717775, 7980.6408711606955, 8199.754007379788, 8310.875336870073, 11784.116191285517, 12685.112042157329, 12732.363381831057, 12909.419076141785, 13157.947364211032, 10945.507452164513, 11100.056495197676, 11163.274387164809, 11393.430328483377, 11503.0, 10497.370157790252, 10816.67549973502, 10949.56201751921, 11304.0, 11312.886871131182, 11214.607488737855, 11409.543618255177, 11454.541818327174, 11663.358515281741, 11698.70753231142, 7987.880181797394, 8002.1997800219515, 8084.555349455836, 8441.746747597335, 8454.66181352739, 7110.5022648414115, 9848.803023939337, 10306.0, 10824.56701731922, 11336.263142895685], "value": 9618.257549329848, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1267.28398454873, 1320.887724543414, 1424.3092100331337, 1436.0450300550135, 1453.4404254362073, 7492.512986655864, 7729.304362607364, 7734.883976740346, 7804.18056104108, 7814.179511151318, 7430.665620047097, 7606.771796846104, 7615.504992175504, 7624.65689043993, 8044.954155959732, 2279.122537822938, 2295.3228797504776, 2330.941726456835, 2331.1957374705785, 2332.4052366646483, 2300.8389412741117, 2335.474518233401, 2337.345543247893, 2355.3993731598416, 2356.846804957681, 2009.1260301768705, 2092.853500254975, 2239.4513344230686, 2319.072371051576, 2346.577616029114, 2274.4768703198274, 2290.7365652949957, 2297.173017713622, 2298.689676893623, 2309.722833260003, 2016.7378240828746, 2249.741279752833, 2307.711536057993, 2316.872572008539, 2318.8956496957676, 8302.83394332116, 8317.334613230987, 8384.287335576484, 8429.83140337196, 8429.957850210709, 5004.824831130929, 5170.508801663836, 5176.637635365526, 5180.067587834188, 5186.273921650972, 3300.85146168423, 3487.075924879909, 3529.699975502085, 3535.982320088409, 3571.321448924696, 1130.152385710717, 1176.9411529423494, 1208.9818652720217, 1234.8950339221087, 1246.426643743879, 9382.812343753223, 10638.734031649194, 10786.0, 10831.891681083245, 10879.728006799814, 4526.886827829265, 4594.908101837945, 4640.7215567065405, 4687.507811679793, 4690.765461726968, 9369.812603747887, 9414.952925235468, 9440.339176257596, 9444.811103777885, 9446.716598502057, 5242.213667949838, 5253.080710875582, 5253.605979551549, 5254.737263136828, 5255.658382205144, 4108.650764684976, 4173.603507666767, 4792.95207047925, 4817.253325734517, 4825.9034819304115, 3721.2557488502407, 3759.8872033838484, 3800.82896269663, 3847.634474724897, 3853.9979605302824, 1247.7192631657804, 1254.0845183016268, 1260.2419624623783, 1274.6112435707014, 1276.90423218259, 682.4506272450626, 767.8195624028349, 794.301015106714, 796.3072127249214, 806.6491076381793, 5282.656627319212, 5305.575553955674, 5328.520433161034, 5408.4050754416985, 5465.480779325958, 550.3340957441461, 568.5622071005303, 572.7394035713824, 577.9595428319977, 578.7164289498115, 3474.166200111955, 3510.613832478425, 3670.596234414212, 3713.498677678479, 3768.3028639701743, 9432.858507122399, 9883.863355714186, 9909.950450247703, 9912.603495860085, 9919.008099190167, 9122.771930701785, 9470.431774093577, 9658.855117173249, 9867.16129129032, 9892.851607225897, 7587.127480339832, 7656.425768067305, 7661.731839385595, 7673.040869891263, 7685.193054729285, 5369.436209197978, 5475.206095116266, 5493.23094766725, 5567.47109024642, 5661.688607126647, 8963.610640350757, 9249.213816825646, 9255.167034966886, 9383.108604682544, 9415.105564971316, 9358.625654973725, 9392.859107113261, 9403.294752893697, 9435.386699864486, 9532.189763869872, 6883.380495755406, 7044.15470143586, 7085.964570177116, 7140.7143714252925, 7245.456590755609, 7847.529148251123, 7894.723684671009, 7967.442279040411, 7988.281054704872, 7994.080680721559, 5561.9976808349775, 6963.89477707907, 7098.148222213367, 7168.569885806868, 7286.6720997556295, 7042.260562640849, 7048.0, 7062.293770622897, 7073.964630176816, 7099.396551292992, 6200.875982480416, 6207.068939659087, 6304.5586808922935, 6318.557700960889, 6405.03924411342, 4158.875233742992, 4694.413198350207, 4735.408073990751, 4749.382580264543, 4795.856124316276, 4693.436787585511, 4754.595859351994, 4790.95209047919, 4809.110314591811, 4951.405831300267], "value": 4277.480139661261, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.88867562380034, 358.32098173960344, 364.4351255553891, 367.79587329032375, 370.4313878196966, 5188.143956247224, 5282.471752824716, 5302.628815982883, 5323.22813192087, 5370.328708911381, 4415.425994620695, 4448.888777780557, 4644.97677511612, 4678.97660511697, 4918.262260660903, 3043.812912963946, 3612.422012478004, 3810.695144388449, 3970.940435893464, 3976.145128797308, 4040.7777572233517, 4067.776272305022, 4092.877213683594, 4095.692823038269, 4125.0, 3493.615702272748, 3640.981795091021, 3923.9773090444132, 4057.797110144496, 4086.734362266458, 3523.6300188480227, 3568.571771387424, 3766.4538641897047, 3911.706622003361, 4052.189562087565, 3121.7034381733924, 3535.4520049392386, 3618.240169564395, 3635.4910312556262, 3649.872254471107, 4147.751134931884, 5033.546980771712, 5088.643794934356, 5098.974505127488, 5153.768080436352, 4304.698671093025, 4384.846530371454, 4531.3007622141695, 4757.595604373632, 4783.545563171493, 5519.862003449946, 5537.0, 5542.528885044736, 5573.972130139324, 5605.635633683838, 3781.489498917666, 3791.620837916214, 3795.7532760370486, 3799.3161230978412, 3831.444440556104, 4564.3838081859185, 5455.563554915637, 5535.944640553583, 5650.576206784466, 5810.0, 3731.347014272546, 3762.8523300393645, 3780.432935059708, 3804.2771873343977, 3820.3887378019385, 3804.2201348723447, 3819.618038196158, 3853.460515527828, 3874.767513949172, 3899.74651647642, 4324.394584758136, 4486.327050942385, 4503.81984720614, 4511.842085526991, 4536.478304994902, 4923.310736496894, 5030.094582975026, 5457.635591102225, 5586.888262234733, 5631.324241091013, 1079.8704155501232, 1193.4032983508246, 1208.9335086570322, 1228.4287806170173, 1254.4166962362544, 732.7948174511146, 848.9320854331638, 850.7617866997251, 863.6027427383349, 867.7526904832218, 4635.3278774578175, 4760.95239047612, 4889.313186950496, 4939.431965323962, 4958.454569997298, 5475.645472446807, 5490.529261832192, 5492.80775172867, 5494.862628434241, 5513.42109078549, 3705.6109108543214, 4187.267228235047, 4692.032331986997, 4738.228136540162, 4883.975580122148, 2543.4150145466647, 2620.213935819285, 2712.769414599781, 2788.60959465675, 2891.542189139257, 4922.843131864009, 5466.289382380344, 5626.690532020778, 5671.043489995945, 5714.685622306893, 4845.297431872359, 5055.747212639353, 5134.122583545646, 5646.774129034792, 5677.233573467612, 5021.949780502221, 5072.82245121419, 5446.863828404163, 5640.717964101696, 5649.576281778883, 4791.856244312537, 4911.017796440798, 4938.703677779345, 4972.676776009548, 5119.87200319995, 4754.7384893829785, 4756.405449318835, 4932.210846264724, 5003.574696150868, 5281.313429254173, 5098.97450512745, 5628.577856660685, 5658.773649053992, 5723.484886360247, 5793.102069179284, 3247.7564182686865, 3806.4670946068127, 3819.78991155478, 3834.750741201813, 3866.2654095721723, 4819.036192761391, 4959.628027897994, 5627.493525582717, 5781.335146458211, 5812.534997200299, 4601.976990115028, 5509.57578665386, 5530.6128570999645, 5540.80607178763, 5583.74873130702, 4653.86038418848, 5230.686158830483, 5482.945170548403, 5504.146857237135, 5572.359178694502, 5312.282841816305, 5345.518903298722, 5348.652337598043, 5416.485433883775, 5476.0, 5188.922166167586, 5244.951009798132, 5446.883388905344, 5451.836444906659, 5560.666360018412, 4976.303317535476, 5058.974705126451, 5207.973960130326, 5373.623846330719, 5389.487998640123], "value": 3965.057124905011, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [699.3251512290642, 700.7057036044861, 723.6454137472637, 770.1335997003371, 781.2032922138742, 14968.401263949443, 15596.298166582503, 15665.608359791011, 15778.053316801, 15952.680946381066, 13962.581122566315, 14218.71562568748, 14324.427022919097, 14428.0, 14528.854711452883, 862.9827403451927, 876.9386142969995, 880.3133555826465, 881.8985816631106, 882.9337799665019, 693.9236683964759, 711.0329951266274, 841.2134654098437, 865.9134086591354, 868.1578868497547, 912.3522299167599, 912.8585069314233, 913.7943962608393, 915.1397686175007, 915.5102020419077, 903.2187158108255, 905.6060613633091, 909.4543274035592, 909.8180363927241, 912.0332447605526, 529.0556356902948, 543.4429709547705, 546.2707285773497, 546.2870953405804, 546.8386825886373, 14448.927755361156, 14975.026623269452, 15039.548813535612, 15142.394304227817, 15345.386184552604, 14218.573442796733, 14271.357788899524, 14343.713125737428, 14379.496717614833, 14408.855911440856, 9857.802843943153, 11644.35956022427, 11664.125190610737, 11717.0, 12022.90360161609, 3723.6090210528046, 3743.2513497300647, 3747.0445036515516, 3852.287326844543, 3858.614138586147, 91376.0, 92805.53597232039, 93573.53213233958, 94634.5268273661, 95631.52184239104, 10926.508307126198, 10980.505877235544, 10997.0, 11244.26912250701, 11537.711557211136, 22627.43431414195, 22766.886165569067, 22858.885705571367, 22947.770522294562, 23051.7694823053, 6817.625030623264, 7557.4331925105835, 7781.649825757854, 7833.216678332121, 8070.9192908071345, 4266.722663026893, 4285.121550082225, 4319.244132276902, 4338.739675619473, 4387.736735795798, 3500.1949551603275, 3546.680798728127, 3591.012471570287, 3599.7300202484917, 3607.3687104756564, 645.4287955159624, 649.4382359259207, 660.9636469994197, 668.1714673804545, 672.4553111979312, 580.1935309919215, 603.6860832367145, 658.9209294884548, 664.9368310010541, 679.4734081087098, 8859.867101993475, 8882.467051976902, 9415.293852961055, 9430.717078487656, 9561.952190239144, 630.2940706408759, 642.8071578526517, 682.3381320119477, 688.5902887781762, 688.5937296994797, 4550.726956382628, 4602.585767280961, 4603.355530225771, 4612.86161415158, 4713.410823647044, 74946.0, 90631.54684226536, 96317.51841240888, 100443.0, 102764.48617756863, 85902.57048714842, 87245.1275487237, 87292.0, 89032.0, 89942.0, 62263.06605400832, 66553.33446665472, 67278.0, 72739.27260727431, 80343.0, 9742.95128524353, 9800.36297640651, 10068.647597334058, 10134.543945522617, 10192.490375481344, 43190.0, 45375.54624453713, 47433.0, 49776.0, 51097.23354149616, 48764.0, 57732.26803195911, 57862.71068644798, 57870.4212957865, 58391.08044597838, 10661.466926653482, 11985.34080625574, 12438.564650237198, 13160.47358105665, 13668.52160174389, 14585.270736462922, 14712.779308310597, 14770.556883293519, 14956.700865982837, 15170.848291516944, 12120.454579543766, 13333.86666133365, 13964.441422342992, 14231.786523201954, 14355.354009069408, 12544.49822007146, 13636.93181534086, 13984.930075349965, 14405.711885762017, 14617.707645847237, 12244.265344079384, 13407.463701452223, 13706.38321275525, 13725.725485490433, 13813.51652692151, 9272.72181834546, 10865.891341086804, 11195.60815371458, 11343.716407089889, 11927.0, 10444.373337599769, 11356.886431135583, 11385.601503947322, 11406.543738250377, 11603.36181510025], "value": 7328.419508791303, "unit": "score"}], "name": "dromaeo_css", "value": 7081.740086233708, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 116.0, 114.0, 114.0, 120.0, 154.0, 152.0, 113.0, 113.0], "value": 122.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 109.0, 113.0, 158.0, 138.0, 133.0, 124.0, 111.0, 130.0, 113.0], "value": 127.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 186.0, 185.0, 186.0, 186.0, 183.0, 182.0, 184.0, 185.0, 185.0], "value": 184.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 59.0, 59.0, 88.0, 62.0, 61.0, 59.0, 59.0, 75.0, 57.0], "value": 65.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 67.0, 86.0, 70.0, 68.0, 68.0, 83.0, 70.0, 69.0, 85.0], "value": 75.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 90.0, 87.0, 90.0, 86.0, 97.0, 107.0, 90.0, 87.0], "value": 91.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 95.0, 94.0, 94.0, 95.0, 95.0, 95.0, 94.0], "value": 94.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 88.0, 85.0, 88.0, 85.0, 85.0, 86.0, 85.0, 88.0, 85.0], "value": 86.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 55.0, 55.0, 55.0, 55.0, 56.0, 55.0, 55.0, 57.0], "value": 55.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 77.0, 77.0, 73.0, 71.0, 71.0, 71.0, 70.0, 70.0], "value": 71.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 83.0, 77.0, 104.0, 100.0, 98.0, 99.0, 96.0, 93.0, 87.0], "value": 91.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 159.0, 147.0, 150.0, 140.0, 140.0, 140.0, 140.0, 205.0, 180.0], "value": 153.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [208.0, 172.0, 173.0, 170.0, 172.0, 170.0, 181.0, 168.0, 170.0, 171.0], "value": 175.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 82.0, 82.0, 84.0, 83.0, 70.0, 66.0, 65.0, 65.0, 67.0], "value": 73.3, "unit": "score"}], "name": "kraken", "value": 1467.8, "alertThreshold": 2.0}]} 04:46:39 INFO - Return code: 0 04:46:39 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:46:39 INFO - # TBPL SUCCESS # 04:46:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:46:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:46:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:46:40 INFO - 2017-01-04 04:46:40.002 screenresolution[1654:9875] starting screenresolution argv=screenresolution get 04:46:40 INFO - 2017-01-04 04:46:40.005 screenresolution[1654:9875] Display 0: 1600x1200x32@60 04:46:40 INFO - 2017-01-04 04:46:40.011 screenresolution[1655:9878] starting screenresolution argv=screenresolution list 04:46:40 INFO - Available Modes on Display 0 04:46:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:46:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:46:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:46:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:46:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:46:40 INFO - Intel Iris: 04:46:40 INFO - Chipset Model: Intel Iris 04:46:40 INFO - Type: GPU 04:46:40 INFO - Bus: Built-In 04:46:40 INFO - VRAM (Dynamic, Max): 1536 MB 04:46:40 INFO - Vendor: Intel (0x8086) 04:46:40 INFO - Device ID: 0x0a2e 04:46:40 INFO - Revision ID: 0x0009 04:46:40 INFO - Displays: 04:46:40 INFO - Display: 04:46:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:46:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:46:40 INFO - Display Serial Number: 1600x1200 60 04:46:40 INFO - Main Display: Yes 04:46:40 INFO - Mirror: Off 04:46:40 INFO - Online: Yes 04:46:40 INFO - Rotation: Supported 04:46:40 INFO - Return code: 0 04:46:40 INFO - Running post-action listener: _resource_record_post_action 04:46:40 INFO - [mozharness: 2017-01-04 12:46:40.252463Z] Finished run-tests step (success) 04:46:40 INFO - Running post-run listener: _resource_record_post_run 04:46:40 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 38880256; Write bytes: 548884992; Read time: 380; Write time: 1782 04:46:40 INFO - TinderboxPrint: CPU usage
54.1% 04:46:40 INFO - TinderboxPrint: I/O read bytes / time
38,880,256 / 380 04:46:40 INFO - TinderboxPrint: I/O write bytes / time
548,884,992 / 1,782 04:46:40 INFO - TinderboxPrint: CPU idle
2,206.5 (45.9%) 04:46:40 INFO - TinderboxPrint: CPU system
964.9 (20.1%) 04:46:40 INFO - TinderboxPrint: CPU user
1,638.3 (34.1%) 04:46:40 INFO - TinderboxPrint: Swap in / out
288,288,768 / 0 04:46:40 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 245793280; Write bytes: 240293888; Read time: 17985; Write time: 462 04:46:40 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 36803584; Write bytes: 295180800; Read time: 357; Write time: 1280 04:46:40 INFO - Running post-run listener: _upload_blobber_files 04:46:40 INFO - Blob upload gear active. 04:46:40 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:46:40 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 04:46:40 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'] 04:46:40 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 04:46:41 INFO - (blobuploader) - INFO - Open directory for files ... 04:46:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:46:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:46:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:46:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:46:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:46:42 INFO - (blobuploader) - INFO - Done attempting. 04:46:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:46:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:46:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:46:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:46:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:46:43 INFO - (blobuploader) - INFO - Done attempting. 04:46:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:46:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:46:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:46:44 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:46:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:46:44 INFO - (blobuploader) - INFO - Done attempting. 04:46:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:46:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:46:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:46:46 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:46:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:46:46 INFO - (blobuploader) - INFO - Done attempting. 04:46:46 INFO - (blobuploader) - INFO - Iteration through files over. 04:46:46 INFO - Return code: 0 04:46:46 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:46:46 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:46:46 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c4cca98020e944a048efaf72eb8551aa7f540da5601b73f1ba40f387e9737f7c2c6bf39f87db6a455124c1cb4abbfb9d9dafc1672b730fba1f74e73c71168202", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5e33e7c22f0542465b3b9f72bd38c2ca95c94b9bd5dbddcaa628f892ad1b540be5e64f714d139fb1f50ad8008873c192079c51f01c85f8265f3a318cc19418ad", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ece959f842910ca30e1faf26dbb070324985df5315666022bef6a3562004342dadb99aede46d41fe91f6eed473db03ccebbdecb1dce2db801af0bd2ccb332e13", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2a4823a82aef0c852319598e80fd955769ca70dc1a48960919adcb969ac3bc5b0a021c8c2eec58aa21f2f63e054a2f4df54980b61498ea5803b6c5991de11a6c"} 04:46:46 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:46:46 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:46:46 INFO - Contents: 04:46:46 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c4cca98020e944a048efaf72eb8551aa7f540da5601b73f1ba40f387e9737f7c2c6bf39f87db6a455124c1cb4abbfb9d9dafc1672b730fba1f74e73c71168202", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5e33e7c22f0542465b3b9f72bd38c2ca95c94b9bd5dbddcaa628f892ad1b540be5e64f714d139fb1f50ad8008873c192079c51f01c85f8265f3a318cc19418ad", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ece959f842910ca30e1faf26dbb070324985df5315666022bef6a3562004342dadb99aede46d41fe91f6eed473db03ccebbdecb1dce2db801af0bd2ccb332e13", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2a4823a82aef0c852319598e80fd955769ca70dc1a48960919adcb969ac3bc5b0a021c8c2eec58aa21f2f63e054a2f4df54980b61498ea5803b6c5991de11a6c"} 04:46:46 INFO - Running post-run listener: copy_logs_to_upload_dir 04:46:46 INFO - Copying logs to upload dir... 04:46:46 INFO - mkdir: /builds/slave/test/build/upload/logs 04:46:46 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1268.124395 ========= master_lag: 0.73 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2017-01-04 04:46:46.686290) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-04 04:46:46.689935) ========= 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.5DJj4IlMo1/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.wH92rz8V4x/Listeners TMPDIR=/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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/c4cca98020e944a048efaf72eb8551aa7f540da5601b73f1ba40f387e9737f7c2c6bf39f87db6a455124c1cb4abbfb9d9dafc1672b730fba1f74e73c71168202", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5e33e7c22f0542465b3b9f72bd38c2ca95c94b9bd5dbddcaa628f892ad1b540be5e64f714d139fb1f50ad8008873c192079c51f01c85f8265f3a318cc19418ad", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ece959f842910ca30e1faf26dbb070324985df5315666022bef6a3562004342dadb99aede46d41fe91f6eed473db03ccebbdecb1dce2db801af0bd2ccb332e13", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2a4823a82aef0c852319598e80fd955769ca70dc1a48960919adcb969ac3bc5b0a021c8c2eec58aa21f2f63e054a2f4df54980b61498ea5803b6c5991de11a6c"} build_url:https://queue.taskcluster.net/v1/task/NKpy0AF2QGeD4x017M9vVg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010783 build_url: 'https://queue.taskcluster.net/v1/task/NKpy0AF2QGeD4x017M9vVg/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/c4cca98020e944a048efaf72eb8551aa7f540da5601b73f1ba40f387e9737f7c2c6bf39f87db6a455124c1cb4abbfb9d9dafc1672b730fba1f74e73c71168202", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5e33e7c22f0542465b3b9f72bd38c2ca95c94b9bd5dbddcaa628f892ad1b540be5e64f714d139fb1f50ad8008873c192079c51f01c85f8265f3a318cc19418ad", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ece959f842910ca30e1faf26dbb070324985df5315666022bef6a3562004342dadb99aede46d41fe91f6eed473db03ccebbdecb1dce2db801af0bd2ccb332e13", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2a4823a82aef0c852319598e80fd955769ca70dc1a48960919adcb969ac3bc5b0a021c8c2eec58aa21f2f63e054a2f4df54980b61498ea5803b6c5991de11a6c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-04 04:46:46.724440) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 04:46:46.724792) ========= 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.5DJj4IlMo1/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.wH92rz8V4x/Listeners TMPDIR=/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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.004584 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 04:46:46.773269) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-04 04:46:46.773587) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-04 04:46:46.773892) ========= ========= Total master_lag: 0.87 =========