builder: graphics_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0037 starttime: 1484330081.87 results: success (0) buildid: 20170113073116 builduid: b0e6c71cccd5421d8f9e3c05271dd03c revision: 2dad5748892eea01c320091ec83f274c0a3c9eb3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-13 09:54:41.870476) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-13 09:54:41.871093) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-13 09:54:41.871529) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-13 09:54:41.899798) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-13 09:54:41.900242) ========= 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.4PscngIU9B/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.vbJm8ddEIR/Listeners TMPDIR=/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-01-13 09:54:41-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 164M=0s 2017-01-13 09:54:42 (164 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.121339 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-13 09:54:42.047571) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-13 09:54:42.048048) ========= 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.4PscngIU9B/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.vbJm8ddEIR/Listeners TMPDIR=/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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.027800 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-13 09:54:42.102731) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-13 09:54:42.103075) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev 2dad5748892eea01c320091ec83f274c0a3c9eb3 --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 2dad5748892eea01c320091ec83f274c0a3c9eb3 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.4PscngIU9B/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.vbJm8ddEIR/Listeners TMPDIR=/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-01-13 09:54:42,196 truncating revision to first 12 chars 2017-01-13 09:54:42,196 Setting DEBUG logging. 2017-01-13 09:54:42,196 attempt 1/10 2017-01-13 09:54:42,196 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/2dad5748892e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-13 09:54:43,354 unpacking tar archive at: graphics-2dad5748892e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.436295 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-13 09:54:43.562713) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-13 09:54:43.563030) ========= script_repo_revision: 2dad5748892eea01c320091ec83f274c0a3c9eb3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-13 09:54:43.563405) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-13 09:54:43.563706) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-13 09:54:43.583219) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2017-01-13 09:54:43.583596) ========= /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.4PscngIU9B/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.vbJm8ddEIR/Listeners TMPDIR=/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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 09:54:43 INFO - MultiFileLogger online at 20170113 09:54:43 in /builds/slave/test 09:54:43 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 09:54:43 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:54:43 INFO - {'append_to_log': False, 09:54:43 INFO - 'base_work_dir': '/builds/slave/test', 09:54:43 INFO - 'blob_upload_branch': 'Graphics', 09:54:43 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:54:43 INFO - 'branch': 'Graphics', 09:54:43 INFO - 'buildbot_json_path': 'buildprops.json', 09:54:43 INFO - 'config_files': ('talos/mac_config.py',), 09:54:43 INFO - 'default_actions': ('clobber', 09:54:43 INFO - 'read-buildbot-config', 09:54:43 INFO - 'download-and-extract', 09:54:43 INFO - 'populate-webroot', 09:54:43 INFO - 'create-virtualenv', 09:54:43 INFO - 'install', 09:54:43 INFO - 'run-tests'), 09:54:43 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:54:43 INFO - 'download_minidump_stackwalk': True, 09:54:43 INFO - 'download_symbols': 'ondemand', 09:54:43 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:54:43 INFO - 'tooltool.py': '/tools/tooltool.py', 09:54:43 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:54:43 INFO - '/tools/misc-python/virtualenv.py')}, 09:54:43 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:54:43 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:54:43 INFO - 'installer_path': 'installer.exe', 09:54:43 INFO - 'log_level': 'info', 09:54:43 INFO - 'log_name': 'talos', 09:54:43 INFO - 'log_to_console': True, 09:54:43 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:54:43 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:54:43 INFO - 'opt_config_files': (), 09:54:43 INFO - 'pip_index': False, 09:54:43 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:54:43 INFO - 'cmd': ('bash', 09:54:43 INFO - '-c', 09:54:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:54:43 INFO - 'enabled': True, 09:54:43 INFO - 'halt_on_failure': False, 09:54:43 INFO - 'name': 'check_screen_resolution'},), 09:54:43 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:54:43 INFO - 'cmd': ('bash', 09:54:43 INFO - '-c', 09:54:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:54:43 INFO - 'enabled': True, 09:54:43 INFO - 'halt_on_failure': False, 09:54:43 INFO - 'name': 'check_screen_resolution'},), 09:54:43 INFO - 'run_cmd_checks_enabled': True, 09:54:43 INFO - 'sps_profile': False, 09:54:43 INFO - 'sps_profile_interval': 0, 09:54:43 INFO - 'suite': 'dromaeojs-e10s', 09:54:43 INFO - 'system_bits': '32', 09:54:43 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:54:43 INFO - 'title': 't-yosemite-r7-0037', 09:54:43 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:54:43 INFO - 'use_talos_json': True, 09:54:43 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:54:43 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:54:43 INFO - 'work_dir': 'build'} 09:54:43 INFO - [mozharness: 2017-01-13 17:54:43.789404Z] Running clobber step. 09:54:43 INFO - Running pre-action listener: _resource_record_pre_action 09:54:43 INFO - Running main action method: clobber 09:54:43 INFO - rmtree: /builds/slave/test/build 09:54:43 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:54:46 INFO - Running post-action listener: _resource_record_post_action 09:54:46 INFO - [mozharness: 2017-01-13 17:54:46.327381Z] Finished clobber step (success) 09:54:46 INFO - [mozharness: 2017-01-13 17:54:46.327502Z] Running read-buildbot-config step. 09:54:46 INFO - Running pre-action listener: _resource_record_pre_action 09:54:46 INFO - Running main action method: read_buildbot_config 09:54:46 INFO - Using buildbot properties: 09:54:46 INFO - { 09:54:46 INFO - "project": "", 09:54:46 INFO - "product": "firefox", 09:54:46 INFO - "script_repo_revision": "default", 09:54:46 INFO - "builddir": "graphics_yosemite_r7_test-dromaeojs-e10s", 09:54:46 INFO - "repository": "", 09:54:46 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 graphics talos dromaeojs-e10s", 09:54:46 INFO - "buildid": "20170113073116", 09:54:46 INFO - "pgo_build": "False", 09:54:46 INFO - "basedir": "/builds/slave/test", 09:54:46 INFO - "buildnumber": 19, 09:54:46 INFO - "slavename": "t-yosemite-r7-0037", 09:54:46 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 09:54:46 INFO - "platform": "yosemite_r7", 09:54:46 INFO - "scheduler": "tests-graphics-macosx64-talos-8-3600", 09:54:46 INFO - "branch": "graphics", 09:54:46 INFO - "repo_path": "projects/graphics", 09:54:46 INFO - "revision": "2dad5748892eea01c320091ec83f274c0a3c9eb3", 09:54:46 INFO - "stage_platform": "macosx64", 09:54:46 INFO - "builduid": "b0e6c71cccd5421d8f9e3c05271dd03c", 09:54:46 INFO - "slavebuilddir": "test" 09:54:46 INFO - } 09:54:46 INFO - Found installer url https://queue.taskcluster.net/v1/task/PhPiWANYQZCgRzkfvA2fxg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 09:54:46 INFO - Running post-action listener: _resource_record_post_action 09:54:46 INFO - [mozharness: 2017-01-13 17:54:46.330713Z] Finished read-buildbot-config step (success) 09:54:46 INFO - [mozharness: 2017-01-13 17:54:46.330840Z] Running download-and-extract step. 09:54:46 INFO - Running pre-action listener: _resource_record_pre_action 09:54:46 INFO - Running main action method: download_and_extract 09:54:46 INFO - mkdir: /builds/slave/test/build/tests 09:54:46 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')]} 09:54:46 INFO - trying https://queue.taskcluster.net/v1/task/PhPiWANYQZCgRzkfvA2fxg/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 09:54:46 INFO - Downloading https://queue.taskcluster.net/v1/task/PhPiWANYQZCgRzkfvA2fxg/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 09:54:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PhPiWANYQZCgRzkfvA2fxg/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 09:54:46 INFO - Downloaded 1199 bytes. 09:54:46 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 09:54:46 INFO - Using the following test package requirements: 09:54:46 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 09:54:46 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:54:46 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 09:54:46 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:54:46 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 09:54:46 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:54:46 INFO - u'jsshell-mac.zip'], 09:54:46 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:54:46 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 09:54:46 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 09:54:46 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:54:46 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 09:54:46 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:54:46 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 09:54:46 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:54:46 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 09:54:46 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:54:46 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 09:54:46 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:54:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PhPiWANYQZCgRzkfvA2fxg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 09:54:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PhPiWANYQZCgRzkfvA2fxg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:54:46 INFO - Fetch https://queue.taskcluster.net/v1/task/PhPiWANYQZCgRzkfvA2fxg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 09:54:47 INFO - Expected file size: 16314238 09:54:47 INFO - Obtained file size: 16314238 09:54:54 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 09:54:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PhPiWANYQZCgRzkfvA2fxg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 09:54:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PhPiWANYQZCgRzkfvA2fxg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:54:54 INFO - Fetch https://queue.taskcluster.net/v1/task/PhPiWANYQZCgRzkfvA2fxg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 09:54:55 INFO - Expected file size: 16314238 09:54:55 INFO - Obtained file size: 16314238 09:55:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PhPiWANYQZCgRzkfvA2fxg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 09:55:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PhPiWANYQZCgRzkfvA2fxg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:55:01 INFO - Fetch https://queue.taskcluster.net/v1/task/PhPiWANYQZCgRzkfvA2fxg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 09:55:02 INFO - Expected file size: 14403532 09:55:02 INFO - Obtained file size: 14403532 09:55: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')]} 09:55:03 INFO - trying https://queue.taskcluster.net/v1/task/PhPiWANYQZCgRzkfvA2fxg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 09:55:03 INFO - Downloading https://queue.taskcluster.net/v1/task/PhPiWANYQZCgRzkfvA2fxg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 09:55:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PhPiWANYQZCgRzkfvA2fxg/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 09:55:05 INFO - Downloaded 70114455 bytes. 09:55:05 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PhPiWANYQZCgRzkfvA2fxg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 09:55:05 INFO - mkdir: /builds/slave/test/properties 09:55:05 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:55:05 INFO - Writing to file /builds/slave/test/properties/build_url 09:55:05 INFO - Contents: 09:55:05 INFO - build_url:https://queue.taskcluster.net/v1/task/PhPiWANYQZCgRzkfvA2fxg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 09:55:05 INFO - Running post-action listener: _resource_record_post_action 09:55:05 INFO - Running post-action listener: set_extra_try_arguments 09:55:05 INFO - [mozharness: 2017-01-13 17:55:05.902132Z] Finished download-and-extract step (success) 09:55:05 INFO - [mozharness: 2017-01-13 17:55:05.902260Z] Running populate-webroot step. 09:55:05 INFO - Running pre-action listener: _resource_record_pre_action 09:55:05 INFO - Running main action method: populate_webroot 09:55:05 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:55:05 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:55:05 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:55:05 INFO - '--tppagecycles', 09:55:05 INFO - '7'], 09:55:05 INFO - 'tests': ['tsvgm']}}, 09:55:05 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:55:05 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:55:05 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:55:05 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:55:05 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:55:05 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:55:05 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:55:05 INFO - 'talos_options': ['--disable-e10s'], 09:55:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:55:05 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:55:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:55:05 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:55:05 INFO - 'talos_options': ['--disable-e10s'], 09:55:05 INFO - 'tests': ['damp', 'tps']}, 09:55:05 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:55:05 INFO - 'tests': ['damp', 'tps']}, 09:55:05 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:55:05 INFO - 'tests': ['dromaeo_dom']}, 09:55:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:55:05 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:55:05 INFO - 'tests': ['basic_compositor_video']}, 09:55:05 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:55:05 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:55:05 INFO - 'tests': ['a11yr', 09:55:05 INFO - 'ts_paint', 09:55:05 INFO - 'tpaint', 09:55:05 INFO - 'sessionrestore', 09:55:05 INFO - 'sessionrestore_no_auto_restore', 09:55:05 INFO - 'tabpaint']}, 09:55:05 INFO - 'other-e10s': {'tests': ['a11yr', 09:55:05 INFO - 'ts_paint', 09:55:05 INFO - 'tpaint', 09:55:05 INFO - 'sessionrestore', 09:55:05 INFO - 'sessionrestore_no_auto_restore', 09:55:05 INFO - 'tabpaint']}, 09:55:05 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:55:05 INFO - 'ts_paint', 09:55:05 INFO - 'tpaint', 09:55:05 INFO - 'sessionrestore', 09:55:05 INFO - 'sessionrestore_no_auto_restore', 09:55:05 INFO - 'tabpaint']}, 09:55:05 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:55:05 INFO - 'ts_paint', 09:55:05 INFO - 'tpaint', 09:55:05 INFO - 'sessionrestore', 09:55:05 INFO - 'sessionrestore_no_auto_restore', 09:55:05 INFO - 'tabpaint']}, 09:55:05 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:55:05 INFO - 'tests': ['a11yr', 09:55:05 INFO - 'ts_paint', 09:55:05 INFO - 'tpaint', 09:55:05 INFO - 'sessionrestore', 09:55:05 INFO - 'sessionrestore_no_auto_restore', 09:55:05 INFO - 'tabpaint']}, 09:55:05 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:55:05 INFO - 'tests': ['a11yr', 09:55:05 INFO - 'ts_paint', 09:55:05 INFO - 'tpaint', 09:55:05 INFO - 'sessionrestore', 09:55:05 INFO - 'sessionrestore_no_auto_restore', 09:55:05 INFO - 'tabpaint']}, 09:55:05 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:55:05 INFO - 'tests': ['tsvgx', 09:55:05 INFO - 'tsvgr_opacity', 09:55:05 INFO - 'tart', 09:55:05 INFO - 'tscrollx', 09:55:05 INFO - 'cart', 09:55:05 INFO - 'tsvg_static']}, 09:55:05 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:55:05 INFO - 'tsvgr_opacity', 09:55:05 INFO - 'tart', 09:55:05 INFO - 'tscrollx', 09:55:05 INFO - 'cart', 09:55:05 INFO - 'tsvg_static']}, 09:55:05 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:55:05 INFO - 'talos_options': ['--disable-e10s'], 09:55:05 INFO - 'tests': ['tp5o']}, 09:55:05 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:55:05 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:55:05 INFO - 'talos_options': ['--disable-e10s', 09:55:05 INFO - '--xperf_path', 09:55:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:55:05 INFO - 'tests': ['tp5n']}, 09:55:05 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:55:05 INFO - 'talos_options': ['--xperf_path', 09:55:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:55:05 INFO - 'tests': ['tp5n']}}, 09:55:05 INFO - 'talos.zip': {'path': '', 09:55:05 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:55:05 INFO - Running post-action listener: _resource_record_post_action 09:55:05 INFO - [mozharness: 2017-01-13 17:55:05.920416Z] Finished populate-webroot step (success) 09:55:05 INFO - [mozharness: 2017-01-13 17:55:05.920513Z] Running create-virtualenv step. 09:55:05 INFO - Running pre-action listener: _resource_record_pre_action 09:55:05 INFO - Running main action method: create_virtualenv 09:55:05 INFO - Creating virtualenv /builds/slave/test/build/venv 09:55:05 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 09:55:05 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:55:06 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:55:06 INFO - Using real prefix '/tools/python27' 09:55:06 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:55:06 INFO - Installing distribute.............................................................................................................................................................................................done. 09:55:09 INFO - Installing pip.................done. 09:55:09 INFO - Return code: 0 09:55:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:55:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:55:09 INFO - Reading from file tmpfile_stdout 09:55:09 INFO - Output received: 09:55:09 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) 09:55:09 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:55:09 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')]} 09:55:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:55:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55:09 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')]} 09:55:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:55:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55:09 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 0x10ce43b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce46768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdfbc8c840>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc21d30>, '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 0x10cb48db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc16be8>, '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.4PscngIU9B/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.vbJm8ddEIR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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 09:55:09 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 09:55:09 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 09:55:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4PscngIU9B/Render', 09:55:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:55:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:55:09 INFO - 'HOME': '/Users/cltbld', 09:55:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:55:09 INFO - 'LOGNAME': 'cltbld', 09:55:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:55:09 INFO - 'MOZ_NO_REMOTE': '1', 09:55:09 INFO - 'NO_EM_RESTART': '1', 09:55:09 INFO - 'PAGER': '/bin/cat', 09:55:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:55:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:55:09 INFO - 'PWD': '/builds/slave/test', 09:55:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:55:09 INFO - 'SHELL': '/bin/bash', 09:55:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vbJm8ddEIR/Listeners', 09:55:09 INFO - 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/', 09:55:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:55:09 INFO - 'USER': 'cltbld', 09:55:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:55:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:55:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:55:09 INFO - 'XPC_FLAGS': '0x0', 09:55:09 INFO - 'XPC_SERVICE_NAME': '0', 09:55:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:55:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:55:10 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 09:55:10 INFO - Cleaning up... 09:55:10 INFO - Return code: 0 09:55:10 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:55: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')]} 09:55:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:55:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55: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')]} 09:55:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:55:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55: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', '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 0x10ce43b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce46768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdfbc8c840>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc21d30>, '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 0x10cb48db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc16be8>, '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.4PscngIU9B/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.vbJm8ddEIR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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 09:55: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', 'psutil>=3.1.1'] in /builds/slave/test/build 09:55: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 psutil>=3.1.1 09:55:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4PscngIU9B/Render', 09:55:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:55:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:55:10 INFO - 'HOME': '/Users/cltbld', 09:55:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:55:10 INFO - 'LOGNAME': 'cltbld', 09:55:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:55:10 INFO - 'MOZ_NO_REMOTE': '1', 09:55:10 INFO - 'NO_EM_RESTART': '1', 09:55:10 INFO - 'PAGER': '/bin/cat', 09:55:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:55:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:55:10 INFO - 'PWD': '/builds/slave/test', 09:55:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:55:10 INFO - 'SHELL': '/bin/bash', 09:55:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vbJm8ddEIR/Listeners', 09:55:10 INFO - 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/', 09:55:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:55:10 INFO - 'USER': 'cltbld', 09:55:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:55:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:55:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:55:10 INFO - 'XPC_FLAGS': '0x0', 09:55:10 INFO - 'XPC_SERVICE_NAME': '0', 09:55:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:55:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:55:10 INFO - Downloading/unpacking psutil>=3.1.1 09:55:10 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 09:55:10 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 09:55:10 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 09:55:10 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 09:55:10 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 09:55:10 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 09:55:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:55:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:55:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:55:13 INFO - Installing collected packages: psutil 09:55:13 INFO - Running setup.py install for psutil 09:55:13 INFO - building 'psutil._psutil_osx' extension 09:55:13 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 09:55:14 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 09:55:14 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 09:55:14 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 09:55:14 INFO - building 'psutil._psutil_posix' extension 09:55:14 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 09:55:14 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:55:14 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:55:14 INFO - ^ 09:55:14 INFO - 1 warning generated. 09:55:14 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 09:55:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:55:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:55:15 INFO - Successfully installed psutil 09:55:15 INFO - Cleaning up... 09:55:15 INFO - Return code: 0 09:55:15 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:55:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:55:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:55:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:55:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:55:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ce43b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce46768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdfbc8c840>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc21d30>, '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 0x10cb48db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc16be8>, '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.4PscngIU9B/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.vbJm8ddEIR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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 09:55:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:55:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:55:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4PscngIU9B/Render', 09:55:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:55:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:55:15 INFO - 'HOME': '/Users/cltbld', 09:55:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:55:15 INFO - 'LOGNAME': 'cltbld', 09:55:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:55:15 INFO - 'MOZ_NO_REMOTE': '1', 09:55:15 INFO - 'NO_EM_RESTART': '1', 09:55:15 INFO - 'PAGER': '/bin/cat', 09:55:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:55:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:55:15 INFO - 'PWD': '/builds/slave/test', 09:55:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:55:15 INFO - 'SHELL': '/bin/bash', 09:55:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vbJm8ddEIR/Listeners', 09:55:15 INFO - 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/', 09:55:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:55:15 INFO - 'USER': 'cltbld', 09:55:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:55:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:55:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:55:15 INFO - 'XPC_FLAGS': '0x0', 09:55:15 INFO - 'XPC_SERVICE_NAME': '0', 09:55:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:55:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:55:15 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:55:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:55:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:55:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:55:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:55:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:55:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:55:17 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:55:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:55:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:55:18 INFO - Installing collected packages: mozsystemmonitor 09:55:18 INFO - Running setup.py install for mozsystemmonitor 09:55:18 INFO - Successfully installed mozsystemmonitor 09:55:18 INFO - Cleaning up... 09:55:18 INFO - Return code: 0 09:55:18 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:55:18 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')]} 09:55:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:55:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55:18 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')]} 09:55:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:55:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55:18 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 0x10ce43b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce46768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdfbc8c840>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc21d30>, '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 0x10cb48db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc16be8>, '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.4PscngIU9B/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.vbJm8ddEIR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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 09:55:18 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 09:55:18 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 09:55:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4PscngIU9B/Render', 09:55:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:55:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:55:18 INFO - 'HOME': '/Users/cltbld', 09:55:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:55:18 INFO - 'LOGNAME': 'cltbld', 09:55:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:55:18 INFO - 'MOZ_NO_REMOTE': '1', 09:55:18 INFO - 'NO_EM_RESTART': '1', 09:55:18 INFO - 'PAGER': '/bin/cat', 09:55:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:55:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:55:18 INFO - 'PWD': '/builds/slave/test', 09:55:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:55:18 INFO - 'SHELL': '/bin/bash', 09:55:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vbJm8ddEIR/Listeners', 09:55:18 INFO - 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/', 09:55:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:55:18 INFO - 'USER': 'cltbld', 09:55:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:55:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:55:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:55:18 INFO - 'XPC_FLAGS': '0x0', 09:55:18 INFO - 'XPC_SERVICE_NAME': '0', 09:55:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:55:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:55:18 INFO - Downloading/unpacking jsonschema==2.5.1 09:55:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:55:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:55:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:55:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:55:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:55:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:55:21 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:55:21 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:55:21 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 09:55:21 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 09:55:21 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 09:55:21 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 09:55:21 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 09:55:21 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 09:55:21 INFO - Downloading functools32-3.2.3-2.tar.gz 09:55:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:55:21 INFO - warning: no files found matching '*.txt' 09:55:21 INFO - no previously-included directories found matching 'build' 09:55:21 INFO - no previously-included directories found matching 'dist' 09:55:21 INFO - no previously-included directories found matching '.git*' 09:55:21 INFO - Installing collected packages: jsonschema, functools32 09:55:21 INFO - Running setup.py install for functools32 09:55:21 INFO - warning: no files found matching '*.txt' 09:55:21 INFO - no previously-included directories found matching 'build' 09:55:21 INFO - no previously-included directories found matching 'dist' 09:55:21 INFO - no previously-included directories found matching '.git*' 09:55:21 INFO - Successfully installed jsonschema functools32 09:55:21 INFO - Cleaning up... 09:55:22 INFO - Return code: 0 09:55:22 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:55:22 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')]} 09:55:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:55:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55:22 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')]} 09:55:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:55:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55:22 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 0x10ce43b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce46768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdfbc8c840>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc21d30>, '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 0x10cb48db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc16be8>, '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.4PscngIU9B/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.vbJm8ddEIR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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 09:55:22 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 09:55:22 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 09:55:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4PscngIU9B/Render', 09:55:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:55:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:55:22 INFO - 'HOME': '/Users/cltbld', 09:55:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:55:22 INFO - 'LOGNAME': 'cltbld', 09:55:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:55:22 INFO - 'MOZ_NO_REMOTE': '1', 09:55:22 INFO - 'NO_EM_RESTART': '1', 09:55:22 INFO - 'PAGER': '/bin/cat', 09:55:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:55:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:55:22 INFO - 'PWD': '/builds/slave/test', 09:55:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:55:22 INFO - 'SHELL': '/bin/bash', 09:55:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vbJm8ddEIR/Listeners', 09:55:22 INFO - 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/', 09:55:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:55:22 INFO - 'USER': 'cltbld', 09:55:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:55:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:55:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:55:22 INFO - 'XPC_FLAGS': '0x0', 09:55:22 INFO - 'XPC_SERVICE_NAME': '0', 09:55:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:55:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:55:22 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:55:22 INFO - Cleaning up... 09:55:22 INFO - Return code: 0 09:55:22 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:55:22 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')]} 09:55:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:55:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55:22 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')]} 09:55:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:55:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55:22 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 0x10ce43b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce46768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdfbc8c840>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc21d30>, '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 0x10cb48db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc16be8>, '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.4PscngIU9B/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.vbJm8ddEIR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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 09:55:22 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 09:55:22 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 09:55:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4PscngIU9B/Render', 09:55:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:55:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:55:22 INFO - 'HOME': '/Users/cltbld', 09:55:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:55:22 INFO - 'LOGNAME': 'cltbld', 09:55:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:55:22 INFO - 'MOZ_NO_REMOTE': '1', 09:55:22 INFO - 'NO_EM_RESTART': '1', 09:55:22 INFO - 'PAGER': '/bin/cat', 09:55:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:55:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:55:22 INFO - 'PWD': '/builds/slave/test', 09:55:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:55:22 INFO - 'SHELL': '/bin/bash', 09:55:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vbJm8ddEIR/Listeners', 09:55:22 INFO - 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/', 09:55:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:55:22 INFO - 'USER': 'cltbld', 09:55:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:55:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:55:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:55:22 INFO - 'XPC_FLAGS': '0x0', 09:55:22 INFO - 'XPC_SERVICE_NAME': '0', 09:55:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:55:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:55:22 INFO - Downloading/unpacking blobuploader==1.2.4 09:55:22 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 09:55:22 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 09:55:22 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 09:55:22 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 09:55:22 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 09:55:22 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 09:55:25 INFO - Downloading blobuploader-1.2.4.tar.gz 09:55:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:55:25 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:55:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:55:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:55:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:55:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:55:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:55:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:55:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:55:26 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:55:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:55:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:55:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:55:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:55:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:55:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:55:26 INFO - Downloading docopt-0.6.1.tar.gz 09:55:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:55:26 INFO - Installing collected packages: blobuploader, requests, docopt 09:55:26 INFO - Running setup.py install for blobuploader 09:55:26 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:55:26 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:55:26 INFO - Running setup.py install for requests 09:55:27 INFO - Running setup.py install for docopt 09:55:27 INFO - Successfully installed blobuploader requests docopt 09:55:27 INFO - Cleaning up... 09:55:27 INFO - Return code: 0 09:55:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:55: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')]} 09:55:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:55:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55: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')]} 09:55:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:55:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55:27 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 0x10ce43b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce46768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdfbc8c840>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc21d30>, '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 0x10cb48db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc16be8>, '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.4PscngIU9B/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.vbJm8ddEIR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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 09:55:27 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 09:55:27 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 09:55:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4PscngIU9B/Render', 09:55:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:55:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:55:27 INFO - 'HOME': '/Users/cltbld', 09:55:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:55:27 INFO - 'LOGNAME': 'cltbld', 09:55:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:55:27 INFO - 'MOZ_NO_REMOTE': '1', 09:55:27 INFO - 'NO_EM_RESTART': '1', 09:55:27 INFO - 'PAGER': '/bin/cat', 09:55:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:55:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:55:27 INFO - 'PWD': '/builds/slave/test', 09:55:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:55:27 INFO - 'SHELL': '/bin/bash', 09:55:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vbJm8ddEIR/Listeners', 09:55:27 INFO - 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/', 09:55:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:55:27 INFO - 'USER': 'cltbld', 09:55:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:55:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:55:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:55:27 INFO - 'XPC_FLAGS': '0x0', 09:55:27 INFO - 'XPC_SERVICE_NAME': '0', 09:55:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:55:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:55:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:55:27 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-emhlfd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:55:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:55:27 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-Gef3En-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:55:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:55:27 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-gLfrWP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:55:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:55:27 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-8l_311-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:55:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:55:28 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-vRV51K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:55:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:55:28 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-6VuUGa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:55:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:55:28 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-O7AyeT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:55:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:55:28 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-be7SUF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:55:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:55:28 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-xOIPMj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:55:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:55:28 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-zQHZau-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:55:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:55:28 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-Y5D0yr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:55:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:55:28 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-Q5n_MH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:55:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:55:28 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-YAuznx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:55:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:55:29 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-j0EhNo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:55:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:55:29 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-E_1DNp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:55:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:55:29 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-KsRSrm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:55:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:55:29 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-Nfotgi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:55:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:55:29 INFO - Running setup.py install for manifestparser 09:55:29 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:55:29 INFO - Running setup.py install for mozcrash 09:55:29 INFO - Running setup.py install for mozdebug 09:55:29 INFO - Running setup.py install for mozdevice 09:55:30 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:55:30 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:55:30 INFO - Running setup.py install for mozfile 09:55:30 INFO - Running setup.py install for mozhttpd 09:55:30 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:55:30 INFO - Running setup.py install for mozinfo 09:55:30 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:55:30 INFO - Running setup.py install for mozInstall 09:55:30 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:55:30 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:55:30 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:55:30 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:55:30 INFO - Running setup.py install for mozleak 09:55:30 INFO - Running setup.py install for mozlog 09:55:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:55:30 INFO - Running setup.py install for moznetwork 09:55:31 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:55:31 INFO - Running setup.py install for mozprocess 09:55:31 INFO - Running setup.py install for mozprofile 09:55:31 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:55:31 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:55:31 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:55:31 INFO - Running setup.py install for mozrunner 09:55:31 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:55:31 INFO - Running setup.py install for mozscreenshot 09:55:31 INFO - Running setup.py install for moztest 09:55:31 INFO - Running setup.py install for mozversion 09:55:31 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:55:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:55:31 INFO - Cleaning up... 09:55:31 INFO - Return code: 0 09:55:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:55:31 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')]} 09:55:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:55:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55:32 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')]} 09:55:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:55:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55:32 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 0x10ce43b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce46768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdfbc8c840>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc21d30>, '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 0x10cb48db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc16be8>, '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.4PscngIU9B/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.vbJm8ddEIR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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 09:55:32 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 09:55:32 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 09:55:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4PscngIU9B/Render', 09:55:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:55:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:55:32 INFO - 'HOME': '/Users/cltbld', 09:55:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:55:32 INFO - 'LOGNAME': 'cltbld', 09:55:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:55:32 INFO - 'MOZ_NO_REMOTE': '1', 09:55:32 INFO - 'NO_EM_RESTART': '1', 09:55:32 INFO - 'PAGER': '/bin/cat', 09:55:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:55:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:55:32 INFO - 'PWD': '/builds/slave/test', 09:55:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:55:32 INFO - 'SHELL': '/bin/bash', 09:55:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vbJm8ddEIR/Listeners', 09:55:32 INFO - 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/', 09:55:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:55:32 INFO - 'USER': 'cltbld', 09:55:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:55:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:55:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:55:32 INFO - 'XPC_FLAGS': '0x0', 09:55:32 INFO - 'XPC_SERVICE_NAME': '0', 09:55:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:55:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:55:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:55:32 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-sRpsT1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:55:32 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)) 09:55:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:55:32 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-_NKmv6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:55:32 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)) 09:55:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:55:32 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-gOtdd8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:55:32 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)) 09:55:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:55:32 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-0Yoja1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:55:32 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)) 09:55:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:55:32 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-CRdDiG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:55:32 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)) 09:55:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:55:32 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-QKvgSW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:55:32 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)) 09:55:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:55:32 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-vu8Awa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:55:32 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)) 09:55:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:55:33 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-2IhrdM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:55:33 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)) 09:55:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:55:33 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-LPGB2f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:55:33 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)) 09:55:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:55:33 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-8LWc9w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:55:33 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)) 09:55:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:55:33 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-T2rQXj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:55:33 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)) 09:55:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:55:33 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-xzTkuZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:55:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:55:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:55:33 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-Q63H1p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:55:33 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)) 09:55:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:55:33 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-k8VAGo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:55:33 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)) 09:55:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:55:33 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-KXhr7w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:55:33 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)) 09:55:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:55:33 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-NFX8p7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:55:33 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:55:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:55:33 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-F4WDZA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:55:34 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)) 09:55:34 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)) 09:55:34 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)) 09:55:34 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)) 09:55:34 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)) 09:55:34 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)) 09:55:34 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:55:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:55:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:55:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:55:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:55:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:55:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:55:36 INFO - Downloading blessings-1.6.tar.gz 09:55:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:55:36 INFO - Installing collected packages: blessings 09:55:36 INFO - Running setup.py install for blessings 09:55:36 INFO - Successfully installed blessings 09:55:36 INFO - Cleaning up... 09:55:37 INFO - Return code: 0 09:55:37 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:55:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:55:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:55:37 INFO - Reading from file tmpfile_stdout 09:55:37 INFO - Current package versions: 09:55:37 INFO - blessings == 1.6 09:55:37 INFO - blobuploader == 1.2.4 09:55:37 INFO - docopt == 0.6.1 09:55:37 INFO - functools32 == 3.2.3-2 09:55:37 INFO - jsonschema == 2.5.1 09:55:37 INFO - manifestparser == 1.1 09:55:37 INFO - mozInstall == 1.12 09:55:37 INFO - mozcrash == 1.0 09:55:37 INFO - mozdebug == 0.1 09:55:37 INFO - mozdevice == 0.49 09:55:37 INFO - mozfile == 1.2 09:55:37 INFO - mozhttpd == 0.7 09:55:37 INFO - mozinfo == 0.9 09:55:37 INFO - mozleak == 0.1 09:55:37 INFO - mozlog == 3.4 09:55:37 INFO - moznetwork == 0.27 09:55:37 INFO - mozprocess == 0.24 09:55:37 INFO - mozprofile == 0.28 09:55:37 INFO - mozrunner == 6.13 09:55:37 INFO - mozscreenshot == 0.1 09:55:37 INFO - mozsystemmonitor == 0.3 09:55:37 INFO - moztest == 0.8 09:55:37 INFO - mozversion == 1.4 09:55:37 INFO - psutil == 3.1.1 09:55:37 INFO - requests == 1.2.3 09:55:37 INFO - wsgiref == 0.1.2 09:55:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:55:37 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')]} 09:55:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:55:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55:37 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')]} 09:55:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:55:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55:37 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 0x10ce43b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce46768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdfbc8c840>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc21d30>, '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 0x10cb48db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc16be8>, '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.4PscngIU9B/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.vbJm8ddEIR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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 09:55:37 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 09:55:37 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 09:55:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4PscngIU9B/Render', 09:55:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:55:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:55:37 INFO - 'HOME': '/Users/cltbld', 09:55:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:55:37 INFO - 'LOGNAME': 'cltbld', 09:55:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:55:37 INFO - 'MOZ_NO_REMOTE': '1', 09:55:37 INFO - 'NO_EM_RESTART': '1', 09:55:37 INFO - 'PAGER': '/bin/cat', 09:55:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:55:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:55:37 INFO - 'PWD': '/builds/slave/test', 09:55:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:55:37 INFO - 'SHELL': '/bin/bash', 09:55:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vbJm8ddEIR/Listeners', 09:55:37 INFO - 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/', 09:55:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:55:37 INFO - 'USER': 'cltbld', 09:55:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:55:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:55:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:55:37 INFO - 'XPC_FLAGS': '0x0', 09:55:37 INFO - 'XPC_SERVICE_NAME': '0', 09:55:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:55:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:55:37 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)) 09:55:37 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)) 09:55:37 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)) 09:55:37 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)) 09:55:37 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)) 09:55:37 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)) 09:55:37 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)) 09:55:37 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)) 09:55:37 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)) 09:55:37 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)) 09:55:37 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)) 09:55:37 INFO - Cleaning up... 09:55:37 INFO - Return code: 0 09:55:37 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:55:37 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')]} 09:55:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:55:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55:37 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')]} 09:55:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:55:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:55:37 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 0x10ce43b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce46768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdfbc8c840>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc21d30>, '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 0x10cb48db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc16be8>, '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.4PscngIU9B/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.vbJm8ddEIR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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 09:55:37 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 09:55:37 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 09:55:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4PscngIU9B/Render', 09:55:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:55:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:55:37 INFO - 'HOME': '/Users/cltbld', 09:55:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:55:37 INFO - 'LOGNAME': 'cltbld', 09:55:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:55:37 INFO - 'MOZ_NO_REMOTE': '1', 09:55:37 INFO - 'NO_EM_RESTART': '1', 09:55:37 INFO - 'PAGER': '/bin/cat', 09:55:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:55:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:55:37 INFO - 'PWD': '/builds/slave/test', 09:55:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:55:37 INFO - 'SHELL': '/bin/bash', 09:55:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vbJm8ddEIR/Listeners', 09:55:37 INFO - 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/', 09:55:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:55:37 INFO - 'USER': 'cltbld', 09:55:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:55:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:55:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:55:37 INFO - 'XPC_FLAGS': '0x0', 09:55:37 INFO - 'XPC_SERVICE_NAME': '0', 09:55:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:55:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:55:37 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:55:37 INFO - Cleaning up... 09:55:37 INFO - Return code: 0 09:55:37 INFO - Running post-action listener: _resource_record_post_action 09:55:37 INFO - Running post-action listener: _start_resource_monitoring 09:55:37 INFO - Starting resource monitoring. 09:55:37 INFO - [mozharness: 2017-01-13 17:55:37.913949Z] Finished create-virtualenv step (success) 09:55:37 INFO - [mozharness: 2017-01-13 17:55:37.914938Z] Running install step. 09:55:37 INFO - Running pre-action listener: _resource_record_pre_action 09:55:37 INFO - Running main action method: install 09:55:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:55:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:55:38 INFO - Reading from file tmpfile_stdout 09:55:38 INFO - Detecting whether we're running mozinstall >=1.0... 09:55:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:55:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:55:38 INFO - Reading from file tmpfile_stdout 09:55:38 INFO - Output received: 09:55:38 INFO - Usage: mozinstall [options] installer 09:55:38 INFO - Options: 09:55:38 INFO - -h, --help show this help message and exit 09:55:38 INFO - -d DEST, --destination=DEST 09:55:38 INFO - Directory to install application into. [default: 09:55:38 INFO - "/builds/slave/test"] 09:55:38 INFO - --app=APP Application being installed. [default: firefox] 09:55:38 INFO - mkdir: /builds/slave/test/build/application 09:55:38 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'] 09:55:38 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 09:56:00 INFO - Reading from file tmpfile_stdout 09:56:00 INFO - Output received: 09:56:00 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:56:00 INFO - Running post-action listener: _resource_record_post_action 09:56:00 INFO - [mozharness: 2017-01-13 17:56:00.166348Z] Finished install step (success) 09:56:00 INFO - [mozharness: 2017-01-13 17:56:00.166492Z] Running run-tests step. 09:56:00 INFO - Running pre-action listener: _resource_record_pre_action 09:56:00 INFO - Running main action method: run_tests 09:56:00 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:56:00 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:56:00 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:56:00 INFO - 2017-01-13 09:56:00.181 screenresolution[1624:7415] starting screenresolution argv=screenresolution get 09:56:00 INFO - 2017-01-13 09:56:00.183 screenresolution[1624:7415] Display 0: 1600x1200x32@60 09:56:00 INFO - 2017-01-13 09:56:00.189 screenresolution[1625:7418] starting screenresolution argv=screenresolution list 09:56:00 INFO - Available Modes on Display 0 09:56:00 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:56:00 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:56:00 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:56:00 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:56:00 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:56:00 INFO - Intel Iris: 09:56:00 INFO - Chipset Model: Intel Iris 09:56:00 INFO - Type: GPU 09:56:00 INFO - Bus: Built-In 09:56:00 INFO - VRAM (Dynamic, Max): 1536 MB 09:56:00 INFO - Vendor: Intel (0x8086) 09:56:00 INFO - Device ID: 0x0a2e 09:56:00 INFO - Revision ID: 0x0009 09:56:00 INFO - Displays: 09:56:00 INFO - Display: 09:56:00 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:56:00 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:56:00 INFO - Display Serial Number: 1600x1200 60 09:56:00 INFO - Main Display: Yes 09:56:00 INFO - Mirror: Off 09:56:00 INFO - Online: Yes 09:56:00 INFO - Rotation: Supported 09:56:00 INFO - Return code: 0 09:56:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:56:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:56:00 INFO - Python 2.7.3 09:56:00 INFO - Return code: 0 09:56:00 INFO - grabbing minidump binary from tooltool 09:56:00 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')]} 09:56:00 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 0x10cc21d30>, '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 0x10cb48db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc16be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:56:00 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 09:56:00 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 09:56:00 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 09:56:00 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:56:00 INFO - Return code: 0 09:56:00 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:56:00 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:56:00 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:56:00 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:56:00 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:56:00 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/PhPiWANYQZCgRzkfvA2fxg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0037', '--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 09:56:00 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/PhPiWANYQZCgRzkfvA2fxg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0037 --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 09:56:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4PscngIU9B/Render', 09:56:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:56:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:56:00 INFO - 'HOME': '/Users/cltbld', 09:56:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:56:00 INFO - 'LOGNAME': 'cltbld', 09:56:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:56:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:56:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:00 INFO - 'MOZ_NO_REMOTE': '1', 09:56:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:56:00 INFO - 'NO_EM_RESTART': '1', 09:56:00 INFO - 'PAGER': '/bin/cat', 09:56:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:56:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:56:00 INFO - 'PWD': '/builds/slave/test', 09:56:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:56:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:56:00 INFO - 'SHELL': '/bin/bash', 09:56:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vbJm8ddEIR/Listeners', 09:56:00 INFO - 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/', 09:56:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:56:00 INFO - 'USER': 'cltbld', 09:56:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:56:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:56:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:00 INFO - 'XPC_FLAGS': '0x0', 09:56:00 INFO - 'XPC_SERVICE_NAME': '0', 09:56:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:56:00 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/PhPiWANYQZCgRzkfvA2fxg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0037', '--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 09:56:00 INFO - mozversion application_buildid: 20170113073116 09:56:00 INFO - mozversion application_changeset: 2dad5748892eea01c320091ec83f274c0a3c9eb3 09:56:00 INFO - mozversion application_display_name: Nightly 09:56:00 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:56:00 INFO - mozversion application_name: Firefox 09:56:00 INFO - mozversion application_remotingname: firefox 09:56:00 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 09:56:00 INFO - mozversion application_vendor: Mozilla 09:56:00 INFO - mozversion application_version: 53.0a1 09:56:00 INFO - mozversion platform_buildid: 20170113073116 09:56:00 INFO - mozversion platform_changeset: 2dad5748892eea01c320091ec83f274c0a3c9eb3 09:56:00 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 09:56:00 INFO - mozversion platform_version: 53.0a1 09:56:00 INFO - using testdate: 1484330160 09:56:00 INFO - actual date: 1484330160 09:56:00 INFO - starting webserver on 'localhost:49241' 09:56:00 INFO - SUITE-START | Running 2 tests 09:56:00 INFO - TEST-START | dromaeo_css 09:56:00 INFO - operating with platform_type : mac_ 09:56:00 INFO - Initialising browser for dromaeo_css test... 09:56:00 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/tmppoLXL1/profile http://localhost:49241/getInfo.html) 09:56:03 INFO - PROCESS | 1635 | 2017-01-13 09:56:03.671 plugin-container[1637:7584] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e3b, name = 'com.apple.tsm.portname' 09:56:03 INFO - PROCESS | 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:56:03 INFO - PROCESS | 1635 | 2017-01-13 09:56:03.675 plugin-container[1637:7584] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x494f, name = 'com.apple.CFPasteboardClient' 09:56:03 INFO - PROCESS | 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:56:03 INFO - PROCESS | 1635 | 2017-01-13 09:56:03.675 plugin-container[1637:7584] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:56:03 INFO - PROCESS | 1635 | __metrics Screen width/height:1600/1200 09:56:03 INFO - PROCESS | 1635 | colorDepth:24 09:56:03 INFO - PROCESS | 1635 | Browser inner width/height: 1024/689 09:56:03 INFO - PROCESS | 1635 | __metrics 09:56:03 ERROR - PROCESS | 1635 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3242: TypeError: this.tabs is undefined 09:56:04 INFO - TEST-INFO | 1635: exit 0 09:56:04 INFO - Browser initialized. 09:56:04 INFO - Running cycle 1/1 for dromaeo_css test... 09:56:04 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/tmppoLXL1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:56:06 INFO - PROCESS | 1638 | 2017-01-13 09:56:06.899 plugin-container[1639:7702] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x434b, name = 'com.apple.tsm.portname' 09:56:06 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:56:06 INFO - PROCESS | 1638 | 2017-01-13 09:56:06.900 plugin-container[1639:7702] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 09:56:06 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:56:06 INFO - PROCESS | 1638 | 2017-01-13 09:56:06.900 plugin-container[1639:7702] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:56:16 INFO - PROCESS | 1638 | RSS: Main: 233336832 09:56:16 INFO - PROCESS | 1638 | 09:59:20 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 09:59:21 INFO - PROCESS | 1638 | RSS: Main: 250961920 09:59:21 INFO - PROCESS | 1638 | 10:01:04 INFO - PROCESS | 1638 | ************************* 10:01:04 INFO - PROCESS | 1638 | A coding exception was thrown and uncaught in a Task. 10:01:04 INFO - PROCESS | 1638 | 10:01:04 INFO - PROCESS | 1638 | Full message: ReferenceError: fetch is not defined 10:01:04 INFO - PROCESS | 1638 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 10:01:04 INFO - PROCESS | 1638 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 10:01:04 INFO - PROCESS | 1638 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 10:01:04 INFO - PROCESS | 1638 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 10:01:04 INFO - PROCESS | 1638 | TaskImpl@resource://gre/modules/Task.jsm:277:3 10:01:04 INFO - PROCESS | 1638 | asyncFunction@resource://gre/modules/Task.jsm:252:14 10:01:04 INFO - PROCESS | 1638 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 10:01:04 INFO - PROCESS | 1638 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 10:01:04 INFO - PROCESS | 1638 | TaskImpl@resource://gre/modules/Task.jsm:277:3 10:01:04 INFO - PROCESS | 1638 | asyncFunction@resource://gre/modules/Task.jsm:252:14 10:01:04 INFO - PROCESS | 1638 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 10:01:04 INFO - PROCESS | 1638 | 10:01:04 INFO - PROCESS | 1638 | ************************* 10:02:23 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 10:02:24 INFO - PROCESS | 1638 | RSS: Main: 262156288 10:02:24 INFO - PROCESS | 1638 | 10:05:26 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 10:05:27 INFO - PROCESS | 1638 | RSS: Main: 270655488 10:05:27 INFO - PROCESS | 1638 | 10:08:28 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 10:08:29 INFO - PROCESS | 1638 | RSS: Main: 266936320 10:08:29 INFO - PROCESS | 1638 | 10:11:32 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 10:11:32 INFO - PROCESS | 1638 | RSS: Main: 272568320 10:11:32 INFO - PROCESS | 1638 | 10:14:35 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 10:14:36 INFO - PROCESS | 1638 | RSS: Main: 275017728 10:14:36 INFO - PROCESS | 1638 | 10:14:36 INFO - PROCESS | 1638 | __start_tp_report 10:14:36 INFO - PROCESS | 1638 | _x_x_mozilla_page_load 10:14:36 INFO - PROCESS | 1638 | _x_x_mozilla_page_load_details 10:14:36 INFO - PROCESS | 1638 | |i|pagename|runs| 10:14:36 INFO - PROCESS | 1638 | |0;dojo.html;3339.6660333966593;3344.548485954396;3380.5267262583243;3389.322135572886;3391;5298.178782288742;5472.72636368181;5496.422875598059;5500.312460942382;5527.585431092664;5493.176023596463;5524.309461317335;5540.279763630732;5617.494425501704;5665.49010589048;4342.067779837973;4345.261305578059;4809.182438985364;5060.772265248054;5203.843884683465;4477.731336119843;4736.123817093831;5034.949650503485;5228.843134705946;5233.450487698794;4999.250112483121;5168.664036837631;5183.974080129613;5206.375234971809;5239.318888544503;5037.848864534069;5062.898742025139;5095.8980820383385;5111.514406131449;5112.821051263225;5060.797568097271;5129.307543481619;5135.075686376451;5137.409197942247;5143.922841157386;5384.246205531229;5546.972265138689;5590.720463976785;5614.691191984439;5628.971855140698;6030.728617212236;6075.9696201519155;6179.845503862394;6184.628922264634;6205.2553693557065;5118.564921981636;6135.04893764628;6180.721867515973;6201.751929922798;6207.255129384503;5791.942080579182;5793.507551858139;5799.82600521985;5821.708914554255;5822.359540450547;6114.449699527064;6130.724117414727;6181.660008699565;6197.752089916443;6204.875902482016;5794.652320860761;5863.941360586339;5881.705914704247;5897.469227769521;5935.317438494542;6087.117367981707;6104.4591554472245;6104.847378815565;6117.724702388403;6162.322144564095;5017.322661440732;5962.970185149134;6067.089936509559;6108.615274519966;6138.570300078952;6099.9085013724825;6129.948138092848;6130.662813545208;6161.106639537332;6173.382661733881;4884.7313397762755;4885.511448855157;4905.558499735041;4916.9428572856295;4927.063857866994;3760.793156376371;4239.830406783756;4346.1525002624185;4346.6522678185665;4360.629346505582;5691.630044047124;5696.8006119785605;5704.458076482728;5717.685527296038;5734.426557344233;6193.318734939153;6214.906776398358;6219.720112594944;6233.065040244;6257.342978987232;5568.972155139199;5602.411746766612;5626.43735626434;5675.687837168995;5687.431256874363;5311.840644780663;5316.229146773773;5354.277172581729;5387.595930305243;5432.782688692487;6171.889059969249;6213.471854892385;6218.098375735402;6223.408776166257;6256.5620406572925;6128.387161283836;6193.752249909953;6214.067889816563;6218.160548325918;6219.0982307566055;5254.632175747814;6144.941444616104;6160.507159427146;6172.814815555541;6191.318954915046;4656.790444430077;5875.41245875409;6121.47967422774;6135.785247516315;6145.201123853871;6180.443760061616;6200.627962322275;6205.782797602063;6205.968970155121;6234.15838861766;5873.588848780544;5975.43233392827;5979.671118088547;5989.760409583568;6002.579819412599;6010.759569617167;6020.9764340063175;6036.758529658764;6068.726907289094;6091.817245482643;4786.832460863853;4879;5760.510356619734;5768.221290125779;5888.411158884249;5899.734511946886;5999.340072592098;6000.189974353407;6012.6392416455155;6071.332153463204;5738.081906895044;5861.1501332307425;5922.585419020799;5978.8206353809455;5992.340842507234 10:14:36 INFO - PROCESS | 1638 | |1;ext.html;454.7294359855886;508.433097096737;519.7011718262;530.2523441946855;537.8628449745314;40091.79724608263;42388.304467821254;42624.78687606558;44021.7798911005;44203;41777;42219.15561688764;42231;42301.36547951783;42374.7881260594;8972.910270897306;9266.953665231682;9467.147956683897;9493.81012379752;9507.382020168701;10880.891191088067;11104.944475277613;11117.888821111766;11125.109991200725;11129.554817807279;11101.278416902916;11156.107511399068;11156.330620162775;11163.944180279088;11184.161187910899;11079.72300692481;11135.554577816878;11140.498677559448;11152.16358773087;11159.27464714791;11065.723356916142;11073.612423565217;11098;11149.498272577754;11152.275102118418;37209.44185837215;37298.254034919424;37439.62560374389;37464.812675936715;37470.06324841874;3992.8602498912687;4018.457508236409;4026.879193624196;4028.8388464461386;4032.697547683935;28406.721697523455;28955.56566651502;29385.70614293851;29468.26329341762;29490.852545737132;3140.1364624728153;3163.604549431321;3181.1569933967517;3222.6616205298574;3258.348330333919;474684;477565;479270;480514.9999999965;480945;45306.546934530896;56414.307570772944;56836.863262734514;57321;57321;48441;49451.50548494541;57834;58535;58652.41347586555;12781.872181278255;15480.922595387176;15566.533004009898;15590;15617.219139043229;10912.399818010086;11140.053095486812;11424.600138995096;11927.940360298317;12120.575779847839;15606.9219653901;16514.42199523011;17265.82734172642;18126.27494900189;18210.817891821178;6554.344565543408;6973.4072603829245;7845.293923546794;7873.795337305563;7934.722284720007;811.2333844516875;881.1761003461784;890.7995700967231;900.4057322167469;909.2226146644656;23748.05007799703;28865.711342886723;30540.389192216033;30570.847145764128;31045.68954310428;961.8845738511424;993.7528401855769;1047.115187666436;1071.9196060295353;1073.9355638661705;12407.627771166879;14373.568792936228;14884.553463396116;15183.848161518465;15254.542363729106;629324;695826;711943;712495;914639;55122.72438637861;55782.44217557854;55808.441915581134;55854.44145558492;55897.720511397994;1422.8292604887272;1436.3105709259405;1451.8112645356036;1452.7094581083825;1464.5313499680212;29235.122946311643;29323.560146597392;29328.56007159937;29676.851615741783;29954.25114372158;47852.52147478481;48758;48898.02203955972;48902.0219595599;49007.509924900296;572448;576400;577177;578377;579628;25225.495490090463;25889.482210355312;26030.828612712852;26049.869750651884;27049.45901082007;37080;43745.78127109344;43852.780736096116;44925.55074449214;46272;36548.81725591355;36721.42949993475;36963;37390;37660;36314.4552831713;36878.8156059218;37511.81244093762;37633.2473350537;37908.43137352997;34491.4826277601;34551.82724086363;35098.47352289667;41564.79217603893;42506.93732656708;39566.802165988986;41765;41860.79069604632;42840.57159428366;43160.92097697582;32160.35679286448;32990.5051424224;33050.33899322048;33053.504197436574;33414.83292583522 10:14:36 INFO - PROCESS | 1638 | |2;jquery.html;377.1250698379759;388.8891665875224;390.81045692838984;400.92783299006175;423.3776348767311;20456.69314960277;21803;21809.781902180974;22021.779782202215;22254.554908901813;21636.783632163675;21773.693578385308;22423.887880560575;22427.215047473343;22609.982550785215;5474.835754927359;5844.912326315109;5986.802639472101;6152.938470615304;6176.69116544171;5823.242978412801;6040;6044.727987240584;6108.083787431877;6162.1681073055015;6066.454019138277;6076.756929722806;6077.179580756608;6094.634321940675;6099.451049405553;5833.766649334021;6024.457798798129;6050.42520960508;6064.211652485193;6072.089186622042;5285.418603953562;5322.467753224685;5323.30796996388;5324.174752913266;5325.866853328659;13660.248686322248;17083.487495375033;18980.335688250983;20894.79105208959;23897.402564936016;2919.474494590973;2950.542665886791;3022.319978004953;3022.4106299271652;3076.138681169276;8875.778105547348;10245.89754102457;10338.948305258426;12319.322437265946;14954.775678364833;1797.074506629087;1812.1211212561898;1825.0783354406074;1828.780546334435;1848.5286252005653;100419;133284;137187.31406343004;137812;138519;10644;11444.94277528607;11902.583409580624;12105.455254513568;12233.510659573518;15643.530694079196;15722;15778.13220272873;15825.366985320688;15835.445759398366;8643.870341944876;8909.465432073966;8925.509096999598;8992.55037248135;8996.730098096936;8585.399022068521;8665.2634526066;8684.305255579664;8811.603477843513;8857.335699822539;4777.641676874248;4786.0667169902235;4857.684250523705;4918.581920536794;4920.507949205123;1984.382728076613;2028.4421784009514;2038.225474319749;2038.3477287268229;2044.0801639262095;824.5052968219021;840.8696652018947;846.1242613894713;864.7751584587927;877.9429337093069;17257.10171881092;21885.67171492429;22983.42541436444;23115.422114447272;23153;885.3890815337396;891.4517571693361;910.9908900911039;911.4531281231341;917.5228880981851;9369.250459963187;11865.037062746327;11963.64109076729;11965.46155423008;11989.460474278678;673742;686163;702214;703308;716755;149731.50268497175;152918.23540882225;152983.23508382388;164748.3525164757;167150;1003.6236411345745;1019.7195771162841;1059.046857827961;1060.2207377577474;1061.2253055269696;20342.79657203409;21441.785582144603;21534.492585518863;21560.784392155878;21569;61991.07013394713;62688.37311626825;63110.99999999817;63291.36708633039;63481.682591586745;91906;92957.5352123235;94206.28215190861;94588.58117128111;95580;8420.65269556868;8534.658613655633;8598.39811213209;8605.612747426256;8677.1756683115;9332.673356432493;12940.352982351033;13332.200067995951;13704.314784260947;13782.173069615856;11475.311481310815;11555.01782348476;11755.353455560027;11901.82147267809;11909.166358354832;11474.77050459003;11543.5959741412;11560.190786644855;11886.583969561023;11923.046156307648;11677.708057298296;11708.238964467282;11864.347460889734;11997;12001.63995080149;8444.915550844413;8558.957205213934;8570;8667.263282621048;8673.609687563949;9806.901930980599;11129.944350278522;11493.942530287295;11525;11764.705882353106 10:14:36 INFO - PROCESS | 1638 | |3;mootools.html;1467.1050659097955;1475.0338528263992;1478.0540454109366;1492.8357880633132;1512.085188460981;7549.622518874048;7794.532328060306;7854.410919181056;8287.171282871717;8287.254147126758;7803.804904877381;8037.7588672339925;8048;8165.795855103625;8173.632186551605;2283.075354481438;2337.333859849943;2338.2166974063707;2338.8362814602983;2342.145117032288;2080.583883223354;2326.232343326698;2343.3907184132077;2356.304890057437;2360.905563777447;1963.234338607944;2207.6578130389735;2316.9884150579223;2320.9535809283802;2339.087755775249;2197.8681279123302;2295.6671282664006;2323.186884590397;2324.244620498328;2325.197806756666;2062.7937206279403;2295.162265772981;2330.860148391085;2336.5910965580956;2339.6607491913746;8168.305694016016;8195.385346099007;8210.794730131794;8245.711400101027;8253.009638843318;5034.421041580192;5167.328247327823;5173.896522069575;5174.7671354788745;5194.740262986873;2863.957040644392;3216.404965081444;3293.275479394529;3463.341965026662;3473.513708080871;1207.1851500237306;1226.4297101847594;1701.1749301588843;1729.3082766893217;1811.1759149586906;10616.787664246673;10709.303895246861;10765.600471938755;10765.677029689123;10813.297135686154;4530.365748795171;4708.293755936637;4710.222813235846;4712.104700107037;4772.76136193189;8629.568521573896;8684.6526138955;8686.131386861389;8738.300935925237;8749.518776467356;5144.151215049475;5149;5155.32980712505;5161.251618515369;5169.689818610896;4627.814887404534;4735.408073990751;4811.398575178103;4825.758712064383;4836.250381190921;3532.947005794915;3660.048387419237;3668.5414323209598;3684.3552378333684;3697.5193224880595;1131.6548452722;1143.662619527225;1149.7757937202332;1150.5685367987005;1155.0932517973351;919.9218066464425;932.3520153493257;944.9699827188398;946.9005754395827;991.4398364923795;5512.145617429305;5598.10430331145;5638.915416268759;5722.427757224327;5814.622049566765;563.1805722673582;576.1357963055417;580.8402689260487;583.5993615323258;594.6105301027834;3371.544841446422;3531.081918701156;3715.405535114423;3810.0665336992615;3842.865499707497;7809.80475488106;9756.707298781048;9878.654247101316;9879.851802222973;9946.552405141785;9614;9733.2213422926;9771.413715177112;9801.166900813369;9823.11591956713;7646;7738.187490313549;7780.922190778133;7838.490498117604;7844.294013538809;5451.536619387396;5620.353659329229;5621.381648018796;5641.717914104196;5795.82612521625;9109.362344635812;9176.128267814547;9191.310651701282;9195.98844127159;9217.493037882712;8291.875621865798;8553.230209281195;8682.826343473222;9045.954770226106;9067.727968160965;5903.409659034062;6820.35206655367;6988.825279368057;7034;7050.118735158106;6570.5729127606555;7713.305802477804;7904.170062143392;7932.960335198287;7986.720464783704;7090.893636595352;7092.361687448154;7117.110361204849;7138.650723147747;7255.52839065459;6930.5148639594745;6952.443804495527;6961.686724097326;7076.858462830817;7104.11198600175;5169.81905633301;6339.619622822645;6416.679166041773;6442.549021568445;6595.571287866273;3893.8972954604196;4918.581920536794;4960.8759781005765;5020.623453240948;5124.7950081996305;4929.556339929423;4998;5087.87280317995;5118.053160165381;5129.384473863161 10:14:36 INFO - PROCESS | 1638 | |4;prototype.html;310.3389779769094;359.98560057597695;361.41450849623607;366.62054773309615;379.0996383588976;4196.475440569929;5225.86935326617;5301.8409447716585;5436.945630543693;5460.126379779231;4385.894770510157;4390.407295015172;5230.6600070995355;5335.439778823216;5346.786128554862;3468.618451970281;4201.642860356866;4210.663146948237;4221.746695198298;4226.3026600610865;3392.796432214075;4130.987907962538;4231.555686652903;4248.915021699564;4253;3299.4720844664857;3960.188161426915;4007.5792041835625;4009.398590211477;4053.351463765798;3981.741186822862;4009.458723072377;4009.939850902239;4022.8189731462153;4033.132876431552;3057.113437103237;3199.984000080008;3229.7093261606333;3522.8198553484613;3543.8405271762576;4660.5106463821485;4845.709257444564;4916.680415772999;5234.842954711365;5290.735463226823;3910.687145028391;4628.930566041512;4678.508756580578;4686.953130468686;4716.740579268139;4834.033193361342;4987.329244703029;5664.348599910981;5693.572982026365;5766.596338256324;3387.8814241501427;3480.843362048714;3553.662402071799;3756.868509602178;3759.3797023491093;4946.35697359341;5456.208849716769;5947.840171166624;5959.165716799652;5963.078091735062;3314.386838434897;3927.273454410943;3943.3099207638547;3946.3291240489184;3961.26716557432;3803.67668748154;3995.900102497461;4004.7797371144284;4007.8196481158416;4017.738846974937;4274.935875961863;4587.747673877969;4634.335257227108;4666.696664716783;4687.507811679793;5497.367802702661;5796.82609521715;5797.420257974253;5822.796202132905;5827.533797296206;1148.8104462763717;1216.4829947272374;1234.3581337704343;1248.3196657821509;1284.5696691608416;824.5959479854828;879.2833840420037;884.9867251991225;892.9419587726907;895.8790563274005;4526.954730452653;4614.877156507977;4679.9298010529865;4753.053107073567;5077.8320986173385;4739.246478802865;5252.868678282997;5338.546223570962;5406.729663516809;5655.897100065447;5104.872378190575;5139;5141.871453213699;5154.329937108152;5156.845294641167;2623.8819253133656;2641.260447074822;2929.169269206746;2939.8824047038306;2957.452871218831;4905.9754701226975;5058.974705126451;5428.805662754187;5827.7668893244645;5847.684271039067;5733.627314224562;5744.339400968858;5785.739641716133;5788.710564471759;5820.330661973842;5684.431556844365;5744.221818254333;5794.217780599565;5823;5842.795502157489;4593;4605.516420775904;4654;4851.927221091616;5526.849695673428;4372.781360931877;4614.923284522046;5287.017368486818;5413.675179489243;5586.692731899784;4775.785089670903;5291;5363.249145119765;5384.865378365572;5872.236609240771;3426.9657303426648;3628.32875917956;3684.5025921500255;3693.9261214776093;3714.5542534895985;4527.709602763197;4742.668537502473;4833.806647734052;4836;5598.664080155204;4471.262241730208;4838.153323168502;4882.438519570295;5228.6405534562045;5432.6468779529205;4655.533506945437;5189.532942035084;5316.6012549058205;5338.279332290246;5410.648307859977;4424.823007079681;4545.250033744527;4847.13385346637;5008.749562521933;5366.785328586813;5651.067573850269;5711.343195532567;5725.770969161187;5734.645232539011;5779.104238842986;4921.434035086011;5026.617680137992;5104.259882317117;5115.590752739847;5576.693281869536 10:14:36 INFO - PROCESS | 1638 | |5;yui.html;707.8761216787061;718.7951433841356;751.7594369801664;775.1783109903503;859.1923591823687;12190.573329933453;15545.300461479232;15759.763603545956;15786.447474338414;15805.446809361674;14031.78952315716;14536.691697747201;14659.633509162277;14671.193084380355;14679.853201467982;854.5470900422755;870.8867847179857;872.2978002707817;875.5840975536632;875.6672464463527;693.8820400531896;716.4304378019488;841.6465084664455;862.3877047296396;875.5797217335682;916.5096673279796;924.5608336040394;924.8057907839362;925.3476299209075;925.5927391947522;918.76112210825;920.4523308631387;920.4661296448111;920.9493477858715;921.1893533690377;673.0308355967392;687.0655907965163;687.1856849633201;687.6183718036488;689.5965859971923;14973.251337433085;15265.465708700252;15329.233538323151;15364.923175384163;15410.383584656713;14120.788188177186;14310.49913253031;14399.640008999753;14400.135991840523;14479.855201447956;11815.586454474053;11841.171118021743;11903.583374581847;11957;11975.389371061709;3678.558572971234;3704.0554658562164;3715.498407714951;3718.4608231806506;3859.2281543691374;114154;115129;115540;115740.84259157469;117318;8397.70608028716;9778.951105244429;10002.749931251632;10014.148797352162;10127.59489620407;22552.21067262638;22788.658170127463;22799.088036478355;22812.20157294487;22812.885935570546;7555;7579.431542634325;7606.467547271748;7620.5808680522;7673.808154796063;4262.893427664333;4355.651547876162;4368.36658684489;4467.776611169428;4522.140793249338;3578.499010138583;3636.090977255686;3669.192777588926;3675.5956844747056;3676.0809797550614;515.3197778931849;633.4710516718592;662.4733336997099;668.9933100669028;680.3502654964497;669.6450881033061;686.8385929306609;695.6799872058808;700.425650966213;736.6684991753732;10027.649032283982;10271.794564108675;10313;10512.632057877938;10748.408837514013;666.986660266792;691.1464341538197;692.2765709833236;696.474162007681;702.7715992302523;4935.531124543163;5081.72050537224;5086.92369614456;5089.160288552422;5090.847274581768;84082;105553;106365;107033;110554;87478;89332.5533372329;89428.6585701215;89697;92462.07537924535;60000.09999850006;77718;79545.20454795494;79877;80727.19272807315;7542.962285188539;8047.436679432383;8542.615582298688;9207.125323094295;9224.723258302261;49275.753621233096;51175.99999999851;51193.48806511887;51213.74393128011;53933;68215.65892170508;68480.31519684939;71146.64426678041;71195.64402177956;73449;12922.870771292542;12957.497341720848;13110.525710686867;13434;13804;10854.67435976922;14480.039920159681;14870.925645371704;14996.775048374066;15315.693686126438;13857.653558661113;14034.461739737946;14042.859571404155;14505.492307769178;14545.200013999332;13695.178289302674;13874.445022199;13969.860301396857;14060.08609440383;14512.492062777752;13655;13676.589702308947;13713.931430342784;13727;14124.646883827987;10311.329763565343;10966.17753668494;11391.316521008766;11667.708307292385;11869.821952670543;8529.95735021321;8663.523506207219;11222.943885280521;11284.548618055185;11423.771524569629 10:14:36 INFO - PROCESS | 1638 | __end_tp_report 10:14:36 INFO - PROCESS | 1638 | __start_cc_report 10:14:36 INFO - PROCESS | 1638 | _x_x_mozilla_cycle_collect,380 10:14:36 INFO - PROCESS | 1638 | __end_cc_report 10:14:36 INFO - PROCESS | 1638 | __startTimestamp1484331276010__endTimestamp 10:14:36 INFO - PROCESS | 1638 | 10:14:36 INFO - PROCESS | 1638 | ------- Summary: start ------- 10:14:36 INFO - PROCESS | 1638 | Number of tests: 6 10:14:36 INFO - PROCESS | 1638 | 10:14:36 INFO - PROCESS | 1638 | [#0] dojo.html Cycles:165 Average:5600.69 Median:5799.83 stddev:664.29 (11.5%) stddev-sans-first:642.21 10:14:36 INFO - PROCESS | 1638 | Values: 3339.7 3344.5 3380.5 3389.3 3391.0 5298.2 5472.7 5496.4 5500.3 5527.6 5493.2 5524.3 5540.3 5617.5 5665.5 4342.1 4345.3 4809.2 5060.8 5203.8 4477.7 4736.1 5034.9 5228.8 5233.5 4999.3 5168.7 5184.0 5206.4 5239.3 5037.8 5062.9 5095.9 5111.5 5112.8 5060.8 5129.3 5135.1 5137.4 5143.9 5384.2 5547.0 5590.7 5614.7 5629.0 6030.7 6076.0 6179.8 6184.6 6205.3 5118.6 6135.0 6180.7 6201.8 6207.3 5791.9 5793.5 5799.8 5821.7 5822.4 6114.4 6130.7 6181.7 6197.8 6204.9 5794.7 5863.9 5881.7 5897.5 5935.3 6087.1 6104.5 6104.8 6117.7 6162.3 5017.3 5963.0 6067.1 6108.6 6138.6 6099.9 6129.9 6130.7 6161.1 6173.4 4884.7 4885.5 4905.6 4916.9 4927.1 3760.8 4239.8 4346.2 4346.7 4360.6 5691.6 5696.8 5704.5 5717.7 5734.4 6193.3 6214.9 6219.7 6233.1 6257.3 5569.0 5602.4 5626.4 5675.7 5687.4 5311.8 5316.2 5354.3 5387.6 5432.8 6171.9 6213.5 6218.1 6223.4 6256.6 6128.4 6193.8 6214.1 6218.2 6219.1 5254.6 6144.9 6160.5 6172.8 6191.3 4656.8 5875.4 6121.5 6135.8 6145.2 6180.4 6200.6 6205.8 6206.0 6234.2 5873.6 5975.4 5979.7 5989.8 6002.6 6010.8 6021.0 6036.8 6068.7 6091.8 4786.8 4879.0 5760.5 5768.2 5888.4 5899.7 5999.3 6000.2 6012.6 6071.3 5738.1 5861.2 5922.6 5978.8 5992.3 10:14:36 INFO - PROCESS | 1638 | 10:14:36 INFO - PROCESS | 1638 | [#1] ext.html Cycles:180 Average:72167.03 Median:37452.22 stddev:162959.34 (435.1%) stddev-sans-first:163327.53 10:14:36 INFO - PROCESS | 1638 | Values: 454.7 508.4 519.7 530.3 537.9 40091.8 42388.3 42624.8 44021.8 44203.0 41777.0 42219.2 42231.0 42301.4 42374.8 8972.9 9267.0 9467.1 9493.8 9507.4 10880.9 11104.9 11117.9 11125.1 11129.6 11101.3 11156.1 11156.3 11163.9 11184.2 11079.7 11135.6 11140.5 11152.2 11159.3 11065.7 11073.6 11098.0 11149.5 11152.3 37209.4 37298.3 37439.6 37464.8 37470.1 3992.9 4018.5 4026.9 4028.8 4032.7 28406.7 28955.6 29385.7 29468.3 29490.9 3140.1 3163.6 3181.2 3222.7 3258.3 474684.0 477565.0 479270.0 480515.0 480945.0 45306.5 56414.3 56836.9 57321.0 57321.0 48441.0 49451.5 57834.0 58535.0 58652.4 12781.9 15480.9 15566.5 15590.0 15617.2 10912.4 11140.1 11424.6 11927.9 12120.6 15606.9 16514.4 17265.8 18126.3 18210.8 6554.3 6973.4 7845.3 7873.8 7934.7 811.2 881.2 890.8 900.4 909.2 23748.1 28865.7 30540.4 30570.8 31045.7 961.9 993.8 1047.1 1071.9 1073.9 12407.6 14373.6 14884.6 15183.8 15254.5 629324.0 695826.0 711943.0 712495.0 914639.0 55122.7 55782.4 55808.4 55854.4 55897.7 1422.8 1436.3 1451.8 1452.7 1464.5 29235.1 29323.6 29328.6 29676.9 29954.3 47852.5 48758.0 48898.0 48902.0 49007.5 572448.0 576400.0 577177.0 578377.0 579628.0 25225.5 25889.5 26030.8 26049.9 27049.5 37080.0 43745.8 43852.8 44925.6 46272.0 36548.8 36721.4 36963.0 37390.0 37660.0 36314.5 36878.8 37511.8 37633.2 37908.4 34491.5 34551.8 35098.5 41564.8 42506.9 39566.8 41765.0 41860.8 42840.6 43160.9 32160.4 32990.5 33050.3 33053.5 33414.8 10:14:36 INFO - PROCESS | 1638 | 10:14:36 INFO - PROCESS | 1638 | [#2] jquery.html Cycles:180 Average:39836.18 Median:13408.44 stddev:116614.29 (869.7%) stddev-sans-first:116903.79 10:14:36 INFO - PROCESS | 1638 | Values: 377.1 388.9 390.8 400.9 423.4 20456.7 21803.0 21809.8 22021.8 22254.6 21636.8 21773.7 22423.9 22427.2 22610.0 5474.8 5844.9 5986.8 6152.9 6176.7 5823.2 6040.0 6044.7 6108.1 6162.2 6066.5 6076.8 6077.2 6094.6 6099.5 5833.8 6024.5 6050.4 6064.2 6072.1 5285.4 5322.5 5323.3 5324.2 5325.9 13660.2 17083.5 18980.3 20894.8 23897.4 2919.5 2950.5 3022.3 3022.4 3076.1 8875.8 10245.9 10338.9 12319.3 14954.8 1797.1 1812.1 1825.1 1828.8 1848.5 100419.0 133284.0 137187.3 137812.0 138519.0 10644.0 11444.9 11902.6 12105.5 12233.5 15643.5 15722.0 15778.1 15825.4 15835.4 8643.9 8909.5 8925.5 8992.6 8996.7 8585.4 8665.3 8684.3 8811.6 8857.3 4777.6 4786.1 4857.7 4918.6 4920.5 1984.4 2028.4 2038.2 2038.3 2044.1 824.5 840.9 846.1 864.8 877.9 17257.1 21885.7 22983.4 23115.4 23153.0 885.4 891.5 911.0 911.5 917.5 9369.3 11865.0 11963.6 11965.5 11989.5 673742.0 686163.0 702214.0 703308.0 716755.0 149731.5 152918.2 152983.2 164748.4 167150.0 1003.6 1019.7 1059.0 1060.2 1061.2 20342.8 21441.8 21534.5 21560.8 21569.0 61991.1 62688.4 63111.0 63291.4 63481.7 91906.0 92957.5 94206.3 94588.6 95580.0 8420.7 8534.7 8598.4 8605.6 8677.2 9332.7 12940.4 13332.2 13704.3 13782.2 11475.3 11555.0 11755.4 11901.8 11909.2 11474.8 11543.6 11560.2 11886.6 11923.0 11677.7 11708.2 11864.3 11997.0 12001.6 8444.9 8559.0 8570.0 8667.3 8673.6 9806.9 11129.9 11493.9 11525.0 11764.7 10:14:36 INFO - PROCESS | 1638 | 10:14:36 INFO - PROCESS | 1638 | [#3] mootools.html Cycles:180 Average:5327.71 Median:5323.14 stddev:2867.32 (53.9%) stddev-sans-first:2860.68 10:14:36 INFO - PROCESS | 1638 | Values: 1467.1 1475.0 1478.1 1492.8 1512.1 7549.6 7794.5 7854.4 8287.2 8287.3 7803.8 8037.8 8048.0 8165.8 8173.6 2283.1 2337.3 2338.2 2338.8 2342.1 2080.6 2326.2 2343.4 2356.3 2360.9 1963.2 2207.7 2317.0 2321.0 2339.1 2197.9 2295.7 2323.2 2324.2 2325.2 2062.8 2295.2 2330.9 2336.6 2339.7 8168.3 8195.4 8210.8 8245.7 8253.0 5034.4 5167.3 5173.9 5174.8 5194.7 2864.0 3216.4 3293.3 3463.3 3473.5 1207.2 1226.4 1701.2 1729.3 1811.2 10616.8 10709.3 10765.6 10765.7 10813.3 4530.4 4708.3 4710.2 4712.1 4772.8 8629.6 8684.7 8686.1 8738.3 8749.5 5144.2 5149.0 5155.3 5161.3 5169.7 4627.8 4735.4 4811.4 4825.8 4836.3 3532.9 3660.0 3668.5 3684.4 3697.5 1131.7 1143.7 1149.8 1150.6 1155.1 919.9 932.4 945.0 946.9 991.4 5512.1 5598.1 5638.9 5722.4 5814.6 563.2 576.1 580.8 583.6 594.6 3371.5 3531.1 3715.4 3810.1 3842.9 7809.8 9756.7 9878.7 9879.9 9946.6 9614.0 9733.2 9771.4 9801.2 9823.1 7646.0 7738.2 7780.9 7838.5 7844.3 5451.5 5620.4 5621.4 5641.7 5795.8 9109.4 9176.1 9191.3 9196.0 9217.5 8291.9 8553.2 8682.8 9046.0 9067.7 5903.4 6820.4 6988.8 7034.0 7050.1 6570.6 7713.3 7904.2 7933.0 7986.7 7090.9 7092.4 7117.1 7138.7 7255.5 6930.5 6952.4 6961.7 7076.9 7104.1 5169.8 6339.6 6416.7 6442.5 6595.6 3893.9 4918.6 4960.9 5020.6 5124.8 4929.6 4998.0 5087.9 5118.1 5129.4 10:14:36 INFO - PROCESS | 1638 | 10:14:36 INFO - PROCESS | 1638 | [#4] prototype.html Cycles:180 Average:4406.76 Median:4804.80 stddev:1343.53 (28.0%) stddev-sans-first:1311.64 10:14:36 INFO - PROCESS | 1638 | Values: 310.3 360.0 361.4 366.6 379.1 4196.5 5225.9 5301.8 5436.9 5460.1 4385.9 4390.4 5230.7 5335.4 5346.8 3468.6 4201.6 4210.7 4221.7 4226.3 3392.8 4131.0 4231.6 4248.9 4253.0 3299.5 3960.2 4007.6 4009.4 4053.4 3981.7 4009.5 4009.9 4022.8 4033.1 3057.1 3200.0 3229.7 3522.8 3543.8 4660.5 4845.7 4916.7 5234.8 5290.7 3910.7 4628.9 4678.5 4687.0 4716.7 4834.0 4987.3 5664.3 5693.6 5766.6 3387.9 3480.8 3553.7 3756.9 3759.4 4946.4 5456.2 5947.8 5959.2 5963.1 3314.4 3927.3 3943.3 3946.3 3961.3 3803.7 3995.9 4004.8 4007.8 4017.7 4274.9 4587.7 4634.3 4666.7 4687.5 5497.4 5796.8 5797.4 5822.8 5827.5 1148.8 1216.5 1234.4 1248.3 1284.6 824.6 879.3 885.0 892.9 895.9 4527.0 4614.9 4679.9 4753.1 5077.8 4739.2 5252.9 5338.5 5406.7 5655.9 5104.9 5139.0 5141.9 5154.3 5156.8 2623.9 2641.3 2929.2 2939.9 2957.5 4906.0 5059.0 5428.8 5827.8 5847.7 5733.6 5744.3 5785.7 5788.7 5820.3 5684.4 5744.2 5794.2 5823.0 5842.8 4593.0 4605.5 4654.0 4851.9 5526.8 4372.8 4614.9 5287.0 5413.7 5586.7 4775.8 5291.0 5363.2 5384.9 5872.2 3427.0 3628.3 3684.5 3693.9 3714.6 4527.7 4742.7 4833.8 4836.0 5598.7 4471.3 4838.2 4882.4 5228.6 5432.6 4655.5 5189.5 5316.6 5338.3 5410.6 4424.8 4545.3 4847.1 5008.7 5366.8 5651.1 5711.3 5725.8 5734.6 5779.1 4921.4 5026.6 5104.3 5115.6 5576.7 10:14:36 INFO - PROCESS | 1638 | 10:14:36 INFO - PROCESS | 1638 | [#5] yui.html Cycles:180 Average:20832.93 Median:4309.27 stddev:30338.64 (704.0%) stddev-sans-first:30386.12 10:14:36 INFO - PROCESS | 1638 | Values: 707.9 718.8 751.8 775.2 859.2 12190.6 15545.3 15759.8 15786.4 15805.4 14031.8 14536.7 14659.6 14671.2 14679.9 854.5 870.9 872.3 875.6 875.7 693.9 716.4 841.6 862.4 875.6 916.5 924.6 924.8 925.3 925.6 918.8 920.5 920.5 920.9 921.2 673.0 687.1 687.2 687.6 689.6 14973.3 15265.5 15329.2 15364.9 15410.4 14120.8 14310.5 14399.6 14400.1 14479.9 11815.6 11841.2 11903.6 11957.0 11975.4 3678.6 3704.1 3715.5 3718.5 3859.2 114154.0 115129.0 115540.0 115740.8 117318.0 8397.7 9779.0 10002.7 10014.1 10127.6 22552.2 22788.7 22799.1 22812.2 22812.9 7555.0 7579.4 7606.5 7620.6 7673.8 4262.9 4355.7 4368.4 4467.8 4522.1 3578.5 3636.1 3669.2 3675.6 3676.1 515.3 633.5 662.5 669.0 680.4 669.6 686.8 695.7 700.4 736.7 10027.6 10271.8 10313.0 10512.6 10748.4 667.0 691.1 692.3 696.5 702.8 4935.5 5081.7 5086.9 5089.2 5090.8 84082.0 105553.0 106365.0 107033.0 110554.0 87478.0 89332.6 89428.7 89697.0 92462.1 60000.1 77718.0 79545.2 79877.0 80727.2 7543.0 8047.4 8542.6 9207.1 9224.7 49275.8 51176.0 51193.5 51213.7 53933.0 68215.7 68480.3 71146.6 71195.6 73449.0 12922.9 12957.5 13110.5 13434.0 13804.0 10854.7 14480.0 14870.9 14996.8 15315.7 13857.7 14034.5 14042.9 14505.5 14545.2 13695.2 13874.4 13969.9 14060.1 14512.5 13655.0 13676.6 13713.9 13727.0 14124.6 10311.3 10966.2 11391.3 11667.7 11869.8 8530.0 8663.5 11222.9 11284.5 11423.8 10:14:36 INFO - PROCESS | 1638 | -------- Summary: end -------- 10:14:36 INFO - PROCESS | 1638 | 10:14:36 INFO - TEST-INFO | 1638: exit 0 10:14:36 INFO - TEST-OK | dromaeo_css | took 1115894ms 10:14:36 INFO - TEST-START | kraken 10:14:36 INFO - operating with platform_type : mac_ 10:14:36 INFO - Initialising browser for kraken test... 10:14:36 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/tmpY5yjga/profile http://localhost:49241/getInfo.html) 10:14:39 INFO - PROCESS | 1649 | 2017-01-13 10:14:39.390 plugin-container[1650:10030] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893b, name = 'com.apple.tsm.portname' 10:14:39 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:14:39 INFO - PROCESS | 1649 | 2017-01-13 10:14:39.393 plugin-container[1650:10030] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4607, name = 'com.apple.CFPasteboardClient' 10:14:39 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:14:39 INFO - PROCESS | 1649 | 2017-01-13 10:14:39.393 plugin-container[1650:10030] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:14:39 INFO - PROCESS | 1649 | __metrics Screen width/height:1600/1200 10:14:39 INFO - PROCESS | 1649 | colorDepth:24 10:14:39 INFO - PROCESS | 1649 | Browser inner width/height: 1024/689 10:14:39 INFO - PROCESS | 1649 | __metrics 10:14:39 ERROR - PROCESS | 1649 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3242: TypeError: this.tabs is undefined 10:14:40 INFO - TEST-INFO | 1649: exit 0 10:14:40 INFO - Browser initialized. 10:14:40 INFO - Running cycle 1/1 for kraken test... 10:14:40 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/tmpY5yjga/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:14:42 INFO - PROCESS | 1651 | 2017-01-13 10:14:42.683 plugin-container[1652:10152] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8843, name = 'com.apple.tsm.portname' 10:14:42 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:14:42 INFO - PROCESS | 1651 | 2017-01-13 10:14:42.683 plugin-container[1652:10152] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 10:14:42 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:14:42 INFO - PROCESS | 1651 | 2017-01-13 10:14:42.683 plugin-container[1652:10152] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:14:52 INFO - PROCESS | 1651 | RSS: Main: 233455616 10:14:52 INFO - PROCESS | 1651 | 10:14:55 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 10:14:56 INFO - PROCESS | 1651 | RSS: Main: 246079488 10:14:56 INFO - PROCESS | 1651 | 10:14:59 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 10:14:59 INFO - PROCESS | 1651 | RSS: Main: 254156800 10:14:59 INFO - PROCESS | 1651 | 10:15:03 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 10:15:04 INFO - PROCESS | 1651 | RSS: Main: 256888832 10:15:04 INFO - PROCESS | 1651 | 10:15:05 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 10:15:06 INFO - PROCESS | 1651 | RSS: Main: 258973696 10:15:06 INFO - PROCESS | 1651 | 10:15:08 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:15:09 INFO - PROCESS | 1651 | RSS: Main: 259260416 10:15:09 INFO - PROCESS | 1651 | 10:15:12 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 10:15:12 INFO - PROCESS | 1651 | RSS: Main: 265109504 10:15:12 INFO - PROCESS | 1651 | 10:15:15 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 10:15:16 INFO - PROCESS | 1651 | RSS: Main: 264589312 10:15:16 INFO - PROCESS | 1651 | 10:15:19 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 10:15:20 INFO - PROCESS | 1651 | RSS: Main: 264757248 10:15:20 INFO - PROCESS | 1651 | 10:15:21 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:15:22 INFO - PROCESS | 1651 | RSS: Main: 263352320 10:15:22 INFO - PROCESS | 1651 | 10:15:24 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:15:25 INFO - PROCESS | 1651 | RSS: Main: 263385088 10:15:25 INFO - PROCESS | 1651 | 10:15:27 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:15:27 INFO - PROCESS | 1651 | RSS: Main: 263524352 10:15:27 INFO - PROCESS | 1651 | 10:15:30 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:15:31 INFO - PROCESS | 1651 | RSS: Main: 263733248 10:15:31 INFO - PROCESS | 1651 | 10:15:34 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:15:35 INFO - PROCESS | 1651 | RSS: Main: 263614464 10:15:35 INFO - PROCESS | 1651 | 10:15:37 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 10:15:37 INFO - PROCESS | 1651 | RSS: Main: 265465856 10:15:37 INFO - PROCESS | 1651 | 10:15:37 INFO - PROCESS | 1651 | __start_tp_report 10:15:37 INFO - PROCESS | 1651 | _x_x_mozilla_page_load 10:15:37 INFO - PROCESS | 1651 | _x_x_mozilla_page_load_details 10:15:37 INFO - PROCESS | 1651 | |i|pagename|runs| 10:15:37 INFO - PROCESS | 1651 | |0;ai-astar;112;174;119;126;119;119;119;117;117;141 10:15:37 INFO - PROCESS | 1651 | |1;audio-beat-detection;180;144;116;148;114;141;143;132;166;145 10:15:37 INFO - PROCESS | 1651 | |2;audio-dft;204;264;285;196;195;200;197;193;196;194 10:15:37 INFO - PROCESS | 1651 | |3;audio-fft;80;58;60;97;63;61;61;63;88;54 10:15:37 INFO - PROCESS | 1651 | |4;audio-oscillator;90;72;83;101;102;83;81;70;71;84 10:15:37 INFO - PROCESS | 1651 | |5;imaging-gaussian-blur;102;133;89;101;99;109;93;86;89;87 10:15:37 INFO - PROCESS | 1651 | |6;imaging-darkroom;97;96;130;155;106;98;97;100;126;155 10:15:37 INFO - PROCESS | 1651 | |7;imaging-desaturate;93;96;91;94;94;107;126;91;105;93 10:15:37 INFO - PROCESS | 1651 | |8;json-parse-financial;62;62;60;63;62;61;64;61;61;62 10:15:37 INFO - PROCESS | 1651 | |9;json-stringify-tinderbox;72;73;69;89;111;102;83;70;69;72 10:15:37 INFO - PROCESS | 1651 | |10;stanford-crypto-aes;92;80;100;106;109;112;91;91;90;93 10:15:37 INFO - PROCESS | 1651 | |11;stanford-crypto-ccm;150;158;151;152;148;160;151;164;201;166 10:15:37 INFO - PROCESS | 1651 | |12;stanford-crypto-pbkdf2;173;191;241;172;229;216;182;176;174;171 10:15:37 INFO - PROCESS | 1651 | |13;stanford-crypto-sha256-iterative;68;67;69;66;71;69;68;68;75;64 10:15:37 INFO - PROCESS | 1651 | __end_tp_report 10:15:37 INFO - PROCESS | 1651 | __start_cc_report 10:15:37 INFO - PROCESS | 1651 | _x_x_mozilla_cycle_collect,914 10:15:37 INFO - PROCESS | 1651 | __end_cc_report 10:15:37 INFO - PROCESS | 1651 | __startTimestamp1484331337417__endTimestamp 10:15:37 INFO - PROCESS | 1651 | 10:15:37 INFO - PROCESS | 1651 | ------- Summary: start ------- 10:15:37 INFO - PROCESS | 1651 | Number of tests: 14 10:15:37 INFO - PROCESS | 1651 | 10:15:37 INFO - PROCESS | 1651 | [#0] ai-astar Cycles:10 Average:126.30 Median:119.00 stddev:18.51 (15.6%) stddev-sans-first:18.89 10:15:37 INFO - PROCESS | 1651 | Values: 112.0 174.0 119.0 126.0 119.0 119.0 119.0 117.0 117.0 141.0 10:15:37 INFO - PROCESS | 1651 | 10:15:37 INFO - PROCESS | 1651 | [#1] audio-beat-detection Cycles:10 Average:142.90 Median:144.50 stddev:20.06 (13.9%) stddev-sans-first:16.18 10:15:37 INFO - PROCESS | 1651 | Values: 180.0 144.0 116.0 148.0 114.0 141.0 143.0 132.0 166.0 145.0 10:15:37 INFO - PROCESS | 1651 | 10:15:37 INFO - PROCESS | 1651 | [#2] audio-dft Cycles:10 Average:212.40 Median:198.50 stddev:33.25 (16.8%) stddev-sans-first:35.13 10:15:37 INFO - PROCESS | 1651 | Values: 204.0 264.0 285.0 196.0 195.0 200.0 197.0 193.0 196.0 194.0 10:15:37 INFO - PROCESS | 1651 | 10:15:37 INFO - PROCESS | 1651 | [#3] audio-fft Cycles:10 Average:68.50 Median:63.00 stddev:14.49 (23.0%) stddev-sans-first:14.76 10:15:37 INFO - PROCESS | 1651 | Values: 80.0 58.0 60.0 97.0 63.0 61.0 61.0 63.0 88.0 54.0 10:15:37 INFO - PROCESS | 1651 | 10:15:37 INFO - PROCESS | 1651 | [#4] audio-oscillator Cycles:10 Average:83.70 Median:82.00 stddev:11.39 (13.9%) stddev-sans-first:11.85 10:15:37 INFO - PROCESS | 1651 | Values: 90.0 72.0 83.0 101.0 102.0 83.0 81.0 70.0 71.0 84.0 10:15:37 INFO - PROCESS | 1651 | 10:15:37 INFO - PROCESS | 1651 | [#5] imaging-gaussian-blur Cycles:10 Average:98.80 Median:88.00 stddev:14.21 (16.1%) stddev-sans-first:15.03 10:15:37 INFO - PROCESS | 1651 | Values: 102.0 133.0 89.0 101.0 99.0 109.0 93.0 86.0 89.0 87.0 10:15:37 INFO - PROCESS | 1651 | 10:15:37 INFO - PROCESS | 1651 | [#6] imaging-darkroom Cycles:10 Average:116.00 Median:125.50 stddev:23.90 (19.0%) stddev-sans-first:24.34 10:15:37 INFO - PROCESS | 1651 | Values: 97.0 96.0 130.0 155.0 106.0 98.0 97.0 100.0 126.0 155.0 10:15:37 INFO - PROCESS | 1651 | 10:15:37 INFO - PROCESS | 1651 | [#7] imaging-desaturate Cycles:10 Average:99.00 Median:93.00 stddev:10.99 (11.8%) stddev-sans-first:11.45 10:15:37 INFO - PROCESS | 1651 | Values: 93.0 96.0 91.0 94.0 94.0 107.0 126.0 91.0 105.0 93.0 10:15:37 INFO - PROCESS | 1651 | 10:15:37 INFO - PROCESS | 1651 | [#8] json-parse-financial Cycles:10 Average:61.80 Median:62.00 stddev:1.14 (1.8%) stddev-sans-first:1.20 10:15:37 INFO - PROCESS | 1651 | Values: 62.0 62.0 60.0 63.0 62.0 61.0 64.0 61.0 61.0 62.0 10:15:37 INFO - PROCESS | 1651 | 10:15:37 INFO - PROCESS | 1651 | [#9] json-stringify-tinderbox Cycles:10 Average:81.00 Median:72.00 stddev:15.07 (20.9%) stddev-sans-first:15.63 10:15:37 INFO - PROCESS | 1651 | Values: 72.0 73.0 69.0 89.0 111.0 102.0 83.0 70.0 69.0 72.0 10:15:37 INFO - PROCESS | 1651 | 10:15:37 INFO - PROCESS | 1651 | [#10] stanford-crypto-aes Cycles:10 Average:96.40 Median:90.50 stddev:10.04 (11.1%) stddev-sans-first:10.52 10:15:37 INFO - PROCESS | 1651 | Values: 92.0 80.0 100.0 106.0 109.0 112.0 91.0 91.0 90.0 93.0 10:15:37 INFO - PROCESS | 1651 | 10:15:37 INFO - PROCESS | 1651 | [#11] stanford-crypto-ccm Cycles:10 Average:160.10 Median:159.00 stddev:15.66 (9.8%) stddev-sans-first:16.18 10:15:37 INFO - PROCESS | 1651 | Values: 150.0 158.0 151.0 152.0 148.0 160.0 151.0 164.0 201.0 166.0 10:15:37 INFO - PROCESS | 1651 | 10:15:37 INFO - PROCESS | 1651 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:192.50 Median:186.50 stddev:26.30 (14.1%) stddev-sans-first:26.94 10:15:37 INFO - PROCESS | 1651 | Values: 173.0 191.0 241.0 172.0 229.0 216.0 182.0 176.0 174.0 171.0 10:15:37 INFO - PROCESS | 1651 | 10:15:37 INFO - PROCESS | 1651 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.50 Median:68.50 stddev:2.95 (4.3%) stddev-sans-first:3.13 10:15:37 INFO - PROCESS | 1651 | Values: 68.0 67.0 69.0 66.0 71.0 69.0 68.0 68.0 75.0 64.0 10:15:37 INFO - PROCESS | 1651 | -------- Summary: end -------- 10:15:37 INFO - PROCESS | 1651 | 10:15:38 INFO - TEST-INFO | 1651: exit 0 10:15:38 INFO - TEST-OK | kraken | took 61837ms 10:15:38 INFO - SUITE-END | took 1177s 10:15:38 INFO - Completed test suite (00:19:37) 10:15:38 INFO - javascript benchmark 10:15:38 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7265.959925933152, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3339.6660333966593, 3344.548485954396, 3380.5267262583243, 3389.322135572886, 3391.0, 5298.178782288742, 5472.72636368181, 5496.422875598059, 5500.312460942382, 5527.585431092664, 5493.176023596463, 5524.309461317335, 5540.279763630732, 5617.494425501704, 5665.49010589048, 4342.067779837973, 4345.261305578059, 4809.182438985364, 5060.772265248054, 5203.843884683465, 4477.731336119843, 4736.123817093831, 5034.949650503485, 5228.843134705946, 5233.450487698794, 4999.250112483121, 5168.664036837631, 5183.974080129613, 5206.375234971809, 5239.318888544503, 5037.848864534069, 5062.898742025139, 5095.8980820383385, 5111.514406131449, 5112.821051263225, 5060.797568097271, 5129.307543481619, 5135.075686376451, 5137.409197942247, 5143.922841157386, 5384.246205531229, 5546.972265138689, 5590.720463976785, 5614.691191984439, 5628.971855140698, 6030.728617212236, 6075.9696201519155, 6179.845503862394, 6184.628922264634, 6205.2553693557065, 5118.564921981636, 6135.04893764628, 6180.721867515973, 6201.751929922798, 6207.255129384503, 5791.942080579182, 5793.507551858139, 5799.82600521985, 5821.708914554255, 5822.359540450547, 6114.449699527064, 6130.724117414727, 6181.660008699565, 6197.752089916443, 6204.875902482016, 5794.652320860761, 5863.941360586339, 5881.705914704247, 5897.469227769521, 5935.317438494542, 6087.117367981707, 6104.4591554472245, 6104.847378815565, 6117.724702388403, 6162.322144564095, 5017.322661440732, 5962.970185149134, 6067.089936509559, 6108.615274519966, 6138.570300078952, 6099.9085013724825, 6129.948138092848, 6130.662813545208, 6161.106639537332, 6173.382661733881, 4884.7313397762755, 4885.511448855157, 4905.558499735041, 4916.9428572856295, 4927.063857866994, 3760.793156376371, 4239.830406783756, 4346.1525002624185, 4346.6522678185665, 4360.629346505582, 5691.630044047124, 5696.8006119785605, 5704.458076482728, 5717.685527296038, 5734.426557344233, 6193.318734939153, 6214.906776398358, 6219.720112594944, 6233.065040244, 6257.342978987232, 5568.972155139199, 5602.411746766612, 5626.43735626434, 5675.687837168995, 5687.431256874363, 5311.840644780663, 5316.229146773773, 5354.277172581729, 5387.595930305243, 5432.782688692487, 6171.889059969249, 6213.471854892385, 6218.098375735402, 6223.408776166257, 6256.5620406572925, 6128.387161283836, 6193.752249909953, 6214.067889816563, 6218.160548325918, 6219.0982307566055, 5254.632175747814, 6144.941444616104, 6160.507159427146, 6172.814815555541, 6191.318954915046, 4656.790444430077, 5875.41245875409, 6121.47967422774, 6135.785247516315, 6145.201123853871, 6180.443760061616, 6200.627962322275, 6205.782797602063, 6205.968970155121, 6234.15838861766, 5873.588848780544, 5975.43233392827, 5979.671118088547, 5989.760409583568, 6002.579819412599, 6010.759569617167, 6020.9764340063175, 6036.758529658764, 6068.726907289094, 6091.817245482643, 4786.832460863853, 4879.0, 5760.510356619734, 5768.221290125779, 5888.411158884249, 5899.734511946886, 5999.340072592098, 6000.189974353407, 6012.6392416455155, 6071.332153463204, 5738.081906895044, 5861.1501332307425, 5922.585419020799, 5978.8206353809455, 5992.340842507234], "value": 5559.218701892983, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [454.7294359855886, 508.433097096737, 519.7011718262, 530.2523441946855, 537.8628449745314, 40091.79724608263, 42388.304467821254, 42624.78687606558, 44021.7798911005, 44203.0, 41777.0, 42219.15561688764, 42231.0, 42301.36547951783, 42374.7881260594, 8972.910270897306, 9266.953665231682, 9467.147956683897, 9493.81012379752, 9507.382020168701, 10880.891191088067, 11104.944475277613, 11117.888821111766, 11125.109991200725, 11129.554817807279, 11101.278416902916, 11156.107511399068, 11156.330620162775, 11163.944180279088, 11184.161187910899, 11079.72300692481, 11135.554577816878, 11140.498677559448, 11152.16358773087, 11159.27464714791, 11065.723356916142, 11073.612423565217, 11098.0, 11149.498272577754, 11152.275102118418, 37209.44185837215, 37298.254034919424, 37439.62560374389, 37464.812675936715, 37470.06324841874, 3992.8602498912687, 4018.457508236409, 4026.879193624196, 4028.8388464461386, 4032.697547683935, 28406.721697523455, 28955.56566651502, 29385.70614293851, 29468.26329341762, 29490.852545737132, 3140.1364624728153, 3163.604549431321, 3181.1569933967517, 3222.6616205298574, 3258.348330333919, 474684.0, 477565.0, 479270.0, 480514.9999999965, 480945.0, 45306.546934530896, 56414.307570772944, 56836.863262734514, 57321.0, 57321.0, 48441.0, 49451.50548494541, 57834.0, 58535.0, 58652.41347586555, 12781.872181278255, 15480.922595387176, 15566.533004009898, 15590.0, 15617.219139043229, 10912.399818010086, 11140.053095486812, 11424.600138995096, 11927.940360298317, 12120.575779847839, 15606.9219653901, 16514.42199523011, 17265.82734172642, 18126.27494900189, 18210.817891821178, 6554.344565543408, 6973.4072603829245, 7845.293923546794, 7873.795337305563, 7934.722284720007, 811.2333844516875, 881.1761003461784, 890.7995700967231, 900.4057322167469, 909.2226146644656, 23748.05007799703, 28865.711342886723, 30540.389192216033, 30570.847145764128, 31045.68954310428, 961.8845738511424, 993.7528401855769, 1047.115187666436, 1071.9196060295353, 1073.9355638661705, 12407.627771166879, 14373.568792936228, 14884.553463396116, 15183.848161518465, 15254.542363729106, 629324.0, 695826.0, 711943.0, 712495.0, 914639.0, 55122.72438637861, 55782.44217557854, 55808.441915581134, 55854.44145558492, 55897.720511397994, 1422.8292604887272, 1436.3105709259405, 1451.8112645356036, 1452.7094581083825, 1464.5313499680212, 29235.122946311643, 29323.560146597392, 29328.56007159937, 29676.851615741783, 29954.25114372158, 47852.52147478481, 48758.0, 48898.02203955972, 48902.0219595599, 49007.509924900296, 572448.0, 576400.0, 577177.0, 578377.0, 579628.0, 25225.495490090463, 25889.482210355312, 26030.828612712852, 26049.869750651884, 27049.45901082007, 37080.0, 43745.78127109344, 43852.780736096116, 44925.55074449214, 46272.0, 36548.81725591355, 36721.42949993475, 36963.0, 37390.0, 37660.0, 36314.4552831713, 36878.8156059218, 37511.81244093762, 37633.2473350537, 37908.43137352997, 34491.4826277601, 34551.82724086363, 35098.47352289667, 41564.79217603893, 42506.93732656708, 39566.802165988986, 41765.0, 41860.79069604632, 42840.57159428366, 43160.92097697582, 32160.35679286448, 32990.5051424224, 33050.33899322048, 33053.504197436574, 33414.83292583522], "value": 20124.82001779498, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [377.1250698379759, 388.8891665875224, 390.81045692838984, 400.92783299006175, 423.3776348767311, 20456.69314960277, 21803.0, 21809.781902180974, 22021.779782202215, 22254.554908901813, 21636.783632163675, 21773.693578385308, 22423.887880560575, 22427.215047473343, 22609.982550785215, 5474.835754927359, 5844.912326315109, 5986.802639472101, 6152.938470615304, 6176.69116544171, 5823.242978412801, 6040.0, 6044.727987240584, 6108.083787431877, 6162.1681073055015, 6066.454019138277, 6076.756929722806, 6077.179580756608, 6094.634321940675, 6099.451049405553, 5833.766649334021, 6024.457798798129, 6050.42520960508, 6064.211652485193, 6072.089186622042, 5285.418603953562, 5322.467753224685, 5323.30796996388, 5324.174752913266, 5325.866853328659, 13660.248686322248, 17083.487495375033, 18980.335688250983, 20894.79105208959, 23897.402564936016, 2919.474494590973, 2950.542665886791, 3022.319978004953, 3022.4106299271652, 3076.138681169276, 8875.778105547348, 10245.89754102457, 10338.948305258426, 12319.322437265946, 14954.775678364833, 1797.074506629087, 1812.1211212561898, 1825.0783354406074, 1828.780546334435, 1848.5286252005653, 100419.0, 133284.0, 137187.31406343004, 137812.0, 138519.0, 10644.0, 11444.94277528607, 11902.583409580624, 12105.455254513568, 12233.510659573518, 15643.530694079196, 15722.0, 15778.13220272873, 15825.366985320688, 15835.445759398366, 8643.870341944876, 8909.465432073966, 8925.509096999598, 8992.55037248135, 8996.730098096936, 8585.399022068521, 8665.2634526066, 8684.305255579664, 8811.603477843513, 8857.335699822539, 4777.641676874248, 4786.0667169902235, 4857.684250523705, 4918.581920536794, 4920.507949205123, 1984.382728076613, 2028.4421784009514, 2038.225474319749, 2038.3477287268229, 2044.0801639262095, 824.5052968219021, 840.8696652018947, 846.1242613894713, 864.7751584587927, 877.9429337093069, 17257.10171881092, 21885.67171492429, 22983.42541436444, 23115.422114447272, 23153.0, 885.3890815337396, 891.4517571693361, 910.9908900911039, 911.4531281231341, 917.5228880981851, 9369.250459963187, 11865.037062746327, 11963.64109076729, 11965.46155423008, 11989.460474278678, 673742.0, 686163.0, 702214.0, 703308.0, 716755.0, 149731.50268497175, 152918.23540882225, 152983.23508382388, 164748.3525164757, 167150.0, 1003.6236411345745, 1019.7195771162841, 1059.046857827961, 1060.2207377577474, 1061.2253055269696, 20342.79657203409, 21441.785582144603, 21534.492585518863, 21560.784392155878, 21569.0, 61991.07013394713, 62688.37311626825, 63110.99999999817, 63291.36708633039, 63481.682591586745, 91906.0, 92957.5352123235, 94206.28215190861, 94588.58117128111, 95580.0, 8420.65269556868, 8534.658613655633, 8598.39811213209, 8605.612747426256, 8677.1756683115, 9332.673356432493, 12940.352982351033, 13332.200067995951, 13704.314784260947, 13782.173069615856, 11475.311481310815, 11555.01782348476, 11755.353455560027, 11901.82147267809, 11909.166358354832, 11474.77050459003, 11543.5959741412, 11560.190786644855, 11886.583969561023, 11923.046156307648, 11677.708057298296, 11708.238964467282, 11864.347460889734, 11997.0, 12001.63995080149, 8444.915550844413, 8558.957205213934, 8570.0, 8667.263282621048, 8673.609687563949, 9806.901930980599, 11129.944350278522, 11493.942530287295, 11525.0, 11764.705882353106], "value": 10072.794714781436, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1467.1050659097955, 1475.0338528263992, 1478.0540454109366, 1492.8357880633132, 1512.085188460981, 7549.622518874048, 7794.532328060306, 7854.410919181056, 8287.171282871717, 8287.254147126758, 7803.804904877381, 8037.7588672339925, 8048.0, 8165.795855103625, 8173.632186551605, 2283.075354481438, 2337.333859849943, 2338.2166974063707, 2338.8362814602983, 2342.145117032288, 2080.583883223354, 2326.232343326698, 2343.3907184132077, 2356.304890057437, 2360.905563777447, 1963.234338607944, 2207.6578130389735, 2316.9884150579223, 2320.9535809283802, 2339.087755775249, 2197.8681279123302, 2295.6671282664006, 2323.186884590397, 2324.244620498328, 2325.197806756666, 2062.7937206279403, 2295.162265772981, 2330.860148391085, 2336.5910965580956, 2339.6607491913746, 8168.305694016016, 8195.385346099007, 8210.794730131794, 8245.711400101027, 8253.009638843318, 5034.421041580192, 5167.328247327823, 5173.896522069575, 5174.7671354788745, 5194.740262986873, 2863.957040644392, 3216.404965081444, 3293.275479394529, 3463.341965026662, 3473.513708080871, 1207.1851500237306, 1226.4297101847594, 1701.1749301588843, 1729.3082766893217, 1811.1759149586906, 10616.787664246673, 10709.303895246861, 10765.600471938755, 10765.677029689123, 10813.297135686154, 4530.365748795171, 4708.293755936637, 4710.222813235846, 4712.104700107037, 4772.76136193189, 8629.568521573896, 8684.6526138955, 8686.131386861389, 8738.300935925237, 8749.518776467356, 5144.151215049475, 5149.0, 5155.32980712505, 5161.251618515369, 5169.689818610896, 4627.814887404534, 4735.408073990751, 4811.398575178103, 4825.758712064383, 4836.250381190921, 3532.947005794915, 3660.048387419237, 3668.5414323209598, 3684.3552378333684, 3697.5193224880595, 1131.6548452722, 1143.662619527225, 1149.7757937202332, 1150.5685367987005, 1155.0932517973351, 919.9218066464425, 932.3520153493257, 944.9699827188398, 946.9005754395827, 991.4398364923795, 5512.145617429305, 5598.10430331145, 5638.915416268759, 5722.427757224327, 5814.622049566765, 563.1805722673582, 576.1357963055417, 580.8402689260487, 583.5993615323258, 594.6105301027834, 3371.544841446422, 3531.081918701156, 3715.405535114423, 3810.0665336992615, 3842.865499707497, 7809.80475488106, 9756.707298781048, 9878.654247101316, 9879.851802222973, 9946.552405141785, 9614.0, 9733.2213422926, 9771.413715177112, 9801.166900813369, 9823.11591956713, 7646.0, 7738.187490313549, 7780.922190778133, 7838.490498117604, 7844.294013538809, 5451.536619387396, 5620.353659329229, 5621.381648018796, 5641.717914104196, 5795.82612521625, 9109.362344635812, 9176.128267814547, 9191.310651701282, 9195.98844127159, 9217.493037882712, 8291.875621865798, 8553.230209281195, 8682.826343473222, 9045.954770226106, 9067.727968160965, 5903.409659034062, 6820.35206655367, 6988.825279368057, 7034.0, 7050.118735158106, 6570.5729127606555, 7713.305802477804, 7904.170062143392, 7932.960335198287, 7986.720464783704, 7090.893636595352, 7092.361687448154, 7117.110361204849, 7138.650723147747, 7255.52839065459, 6930.5148639594745, 6952.443804495527, 6961.686724097326, 7076.858462830817, 7104.11198600175, 5169.81905633301, 6339.619622822645, 6416.679166041773, 6442.549021568445, 6595.571287866273, 3893.8972954604196, 4918.581920536794, 4960.8759781005765, 5020.623453240948, 5124.7950081996305, 4929.556339929423, 4998.0, 5087.87280317995, 5118.053160165381, 5129.384473863161], "value": 4326.511851027833, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [310.3389779769094, 359.98560057597695, 361.41450849623607, 366.62054773309615, 379.0996383588976, 4196.475440569929, 5225.86935326617, 5301.8409447716585, 5436.945630543693, 5460.126379779231, 4385.894770510157, 4390.407295015172, 5230.6600070995355, 5335.439778823216, 5346.786128554862, 3468.618451970281, 4201.642860356866, 4210.663146948237, 4221.746695198298, 4226.3026600610865, 3392.796432214075, 4130.987907962538, 4231.555686652903, 4248.915021699564, 4253.0, 3299.4720844664857, 3960.188161426915, 4007.5792041835625, 4009.398590211477, 4053.351463765798, 3981.741186822862, 4009.458723072377, 4009.939850902239, 4022.8189731462153, 4033.132876431552, 3057.113437103237, 3199.984000080008, 3229.7093261606333, 3522.8198553484613, 3543.8405271762576, 4660.5106463821485, 4845.709257444564, 4916.680415772999, 5234.842954711365, 5290.735463226823, 3910.687145028391, 4628.930566041512, 4678.508756580578, 4686.953130468686, 4716.740579268139, 4834.033193361342, 4987.329244703029, 5664.348599910981, 5693.572982026365, 5766.596338256324, 3387.8814241501427, 3480.843362048714, 3553.662402071799, 3756.868509602178, 3759.3797023491093, 4946.35697359341, 5456.208849716769, 5947.840171166624, 5959.165716799652, 5963.078091735062, 3314.386838434897, 3927.273454410943, 3943.3099207638547, 3946.3291240489184, 3961.26716557432, 3803.67668748154, 3995.900102497461, 4004.7797371144284, 4007.8196481158416, 4017.738846974937, 4274.935875961863, 4587.747673877969, 4634.335257227108, 4666.696664716783, 4687.507811679793, 5497.367802702661, 5796.82609521715, 5797.420257974253, 5822.796202132905, 5827.533797296206, 1148.8104462763717, 1216.4829947272374, 1234.3581337704343, 1248.3196657821509, 1284.5696691608416, 824.5959479854828, 879.2833840420037, 884.9867251991225, 892.9419587726907, 895.8790563274005, 4526.954730452653, 4614.877156507977, 4679.9298010529865, 4753.053107073567, 5077.8320986173385, 4739.246478802865, 5252.868678282997, 5338.546223570962, 5406.729663516809, 5655.897100065447, 5104.872378190575, 5139.0, 5141.871453213699, 5154.329937108152, 5156.845294641167, 2623.8819253133656, 2641.260447074822, 2929.169269206746, 2939.8824047038306, 2957.452871218831, 4905.9754701226975, 5058.974705126451, 5428.805662754187, 5827.7668893244645, 5847.684271039067, 5733.627314224562, 5744.339400968858, 5785.739641716133, 5788.710564471759, 5820.330661973842, 5684.431556844365, 5744.221818254333, 5794.217780599565, 5823.0, 5842.795502157489, 4593.0, 4605.516420775904, 4654.0, 4851.927221091616, 5526.849695673428, 4372.781360931877, 4614.923284522046, 5287.017368486818, 5413.675179489243, 5586.692731899784, 4775.785089670903, 5291.0, 5363.249145119765, 5384.865378365572, 5872.236609240771, 3426.9657303426648, 3628.32875917956, 3684.5025921500255, 3693.9261214776093, 3714.5542534895985, 4527.709602763197, 4742.668537502473, 4833.806647734052, 4836.0, 5598.664080155204, 4471.262241730208, 4838.153323168502, 4882.438519570295, 5228.6405534562045, 5432.6468779529205, 4655.533506945437, 5189.532942035084, 5316.6012549058205, 5338.279332290246, 5410.648307859977, 4424.823007079681, 4545.250033744527, 4847.13385346637, 5008.749562521933, 5366.785328586813, 5651.067573850269, 5711.343195532567, 5725.770969161187, 5734.645232539011, 5779.104238842986, 4921.434035086011, 5026.617680137992, 5104.259882317117, 5115.590752739847, 5576.693281869536], "value": 3994.5031208079395, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [707.8761216787061, 718.7951433841356, 751.7594369801664, 775.1783109903503, 859.1923591823687, 12190.573329933453, 15545.300461479232, 15759.763603545956, 15786.447474338414, 15805.446809361674, 14031.78952315716, 14536.691697747201, 14659.633509162277, 14671.193084380355, 14679.853201467982, 854.5470900422755, 870.8867847179857, 872.2978002707817, 875.5840975536632, 875.6672464463527, 693.8820400531896, 716.4304378019488, 841.6465084664455, 862.3877047296396, 875.5797217335682, 916.5096673279796, 924.5608336040394, 924.8057907839362, 925.3476299209075, 925.5927391947522, 918.76112210825, 920.4523308631387, 920.4661296448111, 920.9493477858715, 921.1893533690377, 673.0308355967392, 687.0655907965163, 687.1856849633201, 687.6183718036488, 689.5965859971923, 14973.251337433085, 15265.465708700252, 15329.233538323151, 15364.923175384163, 15410.383584656713, 14120.788188177186, 14310.49913253031, 14399.640008999753, 14400.135991840523, 14479.855201447956, 11815.586454474053, 11841.171118021743, 11903.583374581847, 11957.0, 11975.389371061709, 3678.558572971234, 3704.0554658562164, 3715.498407714951, 3718.4608231806506, 3859.2281543691374, 114154.0, 115129.0, 115540.0, 115740.84259157469, 117318.0, 8397.70608028716, 9778.951105244429, 10002.749931251632, 10014.148797352162, 10127.59489620407, 22552.21067262638, 22788.658170127463, 22799.088036478355, 22812.20157294487, 22812.885935570546, 7555.0, 7579.431542634325, 7606.467547271748, 7620.5808680522, 7673.808154796063, 4262.893427664333, 4355.651547876162, 4368.36658684489, 4467.776611169428, 4522.140793249338, 3578.499010138583, 3636.090977255686, 3669.192777588926, 3675.5956844747056, 3676.0809797550614, 515.3197778931849, 633.4710516718592, 662.4733336997099, 668.9933100669028, 680.3502654964497, 669.6450881033061, 686.8385929306609, 695.6799872058808, 700.425650966213, 736.6684991753732, 10027.649032283982, 10271.794564108675, 10313.0, 10512.632057877938, 10748.408837514013, 666.986660266792, 691.1464341538197, 692.2765709833236, 696.474162007681, 702.7715992302523, 4935.531124543163, 5081.72050537224, 5086.92369614456, 5089.160288552422, 5090.847274581768, 84082.0, 105553.0, 106365.0, 107033.0, 110554.0, 87478.0, 89332.5533372329, 89428.6585701215, 89697.0, 92462.07537924535, 60000.09999850006, 77718.0, 79545.20454795494, 79877.0, 80727.19272807315, 7542.962285188539, 8047.436679432383, 8542.615582298688, 9207.125323094295, 9224.723258302261, 49275.753621233096, 51175.99999999851, 51193.48806511887, 51213.74393128011, 53933.0, 68215.65892170508, 68480.31519684939, 71146.64426678041, 71195.64402177956, 73449.0, 12922.870771292542, 12957.497341720848, 13110.525710686867, 13434.0, 13804.0, 10854.67435976922, 14480.039920159681, 14870.925645371704, 14996.775048374066, 15315.693686126438, 13857.653558661113, 14034.461739737946, 14042.859571404155, 14505.492307769178, 14545.200013999332, 13695.178289302674, 13874.445022199, 13969.860301396857, 14060.08609440383, 14512.492062777752, 13655.0, 13676.589702308947, 13713.931430342784, 13727.0, 14124.646883827987, 10311.329763565343, 10966.17753668494, 11391.316521008766, 11667.708307292385, 11869.821952670543, 8529.95735021321, 8663.523506207219, 11222.943885280521, 11284.548618055185, 11423.771524569629], "value": 7554.588488382581, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1607.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 174.0, 119.0, 126.0, 119.0, 119.0, 119.0, 117.0, 117.0, 141.0], "value": 126.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 144.0, 116.0, 148.0, 114.0, 141.0, 143.0, 132.0, 166.0, 145.0], "value": 142.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [204.0, 264.0, 285.0, 196.0, 195.0, 200.0, 197.0, 193.0, 196.0, 194.0], "value": 212.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 58.0, 60.0, 97.0, 63.0, 61.0, 61.0, 63.0, 88.0, 54.0], "value": 68.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 72.0, 83.0, 101.0, 102.0, 83.0, 81.0, 70.0, 71.0, 84.0], "value": 83.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 133.0, 89.0, 101.0, 99.0, 109.0, 93.0, 86.0, 89.0, 87.0], "value": 98.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 96.0, 130.0, 155.0, 106.0, 98.0, 97.0, 100.0, 126.0, 155.0], "value": 116.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 96.0, 91.0, 94.0, 94.0, 107.0, 126.0, 91.0, 105.0, 93.0], "value": 99.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 60.0, 63.0, 62.0, 61.0, 64.0, 61.0, 61.0, 62.0], "value": 61.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 73.0, 69.0, 89.0, 111.0, 102.0, 83.0, 70.0, 69.0, 72.0], "value": 81.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 80.0, 100.0, 106.0, 109.0, 112.0, 91.0, 91.0, 90.0, 93.0], "value": 96.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 158.0, 151.0, 152.0, 148.0, 160.0, 151.0, 164.0, 201.0, 166.0], "value": 160.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 191.0, 241.0, 172.0, 229.0, 216.0, 182.0, 176.0, 174.0, 171.0], "value": 192.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 69.0, 66.0, 71.0, 69.0, 68.0, 68.0, 75.0, 64.0], "value": 68.5, "unit": "score"}]}]} 10:15:38 INFO - Return code: 0 10:15:38 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:15:38 ERROR - # TBPL SUCCESS # 10:15:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:15:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:15:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:15:38 INFO - 2017-01-13 10:15:38.773 screenresolution[1656:10522] starting screenresolution argv=screenresolution get 10:15:38 INFO - 2017-01-13 10:15:38.776 screenresolution[1656:10522] Display 0: 1600x1200x32@60 10:15:38 INFO - 2017-01-13 10:15:38.782 screenresolution[1657:10525] starting screenresolution argv=screenresolution list 10:15:38 INFO - Available Modes on Display 0 10:15:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:15:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:15:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:15:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:15:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:15:39 INFO - Intel Iris: 10:15:39 INFO - Chipset Model: Intel Iris 10:15:39 INFO - Type: GPU 10:15:39 INFO - Bus: Built-In 10:15:39 INFO - VRAM (Dynamic, Max): 1536 MB 10:15:39 INFO - Vendor: Intel (0x8086) 10:15:39 INFO - Device ID: 0x0a2e 10:15:39 INFO - Revision ID: 0x0009 10:15:39 INFO - Displays: 10:15:39 INFO - Display: 10:15:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:15:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:15:39 INFO - Display Serial Number: 1600x1200 60 10:15:39 INFO - Main Display: Yes 10:15:39 INFO - Mirror: Off 10:15:39 INFO - Online: Yes 10:15:39 INFO - Rotation: Supported 10:15:39 INFO - Return code: 0 10:15:39 INFO - Running post-action listener: _resource_record_post_action 10:15:39 INFO - [mozharness: 2017-01-13 18:15:39.026018Z] Finished run-tests step (success) 10:15:39 INFO - Running post-run listener: _resource_record_post_run 10:15:39 INFO - Total resource usage - Wall time: 1200s; CPU: 54.0%; Read bytes: 39909888; Write bytes: 553846272; Read time: 383; Write time: 1654 10:15:39 INFO - TinderboxPrint: CPU usage
54.4% 10:15:39 INFO - TinderboxPrint: I/O read bytes / time
39,909,888 / 383 10:15:39 INFO - TinderboxPrint: I/O write bytes / time
553,846,272 / 1,654 10:15:39 INFO - TinderboxPrint: CPU idle
2,189.6 (45.6%) 10:15:39 INFO - TinderboxPrint: CPU system
965.8 (20.1%) 10:15:39 INFO - TinderboxPrint: CPU user
1,646.4 (34.3%) 10:15:39 INFO - TinderboxPrint: Swap in / out
291,459,072 / 0 10:15:39 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 248046080; Write bytes: 240308224; Read time: 17875; Write time: 438 10:15:39 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 35289600; Write bytes: 299996672; Read time: 351; Write time: 1180 10:15:39 INFO - Running post-run listener: _upload_blobber_files 10:15:39 INFO - Blob upload gear active. 10:15:39 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:15:39 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 10:15:39 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'] 10:15:39 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 10:15:39 INFO - (blobuploader) - INFO - Open directory for files ... 10:15:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 10:15:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:15:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:15:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 10:15:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:15:41 INFO - (blobuploader) - INFO - Done attempting. 10:15:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 10:15:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:15:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:15:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 10:15:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:15:42 INFO - (blobuploader) - INFO - Done attempting. 10:15:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 10:15:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:15:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:15:43 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 10:15:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:15:43 INFO - (blobuploader) - INFO - Done attempting. 10:15:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:15:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:15:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:15:45 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:15:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:15:45 INFO - (blobuploader) - INFO - Done attempting. 10:15:45 INFO - (blobuploader) - INFO - Iteration through files over. 10:15:45 INFO - Return code: 0 10:15:45 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:15:45 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:15:45 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/ed6008fa0b11998b9b4e36c886451a5a6addfda6efba825085505532615c5151deca32fc6bbfb75f769566106528bed04b38f418da6f84217a03a765f350f014", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/5dfbc14dd49dbccefb764283d5e2a4c6e869cc88e3bb7bb697e98c8910b05037d6b4c0d21535de2bedba46c95cc9ee449cf772993da257809d87a5c33610d68e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/59abf3730bc7a1731801b0967657683f45c6b9292fcb1a0f4b524a6ba1976d6f11b9d419dfdaeb0e91f94223ec80fc42873c15442d336a6d27db737efca71e83", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/141d51fd1168864b90ae8c70c0d3b8de5b598f7e56cb1104a7a55578d8ef69c86acc363732dd6aa48d9796816e5793582661cbae79fa92e9626a13be4c955ea5"} 10:15:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:15:45 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:15:45 INFO - Contents: 10:15:45 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/ed6008fa0b11998b9b4e36c886451a5a6addfda6efba825085505532615c5151deca32fc6bbfb75f769566106528bed04b38f418da6f84217a03a765f350f014", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/5dfbc14dd49dbccefb764283d5e2a4c6e869cc88e3bb7bb697e98c8910b05037d6b4c0d21535de2bedba46c95cc9ee449cf772993da257809d87a5c33610d68e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/59abf3730bc7a1731801b0967657683f45c6b9292fcb1a0f4b524a6ba1976d6f11b9d419dfdaeb0e91f94223ec80fc42873c15442d336a6d27db737efca71e83", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/141d51fd1168864b90ae8c70c0d3b8de5b598f7e56cb1104a7a55578d8ef69c86acc363732dd6aa48d9796816e5793582661cbae79fa92e9626a13be4c955ea5"} 10:15:45 INFO - Running post-run listener: copy_logs_to_upload_dir 10:15:45 INFO - Copying logs to upload dir... 10:15:45 INFO - mkdir: /builds/slave/test/build/upload/logs 10:15:45 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1261.917261 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2017-01-13 10:15:45.543982) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-13 10:15:45.547957) ========= 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.4PscngIU9B/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.vbJm8ddEIR/Listeners TMPDIR=/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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/ed6008fa0b11998b9b4e36c886451a5a6addfda6efba825085505532615c5151deca32fc6bbfb75f769566106528bed04b38f418da6f84217a03a765f350f014", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/5dfbc14dd49dbccefb764283d5e2a4c6e869cc88e3bb7bb697e98c8910b05037d6b4c0d21535de2bedba46c95cc9ee449cf772993da257809d87a5c33610d68e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/59abf3730bc7a1731801b0967657683f45c6b9292fcb1a0f4b524a6ba1976d6f11b9d419dfdaeb0e91f94223ec80fc42873c15442d336a6d27db737efca71e83", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/141d51fd1168864b90ae8c70c0d3b8de5b598f7e56cb1104a7a55578d8ef69c86acc363732dd6aa48d9796816e5793582661cbae79fa92e9626a13be4c955ea5"} build_url:https://queue.taskcluster.net/v1/task/PhPiWANYQZCgRzkfvA2fxg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011532 build_url: 'https://queue.taskcluster.net/v1/task/PhPiWANYQZCgRzkfvA2fxg/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/ed6008fa0b11998b9b4e36c886451a5a6addfda6efba825085505532615c5151deca32fc6bbfb75f769566106528bed04b38f418da6f84217a03a765f350f014", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/5dfbc14dd49dbccefb764283d5e2a4c6e869cc88e3bb7bb697e98c8910b05037d6b4c0d21535de2bedba46c95cc9ee449cf772993da257809d87a5c33610d68e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/59abf3730bc7a1731801b0967657683f45c6b9292fcb1a0f4b524a6ba1976d6f11b9d419dfdaeb0e91f94223ec80fc42873c15442d336a6d27db737efca71e83", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/141d51fd1168864b90ae8c70c0d3b8de5b598f7e56cb1104a7a55578d8ef69c86acc363732dd6aa48d9796816e5793582661cbae79fa92e9626a13be4c955ea5"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-13 10:15:45.582248) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-13 10:15:45.582552) ========= 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.4PscngIU9B/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.vbJm8ddEIR/Listeners TMPDIR=/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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.005265 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-13 10:15:45.630957) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-13 10:15:45.631235) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-13 10:15:45.631531) ========= ========= Total master_lag: 0.19 =========