builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0259 starttime: 1483149214.17 results: success (0) buildid: 20161230165019 builduid: 409b71e017ef4e8eaf135121d7cb7c36 revision: a06f92099a5d8edeb05e5971967fe8d6cd4c593c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-30 17:53:34.170633) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-30 17:53:34.171089) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-30 17:53:34.171381) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-30 17:53:34.204713) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-30 17:53:34.205092) ========= 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.OoyqAjZuLl/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.V55PI9dvsI/Listeners TMPDIR=/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/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 --2016-12-30 17:53:34-- 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% 117M=0s 2016-12-30 17:53:34 (117 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.640500 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-30 17:53:34.870377) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-30 17:53:34.870683) ========= 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.OoyqAjZuLl/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.V55PI9dvsI/Listeners TMPDIR=/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/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.025559 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-30 17:53:34.956066) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-30 17:53:34.956420) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev a06f92099a5d8edeb05e5971967fe8d6cd4c593c --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 a06f92099a5d8edeb05e5971967fe8d6cd4c593c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.OoyqAjZuLl/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.V55PI9dvsI/Listeners TMPDIR=/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/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 2016-12-30 17:53:35,058 truncating revision to first 12 chars 2016-12-30 17:53:35,058 Setting DEBUG logging. 2016-12-30 17:53:35,058 attempt 1/10 2016-12-30 17:53:35,058 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/a06f92099a5d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-30 17:53:36,340 unpacking tar archive at: mozilla-central-a06f92099a5d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.553369 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-30 17:53:36.534073) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-30 17:53:36.537446) ========= script_repo_revision: a06f92099a5d8edeb05e5971967fe8d6cd4c593c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-30 17:53:36.537843) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-30 17:53:36.538102) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-30 17:53:36.555674) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-12-30 17:53:36.555954) ========= /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.OoyqAjZuLl/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.V55PI9dvsI/Listeners TMPDIR=/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 17:53:36 INFO - MultiFileLogger online at 20161230 17:53:36 in /builds/slave/test 17:53:36 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 17:53:36 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:53:36 INFO - {'append_to_log': False, 17:53:36 INFO - 'base_work_dir': '/builds/slave/test', 17:53:36 INFO - 'blob_upload_branch': 'Firefox', 17:53:36 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:53:36 INFO - 'branch': 'Firefox', 17:53:36 INFO - 'buildbot_json_path': 'buildprops.json', 17:53:36 INFO - 'config_files': ('talos/mac_config.py',), 17:53:36 INFO - 'default_actions': ('clobber', 17:53:36 INFO - 'read-buildbot-config', 17:53:36 INFO - 'download-and-extract', 17:53:36 INFO - 'populate-webroot', 17:53:36 INFO - 'create-virtualenv', 17:53:36 INFO - 'install', 17:53:36 INFO - 'run-tests'), 17:53:36 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:53:36 INFO - 'download_minidump_stackwalk': True, 17:53:36 INFO - 'download_symbols': 'ondemand', 17:53:36 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:53:36 INFO - 'tooltool.py': '/tools/tooltool.py', 17:53:36 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:53:36 INFO - '/tools/misc-python/virtualenv.py')}, 17:53:36 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:53:36 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:53:36 INFO - 'installer_path': 'installer.exe', 17:53:36 INFO - 'log_level': 'info', 17:53:36 INFO - 'log_name': 'talos', 17:53:36 INFO - 'log_to_console': True, 17:53:36 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:53:36 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:53:36 INFO - 'opt_config_files': (), 17:53:36 INFO - 'pip_index': False, 17:53:36 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:53:36 INFO - 'cmd': ('bash', 17:53:36 INFO - '-c', 17:53:36 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:53:36 INFO - 'enabled': True, 17:53:36 INFO - 'halt_on_failure': False, 17:53:36 INFO - 'name': 'check_screen_resolution'},), 17:53:36 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:53:36 INFO - 'cmd': ('bash', 17:53:36 INFO - '-c', 17:53:36 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:53:36 INFO - 'enabled': True, 17:53:36 INFO - 'halt_on_failure': False, 17:53:36 INFO - 'name': 'check_screen_resolution'},), 17:53:36 INFO - 'run_cmd_checks_enabled': True, 17:53:36 INFO - 'sps_profile': False, 17:53:36 INFO - 'sps_profile_interval': 0, 17:53:36 INFO - 'suite': 'dromaeojs', 17:53:36 INFO - 'system_bits': '32', 17:53:36 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:53:36 INFO - 'title': 't-yosemite-r7-0259', 17:53:36 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:53:36 INFO - 'use_talos_json': True, 17:53:36 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:53:36 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:53:36 INFO - 'work_dir': 'build'} 17:53:36 INFO - [mozharness: 2016-12-31 01:53:36.769269Z] Running clobber step. 17:53:36 INFO - Running pre-action listener: _resource_record_pre_action 17:53:36 INFO - Running main action method: clobber 17:53:36 INFO - rmtree: /builds/slave/test/build 17:53:36 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:53:39 INFO - Running post-action listener: _resource_record_post_action 17:53:39 INFO - [mozharness: 2016-12-31 01:53:39.042255Z] Finished clobber step (success) 17:53:39 INFO - [mozharness: 2016-12-31 01:53:39.042421Z] Running read-buildbot-config step. 17:53:39 INFO - Running pre-action listener: _resource_record_pre_action 17:53:39 INFO - Running main action method: read_buildbot_config 17:53:39 INFO - Using buildbot properties: 17:53:39 INFO - { 17:53:39 INFO - "project": "", 17:53:39 INFO - "product": "firefox", 17:53:39 INFO - "script_repo_revision": "production", 17:53:39 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 17:53:39 INFO - "repository": "", 17:53:39 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 17:53:39 INFO - "buildid": "20161230165019", 17:53:39 INFO - "pgo_build": "False", 17:53:39 INFO - "basedir": "/builds/slave/test", 17:53:39 INFO - "buildnumber": 46, 17:53:39 INFO - "slavename": "t-yosemite-r7-0259", 17:53:39 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 17:53:39 INFO - "platform": "yosemite_r7", 17:53:39 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 17:53:39 INFO - "branch": "mozilla-central", 17:53:39 INFO - "repo_path": "mozilla-central", 17:53:39 INFO - "revision": "a06f92099a5d8edeb05e5971967fe8d6cd4c593c", 17:53:39 INFO - "stage_platform": "macosx64", 17:53:39 INFO - "builduid": "409b71e017ef4e8eaf135121d7cb7c36", 17:53:39 INFO - "slavebuilddir": "test" 17:53:39 INFO - } 17:53:39 INFO - Found installer url https://queue.taskcluster.net/v1/task/PbyaYpSDQsO5o7Mz2D9dtw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 17:53:39 INFO - Running post-action listener: _resource_record_post_action 17:53:39 INFO - [mozharness: 2016-12-31 01:53:39.045770Z] Finished read-buildbot-config step (success) 17:53:39 INFO - [mozharness: 2016-12-31 01:53:39.045907Z] Running download-and-extract step. 17:53:39 INFO - Running pre-action listener: _resource_record_pre_action 17:53:39 INFO - Running main action method: download_and_extract 17:53:39 INFO - mkdir: /builds/slave/test/build/tests 17:53:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:53:39 INFO - trying https://queue.taskcluster.net/v1/task/PbyaYpSDQsO5o7Mz2D9dtw/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 17:53:39 INFO - Downloading https://queue.taskcluster.net/v1/task/PbyaYpSDQsO5o7Mz2D9dtw/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 17:53:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PbyaYpSDQsO5o7Mz2D9dtw/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 17:53:39 INFO - Downloaded 1199 bytes. 17:53:39 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 17:53:39 INFO - Using the following test package requirements: 17:53:39 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 17:53:39 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 17:53:39 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 17:53:39 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 17:53:39 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 17:53:39 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 17:53:39 INFO - u'jsshell-mac.zip'], 17:53:39 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 17:53:39 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 17:53:39 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 17:53:39 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 17:53:39 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 17:53:39 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 17:53:39 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 17:53:39 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 17:53:39 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 17:53:39 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 17:53:39 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 17:53:39 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 17:53:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PbyaYpSDQsO5o7Mz2D9dtw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 17:53:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PbyaYpSDQsO5o7Mz2D9dtw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:53:39 INFO - Fetch https://queue.taskcluster.net/v1/task/PbyaYpSDQsO5o7Mz2D9dtw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 17:53:40 INFO - Expected file size: 16299336 17:53:40 INFO - Obtained file size: 16299336 17:53:47 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 17:53:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PbyaYpSDQsO5o7Mz2D9dtw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 17:53:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PbyaYpSDQsO5o7Mz2D9dtw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:53:47 INFO - Fetch https://queue.taskcluster.net/v1/task/PbyaYpSDQsO5o7Mz2D9dtw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 17:53:48 INFO - Expected file size: 16299336 17:53:48 INFO - Obtained file size: 16299336 17:53:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PbyaYpSDQsO5o7Mz2D9dtw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 17:53:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PbyaYpSDQsO5o7Mz2D9dtw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 17:53:54 INFO - Fetch https://queue.taskcluster.net/v1/task/PbyaYpSDQsO5o7Mz2D9dtw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 17:53:55 INFO - Expected file size: 14409776 17:53:55 INFO - Obtained file size: 14409776 17:53:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:53:55 INFO - trying https://queue.taskcluster.net/v1/task/PbyaYpSDQsO5o7Mz2D9dtw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 17:53:55 INFO - Downloading https://queue.taskcluster.net/v1/task/PbyaYpSDQsO5o7Mz2D9dtw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 17:53:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PbyaYpSDQsO5o7Mz2D9dtw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 17:53:57 INFO - Downloaded 69759309 bytes. 17:53:57 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PbyaYpSDQsO5o7Mz2D9dtw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 17:53:57 INFO - mkdir: /builds/slave/test/properties 17:53:57 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:53:57 INFO - Writing to file /builds/slave/test/properties/build_url 17:53:57 INFO - Contents: 17:53:57 INFO - build_url:https://queue.taskcluster.net/v1/task/PbyaYpSDQsO5o7Mz2D9dtw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 17:53:58 INFO - Running post-action listener: _resource_record_post_action 17:53:58 INFO - Running post-action listener: set_extra_try_arguments 17:53:58 INFO - [mozharness: 2016-12-31 01:53:58.426588Z] Finished download-and-extract step (success) 17:53:58 INFO - [mozharness: 2016-12-31 01:53:58.426712Z] Running populate-webroot step. 17:53:58 INFO - Running pre-action listener: _resource_record_pre_action 17:53:58 INFO - Running main action method: populate_webroot 17:53:58 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:53:58 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:53:58 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:53:58 INFO - '--tppagecycles', 17:53:58 INFO - '7'], 17:53:58 INFO - 'tests': ['tsvgm']}}, 17:53:58 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:53:58 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:53:58 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:53:58 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:53:58 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:53:58 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:53:58 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 17:53:58 INFO - 'talos_options': ['--disable-e10s'], 17:53:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:53:58 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 17:53:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:53:58 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 17:53:58 INFO - 'talos_options': ['--disable-e10s'], 17:53:58 INFO - 'tests': ['damp', 'tps']}, 17:53:58 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 17:53:58 INFO - 'tests': ['damp', 'tps']}, 17:53:58 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:53:58 INFO - 'tests': ['dromaeo_dom']}, 17:53:58 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:53:58 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:53:58 INFO - 'tests': ['basic_compositor_video']}, 17:53:58 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:53:58 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:53:58 INFO - 'tests': ['a11yr', 17:53:58 INFO - 'ts_paint', 17:53:58 INFO - 'tpaint', 17:53:58 INFO - 'sessionrestore', 17:53:58 INFO - 'sessionrestore_no_auto_restore', 17:53:58 INFO - 'tabpaint']}, 17:53:58 INFO - 'other-e10s': {'tests': ['a11yr', 17:53:58 INFO - 'ts_paint', 17:53:58 INFO - 'tpaint', 17:53:58 INFO - 'sessionrestore', 17:53:58 INFO - 'sessionrestore_no_auto_restore', 17:53:58 INFO - 'tabpaint']}, 17:53:58 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:53:58 INFO - 'ts_paint', 17:53:58 INFO - 'tpaint', 17:53:58 INFO - 'sessionrestore', 17:53:58 INFO - 'sessionrestore_no_auto_restore', 17:53:58 INFO - 'tabpaint']}, 17:53:58 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:53:58 INFO - 'ts_paint', 17:53:58 INFO - 'tpaint', 17:53:58 INFO - 'sessionrestore', 17:53:58 INFO - 'sessionrestore_no_auto_restore', 17:53:58 INFO - 'tabpaint']}, 17:53:58 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:53:58 INFO - 'tests': ['a11yr', 17:53:58 INFO - 'ts_paint', 17:53:58 INFO - 'tpaint', 17:53:58 INFO - 'sessionrestore', 17:53:58 INFO - 'sessionrestore_no_auto_restore', 17:53:58 INFO - 'tabpaint']}, 17:53:58 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:53:58 INFO - 'tests': ['a11yr', 17:53:58 INFO - 'ts_paint', 17:53:58 INFO - 'tpaint', 17:53:58 INFO - 'sessionrestore', 17:53:58 INFO - 'sessionrestore_no_auto_restore', 17:53:58 INFO - 'tabpaint']}, 17:53:58 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:53:58 INFO - 'tests': ['tsvgx', 17:53:58 INFO - 'tsvgr_opacity', 17:53:58 INFO - 'tart', 17:53:58 INFO - 'tscrollx', 17:53:58 INFO - 'cart', 17:53:58 INFO - 'tsvg_static']}, 17:53:58 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:53:58 INFO - 'tsvgr_opacity', 17:53:58 INFO - 'tart', 17:53:58 INFO - 'tscrollx', 17:53:58 INFO - 'cart', 17:53:58 INFO - 'tsvg_static']}, 17:53:58 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 17:53:58 INFO - 'talos_options': ['--disable-e10s'], 17:53:58 INFO - 'tests': ['tp5o']}, 17:53:58 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 17:53:58 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 17:53:58 INFO - 'talos_options': ['--disable-e10s', 17:53:58 INFO - '--xperf_path', 17:53:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:53:58 INFO - 'tests': ['tp5n']}, 17:53:58 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 17:53:58 INFO - 'talos_options': ['--xperf_path', 17:53:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:53:58 INFO - 'tests': ['tp5n']}}, 17:53:58 INFO - 'talos.zip': {'path': '', 17:53:58 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:53:58 INFO - Running post-action listener: _resource_record_post_action 17:53:58 INFO - [mozharness: 2016-12-31 01:53:58.444730Z] Finished populate-webroot step (success) 17:53:58 INFO - [mozharness: 2016-12-31 01:53:58.444848Z] Running create-virtualenv step. 17:53:58 INFO - Running pre-action listener: _resource_record_pre_action 17:53:58 INFO - Running main action method: create_virtualenv 17:53:58 INFO - Creating virtualenv /builds/slave/test/build/venv 17:53:58 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 17:53:58 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:53:58 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:53:58 INFO - Using real prefix '/tools/python27' 17:53:58 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:53:59 INFO - Installing distribute.............................................................................................................................................................................................done. 17:54:02 INFO - Installing pip.................done. 17:54:02 INFO - Return code: 0 17:54:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:54:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:54:02 INFO - Reading from file tmpfile_stdout 17:54:02 INFO - Output received: 17:54:02 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 17:54:02 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:54:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:54:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:54:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:54:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:54:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:54:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:54:02 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 0x1096adb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096b0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf93561170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10948bd30>, '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 0x1093b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109480be8>, '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.OoyqAjZuLl/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.V55PI9dvsI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:54:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 17:54:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 17:54:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OoyqAjZuLl/Render', 17:54:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:54:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:54:02 INFO - 'HOME': '/Users/cltbld', 17:54:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:54:02 INFO - 'LOGNAME': 'cltbld', 17:54:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:54:02 INFO - 'MOZ_NO_REMOTE': '1', 17:54:02 INFO - 'NO_EM_RESTART': '1', 17:54:02 INFO - 'PAGER': '/bin/cat', 17:54:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:54:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:54:02 INFO - 'PWD': '/builds/slave/test', 17:54:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:54:02 INFO - 'SHELL': '/bin/bash', 17:54:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.V55PI9dvsI/Listeners', 17:54:02 INFO - 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/', 17:54:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:54:02 INFO - 'USER': 'cltbld', 17:54:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:54:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:54:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:54:02 INFO - 'XPC_FLAGS': '0x0', 17:54:02 INFO - 'XPC_SERVICE_NAME': '0', 17:54:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:54:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:54:02 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 17:54:02 INFO - Cleaning up... 17:54:02 INFO - Return code: 0 17:54:02 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:54:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:54:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:54:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:54:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:54:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:54:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:54:02 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 0x1096adb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096b0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf93561170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10948bd30>, '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 0x1093b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109480be8>, '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.OoyqAjZuLl/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.V55PI9dvsI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:54:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 17:54:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 17:54:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OoyqAjZuLl/Render', 17:54:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:54:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:54:02 INFO - 'HOME': '/Users/cltbld', 17:54:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:54:02 INFO - 'LOGNAME': 'cltbld', 17:54:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:54:02 INFO - 'MOZ_NO_REMOTE': '1', 17:54:02 INFO - 'NO_EM_RESTART': '1', 17:54:02 INFO - 'PAGER': '/bin/cat', 17:54:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:54:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:54:02 INFO - 'PWD': '/builds/slave/test', 17:54:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:54:02 INFO - 'SHELL': '/bin/bash', 17:54:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.V55PI9dvsI/Listeners', 17:54:02 INFO - 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/', 17:54:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:54:02 INFO - 'USER': 'cltbld', 17:54:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:54:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:54:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:54:02 INFO - 'XPC_FLAGS': '0x0', 17:54:02 INFO - 'XPC_SERVICE_NAME': '0', 17:54:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:54:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:54:02 INFO - Downloading/unpacking psutil>=3.1.1 17:54:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:54:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:54:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:54:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:54:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:54:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:54:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:54:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:54:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:54:06 INFO - Installing collected packages: psutil 17:54:06 INFO - Running setup.py install for psutil 17:54:06 INFO - building 'psutil._psutil_osx' extension 17:54:06 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 17:54:07 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 17:54:07 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 17:54:07 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 17:54:07 INFO - building 'psutil._psutil_posix' extension 17:54:07 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 17:54:07 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:54:07 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:54:07 INFO - ^ 17:54:07 INFO - 1 warning generated. 17:54:07 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 17:54:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:54:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:54:07 INFO - Successfully installed psutil 17:54:07 INFO - Cleaning up... 17:54:08 INFO - Return code: 0 17:54:08 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:54: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')]} 17:54:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:54:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:54: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')]} 17:54:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:54:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:54: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1096adb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096b0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf93561170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10948bd30>, '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 0x1093b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109480be8>, '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.OoyqAjZuLl/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.V55PI9dvsI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:54: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 17:54: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 mozsystemmonitor==0.3 17:54:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OoyqAjZuLl/Render', 17:54:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:54:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:54:08 INFO - 'HOME': '/Users/cltbld', 17:54:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:54:08 INFO - 'LOGNAME': 'cltbld', 17:54:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:54:08 INFO - 'MOZ_NO_REMOTE': '1', 17:54:08 INFO - 'NO_EM_RESTART': '1', 17:54:08 INFO - 'PAGER': '/bin/cat', 17:54:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:54:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:54:08 INFO - 'PWD': '/builds/slave/test', 17:54:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:54:08 INFO - 'SHELL': '/bin/bash', 17:54:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.V55PI9dvsI/Listeners', 17:54:08 INFO - 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/', 17:54:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:54:08 INFO - 'USER': 'cltbld', 17:54:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:54:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:54:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:54:08 INFO - 'XPC_FLAGS': '0x0', 17:54:08 INFO - 'XPC_SERVICE_NAME': '0', 17:54:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:54:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:54:08 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:54: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 17:54: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 17:54: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 17:54: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 17:54: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 17:54: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 17:54:10 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:54:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:54:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:54:11 INFO - Installing collected packages: mozsystemmonitor 17:54:11 INFO - Running setup.py install for mozsystemmonitor 17:54:11 INFO - Successfully installed mozsystemmonitor 17:54:11 INFO - Cleaning up... 17:54:11 INFO - Return code: 0 17:54:11 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:54:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:54:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:54:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:54:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:54:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:54:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:54:11 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 0x1096adb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096b0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf93561170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10948bd30>, '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 0x1093b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109480be8>, '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.OoyqAjZuLl/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.V55PI9dvsI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:54:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 17:54:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 17:54:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OoyqAjZuLl/Render', 17:54:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:54:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:54:11 INFO - 'HOME': '/Users/cltbld', 17:54:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:54:11 INFO - 'LOGNAME': 'cltbld', 17:54:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:54:11 INFO - 'MOZ_NO_REMOTE': '1', 17:54:11 INFO - 'NO_EM_RESTART': '1', 17:54:11 INFO - 'PAGER': '/bin/cat', 17:54:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:54:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:54:11 INFO - 'PWD': '/builds/slave/test', 17:54:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:54:11 INFO - 'SHELL': '/bin/bash', 17:54:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.V55PI9dvsI/Listeners', 17:54:11 INFO - 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/', 17:54:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:54:11 INFO - 'USER': 'cltbld', 17:54:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:54:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:54:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:54:11 INFO - 'XPC_FLAGS': '0x0', 17:54:11 INFO - 'XPC_SERVICE_NAME': '0', 17:54:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:54:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:54:11 INFO - Downloading/unpacking jsonschema==2.5.1 17:54:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:54:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:54:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:54:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:54:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:54:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:54:14 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:54:14 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:54:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:54:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:54:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:54:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:54:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:54:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:54:14 INFO - Downloading functools32-3.2.3-2.tar.gz 17:54:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:54:14 INFO - warning: no files found matching '*.txt' 17:54:14 INFO - no previously-included directories found matching 'build' 17:54:14 INFO - no previously-included directories found matching 'dist' 17:54:14 INFO - no previously-included directories found matching '.git*' 17:54:14 INFO - Installing collected packages: jsonschema, functools32 17:54:14 INFO - Running setup.py install for functools32 17:54:14 INFO - warning: no files found matching '*.txt' 17:54:14 INFO - no previously-included directories found matching 'build' 17:54:14 INFO - no previously-included directories found matching 'dist' 17:54:14 INFO - no previously-included directories found matching '.git*' 17:54:14 INFO - Successfully installed jsonschema functools32 17:54:14 INFO - Cleaning up... 17:54:14 INFO - Return code: 0 17:54:14 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:54:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:54:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:54:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:54:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:54:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:54:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:54:14 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 0x1096adb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096b0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf93561170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10948bd30>, '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 0x1093b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109480be8>, '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.OoyqAjZuLl/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.V55PI9dvsI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:54:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 17:54:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 17:54:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OoyqAjZuLl/Render', 17:54:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:54:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:54:14 INFO - 'HOME': '/Users/cltbld', 17:54:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:54:14 INFO - 'LOGNAME': 'cltbld', 17:54:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:54:14 INFO - 'MOZ_NO_REMOTE': '1', 17:54:14 INFO - 'NO_EM_RESTART': '1', 17:54:14 INFO - 'PAGER': '/bin/cat', 17:54:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:54:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:54:14 INFO - 'PWD': '/builds/slave/test', 17:54:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:54:14 INFO - 'SHELL': '/bin/bash', 17:54:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.V55PI9dvsI/Listeners', 17:54:14 INFO - 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/', 17:54:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:54:14 INFO - 'USER': 'cltbld', 17:54:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:54:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:54:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:54:14 INFO - 'XPC_FLAGS': '0x0', 17:54:14 INFO - 'XPC_SERVICE_NAME': '0', 17:54:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:54:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:54:15 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:54:15 INFO - Cleaning up... 17:54:15 INFO - Return code: 0 17:54:15 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:54:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:54:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:54:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:54:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:54:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:54:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:54:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1096adb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096b0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf93561170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10948bd30>, '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 0x1093b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109480be8>, '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.OoyqAjZuLl/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.V55PI9dvsI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:54:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 17:54:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 17:54:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OoyqAjZuLl/Render', 17:54:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:54:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:54:15 INFO - 'HOME': '/Users/cltbld', 17:54:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:54:15 INFO - 'LOGNAME': 'cltbld', 17:54:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:54:15 INFO - 'MOZ_NO_REMOTE': '1', 17:54:15 INFO - 'NO_EM_RESTART': '1', 17:54:15 INFO - 'PAGER': '/bin/cat', 17:54:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:54:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:54:15 INFO - 'PWD': '/builds/slave/test', 17:54:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:54:15 INFO - 'SHELL': '/bin/bash', 17:54:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.V55PI9dvsI/Listeners', 17:54:15 INFO - 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/', 17:54:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:54:15 INFO - 'USER': 'cltbld', 17:54:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:54:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:54:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:54:15 INFO - 'XPC_FLAGS': '0x0', 17:54:15 INFO - 'XPC_SERVICE_NAME': '0', 17:54:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:54:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:54:15 INFO - Downloading/unpacking blobuploader==1.2.4 17:54:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:54:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:54:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:54:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:54:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:54:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:54:17 INFO - Downloading blobuploader-1.2.4.tar.gz 17:54:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:54:18 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:54:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:54:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:54:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:54:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:54:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:54:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:54:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:54:18 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:54:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:54:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:54:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:54:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:54:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:54:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:54:19 INFO - Downloading docopt-0.6.1.tar.gz 17:54:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:54:19 INFO - Installing collected packages: blobuploader, requests, docopt 17:54:19 INFO - Running setup.py install for blobuploader 17:54:19 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:54:19 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:54:19 INFO - Running setup.py install for requests 17:54:20 INFO - Running setup.py install for docopt 17:54:20 INFO - Successfully installed blobuploader requests docopt 17:54:20 INFO - Cleaning up... 17:54:20 INFO - Return code: 0 17:54:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:54: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')]} 17:54:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:54:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:54: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')]} 17:54:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:54:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:54:20 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 0x1096adb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096b0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf93561170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10948bd30>, '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 0x1093b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109480be8>, '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.OoyqAjZuLl/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.V55PI9dvsI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:54:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 17:54:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:54:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OoyqAjZuLl/Render', 17:54:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:54:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:54:20 INFO - 'HOME': '/Users/cltbld', 17:54:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:54:20 INFO - 'LOGNAME': 'cltbld', 17:54:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:54:20 INFO - 'MOZ_NO_REMOTE': '1', 17:54:20 INFO - 'NO_EM_RESTART': '1', 17:54:20 INFO - 'PAGER': '/bin/cat', 17:54:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:54:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:54:20 INFO - 'PWD': '/builds/slave/test', 17:54:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:54:20 INFO - 'SHELL': '/bin/bash', 17:54:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.V55PI9dvsI/Listeners', 17:54:20 INFO - 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/', 17:54:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:54:20 INFO - 'USER': 'cltbld', 17:54:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:54:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:54:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:54:20 INFO - 'XPC_FLAGS': '0x0', 17:54:20 INFO - 'XPC_SERVICE_NAME': '0', 17:54:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:54:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:54:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:54:20 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-2uVA_U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:54:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:54:20 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-F8NiWd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:54:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:54:20 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-8v2CdG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:54:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:54:20 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-66a7kU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:54:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:54:20 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-i8Lwhs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:54:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:54:21 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-oXbK0a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:54:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:54:21 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-rMOZtf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:54:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:54:21 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-ObVLT3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:54:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:54:21 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-05iXIM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:54:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:54:21 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-Z5ZFAK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:54:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:54:21 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-_TsnuI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:54:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:54:21 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-kmxlsc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:54:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:54:21 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-3IasG4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:54:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:54:21 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-OhhW8v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:54:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:54:21 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-jqWapM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:54:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:54:22 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-nF_3jA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:54:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:54:22 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-gPP8vp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:54:22 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:54:22 INFO - Running setup.py install for manifestparser 17:54:22 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:54:22 INFO - Running setup.py install for mozcrash 17:54:22 INFO - Running setup.py install for mozdebug 17:54:22 INFO - Running setup.py install for mozdevice 17:54:22 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:54:22 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:54:22 INFO - Running setup.py install for mozfile 17:54:23 INFO - Running setup.py install for mozhttpd 17:54:23 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:54:23 INFO - Running setup.py install for mozinfo 17:54:23 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:54:23 INFO - Running setup.py install for mozInstall 17:54:23 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:54:23 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:54:23 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:54:23 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:54:23 INFO - Running setup.py install for mozleak 17:54:23 INFO - Running setup.py install for mozlog 17:54:23 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:54:23 INFO - Running setup.py install for moznetwork 17:54:23 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:54:23 INFO - Running setup.py install for mozprocess 17:54:24 INFO - Running setup.py install for mozprofile 17:54:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:54:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:54:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:54:24 INFO - Running setup.py install for mozrunner 17:54:24 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:54:24 INFO - Running setup.py install for mozscreenshot 17:54:24 INFO - Running setup.py install for moztest 17:54:24 INFO - Running setup.py install for mozversion 17:54:24 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:54:24 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:54:24 INFO - Cleaning up... 17:54:24 INFO - Return code: 0 17:54:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:54:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:54:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:54:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:54:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:54:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:54:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:54:24 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 0x1096adb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096b0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf93561170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10948bd30>, '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 0x1093b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109480be8>, '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.OoyqAjZuLl/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.V55PI9dvsI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:54:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 17:54:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:54:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OoyqAjZuLl/Render', 17:54:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:54:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:54:24 INFO - 'HOME': '/Users/cltbld', 17:54:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:54:24 INFO - 'LOGNAME': 'cltbld', 17:54:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:54:24 INFO - 'MOZ_NO_REMOTE': '1', 17:54:24 INFO - 'NO_EM_RESTART': '1', 17:54:24 INFO - 'PAGER': '/bin/cat', 17:54:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:54:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:54:24 INFO - 'PWD': '/builds/slave/test', 17:54:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:54:24 INFO - 'SHELL': '/bin/bash', 17:54:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.V55PI9dvsI/Listeners', 17:54:24 INFO - 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/', 17:54:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:54:24 INFO - 'USER': 'cltbld', 17:54:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:54:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:54:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:54:24 INFO - 'XPC_FLAGS': '0x0', 17:54:24 INFO - 'XPC_SERVICE_NAME': '0', 17:54:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:54:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:54:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:54:25 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-DH_DXr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:54:25 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 17:54:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:54:25 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-ZGKzRz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:54:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:54:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:54:25 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-EhzXTZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:54:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 17:54:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:54:25 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-4eg_Kr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:54:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:54:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:54:25 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-PiGSeB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:54:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 17:54:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:54:25 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-djwcKA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:54:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 17:54:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:54:25 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-nXBKAn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:54:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 17:54:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:54:25 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-VB3HBD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:54:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 17:54:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:54:25 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-ZO6LIC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:54:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 17:54:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:54:26 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-s6tUf9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:54:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:54:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:54:26 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-80bICl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:54:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 17:54:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:54:26 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-WauO9p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:54:26 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)) 17:54:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:54:26 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-Bu7y9X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:54:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 17:54:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:54:26 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-3fX6Yt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:54:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 17:54:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:54:26 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-zD_ZAA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:54:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 17:54:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:54:26 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-SRUeFO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:54:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 17:54:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:54:26 INFO - Running setup.py (path:/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/pip-WZlegT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:54:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 17:54:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:54:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:54:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 17:54:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:54:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:54:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:54:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:54:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:54:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:54:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:54:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:54:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:54:29 INFO - Downloading blessings-1.6.tar.gz 17:54:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:54:29 INFO - Installing collected packages: blessings 17:54:29 INFO - Running setup.py install for blessings 17:54:29 INFO - Successfully installed blessings 17:54:29 INFO - Cleaning up... 17:54:29 INFO - Return code: 0 17:54:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:54:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:54:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:54:30 INFO - Reading from file tmpfile_stdout 17:54:30 INFO - Current package versions: 17:54:30 INFO - blessings == 1.6 17:54:30 INFO - blobuploader == 1.2.4 17:54:30 INFO - docopt == 0.6.1 17:54:30 INFO - functools32 == 3.2.3-2 17:54:30 INFO - jsonschema == 2.5.1 17:54:30 INFO - manifestparser == 1.1 17:54:30 INFO - mozInstall == 1.12 17:54:30 INFO - mozcrash == 1.0 17:54:30 INFO - mozdebug == 0.1 17:54:30 INFO - mozdevice == 0.49 17:54:30 INFO - mozfile == 1.2 17:54:30 INFO - mozhttpd == 0.7 17:54:30 INFO - mozinfo == 0.9 17:54:30 INFO - mozleak == 0.1 17:54:30 INFO - mozlog == 3.4 17:54:30 INFO - moznetwork == 0.27 17:54:30 INFO - mozprocess == 0.23 17:54:30 INFO - mozprofile == 0.28 17:54:30 INFO - mozrunner == 6.13 17:54:30 INFO - mozscreenshot == 0.1 17:54:30 INFO - mozsystemmonitor == 0.3 17:54:30 INFO - moztest == 0.8 17:54:30 INFO - mozversion == 1.4 17:54:30 INFO - psutil == 3.1.1 17:54:30 INFO - requests == 1.2.3 17:54:30 INFO - wsgiref == 0.1.2 17:54:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:54: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')]} 17:54:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:54:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:54: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')]} 17:54:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:54:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:54:30 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 0x1096adb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096b0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf93561170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10948bd30>, '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 0x1093b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109480be8>, '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.OoyqAjZuLl/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.V55PI9dvsI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:54:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 17:54:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:54:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OoyqAjZuLl/Render', 17:54:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:54:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:54:30 INFO - 'HOME': '/Users/cltbld', 17:54:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:54:30 INFO - 'LOGNAME': 'cltbld', 17:54:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:54:30 INFO - 'MOZ_NO_REMOTE': '1', 17:54:30 INFO - 'NO_EM_RESTART': '1', 17:54:30 INFO - 'PAGER': '/bin/cat', 17:54:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:54:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:54:30 INFO - 'PWD': '/builds/slave/test', 17:54:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:54:30 INFO - 'SHELL': '/bin/bash', 17:54:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.V55PI9dvsI/Listeners', 17:54:30 INFO - 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/', 17:54:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:54:30 INFO - 'USER': 'cltbld', 17:54:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:54:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:54:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:54:30 INFO - 'XPC_FLAGS': '0x0', 17:54:30 INFO - 'XPC_SERVICE_NAME': '0', 17:54:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:54:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:54:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 17:54:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 17:54:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 17:54:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 17:54:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 17:54:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 17:54:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 17:54:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 17:54:30 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 17:54:30 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 17:54:30 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 17:54:30 INFO - Cleaning up... 17:54:30 INFO - Return code: 0 17:54:30 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:54: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')]} 17:54:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:54:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:54: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')]} 17:54:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:54:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:54:30 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 0x1096adb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096b0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf93561170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10948bd30>, '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 0x1093b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109480be8>, '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.OoyqAjZuLl/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.V55PI9dvsI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:54:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 17:54:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 17:54:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OoyqAjZuLl/Render', 17:54:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:54:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:54:30 INFO - 'HOME': '/Users/cltbld', 17:54:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:54:30 INFO - 'LOGNAME': 'cltbld', 17:54:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:54:30 INFO - 'MOZ_NO_REMOTE': '1', 17:54:30 INFO - 'NO_EM_RESTART': '1', 17:54:30 INFO - 'PAGER': '/bin/cat', 17:54:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:54:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:54:30 INFO - 'PWD': '/builds/slave/test', 17:54:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:54:30 INFO - 'SHELL': '/bin/bash', 17:54:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.V55PI9dvsI/Listeners', 17:54:30 INFO - 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/', 17:54:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:54:30 INFO - 'USER': 'cltbld', 17:54:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:54:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:54:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:54:30 INFO - 'XPC_FLAGS': '0x0', 17:54:30 INFO - 'XPC_SERVICE_NAME': '0', 17:54:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:54:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:54:30 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:54:30 INFO - Cleaning up... 17:54:30 INFO - Return code: 0 17:54:30 INFO - Running post-action listener: _resource_record_post_action 17:54:30 INFO - Running post-action listener: _start_resource_monitoring 17:54:30 INFO - Starting resource monitoring. 17:54:30 INFO - [mozharness: 2016-12-31 01:54:30.664309Z] Finished create-virtualenv step (success) 17:54:30 INFO - [mozharness: 2016-12-31 01:54:30.665259Z] Running install step. 17:54:30 INFO - Running pre-action listener: _resource_record_pre_action 17:54:30 INFO - Running main action method: install 17:54:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:54:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:54:30 INFO - Reading from file tmpfile_stdout 17:54:30 INFO - Detecting whether we're running mozinstall >=1.0... 17:54:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:54:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:54:31 INFO - Reading from file tmpfile_stdout 17:54:31 INFO - Output received: 17:54:31 INFO - Usage: mozinstall [options] installer 17:54:31 INFO - Options: 17:54:31 INFO - -h, --help show this help message and exit 17:54:31 INFO - -d DEST, --destination=DEST 17:54:31 INFO - Directory to install application into. [default: 17:54:31 INFO - "/builds/slave/test"] 17:54:31 INFO - --app=APP Application being installed. [default: firefox] 17:54:31 INFO - mkdir: /builds/slave/test/build/application 17:54:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 17:54:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 17:54:52 INFO - Reading from file tmpfile_stdout 17:54:52 INFO - Output received: 17:54:52 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 17:54:52 INFO - Running post-action listener: _resource_record_post_action 17:54:52 INFO - [mozharness: 2016-12-31 01:54:52.957164Z] Finished install step (success) 17:54:52 INFO - [mozharness: 2016-12-31 01:54:52.957318Z] Running run-tests step. 17:54:52 INFO - Running pre-action listener: _resource_record_pre_action 17:54:52 INFO - Running main action method: run_tests 17:54:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:54:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:54:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:54:52 INFO - 2016-12-30 17:54:52.971 screenresolution[1634:8014] starting screenresolution argv=screenresolution get 17:54:52 INFO - 2016-12-30 17:54:52.974 screenresolution[1634:8014] Display 0: 1600x1200x32@60 17:54:52 INFO - 2016-12-30 17:54:52.980 screenresolution[1635:8017] starting screenresolution argv=screenresolution list 17:54:52 INFO - Available Modes on Display 0 17:54:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:54:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:54:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:54:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:54:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:54:53 INFO - Intel Iris: 17:54:53 INFO - Chipset Model: Intel Iris 17:54:53 INFO - Type: GPU 17:54:53 INFO - Bus: Built-In 17:54:53 INFO - VRAM (Dynamic, Max): 1536 MB 17:54:53 INFO - Vendor: Intel (0x8086) 17:54:53 INFO - Device ID: 0x0a2e 17:54:53 INFO - Revision ID: 0x0009 17:54:53 INFO - Displays: 17:54:53 INFO - Display: 17:54:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:54:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:54:53 INFO - Display Serial Number: 1600x1200 60 17:54:53 INFO - Main Display: Yes 17:54:53 INFO - Mirror: Off 17:54:53 INFO - Online: Yes 17:54:53 INFO - Rotation: Supported 17:54:53 INFO - Return code: 0 17:54:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:54:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:54:53 INFO - Python 2.7.3 17:54:53 INFO - Return code: 0 17:54:53 INFO - grabbing minidump binary from tooltool 17:54:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:54:53 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 0x10948bd30>, '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 0x1093b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109480be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:54:53 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 17:54:53 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 17:54:53 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 17:54:53 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:54:53 INFO - Return code: 0 17:54:53 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:54:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:54:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:54:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:54:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:54:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PbyaYpSDQsO5o7Mz2D9dtw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0259', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 17:54:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/PbyaYpSDQsO5o7Mz2D9dtw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0259 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 17:54:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OoyqAjZuLl/Render', 17:54:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:54:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:54:53 INFO - 'HOME': '/Users/cltbld', 17:54:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:54:53 INFO - 'LOGNAME': 'cltbld', 17:54:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:54:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:54:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:54:53 INFO - 'MOZ_NO_REMOTE': '1', 17:54:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:54:53 INFO - 'NO_EM_RESTART': '1', 17:54:53 INFO - 'PAGER': '/bin/cat', 17:54:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:54:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:54:53 INFO - 'PWD': '/builds/slave/test', 17:54:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:54:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:54:53 INFO - 'SHELL': '/bin/bash', 17:54:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.V55PI9dvsI/Listeners', 17:54:53 INFO - 'TMPDIR': '/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/', 17:54:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:54:53 INFO - 'USER': 'cltbld', 17:54:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:54:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:54:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:54:53 INFO - 'XPC_FLAGS': '0x0', 17:54:53 INFO - 'XPC_SERVICE_NAME': '0', 17:54:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:54:53 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PbyaYpSDQsO5o7Mz2D9dtw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0259', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 17:54:53 INFO - mozversion application_buildid: 20161230165019 17:54:53 INFO - mozversion application_changeset: a06f92099a5d8edeb05e5971967fe8d6cd4c593c 17:54:53 INFO - mozversion application_display_name: Nightly 17:54:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:54:53 INFO - mozversion application_name: Firefox 17:54:53 INFO - mozversion application_remotingname: firefox 17:54:53 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 17:54:53 INFO - mozversion application_vendor: Mozilla 17:54:53 INFO - mozversion application_version: 53.0a1 17:54:53 INFO - mozversion platform_buildid: 20161230165019 17:54:53 INFO - mozversion platform_changeset: a06f92099a5d8edeb05e5971967fe8d6cd4c593c 17:54:53 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 17:54:53 INFO - mozversion platform_version: 53.0a1 17:54:53 INFO - using testdate: 1483149293 17:54:53 INFO - actual date: 1483149293 17:54:53 INFO - starting webserver on 'localhost:49241' 17:54:53 INFO - SUITE-START | Running 2 tests 17:54:53 INFO - TEST-START | dromaeo_css 17:54:53 INFO - operating with platform_type : mac_ 17:54:53 INFO - Initialising browser for dromaeo_css test... 17:54:53 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/tmpHthVfP/profile http://localhost:49241/getInfo.html) 17:54:54 INFO - PROCESS | 1645 | 1483149294174 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4986 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3827 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2831 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 17:54:56 INFO - PROCESS | 1645 | __metrics Screen width/height:1600/1200 17:54:56 INFO - PROCESS | 1645 | colorDepth:24 17:54:56 INFO - PROCESS | 1645 | Browser inner width/height: 1024/689 17:54:56 INFO - PROCESS | 1645 | __metrics 17:54:56 INFO - PROCESS | 1645 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 17:54:56 INFO - PROCESS | 1645 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:54:56 INFO - TEST-INFO | 1645: exit 0 17:54:56 INFO - Browser initialized. 17:54:56 INFO - Running cycle 1/1 for dromaeo_css test... 17:54:56 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/tmpHthVfP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:55:09 INFO - PROCESS | 1648 | RSS: Main: 224866304 17:55:09 INFO - PROCESS | 1648 | 17:56:00 INFO - PROCESS | 1648 | 1483149360764 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] 17:58:13 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 17:58:15 INFO - PROCESS | 1648 | RSS: Main: 424235008 17:58:15 INFO - PROCESS | 1648 | 17:59:58 INFO - PROCESS | 1648 | ************************* 17:59:58 INFO - PROCESS | 1648 | A coding exception was thrown and uncaught in a Task. 17:59:58 INFO - PROCESS | 1648 | 17:59:58 INFO - PROCESS | 1648 | Full message: ReferenceError: fetch is not defined 17:59:58 INFO - PROCESS | 1648 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 17:59:58 INFO - PROCESS | 1648 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 17:59:58 INFO - PROCESS | 1648 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 17:59:58 INFO - PROCESS | 1648 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 17:59:58 INFO - PROCESS | 1648 | TaskImpl@resource://gre/modules/Task.jsm:277:3 17:59:58 INFO - PROCESS | 1648 | asyncFunction@resource://gre/modules/Task.jsm:252:14 17:59:58 INFO - PROCESS | 1648 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 17:59:58 INFO - PROCESS | 1648 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 17:59:58 INFO - PROCESS | 1648 | TaskImpl@resource://gre/modules/Task.jsm:277:3 17:59:58 INFO - PROCESS | 1648 | asyncFunction@resource://gre/modules/Task.jsm:252:14 17:59:58 INFO - PROCESS | 1648 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 17:59:58 INFO - PROCESS | 1648 | 17:59:58 INFO - PROCESS | 1648 | ************************* 18:01:18 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 18:01:18 INFO - PROCESS | 1648 | RSS: Main: 369655808 18:01:18 INFO - PROCESS | 1648 | 18:04:21 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 18:04:21 INFO - PROCESS | 1648 | RSS: Main: 338817024 18:04:21 INFO - PROCESS | 1648 | 18:07:24 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 18:07:24 INFO - PROCESS | 1648 | RSS: Main: 328912896 18:07:24 INFO - PROCESS | 1648 | 18:10:28 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 18:10:29 INFO - PROCESS | 1648 | RSS: Main: 336134144 18:10:29 INFO - PROCESS | 1648 | 18:13:32 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 18:13:32 INFO - PROCESS | 1648 | RSS: Main: 426684416 18:13:32 INFO - PROCESS | 1648 | 18:13:32 INFO - PROCESS | 1648 | __start_tp_report 18:13:32 INFO - PROCESS | 1648 | _x_x_mozilla_page_load 18:13:32 INFO - PROCESS | 1648 | _x_x_mozilla_page_load_details 18:13:32 INFO - PROCESS | 1648 | |i|pagename|runs| 18:13:32 INFO - PROCESS | 1648 | |0;dojo.html;2911.563265510173;3375.0381141374696;3399.4900764885247;3403.5745531808525;3419.9829000855007;4727.290906364038;5608.5793565482545;5646.237757902682;5688.800891968782;5728.7135643217825;5655.123455864337;5730.226419433386;5758.136279558068;5758.596898217126;5827.737751801179;5133.82031628893;5161.870953226162;5162.070827251093;5179.922301165486;5208.869778255536;5296.92054619181;5317.202419637047;5328.040952628526;5331.760070796804;5347.197920311945;5262.079136151158;5279.392869819943;5298;5325.840224793263;5341.786328546873;4528.8867778305475;4797.7525843281;5167.173252279655;5192.350956130484;5199.714015729133;5216.556592689626;5230.686158830445;5230.869228269324;5239.633225674166;5259.86850328741;5692.0608099663505;5692.971535142339;5705.515031222351;5707.229524014247;5731.226284451587;6078.665673387962;6106.915739268716;6141.170941922828;6188.659623720693;6229.87540249197;4639.930401043987;6153.446189842891;6222.222222222223;6231.283402408691;6238.968805155991;5746.396628354005;5746.396628354046;5747.712614369265;5753.654780713171;5760.9711951440395;6185.907211391833;6210.4100110489435;6233.563650544418;6265.780697675651;6275.246970363585;6128.7282265584945;6176.413240742122;6206.286277078194;6208.099825525273;6217.160683307786;6268.811935641938;6273.654948977759;6277.1525844010475;6292.905606415908;6295.118683384329;6070.332263451017;6097.115918191837;6105.023196288573;6117.218487018656;6144.126041557279;6173.9073913891325;6176.752929882844;6180.6291622502795;6187.628742275478;6193.597416167935;4856.975715121402;4884.072026314989;4892.046051020088;4930.260460930817;4941.975290123526;4257.467816522934;4367.213901497761;4373.868783936487;4383.101464199832;4399.84600538986;5738;5746.396628353963;5753.079507278899;5759.740811663485;5762.88474230513;6018.518518518508;6039.909401358983;6097.5731698780655;6136.427173609184;6162.322144564095;5750.166225897305;5761.135829625589;5810.912836307459;5838.182654428383;5869.4717475427415;5384.6769193848495;5393.730313484389;5395.81114660985;5408.13469844823;5420.268263784417;6310.463610593151;6317.1156038154695;6317.178766760292;6331.176946996861;6336.461400780985;6102.186302349175;6112.2970858351855;6126.540509461897;6199.597026193283;6262.123302737711;6207.751689932352;6304.495640348853;6337;6343.746250149942;6356.555041147075;6140.355262697352;6177.96911015442;6184.443400094013;6203.47270481996;6214.378562143749;5321.600879934098;5813.593048486566;6278.340774218824;6294.748210071546;6294.992801151794;6081.543884208613;6087.147799308012;6125.663088530174;6163.722632481459;6177.505799535937;6143.139960405458;6152.384761523812;6153.323134455296;6155.199824022849;6158.784442544489;5746.2529871116485;5936.5844390892225;6013.579049466495;6026.337102918763;6038.275406951194;5958;6000.009998350398;6011;6011.188489554029;6021.759129634941;5842.1820945068575;5848.53211743068;5853.1220316952795;5858.326292476419;5860.619059761102 18:13:32 INFO - PROCESS | 1648 | |1;ext.html;452.2402364028431;457.16795432313194;478.3112318261703;483.56479168748143;507.85526125054355;29033.56449653254;30836.229094272654;37420;39290.21419571607;39355.212895742065;39695;40072.19855602886;40177.598224017755;40219.99450013751;40302;9154.267658587314;9369.625214991393;9385.483798391086;9401.341906066578;9411.858822117674;8360.28614134101;8600.35497337699;10171.881093079755;10724.731881702943;10993.780124397506;11153.776924461506;11204.15968802343;11218.607348742795;11225.60710375137;11246.044086252678;11172.385518796464;11243.775124497464;11269.718257043558;11271.041961433288;11273.492692828842;8278.420510564263;8499.192576705203;8499.277561407287;8609.26821220197;11036.006759391694;30580.84709576438;36346.27307453836;36772.26455470902;36899.815500922596;38709.419358709645;3943.960560394388;3958.3072962231495;3974.8012599369918;3977.8607748728946;4086.8160932758096;28625.85687071572;29471.70528294711;29500.902405247354;29590;29808.8509557453;3130.248740302311;3262.771605987582;3266.6243382011135;3321.2693207494312;3323.302106557626;455096;460160;460820;461426;461641;55458;55937;56087.71956140275;56131.71934140303;56160.43839561634;48183.80875251216;56633;57471;57858.132128018115;57900;14497.492587759378;15110.773338399933;15128.319225634872;15140.697186056217;15160;11781.469833857496;11841.407929603485;11851.46668399924;11857.703557410961;11866;13400.463981440827;13561.050726449252;13592.184468931895;14792.11553747009;14964.40142394292;6513.153290072355;6585.6707164641575;6613.272540020594;6634.203895532471;6635.668216589152;827.2554700769235;847.4068152293419;855.5294587976588;860.6665439512027;867.6919693508713;28448.25435475736;28635.57046644302;29260.414791704472;29439.85280073586;29483.557746633818;1013.5489707080278;1038.0709265207597;1041.6510468992822;1043.2697112021463;1104.812181929058;14135.575932722035;14552.417903283962;14646;14950.925245373703;14990.775138372934;564140;565452.1727391337;567213;568708;573623;55800.441995579524;55960.16059759107;56022.43697100921;56231.43768562344;56382.43617563772;1330.9667258318425;1353.627752368087;1357.0636260979893;1358.701085761131;1359.7552440560794;27627;28252.717472825832;28844.701988410154;29307.560386594643;29450.410991779616;47025.58923232309;60424.697876510334;60870.69564652148;60952.695236523534;65017.67491162514;431588;438634;439252;557385;557520;42868.845089557806;42916.570834291255;43775.78112109419;43792.34311485394;43966.90082747957;47121.293180603;47434;47525.52474475211;47530;47892.76053619709;38853;38908.80545597254;39250.411243831935;39946;40284;38851.02872428212;38975.22049558936;39188;40458.190836182526;40541.39187912243;38318;38904;39200.511928329484;39223.41164882472;39287;33544.32911341711;34072.48891266583;35099.298014039065;35256.29487410186;36004;21897;23975.702596585343;25246;25451.74548254494;26015.73984260133 18:13:32 INFO - PROCESS | 1648 | |2;jquery.html;351.9912002199943;355.59462213077086;367.9411294192928;375.12408526091565;396.1265409771454;14295.785563216547;14641.633959151026;15236.771448428268;19221.711674324895;19693.31073412431;18602.53493662656;19604.90197549014;19641.214351425926;19814.405567832953;19862.50343741407;5654.971725141369;5852.502537284336;5862.88274234515;5977.342492325841;5986.042233242682;5166.974165129169;5769.538436925036;5867.207926929853;5901.557383196277;5904.7047647617665;5753.108268218432;5853.5317174625925;5853.678047707374;5857.736401861926;5861.531077513789;6062.424069713392;6380.202474690687;6440.871182576322;6470.061841033071;6525.641089740062;5453.3728621208675;5604.775808967636;5656.490915817596;5733;5743.684097374685;12258.283840262371;12297.139200255897;12441.253524788543;12467.002639788796;12500.24998500093;2475.405902583367;2534.6071358939394;2552.7494001533737;2594.040205124097;2635;9480.95259523705;9496.857547136799;9514.571844266955;9919.752006199831;10030.498475076218;1626.4957863062489;1724.6378260565295;1735.8264173582668;1774.8136445673147;1799.136414521024;113028;119460;119474.99999999913;120961;121648;10675.306105103144;10929.398883061507;10984.286021408583;11008.174386921013;11179;13953.441862325597;14031.719365612835;14057.578272651837;14071.155730656194;14100.788488172686;7609.124950630749;7843.803904902309;7853.607319633996;7965.8805117923275;8023.679052837938;6336.746530138743;7575.6969721210535;7718.884216736754;7732.162217525732;7743.961280193563;4254.914901701949;4320.395144679747;4338.82644694215;4413.161499315184;4421.823127074946;1630.7390817469147;1813.9274429022691;1866.861214659057;1877.8967156806507;1878.164216923456;875.2516598308482;885.9778505537413;891.9286457083417;904.1907492793913;966.3525437956586;17702.91148544249;18710.15804288783;19151.04244787755;19266.518337041685;19573.412797616093;927.6289484206236;934.7709811096325;947.6304241345881;949.7435692363023;965.888922773876;8534.615942282611;9175.493416126725;9507.57215925285;9924.553395097239;10098.343607665478;544947;550015;553357;560739;563833.3616663781;86521.26957460941;105531.47234263779;113667;121709;125529;747.5813544415145;929.8186853563625;962.1100482054072;963.3401120232663;968.7093871838421;17935;17992.730109048636;18043.90978045154;18098.638027239118;18189.909050454662;51758;52069.73965130302;52119.47880521146;52294;52855;74004.14989625303;74779;75014.8747768773;75161.87257191037;76540.23459765331;7005.649717514002;7470.514416562906;7818.0618325799;7843.764687059397;7989.360851132012;10139;12537.310447925453;12836.679083022998;12861.035422343175;12944.029197810014;10689.412082335546;10981.17641176931;11114.166437517057;11314.886851131712;11388.658340249805;8255.628496717782;9855.162311203341;9968.950155249468;10009.799804004026;11013.944930275296;9184;9363.485008324607;10905.890941090805;11194.216404851582;11267.492962816528;6759.729610815512;7074.504784665024;7298.087739032621;7402.222766609644;7482.2517748224745;10377.325473844176;10453.26827122094;10477.9476102619;11147.052500537546;11300.49147788335 18:13:32 INFO - PROCESS | 1648 | |3;mootools.html;1384.0795870745953;1394.7350003499334;1410.4358256697312;1418.1065928465066;1421.459845258802;7907.051153861537;8345.37409694273;8354.373421993345;8371.204735550113;8384.077751447336;7321.121465424143;7604.239576042393;7619.42854285928;7922.049354077519;8030.558319292451;2266.9433264168365;2304.0207911637485;2307.9192228272013;2308.595995700754;2310.376198426424;2249.853759505627;2273.8067264282554;2284.497410569672;2288.473651060257;2308.8960996755186;1996.9700454493195;2067.9276225332114;2232.6427771556555;2280.737715162761;2307.896144673485;2283.3264187064856;2290.839641225115;2294.1167650454527;2296.127471560796;2302.159711705224;2277.225743247304;2296.0471404367167;2304.8617082974906;2326.941826454335;2336.1823361823394;8284.668613255462;8379.287760540361;8380.748577542683;8382.832343353099;8387;4916.139675556763;5038.470960549126;5088.6946783193125;5093.770780314858;5097.617678674114;3204.871805127792;3336.4661654135466;3345.330933813247;3406.5060566217758;3412.8805491807666;1124.2916962313773;1166.393475392791;1206.8189771534253;1207.028342184541;1216.720154364501;10310.32982856112;10610.946945265225;10631.94684026575;10652;10761.892381076246;3614.475900994341;3633.94549081764;4198.181354635878;4382.408374869416;4395.846145384896;8456.534890580953;8495.938007749031;8580.35647326453;8635.568221588896;8648.394612377073;4931.852044438672;4951.455339912607;4992.951480189201;4994.550490455804;5015.799368025238;4125.162894504303;4190.622843944059;4347.456567929009;4534.410526631516;4619.214733495247;3210.9839450802597;3515.1739341254784;3541.9822900885847;3676.7058635309113;3698.0754811297174;1095.5782023920847;1121.5681962444517;1137.4028634966708;1141.1042331769522;1142.4801715219555;801.5030680977715;857.2242120880613;862.491130233153;886.6985225023523;914.2868562521244;4223.598758117974;4449.933251001237;5332.973335133377;5382.650127741763;5449.427810079964;419.1470357821818;525.7528961388141;536.8711509237833;539.4282061015336;555.2060553408664;3535.734819888519;3560.8219589020446;3589.7871405092296;3595.0113718727557;3611.9277614447565;9432.056794320513;9812.803743925082;9880.901190988141;9884.901150988542;9909.157721593601;9444.386114902509;9830.262730295257;9840.606375745034;9851.064148905842;9880.851787223197;7479.214682458263;7667.348275396542;7711;7719.34385577221;7719.65261563231;4772.976135119302;5292.232626269092;5358.598105142052;5491.368492623399;5524.558035357081;8812.51531165792;8888.33337499703;9064.048274931038;9110.498922559324;9274.747909032194;9322.813543728951;9382.671606494014;9411.152996230372;9447.763805904633;9500.14498695093;6368.681565921778;6533.607983521004;7011.929880701332;7155.3202445767565;7157.997880296659;7358.1538123116525;7569.810754731176;7870.881936770838;7902.130765615662;7956.6419511120985;6893.862122757417;6979.127609048869;7022.6488675565;7024.192217894803;7091.964540177266;6850.691718872563;6923.065386172802;6927.6882540286815;6954.095967524189;6975.093237879043;6084.878302433838;6259.530535209969;6323.873522529616;6341.0805433212845;6421.389967953037;4594.494605593449;4687.343771871872;4688.789004494738;4713.2458806590785;4841.419029716457;4683.1336202802595;4783.689060211075;4792.5207479253195;4852.927206091841;4918.877028074184 18:13:32 INFO - PROCESS | 1648 | |4;prototype.html;349.3135987784004;359.52901698774605;363.59459202988677;369.85205917632925;371.3167771300806;4652.627789776814;4665.883352916178;5191.247269145972;5255.7911680313555;5258;4181.560936101711;4587.518310577391;4884.656719402166;5152.175651895697;5179.2749015137915;3308.553345298378;3758.4362345648096;3837.697381571049;4039.9596004040022;4166.666666666668;3618.601953785095;3889.183271512986;3946.585608511108;4102.220578090153;4108.36320370343;3295.7528185386072;4092.3656833190903;4123.8350465981475;4134.52452967908;4151.709380343377;3560.9495198916384;3829.9808500957597;3862.2854771867014;3893.824777885002;4108.732932359387;3638.5815631202486;3640.4903313536124;3646.32542979547;3655.3054919565197;3658.1769101952113;4506.963398418349;4666.346711460398;4679.695819771704;4698.95301046992;5032.798688052473;4017.357222844331;4245.596668316505;4305.677074219446;4382.7242919244545;4696.976515117437;4710.811567537295;5123.538881500683;5189.936063107054;5349.8230389314285;5560.913692900439;3370.612379576331;3695.648913353254;3883.980580097081;3989.40158976153;4036.7174297799165;4767.737774422405;5203.320290050627;5462.2079798429;5822.505087067604;5847.56143289255;3914.393269043303;4107.67138628909;4145.813438395279;4159.584041595876;4171.561985991488;4229.492460904649;4233.449651545279;4249.617534421917;4256.978715106405;4271.508776490743;4128.91742165155;4647.512011238771;4831.4202295724735;4853.3447984522345;4853.805847766121;5335.919961200585;5566.777328906879;5592.720363981866;5605;5637.477880972115;1239.7210627608897;1337;1358.3479929633831;1362.3733082781832;1363.4069179906771;905.343625871238;931.5202670624634;951.9433428893922;962.4033099478369;991.7718924647371;4430.667699922518;4893.755312234374;5282.867928301823;5320.813771517978;5337.466253374709;5123.538881500683;5194.714290714047;5325.360956685223;5484.424135465799;5776.566757493205;4519.344695019204;4591.874990627251;5087.440381558025;5214.530692237717;5319.1223448131395;2848.60418394985;2866.2834291427143;2880.2799300174956;2889.8266104033823;2902.274431392152;5128.692278463304;5597.44025597437;5726.541876649858;5729.341125770507;5788.0739081746715;5101.648063263238;5298.2647233700145;5859.94140058594;5879.823605291848;5931.73307201177;5475.616706830484;5559.360673522597;5684.8294551163535;5764.36591974874;5949.6430214187285;4608.700434471749;4971.216118973386;5354.866128346823;5699.829005129853;5766.509846663081;5655.547556195412;5668.234788303526;5673.404292549223;5711.457411545896;5718.285214348207;5458.317710286215;5956.672383018976;5963.896679114378;5981.880362392815;6047.248501931698;3271.787333823294;3693.575238847567;3797.5442946846556;3825.8278377472525;3865.7436333192386;4931.013797240638;5644.915326270027;5686.744096515584;5707.372189059283;5707.4577915098;5518.641288316408;5526.50261476469;5547.33431988164;5567.5545956322585;5570.19232211319;5084;5156.4070131935305;5205.687658740336;5519.448055194448;5527.806526771544;4415.492218394926;5438.864028399322;5444.8094316698725;5465.918011229755;5512.6692398456225;4760.333883141011;4834.9604834960655;5130.204818253177;5392.865178370572;5419.241306217212;4717.599004084691;4910.79014443509;5071.974640126776;5406.162044883049;5442.455754424526 18:13:32 INFO - PROCESS | 1648 | |5;yui.html;712.9037579926709;717.4905816870022;755.9508631938924;796.0983299669936;796.5380079553858;14195;15201.239938003084;15210.315535800888;15221.391144354213;15231.31459084341;13766;13930.721385572282;13935.44258229672;13940.372683229254;13967.441302347894;675.9188897332327;691.5504921800845;697.0624510033996;832.6169961817571;839.3159574946415;853.7908212487949;864.4597126795753;865.0311650950944;866.2463656618751;866.4065115395942;907.4827348411408;921.2814005076051;921.6681994481982;924.0159230419615;924.9213816825578;881.1716986033108;916.724982505249;920.3005715656083;920.3787443475627;924.8705181274594;531.2350215689395;542.0351773842566;656.0323522803874;682.7200847652489;683.6035099642196;13550.186988780606;13989.650258743512;14016.299185040707;14029.228392438412;14065.648358791112;13040.869591304061;13178.538751143664;13181.340932953313;13188.538401156009;13398.933005334911;12315.50737970489;12404.503819847196;12511.687207819787;12532.310722910237;12777.752895099557;4196.433481479991;4214.36784482327;4461.21928662486;4502.819887204508;4503.932441013388;103451.48274258655;103736.96263037348;105157.47421262921;105191.94808051898;106081.46959265154;9811.656592019244;10120.696379108638;10348.275620706478;10552.261341706007;10586.20603454744;17576.648467030587;20311.39065827998;20529.2814751483;20589.897050514952;20612.69080963787;7284.271572842779;7363.300486453778;7443.144038435649;7586.734464293723;7605;4005.3991901214467;4124.484439445069;4131.380292956021;4133.111381053088;4144.212599606065;3286.5539162768305;3331.8833840815455;3336.8665253390077;3370.76404651672;3380.39152952466;608.3217212807688;612.8621060261405;620.1225266248208;623.996880015606;630.2059405149545;578.3089208395927;600.7236671131241;610.9389061093943;623.6507555768784;626.101544283954;8471.322294216448;8597.52713600758;8629.741107766651;8742.30061595071;8912.55437228136;575.9596828222068;582.72320647692;612.8906678911185;628.3308276685316;638.4668801550757;4162.937555936664;4180.561041090642;4186;4192.7065105442325;4332.068605249887;95351;100944;101995.49002255089;103484.48257758813;104699;86483.56758216169;88100;88732.5563372179;89126.10873891308;89825.55087224522;75724.9999999989;77596.61201694068;77680.61159694391;78112.43775124465;79499;7862.213778622092;8139.3488520917;8325.583720813815;8330.250277474814;8498.70254541088;39710.60451943601;48337.75831120822;49042;50230.748846255534;51235;65848.0122798168;66522;66633.66732665223;67066;67844.6431071366;10583.100436462986;10660.680179594625;10663.4135122569;11061.225714199929;11178.88821111811;11396.316221026766;11405.258658187191;11634.88365116338;11650.6504804856;11992.76014479723;11638.88361116378;11974;12646.620601381974;13100.410481528164;13383.598492045254;13100.672483187996;13137.146085504412;13367.732645347232;13474.730505389642;13815;13173.802392964304;13179.60461186166;13421.798673019717;13833.290853935632;14027.50903718365;10688.946555267174;11045.116390688887;11196.104311655212;11496.94251528737;11880.702982425508;8996.64013439455;9304.534773261445;10655.631964911125;11183.552657893919;11362.431878405881 18:13:32 INFO - PROCESS | 1648 | __end_tp_report 18:13:32 INFO - PROCESS | 1648 | __start_cc_report 18:13:32 INFO - PROCESS | 1648 | _x_x_mozilla_cycle_collect,1863 18:13:32 INFO - PROCESS | 1648 | __end_cc_report 18:13:32 INFO - PROCESS | 1648 | __startTimestamp1483150412588__endTimestamp 18:13:32 INFO - PROCESS | 1648 | 18:13:32 INFO - PROCESS | 1648 | ------- Summary: start ------- 18:13:32 INFO - PROCESS | 1648 | Number of tests: 6 18:13:32 INFO - PROCESS | 1648 | 18:13:32 INFO - PROCESS | 1648 | [#0] dojo.html Cycles:165 Average:5709.10 Median:5860.62 stddev:653.86 (11.2%) stddev-sans-first:617.94 18:13:32 INFO - PROCESS | 1648 | Values: 2911.6 3375.0 3399.5 3403.6 3420.0 4727.3 5608.6 5646.2 5688.8 5728.7 5655.1 5730.2 5758.1 5758.6 5827.7 5133.8 5161.9 5162.1 5179.9 5208.9 5296.9 5317.2 5328.0 5331.8 5347.2 5262.1 5279.4 5298.0 5325.8 5341.8 4528.9 4797.8 5167.2 5192.4 5199.7 5216.6 5230.7 5230.9 5239.6 5259.9 5692.1 5693.0 5705.5 5707.2 5731.2 6078.7 6106.9 6141.2 6188.7 6229.9 4639.9 6153.4 6222.2 6231.3 6239.0 5746.4 5746.4 5747.7 5753.7 5761.0 6185.9 6210.4 6233.6 6265.8 6275.2 6128.7 6176.4 6206.3 6208.1 6217.2 6268.8 6273.7 6277.2 6292.9 6295.1 6070.3 6097.1 6105.0 6117.2 6144.1 6173.9 6176.8 6180.6 6187.6 6193.6 4857.0 4884.1 4892.0 4930.3 4942.0 4257.5 4367.2 4373.9 4383.1 4399.8 5738.0 5746.4 5753.1 5759.7 5762.9 6018.5 6039.9 6097.6 6136.4 6162.3 5750.2 5761.1 5810.9 5838.2 5869.5 5384.7 5393.7 5395.8 5408.1 5420.3 6310.5 6317.1 6317.2 6331.2 6336.5 6102.2 6112.3 6126.5 6199.6 6262.1 6207.8 6304.5 6337.0 6343.7 6356.6 6140.4 6178.0 6184.4 6203.5 6214.4 5321.6 5813.6 6278.3 6294.7 6295.0 6081.5 6087.1 6125.7 6163.7 6177.5 6143.1 6152.4 6153.3 6155.2 6158.8 5746.3 5936.6 6013.6 6026.3 6038.3 5958.0 6000.0 6011.0 6011.2 6021.8 5842.2 5848.5 5853.1 5858.3 5860.6 18:13:32 INFO - PROCESS | 1648 | 18:13:32 INFO - PROCESS | 1648 | [#1] ext.html Cycles:180 Average:64703.69 Median:39236.91 stddev:135092.12 (344.3%) stddev-sans-first:135384.96 18:13:32 INFO - PROCESS | 1648 | Values: 452.2 457.2 478.3 483.6 507.9 29033.6 30836.2 37420.0 39290.2 39355.2 39695.0 40072.2 40177.6 40220.0 40302.0 9154.3 9369.6 9385.5 9401.3 9411.9 8360.3 8600.4 10171.9 10724.7 10993.8 11153.8 11204.2 11218.6 11225.6 11246.0 11172.4 11243.8 11269.7 11271.0 11273.5 8278.4 8499.2 8499.3 8609.3 11036.0 30580.8 36346.3 36772.3 36899.8 38709.4 3944.0 3958.3 3974.8 3977.9 4086.8 28625.9 29471.7 29500.9 29590.0 29808.9 3130.2 3262.8 3266.6 3321.3 3323.3 455096.0 460160.0 460820.0 461426.0 461641.0 55458.0 55937.0 56087.7 56131.7 56160.4 48183.8 56633.0 57471.0 57858.1 57900.0 14497.5 15110.8 15128.3 15140.7 15160.0 11781.5 11841.4 11851.5 11857.7 11866.0 13400.5 13561.1 13592.2 14792.1 14964.4 6513.2 6585.7 6613.3 6634.2 6635.7 827.3 847.4 855.5 860.7 867.7 28448.3 28635.6 29260.4 29439.9 29483.6 1013.5 1038.1 1041.7 1043.3 1104.8 14135.6 14552.4 14646.0 14950.9 14990.8 564140.0 565452.2 567213.0 568708.0 573623.0 55800.4 55960.2 56022.4 56231.4 56382.4 1331.0 1353.6 1357.1 1358.7 1359.8 27627.0 28252.7 28844.7 29307.6 29450.4 47025.6 60424.7 60870.7 60952.7 65017.7 431588.0 438634.0 439252.0 557385.0 557520.0 42868.8 42916.6 43775.8 43792.3 43966.9 47121.3 47434.0 47525.5 47530.0 47892.8 38853.0 38908.8 39250.4 39946.0 40284.0 38851.0 38975.2 39188.0 40458.2 40541.4 38318.0 38904.0 39200.5 39223.4 39287.0 33544.3 34072.5 35099.3 35256.3 36004.0 21897.0 23975.7 25246.0 25451.7 26015.7 18:13:32 INFO - PROCESS | 1648 | 18:13:32 INFO - PROCESS | 1648 | [#2] jquery.html Cycles:180 Average:32456.63 Median:4794.40 stddev:92702.01 (1933.5%) stddev-sans-first:92930.72 18:13:32 INFO - PROCESS | 1648 | Values: 352.0 355.6 367.9 375.1 396.1 14295.8 14641.6 15236.8 19221.7 19693.3 18602.5 19604.9 19641.2 19814.4 19862.5 5655.0 5852.5 5862.9 5977.3 5986.0 5167.0 5769.5 5867.2 5901.6 5904.7 5753.1 5853.5 5853.7 5857.7 5861.5 6062.4 6380.2 6440.9 6470.1 6525.6 5453.4 5604.8 5656.5 5733.0 5743.7 12258.3 12297.1 12441.3 12467.0 12500.2 2475.4 2534.6 2552.7 2594.0 2635.0 9481.0 9496.9 9514.6 9919.8 10030.5 1626.5 1724.6 1735.8 1774.8 1799.1 113028.0 119460.0 119475.0 120961.0 121648.0 10675.3 10929.4 10984.3 11008.2 11179.0 13953.4 14031.7 14057.6 14071.2 14100.8 7609.1 7843.8 7853.6 7965.9 8023.7 6336.7 7575.7 7718.9 7732.2 7744.0 4254.9 4320.4 4338.8 4413.2 4421.8 1630.7 1813.9 1866.9 1877.9 1878.2 875.3 886.0 891.9 904.2 966.4 17702.9 18710.2 19151.0 19266.5 19573.4 927.6 934.8 947.6 949.7 965.9 8534.6 9175.5 9507.6 9924.6 10098.3 544947.0 550015.0 553357.0 560739.0 563833.4 86521.3 105531.5 113667.0 121709.0 125529.0 747.6 929.8 962.1 963.3 968.7 17935.0 17992.7 18043.9 18098.6 18189.9 51758.0 52069.7 52119.5 52294.0 52855.0 74004.1 74779.0 75014.9 75161.9 76540.2 7005.6 7470.5 7818.1 7843.8 7989.4 10139.0 12537.3 12836.7 12861.0 12944.0 10689.4 10981.2 11114.2 11314.9 11388.7 8255.6 9855.2 9969.0 10009.8 11013.9 9184.0 9363.5 10905.9 11194.2 11267.5 6759.7 7074.5 7298.1 7402.2 7482.3 10377.3 10453.3 10477.9 11147.1 11300.5 18:13:32 INFO - PROCESS | 1648 | 18:13:32 INFO - PROCESS | 1648 | [#3] mootools.html Cycles:180 Average:5251.22 Median:5063.58 stddev:2923.82 (57.7%) stddev-sans-first:2917.58 18:13:32 INFO - PROCESS | 1648 | Values: 1384.1 1394.7 1410.4 1418.1 1421.5 7907.1 8345.4 8354.4 8371.2 8384.1 7321.1 7604.2 7619.4 7922.0 8030.6 2266.9 2304.0 2307.9 2308.6 2310.4 2249.9 2273.8 2284.5 2288.5 2308.9 1997.0 2067.9 2232.6 2280.7 2307.9 2283.3 2290.8 2294.1 2296.1 2302.2 2277.2 2296.0 2304.9 2326.9 2336.2 8284.7 8379.3 8380.7 8382.8 8387.0 4916.1 5038.5 5088.7 5093.8 5097.6 3204.9 3336.5 3345.3 3406.5 3412.9 1124.3 1166.4 1206.8 1207.0 1216.7 10310.3 10610.9 10631.9 10652.0 10761.9 3614.5 3633.9 4198.2 4382.4 4395.8 8456.5 8495.9 8580.4 8635.6 8648.4 4931.9 4951.5 4993.0 4994.6 5015.8 4125.2 4190.6 4347.5 4534.4 4619.2 3211.0 3515.2 3542.0 3676.7 3698.1 1095.6 1121.6 1137.4 1141.1 1142.5 801.5 857.2 862.5 886.7 914.3 4223.6 4449.9 5333.0 5382.7 5449.4 419.1 525.8 536.9 539.4 555.2 3535.7 3560.8 3589.8 3595.0 3611.9 9432.1 9812.8 9880.9 9884.9 9909.2 9444.4 9830.3 9840.6 9851.1 9880.9 7479.2 7667.3 7711.0 7719.3 7719.7 4773.0 5292.2 5358.6 5491.4 5524.6 8812.5 8888.3 9064.0 9110.5 9274.7 9322.8 9382.7 9411.2 9447.8 9500.1 6368.7 6533.6 7011.9 7155.3 7158.0 7358.2 7569.8 7870.9 7902.1 7956.6 6893.9 6979.1 7022.6 7024.2 7092.0 6850.7 6923.1 6927.7 6954.1 6975.1 6084.9 6259.5 6323.9 6341.1 6421.4 4594.5 4687.3 4688.8 4713.2 4841.4 4683.1 4783.7 4792.5 4852.9 4918.9 18:13:32 INFO - PROCESS | 1648 | 18:13:32 INFO - PROCESS | 1648 | [#4] prototype.html Cycles:180 Average:4478.09 Median:4920.90 stddev:1339.16 (27.2%) stddev-sans-first:1306.57 18:13:32 INFO - PROCESS | 1648 | Values: 349.3 359.5 363.6 369.9 371.3 4652.6 4665.9 5191.2 5255.8 5258.0 4181.6 4587.5 4884.7 5152.2 5179.3 3308.6 3758.4 3837.7 4040.0 4166.7 3618.6 3889.2 3946.6 4102.2 4108.4 3295.8 4092.4 4123.8 4134.5 4151.7 3560.9 3830.0 3862.3 3893.8 4108.7 3638.6 3640.5 3646.3 3655.3 3658.2 4507.0 4666.3 4679.7 4699.0 5032.8 4017.4 4245.6 4305.7 4382.7 4697.0 4710.8 5123.5 5189.9 5349.8 5560.9 3370.6 3695.6 3884.0 3989.4 4036.7 4767.7 5203.3 5462.2 5822.5 5847.6 3914.4 4107.7 4145.8 4159.6 4171.6 4229.5 4233.4 4249.6 4257.0 4271.5 4128.9 4647.5 4831.4 4853.3 4853.8 5335.9 5566.8 5592.7 5605.0 5637.5 1239.7 1337.0 1358.3 1362.4 1363.4 905.3 931.5 951.9 962.4 991.8 4430.7 4893.8 5282.9 5320.8 5337.5 5123.5 5194.7 5325.4 5484.4 5776.6 4519.3 4591.9 5087.4 5214.5 5319.1 2848.6 2866.3 2880.3 2889.8 2902.3 5128.7 5597.4 5726.5 5729.3 5788.1 5101.6 5298.3 5859.9 5879.8 5931.7 5475.6 5559.4 5684.8 5764.4 5949.6 4608.7 4971.2 5354.9 5699.8 5766.5 5655.5 5668.2 5673.4 5711.5 5718.3 5458.3 5956.7 5963.9 5981.9 6047.2 3271.8 3693.6 3797.5 3825.8 3865.7 4931.0 5644.9 5686.7 5707.4 5707.5 5518.6 5526.5 5547.3 5567.6 5570.2 5084.0 5156.4 5205.7 5519.4 5527.8 4415.5 5438.9 5444.8 5465.9 5512.7 4760.3 4835.0 5130.2 5392.9 5419.2 4717.6 4910.8 5072.0 5406.2 5442.5 18:13:32 INFO - PROCESS | 1648 | 18:13:32 INFO - PROCESS | 1648 | [#5] yui.html Cycles:180 Average:19887.84 Median:4194.57 stddev:29113.78 (694.1%) stddev-sans-first:29159.86 18:13:32 INFO - PROCESS | 1648 | Values: 712.9 717.5 756.0 796.1 796.5 14195.0 15201.2 15210.3 15221.4 15231.3 13766.0 13930.7 13935.4 13940.4 13967.4 675.9 691.6 697.1 832.6 839.3 853.8 864.5 865.0 866.2 866.4 907.5 921.3 921.7 924.0 924.9 881.2 916.7 920.3 920.4 924.9 531.2 542.0 656.0 682.7 683.6 13550.2 13989.7 14016.3 14029.2 14065.6 13040.9 13178.5 13181.3 13188.5 13398.9 12315.5 12404.5 12511.7 12532.3 12777.8 4196.4 4214.4 4461.2 4502.8 4503.9 103451.5 103737.0 105157.5 105191.9 106081.5 9811.7 10120.7 10348.3 10552.3 10586.2 17576.6 20311.4 20529.3 20589.9 20612.7 7284.3 7363.3 7443.1 7586.7 7605.0 4005.4 4124.5 4131.4 4133.1 4144.2 3286.6 3331.9 3336.9 3370.8 3380.4 608.3 612.9 620.1 624.0 630.2 578.3 600.7 610.9 623.7 626.1 8471.3 8597.5 8629.7 8742.3 8912.6 576.0 582.7 612.9 628.3 638.5 4162.9 4180.6 4186.0 4192.7 4332.1 95351.0 100944.0 101995.5 103484.5 104699.0 86483.6 88100.0 88732.6 89126.1 89825.6 75725.0 77596.6 77680.6 78112.4 79499.0 7862.2 8139.3 8325.6 8330.3 8498.7 39710.6 48337.8 49042.0 50230.7 51235.0 65848.0 66522.0 66633.7 67066.0 67844.6 10583.1 10660.7 10663.4 11061.2 11178.9 11396.3 11405.3 11634.9 11650.7 11992.8 11638.9 11974.0 12646.6 13100.4 13383.6 13100.7 13137.1 13367.7 13474.7 13815.0 13173.8 13179.6 13421.8 13833.3 14027.5 10688.9 11045.1 11196.1 11496.9 11880.7 8996.6 9304.5 10655.6 11183.6 11362.4 18:13:32 INFO - PROCESS | 1648 | -------- Summary: end -------- 18:13:32 INFO - PROCESS | 1648 | 18:13:33 INFO - TEST-INFO | 1648: exit 0 18:13:33 INFO - TEST-OK | dromaeo_css | took 1119643ms 18:13:33 INFO - TEST-START | kraken 18:13:33 INFO - operating with platform_type : mac_ 18:13:33 INFO - Initialising browser for kraken test... 18:13:33 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/tmpETxRVS/profile http://localhost:49241/getInfo.html) 18:13:33 INFO - PROCESS | 1653 | 1483150413698 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4986 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3827 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2831 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 18:13:35 INFO - PROCESS | 1653 | __metrics Screen width/height:1600/1200 18:13:35 INFO - PROCESS | 1653 | colorDepth:24 18:13:35 INFO - PROCESS | 1653 | Browser inner width/height: 1024/689 18:13:35 INFO - PROCESS | 1653 | __metrics 18:13:35 INFO - PROCESS | 1653 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:13:35 INFO - PROCESS | 1653 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:13:36 INFO - TEST-INFO | 1653: exit 0 18:13:36 INFO - Browser initialized. 18:13:36 INFO - Running cycle 1/1 for kraken test... 18:13:36 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/T/tmpETxRVS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:13:48 INFO - PROCESS | 1654 | RSS: Main: 233078784 18:13:48 INFO - PROCESS | 1654 | 18:13:50 INFO - PROCESS | 1654 | 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) 18:13:51 INFO - PROCESS | 1654 | RSS: Main: 286281728 18:13:51 INFO - PROCESS | 1654 | 18:13:54 INFO - PROCESS | 1654 | 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) 18:13:55 INFO - PROCESS | 1654 | RSS: Main: 286023680 18:13:55 INFO - PROCESS | 1654 | 18:13:58 INFO - PROCESS | 1654 | 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) 18:13:59 INFO - PROCESS | 1654 | RSS: Main: 377360384 18:13:59 INFO - PROCESS | 1654 | 18:14:01 INFO - PROCESS | 1654 | 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) 18:14:01 INFO - PROCESS | 1654 | RSS: Main: 282341376 18:14:01 INFO - PROCESS | 1654 | 18:14:03 INFO - PROCESS | 1654 | 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) 18:14:04 INFO - PROCESS | 1654 | RSS: Main: 286212096 18:14:04 INFO - PROCESS | 1654 | 18:14:07 INFO - PROCESS | 1654 | 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) 18:14:07 INFO - PROCESS | 1654 | RSS: Main: 328568832 18:14:07 INFO - PROCESS | 1654 | 18:14:10 INFO - PROCESS | 1654 | 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) 18:14:11 INFO - PROCESS | 1654 | RSS: Main: 329703424 18:14:11 INFO - PROCESS | 1654 | 18:14:14 INFO - PROCESS | 1654 | 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) 18:14:14 INFO - PROCESS | 1654 | RSS: Main: 327122944 18:14:14 INFO - PROCESS | 1654 | 18:14:16 INFO - PROCESS | 1654 | 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) 18:14:17 INFO - PROCESS | 1654 | RSS: Main: 304431104 18:14:17 INFO - PROCESS | 1654 | 18:14:18 INFO - PROCESS | 1654 | 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) 18:14:19 INFO - PROCESS | 1654 | RSS: Main: 305541120 18:14:19 INFO - PROCESS | 1654 | 18:14:21 INFO - PROCESS | 1654 | 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) 18:14:22 INFO - PROCESS | 1654 | RSS: Main: 314109952 18:14:22 INFO - PROCESS | 1654 | 18:14:25 INFO - PROCESS | 1654 | 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) 18:14:26 INFO - PROCESS | 1654 | RSS: Main: 325795840 18:14:26 INFO - PROCESS | 1654 | 18:14:29 INFO - PROCESS | 1654 | 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) 18:14:29 INFO - PROCESS | 1654 | RSS: Main: 320196608 18:14:29 INFO - PROCESS | 1654 | 18:14:31 INFO - PROCESS | 1654 | 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) 18:14:31 INFO - PROCESS | 1654 | RSS: Main: 364617728 18:14:31 INFO - PROCESS | 1654 | 18:14:31 INFO - PROCESS | 1654 | __start_tp_report 18:14:31 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 18:14:31 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 18:14:31 INFO - PROCESS | 1654 | |i|pagename|runs| 18:14:31 INFO - PROCESS | 1654 | |0;ai-astar;114;114;114;114;114;113;113;114;115;115 18:14:31 INFO - PROCESS | 1654 | |1;audio-beat-detection;146;107;123;126;141;142;137;135;129;110 18:14:31 INFO - PROCESS | 1654 | |2;audio-dft;184;181;184;186;184;184;181;184;182;182 18:14:31 INFO - PROCESS | 1654 | |3;audio-fft;79;58;72;110;87;82;59;58;75;59 18:14:31 INFO - PROCESS | 1654 | |4;audio-oscillator;87;70;86;70;69;69;83;69;69;84 18:14:31 INFO - PROCESS | 1654 | |5;imaging-gaussian-blur;89;86;91;87;89;86;89;87;89;86 18:14:31 INFO - PROCESS | 1654 | |6;imaging-darkroom;96;97;97;96;95;98;98;97;95;96 18:14:31 INFO - PROCESS | 1654 | |7;imaging-desaturate;89;71;78;129;138;120;88;85;88;86 18:14:31 INFO - PROCESS | 1654 | |8;json-parse-financial;53;56;53;54;55;55;54;55;54;56 18:14:31 INFO - PROCESS | 1654 | |9;json-stringify-tinderbox;63;72;82;75;71;69;70;72;70;71 18:14:31 INFO - PROCESS | 1654 | |10;stanford-crypto-aes;91;88;87;86;86;89;89;91;92;84 18:14:31 INFO - PROCESS | 1654 | |11;stanford-crypto-ccm;138;139;137;138;137;137;138;164;199;151 18:14:31 INFO - PROCESS | 1654 | |12;stanford-crypto-pbkdf2;171;231;204;172;171;171;172;196;228;193 18:14:31 INFO - PROCESS | 1654 | |13;stanford-crypto-sha256-iterative;67;66;66;67;67;67;67;67;65;67 18:14:31 INFO - PROCESS | 1654 | __end_tp_report 18:14:31 INFO - PROCESS | 1654 | __start_cc_report 18:14:31 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,1948 18:14:31 INFO - PROCESS | 1654 | __end_cc_report 18:14:31 INFO - PROCESS | 1654 | __startTimestamp1483150471745__endTimestamp 18:14:31 INFO - PROCESS | 1654 | 18:14:31 INFO - PROCESS | 1654 | ------- Summary: start ------- 18:14:31 INFO - PROCESS | 1654 | Number of tests: 14 18:14:31 INFO - PROCESS | 1654 | 18:14:31 INFO - PROCESS | 1654 | [#0] ai-astar Cycles:10 Average:114.00 Median:114.00 stddev:0.67 (0.6%) stddev-sans-first:0.71 18:14:31 INFO - PROCESS | 1654 | Values: 114.0 114.0 114.0 114.0 114.0 113.0 113.0 114.0 115.0 115.0 18:14:31 INFO - PROCESS | 1654 | 18:14:31 INFO - PROCESS | 1654 | [#1] audio-beat-detection Cycles:10 Average:129.60 Median:136.00 stddev:13.28 (9.8%) stddev-sans-first:12.70 18:14:31 INFO - PROCESS | 1654 | Values: 146.0 107.0 123.0 126.0 141.0 142.0 137.0 135.0 129.0 110.0 18:14:31 INFO - PROCESS | 1654 | 18:14:31 INFO - PROCESS | 1654 | [#2] audio-dft Cycles:10 Average:183.20 Median:184.00 stddev:1.62 (0.9%) stddev-sans-first:1.69 18:14:31 INFO - PROCESS | 1654 | Values: 184.0 181.0 184.0 186.0 184.0 184.0 181.0 184.0 182.0 182.0 18:14:31 INFO - PROCESS | 1654 | 18:14:31 INFO - PROCESS | 1654 | [#3] audio-fft Cycles:10 Average:73.90 Median:73.50 stddev:16.74 (22.8%) stddev-sans-first:17.65 18:14:31 INFO - PROCESS | 1654 | Values: 79.0 58.0 72.0 110.0 87.0 82.0 59.0 58.0 75.0 59.0 18:14:31 INFO - PROCESS | 1654 | 18:14:31 INFO - PROCESS | 1654 | [#4] audio-oscillator Cycles:10 Average:75.60 Median:76.50 stddev:8.17 (10.7%) stddev-sans-first:7.55 18:14:31 INFO - PROCESS | 1654 | Values: 87.0 70.0 86.0 70.0 69.0 69.0 83.0 69.0 69.0 84.0 18:14:31 INFO - PROCESS | 1654 | 18:14:31 INFO - PROCESS | 1654 | [#5] imaging-gaussian-blur Cycles:10 Average:87.90 Median:89.00 stddev:1.73 (1.9%) stddev-sans-first:1.79 18:14:31 INFO - PROCESS | 1654 | Values: 89.0 86.0 91.0 87.0 89.0 86.0 89.0 87.0 89.0 86.0 18:14:31 INFO - PROCESS | 1654 | 18:14:31 INFO - PROCESS | 1654 | [#6] imaging-darkroom Cycles:10 Average:96.50 Median:97.00 stddev:1.08 (1.1%) stddev-sans-first:1.13 18:14:31 INFO - PROCESS | 1654 | Values: 96.0 97.0 97.0 96.0 95.0 98.0 98.0 97.0 95.0 96.0 18:14:31 INFO - PROCESS | 1654 | 18:14:31 INFO - PROCESS | 1654 | [#7] imaging-desaturate Cycles:10 Average:97.20 Median:85.50 stddev:23.00 (26.9%) stddev-sans-first:24.20 18:14:31 INFO - PROCESS | 1654 | Values: 89.0 71.0 78.0 129.0 138.0 120.0 88.0 85.0 88.0 86.0 18:14:31 INFO - PROCESS | 1654 | 18:14:31 INFO - PROCESS | 1654 | [#8] json-parse-financial Cycles:10 Average:54.50 Median:55.00 stddev:1.08 (2.0%) stddev-sans-first:1.00 18:14:31 INFO - PROCESS | 1654 | Values: 53.0 56.0 53.0 54.0 55.0 55.0 54.0 55.0 54.0 56.0 18:14:31 INFO - PROCESS | 1654 | 18:14:31 INFO - PROCESS | 1654 | [#9] json-stringify-tinderbox Cycles:10 Average:71.50 Median:71.50 stddev:4.79 (6.7%) stddev-sans-first:3.97 18:14:31 INFO - PROCESS | 1654 | Values: 63.0 72.0 82.0 75.0 71.0 69.0 70.0 72.0 70.0 71.0 18:14:31 INFO - PROCESS | 1654 | 18:14:31 INFO - PROCESS | 1654 | [#10] stanford-crypto-aes Cycles:10 Average:88.30 Median:89.00 stddev:2.58 (2.9%) stddev-sans-first:2.55 18:14:31 INFO - PROCESS | 1654 | Values: 91.0 88.0 87.0 86.0 86.0 89.0 89.0 91.0 92.0 84.0 18:14:31 INFO - PROCESS | 1654 | 18:14:31 INFO - PROCESS | 1654 | [#11] stanford-crypto-ccm Cycles:10 Average:147.80 Median:138.50 stddev:20.03 (14.5%) stddev-sans-first:20.93 18:14:31 INFO - PROCESS | 1654 | Values: 138.0 139.0 137.0 138.0 137.0 137.0 138.0 164.0 199.0 151.0 18:14:31 INFO - PROCESS | 1654 | 18:14:31 INFO - PROCESS | 1654 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:190.90 Median:194.50 stddev:23.78 (12.2%) stddev-sans-first:24.11 18:14:31 INFO - PROCESS | 1654 | Values: 171.0 231.0 204.0 172.0 171.0 171.0 172.0 196.0 228.0 193.0 18:14:31 INFO - PROCESS | 1654 | 18:14:31 INFO - PROCESS | 1654 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.60 Median:67.00 stddev:0.70 (1.0%) stddev-sans-first:0.73 18:14:31 INFO - PROCESS | 1654 | Values: 67.0 66.0 66.0 67.0 67.0 67.0 67.0 67.0 65.0 67.0 18:14:31 INFO - PROCESS | 1654 | -------- Summary: end -------- 18:14:31 INFO - PROCESS | 1654 | 18:14:32 INFO - TEST-INFO | 1654: exit 0 18:14:32 INFO - TEST-OK | kraken | took 59449ms 18:14:32 INFO - SUITE-END | took 1179s 18:14:32 INFO - Completed test suite (00:19:39) 18:14:32 INFO - javascript benchmark 18:14:32 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2911.563265510173, 3375.0381141374696, 3399.4900764885247, 3403.5745531808525, 3419.9829000855007, 4727.290906364038, 5608.5793565482545, 5646.237757902682, 5688.800891968782, 5728.7135643217825, 5655.123455864337, 5730.226419433386, 5758.136279558068, 5758.596898217126, 5827.737751801179, 5133.82031628893, 5161.870953226162, 5162.070827251093, 5179.922301165486, 5208.869778255536, 5296.92054619181, 5317.202419637047, 5328.040952628526, 5331.760070796804, 5347.197920311945, 5262.079136151158, 5279.392869819943, 5298.0, 5325.840224793263, 5341.786328546873, 4528.8867778305475, 4797.7525843281, 5167.173252279655, 5192.350956130484, 5199.714015729133, 5216.556592689626, 5230.686158830445, 5230.869228269324, 5239.633225674166, 5259.86850328741, 5692.0608099663505, 5692.971535142339, 5705.515031222351, 5707.229524014247, 5731.226284451587, 6078.665673387962, 6106.915739268716, 6141.170941922828, 6188.659623720693, 6229.87540249197, 4639.930401043987, 6153.446189842891, 6222.222222222223, 6231.283402408691, 6238.968805155991, 5746.396628354005, 5746.396628354046, 5747.712614369265, 5753.654780713171, 5760.9711951440395, 6185.907211391833, 6210.4100110489435, 6233.563650544418, 6265.780697675651, 6275.246970363585, 6128.7282265584945, 6176.413240742122, 6206.286277078194, 6208.099825525273, 6217.160683307786, 6268.811935641938, 6273.654948977759, 6277.1525844010475, 6292.905606415908, 6295.118683384329, 6070.332263451017, 6097.115918191837, 6105.023196288573, 6117.218487018656, 6144.126041557279, 6173.9073913891325, 6176.752929882844, 6180.6291622502795, 6187.628742275478, 6193.597416167935, 4856.975715121402, 4884.072026314989, 4892.046051020088, 4930.260460930817, 4941.975290123526, 4257.467816522934, 4367.213901497761, 4373.868783936487, 4383.101464199832, 4399.84600538986, 5738.0, 5746.396628353963, 5753.079507278899, 5759.740811663485, 5762.88474230513, 6018.518518518508, 6039.909401358983, 6097.5731698780655, 6136.427173609184, 6162.322144564095, 5750.166225897305, 5761.135829625589, 5810.912836307459, 5838.182654428383, 5869.4717475427415, 5384.6769193848495, 5393.730313484389, 5395.81114660985, 5408.13469844823, 5420.268263784417, 6310.463610593151, 6317.1156038154695, 6317.178766760292, 6331.176946996861, 6336.461400780985, 6102.186302349175, 6112.2970858351855, 6126.540509461897, 6199.597026193283, 6262.123302737711, 6207.751689932352, 6304.495640348853, 6337.0, 6343.746250149942, 6356.555041147075, 6140.355262697352, 6177.96911015442, 6184.443400094013, 6203.47270481996, 6214.378562143749, 5321.600879934098, 5813.593048486566, 6278.340774218824, 6294.748210071546, 6294.992801151794, 6081.543884208613, 6087.147799308012, 6125.663088530174, 6163.722632481459, 6177.505799535937, 6143.139960405458, 6152.384761523812, 6153.323134455296, 6155.199824022849, 6158.784442544489, 5746.2529871116485, 5936.5844390892225, 6013.579049466495, 6026.337102918763, 6038.275406951194, 5958.0, 6000.009998350398, 6011.0, 6011.188489554029, 6021.759129634941, 5842.1820945068575, 5848.53211743068, 5853.1220316952795, 5858.326292476419, 5860.619059761102], "value": 5666.953391532106, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [452.2402364028431, 457.16795432313194, 478.3112318261703, 483.56479168748143, 507.85526125054355, 29033.56449653254, 30836.229094272654, 37420.0, 39290.21419571607, 39355.212895742065, 39695.0, 40072.19855602886, 40177.598224017755, 40219.99450013751, 40302.0, 9154.267658587314, 9369.625214991393, 9385.483798391086, 9401.341906066578, 9411.858822117674, 8360.28614134101, 8600.35497337699, 10171.881093079755, 10724.731881702943, 10993.780124397506, 11153.776924461506, 11204.15968802343, 11218.607348742795, 11225.60710375137, 11246.044086252678, 11172.385518796464, 11243.775124497464, 11269.718257043558, 11271.041961433288, 11273.492692828842, 8278.420510564263, 8499.192576705203, 8499.277561407287, 8609.26821220197, 11036.006759391694, 30580.84709576438, 36346.27307453836, 36772.26455470902, 36899.815500922596, 38709.419358709645, 3943.960560394388, 3958.3072962231495, 3974.8012599369918, 3977.8607748728946, 4086.8160932758096, 28625.85687071572, 29471.70528294711, 29500.902405247354, 29590.0, 29808.8509557453, 3130.248740302311, 3262.771605987582, 3266.6243382011135, 3321.2693207494312, 3323.302106557626, 455096.0, 460160.0, 460820.0, 461426.0, 461641.0, 55458.0, 55937.0, 56087.71956140275, 56131.71934140303, 56160.43839561634, 48183.80875251216, 56633.0, 57471.0, 57858.132128018115, 57900.0, 14497.492587759378, 15110.773338399933, 15128.319225634872, 15140.697186056217, 15160.0, 11781.469833857496, 11841.407929603485, 11851.46668399924, 11857.703557410961, 11866.0, 13400.463981440827, 13561.050726449252, 13592.184468931895, 14792.11553747009, 14964.40142394292, 6513.153290072355, 6585.6707164641575, 6613.272540020594, 6634.203895532471, 6635.668216589152, 827.2554700769235, 847.4068152293419, 855.5294587976588, 860.6665439512027, 867.6919693508713, 28448.25435475736, 28635.57046644302, 29260.414791704472, 29439.85280073586, 29483.557746633818, 1013.5489707080278, 1038.0709265207597, 1041.6510468992822, 1043.2697112021463, 1104.812181929058, 14135.575932722035, 14552.417903283962, 14646.0, 14950.925245373703, 14990.775138372934, 564140.0, 565452.1727391337, 567213.0, 568708.0, 573623.0, 55800.441995579524, 55960.16059759107, 56022.43697100921, 56231.43768562344, 56382.43617563772, 1330.9667258318425, 1353.627752368087, 1357.0636260979893, 1358.701085761131, 1359.7552440560794, 27627.0, 28252.717472825832, 28844.701988410154, 29307.560386594643, 29450.410991779616, 47025.58923232309, 60424.697876510334, 60870.69564652148, 60952.695236523534, 65017.67491162514, 431588.0, 438634.0, 439252.0, 557385.0, 557520.0, 42868.845089557806, 42916.570834291255, 43775.78112109419, 43792.34311485394, 43966.90082747957, 47121.293180603, 47434.0, 47525.52474475211, 47530.0, 47892.76053619709, 38853.0, 38908.80545597254, 39250.411243831935, 39946.0, 40284.0, 38851.02872428212, 38975.22049558936, 39188.0, 40458.190836182526, 40541.39187912243, 38318.0, 38904.0, 39200.511928329484, 39223.41164882472, 39287.0, 33544.32911341711, 34072.48891266583, 35099.298014039065, 35256.29487410186, 36004.0, 21897.0, 23975.702596585343, 25246.0, 25451.74548254494, 26015.73984260133], "value": 19587.251287600935, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [351.9912002199943, 355.59462213077086, 367.9411294192928, 375.12408526091565, 396.1265409771454, 14295.785563216547, 14641.633959151026, 15236.771448428268, 19221.711674324895, 19693.31073412431, 18602.53493662656, 19604.90197549014, 19641.214351425926, 19814.405567832953, 19862.50343741407, 5654.971725141369, 5852.502537284336, 5862.88274234515, 5977.342492325841, 5986.042233242682, 5166.974165129169, 5769.538436925036, 5867.207926929853, 5901.557383196277, 5904.7047647617665, 5753.108268218432, 5853.5317174625925, 5853.678047707374, 5857.736401861926, 5861.531077513789, 6062.424069713392, 6380.202474690687, 6440.871182576322, 6470.061841033071, 6525.641089740062, 5453.3728621208675, 5604.775808967636, 5656.490915817596, 5733.0, 5743.684097374685, 12258.283840262371, 12297.139200255897, 12441.253524788543, 12467.002639788796, 12500.24998500093, 2475.405902583367, 2534.6071358939394, 2552.7494001533737, 2594.040205124097, 2635.0, 9480.95259523705, 9496.857547136799, 9514.571844266955, 9919.752006199831, 10030.498475076218, 1626.4957863062489, 1724.6378260565295, 1735.8264173582668, 1774.8136445673147, 1799.136414521024, 113028.0, 119460.0, 119474.99999999913, 120961.0, 121648.0, 10675.306105103144, 10929.398883061507, 10984.286021408583, 11008.174386921013, 11179.0, 13953.441862325597, 14031.719365612835, 14057.578272651837, 14071.155730656194, 14100.788488172686, 7609.124950630749, 7843.803904902309, 7853.607319633996, 7965.8805117923275, 8023.679052837938, 6336.746530138743, 7575.6969721210535, 7718.884216736754, 7732.162217525732, 7743.961280193563, 4254.914901701949, 4320.395144679747, 4338.82644694215, 4413.161499315184, 4421.823127074946, 1630.7390817469147, 1813.9274429022691, 1866.861214659057, 1877.8967156806507, 1878.164216923456, 875.2516598308482, 885.9778505537413, 891.9286457083417, 904.1907492793913, 966.3525437956586, 17702.91148544249, 18710.15804288783, 19151.04244787755, 19266.518337041685, 19573.412797616093, 927.6289484206236, 934.7709811096325, 947.6304241345881, 949.7435692363023, 965.888922773876, 8534.615942282611, 9175.493416126725, 9507.57215925285, 9924.553395097239, 10098.343607665478, 544947.0, 550015.0, 553357.0, 560739.0, 563833.3616663781, 86521.26957460941, 105531.47234263779, 113667.0, 121709.0, 125529.0, 747.5813544415145, 929.8186853563625, 962.1100482054072, 963.3401120232663, 968.7093871838421, 17935.0, 17992.730109048636, 18043.90978045154, 18098.638027239118, 18189.909050454662, 51758.0, 52069.73965130302, 52119.47880521146, 52294.0, 52855.0, 74004.14989625303, 74779.0, 75014.8747768773, 75161.87257191037, 76540.23459765331, 7005.649717514002, 7470.514416562906, 7818.0618325799, 7843.764687059397, 7989.360851132012, 10139.0, 12537.310447925453, 12836.679083022998, 12861.035422343175, 12944.029197810014, 10689.412082335546, 10981.17641176931, 11114.166437517057, 11314.886851131712, 11388.658340249805, 8255.628496717782, 9855.162311203341, 9968.950155249468, 10009.799804004026, 11013.944930275296, 9184.0, 9363.485008324607, 10905.890941090805, 11194.216404851582, 11267.492962816528, 6759.729610815512, 7074.504784665024, 7298.087739032621, 7402.222766609644, 7482.2517748224745, 10377.325473844176, 10453.26827122094, 10477.9476102619, 11147.052500537546, 11300.49147788335], "value": 8955.66137987812, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1384.0795870745953, 1394.7350003499334, 1410.4358256697312, 1418.1065928465066, 1421.459845258802, 7907.051153861537, 8345.37409694273, 8354.373421993345, 8371.204735550113, 8384.077751447336, 7321.121465424143, 7604.239576042393, 7619.42854285928, 7922.049354077519, 8030.558319292451, 2266.9433264168365, 2304.0207911637485, 2307.9192228272013, 2308.595995700754, 2310.376198426424, 2249.853759505627, 2273.8067264282554, 2284.497410569672, 2288.473651060257, 2308.8960996755186, 1996.9700454493195, 2067.9276225332114, 2232.6427771556555, 2280.737715162761, 2307.896144673485, 2283.3264187064856, 2290.839641225115, 2294.1167650454527, 2296.127471560796, 2302.159711705224, 2277.225743247304, 2296.0471404367167, 2304.8617082974906, 2326.941826454335, 2336.1823361823394, 8284.668613255462, 8379.287760540361, 8380.748577542683, 8382.832343353099, 8387.0, 4916.139675556763, 5038.470960549126, 5088.6946783193125, 5093.770780314858, 5097.617678674114, 3204.871805127792, 3336.4661654135466, 3345.330933813247, 3406.5060566217758, 3412.8805491807666, 1124.2916962313773, 1166.393475392791, 1206.8189771534253, 1207.028342184541, 1216.720154364501, 10310.32982856112, 10610.946945265225, 10631.94684026575, 10652.0, 10761.892381076246, 3614.475900994341, 3633.94549081764, 4198.181354635878, 4382.408374869416, 4395.846145384896, 8456.534890580953, 8495.938007749031, 8580.35647326453, 8635.568221588896, 8648.394612377073, 4931.852044438672, 4951.455339912607, 4992.951480189201, 4994.550490455804, 5015.799368025238, 4125.162894504303, 4190.622843944059, 4347.456567929009, 4534.410526631516, 4619.214733495247, 3210.9839450802597, 3515.1739341254784, 3541.9822900885847, 3676.7058635309113, 3698.0754811297174, 1095.5782023920847, 1121.5681962444517, 1137.4028634966708, 1141.1042331769522, 1142.4801715219555, 801.5030680977715, 857.2242120880613, 862.491130233153, 886.6985225023523, 914.2868562521244, 4223.598758117974, 4449.933251001237, 5332.973335133377, 5382.650127741763, 5449.427810079964, 419.1470357821818, 525.7528961388141, 536.8711509237833, 539.4282061015336, 555.2060553408664, 3535.734819888519, 3560.8219589020446, 3589.7871405092296, 3595.0113718727557, 3611.9277614447565, 9432.056794320513, 9812.803743925082, 9880.901190988141, 9884.901150988542, 9909.157721593601, 9444.386114902509, 9830.262730295257, 9840.606375745034, 9851.064148905842, 9880.851787223197, 7479.214682458263, 7667.348275396542, 7711.0, 7719.34385577221, 7719.65261563231, 4772.976135119302, 5292.232626269092, 5358.598105142052, 5491.368492623399, 5524.558035357081, 8812.51531165792, 8888.33337499703, 9064.048274931038, 9110.498922559324, 9274.747909032194, 9322.813543728951, 9382.671606494014, 9411.152996230372, 9447.763805904633, 9500.14498695093, 6368.681565921778, 6533.607983521004, 7011.929880701332, 7155.3202445767565, 7157.997880296659, 7358.1538123116525, 7569.810754731176, 7870.881936770838, 7902.130765615662, 7956.6419511120985, 6893.862122757417, 6979.127609048869, 7022.6488675565, 7024.192217894803, 7091.964540177266, 6850.691718872563, 6923.065386172802, 6927.6882540286815, 6954.095967524189, 6975.093237879043, 6084.878302433838, 6259.530535209969, 6323.873522529616, 6341.0805433212845, 6421.389967953037, 4594.494605593449, 4687.343771871872, 4688.789004494738, 4713.2458806590785, 4841.419029716457, 4683.1336202802595, 4783.689060211075, 4792.5207479253195, 4852.927206091841, 4918.877028074184], "value": 4200.007928199675, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [349.3135987784004, 359.52901698774605, 363.59459202988677, 369.85205917632925, 371.3167771300806, 4652.627789776814, 4665.883352916178, 5191.247269145972, 5255.7911680313555, 5258.0, 4181.560936101711, 4587.518310577391, 4884.656719402166, 5152.175651895697, 5179.2749015137915, 3308.553345298378, 3758.4362345648096, 3837.697381571049, 4039.9596004040022, 4166.666666666668, 3618.601953785095, 3889.183271512986, 3946.585608511108, 4102.220578090153, 4108.36320370343, 3295.7528185386072, 4092.3656833190903, 4123.8350465981475, 4134.52452967908, 4151.709380343377, 3560.9495198916384, 3829.9808500957597, 3862.2854771867014, 3893.824777885002, 4108.732932359387, 3638.5815631202486, 3640.4903313536124, 3646.32542979547, 3655.3054919565197, 3658.1769101952113, 4506.963398418349, 4666.346711460398, 4679.695819771704, 4698.95301046992, 5032.798688052473, 4017.357222844331, 4245.596668316505, 4305.677074219446, 4382.7242919244545, 4696.976515117437, 4710.811567537295, 5123.538881500683, 5189.936063107054, 5349.8230389314285, 5560.913692900439, 3370.612379576331, 3695.648913353254, 3883.980580097081, 3989.40158976153, 4036.7174297799165, 4767.737774422405, 5203.320290050627, 5462.2079798429, 5822.505087067604, 5847.56143289255, 3914.393269043303, 4107.67138628909, 4145.813438395279, 4159.584041595876, 4171.561985991488, 4229.492460904649, 4233.449651545279, 4249.617534421917, 4256.978715106405, 4271.508776490743, 4128.91742165155, 4647.512011238771, 4831.4202295724735, 4853.3447984522345, 4853.805847766121, 5335.919961200585, 5566.777328906879, 5592.720363981866, 5605.0, 5637.477880972115, 1239.7210627608897, 1337.0, 1358.3479929633831, 1362.3733082781832, 1363.4069179906771, 905.343625871238, 931.5202670624634, 951.9433428893922, 962.4033099478369, 991.7718924647371, 4430.667699922518, 4893.755312234374, 5282.867928301823, 5320.813771517978, 5337.466253374709, 5123.538881500683, 5194.714290714047, 5325.360956685223, 5484.424135465799, 5776.566757493205, 4519.344695019204, 4591.874990627251, 5087.440381558025, 5214.530692237717, 5319.1223448131395, 2848.60418394985, 2866.2834291427143, 2880.2799300174956, 2889.8266104033823, 2902.274431392152, 5128.692278463304, 5597.44025597437, 5726.541876649858, 5729.341125770507, 5788.0739081746715, 5101.648063263238, 5298.2647233700145, 5859.94140058594, 5879.823605291848, 5931.73307201177, 5475.616706830484, 5559.360673522597, 5684.8294551163535, 5764.36591974874, 5949.6430214187285, 4608.700434471749, 4971.216118973386, 5354.866128346823, 5699.829005129853, 5766.509846663081, 5655.547556195412, 5668.234788303526, 5673.404292549223, 5711.457411545896, 5718.285214348207, 5458.317710286215, 5956.672383018976, 5963.896679114378, 5981.880362392815, 6047.248501931698, 3271.787333823294, 3693.575238847567, 3797.5442946846556, 3825.8278377472525, 3865.7436333192386, 4931.013797240638, 5644.915326270027, 5686.744096515584, 5707.372189059283, 5707.4577915098, 5518.641288316408, 5526.50261476469, 5547.33431988164, 5567.5545956322585, 5570.19232211319, 5084.0, 5156.4070131935305, 5205.687658740336, 5519.448055194448, 5527.806526771544, 4415.492218394926, 5438.864028399322, 5444.8094316698725, 5465.918011229755, 5512.6692398456225, 4760.333883141011, 4834.9604834960655, 5130.204818253177, 5392.865178370572, 5419.241306217212, 4717.599004084691, 4910.79014443509, 5071.974640126776, 5406.162044883049, 5442.455754424526], "value": 4073.6318100292083, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [712.9037579926709, 717.4905816870022, 755.9508631938924, 796.0983299669936, 796.5380079553858, 14195.0, 15201.239938003084, 15210.315535800888, 15221.391144354213, 15231.31459084341, 13766.0, 13930.721385572282, 13935.44258229672, 13940.372683229254, 13967.441302347894, 675.9188897332327, 691.5504921800845, 697.0624510033996, 832.6169961817571, 839.3159574946415, 853.7908212487949, 864.4597126795753, 865.0311650950944, 866.2463656618751, 866.4065115395942, 907.4827348411408, 921.2814005076051, 921.6681994481982, 924.0159230419615, 924.9213816825578, 881.1716986033108, 916.724982505249, 920.3005715656083, 920.3787443475627, 924.8705181274594, 531.2350215689395, 542.0351773842566, 656.0323522803874, 682.7200847652489, 683.6035099642196, 13550.186988780606, 13989.650258743512, 14016.299185040707, 14029.228392438412, 14065.648358791112, 13040.869591304061, 13178.538751143664, 13181.340932953313, 13188.538401156009, 13398.933005334911, 12315.50737970489, 12404.503819847196, 12511.687207819787, 12532.310722910237, 12777.752895099557, 4196.433481479991, 4214.36784482327, 4461.21928662486, 4502.819887204508, 4503.932441013388, 103451.48274258655, 103736.96263037348, 105157.47421262921, 105191.94808051898, 106081.46959265154, 9811.656592019244, 10120.696379108638, 10348.275620706478, 10552.261341706007, 10586.20603454744, 17576.648467030587, 20311.39065827998, 20529.2814751483, 20589.897050514952, 20612.69080963787, 7284.271572842779, 7363.300486453778, 7443.144038435649, 7586.734464293723, 7605.0, 4005.3991901214467, 4124.484439445069, 4131.380292956021, 4133.111381053088, 4144.212599606065, 3286.5539162768305, 3331.8833840815455, 3336.8665253390077, 3370.76404651672, 3380.39152952466, 608.3217212807688, 612.8621060261405, 620.1225266248208, 623.996880015606, 630.2059405149545, 578.3089208395927, 600.7236671131241, 610.9389061093943, 623.6507555768784, 626.101544283954, 8471.322294216448, 8597.52713600758, 8629.741107766651, 8742.30061595071, 8912.55437228136, 575.9596828222068, 582.72320647692, 612.8906678911185, 628.3308276685316, 638.4668801550757, 4162.937555936664, 4180.561041090642, 4186.0, 4192.7065105442325, 4332.068605249887, 95351.0, 100944.0, 101995.49002255089, 103484.48257758813, 104699.0, 86483.56758216169, 88100.0, 88732.5563372179, 89126.10873891308, 89825.55087224522, 75724.9999999989, 77596.61201694068, 77680.61159694391, 78112.43775124465, 79499.0, 7862.213778622092, 8139.3488520917, 8325.583720813815, 8330.250277474814, 8498.70254541088, 39710.60451943601, 48337.75831120822, 49042.0, 50230.748846255534, 51235.0, 65848.0122798168, 66522.0, 66633.66732665223, 67066.0, 67844.6431071366, 10583.100436462986, 10660.680179594625, 10663.4135122569, 11061.225714199929, 11178.88821111811, 11396.316221026766, 11405.258658187191, 11634.88365116338, 11650.6504804856, 11992.76014479723, 11638.88361116378, 11974.0, 12646.620601381974, 13100.410481528164, 13383.598492045254, 13100.672483187996, 13137.146085504412, 13367.732645347232, 13474.730505389642, 13815.0, 13173.802392964304, 13179.60461186166, 13421.798673019717, 13833.290853935632, 14027.50903718365, 10688.946555267174, 11045.116390688887, 11196.104311655212, 11496.94251528737, 11880.702982425508, 8996.64013439455, 9304.534773261445, 10655.631964911125, 11183.552657893919, 11362.431878405881], "value": 7159.557475354713, "unit": "score"}], "name": "dromaeo_css", "value": 7040.407307651979, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 114.0, 114.0, 114.0, 113.0, 113.0, 114.0, 115.0, 115.0], "value": 114.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 107.0, 123.0, 126.0, 141.0, 142.0, 137.0, 135.0, 129.0, 110.0], "value": 129.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 181.0, 184.0, 186.0, 184.0, 184.0, 181.0, 184.0, 182.0, 182.0], "value": 183.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 58.0, 72.0, 110.0, 87.0, 82.0, 59.0, 58.0, 75.0, 59.0], "value": 73.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 70.0, 86.0, 70.0, 69.0, 69.0, 83.0, 69.0, 69.0, 84.0], "value": 75.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 91.0, 87.0, 89.0, 86.0, 89.0, 87.0, 89.0, 86.0], "value": 87.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 97.0, 96.0, 95.0, 98.0, 98.0, 97.0, 95.0, 96.0], "value": 96.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 71.0, 78.0, 129.0, 138.0, 120.0, 88.0, 85.0, 88.0, 86.0], "value": 97.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 56.0, 53.0, 54.0, 55.0, 55.0, 54.0, 55.0, 54.0, 56.0], "value": 54.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 72.0, 82.0, 75.0, 71.0, 69.0, 70.0, 72.0, 70.0, 71.0], "value": 71.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 88.0, 87.0, 86.0, 86.0, 89.0, 89.0, 91.0, 92.0, 84.0], "value": 88.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 139.0, 137.0, 138.0, 137.0, 137.0, 138.0, 164.0, 199.0, 151.0], "value": 147.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 231.0, 204.0, 172.0, 171.0, 171.0, 172.0, 196.0, 228.0, 193.0], "value": 190.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 66.0, 67.0, 67.0, 67.0, 67.0, 67.0, 65.0, 67.0], "value": 66.6, "unit": "score"}], "name": "kraken", "value": 1477.5, "alertThreshold": 2.0}]} 18:14:32 INFO - Return code: 0 18:14:33 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:14:33 INFO - # TBPL SUCCESS # 18:14:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:14:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:14:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:14:33 INFO - 2016-12-30 18:14:33.054 screenresolution[1658:10191] starting screenresolution argv=screenresolution get 18:14:33 INFO - 2016-12-30 18:14:33.057 screenresolution[1658:10191] Display 0: 1600x1200x32@60 18:14:33 INFO - 2016-12-30 18:14:33.063 screenresolution[1659:10194] starting screenresolution argv=screenresolution list 18:14:33 INFO - Available Modes on Display 0 18:14:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:14:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:14:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:14:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:14:33 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:14:33 INFO - Intel Iris: 18:14:33 INFO - Chipset Model: Intel Iris 18:14:33 INFO - Type: GPU 18:14:33 INFO - Bus: Built-In 18:14:33 INFO - VRAM (Dynamic, Max): 1536 MB 18:14:33 INFO - Vendor: Intel (0x8086) 18:14:33 INFO - Device ID: 0x0a2e 18:14:33 INFO - Revision ID: 0x0009 18:14:33 INFO - Displays: 18:14:33 INFO - Display: 18:14:33 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:14:33 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:14:33 INFO - Display Serial Number: 1600x1200 60 18:14:33 INFO - Main Display: Yes 18:14:33 INFO - Mirror: Off 18:14:33 INFO - Online: Yes 18:14:33 INFO - Rotation: Supported 18:14:33 INFO - Return code: 0 18:14:33 INFO - Running post-action listener: _resource_record_post_action 18:14:33 INFO - [mozharness: 2016-12-31 02:14:33.301656Z] Finished run-tests step (success) 18:14:33 INFO - Running post-run listener: _resource_record_post_run 18:14:33 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 32316416; Write bytes: 551670784; Read time: 196; Write time: 1661 18:14:33 INFO - TinderboxPrint: CPU usage
53.8% 18:14:33 INFO - TinderboxPrint: I/O read bytes / time
32,316,416 / 196 18:14:33 INFO - TinderboxPrint: I/O write bytes / time
551,670,784 / 1,661 18:14:33 INFO - TinderboxPrint: CPU idle
2,224.1 (46.2%) 18:14:33 INFO - TinderboxPrint: CPU system
960.9 (20.0%) 18:14:33 INFO - TinderboxPrint: CPU user
1,624.7 (33.8%) 18:14:33 INFO - TinderboxPrint: Swap in / out
277,372,928 / 0 18:14:33 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 245735936; Write bytes: 238026752; Read time: 17977; Write time: 500 18:14:33 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 30198784; Write bytes: 300102656; Read time: 176; Write time: 1119 18:14:33 INFO - Running post-run listener: _upload_blobber_files 18:14:33 INFO - Blob upload gear active. 18:14:33 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:14:33 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 18:14:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:14:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:14:34 INFO - (blobuploader) - INFO - Open directory for files ... 18:14:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:14:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:14:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:14:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:14:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:14:34 INFO - (blobuploader) - INFO - Done attempting. 18:14:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:14:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:14:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:14:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:14:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:14:35 INFO - (blobuploader) - INFO - Done attempting. 18:14:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:14:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:14:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:14:36 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:14:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:14:36 INFO - (blobuploader) - INFO - Done attempting. 18:14:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:14:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:14:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:14:39 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:14:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:14:39 INFO - (blobuploader) - INFO - Done attempting. 18:14:39 INFO - (blobuploader) - INFO - Iteration through files over. 18:14:39 INFO - Return code: 0 18:14:39 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:14:39 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:14:39 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e25d27e6496b767ed943490a22acef060599939691f06f7597cb8ad2556c043c874bcb88097e0489707f2eca06b5f9a8012c5ce1d2a272d542d3fbc4c2c86d95", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0c535d6939fe58ec04ba13ea1bed953f806b19eb963d25f1434dfc5a82a87ea54e03ed4709537bfb839bbd6dd7ebabf4f6f875bc5988e187c07f4725efc1212a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/45169d69ea0b92247c0b83579add39ee9526daac3d79b72e8a405e2aa73be551183a743dd9ca4ded010e23a84bb33a5e9903e9441e694df9a95e9bae615e42a5", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4d4e553dd90897863e8a5798d19cf59cdfe376cd14d35cd95523c356149d6bd425019c487a6a5f9a7d14a7ef0c44d63b6e1441126cd1f9237aa9922c4ec78385"} 18:14:39 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:14:39 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:14:39 INFO - Contents: 18:14:39 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e25d27e6496b767ed943490a22acef060599939691f06f7597cb8ad2556c043c874bcb88097e0489707f2eca06b5f9a8012c5ce1d2a272d542d3fbc4c2c86d95", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0c535d6939fe58ec04ba13ea1bed953f806b19eb963d25f1434dfc5a82a87ea54e03ed4709537bfb839bbd6dd7ebabf4f6f875bc5988e187c07f4725efc1212a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/45169d69ea0b92247c0b83579add39ee9526daac3d79b72e8a405e2aa73be551183a743dd9ca4ded010e23a84bb33a5e9903e9441e694df9a95e9bae615e42a5", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4d4e553dd90897863e8a5798d19cf59cdfe376cd14d35cd95523c356149d6bd425019c487a6a5f9a7d14a7ef0c44d63b6e1441126cd1f9237aa9922c4ec78385"} 18:14:39 INFO - Running post-run listener: copy_logs_to_upload_dir 18:14:39 INFO - Copying logs to upload dir... 18:14:39 INFO - mkdir: /builds/slave/test/build/upload/logs 18:14:39 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1262.645978 ========= master_lag: 4.15 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-12-30 18:14:43.352455) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-30 18:14:43.358642) ========= 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.OoyqAjZuLl/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.V55PI9dvsI/Listeners TMPDIR=/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/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/e25d27e6496b767ed943490a22acef060599939691f06f7597cb8ad2556c043c874bcb88097e0489707f2eca06b5f9a8012c5ce1d2a272d542d3fbc4c2c86d95", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0c535d6939fe58ec04ba13ea1bed953f806b19eb963d25f1434dfc5a82a87ea54e03ed4709537bfb839bbd6dd7ebabf4f6f875bc5988e187c07f4725efc1212a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/45169d69ea0b92247c0b83579add39ee9526daac3d79b72e8a405e2aa73be551183a743dd9ca4ded010e23a84bb33a5e9903e9441e694df9a95e9bae615e42a5", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4d4e553dd90897863e8a5798d19cf59cdfe376cd14d35cd95523c356149d6bd425019c487a6a5f9a7d14a7ef0c44d63b6e1441126cd1f9237aa9922c4ec78385"} build_url:https://queue.taskcluster.net/v1/task/PbyaYpSDQsO5o7Mz2D9dtw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011304 build_url: 'https://queue.taskcluster.net/v1/task/PbyaYpSDQsO5o7Mz2D9dtw/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/e25d27e6496b767ed943490a22acef060599939691f06f7597cb8ad2556c043c874bcb88097e0489707f2eca06b5f9a8012c5ce1d2a272d542d3fbc4c2c86d95", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0c535d6939fe58ec04ba13ea1bed953f806b19eb963d25f1434dfc5a82a87ea54e03ed4709537bfb839bbd6dd7ebabf4f6f875bc5988e187c07f4725efc1212a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/45169d69ea0b92247c0b83579add39ee9526daac3d79b72e8a405e2aa73be551183a743dd9ca4ded010e23a84bb33a5e9903e9441e694df9a95e9bae615e42a5", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4d4e553dd90897863e8a5798d19cf59cdfe376cd14d35cd95523c356149d6bd425019c487a6a5f9a7d14a7ef0c44d63b6e1441126cd1f9237aa9922c4ec78385"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-30 18:14:43.398662) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-30 18:14:43.398982) ========= 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.OoyqAjZuLl/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.V55PI9dvsI/Listeners TMPDIR=/var/folders/sw/s26whz2n6pbfv14p7p8hkdxw00000w/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.004970 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-30 18:14:43.449067) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-30 18:14:43.452388) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-30 18:14:43.452700) ========= ========= Total master_lag: 4.33 =========