builder: graphics_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0239 starttime: 1482226868.24 results: success (0) buildid: 20161219234253 builduid: 57608b53fcff45b4a14db76f6edcda37 revision: 8b08c36849a17717c8598b46b2291ca13b3e14e8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-20 01:41:08.245243) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-20 01:41:08.245664) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-20 01:41:08.245984) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-20 01:41:08.281585) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-20 01:41:08.282159) ========= 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.A1rVMozKDP/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.yvRtElBWUA/Listeners TMPDIR=/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-12-20 01:41:08-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 47.8M=0s 2016-12-20 01:41:08 (47.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.152157 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-20 01:41:08.488344) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-20 01:41:08.488652) ========= 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.A1rVMozKDP/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.yvRtElBWUA/Listeners TMPDIR=/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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.043732 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-20 01:41:08.563916) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-20 01:41:08.564278) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev 8b08c36849a17717c8598b46b2291ca13b3e14e8 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/graphics --rev 8b08c36849a17717c8598b46b2291ca13b3e14e8 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.A1rVMozKDP/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.yvRtElBWUA/Listeners TMPDIR=/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-12-20 01:41:08,696 truncating revision to first 12 chars 2016-12-20 01:41:08,696 Setting DEBUG logging. 2016-12-20 01:41:08,696 attempt 1/10 2016-12-20 01:41:08,696 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/8b08c36849a1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-20 01:41:09,932 unpacking tar archive at: graphics-8b08c36849a1/testing/mozharness/ program finished with exit code 0 elapsedTime=1.611328 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-20 01:41:10.209044) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-20 01:41:10.209384) ========= script_repo_revision: 8b08c36849a17717c8598b46b2291ca13b3e14e8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-20 01:41:10.209781) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-20 01:41:10.210049) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-20 01:41:10.239803) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 22 secs) (at 2016-12-20 01:41:10.240198) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Graphics --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 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', 'Graphics', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Graphics'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.A1rVMozKDP/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.yvRtElBWUA/Listeners TMPDIR=/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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 01:41:10 INFO - MultiFileLogger online at 20161220 01:41:10 in /builds/slave/test 01:41:10 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Graphics --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 01:41:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:41:10 INFO - {'append_to_log': False, 01:41:10 INFO - 'base_work_dir': '/builds/slave/test', 01:41:10 INFO - 'blob_upload_branch': 'Graphics', 01:41:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:41:10 INFO - 'branch': 'Graphics', 01:41:10 INFO - 'buildbot_json_path': 'buildprops.json', 01:41:10 INFO - 'config_files': ('talos/mac_config.py',), 01:41:10 INFO - 'default_actions': ('clobber', 01:41:10 INFO - 'read-buildbot-config', 01:41:10 INFO - 'download-and-extract', 01:41:10 INFO - 'populate-webroot', 01:41:10 INFO - 'create-virtualenv', 01:41:10 INFO - 'install', 01:41:10 INFO - 'run-tests'), 01:41:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:41:10 INFO - 'download_minidump_stackwalk': True, 01:41:10 INFO - 'download_symbols': 'ondemand', 01:41:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:41:10 INFO - 'tooltool.py': '/tools/tooltool.py', 01:41:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:41:10 INFO - '/tools/misc-python/virtualenv.py')}, 01:41:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:41:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:41:10 INFO - 'installer_path': 'installer.exe', 01:41:10 INFO - 'log_level': 'info', 01:41:10 INFO - 'log_name': 'talos', 01:41:10 INFO - 'log_to_console': True, 01:41:10 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 01:41:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 01:41:10 INFO - 'opt_config_files': (), 01:41:10 INFO - 'pip_index': False, 01:41:10 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:41:10 INFO - 'cmd': ('bash', 01:41:10 INFO - '-c', 01:41:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:41:10 INFO - 'enabled': True, 01:41:10 INFO - 'halt_on_failure': False, 01:41:10 INFO - 'name': 'check_screen_resolution'},), 01:41:10 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:41:10 INFO - 'cmd': ('bash', 01:41:10 INFO - '-c', 01:41:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:41:10 INFO - 'enabled': True, 01:41:10 INFO - 'halt_on_failure': False, 01:41:10 INFO - 'name': 'check_screen_resolution'},), 01:41:10 INFO - 'run_cmd_checks_enabled': True, 01:41:10 INFO - 'sps_profile': False, 01:41:10 INFO - 'sps_profile_interval': 0, 01:41:10 INFO - 'suite': 'dromaeojs-e10s', 01:41:10 INFO - 'system_bits': '32', 01:41:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:41:10 INFO - 'title': 't-yosemite-r7-0239', 01:41:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:41:10 INFO - 'use_talos_json': True, 01:41:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:41:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:41:10 INFO - 'work_dir': 'build'} 01:41:10 INFO - [mozharness: 2016-12-20 09:41:10.578115Z] Running clobber step. 01:41:10 INFO - Running pre-action listener: _resource_record_pre_action 01:41:10 INFO - Running main action method: clobber 01:41:10 INFO - rmtree: /builds/slave/test/build 01:41:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:41:13 INFO - Running post-action listener: _resource_record_post_action 01:41:13 INFO - [mozharness: 2016-12-20 09:41:13.852981Z] Finished clobber step (success) 01:41:13 INFO - [mozharness: 2016-12-20 09:41:13.853175Z] Running read-buildbot-config step. 01:41:13 INFO - Running pre-action listener: _resource_record_pre_action 01:41:13 INFO - Running main action method: read_buildbot_config 01:41:13 INFO - Using buildbot properties: 01:41:13 INFO - { 01:41:13 INFO - "project": "", 01:41:13 INFO - "product": "firefox", 01:41:13 INFO - "script_repo_revision": "default", 01:41:13 INFO - "builddir": "graphics_yosemite_r7_test-dromaeojs-e10s", 01:41:13 INFO - "repository": "", 01:41:13 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 graphics talos dromaeojs-e10s", 01:41:13 INFO - "buildid": "20161219234253", 01:41:13 INFO - "pgo_build": "False", 01:41:13 INFO - "basedir": "/builds/slave/test", 01:41:13 INFO - "buildnumber": 11, 01:41:13 INFO - "slavename": "t-yosemite-r7-0239", 01:41:13 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 01:41:13 INFO - "platform": "yosemite_r7", 01:41:13 INFO - "scheduler": "tests-graphics-macosx64-talos-8-3600", 01:41:13 INFO - "branch": "graphics", 01:41:13 INFO - "repo_path": "projects/graphics", 01:41:13 INFO - "revision": "8b08c36849a17717c8598b46b2291ca13b3e14e8", 01:41:13 INFO - "stage_platform": "macosx64", 01:41:13 INFO - "builduid": "57608b53fcff45b4a14db76f6edcda37", 01:41:13 INFO - "slavebuilddir": "test" 01:41:13 INFO - } 01:41:13 INFO - Found installer url https://queue.taskcluster.net/v1/task/UTlo9S4OQhy03dIiYm91_Q/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 01:41:13 INFO - Running post-action listener: _resource_record_post_action 01:41:13 INFO - [mozharness: 2016-12-20 09:41:13.857734Z] Finished read-buildbot-config step (success) 01:41:13 INFO - [mozharness: 2016-12-20 09:41:13.857917Z] Running download-and-extract step. 01:41:13 INFO - Running pre-action listener: _resource_record_pre_action 01:41:13 INFO - Running main action method: download_and_extract 01:41:13 INFO - mkdir: /builds/slave/test/build/tests 01:41:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:41:13 INFO - trying https://queue.taskcluster.net/v1/task/UTlo9S4OQhy03dIiYm91_Q/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 01:41:13 INFO - Downloading https://queue.taskcluster.net/v1/task/UTlo9S4OQhy03dIiYm91_Q/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 01:41:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UTlo9S4OQhy03dIiYm91_Q/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 01:41:15 INFO - Downloaded 1199 bytes. 01:41:15 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 01:41:15 INFO - Using the following test package requirements: 01:41:15 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 01:41:15 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 01:41:15 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 01:41:15 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 01:41:15 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 01:41:15 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 01:41:15 INFO - u'jsshell-mac.zip'], 01:41:15 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 01:41:15 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 01:41:15 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 01:41:15 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 01:41:15 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 01:41:15 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 01:41:15 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 01:41:15 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 01:41:15 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 01:41:15 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 01:41:15 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 01:41:15 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 01:41:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UTlo9S4OQhy03dIiYm91_Q/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 01:41:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UTlo9S4OQhy03dIiYm91_Q/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 01:41:15 INFO - Fetch https://queue.taskcluster.net/v1/task/UTlo9S4OQhy03dIiYm91_Q/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 01:41:16 INFO - Expected file size: 16275924 01:41:16 INFO - Obtained file size: 16275924 01:41:27 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 01:41:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UTlo9S4OQhy03dIiYm91_Q/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 01:41:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UTlo9S4OQhy03dIiYm91_Q/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 01:41:27 INFO - Fetch https://queue.taskcluster.net/v1/task/UTlo9S4OQhy03dIiYm91_Q/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 01:41:28 INFO - Expected file size: 16275924 01:41:28 INFO - Obtained file size: 16275924 01:41:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UTlo9S4OQhy03dIiYm91_Q/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 01:41:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UTlo9S4OQhy03dIiYm91_Q/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 01:41:38 INFO - Fetch https://queue.taskcluster.net/v1/task/UTlo9S4OQhy03dIiYm91_Q/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 01:41:40 INFO - Expected file size: 14409734 01:41:40 INFO - Obtained file size: 14409734 01:41:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:41:40 INFO - trying https://queue.taskcluster.net/v1/task/UTlo9S4OQhy03dIiYm91_Q/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 01:41:40 INFO - Downloading https://queue.taskcluster.net/v1/task/UTlo9S4OQhy03dIiYm91_Q/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 01:41:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UTlo9S4OQhy03dIiYm91_Q/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 01:41:44 INFO - Downloaded 69660184 bytes. 01:41:44 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/UTlo9S4OQhy03dIiYm91_Q/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 01:41:44 INFO - mkdir: /builds/slave/test/properties 01:41:44 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:41:44 INFO - Writing to file /builds/slave/test/properties/build_url 01:41:44 INFO - Contents: 01:41:44 INFO - build_url:https://queue.taskcluster.net/v1/task/UTlo9S4OQhy03dIiYm91_Q/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 01:41:44 INFO - Running post-action listener: _resource_record_post_action 01:41:44 INFO - Running post-action listener: set_extra_try_arguments 01:41:44 INFO - [mozharness: 2016-12-20 09:41:44.701331Z] Finished download-and-extract step (success) 01:41:44 INFO - [mozharness: 2016-12-20 09:41:44.701473Z] Running populate-webroot step. 01:41:44 INFO - Running pre-action listener: _resource_record_pre_action 01:41:44 INFO - Running main action method: populate_webroot 01:41:44 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:41:44 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:41:44 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:41:44 INFO - '--tppagecycles', 01:41:44 INFO - '7'], 01:41:44 INFO - 'tests': ['tsvgm']}}, 01:41:44 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 01:41:44 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:41:44 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:41:44 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 01:41:44 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:41:44 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:41:44 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 01:41:44 INFO - 'talos_options': ['--disable-e10s'], 01:41:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:41:44 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 01:41:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:41:44 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 01:41:44 INFO - 'talos_options': ['--disable-e10s'], 01:41:44 INFO - 'tests': ['damp', 'tps']}, 01:41:44 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 01:41:44 INFO - 'tests': ['damp', 'tps']}, 01:41:44 INFO - 'g3': {'talos_options': ['--disable-e10s'], 01:41:44 INFO - 'tests': ['dromaeo_dom']}, 01:41:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:41:44 INFO - 'g4': {'talos_options': ['--disable-e10s'], 01:41:44 INFO - 'tests': ['basic_compositor_video']}, 01:41:44 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 01:41:44 INFO - 'other': {'talos_options': ['--disable-e10s'], 01:41:44 INFO - 'tests': ['a11yr', 01:41:44 INFO - 'ts_paint', 01:41:44 INFO - 'tpaint', 01:41:44 INFO - 'sessionrestore', 01:41:44 INFO - 'sessionrestore_no_auto_restore', 01:41:44 INFO - 'tabpaint']}, 01:41:44 INFO - 'other-e10s': {'tests': ['a11yr', 01:41:44 INFO - 'ts_paint', 01:41:44 INFO - 'tpaint', 01:41:44 INFO - 'sessionrestore', 01:41:44 INFO - 'sessionrestore_no_auto_restore', 01:41:44 INFO - 'tabpaint']}, 01:41:44 INFO - 'other-e10s_l64': {'tests': ['a11yr', 01:41:44 INFO - 'ts_paint', 01:41:44 INFO - 'tpaint', 01:41:44 INFO - 'sessionrestore', 01:41:44 INFO - 'sessionrestore_no_auto_restore', 01:41:44 INFO - 'tabpaint']}, 01:41:44 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 01:41:44 INFO - 'ts_paint', 01:41:44 INFO - 'tpaint', 01:41:44 INFO - 'sessionrestore', 01:41:44 INFO - 'sessionrestore_no_auto_restore', 01:41:44 INFO - 'tabpaint']}, 01:41:44 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 01:41:44 INFO - 'tests': ['a11yr', 01:41:44 INFO - 'ts_paint', 01:41:44 INFO - 'tpaint', 01:41:44 INFO - 'sessionrestore', 01:41:44 INFO - 'sessionrestore_no_auto_restore', 01:41:44 INFO - 'tabpaint']}, 01:41:44 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 01:41:44 INFO - 'tests': ['a11yr', 01:41:44 INFO - 'ts_paint', 01:41:44 INFO - 'tpaint', 01:41:44 INFO - 'sessionrestore', 01:41:44 INFO - 'sessionrestore_no_auto_restore', 01:41:44 INFO - 'tabpaint']}, 01:41:44 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 01:41:44 INFO - 'tests': ['tsvgx', 01:41:44 INFO - 'tsvgr_opacity', 01:41:44 INFO - 'tart', 01:41:44 INFO - 'tscrollx', 01:41:44 INFO - 'cart', 01:41:44 INFO - 'tsvg_static']}, 01:41:44 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:41:44 INFO - 'tsvgr_opacity', 01:41:44 INFO - 'tart', 01:41:44 INFO - 'tscrollx', 01:41:44 INFO - 'cart', 01:41:44 INFO - 'tsvg_static']}, 01:41:44 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 01:41:44 INFO - 'talos_options': ['--disable-e10s'], 01:41:44 INFO - 'tests': ['tp5o']}, 01:41:44 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 01:41:44 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 01:41:44 INFO - 'talos_options': ['--disable-e10s', 01:41:44 INFO - '--xperf_path', 01:41:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:41:44 INFO - 'tests': ['tp5n']}, 01:41:44 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 01:41:44 INFO - 'talos_options': ['--xperf_path', 01:41:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:41:44 INFO - 'tests': ['tp5n']}}, 01:41:44 INFO - 'talos.zip': {'path': '', 01:41:44 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:41:44 INFO - Running post-action listener: _resource_record_post_action 01:41:44 INFO - [mozharness: 2016-12-20 09:41:44.728309Z] Finished populate-webroot step (success) 01:41:44 INFO - [mozharness: 2016-12-20 09:41:44.728531Z] Running create-virtualenv step. 01:41:44 INFO - Running pre-action listener: _resource_record_pre_action 01:41:44 INFO - Running main action method: create_virtualenv 01:41:44 INFO - Creating virtualenv /builds/slave/test/build/venv 01:41:44 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 01:41:44 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:41:44 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:41:44 INFO - Using real prefix '/tools/python27' 01:41:44 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:41:46 INFO - Installing distribute.............................................................................................................................................................................................done. 01:41:50 INFO - Installing pip.................done. 01:41:50 INFO - Return code: 0 01:41:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 01:41:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 01:41:51 INFO - Reading from file tmpfile_stdout 01:41:51 INFO - Output received: 01:41:51 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) 01:41:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:41:51 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')]} 01:41:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:41:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:51 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')]} 01:41:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:41:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:51 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 0x105ad9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105adc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe7ebca3180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1058b7d30>, '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 0x1057dedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1058acbe8>, '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.A1rVMozKDP/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.yvRtElBWUA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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 01:41:51 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 01:41:51 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 01:41:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.A1rVMozKDP/Render', 01:41:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:41:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:41:51 INFO - 'HOME': '/Users/cltbld', 01:41:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:41:51 INFO - 'LOGNAME': 'cltbld', 01:41:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:41:51 INFO - 'MOZ_NO_REMOTE': '1', 01:41:51 INFO - 'NO_EM_RESTART': '1', 01:41:51 INFO - 'PAGER': '/bin/cat', 01:41:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:41:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:41:51 INFO - 'PWD': '/builds/slave/test', 01:41:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:41:51 INFO - 'SHELL': '/bin/bash', 01:41:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yvRtElBWUA/Listeners', 01:41:51 INFO - 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', 01:41:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:41:51 INFO - 'USER': 'cltbld', 01:41:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:41:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:41:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:41:51 INFO - 'XPC_FLAGS': '0x0', 01:41:51 INFO - 'XPC_SERVICE_NAME': '0', 01:41:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:41:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:41:51 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 01:41:51 INFO - Cleaning up... 01:41:51 INFO - Return code: 0 01:41:51 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 01:41:51 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')]} 01:41:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:41:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:51 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')]} 01:41:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:41:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:51 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 0x105ad9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105adc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe7ebca3180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1058b7d30>, '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 0x1057dedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1058acbe8>, '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.A1rVMozKDP/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.yvRtElBWUA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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 01:41:51 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 01:41:51 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 01:41:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.A1rVMozKDP/Render', 01:41:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:41:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:41:51 INFO - 'HOME': '/Users/cltbld', 01:41:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:41:51 INFO - 'LOGNAME': 'cltbld', 01:41:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:41:51 INFO - 'MOZ_NO_REMOTE': '1', 01:41:51 INFO - 'NO_EM_RESTART': '1', 01:41:51 INFO - 'PAGER': '/bin/cat', 01:41:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:41:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:41:51 INFO - 'PWD': '/builds/slave/test', 01:41:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:41:51 INFO - 'SHELL': '/bin/bash', 01:41:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yvRtElBWUA/Listeners', 01:41:51 INFO - 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', 01:41:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:41:51 INFO - 'USER': 'cltbld', 01:41:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:41:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:41:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:41:51 INFO - 'XPC_FLAGS': '0x0', 01:41:51 INFO - 'XPC_SERVICE_NAME': '0', 01:41:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:41:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:41:52 INFO - Downloading/unpacking psutil>=3.1.1 01:41:52 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 01:41:52 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 01:41:52 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 01:41:52 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 01:41:52 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 01:41:52 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 01:41:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:41:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:41:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:41:57 INFO - Installing collected packages: psutil 01:41:57 INFO - Running setup.py install for psutil 01:41:57 INFO - building 'psutil._psutil_osx' extension 01:41:57 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 01:41:58 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 01:41:58 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 01:41:58 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 01:41:58 INFO - building 'psutil._psutil_posix' extension 01:41:58 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 01:41:58 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 01:41:58 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 01:41:58 INFO - ^ 01:41:58 INFO - 1 warning generated. 01:41:58 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 01:41:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:41:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:41:58 INFO - Successfully installed psutil 01:41:58 INFO - Cleaning up... 01:41:58 INFO - Return code: 0 01:41:58 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 01:41:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:41:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:41:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:41:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:41:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105ad9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105adc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe7ebca3180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1058b7d30>, '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 0x1057dedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1058acbe8>, '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.A1rVMozKDP/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.yvRtElBWUA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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 01:41:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 01:41:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 01:41:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.A1rVMozKDP/Render', 01:41:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:41:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:41:58 INFO - 'HOME': '/Users/cltbld', 01:41:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:41:58 INFO - 'LOGNAME': 'cltbld', 01:41:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:41:58 INFO - 'MOZ_NO_REMOTE': '1', 01:41:58 INFO - 'NO_EM_RESTART': '1', 01:41:58 INFO - 'PAGER': '/bin/cat', 01:41:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:41:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:41:58 INFO - 'PWD': '/builds/slave/test', 01:41:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:41:58 INFO - 'SHELL': '/bin/bash', 01:41:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yvRtElBWUA/Listeners', 01:41:58 INFO - 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', 01:41:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:41:58 INFO - 'USER': 'cltbld', 01:41:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:41:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:41:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:41:58 INFO - 'XPC_FLAGS': '0x0', 01:41:58 INFO - 'XPC_SERVICE_NAME': '0', 01:41:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:41:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:41:59 INFO - Downloading/unpacking mozsystemmonitor==0.3 01:41:59 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 01:41:59 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 01:41:59 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 01:41:59 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 01:41:59 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 01:41:59 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 01:42:03 INFO - Downloading mozsystemmonitor-0.3.tar.gz 01:42:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:42:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 01:42:03 INFO - Installing collected packages: mozsystemmonitor 01:42:03 INFO - Running setup.py install for mozsystemmonitor 01:42:03 INFO - Successfully installed mozsystemmonitor 01:42:03 INFO - Cleaning up... 01:42:03 INFO - Return code: 0 01:42:03 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 01:42:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:42:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:42:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:42:04 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')]} 01:42:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:42:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:42:04 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 0x105ad9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105adc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe7ebca3180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1058b7d30>, '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 0x1057dedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1058acbe8>, '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.A1rVMozKDP/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.yvRtElBWUA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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 01:42:04 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 01:42:04 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 01:42:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.A1rVMozKDP/Render', 01:42:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:42:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:42:04 INFO - 'HOME': '/Users/cltbld', 01:42:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:42:04 INFO - 'LOGNAME': 'cltbld', 01:42:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:42:04 INFO - 'MOZ_NO_REMOTE': '1', 01:42:04 INFO - 'NO_EM_RESTART': '1', 01:42:04 INFO - 'PAGER': '/bin/cat', 01:42:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:42:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:42:04 INFO - 'PWD': '/builds/slave/test', 01:42:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:42:04 INFO - 'SHELL': '/bin/bash', 01:42:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yvRtElBWUA/Listeners', 01:42:04 INFO - 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', 01:42:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:42:04 INFO - 'USER': 'cltbld', 01:42:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:42:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:42:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:42:04 INFO - 'XPC_FLAGS': '0x0', 01:42:04 INFO - 'XPC_SERVICE_NAME': '0', 01:42:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:42:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:42:04 INFO - Downloading/unpacking jsonschema==2.5.1 01:42:04 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 01:42:04 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 01:42:04 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 01:42:04 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 01:42:04 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 01:42:04 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 01:42:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:42:09 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 01:42:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:42:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:42:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:42:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:42:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:42:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:42:10 INFO - Downloading functools32-3.2.3-2.tar.gz 01:42:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:42:10 INFO - warning: no files found matching '*.txt' 01:42:10 INFO - no previously-included directories found matching 'build' 01:42:10 INFO - no previously-included directories found matching 'dist' 01:42:10 INFO - no previously-included directories found matching '.git*' 01:42:10 INFO - Installing collected packages: jsonschema, functools32 01:42:10 INFO - Running setup.py install for functools32 01:42:10 INFO - warning: no files found matching '*.txt' 01:42:10 INFO - no previously-included directories found matching 'build' 01:42:10 INFO - no previously-included directories found matching 'dist' 01:42:10 INFO - no previously-included directories found matching '.git*' 01:42:10 INFO - Successfully installed jsonschema functools32 01:42:10 INFO - Cleaning up... 01:42:10 INFO - Return code: 0 01:42:10 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 01:42:10 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')]} 01:42:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:42:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:42:10 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')]} 01:42:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:42:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:42:10 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 0x105ad9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105adc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe7ebca3180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1058b7d30>, '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 0x1057dedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1058acbe8>, '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.A1rVMozKDP/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.yvRtElBWUA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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 01:42:10 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 01:42:10 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 01:42:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.A1rVMozKDP/Render', 01:42:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:42:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:42:10 INFO - 'HOME': '/Users/cltbld', 01:42:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:42:10 INFO - 'LOGNAME': 'cltbld', 01:42:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:42:10 INFO - 'MOZ_NO_REMOTE': '1', 01:42:10 INFO - 'NO_EM_RESTART': '1', 01:42:10 INFO - 'PAGER': '/bin/cat', 01:42:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:42:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:42:10 INFO - 'PWD': '/builds/slave/test', 01:42:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:42:10 INFO - 'SHELL': '/bin/bash', 01:42:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yvRtElBWUA/Listeners', 01:42:10 INFO - 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', 01:42:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:42:10 INFO - 'USER': 'cltbld', 01:42:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:42:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:42:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:42:10 INFO - 'XPC_FLAGS': '0x0', 01:42:10 INFO - 'XPC_SERVICE_NAME': '0', 01:42:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:42:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:42:11 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 01:42:11 INFO - Cleaning up... 01:42:11 INFO - Return code: 0 01:42:11 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:42:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:42:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:42:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:42:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:42:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:42:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:42:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x105ad9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105adc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe7ebca3180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1058b7d30>, '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 0x1057dedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1058acbe8>, '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.A1rVMozKDP/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.yvRtElBWUA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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 01:42:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 01:42:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 01:42:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.A1rVMozKDP/Render', 01:42:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:42:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:42:11 INFO - 'HOME': '/Users/cltbld', 01:42:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:42:11 INFO - 'LOGNAME': 'cltbld', 01:42:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:42:11 INFO - 'MOZ_NO_REMOTE': '1', 01:42:11 INFO - 'NO_EM_RESTART': '1', 01:42:11 INFO - 'PAGER': '/bin/cat', 01:42:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:42:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:42:11 INFO - 'PWD': '/builds/slave/test', 01:42:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:42:11 INFO - 'SHELL': '/bin/bash', 01:42:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yvRtElBWUA/Listeners', 01:42:11 INFO - 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', 01:42:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:42:11 INFO - 'USER': 'cltbld', 01:42:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:42:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:42:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:42:11 INFO - 'XPC_FLAGS': '0x0', 01:42:11 INFO - 'XPC_SERVICE_NAME': '0', 01:42:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:42:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:42:11 INFO - Downloading/unpacking blobuploader==1.2.4 01:42:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:42:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:42:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:42:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:42:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:42:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:42:15 INFO - Downloading blobuploader-1.2.4.tar.gz 01:42:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:42:16 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:42:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:42:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:42:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:42:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:42:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:42:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:42:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:42:17 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:42:17 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 01:42:17 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 01:42:17 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 01:42:17 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 01:42:17 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 01:42:17 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 01:42:18 INFO - Downloading docopt-0.6.1.tar.gz 01:42:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:42:18 INFO - Installing collected packages: blobuploader, requests, docopt 01:42:18 INFO - Running setup.py install for blobuploader 01:42:18 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:42:18 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:42:18 INFO - Running setup.py install for requests 01:42:19 INFO - Running setup.py install for docopt 01:42:19 INFO - Successfully installed blobuploader requests docopt 01:42:19 INFO - Cleaning up... 01:42:19 INFO - Return code: 0 01:42:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:42:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:42:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:42:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:42:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:42:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:42:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:42:19 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 0x105ad9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105adc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe7ebca3180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1058b7d30>, '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 0x1057dedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1058acbe8>, '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.A1rVMozKDP/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.yvRtElBWUA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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 01:42:19 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 01:42:19 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 01:42:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.A1rVMozKDP/Render', 01:42:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:42:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:42:19 INFO - 'HOME': '/Users/cltbld', 01:42:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:42:19 INFO - 'LOGNAME': 'cltbld', 01:42:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:42:19 INFO - 'MOZ_NO_REMOTE': '1', 01:42:19 INFO - 'NO_EM_RESTART': '1', 01:42:19 INFO - 'PAGER': '/bin/cat', 01:42:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:42:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:42:19 INFO - 'PWD': '/builds/slave/test', 01:42:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:42:19 INFO - 'SHELL': '/bin/bash', 01:42:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yvRtElBWUA/Listeners', 01:42:19 INFO - 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', 01:42:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:42:19 INFO - 'USER': 'cltbld', 01:42:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:42:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:42:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:42:19 INFO - 'XPC_FLAGS': '0x0', 01:42:19 INFO - 'XPC_SERVICE_NAME': '0', 01:42:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:42:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:42:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:42:20 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-XiMXb9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:42:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:42:20 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-_w5G5I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:42:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:42:20 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-8OOYkm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:42:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:42:20 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-_8Axoo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:42:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:42:20 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-IeSyBL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:42:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:42:20 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-skpuI0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:42:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:42:21 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-ItD2bX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:42:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:42:21 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-5I5GNb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:42:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:42:21 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-faWnjM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:42:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:42:21 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-tpmMa_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:42:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:42:21 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-kIsXkU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:42:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:42:21 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-_tUcQW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:42:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:42:22 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-LvQXRT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:42:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:42:22 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-B0Chyj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:42:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:42:22 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-65UQs7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:42:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:42:22 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-jePTG5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:42:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:42:22 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-eJjAJ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:42:22 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:42:22 INFO - Running setup.py install for manifestparser 01:42:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:42:23 INFO - Running setup.py install for mozcrash 01:42:23 INFO - Running setup.py install for mozdebug 01:42:23 INFO - Running setup.py install for mozdevice 01:42:23 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:42:23 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:42:23 INFO - Running setup.py install for mozfile 01:42:24 INFO - Running setup.py install for mozhttpd 01:42:24 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:42:24 INFO - Running setup.py install for mozinfo 01:42:24 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:42:24 INFO - Running setup.py install for mozInstall 01:42:24 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:42:24 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:42:24 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:42:24 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:42:24 INFO - Running setup.py install for mozleak 01:42:25 INFO - Running setup.py install for mozlog 01:42:25 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:42:25 INFO - Running setup.py install for moznetwork 01:42:25 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:42:25 INFO - Running setup.py install for mozprocess 01:42:25 INFO - Running setup.py install for mozprofile 01:42:26 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:42:26 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:42:26 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:42:26 INFO - Running setup.py install for mozrunner 01:42:26 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:42:26 INFO - Running setup.py install for mozscreenshot 01:42:26 INFO - Running setup.py install for moztest 01:42:26 INFO - Running setup.py install for mozversion 01:42:27 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:42:27 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:42:27 INFO - Cleaning up... 01:42:27 INFO - Return code: 0 01:42:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:42:27 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')]} 01:42:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:42:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:42:27 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')]} 01:42:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:42:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:42:27 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 0x105ad9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105adc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe7ebca3180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1058b7d30>, '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 0x1057dedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1058acbe8>, '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.A1rVMozKDP/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.yvRtElBWUA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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 01:42:27 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 01:42:27 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 01:42:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.A1rVMozKDP/Render', 01:42:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:42:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:42:27 INFO - 'HOME': '/Users/cltbld', 01:42:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:42:27 INFO - 'LOGNAME': 'cltbld', 01:42:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:42:27 INFO - 'MOZ_NO_REMOTE': '1', 01:42:27 INFO - 'NO_EM_RESTART': '1', 01:42:27 INFO - 'PAGER': '/bin/cat', 01:42:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:42:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:42:27 INFO - 'PWD': '/builds/slave/test', 01:42:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:42:27 INFO - 'SHELL': '/bin/bash', 01:42:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yvRtElBWUA/Listeners', 01:42:27 INFO - 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', 01:42:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:42:27 INFO - 'USER': 'cltbld', 01:42:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:42:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:42:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:42:27 INFO - 'XPC_FLAGS': '0x0', 01:42:27 INFO - 'XPC_SERVICE_NAME': '0', 01:42:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:42:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:42:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:42:27 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-kniOFE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:42:27 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)) 01:42:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:42:27 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-V9SY9w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:42:27 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)) 01:42:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:42:27 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-0jL5_W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:42:28 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)) 01:42:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:42:28 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-gppYnd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:42:28 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)) 01:42:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:42:28 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-RaeL9w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:42:28 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)) 01:42:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:42:28 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-0u5B4d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:42:28 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)) 01:42:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:42:28 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-NwjvQU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:42:28 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)) 01:42:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:42:28 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-ROOu4z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:42:28 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)) 01:42:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:42:28 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-BuDsXW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:42:29 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)) 01:42:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:42:29 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-lH65zW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:42:29 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)) 01:42:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:42:29 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-db7YBB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:42:29 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)) 01:42:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:42:29 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-JFj8R3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:42:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 01:42:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:42:29 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-kg82lm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:42:29 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)) 01:42:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:42:29 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-NyYQh7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:42:30 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)) 01:42:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:42:30 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-fotBsk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:42:30 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)) 01:42:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:42:30 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-l_4Hql-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:42:30 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 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)) 01:42:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:42:30 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-HYNPou-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:42:30 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)) 01:42:30 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)) 01:42:30 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)) 01:42:30 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)) 01:42:30 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)) 01:42:30 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)) 01:42:30 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:42:30 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 01:42:30 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 01:42:30 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 01:42:30 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 01:42:30 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 01:42:30 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 01:42:34 INFO - Downloading blessings-1.6.tar.gz 01:42:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:42:35 INFO - Installing collected packages: blessings 01:42:35 INFO - Running setup.py install for blessings 01:42:35 INFO - Successfully installed blessings 01:42:35 INFO - Cleaning up... 01:42:35 INFO - Return code: 0 01:42:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:42:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:42:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:42:35 INFO - Reading from file tmpfile_stdout 01:42:35 INFO - Current package versions: 01:42:35 INFO - blessings == 1.6 01:42:35 INFO - blobuploader == 1.2.4 01:42:35 INFO - docopt == 0.6.1 01:42:35 INFO - functools32 == 3.2.3-2 01:42:35 INFO - jsonschema == 2.5.1 01:42:35 INFO - manifestparser == 1.1 01:42:35 INFO - mozInstall == 1.12 01:42:35 INFO - mozcrash == 1.0 01:42:35 INFO - mozdebug == 0.1 01:42:35 INFO - mozdevice == 0.49 01:42:35 INFO - mozfile == 1.2 01:42:35 INFO - mozhttpd == 0.7 01:42:35 INFO - mozinfo == 0.9 01:42:35 INFO - mozleak == 0.1 01:42:35 INFO - mozlog == 3.4 01:42:35 INFO - moznetwork == 0.27 01:42:35 INFO - mozprocess == 0.23 01:42:35 INFO - mozprofile == 0.28 01:42:35 INFO - mozrunner == 6.13 01:42:35 INFO - mozscreenshot == 0.1 01:42:35 INFO - mozsystemmonitor == 0.3 01:42:35 INFO - moztest == 0.7 01:42:35 INFO - mozversion == 1.4 01:42:35 INFO - psutil == 3.1.1 01:42:35 INFO - requests == 1.2.3 01:42:35 INFO - wsgiref == 0.1.2 01:42:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:42:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:42:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:42:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:42:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:42:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:42:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:42:35 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 0x105ad9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105adc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe7ebca3180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1058b7d30>, '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 0x1057dedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1058acbe8>, '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.A1rVMozKDP/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.yvRtElBWUA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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 01:42:35 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 01:42:35 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 01:42:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.A1rVMozKDP/Render', 01:42:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:42:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:42:35 INFO - 'HOME': '/Users/cltbld', 01:42:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:42:35 INFO - 'LOGNAME': 'cltbld', 01:42:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:42:35 INFO - 'MOZ_NO_REMOTE': '1', 01:42:35 INFO - 'NO_EM_RESTART': '1', 01:42:35 INFO - 'PAGER': '/bin/cat', 01:42:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:42:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:42:35 INFO - 'PWD': '/builds/slave/test', 01:42:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:42:35 INFO - 'SHELL': '/bin/bash', 01:42:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yvRtElBWUA/Listeners', 01:42:35 INFO - 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', 01:42:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:42:35 INFO - 'USER': 'cltbld', 01:42:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:42:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:42:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:42:35 INFO - 'XPC_FLAGS': '0x0', 01:42:35 INFO - 'XPC_SERVICE_NAME': '0', 01:42:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:42:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:42:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 01:42:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 01:42:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 01:42:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 01:42:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 01:42:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 01:42:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 01:42:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 01:42:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 01:42:36 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 01:42:36 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 01:42:36 INFO - Cleaning up... 01:42:36 INFO - Return code: 0 01:42:36 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:42:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:42:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:42:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:42:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:42:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:42:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:42:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105ad9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105adc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe7ebca3180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1058b7d30>, '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 0x1057dedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1058acbe8>, '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.A1rVMozKDP/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.yvRtElBWUA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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 01:42:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 01:42:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 01:42:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.A1rVMozKDP/Render', 01:42:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:42:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:42:36 INFO - 'HOME': '/Users/cltbld', 01:42:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:42:36 INFO - 'LOGNAME': 'cltbld', 01:42:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:42:36 INFO - 'MOZ_NO_REMOTE': '1', 01:42:36 INFO - 'NO_EM_RESTART': '1', 01:42:36 INFO - 'PAGER': '/bin/cat', 01:42:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:42:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:42:36 INFO - 'PWD': '/builds/slave/test', 01:42:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:42:36 INFO - 'SHELL': '/bin/bash', 01:42:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yvRtElBWUA/Listeners', 01:42:36 INFO - 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', 01:42:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:42:36 INFO - 'USER': 'cltbld', 01:42:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:42:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:42:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:42:36 INFO - 'XPC_FLAGS': '0x0', 01:42:36 INFO - 'XPC_SERVICE_NAME': '0', 01:42:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:42:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:42:36 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 01:42:36 INFO - Cleaning up... 01:42:36 INFO - Return code: 0 01:42:36 INFO - Running post-action listener: _resource_record_post_action 01:42:36 INFO - Running post-action listener: _start_resource_monitoring 01:42:36 INFO - Starting resource monitoring. 01:42:36 INFO - [mozharness: 2016-12-20 09:42:36.786135Z] Finished create-virtualenv step (success) 01:42:36 INFO - [mozharness: 2016-12-20 09:42:36.787435Z] Running install step. 01:42:36 INFO - Running pre-action listener: _resource_record_pre_action 01:42:36 INFO - Running main action method: install 01:42:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:42:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:42:37 INFO - Reading from file tmpfile_stdout 01:42:37 INFO - Detecting whether we're running mozinstall >=1.0... 01:42:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:42:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:42:37 INFO - Reading from file tmpfile_stdout 01:42:37 INFO - Output received: 01:42:37 INFO - Usage: mozinstall [options] installer 01:42:37 INFO - Options: 01:42:37 INFO - -h, --help show this help message and exit 01:42:37 INFO - -d DEST, --destination=DEST 01:42:37 INFO - Directory to install application into. [default: 01:42:37 INFO - "/builds/slave/test"] 01:42:37 INFO - --app=APP Application being installed. [default: firefox] 01:42:37 INFO - mkdir: /builds/slave/test/build/application 01:42:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 01:42:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 01:43:12 INFO - Reading from file tmpfile_stdout 01:43:12 INFO - Output received: 01:43:12 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 01:43:12 INFO - Running post-action listener: _resource_record_post_action 01:43:12 INFO - [mozharness: 2016-12-20 09:43:12.766275Z] Finished install step (success) 01:43:12 INFO - [mozharness: 2016-12-20 09:43:12.766568Z] Running run-tests step. 01:43:12 INFO - Running pre-action listener: _resource_record_pre_action 01:43:12 INFO - Running main action method: run_tests 01:43:12 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:43:12 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:43:12 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:43:12 INFO - 2016-12-20 01:43:12.791 screenresolution[1657:7582] starting screenresolution argv=screenresolution get 01:43:12 INFO - 2016-12-20 01:43:12.795 screenresolution[1657:7582] Display 0: 1600x1200x32@60 01:43:12 INFO - 2016-12-20 01:43:12.805 screenresolution[1658:7589] starting screenresolution argv=screenresolution list 01:43:12 INFO - Available Modes on Display 0 01:43:12 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:43:12 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:43:12 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:43:12 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:43:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:43:13 INFO - Intel Iris: 01:43:13 INFO - Chipset Model: Intel Iris 01:43:13 INFO - Type: GPU 01:43:13 INFO - Bus: Built-In 01:43:13 INFO - VRAM (Dynamic, Max): 1536 MB 01:43:13 INFO - Vendor: Intel (0x8086) 01:43:13 INFO - Device ID: 0x0a2e 01:43:13 INFO - Revision ID: 0x0009 01:43:13 INFO - Displays: 01:43:13 INFO - Display: 01:43:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:43:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:43:13 INFO - Display Serial Number: 1600x1200 60 01:43:13 INFO - Main Display: Yes 01:43:13 INFO - Mirror: Off 01:43:13 INFO - Online: Yes 01:43:13 INFO - Rotation: Supported 01:43:13 INFO - Return code: 0 01:43:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:43:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:43:13 INFO - Python 2.7.3 01:43:13 INFO - Return code: 0 01:43:13 INFO - grabbing minidump binary from tooltool 01:43:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:43:13 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 0x1058b7d30>, '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 0x1057dedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1058acbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:43:13 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 01:43:13 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 01:43:13 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 01:43:13 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:43:13 INFO - Return code: 0 01:43:13 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 01:43:13 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:43:13 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:43:13 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 01:43:13 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:43:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UTlo9S4OQhy03dIiYm91_Q/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0239', '--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 01:43:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Graphics --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/UTlo9S4OQhy03dIiYm91_Q/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0239 --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 01:43:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.A1rVMozKDP/Render', 01:43:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:43:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:43:13 INFO - 'HOME': '/Users/cltbld', 01:43:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:43:13 INFO - 'LOGNAME': 'cltbld', 01:43:13 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:43:13 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 01:43:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:43:13 INFO - 'MOZ_NO_REMOTE': '1', 01:43:13 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:43:13 INFO - 'NO_EM_RESTART': '1', 01:43:13 INFO - 'PAGER': '/bin/cat', 01:43:13 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:43:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:43:13 INFO - 'PWD': '/builds/slave/test', 01:43:13 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:43:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:43:13 INFO - 'SHELL': '/bin/bash', 01:43:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yvRtElBWUA/Listeners', 01:43:13 INFO - 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', 01:43:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:43:13 INFO - 'USER': 'cltbld', 01:43:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:43:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:43:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:43:13 INFO - 'XPC_FLAGS': '0x0', 01:43:13 INFO - 'XPC_SERVICE_NAME': '0', 01:43:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:43:13 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UTlo9S4OQhy03dIiYm91_Q/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0239', '--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 01:43:13 INFO - mozversion application_buildid: 20161219234253 01:43:13 INFO - mozversion application_changeset: 8b08c36849a17717c8598b46b2291ca13b3e14e8 01:43:13 INFO - mozversion application_display_name: Nightly 01:43:13 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:43:13 INFO - mozversion application_name: Firefox 01:43:13 INFO - mozversion application_remotingname: firefox 01:43:13 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 01:43:13 INFO - mozversion application_vendor: Mozilla 01:43:13 INFO - mozversion application_version: 53.0a1 01:43:13 INFO - mozversion platform_buildid: 20161219234253 01:43:13 INFO - mozversion platform_changeset: 8b08c36849a17717c8598b46b2291ca13b3e14e8 01:43:13 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 01:43:13 INFO - mozversion platform_version: 53.0a1 01:43:13 INFO - using testdate: 1482226993 01:43:13 INFO - actual date: 1482226993 01:43:13 INFO - starting webserver on 'localhost:49249' 01:43:13 INFO - SUITE-START | Running 2 tests 01:43:13 INFO - TEST-START | dromaeo_css 01:43:13 INFO - operating with platform_type : mac_ 01:43:13 INFO - Initialising browser for dromaeo_css test... 01:43:13 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/tmp5T6DF9/profile http://localhost:49249/getInfo.html) 01:43:18 INFO - PROCESS | 1668 | 2016-12-20 01:43:18.080 plugin-container[1670:7753] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x494f, name = 'com.apple.tsm.portname' 01:43:18 INFO - PROCESS | 1668 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:43:18 INFO - PROCESS | 1668 | 2016-12-20 01:43:18.084 plugin-container[1670:7753] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e3b, name = 'com.apple.CFPasteboardClient' 01:43:18 INFO - PROCESS | 1668 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:43:18 INFO - PROCESS | 1668 | 2016-12-20 01:43:18.084 plugin-container[1670:7753] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 01:43:18 INFO - PROCESS | 1668 | __metrics Screen width/height:1600/1200 01:43:18 INFO - PROCESS | 1668 | colorDepth:24 01:43:18 INFO - PROCESS | 1668 | Browser inner width/height: 1024/689 01:43:18 INFO - PROCESS | 1668 | __metrics 01:43:18 ERROR - PROCESS | 1668 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3232: TypeError: this.tabs is undefined 01:43:19 INFO - TEST-INFO | 1668: exit 0 01:43:19 INFO - Browser initialized. 01:43:19 INFO - Running cycle 1/1 for dromaeo_css test... 01:43:19 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/tmp5T6DF9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:43:23 INFO - PROCESS | 1671 | 2016-12-20 01:43:23.239 plugin-container[1673:7878] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8943, name = 'com.apple.tsm.portname' 01:43:23 INFO - PROCESS | 1671 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:43:23 INFO - PROCESS | 1671 | 2016-12-20 01:43:23.240 plugin-container[1673:7878] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x471f, name = 'com.apple.CFPasteboardClient' 01:43:23 INFO - PROCESS | 1671 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:43:23 INFO - PROCESS | 1671 | 2016-12-20 01:43:23.240 plugin-container[1673:7878] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 01:43:32 INFO - PROCESS | 1671 | RSS: Main: 236384256 01:43:32 INFO - PROCESS | 1671 | 01:44:22 INFO - PROCESS | 1671 | 1482227062103 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 01:46:35 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49249/tests/dromaeo/cssquery-ext.html) 01:46:37 INFO - PROCESS | 1671 | RSS: Main: 253321216 01:46:37 INFO - PROCESS | 1671 | 01:49:40 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-ext.html (next: http://localhost:49249/tests/dromaeo/cssquery-jquery.html) 01:49:40 INFO - PROCESS | 1671 | RSS: Main: 263557120 01:49:40 INFO - PROCESS | 1671 | 01:52:43 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49249/tests/dromaeo/cssquery-mootools.html) 01:52:44 INFO - PROCESS | 1671 | RSS: Main: 269004800 01:52:44 INFO - PROCESS | 1671 | 01:55:47 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49249/tests/dromaeo/cssquery-prototype.html) 01:55:47 INFO - PROCESS | 1671 | RSS: Main: 267571200 01:55:47 INFO - PROCESS | 1671 | 01:58:51 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49249/tests/dromaeo/cssquery-yui.html) 01:58:52 INFO - PROCESS | 1671 | RSS: Main: 272211968 01:58:52 INFO - PROCESS | 1671 | 02:01:55 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-yui.html (next: http://localhost:49249/tests/dromaeo/cssquery-dojo.html) 02:01:56 INFO - PROCESS | 1671 | RSS: Main: 275136512 02:01:56 INFO - PROCESS | 1671 | 02:01:56 INFO - PROCESS | 1671 | __start_tp_report 02:01:56 INFO - PROCESS | 1671 | _x_x_mozilla_page_load 02:01:56 INFO - PROCESS | 1671 | _x_x_mozilla_page_load_details 02:01:56 INFO - PROCESS | 1671 | |i|pagename|runs| 02:01:56 INFO - PROCESS | 1671 | |0;dojo.html;1691.890027148235;1989.4330115916957;2042.2749923777064;2068.1520576563603;2137.9251726189586;3202.791818531794;3228.967710322896;3272.1655977725645;3313.7349012079035;3361.7982921024727;3119.0486901495033;3193;3267.18320419895;3328.5839270091237;3380.7971521708714;2960.1267626050258;2966.3622321200937;3023.108183085984;3097;3144.0071998655053;2467.3954881054165;2901.7968742188054;2936.6769655337894;3024.5311976643657;3143.32418530016;3058.189579761365;3090.4900691385897;3107.7047680470546;3176.3647270545985;3216.4049650814673;3022.2142243016756;3065.662777094518;3073.2777797217636;3142.1516190628404;3144.701253380925;2763.309172706823;2896.594476773232;3006.8496575171152;3091.31990961988;3230.8223047732367;2695.7169497202904;3289.6052473703307;3321.319129578455;3412.97610716784;3483.89548313551;2996.8501574921384;3234.4663130583426;3424.6404127566743;3551.058969373092;3602.945955810665;3699.1676872703697;3699.7595156314756;3704.3887758519813;3736.159364143073;3747.775133491999;2444.4622183119686;2830.7876909231686;3284.636875696583;3456.723462123024;3469.9826500867334;3603.657652523006;3690.4279836625365;3705.9073523161596;3772.7170462215445;3934.586868378779;2877.7266159714795;3463.8094904779973;3504.9123771905906;3583.211693427442;3707.888763337104;3647.85408583659;3658.817059147032;3662.1943172502006;3679.9264014719556;3689.594144644087;3369.7809642373168;3458.4293591557616;3520.331137083997;3532.2405682778326;3629.9637003630155;3345;3394.117529442363;3402.7958322500235;3473.6873681368293;3601.5858176309534;2735.2478068530913;2789.4700006998605;2829.2926768307925;2840.389316297006;2864.6705628852947;2562.628418879252;2563.7564431378987;2595.545779488582;2611.321056525317;2671.906483273076;3335.766496345281;3350.162459385154;3378.408778463759;3450.6721861423125;3499.842507087187;3554.306910152495;3621.80080095592;3634.618365071682;3691.8186180422545;3737.9252414951548;3154.1010811918495;3230.870765169414;3330.8667653293596;3462.926492787244;3467.5318831957866;2902.969445846694;2963.9851800740857;3116.7818252722545;3122.09459256813;3266.950995735066;2747.3543717226435;3085.889079931223;3750.5686846012513;3847.96152038476;3884.320243957296;3591.0842735102383;3628.510151129564;3638.363286424812;3742.5134732484603;3780.5652349980105;3648.160922987781;3717.665410113103;3769.096606213954;3921.6078392160557;3948.5853985332274;3564.0555252857494;3593.443016332473;3626.727995400303;3628.6552777486095;3774.7923864186637;3544.2379888324135;3584.336897673939;3691.5939246682306;3732.141607430252;3745.5692595350793;2720.7687346575763;3379.847906844149;3463.4458486642015;3468.2716629508086;3486.7559270850797;3388.4070287700047;3408.16499957512;3462.0825481247994;3486.9825650871585;3561.4835848802295;2787.9823864288983;3272.0183944815985;3297.1097803592415;3347.6652334767305;3510.36813373585;3419.1964888251737;3464.636213197578;3476.9304613907075;3582.4984502169195;3588;2879.3953269812737;2960.4523163214876;2998.1605150557007;3465.376232278113;3648 02:01:56 INFO - PROCESS | 1671 | |1;ext.html;238.58367149324425;252.21183800623052;299.30262488402025;299.5342242812426;312.4937601086239;21978.340649780494;23136.30591082266;23358.64962025569;23938.880305598468;24500.01999920001;22397.21609743655;25112;25118.623220651705;25212.991480340763;26117.21648350548;4077.531083925357;5391.757370918299;5632.887342253173;5697.316322041361;5875.148103524986;5031.773570189332;6745.291744366845;6937.098177236978;7073.221945586007;7202.459815513831;6768.661566921659;6873.553219040771;6887.449004079662;6958.338957799001;6966.721331146748;5542.806001789938;6485.93514064858;6642.501812364092;6861.5539989901;7029.789106326818;6778.932210677879;6796.932030679679;6849.835527960258;6890.586564806128;6982.895256571155;23984.28047158588;24201;24432.38919027021;24436.877815610987;24598.310253461947;1909.0645583664043;2004.1682701678785;2080.864743791649;2394.736578976311;2436.0499405231794;18390.54023649406;18573.721394179098;19010.52473688169;19161.94609296488;19208.519787005436;1812.4925020994142;1873.4286042756953;1884.7172924061363;1924.3361040441025;1935.371004423568;275668;285817;289796.9999999979;290107.6483852744;290933.72665683314;36730.44904326384;37021.259574808355;37037.62962370342;37224.255514890094;38754;36907.4463883042;37304.25391492155;37702.62297376991;37835;38974.22051558953;9054.502002389932;9160.358774885693;9229.400088994194;9299.395539289924;9357.877054753473;6327.272363678144;6738.191417029988;6987.637410704864;7011.053507776487;7234;8524.701635442854;9049.728508144766;9779.070988256104;10094.09153176203;10927.071198948179;3665.395209790408;3704.7036237100965;3852.788096654711;3901.356276214393;3929.44987701722;486.14438588085227;539.7723052238999;577.535084257177;584.1383958660941;593.8070127208674;16825.411110611316;17256.568585785455;17866.374676886247;17921.193546290448;18882.716759248622;509.3124282219049;545.2938444714105;573.8221151443066;644.9806505804834;659.1398225316009;6946.79159625212;8086.867838502614;8727.65089396417;9118.452892826452;9163.358564900527;345480.54519454984;356322;364541;365680;377089;33279;34505;35285.823570882494;35552;36342.72800451971;730.9890351644623;807.5356669914705;813.040612077754;818.6234332207251;818.6807145213371;13719.725605488034;17646.64706705833;17762.46712598624;18230.81769182291;18313.90116593009;30099.398012040074;30147.794088236224;31322.21694457657;31996.5200521997;32848.67151328552;305518;318279;321099;322332;328089;14076.170097887209;15010.849891500946;24478.14326498564;24791.87604061968;24803.50392992094;25681.10116145926;25956.35109122287;26435.339116522242;26647.20058398251;27383.178504644893;18151.45545633633;21550.814705191366;21564.67652985238;22062.448438789157;22411.77588224097;19923.203071876964;19967.400977970683;20771.753694778363;21532;21674;19358.612827743647;19725.70411443801;19788.109535070667;20309.89845050765;20655.760654360787;17692.82307176912;17991.910040449715;19213.135408906353;19731;19737.70393444128;14204.857951420354;14414.495492657707;14509.419623214953;14700.55898323052;14706.191159485903 02:01:56 INFO - PROCESS | 1671 | |2;jquery.html;221.8236501980925;226.1609429018341;227.23988259272727;231.31645986111045;239.68720819330767;8414.200650938152;8926.464412135276;11524.423778811057;12112;12620.179688320264;9746.756331091727;10936.890631093687;11402.885971140286;11696.824547631772;12009.159358844863;3207.1340738000786;3565.8751943681978;3572.428411454168;3652.1600031992657;3711.5917249102713;3457.3776720190353;3533.681968622823;3577.141486043344;3608.4948107264872;3639;3078.9993252193017;3317.1541256979435;3396.507506411569;3477;3478.3738926993133;3025.2134445044226;3043.6956304369605;3415.590129184489;3521.6654417830523;3530.170409953672;2903.3612605226817;2908.650961884566;2988.775841811851;3012.3523442459978;3058.388322335542;13891.02762806604;14091.647708807259;14297.428102875872;14647.780283295759;14932.925335373362;1124.6569796212234;1392.4291040673274;1689.6620675864754;1706.5648259693698;1895.6777347850898;8564.48613083217;9003.305339471313;9048.366614337;9389.577469013911;9673.979129082758;959.4003747657714;1039.7712503249272;1055.9651541489384;1170.678063532527;1203.314110956755;48128.51871481275;60625.393746062415;64340;64421;64482.355176448575;5667.716614169275;5927.73325200367;5937.732802023919;5952.613080149777;5977.491913187341;8186.386021048445;8245.546494942835;8321.334293256525;8325.583720813935;8476.194761497647;4211.284081706117;4451.933221001687;4475.507694153641;4780;4830.806767729322;4748.936935676469;4991.825286114969;5032.748362581856;5057.671251368649;5166.896662066738;3017.7434918032213;3853.9421908671393;3896.123372241223;3905.9218815624095;4016.638502534786;1067.8451624514403;1073.3613499967507;1083.5557421457165;1097.1058587251364;1136.2841409911703;501.6989806116301;552.320645605907;553.6014069869688;555.2836840475821;662.9734810607522;9186.540672966325;10819.67540973772;12820.935895320463;13493.987950903722;14142.50501232452;460.5555638808566;523.233462976741;528.9761960711777;589.8466398736359;604.9939500605026;3050.313679422157;3685.7788532687587;3729.4778730977687;3770.472133901256;6648.700808463631;248210;297028;301231;303888;309705;74550.62724686341;77798.88804447872;78025.21974780293;79191;79389;250.93601131711296;268.1847184558919;271.3947896191504;272.69185820023245;278.9692087735837;6332.746690132343;6508.219013718384;6602.075709400592;6621.172353455818;7318.817029574303;22710.978005990102;24683.88922498528;30441.391172176875;31131.066068017997;33733.98798035965;45425.318620221384;45814.77092614649;45933.77033114813;46699.533004669516;46770.76614616905;3743.831527581211;3810.809459526957;4188.937165942452;4378;4758.595519380891;6764.391204791593;7359.742409015659;7737.92262077372;7806.336461400844;8122.55325957078;6176.81469555914;6514.381133792472;6666.233383160805;6693.823078302109;6724.562903411263;6435.710393032231;6493.642849643315;6721.764738234138;6896.551724137915;7015;6299.244090709144;6326.398992095744;6432;6616.801495954936;6824.488163387865;4689.7655117244685;4861.562459378673;4911.214205727067;5062.215356619729;5132;5067.746612669278;6498.74005039812;6617.669116544057;6755.527113102232;6773.695183716646 02:01:56 INFO - PROCESS | 1671 | |3;mootools.html;787.3976408047845;864.6671031652812;880.8458519759045;898.3576742629019;1022.4529876516066;3694.556653201613;4009.4787677601907;4517.847948773062;4571.474280457748;4908.7300198489065;4015.578364271746;4773.355596994405;4867.2942423348595;4985.775640096195;5013.6741111827705;1155.3010428690648;1349.3118509560097;1380.475419340649;1404.8735613794756;1413.1379858286437;1155.2490880927423;1341.5505805555151;1363.4205462678372;1405.5783265020505;1491.9030263032923;1123.9715660170934;1150.9424528773527;1350.8311461067367;1371.5405339211377;1381.5233744358231;1336.5722968649986;1353.7495563320817;1356.945722171112;1363.2433999130478;1377.442135934948;1310.9708878530307;1358.0561509750728;1361.4758318047525;1376.5595009596932;1401.9158850468903;4778.283257511366;4923.261510773377;5023.748812559394;5036.874078148076;5097.745112744385;2924.985375073132;3142.355817057498;3142.5286207068893;3191.4414977378865;3227.177069847174;2055.691646253059;2057.753069631639;2058.433930669063;2082.562661841015;2100.0024988130667;525.4509038055241;596.3946594206883;600.2466904035408;607.7568972411027;620.2309136670541;4650.813967441299;5717.885642287131;6015.157877897098;6039.305479869828;6122.571420000601;2476.4987362763736;2585.728498507667;2602.349412646838;2615.4769046190836;2737;5168.198929165986;5234.502722241381;5245.396779370345;5255.500727430888;5425.02349577072;2927.736503714676;3019.8675496688743;3052.8778848845795;3053.7709671774705;3171.9841400792848;2718.7553120219277;2805.803593748418;2817.7323154300307;2872.138358492444;2893.348996475776;1906.218450435315;2021.14101506859;2052.138101997165;2099.643060679688;2202.1851914791628;523.8245187862033;590.35356284868;656.917885264342;657.5824351536713;665.6172700697118;468.24846121974423;505.86847419671153;543.2991441041013;547.2666626720214;568.3435631845225;2900.6664233612983;2967.925801854971;3152.9684703152675;3251.634313588251;3284.080457471864;473.2782506677276;521.7964993652479;552.4917076289823;554.8224568138157;578.8986927287708;1757.0775342945055;1811.3569682762693;2214.6788715636467;2324.0936034946385;2380.214529205358;5010.674306170087;5971.761129554856;6100.481459076028;6139.324674285825;6165.599236049642;5799.855003624859;5987.491063259585;6018.946684330224;6117.265928088569;6117.755289788359;4585.495595484494;4597.862064138081;4654.394928659252;4672.789724462339;4861.246506791453;2730.30377253799;3158.0367987763952;3229.273413481948;3363.276895467486;3453.9810755827593;4697.718136911796;5473.8357849264585;5654.208410822571;5722.713864306685;5769.740361683818;5629.971850140723;5655.971720141538;5729.656220626775;5754.5684073695475;5808.883822323614;3711.9628803711616;4023.879283621496;4164.979580002919;4231.090315582164;4247.957520424756;4375.453068366454;4539.72761634303;4566.995261042565;4573.908521829479;4649.953760403882;4111.527174374865;4235.957640423557;4263.189993901149;4320.243957307522;4413.44831896013;4019.6784257258764;4047.271491131507;4074.938875916923;4130.215259100762;4169.749815011109;3734.925301494009;3864.401017842239;3881.80590970456;3914.2758589661003;3940.4877365942243;2342.3090188394804;2682.570788673803;2759.406727553586;2850.159203035151;2850.9714902850706;2743.7256274373203;2775.3894143288444;2912.985435072811;2938.5445255985355;2941.5734718465274 02:01:56 INFO - PROCESS | 1671 | |4;prototype.html;171.86680322749868;174.66551553774522;195.75335077801967;210.80711149298392;214.10092479705028;2830.2829283887495;3107.3008573071047;3270.721988630963;3324.933501329972;3389.677980591846;2944.7644188464924;3050.2984313607885;3064.7854650174495;3099.51223412762;3243.351329734057;1968.8974174462346;2174.8042676159143;2321.307283551081;2370.4754436525045;2552.515022145791;2227.24273746926;2230.29745630126;2264.230161745006;2527.671402717644;2527.936801579957;2037.3073155127254;2344.39045848079;2416.8187385946035;2463.9014439422403;2497.3382053755768;2285.748567657548;2352.764723527651;2361.350628577155;2399.928002159938;2459.053264493174;2000.8899510526915;2035.735354403918;2173.4892300309416;2250.3361508355074;2261.536385041079;2810.2576402630298;3132.1073494054085;3224.774265801395;3228.757843161749;3268.6894744999186;2191.791779780918;2501.599744040963;2741.3557813913712;2798.2716851070245;2824.3221626809623;2929.9853500732575;3315.386653469116;3456.4815277708294;3567.4492737692676;3650.4159334506617;2080.843936704753;2248.359217622986;2259.9943025353723;2431.756824317572;2433.3429973907123;3059.92350191247;3649.164856565062;3688.0779805048737;3724.3110024645257;3825.4070619053814;2023.5345870449878;2242.159190303636;2307.8846057697383;2333.4633034401845;2389.7132344118468;2206.165077874923;2252.8735632183907;2269.580127676385;2351.9059237630645;2395.3412811476633;2463.790577800907;2519.7102333231546;2523.205190365029;2705.240654532366;2894.884204631833;3034.5296479045783;3069.4499277864534;3422.3326451342245;3590.084528445282;3613.2773445311045;681.8022773395759;719.8704233237962;751.3087959077661;760.0461420916714;760.7907825347963;447.9014616784302;454.84762604527856;482.9492903245179;515.9226116082542;518.8547206782032;2793.427347393779;2833.8583070846375;3053.740432063255;3207.615086189672;3258.576385069926;2940.514815055492;3449.6032956210356;3617.3488772020764;3633.2733453309443;3701.833417496219;2798.978372893879;3071.8924837630575;3144.9528257076163;3264.249222678797;3368.208471009311;1675.227498490001;1741.5471977285742;1756.7277072053853;1833.6974399224246;1871.756671632679;3112.459332630317;3150.5431712401573;3197.3765115802194;3319.850606722703;3684.4841722158917;2950.8524573771224;3239.49787782894;3378.4256676364585;3503.474478828145;3667.3215455140885;2762.0784864886527;3101.240196151957;3242.302904875463;3589.1695235429597;3665.1020499977144;3211.261409875695;3306.363350141599;3381.7294616430136;3434.536337594393;3468.2196505785996;3078.0150351887182;3298.307355455381;3389.6271410144373;3402.0134161093006;3427.9887433207764;3073.7264812974113;3286.6549012354317;3512.7892326460496;3566.375884220303;3747.70018398522;1924.595834874692;1960.3628820633066;2066.4214020074405;2192.7807219277943;2247.258404726469;2736.986315068412;3074.5695602616097;3492.5634295713035;3631.4734363516613;3636.1091532574687;2835.9716402836534;2858.19970408286;3433.2446861690387;3513.841877115587;3640.7269454790467;3452.1508518001747;3506.8772592959126;3508.561429821272;3518.7712798668003;3583.874564390234;2786.3869948611273;3160.7313378363096;3351.983240083784;3363.4114030044157;3382.8646854126546;2842.147355793295;2988.402319536058;3360.3951288768258;3370.983145084259;3576.6065732768852;2768.750812426891;2900.9684879300758;3186.04058504023;3367.629560748365;3370.612379576282 02:01:56 INFO - PROCESS | 1671 | |5;yui.html;376.8398430666966;387.41693750904886;421.9725717828343;461.22053729197665;478.8946431785006;8046.839063218732;8369.414141010131;8391.922437838999;8514.403991720583;8637.481751094938;7740.72907448238;8218.835623287545;8270.503769773817;8379.790505237372;8505.829883402328;482.25973131243546;494.720482927145;503.62479952435484;509.66362200947384;512.1140427061187;379.13178820501156;413.32627816658805;477.40562999066213;490.99263511047366;526.4682670502782;531.9255104688527;540.9864753381178;544.1810075835881;546.0062685911643;561.8651523634339;550.1307933465115;553.1288221051819;561.4469747298887;562.831150654804;574.2907509226102;392.0434140696691;393.5297319702956;410.6940329454546;413.0726518964913;418.9183109293689;8495.532745698983;8689.69586064491;8754.824903501894;8765.7808554786;8852.822943541158;6943.923691827775;7817.804554886174;8695.217430431228;8749.16882896124;8763.298936085097;6785.694643741043;6790.76232331871;6920.169579650475;6921.688524016431;7103.218645948941;2109.9367018989456;2190.5290362572123;2194.2429861697688;2293.5068960173644;2299.9305323024873;50956.74521627368;66453.33546664519;68591.65704171498;68855.3114468859;68902.96645550372;5271.868203294872;6096.634201947897;6172.814815555451;6191.752329906753;6655.6339401332425;13094;13100.737985240432;13145.60563183087;13553.39009744564;13851.79222311646;3672.192117734094;4328.3723860040745;4414.624756895636;4480.462344518679;4663.813447462131;1934.8258656720964;2406.7111946566174;2455.8649274290087;2460.2619214235656;2464.470138920105;1907.237105157948;1990.1840245499566;2069.9379018629465;2103.379503046605;2104.116271166083;326.59991510400044;366.5107082045499;368.23407312789334;372.89186136020794;387.3105871548648;325.94231718074997;374.88378602632724;376.9812653241905;388.00089768845334;398.70695039146204;5158.406783219978;5194.35070616173;5409.972950135224;5422.539084177889;5562.554995600342;364.27145708582833;376.7984128491244;380.96761775249416;394.459590361207;416.0846435046201;2220.9888950555146;2349.5888219561507;2656.5521790624157;2727.890884364603;2836.1066264126734;50044.49955500471;62899.68550157311;65324.36689082811;65690;70680;45584.77207614007;46165.30752038722;46650.533494665295;55531.722341388035;59728.40271597228;37891.726496410854;39010.43958241752;49521.50478495241;50264.748676256386;50325.496745032084;5289.7355132244;5473.890522189454;5704.258446401941;5847.970760146172;6032.336442991355;27549.58675619827;29737.25656858596;30020.699793002663;31129.46281280866;31519.054428367184;44177.33733993928;44220.55779442164;45218;45546.31680524728;45702;7386.519876208029;7447.590382528796;7545.622718863925;7581.848363032819;7754.185810489817;7190.96404517974;8693.347998900234;8695.826083478169;8725.91274087251;9320.300977426585;8011.599420028859;8121.634526446206;8230.711925082593;8330.625121869653;8368.539730314891;7434.256574342522;7831.373490120663;7895.526268423913;8391.874121888299;8559.918938289204;7731.381489480716;7772.417068719756;7878.684852605831;8168.897198878075;8290.751277461686;6292.370762923671;6648.268690443951;6904.758333458305;7115.715371385087;7399.519031263166;6483.708233129426;6945.166580010431;7061.858762824612;7083.787486375417;7561 02:01:56 INFO - PROCESS | 1671 | __end_tp_report 02:01:56 INFO - PROCESS | 1671 | __start_cc_report 02:01:56 INFO - PROCESS | 1671 | _x_x_mozilla_cycle_collect,528 02:01:56 INFO - PROCESS | 1671 | __end_cc_report 02:01:56 INFO - PROCESS | 1671 | __startTimestamp1482228116071__endTimestamp 02:01:56 INFO - PROCESS | 1671 | 02:01:56 INFO - PROCESS | 1671 | ------- Summary: start ------- 02:01:56 INFO - PROCESS | 1671 | Number of tests: 6 02:01:56 INFO - PROCESS | 1671 | 02:01:56 INFO - PROCESS | 1671 | [#0] dojo.html Cycles:165 Average:3293.32 Median:3380.80 stddev:408.40 (12.1%) stddev-sans-first:389.85 02:01:56 INFO - PROCESS | 1671 | Values: 1691.9 1989.4 2042.3 2068.2 2137.9 3202.8 3229.0 3272.2 3313.7 3361.8 3119.0 3193.0 3267.2 3328.6 3380.8 2960.1 2966.4 3023.1 3097.0 3144.0 2467.4 2901.8 2936.7 3024.5 3143.3 3058.2 3090.5 3107.7 3176.4 3216.4 3022.2 3065.7 3073.3 3142.2 3144.7 2763.3 2896.6 3006.8 3091.3 3230.8 2695.7 3289.6 3321.3 3413.0 3483.9 2996.9 3234.5 3424.6 3551.1 3602.9 3699.2 3699.8 3704.4 3736.2 3747.8 2444.5 2830.8 3284.6 3456.7 3470.0 3603.7 3690.4 3705.9 3772.7 3934.6 2877.7 3463.8 3504.9 3583.2 3707.9 3647.9 3658.8 3662.2 3679.9 3689.6 3369.8 3458.4 3520.3 3532.2 3630.0 3345.0 3394.1 3402.8 3473.7 3601.6 2735.2 2789.5 2829.3 2840.4 2864.7 2562.6 2563.8 2595.5 2611.3 2671.9 3335.8 3350.2 3378.4 3450.7 3499.8 3554.3 3621.8 3634.6 3691.8 3737.9 3154.1 3230.9 3330.9 3462.9 3467.5 2903.0 2964.0 3116.8 3122.1 3267.0 2747.4 3085.9 3750.6 3848.0 3884.3 3591.1 3628.5 3638.4 3742.5 3780.6 3648.2 3717.7 3769.1 3921.6 3948.6 3564.1 3593.4 3626.7 3628.7 3774.8 3544.2 3584.3 3691.6 3732.1 3745.6 2720.8 3379.8 3463.4 3468.3 3486.8 3388.4 3408.2 3462.1 3487.0 3561.5 2788.0 3272.0 3297.1 3347.7 3510.4 3419.2 3464.6 3476.9 3582.5 3588.0 2879.4 2960.5 2998.2 3465.4 3648.0 02:01:56 INFO - PROCESS | 1671 | 02:01:56 INFO - PROCESS | 1671 | [#1] ext.html Cycles:180 Average:40381.91 Median:174800.61 stddev:86449.57 (49.5%) stddev-sans-first:86639.54 02:01:56 INFO - PROCESS | 1671 | Values: 238.6 252.2 299.3 299.5 312.5 21978.3 23136.3 23358.6 23938.9 24500.0 22397.2 25112.0 25118.6 25213.0 26117.2 4077.5 5391.8 5632.9 5697.3 5875.1 5031.8 6745.3 6937.1 7073.2 7202.5 6768.7 6873.6 6887.4 6958.3 6966.7 5542.8 6485.9 6642.5 6861.6 7029.8 6778.9 6796.9 6849.8 6890.6 6982.9 23984.3 24201.0 24432.4 24436.9 24598.3 1909.1 2004.2 2080.9 2394.7 2436.0 18390.5 18573.7 19010.5 19161.9 19208.5 1812.5 1873.4 1884.7 1924.3 1935.4 275668.0 285817.0 289797.0 290107.6 290933.7 36730.4 37021.3 37037.6 37224.3 38754.0 36907.4 37304.3 37702.6 37835.0 38974.2 9054.5 9160.4 9229.4 9299.4 9357.9 6327.3 6738.2 6987.6 7011.1 7234.0 8524.7 9049.7 9779.1 10094.1 10927.1 3665.4 3704.7 3852.8 3901.4 3929.4 486.1 539.8 577.5 584.1 593.8 16825.4 17256.6 17866.4 17921.2 18882.7 509.3 545.3 573.8 645.0 659.1 6946.8 8086.9 8727.7 9118.5 9163.4 345480.5 356322.0 364541.0 365680.0 377089.0 33279.0 34505.0 35285.8 35552.0 36342.7 731.0 807.5 813.0 818.6 818.7 13719.7 17646.6 17762.5 18230.8 18313.9 30099.4 30147.8 31322.2 31996.5 32848.7 305518.0 318279.0 321099.0 322332.0 328089.0 14076.2 15010.8 24478.1 24791.9 24803.5 25681.1 25956.4 26435.3 26647.2 27383.2 18151.5 21550.8 21564.7 22062.4 22411.8 19923.2 19967.4 20771.8 21532.0 21674.0 19358.6 19725.7 19788.1 20309.9 20655.8 17692.8 17991.9 19213.1 19731.0 19737.7 14204.9 14414.5 14509.4 14700.6 14706.2 02:01:56 INFO - PROCESS | 1671 | 02:01:56 INFO - PROCESS | 1671 | [#2] jquery.html Cycles:180 Average:18487.53 Median:24950.41 stddev:49412.27 (198.0%) stddev-sans-first:49531.85 02:01:56 INFO - PROCESS | 1671 | Values: 221.8 226.2 227.2 231.3 239.7 8414.2 8926.5 11524.4 12112.0 12620.2 9746.8 10936.9 11402.9 11696.8 12009.2 3207.1 3565.9 3572.4 3652.2 3711.6 3457.4 3533.7 3577.1 3608.5 3639.0 3079.0 3317.2 3396.5 3477.0 3478.4 3025.2 3043.7 3415.6 3521.7 3530.2 2903.4 2908.7 2988.8 3012.4 3058.4 13891.0 14091.6 14297.4 14647.8 14932.9 1124.7 1392.4 1689.7 1706.6 1895.7 8564.5 9003.3 9048.4 9389.6 9674.0 959.4 1039.8 1056.0 1170.7 1203.3 48128.5 60625.4 64340.0 64421.0 64482.4 5667.7 5927.7 5937.7 5952.6 5977.5 8186.4 8245.5 8321.3 8325.6 8476.2 4211.3 4451.9 4475.5 4780.0 4830.8 4748.9 4991.8 5032.7 5057.7 5166.9 3017.7 3853.9 3896.1 3905.9 4016.6 1067.8 1073.4 1083.6 1097.1 1136.3 501.7 552.3 553.6 555.3 663.0 9186.5 10819.7 12820.9 13494.0 14142.5 460.6 523.2 529.0 589.8 605.0 3050.3 3685.8 3729.5 3770.5 6648.7 248210.0 297028.0 301231.0 303888.0 309705.0 74550.6 77798.9 78025.2 79191.0 79389.0 250.9 268.2 271.4 272.7 279.0 6332.7 6508.2 6602.1 6621.2 7318.8 22711.0 24683.9 30441.4 31131.1 33734.0 45425.3 45814.8 45933.8 46699.5 46770.8 3743.8 3810.8 4188.9 4378.0 4758.6 6764.4 7359.7 7737.9 7806.3 8122.6 6176.8 6514.4 6666.2 6693.8 6724.6 6435.7 6493.6 6721.8 6896.6 7015.0 6299.2 6326.4 6432.0 6616.8 6824.5 4689.8 4861.6 4911.2 5062.2 5132.0 5067.7 6498.7 6617.7 6755.5 6773.7 02:01:56 INFO - PROCESS | 1671 | 02:01:56 INFO - PROCESS | 1671 | [#3] mootools.html Cycles:180 Average:3142.44 Median:4021.78 stddev:1731.42 (43.1%) stddev-sans-first:1727.23 02:01:56 INFO - PROCESS | 1671 | Values: 787.4 864.7 880.8 898.4 1022.5 3694.6 4009.5 4517.8 4571.5 4908.7 4015.6 4773.4 4867.3 4985.8 5013.7 1155.3 1349.3 1380.5 1404.9 1413.1 1155.2 1341.6 1363.4 1405.6 1491.9 1124.0 1150.9 1350.8 1371.5 1381.5 1336.6 1353.7 1356.9 1363.2 1377.4 1311.0 1358.1 1361.5 1376.6 1401.9 4778.3 4923.3 5023.7 5036.9 5097.7 2925.0 3142.4 3142.5 3191.4 3227.2 2055.7 2057.8 2058.4 2082.6 2100.0 525.5 596.4 600.2 607.8 620.2 4650.8 5717.9 6015.2 6039.3 6122.6 2476.5 2585.7 2602.3 2615.5 2737.0 5168.2 5234.5 5245.4 5255.5 5425.0 2927.7 3019.9 3052.9 3053.8 3172.0 2718.8 2805.8 2817.7 2872.1 2893.3 1906.2 2021.1 2052.1 2099.6 2202.2 523.8 590.4 656.9 657.6 665.6 468.2 505.9 543.3 547.3 568.3 2900.7 2967.9 3153.0 3251.6 3284.1 473.3 521.8 552.5 554.8 578.9 1757.1 1811.4 2214.7 2324.1 2380.2 5010.7 5971.8 6100.5 6139.3 6165.6 5799.9 5987.5 6018.9 6117.3 6117.8 4585.5 4597.9 4654.4 4672.8 4861.2 2730.3 3158.0 3229.3 3363.3 3454.0 4697.7 5473.8 5654.2 5722.7 5769.7 5630.0 5656.0 5729.7 5754.6 5808.9 3712.0 4023.9 4165.0 4231.1 4248.0 4375.5 4539.7 4567.0 4573.9 4650.0 4111.5 4236.0 4263.2 4320.2 4413.4 4019.7 4047.3 4074.9 4130.2 4169.7 3734.9 3864.4 3881.8 3914.3 3940.5 2342.3 2682.6 2759.4 2850.2 2851.0 2743.7 2775.4 2913.0 2938.5 2941.6 02:01:56 INFO - PROCESS | 1671 | 02:01:56 INFO - PROCESS | 1671 | [#4] prototype.html Cycles:180 Average:2718.36 Median:3074.15 stddev:863.59 (28.1%) stddev-sans-first:844.60 02:01:56 INFO - PROCESS | 1671 | Values: 171.9 174.7 195.8 210.8 214.1 2830.3 3107.3 3270.7 3324.9 3389.7 2944.8 3050.3 3064.8 3099.5 3243.4 1968.9 2174.8 2321.3 2370.5 2552.5 2227.2 2230.3 2264.2 2527.7 2527.9 2037.3 2344.4 2416.8 2463.9 2497.3 2285.7 2352.8 2361.4 2399.9 2459.1 2000.9 2035.7 2173.5 2250.3 2261.5 2810.3 3132.1 3224.8 3228.8 3268.7 2191.8 2501.6 2741.4 2798.3 2824.3 2930.0 3315.4 3456.5 3567.4 3650.4 2080.8 2248.4 2260.0 2431.8 2433.3 3059.9 3649.2 3688.1 3724.3 3825.4 2023.5 2242.2 2307.9 2333.5 2389.7 2206.2 2252.9 2269.6 2351.9 2395.3 2463.8 2519.7 2523.2 2705.2 2894.9 3034.5 3069.4 3422.3 3590.1 3613.3 681.8 719.9 751.3 760.0 760.8 447.9 454.8 482.9 515.9 518.9 2793.4 2833.9 3053.7 3207.6 3258.6 2940.5 3449.6 3617.3 3633.3 3701.8 2799.0 3071.9 3145.0 3264.2 3368.2 1675.2 1741.5 1756.7 1833.7 1871.8 3112.5 3150.5 3197.4 3319.9 3684.5 2950.9 3239.5 3378.4 3503.5 3667.3 2762.1 3101.2 3242.3 3589.2 3665.1 3211.3 3306.4 3381.7 3434.5 3468.2 3078.0 3298.3 3389.6 3402.0 3428.0 3073.7 3286.7 3512.8 3566.4 3747.7 1924.6 1960.4 2066.4 2192.8 2247.3 2737.0 3074.6 3492.6 3631.5 3636.1 2836.0 2858.2 3433.2 3513.8 3640.7 3452.2 3506.9 3508.6 3518.8 3583.9 2786.4 3160.7 3352.0 3363.4 3382.9 2842.1 2988.4 3360.4 3371.0 3576.6 2768.8 2901.0 3186.0 3367.6 3370.6 02:01:56 INFO - PROCESS | 1671 | 02:01:56 INFO - PROCESS | 1671 | [#5] yui.html Cycles:180 Average:12258.51 Median:2975.48 stddev:17949.94 (603.3%) stddev-sans-first:17978.12 02:01:56 INFO - PROCESS | 1671 | Values: 376.8 387.4 422.0 461.2 478.9 8046.8 8369.4 8391.9 8514.4 8637.5 7740.7 8218.8 8270.5 8379.8 8505.8 482.3 494.7 503.6 509.7 512.1 379.1 413.3 477.4 491.0 526.5 531.9 541.0 544.2 546.0 561.9 550.1 553.1 561.4 562.8 574.3 392.0 393.5 410.7 413.1 418.9 8495.5 8689.7 8754.8 8765.8 8852.8 6943.9 7817.8 8695.2 8749.2 8763.3 6785.7 6790.8 6920.2 6921.7 7103.2 2109.9 2190.5 2194.2 2293.5 2299.9 50956.7 66453.3 68591.7 68855.3 68903.0 5271.9 6096.6 6172.8 6191.8 6655.6 13094.0 13100.7 13145.6 13553.4 13851.8 3672.2 4328.4 4414.6 4480.5 4663.8 1934.8 2406.7 2455.9 2460.3 2464.5 1907.2 1990.2 2069.9 2103.4 2104.1 326.6 366.5 368.2 372.9 387.3 325.9 374.9 377.0 388.0 398.7 5158.4 5194.4 5410.0 5422.5 5562.6 364.3 376.8 381.0 394.5 416.1 2221.0 2349.6 2656.6 2727.9 2836.1 50044.5 62899.7 65324.4 65690.0 70680.0 45584.8 46165.3 46650.5 55531.7 59728.4 37891.7 39010.4 49521.5 50264.7 50325.5 5289.7 5473.9 5704.3 5848.0 6032.3 27549.6 29737.3 30020.7 31129.5 31519.1 44177.3 44220.6 45218.0 45546.3 45702.0 7386.5 7447.6 7545.6 7581.8 7754.2 7191.0 8693.3 8695.8 8725.9 9320.3 8011.6 8121.6 8230.7 8330.6 8368.5 7434.3 7831.4 7895.5 8391.9 8559.9 7731.4 7772.4 7878.7 8168.9 8290.8 6292.4 6648.3 6904.8 7115.7 7399.5 6483.7 6945.2 7061.9 7083.8 7561.0 02:01:56 INFO - PROCESS | 1671 | -------- Summary: end -------- 02:01:56 INFO - PROCESS | 1671 | 02:01:56 INFO - TEST-INFO | 1671: exit 0 02:01:56 INFO - TEST-OK | dromaeo_css | took 1123294ms 02:01:56 INFO - TEST-START | kraken 02:01:56 INFO - operating with platform_type : mac_ 02:01:56 INFO - Initialising browser for kraken test... 02:01:56 INFO - TEST-INFO | started process 1791 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/tmpPpvgfd/profile http://localhost:49249/getInfo.html) 02:02:01 INFO - PROCESS | 1791 | 2016-12-20 02:02:01.075 plugin-container[1792:10203] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x434b, name = 'com.apple.tsm.portname' 02:02:01 INFO - PROCESS | 1791 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:02:01 INFO - PROCESS | 1791 | 2016-12-20 02:02:01.079 plugin-container[1792:10203] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0f, name = 'com.apple.CFPasteboardClient' 02:02:01 INFO - PROCESS | 1791 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:02:01 INFO - PROCESS | 1791 | 2016-12-20 02:02:01.079 plugin-container[1792:10203] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:02:01 INFO - PROCESS | 1791 | __metrics Screen width/height:1600/1200 02:02:01 INFO - PROCESS | 1791 | colorDepth:24 02:02:01 INFO - PROCESS | 1791 | Browser inner width/height: 1024/689 02:02:01 INFO - PROCESS | 1791 | __metrics 02:02:01 ERROR - PROCESS | 1791 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3232: TypeError: this.tabs is undefined 02:02:02 INFO - TEST-INFO | 1791: exit 0 02:02:02 INFO - Browser initialized. 02:02:02 INFO - Running cycle 1/1 for kraken test... 02:02:02 INFO - TEST-INFO | started process 1794 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/tmpPpvgfd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:02:06 INFO - PROCESS | 1794 | 2016-12-20 02:02:06.036 plugin-container[1795:10327] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x433b, name = 'com.apple.tsm.portname' 02:02:06 INFO - PROCESS | 1794 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:02:06 INFO - PROCESS | 1794 | 2016-12-20 02:02:06.037 plugin-container[1795:10327] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4717, name = 'com.apple.CFPasteboardClient' 02:02:06 INFO - PROCESS | 1794 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:02:06 INFO - PROCESS | 1794 | 2016-12-20 02:02:06.037 plugin-container[1795:10327] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:02:15 INFO - PROCESS | 1794 | RSS: Main: 237199360 02:02:15 INFO - PROCESS | 1794 | 02:02:19 INFO - PROCESS | 1794 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-beat-detection) 02:02:20 INFO - PROCESS | 1794 | RSS: Main: 248311808 02:02:20 INFO - PROCESS | 1794 | 02:02:24 INFO - PROCESS | 1794 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-dft) 02:02:25 INFO - PROCESS | 1794 | RSS: Main: 256823296 02:02:25 INFO - PROCESS | 1794 | 02:02:30 INFO - PROCESS | 1794 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-fft) 02:02:31 INFO - PROCESS | 1794 | RSS: Main: 256458752 02:02:31 INFO - PROCESS | 1794 | 02:02:34 INFO - PROCESS | 1794 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-oscillator) 02:02:35 INFO - PROCESS | 1794 | RSS: Main: 266412032 02:02:35 INFO - PROCESS | 1794 | 02:02:38 INFO - PROCESS | 1794 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:02:38 INFO - PROCESS | 1794 | RSS: Main: 265527296 02:02:38 INFO - PROCESS | 1794 | 02:02:44 INFO - PROCESS | 1794 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-darkroom) 02:02:44 INFO - PROCESS | 1794 | RSS: Main: 260481024 02:02:44 INFO - PROCESS | 1794 | 02:02:50 INFO - PROCESS | 1794 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-desaturate) 02:02:50 INFO - PROCESS | 1794 | RSS: Main: 261459968 02:02:50 INFO - PROCESS | 1794 | 02:02:55 INFO - PROCESS | 1794 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49249/tests/kraken/driver.html?testName=json-parse-financial) 02:02:56 INFO - PROCESS | 1794 | RSS: Main: 260513792 02:02:56 INFO - PROCESS | 1794 | 02:02:58 INFO - PROCESS | 1794 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49249/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:02:59 INFO - PROCESS | 1794 | RSS: Main: 266014720 02:02:59 INFO - PROCESS | 1794 | 02:03:01 INFO - PROCESS | 1794 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:03:02 INFO - PROCESS | 1794 | RSS: Main: 267460608 02:03:02 INFO - PROCESS | 1794 | 02:03:04 INFO - PROCESS | 1794 | 1482228184846 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 02:03:06 INFO - PROCESS | 1794 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:03:07 INFO - PROCESS | 1794 | RSS: Main: 267599872 02:03:07 INFO - PROCESS | 1794 | 02:03:12 INFO - PROCESS | 1794 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:03:12 INFO - PROCESS | 1794 | RSS: Main: 259112960 02:03:12 INFO - PROCESS | 1794 | 02:03:17 INFO - PROCESS | 1794 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:03:18 INFO - PROCESS | 1794 | RSS: Main: 259817472 02:03:18 INFO - PROCESS | 1794 | 02:03:21 INFO - PROCESS | 1794 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49249/tests/kraken/driver.html?testName=ai-astar) 02:03:21 INFO - PROCESS | 1794 | RSS: Main: 264966144 02:03:21 INFO - PROCESS | 1794 | 02:03:21 INFO - PROCESS | 1794 | __start_tp_report 02:03:21 INFO - PROCESS | 1794 | _x_x_mozilla_page_load 02:03:21 INFO - PROCESS | 1794 | _x_x_mozilla_page_load_details 02:03:21 INFO - PROCESS | 1794 | |i|pagename|runs| 02:03:21 INFO - PROCESS | 1794 | |0;ai-astar;188;192;194;205;216;264;204;197;208;198 02:03:21 INFO - PROCESS | 1794 | |1;audio-beat-detection;294;183;186;257;236;276;203;211;202;199 02:03:21 INFO - PROCESS | 1794 | |2;audio-dft;277;358;267;266;288;275;311;279;277;274 02:03:21 INFO - PROCESS | 1794 | |3;audio-fft;155;110;96;177;144;144;126;90;178;200 02:03:21 INFO - PROCESS | 1794 | |4;audio-oscillator;155;121;143;131;125;113;141;123;153;186 02:03:21 INFO - PROCESS | 1794 | |5;imaging-gaussian-blur;208;248;255;246;207;216;218;298;203;199 02:03:21 INFO - PROCESS | 1794 | |6;imaging-darkroom;168;208;164;256;201;256;236;260;215;273 02:03:21 INFO - PROCESS | 1794 | |7;imaging-desaturate;168;163;158;147;156;238;171;164;171;161 02:03:21 INFO - PROCESS | 1794 | |8;json-parse-financial;109;102;98;97;99;94;113;103;100;97 02:03:21 INFO - PROCESS | 1794 | |9;json-stringify-tinderbox;131;134;142;145;142;114;128;117;128;121 02:03:21 INFO - PROCESS | 1794 | |10;stanford-crypto-aes;170;172;185;181;175;182;187;192;192;161 02:03:21 INFO - PROCESS | 1794 | |11;stanford-crypto-ccm;253;277;254;294;264;315;243;216;403;282 02:03:21 INFO - PROCESS | 1794 | |12;stanford-crypto-pbkdf2;280;387;290;393;378;299;399;320;332;392 02:03:21 INFO - PROCESS | 1794 | |13;stanford-crypto-sha256-iterative;124;115;137;160;113;123;134;135;149;126 02:03:21 INFO - PROCESS | 1794 | __end_tp_report 02:03:21 INFO - PROCESS | 1794 | __start_cc_report 02:03:21 INFO - PROCESS | 1794 | _x_x_mozilla_cycle_collect,1390 02:03:21 INFO - PROCESS | 1794 | __end_cc_report 02:03:21 INFO - PROCESS | 1794 | __startTimestamp1482228201564__endTimestamp 02:03:21 INFO - PROCESS | 1794 | 02:03:21 INFO - PROCESS | 1794 | ------- Summary: start ------- 02:03:21 INFO - PROCESS | 1794 | Number of tests: 14 02:03:21 INFO - PROCESS | 1794 | 02:03:21 INFO - PROCESS | 1794 | [#0] ai-astar Cycles:10 Average:206.60 Median:204.50 stddev:21.80 (10.7%) stddev-sans-first:22.06 02:03:21 INFO - PROCESS | 1794 | Values: 188.0 192.0 194.0 205.0 216.0 264.0 204.0 197.0 208.0 198.0 02:03:21 INFO - PROCESS | 1794 | 02:03:21 INFO - PROCESS | 1794 | [#1] audio-beat-detection Cycles:10 Average:224.70 Median:223.50 stddev:38.98 (17.4%) stddev-sans-first:32.29 02:03:21 INFO - PROCESS | 1794 | Values: 294.0 183.0 186.0 257.0 236.0 276.0 203.0 211.0 202.0 199.0 02:03:21 INFO - PROCESS | 1794 | 02:03:21 INFO - PROCESS | 1794 | [#2] audio-dft Cycles:10 Average:287.20 Median:278.00 stddev:27.96 (10.1%) stddev-sans-first:29.41 02:03:21 INFO - PROCESS | 1794 | Values: 277.0 358.0 267.0 266.0 288.0 275.0 311.0 279.0 277.0 274.0 02:03:21 INFO - PROCESS | 1794 | 02:03:21 INFO - PROCESS | 1794 | [#3] audio-fft Cycles:10 Average:142.00 Median:177.50 stddev:36.76 (20.7%) stddev-sans-first:38.69 02:03:21 INFO - PROCESS | 1794 | Values: 155.0 110.0 96.0 177.0 144.0 144.0 126.0 90.0 178.0 200.0 02:03:21 INFO - PROCESS | 1794 | 02:03:21 INFO - PROCESS | 1794 | [#4] audio-oscillator Cycles:10 Average:139.10 Median:142.00 stddev:21.59 (15.2%) stddev-sans-first:22.12 02:03:21 INFO - PROCESS | 1794 | Values: 155.0 121.0 143.0 131.0 125.0 113.0 141.0 123.0 153.0 186.0 02:03:21 INFO - PROCESS | 1794 | 02:03:21 INFO - PROCESS | 1794 | [#5] imaging-gaussian-blur Cycles:10 Average:229.80 Median:232.00 stddev:31.40 (13.5%) stddev-sans-first:32.29 02:03:21 INFO - PROCESS | 1794 | Values: 208.0 248.0 255.0 246.0 207.0 216.0 218.0 298.0 203.0 199.0 02:03:21 INFO - PROCESS | 1794 | 02:03:21 INFO - PROCESS | 1794 | [#6] imaging-darkroom Cycles:10 Average:223.70 Median:246.00 stddev:38.72 (15.7%) stddev-sans-first:35.43 02:03:21 INFO - PROCESS | 1794 | Values: 168.0 208.0 164.0 256.0 201.0 256.0 236.0 260.0 215.0 273.0 02:03:21 INFO - PROCESS | 1794 | 02:03:21 INFO - PROCESS | 1794 | [#7] imaging-desaturate Cycles:10 Average:169.70 Median:166.00 stddev:25.09 (15.1%) stddev-sans-first:26.60 02:03:21 INFO - PROCESS | 1794 | Values: 168.0 163.0 158.0 147.0 156.0 238.0 171.0 164.0 171.0 161.0 02:03:21 INFO - PROCESS | 1794 | 02:03:21 INFO - PROCESS | 1794 | [#8] json-parse-financial Cycles:10 Average:101.20 Median:95.50 stddev:5.85 (6.1%) stddev-sans-first:5.48 02:03:21 INFO - PROCESS | 1794 | Values: 109.0 102.0 98.0 97.0 99.0 94.0 113.0 103.0 100.0 97.0 02:03:21 INFO - PROCESS | 1794 | 02:03:21 INFO - PROCESS | 1794 | [#9] json-stringify-tinderbox Cycles:10 Average:130.20 Median:132.50 stddev:10.77 (8.1%) stddev-sans-first:11.42 02:03:21 INFO - PROCESS | 1794 | Values: 131.0 134.0 142.0 145.0 142.0 114.0 128.0 117.0 128.0 121.0 02:03:21 INFO - PROCESS | 1794 | 02:03:21 INFO - PROCESS | 1794 | [#10] stanford-crypto-aes Cycles:10 Average:179.70 Median:183.50 stddev:10.09 (5.5%) stddev-sans-first:10.07 02:03:21 INFO - PROCESS | 1794 | Values: 170.0 172.0 185.0 181.0 175.0 182.0 187.0 192.0 192.0 161.0 02:03:21 INFO - PROCESS | 1794 | 02:03:21 INFO - PROCESS | 1794 | [#11] stanford-crypto-ccm Cycles:10 Average:280.10 Median:279.50 stddev:51.30 (18.4%) stddev-sans-first:53.47 02:03:21 INFO - PROCESS | 1794 | Values: 253.0 277.0 254.0 294.0 264.0 315.0 243.0 216.0 403.0 282.0 02:03:21 INFO - PROCESS | 1794 | 02:03:21 INFO - PROCESS | 1794 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:347.00 Median:382.50 stddev:47.61 (12.4%) stddev-sans-first:43.90 02:03:21 INFO - PROCESS | 1794 | Values: 280.0 387.0 290.0 393.0 378.0 299.0 399.0 320.0 332.0 392.0 02:03:21 INFO - PROCESS | 1794 | 02:03:21 INFO - PROCESS | 1794 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:131.60 Median:134.50 stddev:14.68 (10.9%) stddev-sans-first:15.31 02:03:21 INFO - PROCESS | 1794 | Values: 124.0 115.0 137.0 160.0 113.0 123.0 134.0 135.0 149.0 126.0 02:03:21 INFO - PROCESS | 1794 | -------- Summary: end -------- 02:03:21 INFO - PROCESS | 1794 | 02:03:22 INFO - TEST-INFO | 1794: exit 0 02:03:22 INFO - TEST-OK | kraken | took 85705ms 02:03:22 INFO - SUITE-END | took 1209s 02:03:23 INFO - Completed test suite (00:20:09) 02:03:23 INFO - javascript benchmark 02:03:23 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 4214.03484249472, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1691.890027148235, 1989.4330115916957, 2042.2749923777064, 2068.1520576563603, 2137.9251726189586, 3202.791818531794, 3228.967710322896, 3272.1655977725645, 3313.7349012079035, 3361.7982921024727, 3119.0486901495033, 3193.0, 3267.18320419895, 3328.5839270091237, 3380.7971521708714, 2960.1267626050258, 2966.3622321200937, 3023.108183085984, 3097.0, 3144.0071998655053, 2467.3954881054165, 2901.7968742188054, 2936.6769655337894, 3024.5311976643657, 3143.32418530016, 3058.189579761365, 3090.4900691385897, 3107.7047680470546, 3176.3647270545985, 3216.4049650814673, 3022.2142243016756, 3065.662777094518, 3073.2777797217636, 3142.1516190628404, 3144.701253380925, 2763.309172706823, 2896.594476773232, 3006.8496575171152, 3091.31990961988, 3230.8223047732367, 2695.7169497202904, 3289.6052473703307, 3321.319129578455, 3412.97610716784, 3483.89548313551, 2996.8501574921384, 3234.4663130583426, 3424.6404127566743, 3551.058969373092, 3602.945955810665, 3699.1676872703697, 3699.7595156314756, 3704.3887758519813, 3736.159364143073, 3747.775133491999, 2444.4622183119686, 2830.7876909231686, 3284.636875696583, 3456.723462123024, 3469.9826500867334, 3603.657652523006, 3690.4279836625365, 3705.9073523161596, 3772.7170462215445, 3934.586868378779, 2877.7266159714795, 3463.8094904779973, 3504.9123771905906, 3583.211693427442, 3707.888763337104, 3647.85408583659, 3658.817059147032, 3662.1943172502006, 3679.9264014719556, 3689.594144644087, 3369.7809642373168, 3458.4293591557616, 3520.331137083997, 3532.2405682778326, 3629.9637003630155, 3345.0, 3394.117529442363, 3402.7958322500235, 3473.6873681368293, 3601.5858176309534, 2735.2478068530913, 2789.4700006998605, 2829.2926768307925, 2840.389316297006, 2864.6705628852947, 2562.628418879252, 2563.7564431378987, 2595.545779488582, 2611.321056525317, 2671.906483273076, 3335.766496345281, 3350.162459385154, 3378.408778463759, 3450.6721861423125, 3499.842507087187, 3554.306910152495, 3621.80080095592, 3634.618365071682, 3691.8186180422545, 3737.9252414951548, 3154.1010811918495, 3230.870765169414, 3330.8667653293596, 3462.926492787244, 3467.5318831957866, 2902.969445846694, 2963.9851800740857, 3116.7818252722545, 3122.09459256813, 3266.950995735066, 2747.3543717226435, 3085.889079931223, 3750.5686846012513, 3847.96152038476, 3884.320243957296, 3591.0842735102383, 3628.510151129564, 3638.363286424812, 3742.5134732484603, 3780.5652349980105, 3648.160922987781, 3717.665410113103, 3769.096606213954, 3921.6078392160557, 3948.5853985332274, 3564.0555252857494, 3593.443016332473, 3626.727995400303, 3628.6552777486095, 3774.7923864186637, 3544.2379888324135, 3584.336897673939, 3691.5939246682306, 3732.141607430252, 3745.5692595350793, 2720.7687346575763, 3379.847906844149, 3463.4458486642015, 3468.2716629508086, 3486.7559270850797, 3388.4070287700047, 3408.16499957512, 3462.0825481247994, 3486.9825650871585, 3561.4835848802295, 2787.9823864288983, 3272.0183944815985, 3297.1097803592415, 3347.6652334767305, 3510.36813373585, 3419.1964888251737, 3464.636213197578, 3476.9304613907075, 3582.4984502169195, 3588.0, 2879.3953269812737, 2960.4523163214876, 2998.1605150557007, 3465.376232278113, 3648.0], "value": 3269.9796635803577, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [238.58367149324425, 252.21183800623052, 299.30262488402025, 299.5342242812426, 312.4937601086239, 21978.340649780494, 23136.30591082266, 23358.64962025569, 23938.880305598468, 24500.01999920001, 22397.21609743655, 25112.0, 25118.623220651705, 25212.991480340763, 26117.21648350548, 4077.531083925357, 5391.757370918299, 5632.887342253173, 5697.316322041361, 5875.148103524986, 5031.773570189332, 6745.291744366845, 6937.098177236978, 7073.221945586007, 7202.459815513831, 6768.661566921659, 6873.553219040771, 6887.449004079662, 6958.338957799001, 6966.721331146748, 5542.806001789938, 6485.93514064858, 6642.501812364092, 6861.5539989901, 7029.789106326818, 6778.932210677879, 6796.932030679679, 6849.835527960258, 6890.586564806128, 6982.895256571155, 23984.28047158588, 24201.0, 24432.38919027021, 24436.877815610987, 24598.310253461947, 1909.0645583664043, 2004.1682701678785, 2080.864743791649, 2394.736578976311, 2436.0499405231794, 18390.54023649406, 18573.721394179098, 19010.52473688169, 19161.94609296488, 19208.519787005436, 1812.4925020994142, 1873.4286042756953, 1884.7172924061363, 1924.3361040441025, 1935.371004423568, 275668.0, 285817.0, 289796.9999999979, 290107.6483852744, 290933.72665683314, 36730.44904326384, 37021.259574808355, 37037.62962370342, 37224.255514890094, 38754.0, 36907.4463883042, 37304.25391492155, 37702.62297376991, 37835.0, 38974.22051558953, 9054.502002389932, 9160.358774885693, 9229.400088994194, 9299.395539289924, 9357.877054753473, 6327.272363678144, 6738.191417029988, 6987.637410704864, 7011.053507776487, 7234.0, 8524.701635442854, 9049.728508144766, 9779.070988256104, 10094.09153176203, 10927.071198948179, 3665.395209790408, 3704.7036237100965, 3852.788096654711, 3901.356276214393, 3929.44987701722, 486.14438588085227, 539.7723052238999, 577.535084257177, 584.1383958660941, 593.8070127208674, 16825.411110611316, 17256.568585785455, 17866.374676886247, 17921.193546290448, 18882.716759248622, 509.3124282219049, 545.2938444714105, 573.8221151443066, 644.9806505804834, 659.1398225316009, 6946.79159625212, 8086.867838502614, 8727.65089396417, 9118.452892826452, 9163.358564900527, 345480.54519454984, 356322.0, 364541.0, 365680.0, 377089.0, 33279.0, 34505.0, 35285.823570882494, 35552.0, 36342.72800451971, 730.9890351644623, 807.5356669914705, 813.040612077754, 818.6234332207251, 818.6807145213371, 13719.725605488034, 17646.64706705833, 17762.46712598624, 18230.81769182291, 18313.90116593009, 30099.398012040074, 30147.794088236224, 31322.21694457657, 31996.5200521997, 32848.67151328552, 305518.0, 318279.0, 321099.0, 322332.0, 328089.0, 14076.170097887209, 15010.849891500946, 24478.14326498564, 24791.87604061968, 24803.50392992094, 25681.10116145926, 25956.35109122287, 26435.339116522242, 26647.20058398251, 27383.178504644893, 18151.45545633633, 21550.814705191366, 21564.67652985238, 22062.448438789157, 22411.77588224097, 19923.203071876964, 19967.400977970683, 20771.753694778363, 21532.0, 21674.0, 19358.612827743647, 19725.70411443801, 19788.109535070667, 20309.89845050765, 20655.760654360787, 17692.82307176912, 17991.910040449715, 19213.135408906353, 19731.0, 19737.70393444128, 14204.857951420354, 14414.495492657707, 14509.419623214953, 14700.55898323052, 14706.191159485903], "value": 11762.827381201427, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [221.8236501980925, 226.1609429018341, 227.23988259272727, 231.31645986111045, 239.68720819330767, 8414.200650938152, 8926.464412135276, 11524.423778811057, 12112.0, 12620.179688320264, 9746.756331091727, 10936.890631093687, 11402.885971140286, 11696.824547631772, 12009.159358844863, 3207.1340738000786, 3565.8751943681978, 3572.428411454168, 3652.1600031992657, 3711.5917249102713, 3457.3776720190353, 3533.681968622823, 3577.141486043344, 3608.4948107264872, 3639.0, 3078.9993252193017, 3317.1541256979435, 3396.507506411569, 3477.0, 3478.3738926993133, 3025.2134445044226, 3043.6956304369605, 3415.590129184489, 3521.6654417830523, 3530.170409953672, 2903.3612605226817, 2908.650961884566, 2988.775841811851, 3012.3523442459978, 3058.388322335542, 13891.02762806604, 14091.647708807259, 14297.428102875872, 14647.780283295759, 14932.925335373362, 1124.6569796212234, 1392.4291040673274, 1689.6620675864754, 1706.5648259693698, 1895.6777347850898, 8564.48613083217, 9003.305339471313, 9048.366614337, 9389.577469013911, 9673.979129082758, 959.4003747657714, 1039.7712503249272, 1055.9651541489384, 1170.678063532527, 1203.314110956755, 48128.51871481275, 60625.393746062415, 64340.0, 64421.0, 64482.355176448575, 5667.716614169275, 5927.73325200367, 5937.732802023919, 5952.613080149777, 5977.491913187341, 8186.386021048445, 8245.546494942835, 8321.334293256525, 8325.583720813935, 8476.194761497647, 4211.284081706117, 4451.933221001687, 4475.507694153641, 4780.0, 4830.806767729322, 4748.936935676469, 4991.825286114969, 5032.748362581856, 5057.671251368649, 5166.896662066738, 3017.7434918032213, 3853.9421908671393, 3896.123372241223, 3905.9218815624095, 4016.638502534786, 1067.8451624514403, 1073.3613499967507, 1083.5557421457165, 1097.1058587251364, 1136.2841409911703, 501.6989806116301, 552.320645605907, 553.6014069869688, 555.2836840475821, 662.9734810607522, 9186.540672966325, 10819.67540973772, 12820.935895320463, 13493.987950903722, 14142.50501232452, 460.5555638808566, 523.233462976741, 528.9761960711777, 589.8466398736359, 604.9939500605026, 3050.313679422157, 3685.7788532687587, 3729.4778730977687, 3770.472133901256, 6648.700808463631, 248210.0, 297028.0, 301231.0, 303888.0, 309705.0, 74550.62724686341, 77798.88804447872, 78025.21974780293, 79191.0, 79389.0, 250.93601131711296, 268.1847184558919, 271.3947896191504, 272.69185820023245, 278.9692087735837, 6332.746690132343, 6508.219013718384, 6602.075709400592, 6621.172353455818, 7318.817029574303, 22710.978005990102, 24683.88922498528, 30441.391172176875, 31131.066068017997, 33733.98798035965, 45425.318620221384, 45814.77092614649, 45933.77033114813, 46699.533004669516, 46770.76614616905, 3743.831527581211, 3810.809459526957, 4188.937165942452, 4378.0, 4758.595519380891, 6764.391204791593, 7359.742409015659, 7737.92262077372, 7806.336461400844, 8122.55325957078, 6176.81469555914, 6514.381133792472, 6666.233383160805, 6693.823078302109, 6724.562903411263, 6435.710393032231, 6493.642849643315, 6721.764738234138, 6896.551724137915, 7015.0, 6299.244090709144, 6326.398992095744, 6432.0, 6616.801495954936, 6824.488163387865, 4689.7655117244685, 4861.562459378673, 4911.214205727067, 5062.215356619729, 5132.0, 5067.746612669278, 6498.74005039812, 6617.669116544057, 6755.527113102232, 6773.695183716646], "value": 5348.946910222281, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [787.3976408047845, 864.6671031652812, 880.8458519759045, 898.3576742629019, 1022.4529876516066, 3694.556653201613, 4009.4787677601907, 4517.847948773062, 4571.474280457748, 4908.7300198489065, 4015.578364271746, 4773.355596994405, 4867.2942423348595, 4985.775640096195, 5013.6741111827705, 1155.3010428690648, 1349.3118509560097, 1380.475419340649, 1404.8735613794756, 1413.1379858286437, 1155.2490880927423, 1341.5505805555151, 1363.4205462678372, 1405.5783265020505, 1491.9030263032923, 1123.9715660170934, 1150.9424528773527, 1350.8311461067367, 1371.5405339211377, 1381.5233744358231, 1336.5722968649986, 1353.7495563320817, 1356.945722171112, 1363.2433999130478, 1377.442135934948, 1310.9708878530307, 1358.0561509750728, 1361.4758318047525, 1376.5595009596932, 1401.9158850468903, 4778.283257511366, 4923.261510773377, 5023.748812559394, 5036.874078148076, 5097.745112744385, 2924.985375073132, 3142.355817057498, 3142.5286207068893, 3191.4414977378865, 3227.177069847174, 2055.691646253059, 2057.753069631639, 2058.433930669063, 2082.562661841015, 2100.0024988130667, 525.4509038055241, 596.3946594206883, 600.2466904035408, 607.7568972411027, 620.2309136670541, 4650.813967441299, 5717.885642287131, 6015.157877897098, 6039.305479869828, 6122.571420000601, 2476.4987362763736, 2585.728498507667, 2602.349412646838, 2615.4769046190836, 2737.0, 5168.198929165986, 5234.502722241381, 5245.396779370345, 5255.500727430888, 5425.02349577072, 2927.736503714676, 3019.8675496688743, 3052.8778848845795, 3053.7709671774705, 3171.9841400792848, 2718.7553120219277, 2805.803593748418, 2817.7323154300307, 2872.138358492444, 2893.348996475776, 1906.218450435315, 2021.14101506859, 2052.138101997165, 2099.643060679688, 2202.1851914791628, 523.8245187862033, 590.35356284868, 656.917885264342, 657.5824351536713, 665.6172700697118, 468.24846121974423, 505.86847419671153, 543.2991441041013, 547.2666626720214, 568.3435631845225, 2900.6664233612983, 2967.925801854971, 3152.9684703152675, 3251.634313588251, 3284.080457471864, 473.2782506677276, 521.7964993652479, 552.4917076289823, 554.8224568138157, 578.8986927287708, 1757.0775342945055, 1811.3569682762693, 2214.6788715636467, 2324.0936034946385, 2380.214529205358, 5010.674306170087, 5971.761129554856, 6100.481459076028, 6139.324674285825, 6165.599236049642, 5799.855003624859, 5987.491063259585, 6018.946684330224, 6117.265928088569, 6117.755289788359, 4585.495595484494, 4597.862064138081, 4654.394928659252, 4672.789724462339, 4861.246506791453, 2730.30377253799, 3158.0367987763952, 3229.273413481948, 3363.276895467486, 3453.9810755827593, 4697.718136911796, 5473.8357849264585, 5654.208410822571, 5722.713864306685, 5769.740361683818, 5629.971850140723, 5655.971720141538, 5729.656220626775, 5754.5684073695475, 5808.883822323614, 3711.9628803711616, 4023.879283621496, 4164.979580002919, 4231.090315582164, 4247.957520424756, 4375.453068366454, 4539.72761634303, 4566.995261042565, 4573.908521829479, 4649.953760403882, 4111.527174374865, 4235.957640423557, 4263.189993901149, 4320.243957307522, 4413.44831896013, 4019.6784257258764, 4047.271491131507, 4074.938875916923, 4130.215259100762, 4169.749815011109, 3734.925301494009, 3864.401017842239, 3881.80590970456, 3914.2758589661003, 3940.4877365942243, 2342.3090188394804, 2682.570788673803, 2759.406727553586, 2850.159203035151, 2850.9714902850706, 2743.7256274373203, 2775.3894143288444, 2912.985435072811, 2938.5445255985355, 2941.5734718465274], "value": 2539.5924890581405, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [171.86680322749868, 174.66551553774522, 195.75335077801967, 210.80711149298392, 214.10092479705028, 2830.2829283887495, 3107.3008573071047, 3270.721988630963, 3324.933501329972, 3389.677980591846, 2944.7644188464924, 3050.2984313607885, 3064.7854650174495, 3099.51223412762, 3243.351329734057, 1968.8974174462346, 2174.8042676159143, 2321.307283551081, 2370.4754436525045, 2552.515022145791, 2227.24273746926, 2230.29745630126, 2264.230161745006, 2527.671402717644, 2527.936801579957, 2037.3073155127254, 2344.39045848079, 2416.8187385946035, 2463.9014439422403, 2497.3382053755768, 2285.748567657548, 2352.764723527651, 2361.350628577155, 2399.928002159938, 2459.053264493174, 2000.8899510526915, 2035.735354403918, 2173.4892300309416, 2250.3361508355074, 2261.536385041079, 2810.2576402630298, 3132.1073494054085, 3224.774265801395, 3228.757843161749, 3268.6894744999186, 2191.791779780918, 2501.599744040963, 2741.3557813913712, 2798.2716851070245, 2824.3221626809623, 2929.9853500732575, 3315.386653469116, 3456.4815277708294, 3567.4492737692676, 3650.4159334506617, 2080.843936704753, 2248.359217622986, 2259.9943025353723, 2431.756824317572, 2433.3429973907123, 3059.92350191247, 3649.164856565062, 3688.0779805048737, 3724.3110024645257, 3825.4070619053814, 2023.5345870449878, 2242.159190303636, 2307.8846057697383, 2333.4633034401845, 2389.7132344118468, 2206.165077874923, 2252.8735632183907, 2269.580127676385, 2351.9059237630645, 2395.3412811476633, 2463.790577800907, 2519.7102333231546, 2523.205190365029, 2705.240654532366, 2894.884204631833, 3034.5296479045783, 3069.4499277864534, 3422.3326451342245, 3590.084528445282, 3613.2773445311045, 681.8022773395759, 719.8704233237962, 751.3087959077661, 760.0461420916714, 760.7907825347963, 447.9014616784302, 454.84762604527856, 482.9492903245179, 515.9226116082542, 518.8547206782032, 2793.427347393779, 2833.8583070846375, 3053.740432063255, 3207.615086189672, 3258.576385069926, 2940.514815055492, 3449.6032956210356, 3617.3488772020764, 3633.2733453309443, 3701.833417496219, 2798.978372893879, 3071.8924837630575, 3144.9528257076163, 3264.249222678797, 3368.208471009311, 1675.227498490001, 1741.5471977285742, 1756.7277072053853, 1833.6974399224246, 1871.756671632679, 3112.459332630317, 3150.5431712401573, 3197.3765115802194, 3319.850606722703, 3684.4841722158917, 2950.8524573771224, 3239.49787782894, 3378.4256676364585, 3503.474478828145, 3667.3215455140885, 2762.0784864886527, 3101.240196151957, 3242.302904875463, 3589.1695235429597, 3665.1020499977144, 3211.261409875695, 3306.363350141599, 3381.7294616430136, 3434.536337594393, 3468.2196505785996, 3078.0150351887182, 3298.307355455381, 3389.6271410144373, 3402.0134161093006, 3427.9887433207764, 3073.7264812974113, 3286.6549012354317, 3512.7892326460496, 3566.375884220303, 3747.70018398522, 1924.595834874692, 1960.3628820633066, 2066.4214020074405, 2192.7807219277943, 2247.258404726469, 2736.986315068412, 3074.5695602616097, 3492.5634295713035, 3631.4734363516613, 3636.1091532574687, 2835.9716402836534, 2858.19970408286, 3433.2446861690387, 3513.841877115587, 3640.7269454790467, 3452.1508518001747, 3506.8772592959126, 3508.561429821272, 3518.7712798668003, 3583.874564390234, 2786.3869948611273, 3160.7313378363096, 3351.983240083784, 3363.4114030044157, 3382.8646854126546, 2842.147355793295, 2988.402319536058, 3360.3951288768258, 3370.983145084259, 3576.6065732768852, 2768.750812426891, 2900.9684879300758, 3186.04058504023, 3367.629560748365, 3370.612379576282], "value": 2443.325377888863, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [376.8398430666966, 387.41693750904886, 421.9725717828343, 461.22053729197665, 478.8946431785006, 8046.839063218732, 8369.414141010131, 8391.922437838999, 8514.403991720583, 8637.481751094938, 7740.72907448238, 8218.835623287545, 8270.503769773817, 8379.790505237372, 8505.829883402328, 482.25973131243546, 494.720482927145, 503.62479952435484, 509.66362200947384, 512.1140427061187, 379.13178820501156, 413.32627816658805, 477.40562999066213, 490.99263511047366, 526.4682670502782, 531.9255104688527, 540.9864753381178, 544.1810075835881, 546.0062685911643, 561.8651523634339, 550.1307933465115, 553.1288221051819, 561.4469747298887, 562.831150654804, 574.2907509226102, 392.0434140696691, 393.5297319702956, 410.6940329454546, 413.0726518964913, 418.9183109293689, 8495.532745698983, 8689.69586064491, 8754.824903501894, 8765.7808554786, 8852.822943541158, 6943.923691827775, 7817.804554886174, 8695.217430431228, 8749.16882896124, 8763.298936085097, 6785.694643741043, 6790.76232331871, 6920.169579650475, 6921.688524016431, 7103.218645948941, 2109.9367018989456, 2190.5290362572123, 2194.2429861697688, 2293.5068960173644, 2299.9305323024873, 50956.74521627368, 66453.33546664519, 68591.65704171498, 68855.3114468859, 68902.96645550372, 5271.868203294872, 6096.634201947897, 6172.814815555451, 6191.752329906753, 6655.6339401332425, 13094.0, 13100.737985240432, 13145.60563183087, 13553.39009744564, 13851.79222311646, 3672.192117734094, 4328.3723860040745, 4414.624756895636, 4480.462344518679, 4663.813447462131, 1934.8258656720964, 2406.7111946566174, 2455.8649274290087, 2460.2619214235656, 2464.470138920105, 1907.237105157948, 1990.1840245499566, 2069.9379018629465, 2103.379503046605, 2104.116271166083, 326.59991510400044, 366.5107082045499, 368.23407312789334, 372.89186136020794, 387.3105871548648, 325.94231718074997, 374.88378602632724, 376.9812653241905, 388.00089768845334, 398.70695039146204, 5158.406783219978, 5194.35070616173, 5409.972950135224, 5422.539084177889, 5562.554995600342, 364.27145708582833, 376.7984128491244, 380.96761775249416, 394.459590361207, 416.0846435046201, 2220.9888950555146, 2349.5888219561507, 2656.5521790624157, 2727.890884364603, 2836.1066264126734, 50044.49955500471, 62899.68550157311, 65324.36689082811, 65690.0, 70680.0, 45584.77207614007, 46165.30752038722, 46650.533494665295, 55531.722341388035, 59728.40271597228, 37891.726496410854, 39010.43958241752, 49521.50478495241, 50264.748676256386, 50325.496745032084, 5289.7355132244, 5473.890522189454, 5704.258446401941, 5847.970760146172, 6032.336442991355, 27549.58675619827, 29737.25656858596, 30020.699793002663, 31129.46281280866, 31519.054428367184, 44177.33733993928, 44220.55779442164, 45218.0, 45546.31680524728, 45702.0, 7386.519876208029, 7447.590382528796, 7545.622718863925, 7581.848363032819, 7754.185810489817, 7190.96404517974, 8693.347998900234, 8695.826083478169, 8725.91274087251, 9320.300977426585, 8011.599420028859, 8121.634526446206, 8230.711925082593, 8330.625121869653, 8368.539730314891, 7434.256574342522, 7831.373490120663, 7895.526268423913, 8391.874121888299, 8559.918938289204, 7731.381489480716, 7772.417068719756, 7878.684852605831, 8168.897198878075, 8290.751277461686, 6292.370762923671, 6648.268690443951, 6904.758333458305, 7115.715371385087, 7399.519031263166, 6483.708233129426, 6945.166580010431, 7061.858762824612, 7083.787486375417, 7561.0], "value": 4385.690837824327, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 2792.6000000000004, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [188.0, 192.0, 194.0, 205.0, 216.0, 264.0, 204.0, 197.0, 208.0, 198.0], "value": 206.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [294.0, 183.0, 186.0, 257.0, 236.0, 276.0, 203.0, 211.0, 202.0, 199.0], "value": 224.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [277.0, 358.0, 267.0, 266.0, 288.0, 275.0, 311.0, 279.0, 277.0, 274.0], "value": 287.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 110.0, 96.0, 177.0, 144.0, 144.0, 126.0, 90.0, 178.0, 200.0], "value": 142.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 121.0, 143.0, 131.0, 125.0, 113.0, 141.0, 123.0, 153.0, 186.0], "value": 139.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [208.0, 248.0, 255.0, 246.0, 207.0, 216.0, 218.0, 298.0, 203.0, 199.0], "value": 229.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 208.0, 164.0, 256.0, 201.0, 256.0, 236.0, 260.0, 215.0, 273.0], "value": 223.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 163.0, 158.0, 147.0, 156.0, 238.0, 171.0, 164.0, 171.0, 161.0], "value": 169.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 102.0, 98.0, 97.0, 99.0, 94.0, 113.0, 103.0, 100.0, 97.0], "value": 101.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 134.0, 142.0, 145.0, 142.0, 114.0, 128.0, 117.0, 128.0, 121.0], "value": 130.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 172.0, 185.0, 181.0, 175.0, 182.0, 187.0, 192.0, 192.0, 161.0], "value": 179.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [253.0, 277.0, 254.0, 294.0, 264.0, 315.0, 243.0, 216.0, 403.0, 282.0], "value": 280.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [280.0, 387.0, 290.0, 393.0, 378.0, 299.0, 399.0, 320.0, 332.0, 392.0], "value": 347.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 115.0, 137.0, 160.0, 113.0, 123.0, 134.0, 135.0, 149.0, 126.0], "value": 131.6, "unit": "score"}]}]} 02:03:23 INFO - Return code: 0 02:03:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:03:23 ERROR - # TBPL SUCCESS # 02:03:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:03:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:03:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:03:23 INFO - 2016-12-20 02:03:23.359 screenresolution[1807:10729] starting screenresolution argv=screenresolution get 02:03:23 INFO - 2016-12-20 02:03:23.363 screenresolution[1807:10729] Display 0: 1600x1200x32@60 02:03:23 INFO - 2016-12-20 02:03:23.371 screenresolution[1808:10732] starting screenresolution argv=screenresolution list 02:03:23 INFO - Available Modes on Display 0 02:03:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:03:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:03:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:03:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:03:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:03:23 INFO - Intel Iris: 02:03:23 INFO - Chipset Model: Intel Iris 02:03:23 INFO - Type: GPU 02:03:23 INFO - Bus: Built-In 02:03:23 INFO - VRAM (Dynamic, Max): 1536 MB 02:03:23 INFO - Vendor: Intel (0x8086) 02:03:23 INFO - Device ID: 0x0a2e 02:03:23 INFO - Revision ID: 0x0009 02:03:23 INFO - Displays: 02:03:23 INFO - Display: 02:03:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:03:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:03:23 INFO - Display Serial Number: 1600x1200 60 02:03:23 INFO - Main Display: Yes 02:03:23 INFO - Mirror: Off 02:03:23 INFO - Online: Yes 02:03:23 INFO - Rotation: Supported 02:03:23 INFO - Return code: 0 02:03:23 INFO - Running post-action listener: _resource_record_post_action 02:03:23 INFO - [mozharness: 2016-12-20 10:03:23.747631Z] Finished run-tests step (success) 02:03:23 INFO - Running post-run listener: _resource_record_post_run 02:03:23 INFO - Total resource usage - Wall time: 1247s; CPU: 56.0%; Read bytes: 38623744; Write bytes: 558149120; Read time: 267; Write time: 1834 02:03:23 INFO - TinderboxPrint: CPU usage
55.8% 02:03:23 INFO - TinderboxPrint: I/O read bytes / time
38,623,744 / 267 02:03:23 INFO - TinderboxPrint: I/O write bytes / time
558,149,120 / 1,834 02:03:23 INFO - TinderboxPrint: CPU idle
2,206.3 (44.2%) 02:03:23 INFO - TinderboxPrint: CPU system
1,035.6 (20.8%) 02:03:23 INFO - TinderboxPrint: CPU user
1,744.5 (35.0%) 02:03:23 INFO - TinderboxPrint: Swap in / out
288,415,744 / 0 02:03:23 INFO - install - Wall time: 36s; CPU: 55.0%; Read bytes: 242835968; Write bytes: 230815744; Read time: 28184; Write time: 494 02:03:23 INFO - run-tests - Wall time: 1211s; CPU: 56.0%; Read bytes: 32342528; Write bytes: 306517504; Read time: 208; Write time: 1287 02:03:24 INFO - Running post-run listener: _upload_blobber_files 02:03:24 INFO - Blob upload gear active. 02:03:24 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:03:24 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 02:03:24 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', 'Graphics', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:03:24 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 Graphics -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:03:25 INFO - (blobuploader) - INFO - Open directory for files ... 02:03:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 02:03:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:03:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:03:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 02:03:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:03:26 INFO - (blobuploader) - INFO - Done attempting. 02:03:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 02:03:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:03:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:03:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 02:03:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:03:28 INFO - (blobuploader) - INFO - Done attempting. 02:03:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 02:03:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:03:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:03:28 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 02:03:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:03:28 INFO - (blobuploader) - INFO - Done attempting. 02:03:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:03:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:03:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:03:31 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:03:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:03:31 INFO - (blobuploader) - INFO - Done attempting. 02:03:31 INFO - (blobuploader) - INFO - Iteration through files over. 02:03:31 INFO - Return code: 0 02:03:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:03:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:03:31 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/6e0e9b20e87c4473035dd26802b8b34c74b01f8892422b1ad44dd0ff439699eade98ccdc95d08ddb06b7d1d42d0b1ead651c0ed5cde6aaf7cbc190840280c4bf", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/09585c57f90595f71517b64eb41908a7ccaf7bd2a896b054670f73656a197dbca3e346464baedfe6e3660c5d591fd0bf179e4c954e31ff819fbfe39987ba6cfa", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/ea9261f37a04cd2fa661f95d252de78e6b52750b654b7d5e2e032ebd6c05c453179af956e6ecba7b5f73ad4b5946c4129c01b4859fdd2a8c4390e740e6911953", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/8eae167d6ddab064af3056236e60153ba5af9f705356824ebe9aa87b4caaf216422e8ebe793bc464d0af368d0d48684b0b29db34d524cfecfd1d4193468bfd45"} 02:03:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:03:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:03:31 INFO - Contents: 02:03:31 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/6e0e9b20e87c4473035dd26802b8b34c74b01f8892422b1ad44dd0ff439699eade98ccdc95d08ddb06b7d1d42d0b1ead651c0ed5cde6aaf7cbc190840280c4bf", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/09585c57f90595f71517b64eb41908a7ccaf7bd2a896b054670f73656a197dbca3e346464baedfe6e3660c5d591fd0bf179e4c954e31ff819fbfe39987ba6cfa", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/ea9261f37a04cd2fa661f95d252de78e6b52750b654b7d5e2e032ebd6c05c453179af956e6ecba7b5f73ad4b5946c4129c01b4859fdd2a8c4390e740e6911953", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/8eae167d6ddab064af3056236e60153ba5af9f705356824ebe9aa87b4caaf216422e8ebe793bc464d0af368d0d48684b0b29db34d524cfecfd1d4193468bfd45"} 02:03:31 INFO - Running post-run listener: copy_logs_to_upload_dir 02:03:31 INFO - Copying logs to upload dir... 02:03:31 INFO - mkdir: /builds/slave/test/build/upload/logs 02:03:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1341.142034 ========= master_lag: 1.44 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 22 secs) (at 2016-12-20 02:03:32.819296) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-20 02:03:32.824168) ========= 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.A1rVMozKDP/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.yvRtElBWUA/Listeners TMPDIR=/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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/Graphics/sha512/6e0e9b20e87c4473035dd26802b8b34c74b01f8892422b1ad44dd0ff439699eade98ccdc95d08ddb06b7d1d42d0b1ead651c0ed5cde6aaf7cbc190840280c4bf", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/09585c57f90595f71517b64eb41908a7ccaf7bd2a896b054670f73656a197dbca3e346464baedfe6e3660c5d591fd0bf179e4c954e31ff819fbfe39987ba6cfa", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/ea9261f37a04cd2fa661f95d252de78e6b52750b654b7d5e2e032ebd6c05c453179af956e6ecba7b5f73ad4b5946c4129c01b4859fdd2a8c4390e740e6911953", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/8eae167d6ddab064af3056236e60153ba5af9f705356824ebe9aa87b4caaf216422e8ebe793bc464d0af368d0d48684b0b29db34d524cfecfd1d4193468bfd45"} build_url:https://queue.taskcluster.net/v1/task/UTlo9S4OQhy03dIiYm91_Q/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.016121 build_url: 'https://queue.taskcluster.net/v1/task/UTlo9S4OQhy03dIiYm91_Q/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/6e0e9b20e87c4473035dd26802b8b34c74b01f8892422b1ad44dd0ff439699eade98ccdc95d08ddb06b7d1d42d0b1ead651c0ed5cde6aaf7cbc190840280c4bf", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/09585c57f90595f71517b64eb41908a7ccaf7bd2a896b054670f73656a197dbca3e346464baedfe6e3660c5d591fd0bf179e4c954e31ff819fbfe39987ba6cfa", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/ea9261f37a04cd2fa661f95d252de78e6b52750b654b7d5e2e032ebd6c05c453179af956e6ecba7b5f73ad4b5946c4129c01b4859fdd2a8c4390e740e6911953", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/8eae167d6ddab064af3056236e60153ba5af9f705356824ebe9aa87b4caaf216422e8ebe793bc464d0af368d0d48684b0b29db34d524cfecfd1d4193468bfd45"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-20 02:03:32.873859) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-20 02:03:32.874200) ========= 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.A1rVMozKDP/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.yvRtElBWUA/Listeners TMPDIR=/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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.007627 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-20 02:03:32.925635) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-20 02:03:32.925958) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-20 02:03:32.926280) ========= ========= Total master_lag: 1.63 =========