builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0110 starttime: 1485795437.47 results: success (0) buildid: 20170130072443 builduid: a1481ca579754631aa4e0879dde4d610 revision: 13afabf5d79de6cfeb511b675cb4de22a62016f9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-30 08:57:17.465497) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-30 08:57:17.466006) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-30 08:57:17.466278) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-30 08:57:17.494097) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-30 08:57:17.494507) ========= 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.eiLQUNmMdi/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.t0Sxc0GO4y/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-01-30 08:57:17-- 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% 16.2M=0.001s 2017-01-30 08:57:17 (16.2 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.092405 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-30 08:57:17.611286) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-30 08:57:17.611604) ========= 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.eiLQUNmMdi/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.t0Sxc0GO4y/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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.027745 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-30 08:57:17.661092) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-30 08:57:17.661442) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 13afabf5d79de6cfeb511b675cb4de22a62016f9 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 13afabf5d79de6cfeb511b675cb4de22a62016f9 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.eiLQUNmMdi/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.t0Sxc0GO4y/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-01-30 08:57:17,765 truncating revision to first 12 chars 2017-01-30 08:57:17,765 Setting DEBUG logging. 2017-01-30 08:57:17,765 attempt 1/10 2017-01-30 08:57:17,765 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/13afabf5d79d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-30 08:57:18,394 unpacking tar archive at: mozilla-aurora-13afabf5d79d/testing/mozharness/ program finished with exit code 0 elapsedTime=0.897383 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-30 08:57:18.582680) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-30 08:57:18.583021) ========= script_repo_revision: 13afabf5d79de6cfeb511b675cb4de22a62016f9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-30 08:57:18.583423) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-30 08:57:18.583686) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-30 08:57:18.603744) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2017-01-30 08:57:18.604289) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.eiLQUNmMdi/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.t0Sxc0GO4y/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 08:57:18 INFO - MultiFileLogger online at 20170130 08:57:18 in /builds/slave/test 08:57:18 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 08:57:18 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:57:18 INFO - {'append_to_log': False, 08:57:18 INFO - 'base_work_dir': '/builds/slave/test', 08:57:18 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 08:57:18 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:57:18 INFO - 'branch': 'Mozilla-Aurora', 08:57:18 INFO - 'buildbot_json_path': 'buildprops.json', 08:57:18 INFO - 'config_files': ('talos/mac_config.py',), 08:57:18 INFO - 'default_actions': ('clobber', 08:57:18 INFO - 'read-buildbot-config', 08:57:18 INFO - 'download-and-extract', 08:57:18 INFO - 'populate-webroot', 08:57:18 INFO - 'create-virtualenv', 08:57:18 INFO - 'install', 08:57:18 INFO - 'run-tests'), 08:57:18 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:57:18 INFO - 'download_minidump_stackwalk': True, 08:57:18 INFO - 'download_symbols': 'ondemand', 08:57:18 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:57:18 INFO - 'tooltool.py': '/tools/tooltool.py', 08:57:18 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:57:18 INFO - '/tools/misc-python/virtualenv.py')}, 08:57:18 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:57:18 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:57:18 INFO - 'installer_path': 'installer.exe', 08:57:18 INFO - 'log_level': 'info', 08:57:18 INFO - 'log_name': 'talos', 08:57:18 INFO - 'log_to_console': True, 08:57:18 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:57:18 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:57:18 INFO - 'opt_config_files': (), 08:57:18 INFO - 'pip_index': False, 08:57:18 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:57:18 INFO - 'cmd': ('bash', 08:57:18 INFO - '-c', 08:57:18 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:57:18 INFO - 'enabled': True, 08:57:18 INFO - 'halt_on_failure': False, 08:57:18 INFO - 'name': 'check_screen_resolution'},), 08:57:18 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:57:18 INFO - 'cmd': ('bash', 08:57:18 INFO - '-c', 08:57:18 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:57:18 INFO - 'enabled': True, 08:57:18 INFO - 'halt_on_failure': False, 08:57:18 INFO - 'name': 'check_screen_resolution'},), 08:57:18 INFO - 'run_cmd_checks_enabled': True, 08:57:18 INFO - 'sps_profile': False, 08:57:18 INFO - 'sps_profile_interval': 0, 08:57:18 INFO - 'suite': 'dromaeojs-e10s', 08:57:18 INFO - 'system_bits': '32', 08:57:18 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:57:18 INFO - 'title': 't-yosemite-r7-0110', 08:57:18 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:57:18 INFO - 'use_talos_json': True, 08:57:18 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:57:18 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:57:18 INFO - 'work_dir': 'build'} 08:57:18 INFO - [mozharness: 2017-01-30 16:57:18.830509Z] Running clobber step. 08:57:18 INFO - Running pre-action listener: _resource_record_pre_action 08:57:18 INFO - Running main action method: clobber 08:57:18 INFO - rmtree: /builds/slave/test/build 08:57:18 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:57:21 INFO - Running post-action listener: _resource_record_post_action 08:57:21 INFO - [mozharness: 2017-01-30 16:57:21.323893Z] Finished clobber step (success) 08:57:21 INFO - [mozharness: 2017-01-30 16:57:21.324026Z] Running read-buildbot-config step. 08:57:21 INFO - Running pre-action listener: _resource_record_pre_action 08:57:21 INFO - Running main action method: read_buildbot_config 08:57:21 INFO - Using buildbot properties: 08:57:21 INFO - { 08:57:21 INFO - "project": "", 08:57:21 INFO - "product": "firefox", 08:57:21 INFO - "script_repo_revision": "production", 08:57:21 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 08:57:21 INFO - "repository": "", 08:57:21 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 08:57:21 INFO - "buildid": "20170130072443", 08:57:21 INFO - "pgo_build": "False", 08:57:21 INFO - "basedir": "/builds/slave/test", 08:57:21 INFO - "buildnumber": 43, 08:57:21 INFO - "slavename": "t-yosemite-r7-0110", 08:57:21 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 08:57:21 INFO - "platform": "yosemite_r7", 08:57:21 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 08:57:21 INFO - "branch": "mozilla-aurora", 08:57:21 INFO - "repo_path": "releases/mozilla-aurora", 08:57:21 INFO - "revision": "13afabf5d79de6cfeb511b675cb4de22a62016f9", 08:57:21 INFO - "stage_platform": "macosx64", 08:57:21 INFO - "builduid": "a1481ca579754631aa4e0879dde4d610", 08:57:21 INFO - "slavebuilddir": "test" 08:57:21 INFO - } 08:57:21 INFO - Found installer url https://queue.taskcluster.net/v1/task/VjQInznPSjyycpy8fVGtLw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg. 08:57:21 INFO - Running post-action listener: _resource_record_post_action 08:57:21 INFO - [mozharness: 2017-01-30 16:57:21.327479Z] Finished read-buildbot-config step (success) 08:57:21 INFO - [mozharness: 2017-01-30 16:57:21.327593Z] Running download-and-extract step. 08:57:21 INFO - Running pre-action listener: _resource_record_pre_action 08:57:21 INFO - Running main action method: download_and_extract 08:57:21 INFO - mkdir: /builds/slave/test/build/tests 08:57:21 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')]} 08:57:21 INFO - trying https://queue.taskcluster.net/v1/task/VjQInznPSjyycpy8fVGtLw/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json 08:57:21 INFO - Downloading https://queue.taskcluster.net/v1/task/VjQInznPSjyycpy8fVGtLw/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 08:57:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VjQInznPSjyycpy8fVGtLw/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json'}, attempt #1 08:57:22 INFO - Downloaded 1199 bytes. 08:57:22 INFO - Reading from file /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 08:57:22 INFO - Using the following test package requirements: 08:57:22 INFO - {u'common': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 08:57:22 INFO - u'cppunittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 08:57:22 INFO - u'firefox-53.0a2.en-US.mac.cppunittest.tests.zip'], 08:57:22 INFO - u'gtest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 08:57:22 INFO - u'firefox-53.0a2.en-US.mac.gtest.tests.zip'], 08:57:22 INFO - u'jittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 08:57:22 INFO - u'jsshell-mac.zip'], 08:57:22 INFO - u'mochitest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 08:57:22 INFO - u'firefox-53.0a2.en-US.mac.mochitest.tests.zip'], 08:57:22 INFO - u'mozbase': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 08:57:22 INFO - u'reftest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 08:57:22 INFO - u'firefox-53.0a2.en-US.mac.reftest.tests.zip'], 08:57:22 INFO - u'talos': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 08:57:22 INFO - u'firefox-53.0a2.en-US.mac.talos.tests.zip'], 08:57:22 INFO - u'web-platform': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 08:57:22 INFO - u'firefox-53.0a2.en-US.mac.web-platform.tests.zip'], 08:57:22 INFO - u'xpcshell': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 08:57:22 INFO - u'firefox-53.0a2.en-US.mac.xpcshell.tests.zip']} 08:57:22 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip'] for test suite category: common 08:57:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VjQInznPSjyycpy8fVGtLw/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 08:57:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VjQInznPSjyycpy8fVGtLw/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:57:22 INFO - Fetch https://queue.taskcluster.net/v1/task/VjQInznPSjyycpy8fVGtLw/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 08:57:23 INFO - Expected file size: 16351781 08:57:23 INFO - Obtained file size: 16351781 08:57:29 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip', u'firefox-53.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 08:57:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VjQInznPSjyycpy8fVGtLw/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 08:57:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VjQInznPSjyycpy8fVGtLw/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:57:29 INFO - Fetch https://queue.taskcluster.net/v1/task/VjQInznPSjyycpy8fVGtLw/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 08:57:31 INFO - Expected file size: 16351781 08:57:31 INFO - Obtained file size: 16351781 08:57:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VjQInznPSjyycpy8fVGtLw/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip 08:57:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VjQInznPSjyycpy8fVGtLw/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip'}, attempt #1 08:57:37 INFO - Fetch https://queue.taskcluster.net/v1/task/VjQInznPSjyycpy8fVGtLw/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip into memory 08:57:38 INFO - Expected file size: 14403485 08:57:38 INFO - Obtained file size: 14403485 08:57:38 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')]} 08:57:38 INFO - trying https://queue.taskcluster.net/v1/task/VjQInznPSjyycpy8fVGtLw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 08:57:38 INFO - Downloading https://queue.taskcluster.net/v1/task/VjQInznPSjyycpy8fVGtLw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg 08:57:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VjQInznPSjyycpy8fVGtLw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg'}, attempt #1 08:57:40 INFO - Downloaded 73870916 bytes. 08:57:40 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VjQInznPSjyycpy8fVGtLw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 08:57:40 INFO - mkdir: /builds/slave/test/properties 08:57:40 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:57:40 INFO - Writing to file /builds/slave/test/properties/build_url 08:57:40 INFO - Contents: 08:57:40 INFO - build_url:https://queue.taskcluster.net/v1/task/VjQInznPSjyycpy8fVGtLw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 08:57:41 INFO - Running post-action listener: _resource_record_post_action 08:57:41 INFO - Running post-action listener: set_extra_try_arguments 08:57:41 INFO - [mozharness: 2017-01-30 16:57:41.632024Z] Finished download-and-extract step (success) 08:57:41 INFO - [mozharness: 2017-01-30 16:57:41.632178Z] Running populate-webroot step. 08:57:41 INFO - Running pre-action listener: _resource_record_pre_action 08:57:41 INFO - Running main action method: populate_webroot 08:57:41 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:57:41 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:57:41 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:57:41 INFO - '--tppagecycles', 08:57:41 INFO - '7'], 08:57:41 INFO - 'tests': ['tsvgm']}}, 08:57:41 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:57:41 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:57:41 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:57:41 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:57:41 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:57:41 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:57:41 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:57:41 INFO - 'talos_options': ['--disable-e10s'], 08:57:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:57:41 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:57:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:57:41 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:57:41 INFO - 'talos_options': ['--disable-e10s'], 08:57:41 INFO - 'tests': ['damp', 'tps']}, 08:57:41 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:57:41 INFO - 'tests': ['damp', 'tps']}, 08:57:41 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:57:41 INFO - 'tests': ['dromaeo_dom']}, 08:57:41 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:57:41 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:57:41 INFO - 'tests': ['basic_compositor_video']}, 08:57:41 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:57:41 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:57:41 INFO - 'tests': ['a11yr', 08:57:41 INFO - 'ts_paint', 08:57:41 INFO - 'tpaint', 08:57:41 INFO - 'sessionrestore', 08:57:41 INFO - 'sessionrestore_no_auto_restore', 08:57:41 INFO - 'tabpaint']}, 08:57:41 INFO - 'other-e10s': {'tests': ['a11yr', 08:57:41 INFO - 'ts_paint', 08:57:41 INFO - 'tpaint', 08:57:41 INFO - 'sessionrestore', 08:57:41 INFO - 'sessionrestore_no_auto_restore', 08:57:41 INFO - 'tabpaint']}, 08:57:41 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:57:41 INFO - 'ts_paint', 08:57:41 INFO - 'tpaint', 08:57:41 INFO - 'sessionrestore', 08:57:41 INFO - 'sessionrestore_no_auto_restore', 08:57:41 INFO - 'tabpaint']}, 08:57:41 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:57:41 INFO - 'ts_paint', 08:57:41 INFO - 'tpaint', 08:57:41 INFO - 'sessionrestore', 08:57:41 INFO - 'sessionrestore_no_auto_restore', 08:57:41 INFO - 'tabpaint']}, 08:57:41 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:57:41 INFO - 'tests': ['a11yr', 08:57:41 INFO - 'ts_paint', 08:57:41 INFO - 'tpaint', 08:57:41 INFO - 'sessionrestore', 08:57:41 INFO - 'sessionrestore_no_auto_restore', 08:57:41 INFO - 'tabpaint']}, 08:57:41 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:57:41 INFO - 'tests': ['a11yr', 08:57:41 INFO - 'ts_paint', 08:57:41 INFO - 'tpaint', 08:57:41 INFO - 'sessionrestore', 08:57:41 INFO - 'sessionrestore_no_auto_restore', 08:57:41 INFO - 'tabpaint']}, 08:57:41 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:57:41 INFO - 'tests': ['tsvgx', 08:57:41 INFO - 'tsvgr_opacity', 08:57:41 INFO - 'tart', 08:57:41 INFO - 'tscrollx', 08:57:41 INFO - 'cart', 08:57:41 INFO - 'tsvg_static']}, 08:57:41 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:57:41 INFO - 'tsvgr_opacity', 08:57:41 INFO - 'tart', 08:57:41 INFO - 'tscrollx', 08:57:41 INFO - 'cart', 08:57:41 INFO - 'tsvg_static']}, 08:57:41 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:57:41 INFO - 'talos_options': ['--disable-e10s'], 08:57:41 INFO - 'tests': ['tp5o']}, 08:57:41 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:57:41 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:57:41 INFO - 'talos_options': ['--disable-e10s', 08:57:41 INFO - '--xperf_path', 08:57:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:57:41 INFO - 'tests': ['tp5n']}, 08:57:41 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:57:41 INFO - 'talos_options': ['--xperf_path', 08:57:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:57:41 INFO - 'tests': ['tp5n']}}, 08:57:41 INFO - 'talos.zip': {'path': '', 08:57:41 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:57:41 INFO - Running post-action listener: _resource_record_post_action 08:57:41 INFO - [mozharness: 2017-01-30 16:57:41.650950Z] Finished populate-webroot step (success) 08:57:41 INFO - [mozharness: 2017-01-30 16:57:41.651086Z] Running create-virtualenv step. 08:57:41 INFO - Running pre-action listener: _resource_record_pre_action 08:57:41 INFO - Running main action method: create_virtualenv 08:57:41 INFO - Creating virtualenv /builds/slave/test/build/venv 08:57:41 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 08:57:41 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:57:41 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:57:41 INFO - Using real prefix '/tools/python27' 08:57:41 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:57:42 INFO - Installing distribute.............................................................................................................................................................................................done. 08:57:45 INFO - Installing pip.................done. 08:57:45 INFO - Return code: 0 08:57:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:57:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:57:45 INFO - Reading from file tmpfile_stdout 08:57:45 INFO - Output received: 08:57:45 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) 08:57:45 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:57:45 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')]} 08:57:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:57:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:45 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')]} 08:57:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:57:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:45 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 0x10ec35b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec38768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2a24c76a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea13d30>, '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 0x10e93adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea08be8>, '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.eiLQUNmMdi/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.t0Sxc0GO4y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 08:57:45 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 08:57:45 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 08:57:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eiLQUNmMdi/Render', 08:57:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:57:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:57:45 INFO - 'HOME': '/Users/cltbld', 08:57:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:57:45 INFO - 'LOGNAME': 'cltbld', 08:57:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:57:45 INFO - 'MOZ_NO_REMOTE': '1', 08:57:45 INFO - 'NO_EM_RESTART': '1', 08:57:45 INFO - 'PAGER': '/bin/cat', 08:57:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:57:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:57:45 INFO - 'PWD': '/builds/slave/test', 08:57:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:57:45 INFO - 'SHELL': '/bin/bash', 08:57:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.t0Sxc0GO4y/Listeners', 08:57:45 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 08:57:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:57:45 INFO - 'USER': 'cltbld', 08:57:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:57:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:57:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:57:45 INFO - 'XPC_FLAGS': '0x0', 08:57:45 INFO - 'XPC_SERVICE_NAME': '0', 08:57:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:57:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:57:45 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 08:57:45 INFO - Cleaning up... 08:57:45 INFO - Return code: 0 08:57:45 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:57:45 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')]} 08:57:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:57:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:45 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')]} 08:57:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:57:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:45 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 0x10ec35b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec38768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2a24c76a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea13d30>, '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 0x10e93adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea08be8>, '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.eiLQUNmMdi/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.t0Sxc0GO4y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 08:57:45 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 08:57:45 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 08:57:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eiLQUNmMdi/Render', 08:57:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:57:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:57:45 INFO - 'HOME': '/Users/cltbld', 08:57:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:57:45 INFO - 'LOGNAME': 'cltbld', 08:57:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:57:45 INFO - 'MOZ_NO_REMOTE': '1', 08:57:45 INFO - 'NO_EM_RESTART': '1', 08:57:45 INFO - 'PAGER': '/bin/cat', 08:57:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:57:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:57:45 INFO - 'PWD': '/builds/slave/test', 08:57:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:57:45 INFO - 'SHELL': '/bin/bash', 08:57:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.t0Sxc0GO4y/Listeners', 08:57:45 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 08:57:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:57:45 INFO - 'USER': 'cltbld', 08:57:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:57:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:57:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:57:45 INFO - 'XPC_FLAGS': '0x0', 08:57:45 INFO - 'XPC_SERVICE_NAME': '0', 08:57:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:57:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:57:46 INFO - Downloading/unpacking psutil>=3.1.1 08:57:46 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 08:57:46 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 08:57:46 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 08:57:46 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 08:57:46 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 08:57:46 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 08:57:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:57:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:57:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:57:49 INFO - Installing collected packages: psutil 08:57:49 INFO - Running setup.py install for psutil 08:57:49 INFO - building 'psutil._psutil_osx' extension 08:57:49 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 08:57:50 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 08:57:50 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 08:57:50 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 08:57:50 INFO - building 'psutil._psutil_posix' extension 08:57:50 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 08:57:50 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:57:50 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:57:50 INFO - ^ 08:57:50 INFO - 1 warning generated. 08:57:50 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 08:57:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:57:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:57:50 INFO - Successfully installed psutil 08:57:50 INFO - Cleaning up... 08:57:50 INFO - Return code: 0 08:57:50 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:57:50 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')]} 08:57:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:57:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:50 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')]} 08:57:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:57:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:50 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 0x10ec35b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec38768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2a24c76a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea13d30>, '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 0x10e93adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea08be8>, '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.eiLQUNmMdi/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.t0Sxc0GO4y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 08:57:50 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 08:57:50 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 08:57:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eiLQUNmMdi/Render', 08:57:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:57:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:57:50 INFO - 'HOME': '/Users/cltbld', 08:57:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:57:50 INFO - 'LOGNAME': 'cltbld', 08:57:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:57:50 INFO - 'MOZ_NO_REMOTE': '1', 08:57:50 INFO - 'NO_EM_RESTART': '1', 08:57:50 INFO - 'PAGER': '/bin/cat', 08:57:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:57:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:57:50 INFO - 'PWD': '/builds/slave/test', 08:57:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:57:50 INFO - 'SHELL': '/bin/bash', 08:57:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.t0Sxc0GO4y/Listeners', 08:57:50 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 08:57:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:57:50 INFO - 'USER': 'cltbld', 08:57:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:57:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:57:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:57:50 INFO - 'XPC_FLAGS': '0x0', 08:57:50 INFO - 'XPC_SERVICE_NAME': '0', 08:57:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:57:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:57:51 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:57:51 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 08:57:51 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 08:57:51 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 08:57:51 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 08:57:51 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 08:57:51 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 08:57:53 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:57:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:57:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:57:53 INFO - Installing collected packages: mozsystemmonitor 08:57:53 INFO - Running setup.py install for mozsystemmonitor 08:57:54 INFO - Successfully installed mozsystemmonitor 08:57:54 INFO - Cleaning up... 08:57:54 INFO - Return code: 0 08:57:54 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:57:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:57:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:57:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:57:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:57:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:54 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 0x10ec35b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec38768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2a24c76a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea13d30>, '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 0x10e93adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea08be8>, '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.eiLQUNmMdi/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.t0Sxc0GO4y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 08:57:54 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 08:57:54 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 08:57:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eiLQUNmMdi/Render', 08:57:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:57:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:57:54 INFO - 'HOME': '/Users/cltbld', 08:57:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:57:54 INFO - 'LOGNAME': 'cltbld', 08:57:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:57:54 INFO - 'MOZ_NO_REMOTE': '1', 08:57:54 INFO - 'NO_EM_RESTART': '1', 08:57:54 INFO - 'PAGER': '/bin/cat', 08:57:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:57:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:57:54 INFO - 'PWD': '/builds/slave/test', 08:57:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:57:54 INFO - 'SHELL': '/bin/bash', 08:57:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.t0Sxc0GO4y/Listeners', 08:57:54 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 08:57:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:57:54 INFO - 'USER': 'cltbld', 08:57:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:57:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:57:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:57:54 INFO - 'XPC_FLAGS': '0x0', 08:57:54 INFO - 'XPC_SERVICE_NAME': '0', 08:57:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:57:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:57:54 INFO - Downloading/unpacking jsonschema==2.5.1 08:57:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:57:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:57:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:57:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:57:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:57:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:57:56 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:57:57 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:57:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:57:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:57:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:57:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:57:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:57:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:57:57 INFO - Downloading functools32-3.2.3-2.tar.gz 08:57:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:57:57 INFO - warning: no files found matching '*.txt' 08:57:57 INFO - no previously-included directories found matching 'build' 08:57:57 INFO - no previously-included directories found matching 'dist' 08:57:57 INFO - no previously-included directories found matching '.git*' 08:57:57 INFO - Installing collected packages: jsonschema, functools32 08:57:57 INFO - Running setup.py install for functools32 08:57:57 INFO - warning: no files found matching '*.txt' 08:57:57 INFO - no previously-included directories found matching 'build' 08:57:57 INFO - no previously-included directories found matching 'dist' 08:57:57 INFO - no previously-included directories found matching '.git*' 08:57:57 INFO - Successfully installed jsonschema functools32 08:57:57 INFO - Cleaning up... 08:57:57 INFO - Return code: 0 08:57:57 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:57:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:57:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:57:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:57:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:57:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10ec35b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec38768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2a24c76a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea13d30>, '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 0x10e93adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea08be8>, '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.eiLQUNmMdi/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.t0Sxc0GO4y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 08:57:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:57:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 08:57:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eiLQUNmMdi/Render', 08:57:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:57:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:57:57 INFO - 'HOME': '/Users/cltbld', 08:57:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:57:57 INFO - 'LOGNAME': 'cltbld', 08:57:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:57:57 INFO - 'MOZ_NO_REMOTE': '1', 08:57:57 INFO - 'NO_EM_RESTART': '1', 08:57:57 INFO - 'PAGER': '/bin/cat', 08:57:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:57:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:57:57 INFO - 'PWD': '/builds/slave/test', 08:57:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:57:57 INFO - 'SHELL': '/bin/bash', 08:57:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.t0Sxc0GO4y/Listeners', 08:57:57 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 08:57:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:57:57 INFO - 'USER': 'cltbld', 08:57:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:57:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:57:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:57:57 INFO - 'XPC_FLAGS': '0x0', 08:57:57 INFO - 'XPC_SERVICE_NAME': '0', 08:57:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:57:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:57:58 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:57:58 INFO - Cleaning up... 08:57:58 INFO - Return code: 0 08:57:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:57:58 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')]} 08:57:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:57:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:58 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')]} 08:57:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:57:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:58 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 0x10ec35b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec38768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2a24c76a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea13d30>, '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 0x10e93adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea08be8>, '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.eiLQUNmMdi/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.t0Sxc0GO4y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 08:57:58 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 08:57:58 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 08:57:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eiLQUNmMdi/Render', 08:57:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:57:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:57:58 INFO - 'HOME': '/Users/cltbld', 08:57:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:57:58 INFO - 'LOGNAME': 'cltbld', 08:57:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:57:58 INFO - 'MOZ_NO_REMOTE': '1', 08:57:58 INFO - 'NO_EM_RESTART': '1', 08:57:58 INFO - 'PAGER': '/bin/cat', 08:57:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:57:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:57:58 INFO - 'PWD': '/builds/slave/test', 08:57:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:57:58 INFO - 'SHELL': '/bin/bash', 08:57:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.t0Sxc0GO4y/Listeners', 08:57:58 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 08:57:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:57:58 INFO - 'USER': 'cltbld', 08:57:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:57:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:57:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:57:58 INFO - 'XPC_FLAGS': '0x0', 08:57:58 INFO - 'XPC_SERVICE_NAME': '0', 08:57:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:57:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:57:58 INFO - Downloading/unpacking blobuploader==1.2.4 08:57:58 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 08:57:58 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 08:57:58 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 08:57:58 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 08:57:58 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 08:57:58 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 08:58:00 INFO - Downloading blobuploader-1.2.4.tar.gz 08:58:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:58:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:58:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:58:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:58:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:58:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:58:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:58:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:58:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:58:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:58:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:58:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:58:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:58:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:58:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:58:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:58:02 INFO - Downloading docopt-0.6.1.tar.gz 08:58:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:58:02 INFO - Installing collected packages: blobuploader, requests, docopt 08:58:02 INFO - Running setup.py install for blobuploader 08:58:02 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:58:02 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:58:02 INFO - Running setup.py install for requests 08:58:02 INFO - Running setup.py install for docopt 08:58:03 INFO - Successfully installed blobuploader requests docopt 08:58:03 INFO - Cleaning up... 08:58:03 INFO - Return code: 0 08:58:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:58:03 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')]} 08:58:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:58:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:58:03 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')]} 08:58:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:58:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:58:03 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 0x10ec35b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec38768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2a24c76a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea13d30>, '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 0x10e93adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea08be8>, '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.eiLQUNmMdi/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.t0Sxc0GO4y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 08:58:03 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 08:58:03 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 08:58:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eiLQUNmMdi/Render', 08:58:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:58:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:58:03 INFO - 'HOME': '/Users/cltbld', 08:58:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:58:03 INFO - 'LOGNAME': 'cltbld', 08:58:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:58:03 INFO - 'MOZ_NO_REMOTE': '1', 08:58:03 INFO - 'NO_EM_RESTART': '1', 08:58:03 INFO - 'PAGER': '/bin/cat', 08:58:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:58:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:58:03 INFO - 'PWD': '/builds/slave/test', 08:58:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:58:03 INFO - 'SHELL': '/bin/bash', 08:58:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.t0Sxc0GO4y/Listeners', 08:58:03 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 08:58:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:58:03 INFO - 'USER': 'cltbld', 08:58:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:58:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:58:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:58:03 INFO - 'XPC_FLAGS': '0x0', 08:58:03 INFO - 'XPC_SERVICE_NAME': '0', 08:58:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:58:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:58:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:58:03 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-yDc4bC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:58:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:58:03 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-xnq2Eg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:58:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:58:03 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-V9KDSa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:58:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:58:03 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-yHA5Un-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:58:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:58:03 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-JZvYYB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:58:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:58:03 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-JmKHjR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:58:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:58:04 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-GARdBP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:58:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:58:04 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-H1dq3S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:58:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:58:04 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-ggJm_Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:58:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:58:04 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-38aQ5G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:58:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:58:04 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-BiIb9j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:58:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:58:04 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-syjLK_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:58:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:58:04 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-cs5c1u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:58:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:58:04 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-5uVi4j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:58:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:58:04 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-4FVut0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:58:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:58:05 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-V_j55o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:58:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:58:05 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-4aOzjU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:58:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:58:05 INFO - Running setup.py install for manifestparser 08:58:05 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:58:05 INFO - Running setup.py install for mozcrash 08:58:05 INFO - Running setup.py install for mozdebug 08:58:05 INFO - Running setup.py install for mozdevice 08:58:05 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:58:05 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:58:05 INFO - Running setup.py install for mozfile 08:58:05 INFO - Running setup.py install for mozhttpd 08:58:06 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:58:06 INFO - Running setup.py install for mozinfo 08:58:06 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:58:06 INFO - Running setup.py install for mozInstall 08:58:06 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:58:06 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:58:06 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:58:06 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:58:06 INFO - Running setup.py install for mozleak 08:58:06 INFO - Running setup.py install for mozlog 08:58:06 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:58:06 INFO - Running setup.py install for moznetwork 08:58:06 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:58:06 INFO - Running setup.py install for mozprocess 08:58:07 INFO - Running setup.py install for mozprofile 08:58:07 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:58:07 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:58:07 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:58:07 INFO - Running setup.py install for mozrunner 08:58:07 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:58:07 INFO - Running setup.py install for mozscreenshot 08:58:07 INFO - Running setup.py install for moztest 08:58:07 INFO - Running setup.py install for mozversion 08:58:07 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:58:07 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:58:07 INFO - Cleaning up... 08:58:07 INFO - Return code: 0 08:58:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:58:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:58:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:58:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:58:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:58:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:58:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:58:07 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 0x10ec35b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec38768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2a24c76a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea13d30>, '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 0x10e93adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea08be8>, '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.eiLQUNmMdi/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.t0Sxc0GO4y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 08:58:07 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 08:58:07 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 08:58:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eiLQUNmMdi/Render', 08:58:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:58:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:58:07 INFO - 'HOME': '/Users/cltbld', 08:58:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:58:07 INFO - 'LOGNAME': 'cltbld', 08:58:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:58:07 INFO - 'MOZ_NO_REMOTE': '1', 08:58:07 INFO - 'NO_EM_RESTART': '1', 08:58:07 INFO - 'PAGER': '/bin/cat', 08:58:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:58:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:58:07 INFO - 'PWD': '/builds/slave/test', 08:58:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:58:07 INFO - 'SHELL': '/bin/bash', 08:58:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.t0Sxc0GO4y/Listeners', 08:58:07 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 08:58:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:58:07 INFO - 'USER': 'cltbld', 08:58:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:58:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:58:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:58:07 INFO - 'XPC_FLAGS': '0x0', 08:58:07 INFO - 'XPC_SERVICE_NAME': '0', 08:58:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:58:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:58:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:58:08 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-Ag4suq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:58:08 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)) 08:58:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:58:08 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-XT7htT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:58:08 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)) 08:58:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:58:08 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-AlQn0b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:58:08 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)) 08:58:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:58:08 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-F5TXbi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:58:08 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)) 08:58:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:58:08 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-mcre9f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:58:08 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)) 08:58:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:58:08 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-_wAfyL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:58:08 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)) 08:58:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:58:08 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-raghVw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:58:08 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)) 08:58:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:58:08 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-XEXUmF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:58:08 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)) 08:58:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:58:08 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-_1Rm1F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:58:09 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)) 08:58:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:58:09 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-BnwzCc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:58:09 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)) 08:58:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:58:09 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-LGKIdJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:58:09 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)) 08:58:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:58:09 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-CRaS5b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:58:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 08:58:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:58:09 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-xQZtFj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:58:09 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)) 08:58:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:58:09 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-T8E9rO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:58:09 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)) 08:58:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:58:09 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-QLC9lL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:58:09 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)) 08:58:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:58:09 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-zErBwc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:58:09 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)) 08:58:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:58:09 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-txYeRJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:58:09 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)) 08:58:09 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)) 08:58:09 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)) 08:58:09 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)) 08:58:09 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)) 08:58:09 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)) 08:58:09 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:58:09 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 08:58:09 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 08:58:09 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 08:58:09 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 08:58:09 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 08:58:09 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 08:58:12 INFO - Downloading blessings-1.6.tar.gz 08:58:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:58:12 INFO - Installing collected packages: blessings 08:58:12 INFO - Running setup.py install for blessings 08:58:12 INFO - Successfully installed blessings 08:58:12 INFO - Cleaning up... 08:58:12 INFO - Return code: 0 08:58:12 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:58:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:58:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:58:13 INFO - Reading from file tmpfile_stdout 08:58:13 INFO - Current package versions: 08:58:13 INFO - blessings == 1.6 08:58:13 INFO - blobuploader == 1.2.4 08:58:13 INFO - docopt == 0.6.1 08:58:13 INFO - functools32 == 3.2.3-2 08:58:13 INFO - jsonschema == 2.5.1 08:58:13 INFO - manifestparser == 1.1 08:58:13 INFO - mozInstall == 1.12 08:58:13 INFO - mozcrash == 1.0 08:58:13 INFO - mozdebug == 0.1 08:58:13 INFO - mozdevice == 0.49 08:58:13 INFO - mozfile == 1.2 08:58:13 INFO - mozhttpd == 0.7 08:58:13 INFO - mozinfo == 0.9 08:58:13 INFO - mozleak == 0.1 08:58:13 INFO - mozlog == 3.4 08:58:13 INFO - moznetwork == 0.27 08:58:13 INFO - mozprocess == 0.24 08:58:13 INFO - mozprofile == 0.28 08:58:13 INFO - mozrunner == 6.13 08:58:13 INFO - mozscreenshot == 0.1 08:58:13 INFO - mozsystemmonitor == 0.3 08:58:13 INFO - moztest == 0.8 08:58:13 INFO - mozversion == 1.4 08:58:13 INFO - psutil == 3.1.1 08:58:13 INFO - requests == 1.2.3 08:58:13 INFO - wsgiref == 0.1.2 08:58:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:58:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:58:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:58:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:58:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:58:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:58:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:58:13 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 0x10ec35b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec38768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2a24c76a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea13d30>, '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 0x10e93adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea08be8>, '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.eiLQUNmMdi/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.t0Sxc0GO4y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 08:58:13 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 08:58:13 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 08:58:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eiLQUNmMdi/Render', 08:58:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:58:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:58:13 INFO - 'HOME': '/Users/cltbld', 08:58:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:58:13 INFO - 'LOGNAME': 'cltbld', 08:58:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:58:13 INFO - 'MOZ_NO_REMOTE': '1', 08:58:13 INFO - 'NO_EM_RESTART': '1', 08:58:13 INFO - 'PAGER': '/bin/cat', 08:58:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:58:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:58:13 INFO - 'PWD': '/builds/slave/test', 08:58:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:58:13 INFO - 'SHELL': '/bin/bash', 08:58:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.t0Sxc0GO4y/Listeners', 08:58:13 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 08:58:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:58:13 INFO - 'USER': 'cltbld', 08:58:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:58:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:58:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:58:13 INFO - 'XPC_FLAGS': '0x0', 08:58:13 INFO - 'XPC_SERVICE_NAME': '0', 08:58:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:58:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:58:13 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)) 08:58:13 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)) 08:58:13 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)) 08:58:13 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)) 08:58:13 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)) 08:58:13 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)) 08:58:13 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)) 08:58:13 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)) 08:58:13 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)) 08:58:13 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)) 08:58:13 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)) 08:58:13 INFO - Cleaning up... 08:58:13 INFO - Return code: 0 08:58:13 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:58:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:58:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:58:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:58:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:58:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:58:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:58: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ec35b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec38768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2a24c76a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea13d30>, '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 0x10e93adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea08be8>, '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.eiLQUNmMdi/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.t0Sxc0GO4y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 08:58: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', 'jsonschema'] in /builds/slave/test/build 08:58: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 jsonschema 08:58:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eiLQUNmMdi/Render', 08:58:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:58:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:58:14 INFO - 'HOME': '/Users/cltbld', 08:58:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:58:14 INFO - 'LOGNAME': 'cltbld', 08:58:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:58:14 INFO - 'MOZ_NO_REMOTE': '1', 08:58:14 INFO - 'NO_EM_RESTART': '1', 08:58:14 INFO - 'PAGER': '/bin/cat', 08:58:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:58:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:58:14 INFO - 'PWD': '/builds/slave/test', 08:58:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:58:14 INFO - 'SHELL': '/bin/bash', 08:58:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.t0Sxc0GO4y/Listeners', 08:58:14 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 08:58:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:58:14 INFO - 'USER': 'cltbld', 08:58:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:58:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:58:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:58:14 INFO - 'XPC_FLAGS': '0x0', 08:58:14 INFO - 'XPC_SERVICE_NAME': '0', 08:58:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:58:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:58:14 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:58:14 INFO - Cleaning up... 08:58:14 INFO - Return code: 0 08:58:14 INFO - Running post-action listener: _resource_record_post_action 08:58:14 INFO - Running post-action listener: _start_resource_monitoring 08:58:14 INFO - Starting resource monitoring. 08:58:14 INFO - [mozharness: 2017-01-30 16:58:14.398432Z] Finished create-virtualenv step (success) 08:58:14 INFO - [mozharness: 2017-01-30 16:58:14.399342Z] Running install step. 08:58:14 INFO - Running pre-action listener: _resource_record_pre_action 08:58:14 INFO - Running main action method: install 08:58:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:58:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:58:14 INFO - Reading from file tmpfile_stdout 08:58:14 INFO - Detecting whether we're running mozinstall >=1.0... 08:58:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:58:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:58:14 INFO - Reading from file tmpfile_stdout 08:58:14 INFO - Output received: 08:58:14 INFO - Usage: mozinstall [options] installer 08:58:14 INFO - Options: 08:58:14 INFO - -h, --help show this help message and exit 08:58:14 INFO - -d DEST, --destination=DEST 08:58:14 INFO - Directory to install application into. [default: 08:58:14 INFO - "/builds/slave/test"] 08:58:14 INFO - --app=APP Application being installed. [default: firefox] 08:58:14 INFO - mkdir: /builds/slave/test/build/application 08:58:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:58:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 08:58:34 INFO - Reading from file tmpfile_stdout 08:58:34 INFO - Output received: 08:58:34 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 08:58:34 INFO - Running post-action listener: _resource_record_post_action 08:58:34 INFO - [mozharness: 2017-01-30 16:58:34.783992Z] Finished install step (success) 08:58:34 INFO - [mozharness: 2017-01-30 16:58:34.784129Z] Running run-tests step. 08:58:34 INFO - Running pre-action listener: _resource_record_pre_action 08:58:34 INFO - Running main action method: run_tests 08:58:34 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:58:34 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:58:34 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:58:34 INFO - 2017-01-30 08:58:34.796 screenresolution[1624:7355] starting screenresolution argv=screenresolution get 08:58:34 INFO - 2017-01-30 08:58:34.799 screenresolution[1624:7355] Display 0: 1600x1200x32@60 08:58:34 INFO - 2017-01-30 08:58:34.804 screenresolution[1625:7358] starting screenresolution argv=screenresolution list 08:58:34 INFO - Available Modes on Display 0 08:58:34 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:58:34 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:58:34 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:58:34 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:58:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:58:35 INFO - Intel Iris: 08:58:35 INFO - Chipset Model: Intel Iris 08:58:35 INFO - Type: GPU 08:58:35 INFO - Bus: Built-In 08:58:35 INFO - VRAM (Dynamic, Max): 1536 MB 08:58:35 INFO - Vendor: Intel (0x8086) 08:58:35 INFO - Device ID: 0x0a2e 08:58:35 INFO - Revision ID: 0x0009 08:58:35 INFO - Displays: 08:58:35 INFO - Display: 08:58:35 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:58:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:58:35 INFO - Display Serial Number: 1600x1200 60 08:58:35 INFO - Main Display: Yes 08:58:35 INFO - Mirror: Off 08:58:35 INFO - Online: Yes 08:58:35 INFO - Rotation: Supported 08:58:35 INFO - Return code: 0 08:58:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:58:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:58:35 INFO - Python 2.7.3 08:58:35 INFO - Return code: 0 08:58:35 INFO - grabbing minidump binary from tooltool 08:58:35 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')]} 08:58:35 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 0x10ea13d30>, '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 0x10e93adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ea08be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:58:35 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 08:58:35 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 08:58:35 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 08:58:35 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:58:35 INFO - Return code: 0 08:58:35 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:58:35 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:58:35 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:58:35 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:58:35 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:58:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VjQInznPSjyycpy8fVGtLw/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0110', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 08:58:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/VjQInznPSjyycpy8fVGtLw/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0110 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 08:58:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eiLQUNmMdi/Render', 08:58:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:58:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:58:35 INFO - 'HOME': '/Users/cltbld', 08:58:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:58:35 INFO - 'LOGNAME': 'cltbld', 08:58:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:58:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:58:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:58:35 INFO - 'MOZ_NO_REMOTE': '1', 08:58:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:58:35 INFO - 'NO_EM_RESTART': '1', 08:58:35 INFO - 'PAGER': '/bin/cat', 08:58:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:58:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:58:35 INFO - 'PWD': '/builds/slave/test', 08:58:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:58:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:58:35 INFO - 'SHELL': '/bin/bash', 08:58:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.t0Sxc0GO4y/Listeners', 08:58:35 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 08:58:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:58:35 INFO - 'USER': 'cltbld', 08:58:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:58:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:58:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:58:35 INFO - 'XPC_FLAGS': '0x0', 08:58:35 INFO - 'XPC_SERVICE_NAME': '0', 08:58:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:58:35 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VjQInznPSjyycpy8fVGtLw/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0110', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 08:58:35 INFO - mozversion application_buildid: 20170130072443 08:58:35 INFO - mozversion application_changeset: 13afabf5d79de6cfeb511b675cb4de22a62016f9 08:58:35 INFO - mozversion application_display_name: FirefoxDeveloperEdition 08:58:35 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:58:35 INFO - mozversion application_name: Firefox 08:58:35 INFO - mozversion application_remotingname: firefox-dev 08:58:35 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:58:35 INFO - mozversion application_vendor: Mozilla 08:58:35 INFO - mozversion application_version: 53.0a2 08:58:35 INFO - mozversion platform_buildid: 20170130072443 08:58:35 INFO - mozversion platform_changeset: 13afabf5d79de6cfeb511b675cb4de22a62016f9 08:58:35 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:58:35 INFO - mozversion platform_version: 53.0a2 08:58:35 INFO - using testdate: 1485795515 08:58:35 INFO - actual date: 1485795515 08:58:35 INFO - starting webserver on 'localhost:49245' 08:58:35 INFO - SUITE-START | Running 2 tests 08:58:35 INFO - TEST-START | dromaeo_css 08:58:35 INFO - operating with platform_type : mac_ 08:58:35 INFO - Initialising browser for dromaeo_css test... 08:58:35 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/tmpys0YLu/profile http://localhost:49245/getInfo.html) 08:58:38 INFO - PROCESS | 1635 | 2017-01-30 08:58:38.708 plugin-container[1637:7533] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a4f, name = 'com.apple.tsm.portname' 08:58:38 INFO - PROCESS | 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:58:38 INFO - PROCESS | 1635 | 2017-01-30 08:58:38.711 plugin-container[1637:7533] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x930b, name = 'com.apple.CFPasteboardClient' 08:58:38 INFO - PROCESS | 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:58:38 INFO - PROCESS | 1635 | 2017-01-30 08:58:38.711 plugin-container[1637:7533] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:58:38 INFO - PROCESS | 1635 | __metrics Screen width/height:1600/1200 08:58:38 INFO - PROCESS | 1635 | colorDepth:24 08:58:38 INFO - PROCESS | 1635 | Browser inner width/height: 1024/689 08:58:38 INFO - PROCESS | 1635 | __metrics 08:58:38 ERROR - PROCESS | 1635 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3280: TypeError: this.tabs is undefined 08:58:39 INFO - TEST-INFO | 1635: exit 0 08:58:39 INFO - Browser initialized. 08:58:39 INFO - Running cycle 1/1 for dromaeo_css test... 08:58:39 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/tmpys0YLu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:58:42 INFO - PROCESS | 1638 | 2017-01-30 08:58:42.116 plugin-container[1639:7660] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x433b, name = 'com.apple.tsm.portname' 08:58:42 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:58:42 INFO - PROCESS | 1638 | 2017-01-30 08:58:42.117 plugin-container[1639:7660] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 08:58:42 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:58:42 INFO - PROCESS | 1638 | 2017-01-30 08:58:42.117 plugin-container[1639:7660] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:58:51 INFO - PROCESS | 1638 | RSS: Main: 218456064 08:58:51 INFO - PROCESS | 1638 | 09:01:54 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49245/tests/dromaeo/cssquery-ext.html) 09:01:56 INFO - PROCESS | 1638 | RSS: Main: 239570944 09:01:56 INFO - PROCESS | 1638 | 09:03:39 INFO - PROCESS | 1638 | ************************* 09:03:39 INFO - PROCESS | 1638 | A coding exception was thrown and uncaught in a Task. 09:03:39 INFO - PROCESS | 1638 | 09:03:39 ERROR - PROCESS | 1638 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:03:39 INFO - PROCESS | 1638 | Full stack: 09:03:39 INFO - PROCESS | 1638 | ************************* 09:04:58 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-ext.html (next: http://localhost:49245/tests/dromaeo/cssquery-jquery.html) 09:04:59 INFO - PROCESS | 1638 | RSS: Main: 249794560 09:04:59 INFO - PROCESS | 1638 | 09:08:01 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49245/tests/dromaeo/cssquery-mootools.html) 09:08:02 INFO - PROCESS | 1638 | RSS: Main: 256344064 09:08:02 INFO - PROCESS | 1638 | 09:11:03 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49245/tests/dromaeo/cssquery-prototype.html) 09:11:04 INFO - PROCESS | 1638 | RSS: Main: 252776448 09:11:04 INFO - PROCESS | 1638 | 09:14:07 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49245/tests/dromaeo/cssquery-yui.html) 09:14:08 INFO - PROCESS | 1638 | RSS: Main: 257253376 09:14:08 INFO - PROCESS | 1638 | 09:17:10 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-yui.html (next: http://localhost:49245/tests/dromaeo/cssquery-dojo.html) 09:17:11 INFO - PROCESS | 1638 | RSS: Main: 258613248 09:17:11 INFO - PROCESS | 1638 | 09:17:11 INFO - PROCESS | 1638 | __start_tp_report 09:17:11 INFO - PROCESS | 1638 | _x_x_mozilla_page_load 09:17:11 INFO - PROCESS | 1638 | _x_x_mozilla_page_load_details 09:17:11 INFO - PROCESS | 1638 | |i|pagename|runs| 09:17:11 INFO - PROCESS | 1638 | |0;dojo.html;3163.462211424058;3372.9156771080707;3391.7795343302682;3415.5218269442266;3428.554287942568;5112.8240504683945;5511.614187006911;5703.087505999041;5762.423757624236;5786.971065144674;4876.585490233334;5797.82606521804;5862.50168735658;5882.558773100596;5885.970570147143;4550.294704320836;5232.16285394338;5301.920471192916;5314.990151871151;5360.91958620621;5036.622253331015;5380.408155102936;5384.434634363374;5390.5418039466695;5404.864878378033;5241.379310344828;5264.499872512124;5372.140457526796;5389.353277606673;5439.048166570854;5314.654547454403;5322.20166974953;5341.439148889369;5344.518993290583;5356.866078348053;5201.583873290166;5315.3090098286975;5337.332833395825;5358.758855851496;5393.541548968343;5881.647101173943;5963.940360596382;5975.52195824333;5982;6006.699665016732;6186.19579454668;6236.314005459396;6278.874422511524;6321.683915804238;6352.872942541169;6244.968775156095;6313.368663133696;6320.873582528369;6325.778597749057;6356.9364306357265;4751.64362672801;5083.74581270935;6057.636541807506;6065.878682426371;6069.119977603223;6098.786542470993;6270.184875966095;6295.18162638854;6388.648624325614;6443.387878151568;6162.414570615784;6208.348123447064;6260.530460215502;6305.653189074553;6306.211723534559;5103.923441148386;6225.346338634468;6322.082451114709;6365.777197798055;6367.076773867763;6024.186734790835;6271.43557079865;6347.428731414103;6364.42720155179;6373.649449280241;6072.423119803611;6256.718447669866;6284.214473190851;6285.465735412449;6350.809475715643;4318.52496225415;5081.491850814963;5083.212102124176;5086.898262034739;5089.643724939218;4548.454185497695;4553.453585569687;4563.133004729091;4568.109218702321;4596.862094137181;5772.509336706343;5784.768609255667;5815.563832712563;5843.678597677169;5863.384344643836;6303.338149494329;6318.905216421757;6328.841778955563;6329.050642403677;6330.588511746721;5079.771410286546;5470.483359746403;5751.942480575224;5821.7089145543405;5885.382034886361;5550.833474995757;5561.805336813192;5581.134924086773;5617.21359009739;5632.4930756231315;6444.581102228341;6481.254655714653;6483.7082331295205;6486.189226346801;6490.253620833664;5286.7067581056535;5562.749676264497;6209.410106039919;6350.428461438492;6354.586951848115;5563.360213575491;6251.9687401562705;6359.936400635935;6422.678866056772;6492.058651495412;6275.96862015687;6287.497000240061;6303.086052522451;6330.39861213184;6340.682965851781;5346.930613877318;6260.373962603703;6318.399752023551;6343.238811342484;6343.9682801585695;4948.886500537495;6019.127226552037;6120.447133218882;6142.262928448615;6146.985747351637;6165.414285642857;6190;6192.380761923771;6196.946519091855;6200.720967556381;6011.23623461751;6034;6060.696965151813;6078.240219972504;6088.238970128734;6059.697015149313;6150.477209437069;6155.6614386209185;6167.889779839672;6176.969115154396;6119.694015299307;6151.630902145886;6167.629942203482;6168.290646575701;6192.783252586138 09:17:11 INFO - PROCESS | 1638 | |1;ext.html;455.02170333782385;458.14555853333525;497.4254735780175;498.36209651645885;510.3696934286156;39499.40750888739;41627;41776.37335439967;41915.79042104793;44076.898077548074;42465.57534424662;42875.571244287545;42991.78504107475;42999.570004300025;43109.35335969963;7785.805354866117;8513.404061715682;9272.350935434522;9463.621455141785;9465.858012129824;8394.412391132593;11125;11435.771284574344;11449.942750286236;11578.53685852565;11340.829887551694;11396.544138234462;11420.086393088533;11423.257488263278;11432.028277596415;11350.886491135065;11428.999999999916;11436.771264574745;11447.942760286229;11460.197786154931;11316.434178291134;11356.943215283953;11383;11387.943060284728;11401;40079.19841603152;40292.992675183064;40339.798301008304;40380.394294085614;40380.798096009334;3982.820773065219;4051.392291156321;4056.350983842571;4132.752034877917;4224.070704445048;28886.277843054093;29476.41047179066;29644.851775741197;29705.40589188204;29907.700922990927;3181.34782369616;3227.9354412911844;3283.5238890361;3323.601167859848;3380.6281309055985;402489;403586;405041;405560;406080;62021;62029.68985155045;62207;62240.06639900405;62327;63870.36129638737;63923.68038159779;64399.03401448982;64416.67791661012;64604.35395646077;16268.674626507403;16780.748288775678;16793.74046946484;16797.496075117768;16807.579810504834;9471.76320591977;11937;12649.93675031619;12914.547990820276;13042.282674452807;16503.66992660164;16552.51027407539;16570.254338554794;16608.83391166097;16651.83348166527;5230.503102205284;5359.22291267764;6308.463780578611;6359.141515895388;6411.871762564722;761.9009528761336;811;873.5806812729925;882.5587206396801;911.116217269261;24735.87632061828;30057.549136762966;30329.93845215451;30353.24116897095;31079.689203108133;1051.7738686182508;1113.9053180479589;1129.4352823588206;1143.5825923538016;1173.6420391780418;12878.549250776177;13086.20921778934;13160.078794484485;15601.453949111727;16021.839781602268;646979;647880;649023;649911;651782;60228.698856506315;60428.69785651044;60491.09263361053;60492.69753651204;60553.39446605566;1421.3390773290469;1435.5980325508874;1451.2090910453828;1452.2520901735404;1455.1487379882888;27541.724582753915;29398.41203175967;30057.69942300549;30267.69732302649;30827.383452331276;50305.74847125741;50796.49203507917;50824.491755081974;50856.49143508665;50927.23609145792;526467;564094;566118;567269;569375.1531242317;21618.783812161677;23249.767502325438;23923.760762392154;25905.870470647526;25988.35029124287;46710.532894671975;46990.53009469862;47769.5223047779;47920.76039619919;47960.7601961988;32564.86022989184;38774.80612596919;38962.415563767136;40861.59138408697;41065.76802695924;30079.699203008564;33111.83444082764;36807.44788828223;38757.80621096877;39814.60185398225;32451.83774081209;34643.4803477943;35182;35352.469712954844;35959;26362;26613.334666632716;33231.169220769676;33592;33846.83076584601;24474.877625611756;24684.876575617724;24813.13901457434;24992.62511062299;25114.874425627753 09:17:11 INFO - PROCESS | 1638 | |2;jquery.html;326.37173441125833;383.01755995870604;387.33765261403;398.1082375478926;421.80839129459264;17646.38237661682;20663.966801659913;20920.686189707165;21135.682964755542;21223.469413264676;20589.27937522187;20843.27048553301;20972.37082887512;21025.264115755912;21244.575108497822;5783.334916484616;5846.912296315559;6081.6351018938785;6110.022396416574;6164.07538869171;3966.3257246268204;4168.916621667565;6111.541634377417;6137.693115344215;6157.661328626924;5861.7948371807;6126.142340072372;6168.814935551918;6194.96902515489;6220.875582488347;6158.784442544512;6178.907316390258;6419.743210271584;6722.226943901465;6790.32096790322;5982.970085149546;6033.969830150865;6039.1545183674325;6049.727762250665;6065.8180254592435;13153.868461315456;13154.802677959839;13286.800697989538;13341.53304634343;13437.992150588745;2520.861352625605;2668.1061844281994;2683.610876422927;2698.5952107183884;2821.478026565092;8160.428769986103;9967.302288839785;10055.849162262572;10058.647947321808;10414.114800241989;1623.4480276705858;1879.1719308019;1881.3509339277928;1882.5481884347794;1887.240591012101;106980.25569105044;124954;125036;127768.36115819361;131458.3427082868;11877.703057423634;12076.637700868989;12080.758384832254;12115.697107572381;12293.569725059759;15507.922460387626;15553.922230388776;15563;15600.84399155994;15619.687606247811;8736.8689469658;8897.555122243863;8898.644054237759;8919.974202966741;8970.327225458117;7493.925060749431;8376.916230837614;8709.477431354138;8857.3799834011;8890.822183556293;4703;4753.405824271966;4761.428628564525;4796.8081276748535;4820.68665536746;1680.3866588695282;2013.4865609269543;2071.2129390831387;2071.7513898332295;2077.3767869639046;862.9223369896739;866.5234121233296;875.8423483772982;879.2745984562762;912.0378001208655;16032.198390080683;20589.279375221795;21664.24175153862;21770.673439898415;22233;802.0175285275491;903.4985583001366;911.9680811171577;927.4388994658269;935.2331088507359;8965.820683586291;10707.839382409271;11086.279391839505;11114.388708621103;11116.88831116882;688584;690125;702052;702930;705584.4720776363;149319;155664;155684.443155567;157111;159563;928.5218112671979;1038.413296487467;1056.392574269798;1079.2553138334524;1079.3200283821143;18557.44327670172;18749;19019.52451188676;19106.853588784717;19325;57890.13164802615;57931.71034144802;58086.7095664519;58615;59126;90112;90733.09266907246;92172;92998;93591.53204233936;5566.888662226814;7490.176080631235;7525.3227209551405;7673.769786906401;7673.8848917267405;9631.855522167032;13113.934430327787;13229.536966206137;13362.933185334012;13510.86489135096;6429.6142231466265;10693.786124277627;11005.724856878642;11376.715582110513;11644.941775291409;6855;11088.889111109109;11185.38480383554;11258.774824503627;11418.771624567627;8680.900035715773;9903.464962930757;11496.597619083292;11529.481173347387;11565.884341156481;8225.83548329018;8399.70601028961;8399.832003359776;8479.830403392021;8621.224089831945;11017.559297628006;11219.439028048728;11333.26333788301;11400.144989126014;11487.36819474937 09:17:11 INFO - PROCESS | 1638 | |3;mootools.html;1353.2827601371273;1399.5941177058646;1446.4864972934602;1448.3192899337312;1450.282110355375;7624.27569380908;7874.370050395969;8058.63736131874;8538.957305213473;8540.444871083375;8083.110857805636;8197.795055123624;8337.16628337166;8467.237948584627;8494.447860889039;2298.333483289848;2333.988330058356;2337.439014636492;2339.438534751656;2350.4006478347997;2094.0262777808316;2177.292379976514;2320.6751054852334;2360.0441821062495;2371.762823717632;2269.319204238722;2348.283773449106;2350.753170917055;2356.2813341930705;2356.6229403295474;2337.2403968710237;2339.6841426407386;2350.97649023511;2356.71719393673;2358.4929240213264;2319.791218790317;2325.3489023073566;2390.2948630154146;2394.5091256292417;2397.3527147670034;8400.201980811875;8467.068622451525;8472.15278472154;8474.449160804528;8484.19400156984;5043.798248070073;5155.587552995751;5185.040767458032;5195.454477279869;5204.609654275944;3365.646607106243;3519.1729943463265;3594.3350480161253;3617.1861331200535;3617.4212126059706;1190.93940393926;1195.4620420810584;1238.6160290310033;1260.754152940177;1263.886250237483;10730.248882578222;10844.186685998582;10860.945695271474;10868.510917008754;10912.127029837673;3922.4508568800393;4525.502194758574;4651.232546629836;4694.36626055485;4706.858794236178;9414.62341506332;9447.480388578557;9455.952720236492;9470.384425012353;9602.039796020343;5198.792048318025;5242.7116508591635;5245.396779370421;5249.580033597303;5336.493033161843;4012.8394864205106;4418.7848341706285;4770.165221086296;4793.592544633745;4814.831480898222;3879.165979314461;3903.4535165076913;3964.900877478086;4000.079981604189;4000.899977500586;1199.076710932577;1237.2267332916927;1254.5483625894672;1270.2061211742662;1284.5760898903338;783.8118851475718;786.7049856303886;808.1070417189039;809.0008839083727;818.1123481023037;5591;5605.971970140124;5629.577781666391;5668.744906479218;5671.574631902624;976.1409959235828;987.1263931420601;1004.9397036177706;1008.5511947183579;1074.8015962202244;3513.6662017108333;3654.3056819204267;3842.5773164951834;3857.8263978121054;3888.941665875014;9686.32195746291;9944.602215911282;10027.147692446224;10043.146332561668;10049.346792458613;9763.853542196874;9876.35803672759;9884.357516761387;9927.900720992842;9948.502574871227;7372.410207183412;7717.112532058773;7730.96134519335;7733.41999350051;7884.763457096182;5809.767609295581;5850.853728656818;5864.944310024237;5899.410058994066;5950.494207992196;8973.641054357753;9119.042500537613;9193.494357810385;9215.815683686154;9223.631054758003;8758.299336052973;9011.369204155908;9139.268858491438;9195.816083678155;9216.907830921873;7211.206767255702;7273.599952002691;7322.121345438582;7349.6325183741665;7387.630618468947;7828.2954534092205;7881.172476890073;7882.921170788219;7898.131205567268;7905.288524032865;7032.6132062735;7149.714011439484;7166.820829479097;7168.462365322517;7217.386522145466;7001.194862590867;7040.542364746275;7043.7534686285735;7105.25394833535;7184.281571842774;5500.70733377664;6349.809505714836;6442.0014897690935;6489.707963141765;6516.218053833571;5047.671901326402;5075.061113693979;5091.923621145612;5106.285120083266;5107.131787596043;4054.493188351456;4801.4958429364415;4916.360873086476;4997.175466048061;5010.473900240422 09:17:11 INFO - PROCESS | 1638 | |4;prototype.html;356.98215089245537;369.8649992752646;370.35927844828444;372.7800597647387;376.97549659272147;4034.5763694812063;4382.342648602711;5087.40542323415;5089.872753181167;5136.94692588019;5428.45715428457;5439.401665816757;5464.3442786865635;5472.425395333493;5543.279373681426;3676.6323367663285;3832.965099423161;3867.45855580219;4141.233871733739;4190;3780.281746468176;3862.7875466849314;4219.050713589449;4236.406903033578;4241.936370954438;3736.308782875177;4276.358546218061;4286.39990401344;4299.742015479065;4301.956980430203;3276.5904261967253;4199.307114326132;4248.235317642823;4260.38224457455;4266.679999000072;3686.686631636314;3724.7206459515646;3740.969814450304;3749.662530372252;3758.3798673218885;4406.818624780722;4931.65478416511;5308.283381743454;5391.838244852661;5393.440329382589;4365.7817109144735;4785.521447855222;4925.3597032385915;4945.604351651859;4962.751862406902;4827.85516434504;5771.422857714237;5820.534357251409;5828.9417105828825;5923.437273459014;4160.479940007499;4168.749875007509;4178.078084081326;4178.7283826551175;4210.263203939298;5174.637775355726;5625.971870140664;5656.490915817596;6043.274807023141;6099.57302988791;3474.166200111955;3564.625714299961;3946.5856085111222;4220.514640816284;4250.23495770758;4323.48118225815;4347.782610869507;4349.998501034276;4359.237133501624;4393.099414620059;4106.726914656466;4902.558769710743;4936.555709986047;4959.454460009396;4962.751862406937;5343.7060961647485;5816.73824677882;5858.736356863951;5905.557083218776;5916.355660806246;1266.2845492296822;1323.9937647388053;1386.5718310140571;1395.3720825628507;1398.3916996106725;941.3128416256171;948.4072454715803;980.0640388429033;984.2126298960945;985.7289245457556;4417.337399390053;4640.675152739276;4884.975575122102;5089.847304580869;5142.71715055668;4923.75381230937;5732.111522713903;5787.160861675034;5794.391588883191;5821.505172060338;4887.0170810789095;4892;4955.90088198234;5160.697153821558;5401.756920938567;2405.846767143397;2666.6266722658843;2724.4959682458807;2888.887778205406;2916.6500019997734;5554.22264875236;5922.289325280994;5922.378150294157;5998.580099393001;6047.8488037798525;5476.58925580583;5812.505936995402;6208.906866397007;6223.471004964538;6248.218972628421;4916.967436838232;5365.704886231295;6249.50003999688;6252.3122456529745;6263.279722831841;5607.130894711327;5927.140564618193;6062.6359069209075;6072.937235983597;6148.907766383597;5380.569554435558;5451.64564303319;5925.911111333419;5963.373845746308;6027.306859711014;5747;5905.822825315247;5976.223090998143;6041.697915104315;6242.282137554058;3796.228753010373;3891.3968334908136;3950.7234493585174;3986.1031267964477;3994.4208089827393;5129.307328581634;5276.261323414802;5360.785568577213;6090.786822461369;6093.75624974996;5399.352077750695;5487.862803429946;5823.049278491752;5884.9705751470965;5916.526677865846;4977.776000079933;5901.986649078668;5903.350631430453;5907.645541267538;5910.21746082499;4750.501197374226;5249.868753281045;5332.653377530448;5351.812686555952;5800.622959507618;5003.449620541664;5010.747313171707;5792.275965504312;5831.533477321889;5919.6152250103605;4677.485476597503;4845.466998630078;5634.380218176079;5796.739146738322;5851.215379309351 09:17:11 INFO - PROCESS | 1638 | |5;yui.html;691.5442992500789;743.4127039638686;774.8992630957976;791.2245998921057;831.8336332733453;14037.859621403783;14596.270186490687;14768.261586920664;14777.335019924103;14803.185824779634;14245.928770356133;14423;14431;14439.061461005027;14452.494162704308;679.8815947765946;705.1573369823063;849.8512760266959;850.9914900850974;876.1720174435161;881.8104107616861;881.8192270584515;884.6328773558965;886.8049029213563;887.8401887660218;920.5443305563755;926.9721908342726;928.3269629518579;930.8743319651828;931.3061768982084;903.2277402820625;923.0308176414771;925.4539821505344;926.3561824531953;927.1701826864952;682.3040498691307;685.9744681700889;687.5771400588602;687.9174499060144;691.7267679266662;14967.925160374129;15182.392704291815;15224.543263702106;15623.453179138789;15900.602484937854;14109.717805643932;14327.426902923975;14598.562043138616;14616.196109213988;14646.853531464656;9775.755606109833;11235.382053987027;11435.428228588537;11883.643490695293;12073.094517911191;3704.481372607837;3790.3935370340614;3802.619738026175;3919.9216015679813;3921.705872059607;93448.59827102599;93658;93670.53164734201;94285.52857235739;94879.52560237223;11005.17461190414;11265.605703800327;11282.7179320516;11365;11366.829497557543;19397.4180774577;23082.422939426648;23089.307320780405;23450;23472.882635586713;7119.1457025157315;7317.780466586011;7362.7423040193335;7435.962820185864;7507.324340809353;4167.749935003849;4202.957970420257;4233.428487154257;4238.3777209660575;4258.957410425856;2936.3980384021224;3424.938269136576;3434.1414646338417;3475.9826200868833;3518.736094792901;500;586.0447470622858;614.3395849461847;626.0265287477924;696.9651517424109;644.7743289848515;646.7994921574327;666.5700623098171;694.2366389371042;728.9890651640229;9204.907950920538;9928.354656947275;10045.246606504541;10461.26771126014;10758.515866786014;661.3419647450817;702.3047183288508;707.2856415020866;724.7028718225503;738.0921466596117;4231.513375961804;4968.27959945813;5044.142495775727;5049;5051.166557517969;79291.20708792954;84739;88724;94555.52722236345;96293.51853240828;68249;76327;76574.23425765782;80832;81811.5909420449;63556.68221658954;71489.2851071493;72560.2743972564;72931;74990.6250468755;10552.736181595521;10980.615678451215;11145.72135696614;11172;11208.439578021229;45984;46365.768171158925;46924.7653761729;47364;47696.80757981078;59828.102578462225;60943.080023143564;62487.68756156372;62875;63213.735725286155;12576.308303043055;12917.677058073623;12947;12978.415971281127;13179.67050823737;14083.366248518636;14563.271836407925;14564.708705825613;14771.335289911765;15453;11776.941115294369;13306.866931330564;13581.66045848822;13796.446203353984;13809;12788.552400666304;13497.32513374347;13554.661133471742;13579.524716635266;14293.142411455347;13344.799828002393;14013.5095271665;14163.433462661378;14183;14211.28943552839;9580.520973951414;11400.543978240778;11499.486852462436;11604.94197529007;12142.757144856876;10894.78210435771;11175.664730058112;11217.943910280723;11218.607348742755;11224.719382015515 09:17:11 INFO - PROCESS | 1638 | __end_tp_report 09:17:11 INFO - PROCESS | 1638 | __start_cc_report 09:17:11 INFO - PROCESS | 1638 | _x_x_mozilla_cycle_collect,366 09:17:11 INFO - PROCESS | 1638 | __end_cc_report 09:17:11 INFO - PROCESS | 1638 | __startTimestamp1485796631140__endTimestamp 09:17:11 INFO - PROCESS | 1638 | 09:17:11 INFO - PROCESS | 1638 | ------- Summary: start ------- 09:17:11 INFO - PROCESS | 1638 | Number of tests: 6 09:17:11 INFO - PROCESS | 1638 | 09:17:11 INFO - PROCESS | 1638 | [#0] dojo.html Cycles:165 Average:5778.57 Median:6057.64 stddev:671.77 (11.1%) stddev-sans-first:641.74 09:17:11 INFO - PROCESS | 1638 | Values: 3163.5 3372.9 3391.8 3415.5 3428.6 5112.8 5511.6 5703.1 5762.4 5787.0 4876.6 5797.8 5862.5 5882.6 5886.0 4550.3 5232.2 5301.9 5315.0 5360.9 5036.6 5380.4 5384.4 5390.5 5404.9 5241.4 5264.5 5372.1 5389.4 5439.0 5314.7 5322.2 5341.4 5344.5 5356.9 5201.6 5315.3 5337.3 5358.8 5393.5 5881.6 5963.9 5975.5 5982.0 6006.7 6186.2 6236.3 6278.9 6321.7 6352.9 6245.0 6313.4 6320.9 6325.8 6356.9 4751.6 5083.7 6057.6 6065.9 6069.1 6098.8 6270.2 6295.2 6388.6 6443.4 6162.4 6208.3 6260.5 6305.7 6306.2 5103.9 6225.3 6322.1 6365.8 6367.1 6024.2 6271.4 6347.4 6364.4 6373.6 6072.4 6256.7 6284.2 6285.5 6350.8 4318.5 5081.5 5083.2 5086.9 5089.6 4548.5 4553.5 4563.1 4568.1 4596.9 5772.5 5784.8 5815.6 5843.7 5863.4 6303.3 6318.9 6328.8 6329.1 6330.6 5079.8 5470.5 5751.9 5821.7 5885.4 5550.8 5561.8 5581.1 5617.2 5632.5 6444.6 6481.3 6483.7 6486.2 6490.3 5286.7 5562.7 6209.4 6350.4 6354.6 5563.4 6252.0 6359.9 6422.7 6492.1 6276.0 6287.5 6303.1 6330.4 6340.7 5346.9 6260.4 6318.4 6343.2 6344.0 4948.9 6019.1 6120.4 6142.3 6147.0 6165.4 6190.0 6192.4 6196.9 6200.7 6011.2 6034.0 6060.7 6078.2 6088.2 6059.7 6150.5 6155.7 6167.9 6177.0 6119.7 6151.6 6167.6 6168.3 6192.8 09:17:11 INFO - PROCESS | 1638 | 09:17:11 INFO - PROCESS | 1638 | [#1] ext.html Cycles:180 Average:67496.67 Median:36383.22 stddev:146215.89 (401.9%) stddev-sans-first:146539.42 09:17:11 INFO - PROCESS | 1638 | Values: 455.0 458.1 497.4 498.4 510.4 39499.4 41627.0 41776.4 41915.8 44076.9 42465.6 42875.6 42991.8 42999.6 43109.4 7785.8 8513.4 9272.4 9463.6 9465.9 8394.4 11125.0 11435.8 11449.9 11578.5 11340.8 11396.5 11420.1 11423.3 11432.0 11350.9 11429.0 11436.8 11447.9 11460.2 11316.4 11356.9 11383.0 11387.9 11401.0 40079.2 40293.0 40339.8 40380.4 40380.8 3982.8 4051.4 4056.4 4132.8 4224.1 28886.3 29476.4 29644.9 29705.4 29907.7 3181.3 3227.9 3283.5 3323.6 3380.6 402489.0 403586.0 405041.0 405560.0 406080.0 62021.0 62029.7 62207.0 62240.1 62327.0 63870.4 63923.7 64399.0 64416.7 64604.4 16268.7 16780.7 16793.7 16797.5 16807.6 9471.8 11937.0 12649.9 12914.5 13042.3 16503.7 16552.5 16570.3 16608.8 16651.8 5230.5 5359.2 6308.5 6359.1 6411.9 761.9 811.0 873.6 882.6 911.1 24735.9 30057.5 30329.9 30353.2 31079.7 1051.8 1113.9 1129.4 1143.6 1173.6 12878.5 13086.2 13160.1 15601.5 16021.8 646979.0 647880.0 649023.0 649911.0 651782.0 60228.7 60428.7 60491.1 60492.7 60553.4 1421.3 1435.6 1451.2 1452.3 1455.1 27541.7 29398.4 30057.7 30267.7 30827.4 50305.7 50796.5 50824.5 50856.5 50927.2 526467.0 564094.0 566118.0 567269.0 569375.2 21618.8 23249.8 23923.8 25905.9 25988.4 46710.5 46990.5 47769.5 47920.8 47960.8 32564.9 38774.8 38962.4 40861.6 41065.8 30079.7 33111.8 36807.4 38757.8 39814.6 32451.8 34643.5 35182.0 35352.5 35959.0 26362.0 26613.3 33231.2 33592.0 33846.8 24474.9 24684.9 24813.1 24992.6 25114.9 09:17:11 INFO - PROCESS | 1638 | 09:17:11 INFO - PROCESS | 1638 | [#2] jquery.html Cycles:180 Average:38920.87 Median:1573.92 stddev:116673.86 (7412.9%) stddev-sans-first:116965.17 09:17:11 INFO - PROCESS | 1638 | Values: 326.4 383.0 387.3 398.1 421.8 17646.4 20664.0 20920.7 21135.7 21223.5 20589.3 20843.3 20972.4 21025.3 21244.6 5783.3 5846.9 6081.6 6110.0 6164.1 3966.3 4168.9 6111.5 6137.7 6157.7 5861.8 6126.1 6168.8 6195.0 6220.9 6158.8 6178.9 6419.7 6722.2 6790.3 5983.0 6034.0 6039.2 6049.7 6065.8 13153.9 13154.8 13286.8 13341.5 13438.0 2520.9 2668.1 2683.6 2698.6 2821.5 8160.4 9967.3 10055.8 10058.6 10414.1 1623.4 1879.2 1881.4 1882.5 1887.2 106980.3 124954.0 125036.0 127768.4 131458.3 11877.7 12076.6 12080.8 12115.7 12293.6 15507.9 15553.9 15563.0 15600.8 15619.7 8736.9 8897.6 8898.6 8920.0 8970.3 7493.9 8376.9 8709.5 8857.4 8890.8 4703.0 4753.4 4761.4 4796.8 4820.7 1680.4 2013.5 2071.2 2071.8 2077.4 862.9 866.5 875.8 879.3 912.0 16032.2 20589.3 21664.2 21770.7 22233.0 802.0 903.5 912.0 927.4 935.2 8965.8 10707.8 11086.3 11114.4 11116.9 688584.0 690125.0 702052.0 702930.0 705584.5 149319.0 155664.0 155684.4 157111.0 159563.0 928.5 1038.4 1056.4 1079.3 1079.3 18557.4 18749.0 19019.5 19106.9 19325.0 57890.1 57931.7 58086.7 58615.0 59126.0 90112.0 90733.1 92172.0 92998.0 93591.5 5566.9 7490.2 7525.3 7673.8 7673.9 9631.9 13113.9 13229.5 13362.9 13510.9 6429.6 10693.8 11005.7 11376.7 11644.9 6855.0 11088.9 11185.4 11258.8 11418.8 8680.9 9903.5 11496.6 11529.5 11565.9 8225.8 8399.7 8399.8 8479.8 8621.2 11017.6 11219.4 11333.3 11400.1 11487.4 09:17:11 INFO - PROCESS | 1638 | 09:17:11 INFO - PROCESS | 1638 | [#3] mootools.html Cycles:180 Average:5445.73 Median:5201.70 stddev:2946.50 (56.6%) stddev-sans-first:2938.71 09:17:11 INFO - PROCESS | 1638 | Values: 1353.3 1399.6 1446.5 1448.3 1450.3 7624.3 7874.4 8058.6 8539.0 8540.4 8083.1 8197.8 8337.2 8467.2 8494.4 2298.3 2334.0 2337.4 2339.4 2350.4 2094.0 2177.3 2320.7 2360.0 2371.8 2269.3 2348.3 2350.8 2356.3 2356.6 2337.2 2339.7 2351.0 2356.7 2358.5 2319.8 2325.3 2390.3 2394.5 2397.4 8400.2 8467.1 8472.2 8474.4 8484.2 5043.8 5155.6 5185.0 5195.5 5204.6 3365.6 3519.2 3594.3 3617.2 3617.4 1190.9 1195.5 1238.6 1260.8 1263.9 10730.2 10844.2 10860.9 10868.5 10912.1 3922.5 4525.5 4651.2 4694.4 4706.9 9414.6 9447.5 9456.0 9470.4 9602.0 5198.8 5242.7 5245.4 5249.6 5336.5 4012.8 4418.8 4770.2 4793.6 4814.8 3879.2 3903.5 3964.9 4000.1 4000.9 1199.1 1237.2 1254.5 1270.2 1284.6 783.8 786.7 808.1 809.0 818.1 5591.0 5606.0 5629.6 5668.7 5671.6 976.1 987.1 1004.9 1008.6 1074.8 3513.7 3654.3 3842.6 3857.8 3888.9 9686.3 9944.6 10027.1 10043.1 10049.3 9763.9 9876.4 9884.4 9927.9 9948.5 7372.4 7717.1 7731.0 7733.4 7884.8 5809.8 5850.9 5864.9 5899.4 5950.5 8973.6 9119.0 9193.5 9215.8 9223.6 8758.3 9011.4 9139.3 9195.8 9216.9 7211.2 7273.6 7322.1 7349.6 7387.6 7828.3 7881.2 7882.9 7898.1 7905.3 7032.6 7149.7 7166.8 7168.5 7217.4 7001.2 7040.5 7043.8 7105.3 7184.3 5500.7 6349.8 6442.0 6489.7 6516.2 5047.7 5075.1 5091.9 5106.3 5107.1 4054.5 4801.5 4916.4 4997.2 5010.5 09:17:11 INFO - PROCESS | 1638 | 09:17:11 INFO - PROCESS | 1638 | [#4] prototype.html Cycles:180 Average:4661.28 Median:5089.86 stddev:1425.06 (28.0%) stddev-sans-first:1391.96 09:17:11 INFO - PROCESS | 1638 | Values: 357.0 369.9 370.4 372.8 377.0 4034.6 4382.3 5087.4 5089.9 5136.9 5428.5 5439.4 5464.3 5472.4 5543.3 3676.6 3833.0 3867.5 4141.2 4190.0 3780.3 3862.8 4219.1 4236.4 4241.9 3736.3 4276.4 4286.4 4299.7 4302.0 3276.6 4199.3 4248.2 4260.4 4266.7 3686.7 3724.7 3741.0 3749.7 3758.4 4406.8 4931.7 5308.3 5391.8 5393.4 4365.8 4785.5 4925.4 4945.6 4962.8 4827.9 5771.4 5820.5 5828.9 5923.4 4160.5 4168.7 4178.1 4178.7 4210.3 5174.6 5626.0 5656.5 6043.3 6099.6 3474.2 3564.6 3946.6 4220.5 4250.2 4323.5 4347.8 4350.0 4359.2 4393.1 4106.7 4902.6 4936.6 4959.5 4962.8 5343.7 5816.7 5858.7 5905.6 5916.4 1266.3 1324.0 1386.6 1395.4 1398.4 941.3 948.4 980.1 984.2 985.7 4417.3 4640.7 4885.0 5089.8 5142.7 4923.8 5732.1 5787.2 5794.4 5821.5 4887.0 4892.0 4955.9 5160.7 5401.8 2405.8 2666.6 2724.5 2888.9 2916.7 5554.2 5922.3 5922.4 5998.6 6047.8 5476.6 5812.5 6208.9 6223.5 6248.2 4917.0 5365.7 6249.5 6252.3 6263.3 5607.1 5927.1 6062.6 6072.9 6148.9 5380.6 5451.6 5925.9 5963.4 6027.3 5747.0 5905.8 5976.2 6041.7 6242.3 3796.2 3891.4 3950.7 3986.1 3994.4 5129.3 5276.3 5360.8 6090.8 6093.8 5399.4 5487.9 5823.0 5885.0 5916.5 4977.8 5902.0 5903.4 5907.6 5910.2 4750.5 5249.9 5332.7 5351.8 5800.6 5003.4 5010.7 5792.3 5831.5 5919.6 4677.5 4845.5 5634.4 5796.7 5851.2 09:17:11 INFO - PROCESS | 1638 | 09:17:11 INFO - PROCESS | 1638 | [#5] yui.html Cycles:180 Average:19045.60 Median:4248.67 stddev:25821.00 (607.7%) stddev-sans-first:25856.66 09:17:11 INFO - PROCESS | 1638 | Values: 691.5 743.4 774.9 791.2 831.8 14037.9 14596.3 14768.3 14777.3 14803.2 14245.9 14423.0 14431.0 14439.1 14452.5 679.9 705.2 849.9 851.0 876.2 881.8 881.8 884.6 886.8 887.8 920.5 927.0 928.3 930.9 931.3 903.2 923.0 925.5 926.4 927.2 682.3 686.0 687.6 687.9 691.7 14967.9 15182.4 15224.5 15623.5 15900.6 14109.7 14327.4 14598.6 14616.2 14646.9 9775.8 11235.4 11435.4 11883.6 12073.1 3704.5 3790.4 3802.6 3919.9 3921.7 93448.6 93658.0 93670.5 94285.5 94879.5 11005.2 11265.6 11282.7 11365.0 11366.8 19397.4 23082.4 23089.3 23450.0 23472.9 7119.1 7317.8 7362.7 7436.0 7507.3 4167.7 4203.0 4233.4 4238.4 4259.0 2936.4 3424.9 3434.1 3476.0 3518.7 500.0 586.0 614.3 626.0 697.0 644.8 646.8 666.6 694.2 729.0 9204.9 9928.4 10045.2 10461.3 10758.5 661.3 702.3 707.3 724.7 738.1 4231.5 4968.3 5044.1 5049.0 5051.2 79291.2 84739.0 88724.0 94555.5 96293.5 68249.0 76327.0 76574.2 80832.0 81811.6 63556.7 71489.3 72560.3 72931.0 74990.6 10552.7 10980.6 11145.7 11172.0 11208.4 45984.0 46365.8 46924.8 47364.0 47696.8 59828.1 60943.1 62487.7 62875.0 63213.7 12576.3 12917.7 12947.0 12978.4 13179.7 14083.4 14563.3 14564.7 14771.3 15453.0 11776.9 13306.9 13581.7 13796.4 13809.0 12788.6 13497.3 13554.7 13579.5 14293.1 13344.8 14013.5 14163.4 14183.0 14211.3 9580.5 11400.5 11499.5 11604.9 12142.8 10894.8 11175.7 11217.9 11218.6 11224.7 09:17:11 INFO - PROCESS | 1638 | -------- Summary: end -------- 09:17:11 INFO - PROCESS | 1638 | 09:17:11 INFO - TEST-INFO | 1638: exit 0 09:17:11 INFO - TEST-OK | dromaeo_css | took 1116540ms 09:17:11 INFO - TEST-START | kraken 09:17:11 INFO - operating with platform_type : mac_ 09:17:11 INFO - Initialising browser for kraken test... 09:17:11 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/tmpXENE8y/profile http://localhost:49245/getInfo.html) 09:17:14 INFO - PROCESS | 1649 | 2017-01-30 09:17:14.758 plugin-container[1650:10299] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x463f, name = 'com.apple.tsm.portname' 09:17:14 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:17:14 INFO - PROCESS | 1649 | 2017-01-30 09:17:14.760 plugin-container[1650:10299] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 09:17:14 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:17:14 INFO - PROCESS | 1649 | 2017-01-30 09:17:14.760 plugin-container[1650:10299] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:17:14 INFO - PROCESS | 1649 | __metrics Screen width/height:1600/1200 09:17:14 INFO - PROCESS | 1649 | colorDepth:24 09:17:14 INFO - PROCESS | 1649 | Browser inner width/height: 1024/689 09:17:14 INFO - PROCESS | 1649 | __metrics 09:17:14 ERROR - PROCESS | 1649 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3280: TypeError: this.tabs is undefined 09:17:15 INFO - TEST-INFO | 1649: exit 0 09:17:15 INFO - Browser initialized. 09:17:15 INFO - Running cycle 1/1 for kraken test... 09:17:15 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/tmpXENE8y/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:17:18 INFO - PROCESS | 1651 | 2017-01-30 09:17:18.229 plugin-container[1652:10426] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 09:17:18 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:17:18 INFO - PROCESS | 1651 | 2017-01-30 09:17:18.229 plugin-container[1652:10426] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b2b, name = 'com.apple.CFPasteboardClient' 09:17:18 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:17:18 INFO - PROCESS | 1651 | 2017-01-30 09:17:18.229 plugin-container[1652:10426] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:17:27 INFO - PROCESS | 1651 | RSS: Main: 218755072 09:17:27 INFO - PROCESS | 1651 | 09:17:31 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-beat-detection) 09:17:32 INFO - PROCESS | 1651 | RSS: Main: 233607168 09:17:32 INFO - PROCESS | 1651 | 09:17:34 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-dft) 09:17:35 INFO - PROCESS | 1651 | RSS: Main: 242528256 09:17:35 INFO - PROCESS | 1651 | 09:17:38 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-fft) 09:17:39 INFO - PROCESS | 1651 | RSS: Main: 244883456 09:17:39 INFO - PROCESS | 1651 | 09:17:41 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-oscillator) 09:17:41 INFO - PROCESS | 1651 | RSS: Main: 244740096 09:17:41 INFO - PROCESS | 1651 | 09:17:43 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49245/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:17:44 INFO - PROCESS | 1651 | RSS: Main: 246169600 09:17:44 INFO - PROCESS | 1651 | 09:17:47 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49245/tests/kraken/driver.html?testName=imaging-darkroom) 09:17:48 INFO - PROCESS | 1651 | RSS: Main: 249999360 09:17:48 INFO - PROCESS | 1651 | 09:17:51 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49245/tests/kraken/driver.html?testName=imaging-desaturate) 09:17:52 INFO - PROCESS | 1651 | RSS: Main: 249475072 09:17:52 INFO - PROCESS | 1651 | 09:17:54 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49245/tests/kraken/driver.html?testName=json-parse-financial) 09:17:55 INFO - PROCESS | 1651 | RSS: Main: 249729024 09:17:55 INFO - PROCESS | 1651 | 09:17:57 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49245/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:17:57 INFO - PROCESS | 1651 | RSS: Main: 248721408 09:17:57 INFO - PROCESS | 1651 | 09:17:59 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:18:00 INFO - PROCESS | 1651 | RSS: Main: 248885248 09:18:00 INFO - PROCESS | 1651 | 09:18:02 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:18:03 INFO - PROCESS | 1651 | RSS: Main: 248795136 09:18:03 INFO - PROCESS | 1651 | 09:18:06 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:18:06 INFO - PROCESS | 1651 | RSS: Main: 249090048 09:18:06 INFO - PROCESS | 1651 | 09:18:09 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:18:10 INFO - PROCESS | 1651 | RSS: Main: 248881152 09:18:10 INFO - PROCESS | 1651 | 09:18:12 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49245/tests/kraken/driver.html?testName=ai-astar) 09:18:12 INFO - PROCESS | 1651 | RSS: Main: 250613760 09:18:12 INFO - PROCESS | 1651 | 09:18:12 INFO - PROCESS | 1651 | __start_tp_report 09:18:12 INFO - PROCESS | 1651 | _x_x_mozilla_page_load 09:18:12 INFO - PROCESS | 1651 | _x_x_mozilla_page_load_details 09:18:12 INFO - PROCESS | 1651 | |i|pagename|runs| 09:18:12 INFO - PROCESS | 1651 | |0;ai-astar;106;118;157;119;117;120;119;121;129;173 09:18:12 INFO - PROCESS | 1651 | |1;audio-beat-detection;146;124;143;141;144;104;139;146;170;119 09:18:12 INFO - PROCESS | 1651 | |2;audio-dft;193;197;196;197;201;197;201;195;197;195 09:18:12 INFO - PROCESS | 1651 | |3;audio-fft;88;75;74;75;52;51;52;49;75;84 09:18:12 INFO - PROCESS | 1651 | |4;audio-oscillator;85;72;93;76;61;88;73;77;105;115 09:18:12 INFO - PROCESS | 1651 | |5;imaging-gaussian-blur;89;87;92;129;89;87;137;116;137;132 09:18:12 INFO - PROCESS | 1651 | |6;imaging-darkroom;98;99;98;98;109;110;98;172;97;170 09:18:12 INFO - PROCESS | 1651 | |7;imaging-desaturate;108;95;91;93;93;93;93;92;93;92 09:18:12 INFO - PROCESS | 1651 | |8;json-parse-financial;61;59;60;61;63;64;64;63;64;62 09:18:12 INFO - PROCESS | 1651 | |9;json-stringify-tinderbox;68;74;106;82;79;70;71;74;74;73 09:18:12 INFO - PROCESS | 1651 | |10;stanford-crypto-aes;88;89;84;83;86;89;85;86;88;85 09:18:12 INFO - PROCESS | 1651 | |11;stanford-crypto-ccm;171;159;151;155;153;151;142;147;168;179 09:18:12 INFO - PROCESS | 1651 | |12;stanford-crypto-pbkdf2;169;173;169;167;172;175;183;232;174;178 09:18:12 INFO - PROCESS | 1651 | |13;stanford-crypto-sha256-iterative;69;66;69;70;66;66;68;68;65;68 09:18:12 INFO - PROCESS | 1651 | __end_tp_report 09:18:12 INFO - PROCESS | 1651 | __start_cc_report 09:18:12 INFO - PROCESS | 1651 | _x_x_mozilla_cycle_collect,934 09:18:12 INFO - PROCESS | 1651 | __end_cc_report 09:18:12 INFO - PROCESS | 1651 | __startTimestamp1485796692435__endTimestamp 09:18:12 INFO - PROCESS | 1651 | 09:18:12 INFO - PROCESS | 1651 | ------- Summary: start ------- 09:18:12 INFO - PROCESS | 1651 | Number of tests: 14 09:18:12 INFO - PROCESS | 1651 | 09:18:12 INFO - PROCESS | 1651 | [#0] ai-astar Cycles:10 Average:127.90 Median:120.50 stddev:20.67 (17.2%) stddev-sans-first:20.35 09:18:12 INFO - PROCESS | 1651 | Values: 106.0 118.0 157.0 119.0 117.0 120.0 119.0 121.0 129.0 173.0 09:18:12 INFO - PROCESS | 1651 | 09:18:12 INFO - PROCESS | 1651 | [#1] audio-beat-detection Cycles:10 Average:137.60 Median:143.50 stddev:18.06 (12.6%) stddev-sans-first:18.89 09:18:12 INFO - PROCESS | 1651 | Values: 146.0 124.0 143.0 141.0 144.0 104.0 139.0 146.0 170.0 119.0 09:18:12 INFO - PROCESS | 1651 | 09:18:12 INFO - PROCESS | 1651 | [#2] audio-dft Cycles:10 Average:196.90 Median:197.00 stddev:2.51 (1.3%) stddev-sans-first:2.24 09:18:12 INFO - PROCESS | 1651 | Values: 193.0 197.0 196.0 197.0 201.0 197.0 201.0 195.0 197.0 195.0 09:18:12 INFO - PROCESS | 1651 | 09:18:12 INFO - PROCESS | 1651 | [#3] audio-fft Cycles:10 Average:67.50 Median:75.00 stddev:14.90 (19.9%) stddev-sans-first:13.84 09:18:12 INFO - PROCESS | 1651 | Values: 88.0 75.0 74.0 75.0 52.0 51.0 52.0 49.0 75.0 84.0 09:18:12 INFO - PROCESS | 1651 | 09:18:12 INFO - PROCESS | 1651 | [#4] audio-oscillator Cycles:10 Average:84.50 Median:76.50 stddev:16.35 (21.4%) stddev-sans-first:17.34 09:18:12 INFO - PROCESS | 1651 | Values: 85.0 72.0 93.0 76.0 61.0 88.0 73.0 77.0 105.0 115.0 09:18:12 INFO - PROCESS | 1651 | 09:18:12 INFO - PROCESS | 1651 | [#5] imaging-gaussian-blur Cycles:10 Average:109.50 Median:87.00 stddev:22.61 (26.0%) stddev-sans-first:22.73 09:18:12 INFO - PROCESS | 1651 | Values: 89.0 87.0 92.0 129.0 89.0 87.0 137.0 116.0 137.0 132.0 09:18:12 INFO - PROCESS | 1651 | 09:18:12 INFO - PROCESS | 1651 | [#6] imaging-darkroom Cycles:10 Average:114.90 Median:98.00 stddev:29.95 (30.6%) stddev-sans-first:31.13 09:18:12 INFO - PROCESS | 1651 | Values: 98.0 99.0 98.0 98.0 109.0 110.0 98.0 172.0 97.0 170.0 09:18:12 INFO - PROCESS | 1651 | 09:18:12 INFO - PROCESS | 1651 | [#7] imaging-desaturate Cycles:10 Average:94.30 Median:93.00 stddev:4.92 (5.3%) stddev-sans-first:1.09 09:18:12 INFO - PROCESS | 1651 | Values: 108.0 95.0 91.0 93.0 93.0 93.0 93.0 92.0 93.0 92.0 09:18:12 INFO - PROCESS | 1651 | 09:18:12 INFO - PROCESS | 1651 | [#8] json-parse-financial Cycles:10 Average:62.10 Median:63.00 stddev:1.79 (2.8%) stddev-sans-first:1.86 09:18:12 INFO - PROCESS | 1651 | Values: 61.0 59.0 60.0 61.0 63.0 64.0 64.0 63.0 64.0 62.0 09:18:12 INFO - PROCESS | 1651 | 09:18:12 INFO - PROCESS | 1651 | [#9] json-stringify-tinderbox Cycles:10 Average:77.10 Median:74.00 stddev:10.95 (14.8%) stddev-sans-first:11.11 09:18:12 INFO - PROCESS | 1651 | Values: 68.0 74.0 106.0 82.0 79.0 70.0 71.0 74.0 74.0 73.0 09:18:12 INFO - PROCESS | 1651 | 09:18:12 INFO - PROCESS | 1651 | [#10] stanford-crypto-aes Cycles:10 Average:86.30 Median:87.00 stddev:2.11 (2.4%) stddev-sans-first:2.15 09:18:12 INFO - PROCESS | 1651 | Values: 88.0 89.0 84.0 83.0 86.0 89.0 85.0 86.0 88.0 85.0 09:18:12 INFO - PROCESS | 1651 | 09:18:12 INFO - PROCESS | 1651 | [#11] stanford-crypto-ccm Cycles:10 Average:157.60 Median:157.00 stddev:11.64 (7.4%) stddev-sans-first:11.29 09:18:12 INFO - PROCESS | 1651 | Values: 171.0 159.0 151.0 155.0 153.0 151.0 142.0 147.0 168.0 179.0 09:18:12 INFO - PROCESS | 1651 | 09:18:12 INFO - PROCESS | 1651 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.20 Median:174.50 stddev:19.14 (11.0%) stddev-sans-first:19.94 09:18:12 INFO - PROCESS | 1651 | Values: 169.0 173.0 169.0 167.0 172.0 175.0 183.0 232.0 174.0 178.0 09:18:12 INFO - PROCESS | 1651 | 09:18:12 INFO - PROCESS | 1651 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.50 Median:68.00 stddev:1.65 (2.4%) stddev-sans-first:1.66 09:18:12 INFO - PROCESS | 1651 | Values: 69.0 66.0 69.0 70.0 66.0 66.0 68.0 68.0 65.0 68.0 09:18:12 INFO - PROCESS | 1651 | -------- Summary: end -------- 09:18:12 INFO - PROCESS | 1651 | 09:18:13 INFO - TEST-INFO | 1651: exit 0 09:18:13 INFO - TEST-OK | kraken | took 61392ms 09:18:13 INFO - SUITE-END | took 1177s 09:18:13 INFO - Completed test suite (00:19:38) 09:18:13 INFO - javascript benchmark 09:18:13 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7304.46763262859, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3163.462211424058, 3372.9156771080707, 3391.7795343302682, 3415.5218269442266, 3428.554287942568, 5112.8240504683945, 5511.614187006911, 5703.087505999041, 5762.423757624236, 5786.971065144674, 4876.585490233334, 5797.82606521804, 5862.50168735658, 5882.558773100596, 5885.970570147143, 4550.294704320836, 5232.16285394338, 5301.920471192916, 5314.990151871151, 5360.91958620621, 5036.622253331015, 5380.408155102936, 5384.434634363374, 5390.5418039466695, 5404.864878378033, 5241.379310344828, 5264.499872512124, 5372.140457526796, 5389.353277606673, 5439.048166570854, 5314.654547454403, 5322.20166974953, 5341.439148889369, 5344.518993290583, 5356.866078348053, 5201.583873290166, 5315.3090098286975, 5337.332833395825, 5358.758855851496, 5393.541548968343, 5881.647101173943, 5963.940360596382, 5975.52195824333, 5982.0, 6006.699665016732, 6186.19579454668, 6236.314005459396, 6278.874422511524, 6321.683915804238, 6352.872942541169, 6244.968775156095, 6313.368663133696, 6320.873582528369, 6325.778597749057, 6356.9364306357265, 4751.64362672801, 5083.74581270935, 6057.636541807506, 6065.878682426371, 6069.119977603223, 6098.786542470993, 6270.184875966095, 6295.18162638854, 6388.648624325614, 6443.387878151568, 6162.414570615784, 6208.348123447064, 6260.530460215502, 6305.653189074553, 6306.211723534559, 5103.923441148386, 6225.346338634468, 6322.082451114709, 6365.777197798055, 6367.076773867763, 6024.186734790835, 6271.43557079865, 6347.428731414103, 6364.42720155179, 6373.649449280241, 6072.423119803611, 6256.718447669866, 6284.214473190851, 6285.465735412449, 6350.809475715643, 4318.52496225415, 5081.491850814963, 5083.212102124176, 5086.898262034739, 5089.643724939218, 4548.454185497695, 4553.453585569687, 4563.133004729091, 4568.109218702321, 4596.862094137181, 5772.509336706343, 5784.768609255667, 5815.563832712563, 5843.678597677169, 5863.384344643836, 6303.338149494329, 6318.905216421757, 6328.841778955563, 6329.050642403677, 6330.588511746721, 5079.771410286546, 5470.483359746403, 5751.942480575224, 5821.7089145543405, 5885.382034886361, 5550.833474995757, 5561.805336813192, 5581.134924086773, 5617.21359009739, 5632.4930756231315, 6444.581102228341, 6481.254655714653, 6483.7082331295205, 6486.189226346801, 6490.253620833664, 5286.7067581056535, 5562.749676264497, 6209.410106039919, 6350.428461438492, 6354.586951848115, 5563.360213575491, 6251.9687401562705, 6359.936400635935, 6422.678866056772, 6492.058651495412, 6275.96862015687, 6287.497000240061, 6303.086052522451, 6330.39861213184, 6340.682965851781, 5346.930613877318, 6260.373962603703, 6318.399752023551, 6343.238811342484, 6343.9682801585695, 4948.886500537495, 6019.127226552037, 6120.447133218882, 6142.262928448615, 6146.985747351637, 6165.414285642857, 6190.0, 6192.380761923771, 6196.946519091855, 6200.720967556381, 6011.23623461751, 6034.0, 6060.696965151813, 6078.240219972504, 6088.238970128734, 6059.697015149313, 6150.477209437069, 6155.6614386209185, 6167.889779839672, 6176.969115154396, 6119.694015299307, 6151.630902145886, 6167.629942203482, 6168.290646575701, 6192.783252586138], "value": 5737.7920246435215, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [455.02170333782385, 458.14555853333525, 497.4254735780175, 498.36209651645885, 510.3696934286156, 39499.40750888739, 41627.0, 41776.37335439967, 41915.79042104793, 44076.898077548074, 42465.57534424662, 42875.571244287545, 42991.78504107475, 42999.570004300025, 43109.35335969963, 7785.805354866117, 8513.404061715682, 9272.350935434522, 9463.621455141785, 9465.858012129824, 8394.412391132593, 11125.0, 11435.771284574344, 11449.942750286236, 11578.53685852565, 11340.829887551694, 11396.544138234462, 11420.086393088533, 11423.257488263278, 11432.028277596415, 11350.886491135065, 11428.999999999916, 11436.771264574745, 11447.942760286229, 11460.197786154931, 11316.434178291134, 11356.943215283953, 11383.0, 11387.943060284728, 11401.0, 40079.19841603152, 40292.992675183064, 40339.798301008304, 40380.394294085614, 40380.798096009334, 3982.820773065219, 4051.392291156321, 4056.350983842571, 4132.752034877917, 4224.070704445048, 28886.277843054093, 29476.41047179066, 29644.851775741197, 29705.40589188204, 29907.700922990927, 3181.34782369616, 3227.9354412911844, 3283.5238890361, 3323.601167859848, 3380.6281309055985, 402489.0, 403586.0, 405041.0, 405560.0, 406080.0, 62021.0, 62029.68985155045, 62207.0, 62240.06639900405, 62327.0, 63870.36129638737, 63923.68038159779, 64399.03401448982, 64416.67791661012, 64604.35395646077, 16268.674626507403, 16780.748288775678, 16793.74046946484, 16797.496075117768, 16807.579810504834, 9471.76320591977, 11937.0, 12649.93675031619, 12914.547990820276, 13042.282674452807, 16503.66992660164, 16552.51027407539, 16570.254338554794, 16608.83391166097, 16651.83348166527, 5230.503102205284, 5359.22291267764, 6308.463780578611, 6359.141515895388, 6411.871762564722, 761.9009528761336, 811.0, 873.5806812729925, 882.5587206396801, 911.116217269261, 24735.87632061828, 30057.549136762966, 30329.93845215451, 30353.24116897095, 31079.689203108133, 1051.7738686182508, 1113.9053180479589, 1129.4352823588206, 1143.5825923538016, 1173.6420391780418, 12878.549250776177, 13086.20921778934, 13160.078794484485, 15601.453949111727, 16021.839781602268, 646979.0, 647880.0, 649023.0, 649911.0, 651782.0, 60228.698856506315, 60428.69785651044, 60491.09263361053, 60492.69753651204, 60553.39446605566, 1421.3390773290469, 1435.5980325508874, 1451.2090910453828, 1452.2520901735404, 1455.1487379882888, 27541.724582753915, 29398.41203175967, 30057.69942300549, 30267.69732302649, 30827.383452331276, 50305.74847125741, 50796.49203507917, 50824.491755081974, 50856.49143508665, 50927.23609145792, 526467.0, 564094.0, 566118.0, 567269.0, 569375.1531242317, 21618.783812161677, 23249.767502325438, 23923.760762392154, 25905.870470647526, 25988.35029124287, 46710.532894671975, 46990.53009469862, 47769.5223047779, 47920.76039619919, 47960.7601961988, 32564.86022989184, 38774.80612596919, 38962.415563767136, 40861.59138408697, 41065.76802695924, 30079.699203008564, 33111.83444082764, 36807.44788828223, 38757.80621096877, 39814.60185398225, 32451.83774081209, 34643.4803477943, 35182.0, 35352.469712954844, 35959.0, 26362.0, 26613.334666632716, 33231.169220769676, 33592.0, 33846.83076584601, 24474.877625611756, 24684.876575617724, 24813.13901457434, 24992.62511062299, 25114.874425627753], "value": 19815.63696107528, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [326.37173441125833, 383.01755995870604, 387.33765261403, 398.1082375478926, 421.80839129459264, 17646.38237661682, 20663.966801659913, 20920.686189707165, 21135.682964755542, 21223.469413264676, 20589.27937522187, 20843.27048553301, 20972.37082887512, 21025.264115755912, 21244.575108497822, 5783.334916484616, 5846.912296315559, 6081.6351018938785, 6110.022396416574, 6164.07538869171, 3966.3257246268204, 4168.916621667565, 6111.541634377417, 6137.693115344215, 6157.661328626924, 5861.7948371807, 6126.142340072372, 6168.814935551918, 6194.96902515489, 6220.875582488347, 6158.784442544512, 6178.907316390258, 6419.743210271584, 6722.226943901465, 6790.32096790322, 5982.970085149546, 6033.969830150865, 6039.1545183674325, 6049.727762250665, 6065.8180254592435, 13153.868461315456, 13154.802677959839, 13286.800697989538, 13341.53304634343, 13437.992150588745, 2520.861352625605, 2668.1061844281994, 2683.610876422927, 2698.5952107183884, 2821.478026565092, 8160.428769986103, 9967.302288839785, 10055.849162262572, 10058.647947321808, 10414.114800241989, 1623.4480276705858, 1879.1719308019, 1881.3509339277928, 1882.5481884347794, 1887.240591012101, 106980.25569105044, 124954.0, 125036.0, 127768.36115819361, 131458.3427082868, 11877.703057423634, 12076.637700868989, 12080.758384832254, 12115.697107572381, 12293.569725059759, 15507.922460387626, 15553.922230388776, 15563.0, 15600.84399155994, 15619.687606247811, 8736.8689469658, 8897.555122243863, 8898.644054237759, 8919.974202966741, 8970.327225458117, 7493.925060749431, 8376.916230837614, 8709.477431354138, 8857.3799834011, 8890.822183556293, 4703.0, 4753.405824271966, 4761.428628564525, 4796.8081276748535, 4820.68665536746, 1680.3866588695282, 2013.4865609269543, 2071.2129390831387, 2071.7513898332295, 2077.3767869639046, 862.9223369896739, 866.5234121233296, 875.8423483772982, 879.2745984562762, 912.0378001208655, 16032.198390080683, 20589.279375221795, 21664.24175153862, 21770.673439898415, 22233.0, 802.0175285275491, 903.4985583001366, 911.9680811171577, 927.4388994658269, 935.2331088507359, 8965.820683586291, 10707.839382409271, 11086.279391839505, 11114.388708621103, 11116.88831116882, 688584.0, 690125.0, 702052.0, 702930.0, 705584.4720776363, 149319.0, 155664.0, 155684.443155567, 157111.0, 159563.0, 928.5218112671979, 1038.413296487467, 1056.392574269798, 1079.2553138334524, 1079.3200283821143, 18557.44327670172, 18749.0, 19019.52451188676, 19106.853588784717, 19325.0, 57890.13164802615, 57931.71034144802, 58086.7095664519, 58615.0, 59126.0, 90112.0, 90733.09266907246, 92172.0, 92998.0, 93591.53204233936, 5566.888662226814, 7490.176080631235, 7525.3227209551405, 7673.769786906401, 7673.8848917267405, 9631.855522167032, 13113.934430327787, 13229.536966206137, 13362.933185334012, 13510.86489135096, 6429.6142231466265, 10693.786124277627, 11005.724856878642, 11376.715582110513, 11644.941775291409, 6855.0, 11088.889111109109, 11185.38480383554, 11258.774824503627, 11418.771624567627, 8680.900035715773, 9903.464962930757, 11496.597619083292, 11529.481173347387, 11565.884341156481, 8225.83548329018, 8399.70601028961, 8399.832003359776, 8479.830403392021, 8621.224089831945, 11017.559297628006, 11219.439028048728, 11333.26333788301, 11400.144989126014, 11487.36819474937], "value": 9617.56979117696, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1353.2827601371273, 1399.5941177058646, 1446.4864972934602, 1448.3192899337312, 1450.282110355375, 7624.27569380908, 7874.370050395969, 8058.63736131874, 8538.957305213473, 8540.444871083375, 8083.110857805636, 8197.795055123624, 8337.16628337166, 8467.237948584627, 8494.447860889039, 2298.333483289848, 2333.988330058356, 2337.439014636492, 2339.438534751656, 2350.4006478347997, 2094.0262777808316, 2177.292379976514, 2320.6751054852334, 2360.0441821062495, 2371.762823717632, 2269.319204238722, 2348.283773449106, 2350.753170917055, 2356.2813341930705, 2356.6229403295474, 2337.2403968710237, 2339.6841426407386, 2350.97649023511, 2356.71719393673, 2358.4929240213264, 2319.791218790317, 2325.3489023073566, 2390.2948630154146, 2394.5091256292417, 2397.3527147670034, 8400.201980811875, 8467.068622451525, 8472.15278472154, 8474.449160804528, 8484.19400156984, 5043.798248070073, 5155.587552995751, 5185.040767458032, 5195.454477279869, 5204.609654275944, 3365.646607106243, 3519.1729943463265, 3594.3350480161253, 3617.1861331200535, 3617.4212126059706, 1190.93940393926, 1195.4620420810584, 1238.6160290310033, 1260.754152940177, 1263.886250237483, 10730.248882578222, 10844.186685998582, 10860.945695271474, 10868.510917008754, 10912.127029837673, 3922.4508568800393, 4525.502194758574, 4651.232546629836, 4694.36626055485, 4706.858794236178, 9414.62341506332, 9447.480388578557, 9455.952720236492, 9470.384425012353, 9602.039796020343, 5198.792048318025, 5242.7116508591635, 5245.396779370421, 5249.580033597303, 5336.493033161843, 4012.8394864205106, 4418.7848341706285, 4770.165221086296, 4793.592544633745, 4814.831480898222, 3879.165979314461, 3903.4535165076913, 3964.900877478086, 4000.079981604189, 4000.899977500586, 1199.076710932577, 1237.2267332916927, 1254.5483625894672, 1270.2061211742662, 1284.5760898903338, 783.8118851475718, 786.7049856303886, 808.1070417189039, 809.0008839083727, 818.1123481023037, 5591.0, 5605.971970140124, 5629.577781666391, 5668.744906479218, 5671.574631902624, 976.1409959235828, 987.1263931420601, 1004.9397036177706, 1008.5511947183579, 1074.8015962202244, 3513.6662017108333, 3654.3056819204267, 3842.5773164951834, 3857.8263978121054, 3888.941665875014, 9686.32195746291, 9944.602215911282, 10027.147692446224, 10043.146332561668, 10049.346792458613, 9763.853542196874, 9876.35803672759, 9884.357516761387, 9927.900720992842, 9948.502574871227, 7372.410207183412, 7717.112532058773, 7730.96134519335, 7733.41999350051, 7884.763457096182, 5809.767609295581, 5850.853728656818, 5864.944310024237, 5899.410058994066, 5950.494207992196, 8973.641054357753, 9119.042500537613, 9193.494357810385, 9215.815683686154, 9223.631054758003, 8758.299336052973, 9011.369204155908, 9139.268858491438, 9195.816083678155, 9216.907830921873, 7211.206767255702, 7273.599952002691, 7322.121345438582, 7349.6325183741665, 7387.630618468947, 7828.2954534092205, 7881.172476890073, 7882.921170788219, 7898.131205567268, 7905.288524032865, 7032.6132062735, 7149.714011439484, 7166.820829479097, 7168.462365322517, 7217.386522145466, 7001.194862590867, 7040.542364746275, 7043.7534686285735, 7105.25394833535, 7184.281571842774, 5500.70733377664, 6349.809505714836, 6442.0014897690935, 6489.707963141765, 6516.218053833571, 5047.671901326402, 5075.061113693979, 5091.923621145612, 5106.285120083266, 5107.131787596043, 4054.493188351456, 4801.4958429364415, 4916.360873086476, 4997.175466048061, 5010.473900240422], "value": 4432.549595523491, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [356.98215089245537, 369.8649992752646, 370.35927844828444, 372.7800597647387, 376.97549659272147, 4034.5763694812063, 4382.342648602711, 5087.40542323415, 5089.872753181167, 5136.94692588019, 5428.45715428457, 5439.401665816757, 5464.3442786865635, 5472.425395333493, 5543.279373681426, 3676.6323367663285, 3832.965099423161, 3867.45855580219, 4141.233871733739, 4190.0, 3780.281746468176, 3862.7875466849314, 4219.050713589449, 4236.406903033578, 4241.936370954438, 3736.308782875177, 4276.358546218061, 4286.39990401344, 4299.742015479065, 4301.956980430203, 3276.5904261967253, 4199.307114326132, 4248.235317642823, 4260.38224457455, 4266.679999000072, 3686.686631636314, 3724.7206459515646, 3740.969814450304, 3749.662530372252, 3758.3798673218885, 4406.818624780722, 4931.65478416511, 5308.283381743454, 5391.838244852661, 5393.440329382589, 4365.7817109144735, 4785.521447855222, 4925.3597032385915, 4945.604351651859, 4962.751862406902, 4827.85516434504, 5771.422857714237, 5820.534357251409, 5828.9417105828825, 5923.437273459014, 4160.479940007499, 4168.749875007509, 4178.078084081326, 4178.7283826551175, 4210.263203939298, 5174.637775355726, 5625.971870140664, 5656.490915817596, 6043.274807023141, 6099.57302988791, 3474.166200111955, 3564.625714299961, 3946.5856085111222, 4220.514640816284, 4250.23495770758, 4323.48118225815, 4347.782610869507, 4349.998501034276, 4359.237133501624, 4393.099414620059, 4106.726914656466, 4902.558769710743, 4936.555709986047, 4959.454460009396, 4962.751862406937, 5343.7060961647485, 5816.73824677882, 5858.736356863951, 5905.557083218776, 5916.355660806246, 1266.2845492296822, 1323.9937647388053, 1386.5718310140571, 1395.3720825628507, 1398.3916996106725, 941.3128416256171, 948.4072454715803, 980.0640388429033, 984.2126298960945, 985.7289245457556, 4417.337399390053, 4640.675152739276, 4884.975575122102, 5089.847304580869, 5142.71715055668, 4923.75381230937, 5732.111522713903, 5787.160861675034, 5794.391588883191, 5821.505172060338, 4887.0170810789095, 4892.0, 4955.90088198234, 5160.697153821558, 5401.756920938567, 2405.846767143397, 2666.6266722658843, 2724.4959682458807, 2888.887778205406, 2916.6500019997734, 5554.22264875236, 5922.289325280994, 5922.378150294157, 5998.580099393001, 6047.8488037798525, 5476.58925580583, 5812.505936995402, 6208.906866397007, 6223.471004964538, 6248.218972628421, 4916.967436838232, 5365.704886231295, 6249.50003999688, 6252.3122456529745, 6263.279722831841, 5607.130894711327, 5927.140564618193, 6062.6359069209075, 6072.937235983597, 6148.907766383597, 5380.569554435558, 5451.64564303319, 5925.911111333419, 5963.373845746308, 6027.306859711014, 5747.0, 5905.822825315247, 5976.223090998143, 6041.697915104315, 6242.282137554058, 3796.228753010373, 3891.3968334908136, 3950.7234493585174, 3986.1031267964477, 3994.4208089827393, 5129.307328581634, 5276.261323414802, 5360.785568577213, 6090.786822461369, 6093.75624974996, 5399.352077750695, 5487.862803429946, 5823.049278491752, 5884.9705751470965, 5916.526677865846, 4977.776000079933, 5901.986649078668, 5903.350631430453, 5907.645541267538, 5910.21746082499, 4750.501197374226, 5249.868753281045, 5332.653377530448, 5351.812686555952, 5800.622959507618, 5003.449620541664, 5010.747313171707, 5792.275965504312, 5831.533477321889, 5919.6152250103605, 4677.485476597503, 4845.466998630078, 5634.380218176079, 5796.739146738322, 5851.215379309351], "value": 4227.021210524521, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [691.5442992500789, 743.4127039638686, 774.8992630957976, 791.2245998921057, 831.8336332733453, 14037.859621403783, 14596.270186490687, 14768.261586920664, 14777.335019924103, 14803.185824779634, 14245.928770356133, 14423.0, 14431.0, 14439.061461005027, 14452.494162704308, 679.8815947765946, 705.1573369823063, 849.8512760266959, 850.9914900850974, 876.1720174435161, 881.8104107616861, 881.8192270584515, 884.6328773558965, 886.8049029213563, 887.8401887660218, 920.5443305563755, 926.9721908342726, 928.3269629518579, 930.8743319651828, 931.3061768982084, 903.2277402820625, 923.0308176414771, 925.4539821505344, 926.3561824531953, 927.1701826864952, 682.3040498691307, 685.9744681700889, 687.5771400588602, 687.9174499060144, 691.7267679266662, 14967.925160374129, 15182.392704291815, 15224.543263702106, 15623.453179138789, 15900.602484937854, 14109.717805643932, 14327.426902923975, 14598.562043138616, 14616.196109213988, 14646.853531464656, 9775.755606109833, 11235.382053987027, 11435.428228588537, 11883.643490695293, 12073.094517911191, 3704.481372607837, 3790.3935370340614, 3802.619738026175, 3919.9216015679813, 3921.705872059607, 93448.59827102599, 93658.0, 93670.53164734201, 94285.52857235739, 94879.52560237223, 11005.17461190414, 11265.605703800327, 11282.7179320516, 11365.0, 11366.829497557543, 19397.4180774577, 23082.422939426648, 23089.307320780405, 23450.0, 23472.882635586713, 7119.1457025157315, 7317.780466586011, 7362.7423040193335, 7435.962820185864, 7507.324340809353, 4167.749935003849, 4202.957970420257, 4233.428487154257, 4238.3777209660575, 4258.957410425856, 2936.3980384021224, 3424.938269136576, 3434.1414646338417, 3475.9826200868833, 3518.736094792901, 500.0, 586.0447470622858, 614.3395849461847, 626.0265287477924, 696.9651517424109, 644.7743289848515, 646.7994921574327, 666.5700623098171, 694.2366389371042, 728.9890651640229, 9204.907950920538, 9928.354656947275, 10045.246606504541, 10461.26771126014, 10758.515866786014, 661.3419647450817, 702.3047183288508, 707.2856415020866, 724.7028718225503, 738.0921466596117, 4231.513375961804, 4968.27959945813, 5044.142495775727, 5049.0, 5051.166557517969, 79291.20708792954, 84739.0, 88724.0, 94555.52722236345, 96293.51853240828, 68249.0, 76327.0, 76574.23425765782, 80832.0, 81811.5909420449, 63556.68221658954, 71489.2851071493, 72560.2743972564, 72931.0, 74990.6250468755, 10552.736181595521, 10980.615678451215, 11145.72135696614, 11172.0, 11208.439578021229, 45984.0, 46365.768171158925, 46924.7653761729, 47364.0, 47696.80757981078, 59828.102578462225, 60943.080023143564, 62487.68756156372, 62875.0, 63213.735725286155, 12576.308303043055, 12917.677058073623, 12947.0, 12978.415971281127, 13179.67050823737, 14083.366248518636, 14563.271836407925, 14564.708705825613, 14771.335289911765, 15453.0, 11776.941115294369, 13306.866931330564, 13581.66045848822, 13796.446203353984, 13809.0, 12788.552400666304, 13497.32513374347, 13554.661133471742, 13579.524716635266, 14293.142411455347, 13344.799828002393, 14013.5095271665, 14163.433462661378, 14183.0, 14211.28943552839, 9580.520973951414, 11400.543978240778, 11499.486852462436, 11604.94197529007, 12142.757144856876, 10894.78210435771, 11175.664730058112, 11217.943910280723, 11218.607348742755, 11224.719382015515], "value": 7412.758881953768, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1562.8999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 118.0, 157.0, 119.0, 117.0, 120.0, 119.0, 121.0, 129.0, 173.0], "value": 127.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 124.0, 143.0, 141.0, 144.0, 104.0, 139.0, 146.0, 170.0, 119.0], "value": 137.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [193.0, 197.0, 196.0, 197.0, 201.0, 197.0, 201.0, 195.0, 197.0, 195.0], "value": 196.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 75.0, 74.0, 75.0, 52.0, 51.0, 52.0, 49.0, 75.0, 84.0], "value": 67.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 72.0, 93.0, 76.0, 61.0, 88.0, 73.0, 77.0, 105.0, 115.0], "value": 84.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 92.0, 129.0, 89.0, 87.0, 137.0, 116.0, 137.0, 132.0], "value": 109.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 99.0, 98.0, 98.0, 109.0, 110.0, 98.0, 172.0, 97.0, 170.0], "value": 114.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 95.0, 91.0, 93.0, 93.0, 93.0, 93.0, 92.0, 93.0, 92.0], "value": 94.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 59.0, 60.0, 61.0, 63.0, 64.0, 64.0, 63.0, 64.0, 62.0], "value": 62.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 74.0, 106.0, 82.0, 79.0, 70.0, 71.0, 74.0, 74.0, 73.0], "value": 77.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 89.0, 84.0, 83.0, 86.0, 89.0, 85.0, 86.0, 88.0, 85.0], "value": 86.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 159.0, 151.0, 155.0, 153.0, 151.0, 142.0, 147.0, 168.0, 179.0], "value": 157.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 173.0, 169.0, 167.0, 172.0, 175.0, 183.0, 232.0, 174.0, 178.0], "value": 179.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 66.0, 69.0, 70.0, 66.0, 66.0, 68.0, 68.0, 65.0, 68.0], "value": 67.5, "unit": "score"}]}]} 09:18:13 INFO - Return code: 0 09:18:13 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:18:13 ERROR - # TBPL SUCCESS # 09:18:13 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:18:13 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:18:13 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:18:13 INFO - 2017-01-30 09:18:13.746 screenresolution[1656:10803] starting screenresolution argv=screenresolution get 09:18:13 INFO - 2017-01-30 09:18:13.749 screenresolution[1656:10803] Display 0: 1600x1200x32@60 09:18:13 INFO - 2017-01-30 09:18:13.755 screenresolution[1657:10806] starting screenresolution argv=screenresolution list 09:18:13 INFO - Available Modes on Display 0 09:18:13 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:18:13 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:18:13 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:18:13 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:18:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:18:13 INFO - Intel Iris: 09:18:13 INFO - Chipset Model: Intel Iris 09:18:13 INFO - Type: GPU 09:18:13 INFO - Bus: Built-In 09:18:13 INFO - VRAM (Dynamic, Max): 1536 MB 09:18:13 INFO - Vendor: Intel (0x8086) 09:18:13 INFO - Device ID: 0x0a2e 09:18:13 INFO - Revision ID: 0x0009 09:18:13 INFO - Displays: 09:18:13 INFO - Display: 09:18:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:18:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:18:13 INFO - Display Serial Number: 1600x1200 60 09:18:13 INFO - Main Display: Yes 09:18:13 INFO - Mirror: Off 09:18:13 INFO - Online: Yes 09:18:13 INFO - Rotation: Supported 09:18:13 INFO - Return code: 0 09:18:13 INFO - Running post-action listener: _resource_record_post_action 09:18:13 INFO - [mozharness: 2017-01-30 17:18:13.994645Z] Finished run-tests step (success) 09:18:13 INFO - Running post-run listener: _resource_record_post_run 09:18:14 INFO - Total resource usage - Wall time: 1199s; CPU: 54.0%; Read bytes: 39856640; Write bytes: 510737408; Read time: 435; Write time: 1821 09:18:14 INFO - TinderboxPrint: CPU usage
54.3% 09:18:14 INFO - TinderboxPrint: I/O read bytes / time
39,856,640 / 435 09:18:14 INFO - TinderboxPrint: I/O write bytes / time
510,737,408 / 1,821 09:18:14 INFO - TinderboxPrint: CPU idle
2,194.0 (45.7%) 09:18:14 INFO - TinderboxPrint: CPU system
967.3 (20.2%) 09:18:14 INFO - TinderboxPrint: CPU user
1,636.4 (34.1%) 09:18:14 INFO - TinderboxPrint: Swap in / out
247,484,416 / 0 09:18:14 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 203239936; Write bytes: 200087040; Read time: 15972; Write time: 445 09:18:14 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 37779968; Write bytes: 299693568; Read time: 412; Write time: 1345 09:18:14 INFO - Running post-run listener: _upload_blobber_files 09:18:14 INFO - Blob upload gear active. 09:18:14 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:18:14 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 09:18:14 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:18:14 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:18:14 INFO - (blobuploader) - INFO - Open directory for files ... 09:18:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:18:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:18:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:18:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:18:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:18:15 INFO - (blobuploader) - INFO - Done attempting. 09:18:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:18:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:18:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:18:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:18:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:18:16 INFO - (blobuploader) - INFO - Done attempting. 09:18:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:18:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:18:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:18:17 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:18:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:18:17 INFO - (blobuploader) - INFO - Done attempting. 09:18:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:18:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:18:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:18:19 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:18:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:18:19 INFO - (blobuploader) - INFO - Done attempting. 09:18:19 INFO - (blobuploader) - INFO - Iteration through files over. 09:18:19 INFO - Return code: 0 09:18:19 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:18:19 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:18:19 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/58635b3add24ae4149a8f38190324c80aa50260744bcc57c143188c90bd2e417eae14776cbcf4348e14000c21b0410db229c8b869c1b27089a95ff012edf448e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/45b647dbdaa9f2db85bdb5737413a7b6fdd600b9419ef4597825e4d37a6821c1e4125a5ec894ccd7f3c174e8bbc6c49ec01d3018e67ebb7dd15550cdee65afc6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9f6bea49084e37f84bf904748dcf15ccf91d206fc6e6b1cde4e2a5cd12281fd0cbd07d21dcc78473c096e98daac79931128f70113b658224fd3952044f3f95c5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3cccc46a2a1377e2bfda9192b4b68b40b638e217f8303833f3a593169fe46135142777045f863e8015c14f8b2727866f028456e1306dd1d8ac6d8cff6d63c0f6"} 09:18:19 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:18:19 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:18:19 INFO - Contents: 09:18:19 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/58635b3add24ae4149a8f38190324c80aa50260744bcc57c143188c90bd2e417eae14776cbcf4348e14000c21b0410db229c8b869c1b27089a95ff012edf448e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/45b647dbdaa9f2db85bdb5737413a7b6fdd600b9419ef4597825e4d37a6821c1e4125a5ec894ccd7f3c174e8bbc6c49ec01d3018e67ebb7dd15550cdee65afc6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9f6bea49084e37f84bf904748dcf15ccf91d206fc6e6b1cde4e2a5cd12281fd0cbd07d21dcc78473c096e98daac79931128f70113b658224fd3952044f3f95c5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3cccc46a2a1377e2bfda9192b4b68b40b638e217f8303833f3a593169fe46135142777045f863e8015c14f8b2727866f028456e1306dd1d8ac6d8cff6d63c0f6"} 09:18:19 INFO - Running post-run listener: copy_logs_to_upload_dir 09:18:19 INFO - Copying logs to upload dir... 09:18:19 INFO - mkdir: /builds/slave/test/build/upload/logs 09:18:19 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1261.390117 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2017-01-30 09:18:20.049218) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-30 09:18:20.054524) ========= 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.eiLQUNmMdi/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.t0Sxc0GO4y/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/58635b3add24ae4149a8f38190324c80aa50260744bcc57c143188c90bd2e417eae14776cbcf4348e14000c21b0410db229c8b869c1b27089a95ff012edf448e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/45b647dbdaa9f2db85bdb5737413a7b6fdd600b9419ef4597825e4d37a6821c1e4125a5ec894ccd7f3c174e8bbc6c49ec01d3018e67ebb7dd15550cdee65afc6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9f6bea49084e37f84bf904748dcf15ccf91d206fc6e6b1cde4e2a5cd12281fd0cbd07d21dcc78473c096e98daac79931128f70113b658224fd3952044f3f95c5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3cccc46a2a1377e2bfda9192b4b68b40b638e217f8303833f3a593169fe46135142777045f863e8015c14f8b2727866f028456e1306dd1d8ac6d8cff6d63c0f6"} build_url:https://queue.taskcluster.net/v1/task/VjQInznPSjyycpy8fVGtLw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010896 build_url: 'https://queue.taskcluster.net/v1/task/VjQInznPSjyycpy8fVGtLw/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/58635b3add24ae4149a8f38190324c80aa50260744bcc57c143188c90bd2e417eae14776cbcf4348e14000c21b0410db229c8b869c1b27089a95ff012edf448e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/45b647dbdaa9f2db85bdb5737413a7b6fdd600b9419ef4597825e4d37a6821c1e4125a5ec894ccd7f3c174e8bbc6c49ec01d3018e67ebb7dd15550cdee65afc6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9f6bea49084e37f84bf904748dcf15ccf91d206fc6e6b1cde4e2a5cd12281fd0cbd07d21dcc78473c096e98daac79931128f70113b658224fd3952044f3f95c5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3cccc46a2a1377e2bfda9192b4b68b40b638e217f8303833f3a593169fe46135142777045f863e8015c14f8b2727866f028456e1306dd1d8ac6d8cff6d63c0f6"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-30 09:18:20.093096) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-30 09:18:20.093416) ========= 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.eiLQUNmMdi/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.t0Sxc0GO4y/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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.004967 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-30 09:18:20.141855) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-30 09:18:20.142174) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-30 09:18:20.142533) ========= ========= Total master_lag: 0.20 =========