builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0300 starttime: 1489094016.25 results: success (0) buildid: 20170309123527 builduid: 0b6ea0bac1fd4f06af281da32673d340 revision: c43a8446c3895718169a90bf2f7d7e85b3182715 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-09 13:13:36.246306) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-09 13:13:36.246734) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-09 13:13:36.247022) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-09 13:13:36.273910) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-09 13:13:36.274313) ========= 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.Ulp28eD4k1/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.TEKBdypweO/Listeners TMPDIR=/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/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-03-09 13:13:36-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.2M=0.001s 2017-03-09 13:13:36 (18.2 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.173717 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-09 13:13:36.474956) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-09 13:13:36.475283) ========= 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.Ulp28eD4k1/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.TEKBdypweO/Listeners TMPDIR=/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/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.026116 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-09 13:13:36.539731) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-09 13:13:36.540082) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev c43a8446c3895718169a90bf2f7d7e85b3182715 --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 c43a8446c3895718169a90bf2f7d7e85b3182715 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Ulp28eD4k1/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.TEKBdypweO/Listeners TMPDIR=/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/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-03-09 13:13:36,648 truncating revision to first 12 chars 2017-03-09 13:13:36,648 Setting DEBUG logging. 2017-03-09 13:13:36,648 attempt 1/10 2017-03-09 13:13:36,649 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/c43a8446c389?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-09 13:13:38,191 unpacking tar archive at: mozilla-aurora-c43a8446c389/testing/mozharness/ program finished with exit code 0 elapsedTime=1.814975 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-09 13:13:38.378801) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-09 13:13:38.379159) ========= script_repo_revision: c43a8446c3895718169a90bf2f7d7e85b3182715 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-09 13:13:38.379556) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-09 13:13:38.379849) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-09 13:13:38.399772) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 23 secs) (at 2017-03-09 13:13:38.400177) ========= /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.Ulp28eD4k1/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.TEKBdypweO/Listeners TMPDIR=/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/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 13:13:38 INFO - MultiFileLogger online at 20170309 13:13:38 in /builds/slave/test 13:13:38 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 13:13:38 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:13:38 INFO - {'append_to_log': False, 13:13:38 INFO - 'base_work_dir': '/builds/slave/test', 13:13:38 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 13:13:38 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:13:38 INFO - 'branch': 'Mozilla-Aurora', 13:13:38 INFO - 'buildbot_json_path': 'buildprops.json', 13:13:38 INFO - 'config_files': ('talos/mac_config.py',), 13:13:38 INFO - 'default_actions': ('clobber', 13:13:38 INFO - 'read-buildbot-config', 13:13:38 INFO - 'download-and-extract', 13:13:38 INFO - 'populate-webroot', 13:13:38 INFO - 'create-virtualenv', 13:13:38 INFO - 'install', 13:13:38 INFO - 'run-tests'), 13:13:38 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:13:38 INFO - 'download_minidump_stackwalk': True, 13:13:38 INFO - 'download_symbols': 'ondemand', 13:13:38 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:13:38 INFO - 'tooltool.py': '/tools/tooltool.py', 13:13:38 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:13:38 INFO - '/tools/misc-python/virtualenv.py')}, 13:13:38 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:13:38 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:13:38 INFO - 'gecko_profile': False, 13:13:38 INFO - 'gecko_profile_interval': 0, 13:13:38 INFO - 'installer_path': 'installer.exe', 13:13:38 INFO - 'log_level': 'info', 13:13:38 INFO - 'log_name': 'talos', 13:13:38 INFO - 'log_to_console': True, 13:13:38 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 13:13:38 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 13:13:38 INFO - 'opt_config_files': (), 13:13:38 INFO - 'pip_index': False, 13:13:38 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:13:38 INFO - 'cmd': ('bash', 13:13:38 INFO - '-c', 13:13:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:13:38 INFO - 'enabled': True, 13:13:38 INFO - 'halt_on_failure': False, 13:13:38 INFO - 'name': 'check_screen_resolution'},), 13:13:38 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:13:38 INFO - 'cmd': ('bash', 13:13:38 INFO - '-c', 13:13:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:13:38 INFO - 'enabled': True, 13:13:38 INFO - 'halt_on_failure': False, 13:13:38 INFO - 'name': 'check_screen_resolution'},), 13:13:38 INFO - 'run_cmd_checks_enabled': True, 13:13:38 INFO - 'suite': 'dromaeojs-e10s', 13:13:38 INFO - 'system_bits': '32', 13:13:38 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:13:38 INFO - 'title': 't-yosemite-r7-0300', 13:13:38 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:13:38 INFO - 'use_talos_json': True, 13:13:38 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:13:38 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:13:38 INFO - 'work_dir': 'build'} 13:13:38 INFO - [mozharness: 2017-03-09 21:13:38.617029Z] Running clobber step. 13:13:38 INFO - Running pre-action listener: _resource_record_pre_action 13:13:38 INFO - Running main action method: clobber 13:13:38 INFO - rmtree: /builds/slave/test/build 13:13:38 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:13:41 INFO - Running post-action listener: _resource_record_post_action 13:13:41 INFO - [mozharness: 2017-03-09 21:13:41.170104Z] Finished clobber step (success) 13:13:41 INFO - [mozharness: 2017-03-09 21:13:41.170298Z] Running read-buildbot-config step. 13:13:41 INFO - Running pre-action listener: _resource_record_pre_action 13:13:41 INFO - Running main action method: read_buildbot_config 13:13:41 INFO - Using buildbot properties: 13:13:41 INFO - { 13:13:41 INFO - "project": "", 13:13:41 INFO - "product": "firefox", 13:13:41 INFO - "script_repo_revision": "production", 13:13:41 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 13:13:41 INFO - "repository": "", 13:13:41 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 13:13:41 INFO - "buildid": "20170309123527", 13:13:41 INFO - "pgo_build": "False", 13:13:41 INFO - "basedir": "/builds/slave/test", 13:13:41 INFO - "buildnumber": 61, 13:13:41 INFO - "slavename": "t-yosemite-r7-0300", 13:13:41 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 13:13:41 INFO - "platform": "yosemite_r7", 13:13:41 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 13:13:41 INFO - "branch": "mozilla-aurora", 13:13:41 INFO - "repo_path": "releases/mozilla-aurora", 13:13:41 INFO - "revision": "c43a8446c3895718169a90bf2f7d7e85b3182715", 13:13:41 INFO - "stage_platform": "macosx64", 13:13:41 INFO - "builduid": "0b6ea0bac1fd4f06af281da32673d340", 13:13:41 INFO - "slavebuilddir": "test" 13:13:41 INFO - } 13:13:41 INFO - Found installer url https://queue.taskcluster.net/v1/task/Qmw2REXvSOGiswuXzXxR_Q/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 13:13:41 INFO - Running post-action listener: _resource_record_post_action 13:13:41 INFO - [mozharness: 2017-03-09 21:13:41.174629Z] Finished read-buildbot-config step (success) 13:13:41 INFO - [mozharness: 2017-03-09 21:13:41.174772Z] Running download-and-extract step. 13:13:41 INFO - Running pre-action listener: _resource_record_pre_action 13:13:41 INFO - Running main action method: download_and_extract 13:13:41 INFO - mkdir: /builds/slave/test/build/tests 13:13:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:13:41 INFO - trying https://queue.taskcluster.net/v1/task/Qmw2REXvSOGiswuXzXxR_Q/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 13:13:41 INFO - Downloading https://queue.taskcluster.net/v1/task/Qmw2REXvSOGiswuXzXxR_Q/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 13:13:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Qmw2REXvSOGiswuXzXxR_Q/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json'}, attempt #1 13:13:41 INFO - Downloaded 1199 bytes. 13:13:41 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 13:13:41 INFO - Using the following test package requirements: 13:13:41 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 13:13:41 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 13:13:41 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 13:13:41 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 13:13:41 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 13:13:41 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 13:13:41 INFO - u'jsshell-mac.zip'], 13:13:41 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 13:13:41 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 13:13:41 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 13:13:41 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 13:13:41 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 13:13:41 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 13:13:41 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 13:13:41 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 13:13:41 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 13:13:41 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 13:13:41 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 13:13:41 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 13:13:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Qmw2REXvSOGiswuXzXxR_Q/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 13:13:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Qmw2REXvSOGiswuXzXxR_Q/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 13:13:41 INFO - Fetch https://queue.taskcluster.net/v1/task/Qmw2REXvSOGiswuXzXxR_Q/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 13:13:43 INFO - Expected file size: 51777865 13:13:43 INFO - Obtained file size: 51777865 13:13:59 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip', u'firefox-54.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 13:13:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Qmw2REXvSOGiswuXzXxR_Q/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 13:13:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Qmw2REXvSOGiswuXzXxR_Q/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 13:13:59 INFO - Fetch https://queue.taskcluster.net/v1/task/Qmw2REXvSOGiswuXzXxR_Q/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 13:14:01 INFO - Expected file size: 51777865 13:14:01 INFO - Obtained file size: 51777865 13:14:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Qmw2REXvSOGiswuXzXxR_Q/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 13:14:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Qmw2REXvSOGiswuXzXxR_Q/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 13:14:16 INFO - Fetch https://queue.taskcluster.net/v1/task/Qmw2REXvSOGiswuXzXxR_Q/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 13:14:17 INFO - Expected file size: 14404424 13:14:17 INFO - Obtained file size: 14404424 13:14:17 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')]} 13:14:17 INFO - trying https://queue.taskcluster.net/v1/task/Qmw2REXvSOGiswuXzXxR_Q/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 13:14:17 INFO - Downloading https://queue.taskcluster.net/v1/task/Qmw2REXvSOGiswuXzXxR_Q/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 13:14:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Qmw2REXvSOGiswuXzXxR_Q/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg'}, attempt #1 13:14:19 INFO - Downloaded 74615869 bytes. 13:14:19 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Qmw2REXvSOGiswuXzXxR_Q/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 13:14:19 INFO - mkdir: /builds/slave/test/properties 13:14:19 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:14:19 INFO - Writing to file /builds/slave/test/properties/build_url 13:14:19 INFO - Contents: 13:14:19 INFO - build_url:https://queue.taskcluster.net/v1/task/Qmw2REXvSOGiswuXzXxR_Q/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 13:14:20 INFO - Running post-action listener: _resource_record_post_action 13:14:20 INFO - Running post-action listener: set_extra_try_arguments 13:14:20 INFO - [mozharness: 2017-03-09 21:14:20.592391Z] Finished download-and-extract step (success) 13:14:20 INFO - [mozharness: 2017-03-09 21:14:20.592544Z] Running populate-webroot step. 13:14:20 INFO - Running pre-action listener: _resource_record_pre_action 13:14:20 INFO - Running main action method: populate_webroot 13:14:20 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:14:20 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:14:20 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:14:20 INFO - '--tppagecycles', 13:14:20 INFO - '7'], 13:14:20 INFO - 'tests': ['tsvgm']}}, 13:14:20 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:14:20 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:14:20 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:14:20 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:14:20 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:14:20 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:14:20 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 13:14:20 INFO - 'talos_options': ['--disable-e10s'], 13:14:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:14:20 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 13:14:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:14:20 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 13:14:20 INFO - 'talos_options': ['--disable-e10s'], 13:14:20 INFO - 'tests': ['damp', 'tps']}, 13:14:20 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 13:14:20 INFO - 'tests': ['damp', 'tps']}, 13:14:20 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:14:20 INFO - 'tests': ['dromaeo_dom']}, 13:14:20 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:14:20 INFO - 'g4': {'talos_options': ['--disable-e10s'], 13:14:20 INFO - 'tests': ['basic_compositor_video']}, 13:14:20 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 13:14:20 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:14:20 INFO - 'tests': ['a11yr', 13:14:20 INFO - 'ts_paint', 13:14:20 INFO - 'tpaint', 13:14:20 INFO - 'sessionrestore', 13:14:20 INFO - 'sessionrestore_no_auto_restore', 13:14:20 INFO - 'tabpaint']}, 13:14:20 INFO - 'other-e10s': {'tests': ['a11yr', 13:14:20 INFO - 'ts_paint', 13:14:20 INFO - 'tpaint', 13:14:20 INFO - 'sessionrestore', 13:14:20 INFO - 'sessionrestore_no_auto_restore', 13:14:20 INFO - 'tabpaint']}, 13:14:20 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:14:20 INFO - 'ts_paint', 13:14:20 INFO - 'tpaint', 13:14:20 INFO - 'sessionrestore', 13:14:20 INFO - 'sessionrestore_no_auto_restore', 13:14:20 INFO - 'tabpaint']}, 13:14:20 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:14:20 INFO - 'ts_paint', 13:14:20 INFO - 'tpaint', 13:14:20 INFO - 'sessionrestore', 13:14:20 INFO - 'sessionrestore_no_auto_restore', 13:14:20 INFO - 'tabpaint']}, 13:14:20 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:14:20 INFO - 'tests': ['a11yr', 13:14:20 INFO - 'ts_paint', 13:14:20 INFO - 'tpaint', 13:14:20 INFO - 'sessionrestore', 13:14:20 INFO - 'sessionrestore_no_auto_restore', 13:14:20 INFO - 'tabpaint']}, 13:14:20 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:14:20 INFO - 'tests': ['a11yr', 13:14:20 INFO - 'ts_paint', 13:14:20 INFO - 'tpaint', 13:14:20 INFO - 'sessionrestore', 13:14:20 INFO - 'sessionrestore_no_auto_restore', 13:14:20 INFO - 'tabpaint']}, 13:14:20 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:14:20 INFO - 'tests': ['tsvgx', 13:14:20 INFO - 'tsvgr_opacity', 13:14:20 INFO - 'tart', 13:14:20 INFO - 'tscrollx', 13:14:20 INFO - 'cart', 13:14:20 INFO - 'tsvg_static']}, 13:14:20 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:14:20 INFO - 'tsvgr_opacity', 13:14:20 INFO - 'tart', 13:14:20 INFO - 'tscrollx', 13:14:20 INFO - 'cart', 13:14:20 INFO - 'tsvg_static']}, 13:14:20 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 13:14:20 INFO - 'talos_options': ['--disable-e10s'], 13:14:20 INFO - 'tests': ['tp5o']}, 13:14:20 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 13:14:20 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 13:14:20 INFO - 'talos_options': ['--disable-e10s', 13:14:20 INFO - '--xperf_path', 13:14:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:14:20 INFO - 'tests': ['tp5n']}, 13:14:20 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 13:14:20 INFO - 'talos_options': ['--xperf_path', 13:14:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:14:20 INFO - 'tests': ['tp5n']}}, 13:14:20 INFO - 'talos.zip': {'path': '', 13:14:20 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:14:20 INFO - Running post-action listener: _resource_record_post_action 13:14:20 INFO - [mozharness: 2017-03-09 21:14:20.610825Z] Finished populate-webroot step (success) 13:14:20 INFO - [mozharness: 2017-03-09 21:14:20.610963Z] Running create-virtualenv step. 13:14:20 INFO - Running pre-action listener: _resource_record_pre_action 13:14:20 INFO - Running main action method: create_virtualenv 13:14:20 INFO - Creating virtualenv /builds/slave/test/build/venv 13:14:20 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 13:14:20 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:14:20 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:14:20 INFO - Using real prefix '/tools/python27' 13:14:20 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:14:21 INFO - Installing distribute.............................................................................................................................................................................................done. 13:14:24 INFO - Installing pip.................done. 13:14:24 INFO - Return code: 0 13:14:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 13:14:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 13:14:24 INFO - Reading from file tmpfile_stdout 13:14:24 INFO - Output received: 13:14:24 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) 13:14:24 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:14:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:14:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:14:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:14:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:14:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:14:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:14:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101a5db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a60768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b2bcd8d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10183bd30>, '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 0x101762db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101830be8>, '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.Ulp28eD4k1/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.TEKBdypweO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/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 13:14:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 13:14:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 13:14:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ulp28eD4k1/Render', 13:14:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:14:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:14:24 INFO - 'HOME': '/Users/cltbld', 13:14:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:14:24 INFO - 'LOGNAME': 'cltbld', 13:14:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:14:24 INFO - 'MOZ_NO_REMOTE': '1', 13:14:24 INFO - 'NO_EM_RESTART': '1', 13:14:24 INFO - 'PAGER': '/bin/cat', 13:14:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:14:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:14:24 INFO - 'PWD': '/builds/slave/test', 13:14:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:14:24 INFO - 'SHELL': '/bin/bash', 13:14:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TEKBdypweO/Listeners', 13:14:24 INFO - 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', 13:14:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:14:24 INFO - 'USER': 'cltbld', 13:14:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:14:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:14:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:14:24 INFO - 'XPC_FLAGS': '0x0', 13:14:24 INFO - 'XPC_SERVICE_NAME': '0', 13:14:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:14:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:14:24 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 13:14:24 INFO - Cleaning up... 13:14:24 INFO - Return code: 0 13:14:24 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:14:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:14:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:14:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:14:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:14:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:14:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:14:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x101a5db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a60768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b2bcd8d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10183bd30>, '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 0x101762db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101830be8>, '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.Ulp28eD4k1/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.TEKBdypweO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/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 13:14:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 13:14:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 13:14:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ulp28eD4k1/Render', 13:14:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:14:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:14:24 INFO - 'HOME': '/Users/cltbld', 13:14:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:14:24 INFO - 'LOGNAME': 'cltbld', 13:14:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:14:24 INFO - 'MOZ_NO_REMOTE': '1', 13:14:24 INFO - 'NO_EM_RESTART': '1', 13:14:24 INFO - 'PAGER': '/bin/cat', 13:14:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:14:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:14:24 INFO - 'PWD': '/builds/slave/test', 13:14:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:14:24 INFO - 'SHELL': '/bin/bash', 13:14:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TEKBdypweO/Listeners', 13:14:24 INFO - 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', 13:14:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:14:24 INFO - 'USER': 'cltbld', 13:14:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:14:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:14:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:14:24 INFO - 'XPC_FLAGS': '0x0', 13:14:24 INFO - 'XPC_SERVICE_NAME': '0', 13:14:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:14:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:14:25 INFO - Downloading/unpacking psutil>=3.1.1 13:14:25 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 13:14:25 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 13:14:25 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 13:14:25 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 13:14:25 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 13:14:25 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 13:14:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:14:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:14:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:14:28 INFO - Installing collected packages: psutil 13:14:28 INFO - Running setup.py install for psutil 13:14:28 INFO - building 'psutil._psutil_osx' extension 13:14:28 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 13:14:29 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 13:14:29 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 13:14:29 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 13:14:29 INFO - building 'psutil._psutil_posix' extension 13:14:29 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 13:14:29 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 13:14:29 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 13:14:29 INFO - ^ 13:14:29 INFO - 1 warning generated. 13:14:29 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 13:14:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:14:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:14:29 INFO - Successfully installed psutil 13:14:29 INFO - Cleaning up... 13:14:29 INFO - Return code: 0 13:14:29 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:14:29 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')]} 13:14:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:14:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:14:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:14:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:14:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:14:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101a5db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a60768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b2bcd8d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10183bd30>, '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 0x101762db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101830be8>, '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.Ulp28eD4k1/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.TEKBdypweO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/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 13:14:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 13:14:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 13:14:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ulp28eD4k1/Render', 13:14:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:14:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:14:30 INFO - 'HOME': '/Users/cltbld', 13:14:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:14:30 INFO - 'LOGNAME': 'cltbld', 13:14:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:14:30 INFO - 'MOZ_NO_REMOTE': '1', 13:14:30 INFO - 'NO_EM_RESTART': '1', 13:14:30 INFO - 'PAGER': '/bin/cat', 13:14:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:14:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:14:30 INFO - 'PWD': '/builds/slave/test', 13:14:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:14:30 INFO - 'SHELL': '/bin/bash', 13:14:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TEKBdypweO/Listeners', 13:14:30 INFO - 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', 13:14:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:14:30 INFO - 'USER': 'cltbld', 13:14:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:14:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:14:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:14:30 INFO - 'XPC_FLAGS': '0x0', 13:14:30 INFO - 'XPC_SERVICE_NAME': '0', 13:14:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:14:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:14:31 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:14:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:14:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:14:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:14:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:14:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:14:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:14:33 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:14:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:14:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:14:34 INFO - Installing collected packages: mozsystemmonitor 13:14:34 INFO - Running setup.py install for mozsystemmonitor 13:14:34 INFO - Successfully installed mozsystemmonitor 13:14:34 INFO - Cleaning up... 13:14:34 INFO - Return code: 0 13:14:34 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 13:14:34 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')]} 13:14:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:14:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:14:34 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')]} 13:14:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:14:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:14:34 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 0x101a5db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a60768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b2bcd8d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10183bd30>, '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 0x101762db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101830be8>, '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.Ulp28eD4k1/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.TEKBdypweO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/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 13:14:34 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 13:14:34 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 13:14:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ulp28eD4k1/Render', 13:14:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:14:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:14:34 INFO - 'HOME': '/Users/cltbld', 13:14:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:14:34 INFO - 'LOGNAME': 'cltbld', 13:14:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:14:34 INFO - 'MOZ_NO_REMOTE': '1', 13:14:34 INFO - 'NO_EM_RESTART': '1', 13:14:34 INFO - 'PAGER': '/bin/cat', 13:14:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:14:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:14:34 INFO - 'PWD': '/builds/slave/test', 13:14:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:14:34 INFO - 'SHELL': '/bin/bash', 13:14:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TEKBdypweO/Listeners', 13:14:34 INFO - 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', 13:14:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:14:34 INFO - 'USER': 'cltbld', 13:14:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:14:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:14:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:14:34 INFO - 'XPC_FLAGS': '0x0', 13:14:34 INFO - 'XPC_SERVICE_NAME': '0', 13:14:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:14:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:14:34 INFO - Downloading/unpacking jsonschema==2.5.1 13:14:34 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 13:14:34 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 13:14:34 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 13:14:34 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 13:14:34 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 13:14:34 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 13:14:37 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:14:37 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 13:14:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:14:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:14:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:14:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:14:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:14:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:14:37 INFO - Downloading functools32-3.2.3-2.tar.gz 13:14:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:14:37 INFO - warning: no files found matching '*.txt' 13:14:37 INFO - no previously-included directories found matching 'build' 13:14:37 INFO - no previously-included directories found matching 'dist' 13:14:37 INFO - no previously-included directories found matching '.git*' 13:14:37 INFO - Installing collected packages: jsonschema, functools32 13:14:37 INFO - Running setup.py install for functools32 13:14:37 INFO - warning: no files found matching '*.txt' 13:14:37 INFO - no previously-included directories found matching 'build' 13:14:37 INFO - no previously-included directories found matching 'dist' 13:14:37 INFO - no previously-included directories found matching '.git*' 13:14:37 INFO - Successfully installed jsonschema functools32 13:14:37 INFO - Cleaning up... 13:14:38 INFO - Return code: 0 13:14:38 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 13:14: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')]} 13:14:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:14:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:14: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')]} 13:14:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:14:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:14:38 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 0x101a5db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a60768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b2bcd8d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10183bd30>, '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 0x101762db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101830be8>, '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.Ulp28eD4k1/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.TEKBdypweO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/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 13:14:38 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 13:14:38 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 13:14:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ulp28eD4k1/Render', 13:14:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:14:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:14:38 INFO - 'HOME': '/Users/cltbld', 13:14:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:14:38 INFO - 'LOGNAME': 'cltbld', 13:14:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:14:38 INFO - 'MOZ_NO_REMOTE': '1', 13:14:38 INFO - 'NO_EM_RESTART': '1', 13:14:38 INFO - 'PAGER': '/bin/cat', 13:14:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:14:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:14:38 INFO - 'PWD': '/builds/slave/test', 13:14:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:14:38 INFO - 'SHELL': '/bin/bash', 13:14:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TEKBdypweO/Listeners', 13:14:38 INFO - 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', 13:14:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:14:38 INFO - 'USER': 'cltbld', 13:14:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:14:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:14:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:14:38 INFO - 'XPC_FLAGS': '0x0', 13:14:38 INFO - 'XPC_SERVICE_NAME': '0', 13:14:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:14:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:14:38 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 13:14:38 INFO - Cleaning up... 13:14:38 INFO - Return code: 0 13:14:38 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:14: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')]} 13:14:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:14:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:14: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')]} 13:14:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:14:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:14:38 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 0x101a5db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a60768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b2bcd8d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10183bd30>, '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 0x101762db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101830be8>, '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.Ulp28eD4k1/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.TEKBdypweO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/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 13:14:38 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 13:14:38 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 13:14:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ulp28eD4k1/Render', 13:14:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:14:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:14:38 INFO - 'HOME': '/Users/cltbld', 13:14:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:14:38 INFO - 'LOGNAME': 'cltbld', 13:14:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:14:38 INFO - 'MOZ_NO_REMOTE': '1', 13:14:38 INFO - 'NO_EM_RESTART': '1', 13:14:38 INFO - 'PAGER': '/bin/cat', 13:14:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:14:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:14:38 INFO - 'PWD': '/builds/slave/test', 13:14:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:14:38 INFO - 'SHELL': '/bin/bash', 13:14:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TEKBdypweO/Listeners', 13:14:38 INFO - 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', 13:14:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:14:38 INFO - 'USER': 'cltbld', 13:14:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:14:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:14:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:14:38 INFO - 'XPC_FLAGS': '0x0', 13:14:38 INFO - 'XPC_SERVICE_NAME': '0', 13:14:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:14:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:14:38 INFO - Downloading/unpacking blobuploader==1.2.4 13:14:38 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 13:14:38 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 13:14:38 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 13:14:38 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 13:14:38 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 13:14:38 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 13:14:41 INFO - Downloading blobuploader-1.2.4.tar.gz 13:14:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:14:41 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:14:41 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 13:14:41 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 13:14:41 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 13:14:41 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 13:14:41 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 13:14:41 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 13:14:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:14:41 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:14:41 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 13:14:41 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 13:14:41 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 13:14:41 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 13:14:41 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 13:14:41 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 13:14:42 INFO - Downloading docopt-0.6.1.tar.gz 13:14:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:14:42 INFO - Installing collected packages: blobuploader, requests, docopt 13:14:42 INFO - Running setup.py install for blobuploader 13:14:42 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:14:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:14:42 INFO - Running setup.py install for requests 13:14:43 INFO - Running setup.py install for docopt 13:14:43 INFO - Successfully installed blobuploader requests docopt 13:14:43 INFO - Cleaning up... 13:14:43 INFO - Return code: 0 13:14:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:14:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:14:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:14:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:14:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:14:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:14:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:14:43 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 0x101a5db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a60768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b2bcd8d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10183bd30>, '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 0x101762db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101830be8>, '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.Ulp28eD4k1/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.TEKBdypweO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/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 13:14:43 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 13:14:43 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 13:14:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ulp28eD4k1/Render', 13:14:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:14:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:14:43 INFO - 'HOME': '/Users/cltbld', 13:14:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:14:43 INFO - 'LOGNAME': 'cltbld', 13:14:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:14:43 INFO - 'MOZ_NO_REMOTE': '1', 13:14:43 INFO - 'NO_EM_RESTART': '1', 13:14:43 INFO - 'PAGER': '/bin/cat', 13:14:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:14:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:14:43 INFO - 'PWD': '/builds/slave/test', 13:14:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:14:43 INFO - 'SHELL': '/bin/bash', 13:14:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TEKBdypweO/Listeners', 13:14:43 INFO - 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', 13:14:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:14:43 INFO - 'USER': 'cltbld', 13:14:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:14:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:14:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:14:43 INFO - 'XPC_FLAGS': '0x0', 13:14:43 INFO - 'XPC_SERVICE_NAME': '0', 13:14:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:14:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:14:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:14:43 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-8cnowN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:14:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:14:43 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-Y3QzCZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:14:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:14:43 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-FZ1iEL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:14:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:14:44 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-nxTZ1M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:14:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:14:44 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-zjoVnn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:14:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:14:44 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-l6GYhL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:14:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:14:44 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-3XHQjp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:14:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:14:44 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-UcCecO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:14:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:14:44 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-88yqlx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:14:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:14:44 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-VbtFC5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:14:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:14:44 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-B0hGmV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:14:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:14:44 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-pmP2QE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:14:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:14:45 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-RMSwOC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:14:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:14:45 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-TmKuOe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:14:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:14:45 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-oA_jyK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:14:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:14:45 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-15EefP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:14:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:14:45 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-AZazdH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:14:45 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:14:45 INFO - Running setup.py install for manifestparser 13:14:45 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:14:45 INFO - Running setup.py install for mozcrash 13:14:45 INFO - Running setup.py install for mozdebug 13:14:45 INFO - Running setup.py install for mozdevice 13:14:46 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:14:46 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:14:46 INFO - Running setup.py install for mozfile 13:14:46 INFO - Running setup.py install for mozhttpd 13:14:46 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:14:46 INFO - Running setup.py install for mozinfo 13:14:46 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:14:46 INFO - Running setup.py install for mozInstall 13:14:46 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:14:46 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:14:46 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:14:46 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:14:46 INFO - Running setup.py install for mozleak 13:14:46 INFO - Running setup.py install for mozlog 13:14:47 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:14:47 INFO - Running setup.py install for moznetwork 13:14:47 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:14:47 INFO - Running setup.py install for mozprocess 13:14:47 INFO - Running setup.py install for mozprofile 13:14:47 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:14:47 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:14:47 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:14:47 INFO - Running setup.py install for mozrunner 13:14:47 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:14:47 INFO - Running setup.py install for mozscreenshot 13:14:47 INFO - Running setup.py install for moztest 13:14:47 INFO - Running setup.py install for mozversion 13:14:48 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:14:48 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:14:48 INFO - Cleaning up... 13:14:48 INFO - Return code: 0 13:14:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:14:48 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')]} 13:14:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:14:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:14:48 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')]} 13:14:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:14:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:14:48 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 0x101a5db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a60768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b2bcd8d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10183bd30>, '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 0x101762db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101830be8>, '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.Ulp28eD4k1/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.TEKBdypweO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/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 13:14:48 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 13:14:48 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 13:14:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ulp28eD4k1/Render', 13:14:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:14:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:14:48 INFO - 'HOME': '/Users/cltbld', 13:14:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:14:48 INFO - 'LOGNAME': 'cltbld', 13:14:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:14:48 INFO - 'MOZ_NO_REMOTE': '1', 13:14:48 INFO - 'NO_EM_RESTART': '1', 13:14:48 INFO - 'PAGER': '/bin/cat', 13:14:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:14:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:14:48 INFO - 'PWD': '/builds/slave/test', 13:14:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:14:48 INFO - 'SHELL': '/bin/bash', 13:14:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TEKBdypweO/Listeners', 13:14:48 INFO - 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', 13:14:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:14:48 INFO - 'USER': 'cltbld', 13:14:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:14:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:14:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:14:48 INFO - 'XPC_FLAGS': '0x0', 13:14:48 INFO - 'XPC_SERVICE_NAME': '0', 13:14:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:14:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:14:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:14:48 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-ZxZUmm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:14:48 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)) 13:14:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:14:48 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-Sr2_oU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:14:48 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)) 13:14:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:14:48 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-1KVDYR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:14:48 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)) 13:14:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:14:48 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-7DfiCw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:14:48 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)) 13:14:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:14:48 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-yHhkld-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:14:48 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)) 13:14:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:14:48 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-z5PTxa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:14:49 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)) 13:14:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:14:49 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-x9dwjh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:14:49 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)) 13:14:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:14:49 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-QMCymn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:14:49 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)) 13:14:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:14:49 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-zJo0Q8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:14:49 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)) 13:14:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:14:49 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-WaH0wC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:14:49 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)) 13:14:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:14:49 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-9ZPaUe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:14:49 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)) 13:14:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:14:49 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-uS1OpT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:14:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 13:14:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:14:49 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-SRo8bm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:14:49 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)) 13:14:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:14:49 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-9630vm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:14:49 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)) 13:14:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:14:49 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-jGx4Wn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:14:50 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)) 13:14:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:14:50 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-p3djXQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:14:50 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)) 13:14:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:14:50 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-EzvEnu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:14:50 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)) 13:14:50 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)) 13:14:50 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)) 13:14:50 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)) 13:14:50 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)) 13:14:50 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)) 13:14:50 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:14:50 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 13:14:50 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 13:14:50 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 13:14:50 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 13:14:50 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 13:14:50 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 13:14:52 INFO - Downloading blessings-1.6.tar.gz 13:14:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:14:53 INFO - Installing collected packages: blessings 13:14:53 INFO - Running setup.py install for blessings 13:14:53 INFO - Successfully installed blessings 13:14:53 INFO - Cleaning up... 13:14:53 INFO - Return code: 0 13:14:53 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:14:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:14:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:14:53 INFO - Reading from file tmpfile_stdout 13:14:53 INFO - Current package versions: 13:14:53 INFO - blessings == 1.6 13:14:53 INFO - blobuploader == 1.2.4 13:14:53 INFO - docopt == 0.6.1 13:14:53 INFO - functools32 == 3.2.3-2 13:14:53 INFO - jsonschema == 2.5.1 13:14:53 INFO - manifestparser == 1.1 13:14:53 INFO - mozInstall == 1.12 13:14:53 INFO - mozcrash == 1.0 13:14:53 INFO - mozdebug == 0.1 13:14:53 INFO - mozdevice == 0.49 13:14:53 INFO - mozfile == 1.2 13:14:53 INFO - mozhttpd == 0.7 13:14:53 INFO - mozinfo == 0.9 13:14:53 INFO - mozleak == 0.1 13:14:53 INFO - mozlog == 3.4 13:14:53 INFO - moznetwork == 0.27 13:14:53 INFO - mozprocess == 0.25 13:14:53 INFO - mozprofile == 0.28 13:14:53 INFO - mozrunner == 6.13 13:14:53 INFO - mozscreenshot == 0.1 13:14:53 INFO - mozsystemmonitor == 0.3 13:14:53 INFO - moztest == 0.8 13:14:53 INFO - mozversion == 1.4 13:14:53 INFO - psutil == 3.1.1 13:14:53 INFO - requests == 1.2.3 13:14:53 INFO - wsgiref == 0.1.2 13:14:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:14:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:14:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:14:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:14:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:14:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:14:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:14:53 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 0x101a5db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a60768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b2bcd8d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10183bd30>, '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 0x101762db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101830be8>, '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.Ulp28eD4k1/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.TEKBdypweO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/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 13:14:53 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 13:14:53 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 13:14:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ulp28eD4k1/Render', 13:14:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:14:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:14:53 INFO - 'HOME': '/Users/cltbld', 13:14:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:14:53 INFO - 'LOGNAME': 'cltbld', 13:14:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:14:53 INFO - 'MOZ_NO_REMOTE': '1', 13:14:53 INFO - 'NO_EM_RESTART': '1', 13:14:53 INFO - 'PAGER': '/bin/cat', 13:14:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:14:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:14:53 INFO - 'PWD': '/builds/slave/test', 13:14:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:14:53 INFO - 'SHELL': '/bin/bash', 13:14:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TEKBdypweO/Listeners', 13:14:53 INFO - 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', 13:14:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:14:53 INFO - 'USER': 'cltbld', 13:14:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:14:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:14:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:14:53 INFO - 'XPC_FLAGS': '0x0', 13:14:53 INFO - 'XPC_SERVICE_NAME': '0', 13:14:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:14:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:14:53 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)) 13:14:53 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)) 13:14:53 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)) 13:14:53 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)) 13:14:53 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)) 13:14:53 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)) 13:14:53 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)) 13:14:53 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)) 13:14:53 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)) 13:14:53 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)) 13:14:53 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)) 13:14:53 INFO - Cleaning up... 13:14:53 INFO - Return code: 0 13:14:53 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:14:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:14:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:14:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:14:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:14:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:14:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:14:53 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 0x101a5db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a60768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b2bcd8d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10183bd30>, '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 0x101762db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101830be8>, '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.Ulp28eD4k1/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.TEKBdypweO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/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 13:14:53 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 13:14:53 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 13:14:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ulp28eD4k1/Render', 13:14:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:14:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:14:53 INFO - 'HOME': '/Users/cltbld', 13:14:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:14:53 INFO - 'LOGNAME': 'cltbld', 13:14:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:14:53 INFO - 'MOZ_NO_REMOTE': '1', 13:14:53 INFO - 'NO_EM_RESTART': '1', 13:14:53 INFO - 'PAGER': '/bin/cat', 13:14:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:14:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:14:53 INFO - 'PWD': '/builds/slave/test', 13:14:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:14:53 INFO - 'SHELL': '/bin/bash', 13:14:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TEKBdypweO/Listeners', 13:14:53 INFO - 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', 13:14:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:14:53 INFO - 'USER': 'cltbld', 13:14:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:14:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:14:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:14:53 INFO - 'XPC_FLAGS': '0x0', 13:14:53 INFO - 'XPC_SERVICE_NAME': '0', 13:14:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:14:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:14:54 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 13:14:54 INFO - Cleaning up... 13:14:54 INFO - Return code: 0 13:14:54 INFO - Running post-action listener: _resource_record_post_action 13:14:54 INFO - Running post-action listener: _start_resource_monitoring 13:14:54 INFO - Starting resource monitoring. 13:14:54 INFO - [mozharness: 2017-03-09 21:14:54.232772Z] Finished create-virtualenv step (success) 13:14:54 INFO - [mozharness: 2017-03-09 21:14:54.233817Z] Running install step. 13:14:54 INFO - Running pre-action listener: _resource_record_pre_action 13:14:54 INFO - Running main action method: install 13:14:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:14:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:14:54 INFO - Reading from file tmpfile_stdout 13:14:54 INFO - Detecting whether we're running mozinstall >=1.0... 13:14:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:14:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:14:54 INFO - Reading from file tmpfile_stdout 13:14:54 INFO - Output received: 13:14:54 INFO - Usage: mozinstall [options] installer 13:14:54 INFO - Options: 13:14:54 INFO - -h, --help show this help message and exit 13:14:54 INFO - -d DEST, --destination=DEST 13:14:54 INFO - Directory to install application into. [default: 13:14:54 INFO - "/builds/slave/test"] 13:14:54 INFO - --app=APP Application being installed. [default: firefox] 13:14:54 INFO - mkdir: /builds/slave/test/build/application 13:14:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 13:14:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 13:15:14 INFO - Reading from file tmpfile_stdout 13:15:14 INFO - Output received: 13:15:14 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 13:15:14 INFO - Running post-action listener: _resource_record_post_action 13:15:14 INFO - [mozharness: 2017-03-09 21:15:14.366028Z] Finished install step (success) 13:15:14 INFO - [mozharness: 2017-03-09 21:15:14.366162Z] Running run-tests step. 13:15:14 INFO - Running pre-action listener: _resource_record_pre_action 13:15:14 INFO - Running main action method: run_tests 13:15:14 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:15:14 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:15:14 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:15:14 INFO - 2017-03-09 13:15:14.380 screenresolution[1626:7401] starting screenresolution argv=screenresolution get 13:15:14 INFO - 2017-03-09 13:15:14.383 screenresolution[1626:7401] Display 0: 1600x1200x32@60 13:15:14 INFO - 2017-03-09 13:15:14.388 screenresolution[1627:7404] starting screenresolution argv=screenresolution list 13:15:14 INFO - Available Modes on Display 0 13:15:14 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:15:14 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:15:14 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:15:14 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:15:14 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:15:14 INFO - Intel Iris: 13:15:14 INFO - Chipset Model: Intel Iris 13:15:14 INFO - Type: GPU 13:15:14 INFO - Bus: Built-In 13:15:14 INFO - VRAM (Dynamic, Max): 1536 MB 13:15:14 INFO - Vendor: Intel (0x8086) 13:15:14 INFO - Device ID: 0x0a2e 13:15:14 INFO - Revision ID: 0x0009 13:15:14 INFO - Displays: 13:15:14 INFO - Display: 13:15:14 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:15:14 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:15:14 INFO - Display Serial Number: 1600x1200 60 13:15:14 INFO - Main Display: Yes 13:15:14 INFO - Mirror: Off 13:15:14 INFO - Online: Yes 13:15:14 INFO - Rotation: Supported 13:15:14 INFO - Return code: 0 13:15:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:15:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:15:14 INFO - Python 2.7.3 13:15:14 INFO - Return code: 0 13:15:14 INFO - grabbing minidump binary from tooltool 13:15:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:15:14 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 0x10183bd30>, '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 0x101762db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101830be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:15:14 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 13:15:14 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 13:15:14 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 13:15:14 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:15:14 INFO - Return code: 0 13:15:14 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 13:15:14 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:15:14 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:15:14 INFO - ENV: RUST_BACKTRACE is now 1 13:15:14 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 13:15:14 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:15:14 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/Qmw2REXvSOGiswuXzXxR_Q/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0300', '--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 13:15:14 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/Qmw2REXvSOGiswuXzXxR_Q/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0300 --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 13:15:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ulp28eD4k1/Render', 13:15:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:15:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:15:14 INFO - 'HOME': '/Users/cltbld', 13:15:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:15:14 INFO - 'LOGNAME': 'cltbld', 13:15:14 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:15:14 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 13:15:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:15:14 INFO - 'MOZ_NO_REMOTE': '1', 13:15:14 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:15:14 INFO - 'NO_EM_RESTART': '1', 13:15:14 INFO - 'PAGER': '/bin/cat', 13:15:14 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:15:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:15:14 INFO - 'PWD': '/builds/slave/test', 13:15:14 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:15:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:15:14 INFO - 'RUST_BACKTRACE': '1', 13:15:14 INFO - 'SHELL': '/bin/bash', 13:15:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TEKBdypweO/Listeners', 13:15:14 INFO - 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', 13:15:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:15:14 INFO - 'USER': 'cltbld', 13:15:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:15:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:15:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:15:14 INFO - 'XPC_FLAGS': '0x0', 13:15:14 INFO - 'XPC_SERVICE_NAME': '0', 13:15:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:15:14 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/Qmw2REXvSOGiswuXzXxR_Q/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0300', '--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 13:15:14 INFO - mozversion application_buildid: 20170309123527 13:15:14 INFO - mozversion application_changeset: c43a8446c3895718169a90bf2f7d7e85b3182715 13:15:14 INFO - mozversion application_display_name: FirefoxDeveloperEdition 13:15:14 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:15:14 INFO - mozversion application_name: Firefox 13:15:14 INFO - mozversion application_remotingname: firefox-dev 13:15:14 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 13:15:14 INFO - mozversion application_vendor: Mozilla 13:15:14 INFO - mozversion application_version: 54.0a2 13:15:14 INFO - mozversion platform_buildid: 20170309123527 13:15:14 INFO - mozversion platform_changeset: c43a8446c3895718169a90bf2f7d7e85b3182715 13:15:14 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 13:15:14 INFO - mozversion platform_version: 54.0a2 13:15:14 INFO - using testdate: 1489094114 13:15:14 INFO - actual date: 1489094114 13:15:14 INFO - starting webserver on 'localhost:49245' 13:15:14 INFO - SUITE-START | Running 2 tests 13:15:14 INFO - TEST-START | dromaeo_css 13:15:14 INFO - operating with platform_type : mac_ 13:15:14 INFO - Initialising browser for dromaeo_css test... 13:15:14 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/tmpZuZ4wM/profile http://localhost:49245/getInfo.html) 13:15:17 INFO - PROCESS | 1637 | 2017-03-09 13:15:17.741 plugin-container[1639:7584] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f3f, name = 'com.apple.tsm.portname' 13:15:17 INFO - PROCESS | 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:15:17 INFO - PROCESS | 1637 | 2017-03-09 13:15:17.743 plugin-container[1639:7584] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x950b, name = 'com.apple.CFPasteboardClient' 13:15:17 INFO - PROCESS | 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:15:17 INFO - PROCESS | 1637 | 2017-03-09 13:15:17.744 plugin-container[1639:7584] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:15:17 INFO - PROCESS | 1637 | __metrics Screen width/height:1600/1200 13:15:17 INFO - PROCESS | 1637 | colorDepth:24 13:15:17 INFO - PROCESS | 1637 | Browser inner width/height: 1024/689 13:15:17 INFO - PROCESS | 1637 | __metrics 13:15:17 ERROR - PROCESS | 1637 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3353: TypeError: this.tabs is undefined 13:15:18 INFO - TEST-INFO | 1637: exit 0 13:15:18 INFO - Browser initialized. 13:15:18 INFO - Running cycle 1/1 for dromaeo_css test... 13:15:18 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/tmpZuZ4wM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:15:21 INFO - PROCESS | 1640 | 2017-03-09 13:15:21.075 plugin-container[1641:7726] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a3f, name = 'com.apple.tsm.portname' 13:15:21 INFO - PROCESS | 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:15:21 INFO - PROCESS | 1640 | 2017-03-09 13:15:21.075 plugin-container[1641:7726] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9003, name = 'com.apple.CFPasteboardClient' 13:15:21 INFO - PROCESS | 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:15:21 INFO - PROCESS | 1640 | 2017-03-09 13:15:21.075 plugin-container[1641:7726] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:15:30 INFO - PROCESS | 1640 | RSS: Main: 220749824 13:15:30 INFO - PROCESS | 1640 | 13:18:34 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49245/tests/dromaeo/cssquery-ext.html) 13:18:36 INFO - PROCESS | 1640 | RSS: Main: 243568640 13:18:36 INFO - PROCESS | 1640 | 13:20:19 INFO - PROCESS | 1640 | ************************* 13:20:19 INFO - PROCESS | 1640 | A coding exception was thrown and uncaught in a Task. 13:20:19 INFO - PROCESS | 1640 | 13:20:19 ERROR - PROCESS | 1640 | Full message: TypeError: NetworkError when attempting to fetch resource. 13:20:19 INFO - PROCESS | 1640 | Full stack: 13:20:19 INFO - PROCESS | 1640 | ************************* 13:21:38 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-ext.html (next: http://localhost:49245/tests/dromaeo/cssquery-jquery.html) 13:21:39 INFO - PROCESS | 1640 | RSS: Main: 252256256 13:21:39 INFO - PROCESS | 1640 | 13:24:41 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49245/tests/dromaeo/cssquery-mootools.html) 13:24:42 INFO - PROCESS | 1640 | RSS: Main: 259768320 13:24:42 INFO - PROCESS | 1640 | 13:27:44 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49245/tests/dromaeo/cssquery-prototype.html) 13:27:44 INFO - PROCESS | 1640 | RSS: Main: 256016384 13:27:44 INFO - PROCESS | 1640 | 13:30:47 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49245/tests/dromaeo/cssquery-yui.html) 13:30:48 INFO - PROCESS | 1640 | RSS: Main: 260980736 13:30:48 INFO - PROCESS | 1640 | 13:33:51 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-yui.html (next: http://localhost:49245/tests/dromaeo/cssquery-dojo.html) 13:33:51 INFO - PROCESS | 1640 | RSS: Main: 257495040 13:33:51 INFO - PROCESS | 1640 | 13:33:51 INFO - PROCESS | 1640 | __start_tp_report 13:33:51 INFO - PROCESS | 1640 | _x_x_mozilla_page_load 13:33:51 INFO - PROCESS | 1640 | _x_x_mozilla_page_load_details 13:33:51 INFO - PROCESS | 1640 | |i|pagename|runs| 13:33:51 INFO - PROCESS | 1640 | |0;dojo.html;3493.4934434506995;3517.103138699631;3554.933519944016;3558.2171922177126;3562.180698439358;6158.59969102008;6167.167432396625;6168.5373596980235;6187.783427580036;6191;5082.669626474277;6153.415425534578;6186.659733714644;6203.193584833965;6206.007038873781;5507.228987941681;5572.192032155355;5583.748731307101;5592.217089607459;5671.6880571568545;5635.8591035224035;5653.710247349826;5662.320521537401;5684.863027394517;5719.941810765001;4725.3384526166365;5435.0488664483755;5448.645838020536;5578.804741834037;5671.971640141794;4789.449213340458;5665.235193248941;5688.11834165705;5728.999999999979;5765.509931655814;5554.833354999357;5622.156676498517;5623.381428042912;5632.127020311858;5663.7451314690525;6006.249218847644;6090.451859332681;6149.169862068608;6225.7509699611965;6227.782027629011;6376.744930202786;6443.323451037667;6462.935370646327;6483.3192514786215;6492;6696.263411024784;6697.397234248892;6712.160979877515;6713.060171575983;6719.596824190564;5350.705711185883;6106.45041946227;6109.083637454329;6285.748570057238;6289.591176573554;6350.809475715643;6722.6302553359055;6764.458843292623;6823.931760682429;6839.931600684029;6460.676966151674;6463.64449955257;6494.480441564663;6499.1876015498065;6511.576747511396;6286;6624.56940298879;6674.932010878333;6687.130673012477;6705.597664140168;5678.432156784289;6291.905621415683;6301.117843501914;6408.647524386203;6464.644444555594;6290.182276304143;6290.276618188876;6552.770653027121;6564.638944857983;6572.474202063823;5208.114620514521;5236.476352364809;5250.921236181461;5303.28405665238;5316.521513063843;4636.072785442925;4660.300954856799;4728.834490792806;4744.217204161275;4771.594414474808;5564.636664017261;6142.877142457126;6227.283862355796;6255.74977000924;6297.622142671455;6676.165479315086;6676.766313179015;6688.899666505006;6702.028205910213;6727.226368967534;5339.492748188916;6046.939530604725;6281.246250449975;6324.083007963912;6325.936740632535;5154.768035438415;5715.714214289269;5755.482006619341;5779.653220806765;5883.441073098049;6842.726290948406;6878.621675807879;6891.27641597625;6897.517173797986;6904.240533541407;6544.4764418845425;6661.500387470862;6662.56693314933;6689.899651505329;6692.665366731547;6386.9680651596445;6524.9021264682015;6625.006249062679;6742.4943129266485;6910.723571057101;6530.902036469552;6549.967250163719;6550.344965503412;6570;6601.438877695257;6595.604263744191;6627.602343859384;6641.800745977628;6641.933580664131;6648.800535983928;6196.977498712842;6225.657588832658;6273.090401891792;6285.088662144055;6374.840628984312;6355.269144048309;6374.107624932606;6376.776812811529;6395.424411802774;6438.67806609677;4446.533114022981;5610.877824435048;6151.753929842756;6174.9691251545255;6285.811425657238;6202.534809889186;6243.594166379171;6300.905486417799;6328.493720502257;6352.0789485525265;6202.131701561695;6226.25284965807;6241.875162496815;6242.406971337716;6266.624002559861 13:33:51 INFO - PROCESS | 1640 | |1;ext.html;755.5995322479084;757.924207579242;830.8836762853201;966.5650457294215;971.6113554578172;38116;39016.63441779535;40713.38929916046;40738.96305184744;41065.58934410655;39034.60965390352;39113;39133.60866391335;39185;39219.60780392195;7935.682572697086;8077.273045425911;10461.633842815503;10794.46027698616;10886.455677216107;10662.360258384482;10786.73033174169;10804.621838235664;10812.783744325108;10824.512896919658;10811.35131892085;10860.511276992554;10870.239083264174;10871.184661150406;10872.293300935453;10782.622608208714;10790.51442685081;10800.621978230723;10811.783764324748;10815.18886083543;10556.261061725681;10632.946835265851;10635.893641063567;10651.360918344924;10663.146948244123;34360.656393435995;34480.48279275813;34485.310293793984;34518.48222276668;34685.13287167816;3750.6624403803517;3795.7532760370486;3803.9619603803885;3810.40938654509;3830.8467661293794;25086.667827211422;25563.616545751644;25684.871575642188;26339.47321053568;26756.331091722666;3067.5552044953356;3096.3962027404664;3135.498320268746;3193.616765988073;3231.434498962672;329056;332351;332375;333144.99999999756;334908;45307.09385812265;45556.77221613871;45593.54406455893;45647;45707.31439028417;36814.631853681654;47332.763336183096;47379.289310660366;47409.81475463141;47465.28802067972;13834.377453014638;14014.159150451005;14083.929580352238;14166.433342666178;14174.007819452745;12029.398530073637;12123.030157587373;12215.206011609216;12215.328156951453;12248.387580620933;11798.587049453228;12068.758624827455;12101.455434505468;12101.69745756363;12159.635210943687;5732.140178973187;5753.043965051903;5797.246357973437;5806.1000544915605;5839.678817665069;1092.5094323636065;1129.711923459529;1141.4007645985923;1155.064397837754;1172.7361343697771;25445.10942117017;25688.486230275288;26120.608190877152;27141.23581967166;27341.45317093647;1255.3221260519217;1257.214755047816;1259.5766783534548;1259.6207153167268;1305.1907817153235;11896.940515297541;13961.79057314141;14040.36818343177;14159.78760318596;14163.221022843842;439450;500934;516794.41602791747;627348;628225;41699.791501042906;42438;50252;50515;50695.49304506907;1420.3111490926892;1435.6841494871112;1447.0666420158939;1447.073872721459;1455.0978393396163;23540.7052612108;27142.592861107492;27603.44793104167;27949.441011179257;28134.71865281321;33818.985430437126;42101.789491053576;42147.7892610535;42301;42442.57557424386;452953;464747;472851.6357418191;473009;474050;24522.63216051725;29967.400651987275;30380.088597342114;30600.846995764878;30705.38589228158;38087;38331.80834095923;38903.710648229;39251.41122883216;39297.80351098226;30198.09405717832;32265.35469290554;32386.83806580952;32889.506657400634;33667.821626243946;31954.20114497156;31965.520517191795;32893.67106328906;32895.177620559676;33073.50389744108;32039.519407208445;32331.838340808146;32838.01485955425;32879.8492052777;33033.50449743208;27857.582136267567;29666.703332966394;29888;29936.401271974002;29946.700532995263;22728.893820213954;22877.08491660315;23026.503277286924;23254;23263.76736232616 13:33:51 INFO - PROCESS | 1640 | |2;jquery.html;402.490849075919;415.2753445238062;415.6778496665086;424.2279052125132;457.37568219380535;19789.703154452676;20387.49031274217;21465.892670536665;21610.351689449304;21711.782882171174;18974.525636859053;21280.297576193905;21590;21892.196909924387;22416.775832241714;5474.72626368682;6364.45902098321;6576.210854697419;6611.735530578771;6646.634435106067;6266.37336266372;6385.201849768779;6560.475161987053;6592.044153597725;6616;6273.7804176853815;6589.242237142742;6599.769008084718;6606.174228221473;6615.702293396785;5778.42215778423;6510.837229069264;6621;6635.071090047397;6652.234992975821;5599.7480113394995;5648.378678345379;6006.069059295816;6348.682565871688;6460.580062295936;25610;26069.348266293306;26109.73890261092;26430.942762289484;26587.202383928514;2715.266877942944;2767.6678798544303;2837.858107094637;2889.4510043091955;2947.0127507285083;15704.136272505008;15840.204620889106;15880.602984925354;16098.356065757356;16700.74948875768;2029.0970518119443;2094.3821572636107;2094.6648536234284;2095.4656562576597;2098.7376577927757;164983;168930;172290;172564.13717931457;175739;19095;19708.21167153298;20879.373618791462;21133.68299475509;21345.466363341042;31803.522947155812;31811;32383.35233295368;32439;32564;9420.293477989178;9731.345671235908;10165.28842980999;10271.640492582725;10464.319819211727;7253.91191321306;7642.159362470124;8297.211764882326;8629.39594228398;8634.740957771277;9480.383775054599;9688.564014619358;9702.320837541305;9727.513624318755;9728.610855565697;3275.4268003099364;3395.9151021224666;4086.4891888513935;4140.254754144283;4168.541460439349;999.6701088640731;1016.1362841584742;1024.800163968019;1034.8344264917578;1065.984010239847;18294.725579116322;20544.38366848997;20634.07146678373;20797.89601051985;20955.8952205238;965.6764983730388;980.7204946590188;1014.2038499777639;1016.2682868334787;1045.3100953370738;9537.23561141191;10569.524371403306;10617.309874858109;10621.84067238992;10628.946855265829;611118;728467;760364;785015;785902;157704;160687;162677;164097;168737;998.2912132385942;1005.5726316315537;1027.8406846938738;1031.370863773113;1034.2139973619953;17762.667799914798;18230.36193733213;18351;18418.447446576625;18431.907840460713;51586.22620660618;51967.74016129895;54060.64848378822;63628.04557931727;66648.3335166642;90166;91349;91565.62651560099;93614.06385936326;93850;11340.716482087684;11469.598564050219;11537.365444900612;11881.405929703307;11918.702032449259;10651.733706657396;12491.562795302121;12572.182808117443;12641.115121941375;12785.552505662257;9817.460039697886;10919.39943303126;11035.062019728413;11166.776664466828;11211.831822522505;10775.568977240822;10899.182561307774;11006.449677515931;11046.558137674403;11277.097832173571;9699.466529340954;10235.948820255851;11207.71980700489;11341.829872552084;11398.316101033965;11319.717007074889;11345.886541134483;11434.82847757301;11576.479058442223;11704.70738231551;10747.193960453154;10918.07196388316;11071.889281107085;11206.43967801623;11264.94367528157 13:33:51 INFO - PROCESS | 1640 | |3;mootools.html;1535.5316628428325;1561.4300780215224;1586.5161125856616;1600.2078970909402;1615.935362585497;9529.094736000077;9851.802963940725;10553.947230263839;11219.551217951272;11489.655310340702;11187.944060279708;11311.717207069827;11334;11368.488418021188;11428.885711142886;2423.503181847717;2443.4135807406183;2449.865257410842;2451.5587194305017;2457.8279520433575;2406.9037238510527;2432.805375569959;2438.3904023994;2446.5106978604263;2452.448199155194;2366.692329997098;2384.701912260967;2385.9284221473295;2391.2826152154285;2394.8682822444757;2374.7150341958904;2387.080973825081;2397.1250493569896;2398.328468028955;2404.4349577849193;2365.491419344832;2371.762823717632;2422.563938491071;2430.0887167312258;2430.647556104355;11287.040601548879;11320.433978301135;11370.260933039408;11375.431228438545;11393.829092563617;5848.649081055108;5993.790217342372;6020.789272375489;6031.758729650809;6041.033434650479;3478.130467383154;3624.2932628137523;3649.27014597079;3664.926701465956;3724.404095344732;1208.0697862645713;1212.2241765270235;1255.3283993063667;1276.923384596918;1281.4233594882246;13232.735345293137;13999.30003499831;14219;15199.240037998166;15516.689666206725;4523.977380113079;4728.881777955578;4806.7836947336755;4904.092742842514;4925.6305777066145;9251.722448326562;11877.406129693481;11937.88062119385;11995.280283183038;11997.700057498632;5463.098588732894;5583.274174357308;5978.581499295007;5982.192404025488;5985.191999080155;4359.433273674402;5285.233641122016;5330.706811125426;5425.511703946585;5461.481159289861;3982.4424580558743;4019.3569028955812;4019.9397009044887;4081.163361510883;4081.9795901020307;925.6760133953062;1085.9565617375374;1086.1148164246272;1099.719571509276;1099.8735145458215;765.1927216786344;783.9451238413257;793.0047790023484;800.1038836503154;803.2529747335035;6404.3595640436515;6551.44312733423;6587.463339464097;6653.1018312527185;6716.731330746813;949.6913503111516;950.4392408479031;962.6438217859437;991.4299277915228;999.4503023337136;3890.3386424307937;4133.318002529549;4246.63903568194;4248.681348898845;4286.292761694285;11129.499172537255;13974.860251397558;14056.156630602196;14108.929455352658;14110.435582576582;13479.258640774851;13522.93238533801;13819.861801382058;13931.58205253844;13988.370523326475;10148.340357876714;10222.693319200436;10241.129503992095;10316.896831031594;10365.740856478498;6482.740690372521;6556.868862622626;6565.638889861103;6606.372394622503;6654.73381064752;11996.040316774463;12113.878861211628;12163.513459461523;12310.692232693897;12372.07209459312;12405.131640785059;12551.309677967434;12636.55772047974;12743.362831858185;12806.67983300425;7469.58917259556;8665;8671.262942649693;8736.519491427778;8759.824803504021;10222.437765922648;10464.63373781914;10494;10495.947520262655;10521.789564208826;8984;9142.314326425678;9209.861852072358;9218.308626853148;9230.400023998418;8971.013188549385;8992.595333210153;9040.186383225542;9072.591733372146;9194.770130746785;7486.775396738107;7869.0950540688555;7963.442559020813;7974.362051035787;7976.122626511195;5944.702764861826;5953.880922381441;5994.940050599613;6032.245969253843;6175.475084617858;5836.970815145897;6033.336333003454;6096.603720758137;6117.3576774439825;6198.529448220832 13:33:51 INFO - PROCESS | 1640 | |4;prototype.html;319.34693551686803;370.894295125889;378.3435738992849;386.24681870352816;390.4299722405286;4185.288500954837;4799.760011999403;4827.975860120694;4924.384451943507;5273.683578985264;4963.319807304051;5071.7210553419545;5163.366682991673;5316.76074576644;5339.172428273624;4640.512746161647;4680.812767489297;4690.1557719610455;4706.741129237907;4736.644751643623;4120.361343991671;4122.876313710579;4389.253826849427;4510.549429686293;4753.882837533177;3913.7651740895635;3972.0408295819657;4129.0271004876595;4351.150760926594;4403.744932694182;3772.566154892181;4422.5356337584735;4427.22523558376;4453.977730111361;4460.1525710114975;3421.5894092709036;3735.3276410246144;3899.908025752794;4104.932717493473;4110.445089912883;4141.834326626931;4933.161362568372;5180.948190518122;5225.921611175835;5254.605904557136;4314.697971142022;4679.8534359081905;4793.544613261734;5120.487951204887;5129.1271494139;5371.785128594852;5414.91877621836;5715.799947001877;5772.451617096369;5792.391798861143;4151.989807389648;4487.4324911695285;4585.679002469828;4587.151376995266;4605;5201.9479805201845;5348.679079255218;5641.661500309994;5848.707564621752;5963.880722385528;4145.336746120666;4632.7994202753425;4634.791434385461;4658.34783130362;4680.204365257914;4267.594578515036;4277.165952639206;4385.495667998157;4703.858884233478;4731.380493922153;4349.369341445473;4588.426446694163;4622.560856718606;4837.685550439211;5055.747212639353;5346.893062138814;5546.889062218814;5669.015844454408;5679;5720.884427536674;1471.4849802569015;1578.9605259868595;1585.5481187861403;1591.801024871891;1619.4979556337573;1089.9727506812394;1112.7941330853819;1167.2937872587133;1206.9215500992407;1216.3431746856597;5285.154375299933;5287.762050707727;5299.284596579412;5361.820399512101;5365.3293338332705;5929.495992840657;5948.405159484104;5951.3751056139345;5971.491973371195;5973.522118230531;4570.7257564546235;4779.040593356707;4826.565609095198;5301.761420736076;5359.124306492697;2631.7630713564595;3061.387722455473;3070.186400603842;3075.3080556874525;3080.3993221322075;4791.353167322437;5714.200011998323;5781.219535362757;5911.39898444683;6085.96957015221;5448.820471811228;5675.687837168995;5863.696526234316;5988.520918326523;6064.332923378425;5286.827903258014;5347.502699244218;5656.575756818255;5710.857228569236;5856.238688970407;4909.459959404534;5469.644473109394;5483.670979741229;5549.168774304389;5565.5825813063375;5288.894222115613;5635.196737044106;5780;5818.883622327615;5834.445727655866;5348.411674715856;5596.70535080615;5648.322201335866;5904.261967254093;5928.347881733092;3949.9407508887966;4031.0852345135827;4283.850065247701;4335.891602709958;4610.904950030303;5050.444451110448;5258.973705131603;5505.339359276913;5636.633618814764;5891.58758886891;5647;5671;5684.63049901755;5698.971505142614;5745.1956726059225;4687.507811679725;4751.596114330182;5315.255864179096;5331.360236771612;5693.377387444559;5205.6356055075785;5515.669059856422;5526.314474085442;5565.499105080562;5615.438456154352;4845.297431872288;5575.414581469049;5625.115586278479;5735.369109398046;5763.94236057634;4926.8768280793265;5059.468755780737;5647.764176867349;5661.150827375926;5665.685573939127 13:33:51 INFO - PROCESS | 1640 | |5;yui.html;752.5973604121796;759.9278068583486;780.9140994490601;796.8604895000151;841.9663213471459;15024.39902403904;15788.921055394736;16217.675646487063;16234.918825405872;16520.173991300417;14796.926015369934;15015.324310406031;15133.394664213445;15281.159536225503;15340.46308379207;920.3143657974801;952.8856537215544;954.2270760683869;954.8329042417583;955.2167222877243;930.502181332987;957.3968399908048;959.1942768074816;972.9708108756713;974.8635191073255;1020.1379834040224;1024.073213741565;1024.3853687787305;1024.472396715692;1024.8923862994352;1002.4636819301638;1015.8679371681708;1020.1838529176666;1022.3150489172273;1026.471367245869;755.7997130760353;756.2437562437563;756.5649751392971;758.7154816943646;760.1068744225513;15938.601534961603;16269.186540673038;16481.423150189687;16873.831261687348;17114.94620645518;14272.14367137975;14301.713965720732;14326.71346573073;14348.713025739427;14541.49104781338;11751.177417580773;11852.585159519462;11891.643250702493;11970.40147992597;12498.81251781215;3655.0685013520724;3710.5176327077306;3758.530183727034;3842.769433833979;3852.8651497197466;114547.56357309294;119987.40006300087;121047;122770.38614806869;123007;10569.207309451822;10690.78618427627;10764.677059688222;11086.891310868848;11232.045276151455;23354.532909341717;23412.180573679838;23455.41361465977;23466.64800028001;23523;7688.538687678757;7716.151223365426;7723.7682869514;7821.413393995473;8043.356531477468;4152.356384760367;4361.760103194291;4390.648748100144;4412.33814927757;4450.976275456598;3328.683775041367;3348.4809854472596;3427.365937301557;3434.6050204226335;3436.2783815398548;617.6781687189418;643.4434214404506;643.7521554201666;645.1129696667083;654.9017647352936;557.4536953785232;559.351152662909;560.1765404854857;567.4325674325675;583.7985894866264;9362;9550;9747.610095596097;10200.38797672142;10311.329763565343;628.1018144053955;628.8239292997968;665.6039656404431;674.5042393840523;684.5037347922815;4618.49196588375;4670.136024835417;4867.9269810952865;4928.433230178503;4931.605471562267;88916.5554172225;93811;107378;111220;112907.43546282216;96936.0306396927;97318.5134074325;98979;99040;101408.98591014143;85582.5720871404;85753;86911.69632455519;87816.56091719629;89143;9529.077038830961;9701;9709.70870873875;10677.199210059121;11222.887771122185;36439;40408.78773636796;49305.26042109443;51043;51838.740806295726;56131.03541376032;68604.97092543516;70264.94602581067;70697.64651176712;72695;12751.171173873669;13085.08404411682;13299.734005319646;13399;13743.518976835763;15067.397304107713;15257.389704411698;15297.6175595611;16020.679586407974;16050;13167.47330106785;13847;14046.438142474186;14616.269186540418;14965.476208332657;13684.65788355299;13774.931125344308;13856.653583660489;14013.789793153315;14525.491607793676;13332.86667133355;13845.515406960709;14012.299385030503;14125.011249212457;14481.275936203358;11048.944755276172;11269.267497612629;11457.713557161138;11665.416729163677;11742.530298788295;10697.3046751964;11269.830952535554;11588.710282243012;11594.826077608674;11647.6505704829 13:33:51 INFO - PROCESS | 1640 | __end_tp_report 13:33:51 INFO - PROCESS | 1640 | __start_cc_report 13:33:51 INFO - PROCESS | 1640 | _x_x_mozilla_cycle_collect,416 13:33:51 INFO - PROCESS | 1640 | __end_cc_report 13:33:51 INFO - PROCESS | 1640 | __startTimestamp1489095231701__endTimestamp 13:33:51 INFO - PROCESS | 1640 | 13:33:51 INFO - PROCESS | 1640 | ------- Summary: start ------- 13:33:51 INFO - PROCESS | 1640 | Number of tests: 6 13:33:51 INFO - PROCESS | 1640 | 13:33:51 INFO - PROCESS | 1640 | [#0] dojo.html Cycles:165 Average:6060.85 Median:6266.62 stddev:702.73 (11.2%) stddev-sans-first:675.41 13:33:51 INFO - PROCESS | 1640 | Values: 3493.5 3517.1 3554.9 3558.2 3562.2 6158.6 6167.2 6168.5 6187.8 6191.0 5082.7 6153.4 6186.7 6203.2 6206.0 5507.2 5572.2 5583.7 5592.2 5671.7 5635.9 5653.7 5662.3 5684.9 5719.9 4725.3 5435.0 5448.6 5578.8 5672.0 4789.4 5665.2 5688.1 5729.0 5765.5 5554.8 5622.2 5623.4 5632.1 5663.7 6006.2 6090.5 6149.2 6225.8 6227.8 6376.7 6443.3 6462.9 6483.3 6492.0 6696.3 6697.4 6712.2 6713.1 6719.6 5350.7 6106.5 6109.1 6285.7 6289.6 6350.8 6722.6 6764.5 6823.9 6839.9 6460.7 6463.6 6494.5 6499.2 6511.6 6286.0 6624.6 6674.9 6687.1 6705.6 5678.4 6291.9 6301.1 6408.6 6464.6 6290.2 6290.3 6552.8 6564.6 6572.5 5208.1 5236.5 5250.9 5303.3 5316.5 4636.1 4660.3 4728.8 4744.2 4771.6 5564.6 6142.9 6227.3 6255.7 6297.6 6676.2 6676.8 6688.9 6702.0 6727.2 5339.5 6046.9 6281.2 6324.1 6325.9 5154.8 5715.7 5755.5 5779.7 5883.4 6842.7 6878.6 6891.3 6897.5 6904.2 6544.5 6661.5 6662.6 6689.9 6692.7 6387.0 6524.9 6625.0 6742.5 6910.7 6530.9 6550.0 6550.3 6570.0 6601.4 6595.6 6627.6 6641.8 6641.9 6648.8 6197.0 6225.7 6273.1 6285.1 6374.8 6355.3 6374.1 6376.8 6395.4 6438.7 4446.5 5610.9 6151.8 6175.0 6285.8 6202.5 6243.6 6300.9 6328.5 6352.1 6202.1 6226.3 6241.9 6242.4 6266.6 13:33:51 INFO - PROCESS | 1640 | 13:33:51 INFO - PROCESS | 1640 | [#1] ext.html Cycles:180 Average:57045.69 Median:3115.95 stddev:122190.21 (3921.4%) stddev-sans-first:122459.90 13:33:51 INFO - PROCESS | 1640 | Values: 755.6 757.9 830.9 966.6 971.6 38116.0 39016.6 40713.4 40739.0 41065.6 39034.6 39113.0 39133.6 39185.0 39219.6 7935.7 8077.3 10461.6 10794.5 10886.5 10662.4 10786.7 10804.6 10812.8 10824.5 10811.4 10860.5 10870.2 10871.2 10872.3 10782.6 10790.5 10800.6 10811.8 10815.2 10556.3 10632.9 10635.9 10651.4 10663.1 34360.7 34480.5 34485.3 34518.5 34685.1 3750.7 3795.8 3804.0 3810.4 3830.8 25086.7 25563.6 25684.9 26339.5 26756.3 3067.6 3096.4 3135.5 3193.6 3231.4 329056.0 332351.0 332375.0 333145.0 334908.0 45307.1 45556.8 45593.5 45647.0 45707.3 36814.6 47332.8 47379.3 47409.8 47465.3 13834.4 14014.2 14083.9 14166.4 14174.0 12029.4 12123.0 12215.2 12215.3 12248.4 11798.6 12068.8 12101.5 12101.7 12159.6 5732.1 5753.0 5797.2 5806.1 5839.7 1092.5 1129.7 1141.4 1155.1 1172.7 25445.1 25688.5 26120.6 27141.2 27341.5 1255.3 1257.2 1259.6 1259.6 1305.2 11896.9 13961.8 14040.4 14159.8 14163.2 439450.0 500934.0 516794.4 627348.0 628225.0 41699.8 42438.0 50252.0 50515.0 50695.5 1420.3 1435.7 1447.1 1447.1 1455.1 23540.7 27142.6 27603.4 27949.4 28134.7 33819.0 42101.8 42147.8 42301.0 42442.6 452953.0 464747.0 472851.6 473009.0 474050.0 24522.6 29967.4 30380.1 30600.8 30705.4 38087.0 38331.8 38903.7 39251.4 39297.8 30198.1 32265.4 32386.8 32889.5 33667.8 31954.2 31965.5 32893.7 32895.2 33073.5 32039.5 32331.8 32838.0 32879.8 33033.5 27857.6 29666.7 29888.0 29936.4 29946.7 22728.9 22877.1 23026.5 23254.0 23263.8 13:33:51 INFO - PROCESS | 1640 | 13:33:51 INFO - PROCESS | 1640 | [#2] jquery.html Cycles:180 Average:43173.48 Median:21527.95 stddev:123861.84 (575.4%) stddev-sans-first:124167.67 13:33:51 INFO - PROCESS | 1640 | Values: 402.5 415.3 415.7 424.2 457.4 19789.7 20387.5 21465.9 21610.4 21711.8 18974.5 21280.3 21590.0 21892.2 22416.8 5474.7 6364.5 6576.2 6611.7 6646.6 6266.4 6385.2 6560.5 6592.0 6616.0 6273.8 6589.2 6599.8 6606.2 6615.7 5778.4 6510.8 6621.0 6635.1 6652.2 5599.7 5648.4 6006.1 6348.7 6460.6 25610.0 26069.3 26109.7 26430.9 26587.2 2715.3 2767.7 2837.9 2889.5 2947.0 15704.1 15840.2 15880.6 16098.4 16700.7 2029.1 2094.4 2094.7 2095.5 2098.7 164983.0 168930.0 172290.0 172564.1 175739.0 19095.0 19708.2 20879.4 21133.7 21345.5 31803.5 31811.0 32383.4 32439.0 32564.0 9420.3 9731.3 10165.3 10271.6 10464.3 7253.9 7642.2 8297.2 8629.4 8634.7 9480.4 9688.6 9702.3 9727.5 9728.6 3275.4 3395.9 4086.5 4140.3 4168.5 999.7 1016.1 1024.8 1034.8 1066.0 18294.7 20544.4 20634.1 20797.9 20955.9 965.7 980.7 1014.2 1016.3 1045.3 9537.2 10569.5 10617.3 10621.8 10628.9 611118.0 728467.0 760364.0 785015.0 785902.0 157704.0 160687.0 162677.0 164097.0 168737.0 998.3 1005.6 1027.8 1031.4 1034.2 17762.7 18230.4 18351.0 18418.4 18431.9 51586.2 51967.7 54060.6 63628.0 66648.3 90166.0 91349.0 91565.6 93614.1 93850.0 11340.7 11469.6 11537.4 11881.4 11918.7 10651.7 12491.6 12572.2 12641.1 12785.6 9817.5 10919.4 11035.1 11166.8 11211.8 10775.6 10899.2 11006.4 11046.6 11277.1 9699.5 10235.9 11207.7 11341.8 11398.3 11319.7 11345.9 11434.8 11576.5 11704.7 10747.2 10918.1 11071.9 11206.4 11264.9 13:33:51 INFO - PROCESS | 1640 | 13:33:51 INFO - PROCESS | 1640 | [#3] mootools.html Cycles:180 Average:6745.03 Median:2967.98 stddev:4182.92 (140.9%) stddev-sans-first:4176.34 13:33:51 INFO - PROCESS | 1640 | Values: 1535.5 1561.4 1586.5 1600.2 1615.9 9529.1 9851.8 10553.9 11219.6 11489.7 11187.9 11311.7 11334.0 11368.5 11428.9 2423.5 2443.4 2449.9 2451.6 2457.8 2406.9 2432.8 2438.4 2446.5 2452.4 2366.7 2384.7 2385.9 2391.3 2394.9 2374.7 2387.1 2397.1 2398.3 2404.4 2365.5 2371.8 2422.6 2430.1 2430.6 11287.0 11320.4 11370.3 11375.4 11393.8 5848.6 5993.8 6020.8 6031.8 6041.0 3478.1 3624.3 3649.3 3664.9 3724.4 1208.1 1212.2 1255.3 1276.9 1281.4 13232.7 13999.3 14219.0 15199.2 15516.7 4524.0 4728.9 4806.8 4904.1 4925.6 9251.7 11877.4 11937.9 11995.3 11997.7 5463.1 5583.3 5978.6 5982.2 5985.2 4359.4 5285.2 5330.7 5425.5 5461.5 3982.4 4019.4 4019.9 4081.2 4082.0 925.7 1086.0 1086.1 1099.7 1099.9 765.2 783.9 793.0 800.1 803.3 6404.4 6551.4 6587.5 6653.1 6716.7 949.7 950.4 962.6 991.4 999.5 3890.3 4133.3 4246.6 4248.7 4286.3 11129.5 13974.9 14056.2 14108.9 14110.4 13479.3 13522.9 13819.9 13931.6 13988.4 10148.3 10222.7 10241.1 10316.9 10365.7 6482.7 6556.9 6565.6 6606.4 6654.7 11996.0 12113.9 12163.5 12310.7 12372.1 12405.1 12551.3 12636.6 12743.4 12806.7 7469.6 8665.0 8671.3 8736.5 8759.8 10222.4 10464.6 10494.0 10495.9 10521.8 8984.0 9142.3 9209.9 9218.3 9230.4 8971.0 8992.6 9040.2 9072.6 9194.8 7486.8 7869.1 7963.4 7974.4 7976.1 5944.7 5953.9 5994.9 6032.2 6175.5 5837.0 6033.3 6096.6 6117.4 6198.5 13:33:51 INFO - PROCESS | 1640 | 13:33:51 INFO - PROCESS | 1640 | [#4] prototype.html Cycles:180 Average:4699.27 Median:5065.59 stddev:1308.73 (25.8%) stddev-sans-first:1270.44 13:33:51 INFO - PROCESS | 1640 | Values: 319.3 370.9 378.3 386.2 390.4 4185.3 4799.8 4828.0 4924.4 5273.7 4963.3 5071.7 5163.4 5316.8 5339.2 4640.5 4680.8 4690.2 4706.7 4736.6 4120.4 4122.9 4389.3 4510.5 4753.9 3913.8 3972.0 4129.0 4351.2 4403.7 3772.6 4422.5 4427.2 4454.0 4460.2 3421.6 3735.3 3899.9 4104.9 4110.4 4141.8 4933.2 5180.9 5225.9 5254.6 4314.7 4679.9 4793.5 5120.5 5129.1 5371.8 5414.9 5715.8 5772.5 5792.4 4152.0 4487.4 4585.7 4587.2 4605.0 5201.9 5348.7 5641.7 5848.7 5963.9 4145.3 4632.8 4634.8 4658.3 4680.2 4267.6 4277.2 4385.5 4703.9 4731.4 4349.4 4588.4 4622.6 4837.7 5055.7 5346.9 5546.9 5669.0 5679.0 5720.9 1471.5 1579.0 1585.5 1591.8 1619.5 1090.0 1112.8 1167.3 1206.9 1216.3 5285.2 5287.8 5299.3 5361.8 5365.3 5929.5 5948.4 5951.4 5971.5 5973.5 4570.7 4779.0 4826.6 5301.8 5359.1 2631.8 3061.4 3070.2 3075.3 3080.4 4791.4 5714.2 5781.2 5911.4 6086.0 5448.8 5675.7 5863.7 5988.5 6064.3 5286.8 5347.5 5656.6 5710.9 5856.2 4909.5 5469.6 5483.7 5549.2 5565.6 5288.9 5635.2 5780.0 5818.9 5834.4 5348.4 5596.7 5648.3 5904.3 5928.3 3949.9 4031.1 4283.9 4335.9 4610.9 5050.4 5259.0 5505.3 5636.6 5891.6 5647.0 5671.0 5684.6 5699.0 5745.2 4687.5 4751.6 5315.3 5331.4 5693.4 5205.6 5515.7 5526.3 5565.5 5615.4 4845.3 5575.4 5625.1 5735.4 5763.9 4926.9 5059.5 5647.8 5661.2 5665.7 13:33:51 INFO - PROCESS | 1640 | 13:33:51 INFO - PROCESS | 1640 | [#5] yui.html Cycles:180 Average:21549.21 Median:23461.03 stddev:31661.69 (135.0%) stddev-sans-first:31712.00 13:33:51 INFO - PROCESS | 1640 | Values: 752.6 759.9 780.9 796.9 842.0 15024.4 15788.9 16217.7 16234.9 16520.2 14796.9 15015.3 15133.4 15281.2 15340.5 920.3 952.9 954.2 954.8 955.2 930.5 957.4 959.2 973.0 974.9 1020.1 1024.1 1024.4 1024.5 1024.9 1002.5 1015.9 1020.2 1022.3 1026.5 755.8 756.2 756.6 758.7 760.1 15938.6 16269.2 16481.4 16873.8 17114.9 14272.1 14301.7 14326.7 14348.7 14541.5 11751.2 11852.6 11891.6 11970.4 12498.8 3655.1 3710.5 3758.5 3842.8 3852.9 114547.6 119987.4 121047.0 122770.4 123007.0 10569.2 10690.8 10764.7 11086.9 11232.0 23354.5 23412.2 23455.4 23466.6 23523.0 7688.5 7716.2 7723.8 7821.4 8043.4 4152.4 4361.8 4390.6 4412.3 4451.0 3328.7 3348.5 3427.4 3434.6 3436.3 617.7 643.4 643.8 645.1 654.9 557.5 559.4 560.2 567.4 583.8 9362.0 9550.0 9747.6 10200.4 10311.3 628.1 628.8 665.6 674.5 684.5 4618.5 4670.1 4867.9 4928.4 4931.6 88916.6 93811.0 107378.0 111220.0 112907.4 96936.0 97318.5 98979.0 99040.0 101409.0 85582.6 85753.0 86911.7 87816.6 89143.0 9529.1 9701.0 9709.7 10677.2 11222.9 36439.0 40408.8 49305.3 51043.0 51838.7 56131.0 68605.0 70264.9 70697.6 72695.0 12751.2 13085.1 13299.7 13399.0 13743.5 15067.4 15257.4 15297.6 16020.7 16050.0 13167.5 13847.0 14046.4 14616.3 14965.5 13684.7 13774.9 13856.7 14013.8 14525.5 13332.9 13845.5 14012.3 14125.0 14481.3 11048.9 11269.3 11457.7 11665.4 11742.5 10697.3 11269.8 11588.7 11594.8 11647.7 13:33:51 INFO - PROCESS | 1640 | -------- Summary: end -------- 13:33:51 INFO - PROCESS | 1640 | 13:33:52 INFO - TEST-INFO | 1640: exit 0 13:33:52 INFO - TEST-OK | dromaeo_css | took 1117393ms 13:33:52 INFO - TEST-START | kraken 13:33:52 INFO - operating with platform_type : mac_ 13:33:52 INFO - Initialising browser for kraken test... 13:33:52 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/tmpSvaI42/profile http://localhost:49245/getInfo.html) 13:33:55 INFO - PROCESS | 1653 | 2017-03-09 13:33:55.075 plugin-container[1654:10215] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893f, name = 'com.apple.tsm.portname' 13:33:55 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:33:55 INFO - PROCESS | 1653 | 2017-03-09 13:33:55.079 plugin-container[1654:10215] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 13:33:55 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:33:55 INFO - PROCESS | 1653 | 2017-03-09 13:33:55.080 plugin-container[1654:10215] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:33:55 INFO - PROCESS | 1653 | __metrics Screen width/height:1600/1200 13:33:55 INFO - PROCESS | 1653 | colorDepth:24 13:33:55 INFO - PROCESS | 1653 | Browser inner width/height: 1024/689 13:33:55 INFO - PROCESS | 1653 | __metrics 13:33:55 ERROR - PROCESS | 1653 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3353: TypeError: this.tabs is undefined 13:33:55 INFO - TEST-INFO | 1653: exit 0 13:33:55 INFO - Browser initialized. 13:33:55 INFO - Running cycle 1/1 for kraken test... 13:33:55 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/tmpSvaI42/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:33:58 INFO - PROCESS | 1655 | 2017-03-09 13:33:58.222 plugin-container[1656:10344] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x454b, name = 'com.apple.tsm.portname' 13:33:58 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:33:58 INFO - PROCESS | 1655 | 2017-03-09 13:33:58.222 plugin-container[1656:10344] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9013, name = 'com.apple.CFPasteboardClient' 13:33:58 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:33:58 INFO - PROCESS | 1655 | 2017-03-09 13:33:58.222 plugin-container[1656:10344] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:34:07 INFO - PROCESS | 1655 | RSS: Main: 220217344 13:34:07 INFO - PROCESS | 1655 | 13:34:11 INFO - PROCESS | 1655 | 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) 13:34:12 INFO - PROCESS | 1655 | RSS: Main: 234655744 13:34:12 INFO - PROCESS | 1655 | 13:34:14 INFO - PROCESS | 1655 | 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) 13:34:15 INFO - PROCESS | 1655 | RSS: Main: 242892800 13:34:15 INFO - PROCESS | 1655 | 13:34:18 INFO - PROCESS | 1655 | 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) 13:34:19 INFO - PROCESS | 1655 | RSS: Main: 246939648 13:34:19 INFO - PROCESS | 1655 | 13:34:21 INFO - PROCESS | 1655 | 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) 13:34:21 INFO - PROCESS | 1655 | RSS: Main: 247054336 13:34:21 INFO - PROCESS | 1655 | 13:34:23 INFO - PROCESS | 1655 | 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) 13:34:24 INFO - PROCESS | 1655 | RSS: Main: 247099392 13:34:24 INFO - PROCESS | 1655 | 13:34:27 INFO - PROCESS | 1655 | 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) 13:34:28 INFO - PROCESS | 1655 | RSS: Main: 250855424 13:34:28 INFO - PROCESS | 1655 | 13:34:31 INFO - PROCESS | 1655 | 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) 13:34:32 INFO - PROCESS | 1655 | RSS: Main: 251785216 13:34:32 INFO - PROCESS | 1655 | 13:34:35 INFO - PROCESS | 1655 | 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) 13:34:36 INFO - PROCESS | 1655 | RSS: Main: 251658240 13:34:36 INFO - PROCESS | 1655 | 13:34:37 INFO - PROCESS | 1655 | 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) 13:34:38 INFO - PROCESS | 1655 | RSS: Main: 251359232 13:34:38 INFO - PROCESS | 1655 | 13:34:40 INFO - PROCESS | 1655 | 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) 13:34:41 INFO - PROCESS | 1655 | RSS: Main: 251101184 13:34:41 INFO - PROCESS | 1655 | 13:34:43 INFO - PROCESS | 1655 | 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) 13:34:43 INFO - PROCESS | 1655 | RSS: Main: 251191296 13:34:43 INFO - PROCESS | 1655 | 13:34:46 INFO - PROCESS | 1655 | 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) 13:34:47 INFO - PROCESS | 1655 | RSS: Main: 251682816 13:34:47 INFO - PROCESS | 1655 | 13:34:50 INFO - PROCESS | 1655 | 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) 13:34:51 INFO - PROCESS | 1655 | RSS: Main: 251437056 13:34:51 INFO - PROCESS | 1655 | 13:34:53 INFO - PROCESS | 1655 | 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) 13:34:53 INFO - PROCESS | 1655 | RSS: Main: 254734336 13:34:53 INFO - PROCESS | 1655 | 13:34:53 INFO - PROCESS | 1655 | __start_tp_report 13:34:53 INFO - PROCESS | 1655 | _x_x_mozilla_page_load 13:34:53 INFO - PROCESS | 1655 | _x_x_mozilla_page_load_details 13:34:53 INFO - PROCESS | 1655 | |i|pagename|runs| 13:34:53 INFO - PROCESS | 1655 | |0;ai-astar;139;172;142;120;120;121;118;118;119;119 13:34:53 INFO - PROCESS | 1655 | |1;audio-beat-detection;169;135;124;144;111;113;110;112;132;111 13:34:53 INFO - PROCESS | 1655 | |2;audio-dft;219;269;243;195;196;202;198;199;196;199 13:34:53 INFO - PROCESS | 1655 | |3;audio-fft;84;58;61;94;105;68;60;58;74;62 13:34:53 INFO - PROCESS | 1655 | |4;audio-oscillator;82;70;84;73;72;56;91;96;56;95 13:34:53 INFO - PROCESS | 1655 | |5;imaging-gaussian-blur;90;94;91;90;130;130;107;90;91;104 13:34:53 INFO - PROCESS | 1655 | |6;imaging-darkroom;147;97;98;98;97;127;97;104;102;140 13:34:53 INFO - PROCESS | 1655 | |7;imaging-desaturate;91;89;90;90;91;90;94;143;142;89 13:34:53 INFO - PROCESS | 1655 | |8;json-parse-financial;67;67;65;66;71;68;70;70;70;70 13:34:53 INFO - PROCESS | 1655 | |9;json-stringify-tinderbox;57;62;58;60;58;57;54;57;58;57 13:34:53 INFO - PROCESS | 1655 | |10;stanford-crypto-aes;82;94;99;101;87;87;85;79;86;99 13:34:53 INFO - PROCESS | 1655 | |11;stanford-crypto-ccm;147;159;149;147;151;151;158;157;215;147 13:34:53 INFO - PROCESS | 1655 | |12;stanford-crypto-pbkdf2;167;165;167;197;155;220;158;218;223;178 13:34:53 INFO - PROCESS | 1655 | |13;stanford-crypto-sha256-iterative;66;65;65;67;66;67;67;66;64;66 13:34:53 INFO - PROCESS | 1655 | __end_tp_report 13:34:53 INFO - PROCESS | 1655 | __start_cc_report 13:34:53 INFO - PROCESS | 1655 | _x_x_mozilla_cycle_collect,996 13:34:53 INFO - PROCESS | 1655 | __end_cc_report 13:34:53 INFO - PROCESS | 1655 | __startTimestamp1489095293383__endTimestamp 13:34:53 INFO - PROCESS | 1655 | 13:34:53 INFO - PROCESS | 1655 | ------- Summary: start ------- 13:34:53 INFO - PROCESS | 1655 | Number of tests: 14 13:34:53 INFO - PROCESS | 1655 | 13:34:53 INFO - PROCESS | 1655 | [#0] ai-astar Cycles:10 Average:128.80 Median:120.50 stddev:17.59 (14.6%) stddev-sans-first:18.27 13:34:53 INFO - PROCESS | 1655 | Values: 139.0 172.0 142.0 120.0 120.0 121.0 118.0 118.0 119.0 119.0 13:34:53 INFO - PROCESS | 1655 | 13:34:53 INFO - PROCESS | 1655 | [#1] audio-beat-detection Cycles:10 Average:126.10 Median:128.00 stddev:19.34 (15.1%) stddev-sans-first:12.85 13:34:53 INFO - PROCESS | 1655 | Values: 169.0 135.0 124.0 144.0 111.0 113.0 110.0 112.0 132.0 111.0 13:34:53 INFO - PROCESS | 1655 | 13:34:53 INFO - PROCESS | 1655 | [#2] audio-dft Cycles:10 Average:211.60 Median:200.50 stddev:25.15 (12.5%) stddev-sans-first:26.53 13:34:53 INFO - PROCESS | 1655 | Values: 219.0 269.0 243.0 195.0 196.0 202.0 198.0 199.0 196.0 199.0 13:34:53 INFO - PROCESS | 1655 | 13:34:53 INFO - PROCESS | 1655 | [#3] audio-fft Cycles:10 Average:72.40 Median:65.00 stddev:16.64 (25.6%) stddev-sans-first:17.11 13:34:53 INFO - PROCESS | 1655 | Values: 84.0 58.0 61.0 94.0 105.0 68.0 60.0 58.0 74.0 62.0 13:34:53 INFO - PROCESS | 1655 | 13:34:53 INFO - PROCESS | 1655 | [#4] audio-oscillator Cycles:10 Average:77.50 Median:83.00 stddev:14.62 (17.6%) stddev-sans-first:15.42 13:34:53 INFO - PROCESS | 1655 | Values: 82.0 70.0 84.0 73.0 72.0 56.0 91.0 96.0 56.0 95.0 13:34:53 INFO - PROCESS | 1655 | 13:34:53 INFO - PROCESS | 1655 | [#5] imaging-gaussian-blur Cycles:10 Average:101.70 Median:90.00 stddev:16.10 (17.9%) stddev-sans-first:16.52 13:34:53 INFO - PROCESS | 1655 | Values: 90.0 94.0 91.0 90.0 130.0 130.0 107.0 90.0 91.0 104.0 13:34:53 INFO - PROCESS | 1655 | 13:34:53 INFO - PROCESS | 1655 | [#6] imaging-darkroom Cycles:10 Average:110.70 Median:97.00 stddev:19.57 (20.2%) stddev-sans-first:15.75 13:34:53 INFO - PROCESS | 1655 | Values: 147.0 97.0 98.0 98.0 97.0 127.0 97.0 104.0 102.0 140.0 13:34:53 INFO - PROCESS | 1655 | 13:34:53 INFO - PROCESS | 1655 | [#7] imaging-desaturate Cycles:10 Average:100.90 Median:90.00 stddev:21.97 (24.4%) stddev-sans-first:23.01 13:34:53 INFO - PROCESS | 1655 | Values: 91.0 89.0 90.0 90.0 91.0 90.0 94.0 143.0 142.0 89.0 13:34:53 INFO - PROCESS | 1655 | 13:34:53 INFO - PROCESS | 1655 | [#8] json-parse-financial Cycles:10 Average:68.40 Median:70.00 stddev:2.07 (3.0%) stddev-sans-first:2.13 13:34:53 INFO - PROCESS | 1655 | Values: 67.0 67.0 65.0 66.0 71.0 68.0 70.0 70.0 70.0 70.0 13:34:53 INFO - PROCESS | 1655 | 13:34:53 INFO - PROCESS | 1655 | [#9] json-stringify-tinderbox Cycles:10 Average:57.80 Median:58.00 stddev:2.10 (3.6%) stddev-sans-first:2.20 13:34:53 INFO - PROCESS | 1655 | Values: 57.0 62.0 58.0 60.0 58.0 57.0 54.0 57.0 58.0 57.0 13:34:53 INFO - PROCESS | 1655 | 13:34:53 INFO - PROCESS | 1655 | [#10] stanford-crypto-aes Cycles:10 Average:89.90 Median:87.00 stddev:7.77 (8.9%) stddev-sans-first:7.69 13:34:53 INFO - PROCESS | 1655 | Values: 82.0 94.0 99.0 101.0 87.0 87.0 85.0 79.0 86.0 99.0 13:34:53 INFO - PROCESS | 1655 | 13:34:53 INFO - PROCESS | 1655 | [#11] stanford-crypto-ccm Cycles:10 Average:158.10 Median:154.00 stddev:20.53 (13.3%) stddev-sans-first:21.38 13:34:53 INFO - PROCESS | 1655 | Values: 147.0 159.0 149.0 147.0 151.0 151.0 158.0 157.0 215.0 147.0 13:34:53 INFO - PROCESS | 1655 | 13:34:53 INFO - PROCESS | 1655 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:184.80 Median:187.50 stddev:27.14 (14.5%) stddev-sans-first:28.01 13:34:53 INFO - PROCESS | 1655 | Values: 167.0 165.0 167.0 197.0 155.0 220.0 158.0 218.0 223.0 178.0 13:34:53 INFO - PROCESS | 1655 | 13:34:53 INFO - PROCESS | 1655 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.90 Median:66.00 stddev:0.99 (1.5%) stddev-sans-first:1.05 13:34:53 INFO - PROCESS | 1655 | Values: 66.0 65.0 65.0 67.0 66.0 67.0 67.0 66.0 64.0 66.0 13:34:53 INFO - PROCESS | 1655 | -------- Summary: end -------- 13:34:53 INFO - PROCESS | 1655 | 13:34:54 INFO - TEST-INFO | 1655: exit 0 13:34:54 INFO - TEST-OK | kraken | took 61901ms 13:34:54 INFO - SUITE-END | took 1179s 13:34:54 INFO - Completed test suite (00:19:39) 13:34:54 INFO - javascript benchmark 13:34:54 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7756.729602465073, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3493.4934434506995, 3517.103138699631, 3554.933519944016, 3558.2171922177126, 3562.180698439358, 6158.59969102008, 6167.167432396625, 6168.5373596980235, 6187.783427580036, 6191.0, 5082.669626474277, 6153.415425534578, 6186.659733714644, 6203.193584833965, 6206.007038873781, 5507.228987941681, 5572.192032155355, 5583.748731307101, 5592.217089607459, 5671.6880571568545, 5635.8591035224035, 5653.710247349826, 5662.320521537401, 5684.863027394517, 5719.941810765001, 4725.3384526166365, 5435.0488664483755, 5448.645838020536, 5578.804741834037, 5671.971640141794, 4789.449213340458, 5665.235193248941, 5688.11834165705, 5728.999999999979, 5765.509931655814, 5554.833354999357, 5622.156676498517, 5623.381428042912, 5632.127020311858, 5663.7451314690525, 6006.249218847644, 6090.451859332681, 6149.169862068608, 6225.7509699611965, 6227.782027629011, 6376.744930202786, 6443.323451037667, 6462.935370646327, 6483.3192514786215, 6492.0, 6696.263411024784, 6697.397234248892, 6712.160979877515, 6713.060171575983, 6719.596824190564, 5350.705711185883, 6106.45041946227, 6109.083637454329, 6285.748570057238, 6289.591176573554, 6350.809475715643, 6722.6302553359055, 6764.458843292623, 6823.931760682429, 6839.931600684029, 6460.676966151674, 6463.64449955257, 6494.480441564663, 6499.1876015498065, 6511.576747511396, 6286.0, 6624.56940298879, 6674.932010878333, 6687.130673012477, 6705.597664140168, 5678.432156784289, 6291.905621415683, 6301.117843501914, 6408.647524386203, 6464.644444555594, 6290.182276304143, 6290.276618188876, 6552.770653027121, 6564.638944857983, 6572.474202063823, 5208.114620514521, 5236.476352364809, 5250.921236181461, 5303.28405665238, 5316.521513063843, 4636.072785442925, 4660.300954856799, 4728.834490792806, 4744.217204161275, 4771.594414474808, 5564.636664017261, 6142.877142457126, 6227.283862355796, 6255.74977000924, 6297.622142671455, 6676.165479315086, 6676.766313179015, 6688.899666505006, 6702.028205910213, 6727.226368967534, 5339.492748188916, 6046.939530604725, 6281.246250449975, 6324.083007963912, 6325.936740632535, 5154.768035438415, 5715.714214289269, 5755.482006619341, 5779.653220806765, 5883.441073098049, 6842.726290948406, 6878.621675807879, 6891.27641597625, 6897.517173797986, 6904.240533541407, 6544.4764418845425, 6661.500387470862, 6662.56693314933, 6689.899651505329, 6692.665366731547, 6386.9680651596445, 6524.9021264682015, 6625.006249062679, 6742.4943129266485, 6910.723571057101, 6530.902036469552, 6549.967250163719, 6550.344965503412, 6570.0, 6601.438877695257, 6595.604263744191, 6627.602343859384, 6641.800745977628, 6641.933580664131, 6648.800535983928, 6196.977498712842, 6225.657588832658, 6273.090401891792, 6285.088662144055, 6374.840628984312, 6355.269144048309, 6374.107624932606, 6376.776812811529, 6395.424411802774, 6438.67806609677, 4446.533114022981, 5610.877824435048, 6151.753929842756, 6174.9691251545255, 6285.811425657238, 6202.534809889186, 6243.594166379171, 6300.905486417799, 6328.493720502257, 6352.0789485525265, 6202.131701561695, 6226.25284965807, 6241.875162496815, 6242.406971337716, 6266.624002559861], "value": 6017.262204572736, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [755.5995322479084, 757.924207579242, 830.8836762853201, 966.5650457294215, 971.6113554578172, 38116.0, 39016.63441779535, 40713.38929916046, 40738.96305184744, 41065.58934410655, 39034.60965390352, 39113.0, 39133.60866391335, 39185.0, 39219.60780392195, 7935.682572697086, 8077.273045425911, 10461.633842815503, 10794.46027698616, 10886.455677216107, 10662.360258384482, 10786.73033174169, 10804.621838235664, 10812.783744325108, 10824.512896919658, 10811.35131892085, 10860.511276992554, 10870.239083264174, 10871.184661150406, 10872.293300935453, 10782.622608208714, 10790.51442685081, 10800.621978230723, 10811.783764324748, 10815.18886083543, 10556.261061725681, 10632.946835265851, 10635.893641063567, 10651.360918344924, 10663.146948244123, 34360.656393435995, 34480.48279275813, 34485.310293793984, 34518.48222276668, 34685.13287167816, 3750.6624403803517, 3795.7532760370486, 3803.9619603803885, 3810.40938654509, 3830.8467661293794, 25086.667827211422, 25563.616545751644, 25684.871575642188, 26339.47321053568, 26756.331091722666, 3067.5552044953356, 3096.3962027404664, 3135.498320268746, 3193.616765988073, 3231.434498962672, 329056.0, 332351.0, 332375.0, 333144.99999999756, 334908.0, 45307.09385812265, 45556.77221613871, 45593.54406455893, 45647.0, 45707.31439028417, 36814.631853681654, 47332.763336183096, 47379.289310660366, 47409.81475463141, 47465.28802067972, 13834.377453014638, 14014.159150451005, 14083.929580352238, 14166.433342666178, 14174.007819452745, 12029.398530073637, 12123.030157587373, 12215.206011609216, 12215.328156951453, 12248.387580620933, 11798.587049453228, 12068.758624827455, 12101.455434505468, 12101.69745756363, 12159.635210943687, 5732.140178973187, 5753.043965051903, 5797.246357973437, 5806.1000544915605, 5839.678817665069, 1092.5094323636065, 1129.711923459529, 1141.4007645985923, 1155.064397837754, 1172.7361343697771, 25445.10942117017, 25688.486230275288, 26120.608190877152, 27141.23581967166, 27341.45317093647, 1255.3221260519217, 1257.214755047816, 1259.5766783534548, 1259.6207153167268, 1305.1907817153235, 11896.940515297541, 13961.79057314141, 14040.36818343177, 14159.78760318596, 14163.221022843842, 439450.0, 500934.0, 516794.41602791747, 627348.0, 628225.0, 41699.791501042906, 42438.0, 50252.0, 50515.0, 50695.49304506907, 1420.3111490926892, 1435.6841494871112, 1447.0666420158939, 1447.073872721459, 1455.0978393396163, 23540.7052612108, 27142.592861107492, 27603.44793104167, 27949.441011179257, 28134.71865281321, 33818.985430437126, 42101.789491053576, 42147.7892610535, 42301.0, 42442.57557424386, 452953.0, 464747.0, 472851.6357418191, 473009.0, 474050.0, 24522.63216051725, 29967.400651987275, 30380.088597342114, 30600.846995764878, 30705.38589228158, 38087.0, 38331.80834095923, 38903.710648229, 39251.41122883216, 39297.80351098226, 30198.09405717832, 32265.35469290554, 32386.83806580952, 32889.506657400634, 33667.821626243946, 31954.20114497156, 31965.520517191795, 32893.67106328906, 32895.177620559676, 33073.50389744108, 32039.519407208445, 32331.838340808146, 32838.01485955425, 32879.8492052777, 33033.50449743208, 27857.582136267567, 29666.703332966394, 29888.0, 29936.401271974002, 29946.700532995263, 22728.893820213954, 22877.08491660315, 23026.503277286924, 23254.0, 23263.76736232616], "value": 18466.711798035714, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [402.490849075919, 415.2753445238062, 415.6778496665086, 424.2279052125132, 457.37568219380535, 19789.703154452676, 20387.49031274217, 21465.892670536665, 21610.351689449304, 21711.782882171174, 18974.525636859053, 21280.297576193905, 21590.0, 21892.196909924387, 22416.775832241714, 5474.72626368682, 6364.45902098321, 6576.210854697419, 6611.735530578771, 6646.634435106067, 6266.37336266372, 6385.201849768779, 6560.475161987053, 6592.044153597725, 6616.0, 6273.7804176853815, 6589.242237142742, 6599.769008084718, 6606.174228221473, 6615.702293396785, 5778.42215778423, 6510.837229069264, 6621.0, 6635.071090047397, 6652.234992975821, 5599.7480113394995, 5648.378678345379, 6006.069059295816, 6348.682565871688, 6460.580062295936, 25610.0, 26069.348266293306, 26109.73890261092, 26430.942762289484, 26587.202383928514, 2715.266877942944, 2767.6678798544303, 2837.858107094637, 2889.4510043091955, 2947.0127507285083, 15704.136272505008, 15840.204620889106, 15880.602984925354, 16098.356065757356, 16700.74948875768, 2029.0970518119443, 2094.3821572636107, 2094.6648536234284, 2095.4656562576597, 2098.7376577927757, 164983.0, 168930.0, 172290.0, 172564.13717931457, 175739.0, 19095.0, 19708.21167153298, 20879.373618791462, 21133.68299475509, 21345.466363341042, 31803.522947155812, 31811.0, 32383.35233295368, 32439.0, 32564.0, 9420.293477989178, 9731.345671235908, 10165.28842980999, 10271.640492582725, 10464.319819211727, 7253.91191321306, 7642.159362470124, 8297.211764882326, 8629.39594228398, 8634.740957771277, 9480.383775054599, 9688.564014619358, 9702.320837541305, 9727.513624318755, 9728.610855565697, 3275.4268003099364, 3395.9151021224666, 4086.4891888513935, 4140.254754144283, 4168.541460439349, 999.6701088640731, 1016.1362841584742, 1024.800163968019, 1034.8344264917578, 1065.984010239847, 18294.725579116322, 20544.38366848997, 20634.07146678373, 20797.89601051985, 20955.8952205238, 965.6764983730388, 980.7204946590188, 1014.2038499777639, 1016.2682868334787, 1045.3100953370738, 9537.23561141191, 10569.524371403306, 10617.309874858109, 10621.84067238992, 10628.946855265829, 611118.0, 728467.0, 760364.0, 785015.0, 785902.0, 157704.0, 160687.0, 162677.0, 164097.0, 168737.0, 998.2912132385942, 1005.5726316315537, 1027.8406846938738, 1031.370863773113, 1034.2139973619953, 17762.667799914798, 18230.36193733213, 18351.0, 18418.447446576625, 18431.907840460713, 51586.22620660618, 51967.74016129895, 54060.64848378822, 63628.04557931727, 66648.3335166642, 90166.0, 91349.0, 91565.62651560099, 93614.06385936326, 93850.0, 11340.716482087684, 11469.598564050219, 11537.365444900612, 11881.405929703307, 11918.702032449259, 10651.733706657396, 12491.562795302121, 12572.182808117443, 12641.115121941375, 12785.552505662257, 9817.460039697886, 10919.39943303126, 11035.062019728413, 11166.776664466828, 11211.831822522505, 10775.568977240822, 10899.182561307774, 11006.449677515931, 11046.558137674403, 11277.097832173571, 9699.466529340954, 10235.948820255851, 11207.71980700489, 11341.829872552084, 11398.316101033965, 11319.717007074889, 11345.886541134483, 11434.82847757301, 11576.479058442223, 11704.70738231551, 10747.193960453154, 10918.07196388316, 11071.889281107085, 11206.43967801623, 11264.94367528157], "value": 11355.189448811774, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1535.5316628428325, 1561.4300780215224, 1586.5161125856616, 1600.2078970909402, 1615.935362585497, 9529.094736000077, 9851.802963940725, 10553.947230263839, 11219.551217951272, 11489.655310340702, 11187.944060279708, 11311.717207069827, 11334.0, 11368.488418021188, 11428.885711142886, 2423.503181847717, 2443.4135807406183, 2449.865257410842, 2451.5587194305017, 2457.8279520433575, 2406.9037238510527, 2432.805375569959, 2438.3904023994, 2446.5106978604263, 2452.448199155194, 2366.692329997098, 2384.701912260967, 2385.9284221473295, 2391.2826152154285, 2394.8682822444757, 2374.7150341958904, 2387.080973825081, 2397.1250493569896, 2398.328468028955, 2404.4349577849193, 2365.491419344832, 2371.762823717632, 2422.563938491071, 2430.0887167312258, 2430.647556104355, 11287.040601548879, 11320.433978301135, 11370.260933039408, 11375.431228438545, 11393.829092563617, 5848.649081055108, 5993.790217342372, 6020.789272375489, 6031.758729650809, 6041.033434650479, 3478.130467383154, 3624.2932628137523, 3649.27014597079, 3664.926701465956, 3724.404095344732, 1208.0697862645713, 1212.2241765270235, 1255.3283993063667, 1276.923384596918, 1281.4233594882246, 13232.735345293137, 13999.30003499831, 14219.0, 15199.240037998166, 15516.689666206725, 4523.977380113079, 4728.881777955578, 4806.7836947336755, 4904.092742842514, 4925.6305777066145, 9251.722448326562, 11877.406129693481, 11937.88062119385, 11995.280283183038, 11997.700057498632, 5463.098588732894, 5583.274174357308, 5978.581499295007, 5982.192404025488, 5985.191999080155, 4359.433273674402, 5285.233641122016, 5330.706811125426, 5425.511703946585, 5461.481159289861, 3982.4424580558743, 4019.3569028955812, 4019.9397009044887, 4081.163361510883, 4081.9795901020307, 925.6760133953062, 1085.9565617375374, 1086.1148164246272, 1099.719571509276, 1099.8735145458215, 765.1927216786344, 783.9451238413257, 793.0047790023484, 800.1038836503154, 803.2529747335035, 6404.3595640436515, 6551.44312733423, 6587.463339464097, 6653.1018312527185, 6716.731330746813, 949.6913503111516, 950.4392408479031, 962.6438217859437, 991.4299277915228, 999.4503023337136, 3890.3386424307937, 4133.318002529549, 4246.63903568194, 4248.681348898845, 4286.292761694285, 11129.499172537255, 13974.860251397558, 14056.156630602196, 14108.929455352658, 14110.435582576582, 13479.258640774851, 13522.93238533801, 13819.861801382058, 13931.58205253844, 13988.370523326475, 10148.340357876714, 10222.693319200436, 10241.129503992095, 10316.896831031594, 10365.740856478498, 6482.740690372521, 6556.868862622626, 6565.638889861103, 6606.372394622503, 6654.73381064752, 11996.040316774463, 12113.878861211628, 12163.513459461523, 12310.692232693897, 12372.07209459312, 12405.131640785059, 12551.309677967434, 12636.55772047974, 12743.362831858185, 12806.67983300425, 7469.58917259556, 8665.0, 8671.262942649693, 8736.519491427778, 8759.824803504021, 10222.437765922648, 10464.63373781914, 10494.0, 10495.947520262655, 10521.789564208826, 8984.0, 9142.314326425678, 9209.861852072358, 9218.308626853148, 9230.400023998418, 8971.013188549385, 8992.595333210153, 9040.186383225542, 9072.591733372146, 9194.770130746785, 7486.775396738107, 7869.0950540688555, 7963.442559020813, 7974.362051035787, 7976.122626511195, 5944.702764861826, 5953.880922381441, 5994.940050599613, 6032.245969253843, 6175.475084617858, 5836.970815145897, 6033.336333003454, 6096.603720758137, 6117.3576774439825, 6198.529448220832], "value": 5144.377765218132, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [319.34693551686803, 370.894295125889, 378.3435738992849, 386.24681870352816, 390.4299722405286, 4185.288500954837, 4799.760011999403, 4827.975860120694, 4924.384451943507, 5273.683578985264, 4963.319807304051, 5071.7210553419545, 5163.366682991673, 5316.76074576644, 5339.172428273624, 4640.512746161647, 4680.812767489297, 4690.1557719610455, 4706.741129237907, 4736.644751643623, 4120.361343991671, 4122.876313710579, 4389.253826849427, 4510.549429686293, 4753.882837533177, 3913.7651740895635, 3972.0408295819657, 4129.0271004876595, 4351.150760926594, 4403.744932694182, 3772.566154892181, 4422.5356337584735, 4427.22523558376, 4453.977730111361, 4460.1525710114975, 3421.5894092709036, 3735.3276410246144, 3899.908025752794, 4104.932717493473, 4110.445089912883, 4141.834326626931, 4933.161362568372, 5180.948190518122, 5225.921611175835, 5254.605904557136, 4314.697971142022, 4679.8534359081905, 4793.544613261734, 5120.487951204887, 5129.1271494139, 5371.785128594852, 5414.91877621836, 5715.799947001877, 5772.451617096369, 5792.391798861143, 4151.989807389648, 4487.4324911695285, 4585.679002469828, 4587.151376995266, 4605.0, 5201.9479805201845, 5348.679079255218, 5641.661500309994, 5848.707564621752, 5963.880722385528, 4145.336746120666, 4632.7994202753425, 4634.791434385461, 4658.34783130362, 4680.204365257914, 4267.594578515036, 4277.165952639206, 4385.495667998157, 4703.858884233478, 4731.380493922153, 4349.369341445473, 4588.426446694163, 4622.560856718606, 4837.685550439211, 5055.747212639353, 5346.893062138814, 5546.889062218814, 5669.015844454408, 5679.0, 5720.884427536674, 1471.4849802569015, 1578.9605259868595, 1585.5481187861403, 1591.801024871891, 1619.4979556337573, 1089.9727506812394, 1112.7941330853819, 1167.2937872587133, 1206.9215500992407, 1216.3431746856597, 5285.154375299933, 5287.762050707727, 5299.284596579412, 5361.820399512101, 5365.3293338332705, 5929.495992840657, 5948.405159484104, 5951.3751056139345, 5971.491973371195, 5973.522118230531, 4570.7257564546235, 4779.040593356707, 4826.565609095198, 5301.761420736076, 5359.124306492697, 2631.7630713564595, 3061.387722455473, 3070.186400603842, 3075.3080556874525, 3080.3993221322075, 4791.353167322437, 5714.200011998323, 5781.219535362757, 5911.39898444683, 6085.96957015221, 5448.820471811228, 5675.687837168995, 5863.696526234316, 5988.520918326523, 6064.332923378425, 5286.827903258014, 5347.502699244218, 5656.575756818255, 5710.857228569236, 5856.238688970407, 4909.459959404534, 5469.644473109394, 5483.670979741229, 5549.168774304389, 5565.5825813063375, 5288.894222115613, 5635.196737044106, 5780.0, 5818.883622327615, 5834.445727655866, 5348.411674715856, 5596.70535080615, 5648.322201335866, 5904.261967254093, 5928.347881733092, 3949.9407508887966, 4031.0852345135827, 4283.850065247701, 4335.891602709958, 4610.904950030303, 5050.444451110448, 5258.973705131603, 5505.339359276913, 5636.633618814764, 5891.58758886891, 5647.0, 5671.0, 5684.63049901755, 5698.971505142614, 5745.1956726059225, 4687.507811679725, 4751.596114330182, 5315.255864179096, 5331.360236771612, 5693.377387444559, 5205.6356055075785, 5515.669059856422, 5526.314474085442, 5565.499105080562, 5615.438456154352, 4845.297431872288, 5575.414581469049, 5625.115586278479, 5735.369109398046, 5763.94236057634, 4926.8768280793265, 5059.468755780737, 5647.764176867349, 5661.150827375926, 5665.685573939127], "value": 4311.541491063003, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [752.5973604121796, 759.9278068583486, 780.9140994490601, 796.8604895000151, 841.9663213471459, 15024.39902403904, 15788.921055394736, 16217.675646487063, 16234.918825405872, 16520.173991300417, 14796.926015369934, 15015.324310406031, 15133.394664213445, 15281.159536225503, 15340.46308379207, 920.3143657974801, 952.8856537215544, 954.2270760683869, 954.8329042417583, 955.2167222877243, 930.502181332987, 957.3968399908048, 959.1942768074816, 972.9708108756713, 974.8635191073255, 1020.1379834040224, 1024.073213741565, 1024.3853687787305, 1024.472396715692, 1024.8923862994352, 1002.4636819301638, 1015.8679371681708, 1020.1838529176666, 1022.3150489172273, 1026.471367245869, 755.7997130760353, 756.2437562437563, 756.5649751392971, 758.7154816943646, 760.1068744225513, 15938.601534961603, 16269.186540673038, 16481.423150189687, 16873.831261687348, 17114.94620645518, 14272.14367137975, 14301.713965720732, 14326.71346573073, 14348.713025739427, 14541.49104781338, 11751.177417580773, 11852.585159519462, 11891.643250702493, 11970.40147992597, 12498.81251781215, 3655.0685013520724, 3710.5176327077306, 3758.530183727034, 3842.769433833979, 3852.8651497197466, 114547.56357309294, 119987.40006300087, 121047.0, 122770.38614806869, 123007.0, 10569.207309451822, 10690.78618427627, 10764.677059688222, 11086.891310868848, 11232.045276151455, 23354.532909341717, 23412.180573679838, 23455.41361465977, 23466.64800028001, 23523.0, 7688.538687678757, 7716.151223365426, 7723.7682869514, 7821.413393995473, 8043.356531477468, 4152.356384760367, 4361.760103194291, 4390.648748100144, 4412.33814927757, 4450.976275456598, 3328.683775041367, 3348.4809854472596, 3427.365937301557, 3434.6050204226335, 3436.2783815398548, 617.6781687189418, 643.4434214404506, 643.7521554201666, 645.1129696667083, 654.9017647352936, 557.4536953785232, 559.351152662909, 560.1765404854857, 567.4325674325675, 583.7985894866264, 9362.0, 9550.0, 9747.610095596097, 10200.38797672142, 10311.329763565343, 628.1018144053955, 628.8239292997968, 665.6039656404431, 674.5042393840523, 684.5037347922815, 4618.49196588375, 4670.136024835417, 4867.9269810952865, 4928.433230178503, 4931.605471562267, 88916.5554172225, 93811.0, 107378.0, 111220.0, 112907.43546282216, 96936.0306396927, 97318.5134074325, 98979.0, 99040.0, 101408.98591014143, 85582.5720871404, 85753.0, 86911.69632455519, 87816.56091719629, 89143.0, 9529.077038830961, 9701.0, 9709.70870873875, 10677.199210059121, 11222.887771122185, 36439.0, 40408.78773636796, 49305.26042109443, 51043.0, 51838.740806295726, 56131.03541376032, 68604.97092543516, 70264.94602581067, 70697.64651176712, 72695.0, 12751.171173873669, 13085.08404411682, 13299.734005319646, 13399.0, 13743.518976835763, 15067.397304107713, 15257.389704411698, 15297.6175595611, 16020.679586407974, 16050.0, 13167.47330106785, 13847.0, 14046.438142474186, 14616.269186540418, 14965.476208332657, 13684.65788355299, 13774.931125344308, 13856.653583660489, 14013.789793153315, 14525.491607793676, 13332.86667133355, 13845.515406960709, 14012.299385030503, 14125.011249212457, 14481.275936203358, 11048.944755276172, 11269.267497612629, 11457.713557161138, 11665.416729163677, 11742.530298788295, 10697.3046751964, 11269.830952535554, 11588.710282243012, 11594.826077608674, 11647.6505704829], "value": 7781.882143092523, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1554.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 172.0, 142.0, 120.0, 120.0, 121.0, 118.0, 118.0, 119.0, 119.0], "value": 128.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 135.0, 124.0, 144.0, 111.0, 113.0, 110.0, 112.0, 132.0, 111.0], "value": 126.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [219.0, 269.0, 243.0, 195.0, 196.0, 202.0, 198.0, 199.0, 196.0, 199.0], "value": 211.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 58.0, 61.0, 94.0, 105.0, 68.0, 60.0, 58.0, 74.0, 62.0], "value": 72.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 70.0, 84.0, 73.0, 72.0, 56.0, 91.0, 96.0, 56.0, 95.0], "value": 77.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 94.0, 91.0, 90.0, 130.0, 130.0, 107.0, 90.0, 91.0, 104.0], "value": 101.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 97.0, 98.0, 98.0, 97.0, 127.0, 97.0, 104.0, 102.0, 140.0], "value": 110.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 89.0, 90.0, 90.0, 91.0, 90.0, 94.0, 143.0, 142.0, 89.0], "value": 100.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 65.0, 66.0, 71.0, 68.0, 70.0, 70.0, 70.0, 70.0], "value": 68.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 62.0, 58.0, 60.0, 58.0, 57.0, 54.0, 57.0, 58.0, 57.0], "value": 57.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 94.0, 99.0, 101.0, 87.0, 87.0, 85.0, 79.0, 86.0, 99.0], "value": 89.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 159.0, 149.0, 147.0, 151.0, 151.0, 158.0, 157.0, 215.0, 147.0], "value": 158.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 165.0, 167.0, 197.0, 155.0, 220.0, 158.0, 218.0, 223.0, 178.0], "value": 184.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 65.0, 67.0, 66.0, 67.0, 67.0, 66.0, 64.0, 66.0], "value": 65.9, "unit": "score"}]}]} 13:34:54 INFO - Return code: 0 13:34:54 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:34:54 ERROR - # TBPL SUCCESS # 13:34:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:34:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:34:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:34:54 INFO - 2017-03-09 13:34:54.714 screenresolution[1660:10737] starting screenresolution argv=screenresolution get 13:34:54 INFO - 2017-03-09 13:34:54.717 screenresolution[1660:10737] Display 0: 1600x1200x32@60 13:34:54 INFO - 2017-03-09 13:34:54.723 screenresolution[1661:10740] starting screenresolution argv=screenresolution list 13:34:54 INFO - Available Modes on Display 0 13:34:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:34:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:34:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:34:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:34:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:34:54 INFO - Intel Iris: 13:34:54 INFO - Chipset Model: Intel Iris 13:34:54 INFO - Type: GPU 13:34:54 INFO - Bus: Built-In 13:34:54 INFO - VRAM (Dynamic, Max): 1536 MB 13:34:54 INFO - Vendor: Intel (0x8086) 13:34:54 INFO - Device ID: 0x0a2e 13:34:54 INFO - Revision ID: 0x0009 13:34:54 INFO - Displays: 13:34:54 INFO - Display: 13:34:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:34:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:34:54 INFO - Display Serial Number: 1600x1200 60 13:34:54 INFO - Main Display: Yes 13:34:54 INFO - Mirror: Off 13:34:54 INFO - Online: Yes 13:34:54 INFO - Rotation: Supported 13:34:54 INFO - Return code: 0 13:34:54 INFO - Running post-action listener: _resource_record_post_action 13:34:54 INFO - [mozharness: 2017-03-09 21:34:54.965081Z] Finished run-tests step (success) 13:34:54 INFO - Running post-run listener: _resource_record_post_run 13:34:55 INFO - Total resource usage - Wall time: 1200s; CPU: 55.0%; Read bytes: 38550016; Write bytes: 512945152; Read time: 240; Write time: 1606 13:34:55 INFO - TinderboxPrint: CPU usage
54.8% 13:34:55 INFO - TinderboxPrint: I/O read bytes / time
38,550,016 / 240 13:34:55 INFO - TinderboxPrint: I/O write bytes / time
512,945,152 / 1,606 13:34:55 INFO - TinderboxPrint: CPU idle
2,172.6 (45.2%) 13:34:55 INFO - TinderboxPrint: CPU system
968.0 (20.2%) 13:34:55 INFO - TinderboxPrint: CPU user
1,661.0 (34.6%) 13:34:55 INFO - TinderboxPrint: Swap in / out
247,828,480 / 0 13:34:55 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 209654272; Write bytes: 211732480; Read time: 15928; Write time: 457 13:34:55 INFO - run-tests - Wall time: 1181s; CPU: 55.0%; Read bytes: 34867712; Write bytes: 299721728; Read time: 215; Write time: 1143 13:34:55 INFO - Running post-run listener: _upload_blobber_files 13:34:55 INFO - Blob upload gear active. 13:34:55 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:34:55 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 13:34:55 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'] 13:34:55 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 13:34:55 INFO - (blobuploader) - INFO - Open directory for files ... 13:34:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 13:34:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:34:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:34:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 13:34:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:34:57 INFO - (blobuploader) - INFO - Done attempting. 13:34:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 13:34:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:34:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:34:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 13:34:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:34:58 INFO - (blobuploader) - INFO - Done attempting. 13:34:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 13:34:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:34:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:34:59 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 13:34:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:34:59 INFO - (blobuploader) - INFO - Done attempting. 13:34:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:34:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:34:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:35:01 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:35:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:35:01 INFO - (blobuploader) - INFO - Done attempting. 13:35:01 INFO - (blobuploader) - INFO - Iteration through files over. 13:35:01 INFO - Return code: 0 13:35:01 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:35:01 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:35:01 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/89d62ea860e54f37559fb3e15fc746dd0db94254e09b3ba66ef638f95b7aa81ee04d7267ba8960100f10b3fd52def6a175a831b97cbb3843fc23de3dfb79bed1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7f7a80091c8c3f690aa616736563c3e3ae5778bc173d341c3027d662c3769fcf874cdabd6fdfff903e092903cbf09833aafe10394a4c14416576907b7513c499", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d0d8b9a546377d2c685c6300c4919a3e68fa24d2e92d408e8681a2822d9b02d5913db453004e9cd19d745cc1b21a982c5e0180e0dd96bf9ee6438c2aaf947aa8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0cb3bfb873db6d31ecd397ca2b5928ead4bef3aedb9fc2aad4ded02c520e903ddf290cfbc1d4129f160088e8023f39382122cc7211b29f95c8f122775a9c7af6"} 13:35:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:35:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:35:01 INFO - Contents: 13:35:01 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/89d62ea860e54f37559fb3e15fc746dd0db94254e09b3ba66ef638f95b7aa81ee04d7267ba8960100f10b3fd52def6a175a831b97cbb3843fc23de3dfb79bed1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7f7a80091c8c3f690aa616736563c3e3ae5778bc173d341c3027d662c3769fcf874cdabd6fdfff903e092903cbf09833aafe10394a4c14416576907b7513c499", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d0d8b9a546377d2c685c6300c4919a3e68fa24d2e92d408e8681a2822d9b02d5913db453004e9cd19d745cc1b21a982c5e0180e0dd96bf9ee6438c2aaf947aa8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0cb3bfb873db6d31ecd397ca2b5928ead4bef3aedb9fc2aad4ded02c520e903ddf290cfbc1d4129f160088e8023f39382122cc7211b29f95c8f122775a9c7af6"} 13:35:01 INFO - Running post-run listener: copy_logs_to_upload_dir 13:35:01 INFO - Copying logs to upload dir... 13:35:01 INFO - mkdir: /builds/slave/test/build/upload/logs 13:35:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1283.046270 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 23 secs) (at 2017-03-09 13:35:01.487388) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-09 13:35:01.491292) ========= 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.Ulp28eD4k1/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.TEKBdypweO/Listeners TMPDIR=/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/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/89d62ea860e54f37559fb3e15fc746dd0db94254e09b3ba66ef638f95b7aa81ee04d7267ba8960100f10b3fd52def6a175a831b97cbb3843fc23de3dfb79bed1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7f7a80091c8c3f690aa616736563c3e3ae5778bc173d341c3027d662c3769fcf874cdabd6fdfff903e092903cbf09833aafe10394a4c14416576907b7513c499", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d0d8b9a546377d2c685c6300c4919a3e68fa24d2e92d408e8681a2822d9b02d5913db453004e9cd19d745cc1b21a982c5e0180e0dd96bf9ee6438c2aaf947aa8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0cb3bfb873db6d31ecd397ca2b5928ead4bef3aedb9fc2aad4ded02c520e903ddf290cfbc1d4129f160088e8023f39382122cc7211b29f95c8f122775a9c7af6"} build_url:https://queue.taskcluster.net/v1/task/Qmw2REXvSOGiswuXzXxR_Q/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011169 build_url: 'https://queue.taskcluster.net/v1/task/Qmw2REXvSOGiswuXzXxR_Q/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/89d62ea860e54f37559fb3e15fc746dd0db94254e09b3ba66ef638f95b7aa81ee04d7267ba8960100f10b3fd52def6a175a831b97cbb3843fc23de3dfb79bed1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7f7a80091c8c3f690aa616736563c3e3ae5778bc173d341c3027d662c3769fcf874cdabd6fdfff903e092903cbf09833aafe10394a4c14416576907b7513c499", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d0d8b9a546377d2c685c6300c4919a3e68fa24d2e92d408e8681a2822d9b02d5913db453004e9cd19d745cc1b21a982c5e0180e0dd96bf9ee6438c2aaf947aa8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0cb3bfb873db6d31ecd397ca2b5928ead4bef3aedb9fc2aad4ded02c520e903ddf290cfbc1d4129f160088e8023f39382122cc7211b29f95c8f122775a9c7af6"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-09 13:35:01.525311) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-09 13:35:01.525697) ========= 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.Ulp28eD4k1/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.TEKBdypweO/Listeners TMPDIR=/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/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.004975 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-09 13:35:01.574715) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-09 13:35:01.575042) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-09 13:35:01.575380) ========= ========= Total master_lag: 0.20 =========