builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0036 starttime: 1484153038.81 results: success (0) buildid: 20170111072506 builduid: cf2f9cb2156545ebbf47077a24377818 revision: 63ad56438630b389efdb01632e153143cf3008ec ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-11 08:43:58.814693) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-11 08:43:58.815452) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-11 08:43:58.815868) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-11 08:43:58.842189) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-11 08:43:58.842561) ========= 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.CTE536dfEn/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.Gm5BgvehfO/Listeners TMPDIR=/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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-11 08:43:58-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 159M=0s 2017-01-11 08:43:58 (159 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.128197 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-11 08:43:58.994964) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-11 08:43:58.995279) ========= 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.CTE536dfEn/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.Gm5BgvehfO/Listeners TMPDIR=/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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.027725 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-11 08:43:59.044130) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-01-11 08:43:59.044486) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 63ad56438630b389efdb01632e153143cf3008ec --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 63ad56438630b389efdb01632e153143cf3008ec --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.CTE536dfEn/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.Gm5BgvehfO/Listeners TMPDIR=/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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-11 08:43:59,142 truncating revision to first 12 chars 2017-01-11 08:43:59,142 Setting DEBUG logging. 2017-01-11 08:43:59,142 attempt 1/10 2017-01-11 08:43:59,143 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/63ad56438630?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-11 08:44:00,505 unpacking tar archive at: mozilla-central-63ad56438630/testing/mozharness/ program finished with exit code 0 elapsedTime=1.639851 ========= master_lag: 1.83 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-01-11 08:44:02.515419) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-11 08:44:02.515975) ========= script_repo_revision: 63ad56438630b389efdb01632e153143cf3008ec ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-11 08:44:02.516618) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-11 08:44:02.520137) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-11 08:44:02.547972) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2017-01-11 08:44:02.548337) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.CTE536dfEn/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.Gm5BgvehfO/Listeners TMPDIR=/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 08:44:02 INFO - MultiFileLogger online at 20170111 08:44:02 in /builds/slave/test 08:44:02 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 08:44:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:44:02 INFO - {'append_to_log': False, 08:44:02 INFO - 'base_work_dir': '/builds/slave/test', 08:44:02 INFO - 'blob_upload_branch': 'Firefox', 08:44:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:44:02 INFO - 'branch': 'Firefox', 08:44:02 INFO - 'buildbot_json_path': 'buildprops.json', 08:44:02 INFO - 'config_files': ('talos/mac_config.py',), 08:44:02 INFO - 'default_actions': ('clobber', 08:44:02 INFO - 'read-buildbot-config', 08:44:02 INFO - 'download-and-extract', 08:44:02 INFO - 'populate-webroot', 08:44:02 INFO - 'create-virtualenv', 08:44:02 INFO - 'install', 08:44:02 INFO - 'run-tests'), 08:44:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:44:02 INFO - 'download_minidump_stackwalk': True, 08:44:02 INFO - 'download_symbols': 'ondemand', 08:44:02 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:44:02 INFO - 'tooltool.py': '/tools/tooltool.py', 08:44:02 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:44:02 INFO - '/tools/misc-python/virtualenv.py')}, 08:44:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:44:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:44:02 INFO - 'installer_path': 'installer.exe', 08:44:02 INFO - 'log_level': 'info', 08:44:02 INFO - 'log_name': 'talos', 08:44:02 INFO - 'log_to_console': True, 08:44:02 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:44:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:44:02 INFO - 'opt_config_files': (), 08:44:02 INFO - 'pip_index': False, 08:44:02 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:44:02 INFO - 'cmd': ('bash', 08:44:02 INFO - '-c', 08:44:02 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:44:02 INFO - 'enabled': True, 08:44:02 INFO - 'halt_on_failure': False, 08:44:02 INFO - 'name': 'check_screen_resolution'},), 08:44:02 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:44:02 INFO - 'cmd': ('bash', 08:44:02 INFO - '-c', 08:44:02 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:44:02 INFO - 'enabled': True, 08:44:02 INFO - 'halt_on_failure': False, 08:44:02 INFO - 'name': 'check_screen_resolution'},), 08:44:02 INFO - 'run_cmd_checks_enabled': True, 08:44:02 INFO - 'sps_profile': False, 08:44:02 INFO - 'sps_profile_interval': 0, 08:44:02 INFO - 'suite': 'dromaeojs-e10s', 08:44:02 INFO - 'system_bits': '32', 08:44:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:44:02 INFO - 'title': 't-yosemite-r7-0036', 08:44:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:44:02 INFO - 'use_talos_json': True, 08:44:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:44:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:44:02 INFO - 'work_dir': 'build'} 08:44:02 INFO - [mozharness: 2017-01-11 16:44:02.755596Z] Running clobber step. 08:44:02 INFO - Running pre-action listener: _resource_record_pre_action 08:44:02 INFO - Running main action method: clobber 08:44:02 INFO - rmtree: /builds/slave/test/build 08:44:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:44:05 INFO - Running post-action listener: _resource_record_post_action 08:44:05 INFO - [mozharness: 2017-01-11 16:44:05.196718Z] Finished clobber step (success) 08:44:05 INFO - [mozharness: 2017-01-11 16:44:05.196868Z] Running read-buildbot-config step. 08:44:05 INFO - Running pre-action listener: _resource_record_pre_action 08:44:05 INFO - Running main action method: read_buildbot_config 08:44:05 INFO - Using buildbot properties: 08:44:05 INFO - { 08:44:05 INFO - "project": "", 08:44:05 INFO - "product": "firefox", 08:44:05 INFO - "script_repo_revision": "production", 08:44:05 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 08:44:05 INFO - "repository": "", 08:44:05 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 08:44:05 INFO - "buildid": "20170111072506", 08:44:05 INFO - "pgo_build": "False", 08:44:05 INFO - "basedir": "/builds/slave/test", 08:44:05 INFO - "buildnumber": 50, 08:44:05 INFO - "slavename": "t-yosemite-r7-0036", 08:44:05 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 08:44:05 INFO - "platform": "yosemite_r7", 08:44:05 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 08:44:05 INFO - "branch": "mozilla-central", 08:44:05 INFO - "repo_path": "mozilla-central", 08:44:05 INFO - "revision": "63ad56438630b389efdb01632e153143cf3008ec", 08:44:05 INFO - "stage_platform": "macosx64", 08:44:05 INFO - "builduid": "cf2f9cb2156545ebbf47077a24377818", 08:44:05 INFO - "slavebuilddir": "test" 08:44:05 INFO - } 08:44:05 INFO - Found installer url https://queue.taskcluster.net/v1/task/Z0PXP240R4S179nHyIJJAA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 08:44:05 INFO - Running post-action listener: _resource_record_post_action 08:44:05 INFO - [mozharness: 2017-01-11 16:44:05.200266Z] Finished read-buildbot-config step (success) 08:44:05 INFO - [mozharness: 2017-01-11 16:44:05.200372Z] Running download-and-extract step. 08:44:05 INFO - Running pre-action listener: _resource_record_pre_action 08:44:05 INFO - Running main action method: download_and_extract 08:44:05 INFO - mkdir: /builds/slave/test/build/tests 08:44:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:44:05 INFO - trying https://queue.taskcluster.net/v1/task/Z0PXP240R4S179nHyIJJAA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 08:44:05 INFO - Downloading https://queue.taskcluster.net/v1/task/Z0PXP240R4S179nHyIJJAA/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 08:44:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Z0PXP240R4S179nHyIJJAA/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 08:44:06 INFO - Downloaded 1199 bytes. 08:44:06 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 08:44:06 INFO - Using the following test package requirements: 08:44:06 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 08:44:06 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:44:06 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 08:44:06 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:44:06 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 08:44:06 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:44:06 INFO - u'jsshell-mac.zip'], 08:44:06 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:44:06 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 08:44:06 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 08:44:06 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:44:06 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 08:44:06 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:44:06 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 08:44:06 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:44:06 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 08:44:06 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:44:06 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 08:44:06 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:44:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Z0PXP240R4S179nHyIJJAA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 08:44:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Z0PXP240R4S179nHyIJJAA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:44:06 INFO - Fetch https://queue.taskcluster.net/v1/task/Z0PXP240R4S179nHyIJJAA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 08:44:07 INFO - Expected file size: 16312125 08:44:07 INFO - Obtained file size: 16312125 08:44:14 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 08:44:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Z0PXP240R4S179nHyIJJAA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 08:44:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Z0PXP240R4S179nHyIJJAA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:44:14 INFO - Fetch https://queue.taskcluster.net/v1/task/Z0PXP240R4S179nHyIJJAA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 08:44:14 INFO - Expected file size: 16312125 08:44:14 INFO - Obtained file size: 16312125 08:44:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Z0PXP240R4S179nHyIJJAA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 08:44:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Z0PXP240R4S179nHyIJJAA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 08:44:21 INFO - Fetch https://queue.taskcluster.net/v1/task/Z0PXP240R4S179nHyIJJAA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 08:44:22 INFO - Expected file size: 14403529 08:44:22 INFO - Obtained file size: 14403529 08:44: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')]} 08:44:22 INFO - trying https://queue.taskcluster.net/v1/task/Z0PXP240R4S179nHyIJJAA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 08:44:22 INFO - Downloading https://queue.taskcluster.net/v1/task/Z0PXP240R4S179nHyIJJAA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 08:44:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Z0PXP240R4S179nHyIJJAA/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 08:44:25 INFO - Downloaded 69798923 bytes. 08:44:25 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Z0PXP240R4S179nHyIJJAA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 08:44:25 INFO - mkdir: /builds/slave/test/properties 08:44:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:44:25 INFO - Writing to file /builds/slave/test/properties/build_url 08:44:25 INFO - Contents: 08:44:25 INFO - build_url:https://queue.taskcluster.net/v1/task/Z0PXP240R4S179nHyIJJAA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 08:44:26 INFO - Running post-action listener: _resource_record_post_action 08:44:26 INFO - Running post-action listener: set_extra_try_arguments 08:44:26 INFO - [mozharness: 2017-01-11 16:44:26.441526Z] Finished download-and-extract step (success) 08:44:26 INFO - [mozharness: 2017-01-11 16:44:26.441669Z] Running populate-webroot step. 08:44:26 INFO - Running pre-action listener: _resource_record_pre_action 08:44:26 INFO - Running main action method: populate_webroot 08:44:26 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:44:26 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:44:26 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:44:26 INFO - '--tppagecycles', 08:44:26 INFO - '7'], 08:44:26 INFO - 'tests': ['tsvgm']}}, 08:44:26 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:44:26 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:44:26 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:44:26 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:44:26 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:44:26 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:44:26 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:44:26 INFO - 'talos_options': ['--disable-e10s'], 08:44:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:44:26 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:44:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:44:26 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:44:26 INFO - 'talos_options': ['--disable-e10s'], 08:44:26 INFO - 'tests': ['damp', 'tps']}, 08:44:26 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:44:26 INFO - 'tests': ['damp', 'tps']}, 08:44:26 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:44:26 INFO - 'tests': ['dromaeo_dom']}, 08:44:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:44:26 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:44:26 INFO - 'tests': ['basic_compositor_video']}, 08:44:26 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:44:26 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:44:26 INFO - 'tests': ['a11yr', 08:44:26 INFO - 'ts_paint', 08:44:26 INFO - 'tpaint', 08:44:26 INFO - 'sessionrestore', 08:44:26 INFO - 'sessionrestore_no_auto_restore', 08:44:26 INFO - 'tabpaint']}, 08:44:26 INFO - 'other-e10s': {'tests': ['a11yr', 08:44:26 INFO - 'ts_paint', 08:44:26 INFO - 'tpaint', 08:44:26 INFO - 'sessionrestore', 08:44:26 INFO - 'sessionrestore_no_auto_restore', 08:44:26 INFO - 'tabpaint']}, 08:44:26 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:44:26 INFO - 'ts_paint', 08:44:26 INFO - 'tpaint', 08:44:26 INFO - 'sessionrestore', 08:44:26 INFO - 'sessionrestore_no_auto_restore', 08:44:26 INFO - 'tabpaint']}, 08:44:26 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:44:26 INFO - 'ts_paint', 08:44:26 INFO - 'tpaint', 08:44:26 INFO - 'sessionrestore', 08:44:26 INFO - 'sessionrestore_no_auto_restore', 08:44:26 INFO - 'tabpaint']}, 08:44:26 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:44:26 INFO - 'tests': ['a11yr', 08:44:26 INFO - 'ts_paint', 08:44:26 INFO - 'tpaint', 08:44:26 INFO - 'sessionrestore', 08:44:26 INFO - 'sessionrestore_no_auto_restore', 08:44:26 INFO - 'tabpaint']}, 08:44:26 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:44:26 INFO - 'tests': ['a11yr', 08:44:26 INFO - 'ts_paint', 08:44:26 INFO - 'tpaint', 08:44:26 INFO - 'sessionrestore', 08:44:26 INFO - 'sessionrestore_no_auto_restore', 08:44:26 INFO - 'tabpaint']}, 08:44:26 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:44:26 INFO - 'tests': ['tsvgx', 08:44:26 INFO - 'tsvgr_opacity', 08:44:26 INFO - 'tart', 08:44:26 INFO - 'tscrollx', 08:44:26 INFO - 'cart', 08:44:26 INFO - 'tsvg_static']}, 08:44:26 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:44:26 INFO - 'tsvgr_opacity', 08:44:26 INFO - 'tart', 08:44:26 INFO - 'tscrollx', 08:44:26 INFO - 'cart', 08:44:26 INFO - 'tsvg_static']}, 08:44:26 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:44:26 INFO - 'talos_options': ['--disable-e10s'], 08:44:26 INFO - 'tests': ['tp5o']}, 08:44:26 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:44:26 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:44:26 INFO - 'talos_options': ['--disable-e10s', 08:44:26 INFO - '--xperf_path', 08:44:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:44:26 INFO - 'tests': ['tp5n']}, 08:44:26 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:44:26 INFO - 'talos_options': ['--xperf_path', 08:44:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:44:26 INFO - 'tests': ['tp5n']}}, 08:44:26 INFO - 'talos.zip': {'path': '', 08:44:26 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:44:26 INFO - Running post-action listener: _resource_record_post_action 08:44:26 INFO - [mozharness: 2017-01-11 16:44:26.459969Z] Finished populate-webroot step (success) 08:44:26 INFO - [mozharness: 2017-01-11 16:44:26.460097Z] Running create-virtualenv step. 08:44:26 INFO - Running pre-action listener: _resource_record_pre_action 08:44:26 INFO - Running main action method: create_virtualenv 08:44:26 INFO - Creating virtualenv /builds/slave/test/build/venv 08:44:26 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 08:44:26 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:44:26 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:44:26 INFO - Using real prefix '/tools/python27' 08:44:26 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:44:27 INFO - Installing distribute.............................................................................................................................................................................................done. 08:44:30 INFO - Installing pip.................done. 08:44:30 INFO - Return code: 0 08:44:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:44:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:44:30 INFO - Reading from file tmpfile_stdout 08:44:30 INFO - Output received: 08:44:30 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 08:44:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:44:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:44:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:44:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10aa51b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa54768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91ba487ac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a82fd30>, '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 0x10a756db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a824be8>, '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.CTE536dfEn/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.Gm5BgvehfO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:44:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:44:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:44:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CTE536dfEn/Render', 08:44:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:44:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:44:30 INFO - 'HOME': '/Users/cltbld', 08:44:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:44:30 INFO - 'LOGNAME': 'cltbld', 08:44:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:30 INFO - 'MOZ_NO_REMOTE': '1', 08:44:30 INFO - 'NO_EM_RESTART': '1', 08:44:30 INFO - 'PAGER': '/bin/cat', 08:44:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:44:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:30 INFO - 'PWD': '/builds/slave/test', 08:44:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:44:30 INFO - 'SHELL': '/bin/bash', 08:44:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Gm5BgvehfO/Listeners', 08:44:30 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 08:44:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:44:30 INFO - 'USER': 'cltbld', 08:44:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:44:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:44:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:30 INFO - 'XPC_FLAGS': '0x0', 08:44:30 INFO - 'XPC_SERVICE_NAME': '0', 08:44:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:44:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:30 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 08:44:30 INFO - Cleaning up... 08:44:30 INFO - Return code: 0 08:44:30 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:44:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:44:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:44:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10aa51b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa54768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91ba487ac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a82fd30>, '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 0x10a756db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a824be8>, '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.CTE536dfEn/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.Gm5BgvehfO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:44:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:44:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:44:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CTE536dfEn/Render', 08:44:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:44:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:44:30 INFO - 'HOME': '/Users/cltbld', 08:44:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:44:30 INFO - 'LOGNAME': 'cltbld', 08:44:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:30 INFO - 'MOZ_NO_REMOTE': '1', 08:44:30 INFO - 'NO_EM_RESTART': '1', 08:44:30 INFO - 'PAGER': '/bin/cat', 08:44:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:44:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:30 INFO - 'PWD': '/builds/slave/test', 08:44:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:44:30 INFO - 'SHELL': '/bin/bash', 08:44:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Gm5BgvehfO/Listeners', 08:44:30 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 08:44:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:44:30 INFO - 'USER': 'cltbld', 08:44:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:44:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:44:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:30 INFO - 'XPC_FLAGS': '0x0', 08:44:30 INFO - 'XPC_SERVICE_NAME': '0', 08:44:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:44:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:30 INFO - Downloading/unpacking psutil>=3.1.1 08:44:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:44:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:44:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:44:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:44:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:44:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:44:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:44:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:44:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:44:34 INFO - Installing collected packages: psutil 08:44:34 INFO - Running setup.py install for psutil 08:44:34 INFO - building 'psutil._psutil_osx' extension 08:44:34 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 08:44:35 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 08:44:35 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 08:44:35 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 08:44:35 INFO - building 'psutil._psutil_posix' extension 08:44:35 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 08:44:35 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:44:35 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:44:35 INFO - ^ 08:44:35 INFO - 1 warning generated. 08:44:35 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 08:44:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:44:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:44:35 INFO - Successfully installed psutil 08:44:35 INFO - Cleaning up... 08:44:35 INFO - Return code: 0 08:44:35 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:44:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:44:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:44:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:35 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 0x10aa51b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa54768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91ba487ac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a82fd30>, '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 0x10a756db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a824be8>, '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.CTE536dfEn/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.Gm5BgvehfO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:44:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:44:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:44:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CTE536dfEn/Render', 08:44:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:44:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:44:35 INFO - 'HOME': '/Users/cltbld', 08:44:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:44:35 INFO - 'LOGNAME': 'cltbld', 08:44:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:35 INFO - 'MOZ_NO_REMOTE': '1', 08:44:35 INFO - 'NO_EM_RESTART': '1', 08:44:35 INFO - 'PAGER': '/bin/cat', 08:44:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:44:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:35 INFO - 'PWD': '/builds/slave/test', 08:44:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:44:35 INFO - 'SHELL': '/bin/bash', 08:44:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Gm5BgvehfO/Listeners', 08:44:35 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 08:44:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:44:35 INFO - 'USER': 'cltbld', 08:44:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:44:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:44:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:35 INFO - 'XPC_FLAGS': '0x0', 08:44:35 INFO - 'XPC_SERVICE_NAME': '0', 08:44:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:44:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:36 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:44:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:44:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:44:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:44:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:44:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:44:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:44:38 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:44:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:44:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:44:38 INFO - Installing collected packages: mozsystemmonitor 08:44:38 INFO - Running setup.py install for mozsystemmonitor 08:44:38 INFO - Successfully installed mozsystemmonitor 08:44:38 INFO - Cleaning up... 08:44:38 INFO - Return code: 0 08:44:38 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:44:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:44:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:44:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10aa51b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa54768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91ba487ac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a82fd30>, '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 0x10a756db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a824be8>, '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.CTE536dfEn/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.Gm5BgvehfO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:44:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:44:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:44:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CTE536dfEn/Render', 08:44:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:44:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:44:38 INFO - 'HOME': '/Users/cltbld', 08:44:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:44:38 INFO - 'LOGNAME': 'cltbld', 08:44:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:38 INFO - 'MOZ_NO_REMOTE': '1', 08:44:38 INFO - 'NO_EM_RESTART': '1', 08:44:38 INFO - 'PAGER': '/bin/cat', 08:44:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:44:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:38 INFO - 'PWD': '/builds/slave/test', 08:44:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:44:38 INFO - 'SHELL': '/bin/bash', 08:44:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Gm5BgvehfO/Listeners', 08:44:38 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 08:44:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:44:38 INFO - 'USER': 'cltbld', 08:44:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:44:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:44:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:38 INFO - 'XPC_FLAGS': '0x0', 08:44:38 INFO - 'XPC_SERVICE_NAME': '0', 08:44:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:44:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:39 INFO - Downloading/unpacking jsonschema==2.5.1 08:44:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:44:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:44:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:44:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:44:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:44:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:44:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:44:41 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:44:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:44:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:44:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:44:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:44:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:44:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:44:42 INFO - Downloading functools32-3.2.3-2.tar.gz 08:44:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:44:42 INFO - warning: no files found matching '*.txt' 08:44:42 INFO - no previously-included directories found matching 'build' 08:44:42 INFO - no previously-included directories found matching 'dist' 08:44:42 INFO - no previously-included directories found matching '.git*' 08:44:42 INFO - Installing collected packages: jsonschema, functools32 08:44:42 INFO - Running setup.py install for functools32 08:44:42 INFO - warning: no files found matching '*.txt' 08:44:42 INFO - no previously-included directories found matching 'build' 08:44:42 INFO - no previously-included directories found matching 'dist' 08:44:42 INFO - no previously-included directories found matching '.git*' 08:44:42 INFO - Successfully installed jsonschema functools32 08:44:42 INFO - Cleaning up... 08:44:42 INFO - Return code: 0 08:44:42 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:44:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:44:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:44:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:42 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 0x10aa51b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa54768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91ba487ac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a82fd30>, '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 0x10a756db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a824be8>, '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.CTE536dfEn/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.Gm5BgvehfO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:44:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:44:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 08:44:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CTE536dfEn/Render', 08:44:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:44:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:44:42 INFO - 'HOME': '/Users/cltbld', 08:44:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:44:42 INFO - 'LOGNAME': 'cltbld', 08:44:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:42 INFO - 'MOZ_NO_REMOTE': '1', 08:44:42 INFO - 'NO_EM_RESTART': '1', 08:44:42 INFO - 'PAGER': '/bin/cat', 08:44:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:44:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:42 INFO - 'PWD': '/builds/slave/test', 08:44:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:44:42 INFO - 'SHELL': '/bin/bash', 08:44:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Gm5BgvehfO/Listeners', 08:44:42 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 08:44:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:44:42 INFO - 'USER': 'cltbld', 08:44:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:44:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:44:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:42 INFO - 'XPC_FLAGS': '0x0', 08:44:42 INFO - 'XPC_SERVICE_NAME': '0', 08:44:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:44:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:42 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:44:42 INFO - Cleaning up... 08:44:42 INFO - Return code: 0 08:44:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:44:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:44:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:44:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:42 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 0x10aa51b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa54768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91ba487ac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a82fd30>, '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 0x10a756db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a824be8>, '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.CTE536dfEn/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.Gm5BgvehfO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:44:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:44:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:44:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CTE536dfEn/Render', 08:44:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:44:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:44:42 INFO - 'HOME': '/Users/cltbld', 08:44:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:44:42 INFO - 'LOGNAME': 'cltbld', 08:44:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:42 INFO - 'MOZ_NO_REMOTE': '1', 08:44:42 INFO - 'NO_EM_RESTART': '1', 08:44:42 INFO - 'PAGER': '/bin/cat', 08:44:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:44:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:42 INFO - 'PWD': '/builds/slave/test', 08:44:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:44:42 INFO - 'SHELL': '/bin/bash', 08:44:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Gm5BgvehfO/Listeners', 08:44:42 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 08:44:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:44:42 INFO - 'USER': 'cltbld', 08:44:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:44:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:44:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:42 INFO - 'XPC_FLAGS': '0x0', 08:44:42 INFO - 'XPC_SERVICE_NAME': '0', 08:44:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:44:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:43 INFO - Downloading/unpacking blobuploader==1.2.4 08:44:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:44:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:44:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:44:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:44:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:44:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:44:45 INFO - Downloading blobuploader-1.2.4.tar.gz 08:44:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:44:45 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:44:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:44:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:44:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:44:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:44:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:44:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:44:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:44:46 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:44:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:44:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:44:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:44:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:44:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:44:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:44:46 INFO - Downloading docopt-0.6.1.tar.gz 08:44:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:44:46 INFO - Installing collected packages: blobuploader, requests, docopt 08:44:46 INFO - Running setup.py install for blobuploader 08:44:47 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:44:47 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:44:47 INFO - Running setup.py install for requests 08:44:47 INFO - Running setup.py install for docopt 08:44:47 INFO - Successfully installed blobuploader requests docopt 08:44:47 INFO - Cleaning up... 08:44:47 INFO - Return code: 0 08:44:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:44:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:44:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:44:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:47 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 0x10aa51b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa54768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91ba487ac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a82fd30>, '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 0x10a756db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a824be8>, '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.CTE536dfEn/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.Gm5BgvehfO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:44:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:44:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:44:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CTE536dfEn/Render', 08:44:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:44:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:44:47 INFO - 'HOME': '/Users/cltbld', 08:44:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:44:47 INFO - 'LOGNAME': 'cltbld', 08:44:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:47 INFO - 'MOZ_NO_REMOTE': '1', 08:44:47 INFO - 'NO_EM_RESTART': '1', 08:44:47 INFO - 'PAGER': '/bin/cat', 08:44:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:44:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:47 INFO - 'PWD': '/builds/slave/test', 08:44:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:44:47 INFO - 'SHELL': '/bin/bash', 08:44:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Gm5BgvehfO/Listeners', 08:44:47 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 08:44:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:44:47 INFO - 'USER': 'cltbld', 08:44:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:44:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:44:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:47 INFO - 'XPC_FLAGS': '0x0', 08:44:47 INFO - 'XPC_SERVICE_NAME': '0', 08:44:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:44:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:44:48 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-42ut9n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:44:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:44:48 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-jjQMNB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:44:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:44:48 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-nFq3V3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:44:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:44:48 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-WzJVoO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:44:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:44:48 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-51zM_R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:44:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:44:48 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-IW26IS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:44:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:44:48 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-vfwTtX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:44:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:44:48 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-eBRcKF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:44:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:44:49 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-IAj2TW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:44:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:44:49 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-PJfStD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:44:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:44:49 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-NYDgw5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:44:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:44:49 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-JzHfzc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:44:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:44:49 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-9zbVe_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:44:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:44:49 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-EjSBFJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:44:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:44:49 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-IP_NM9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:44:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:44:49 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-OjrnnM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:44:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:44:49 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-AuwREB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:44:50 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:44:50 INFO - Running setup.py install for manifestparser 08:44:50 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:44:50 INFO - Running setup.py install for mozcrash 08:44:50 INFO - Running setup.py install for mozdebug 08:44:50 INFO - Running setup.py install for mozdevice 08:44:50 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:44:50 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:44:50 INFO - Running setup.py install for mozfile 08:44:50 INFO - Running setup.py install for mozhttpd 08:44:50 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:44:50 INFO - Running setup.py install for mozinfo 08:44:51 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:44:51 INFO - Running setup.py install for mozInstall 08:44:51 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:44:51 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:44:51 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:44:51 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:44:51 INFO - Running setup.py install for mozleak 08:44:51 INFO - Running setup.py install for mozlog 08:44:51 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:44:51 INFO - Running setup.py install for moznetwork 08:44:51 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:44:51 INFO - Running setup.py install for mozprocess 08:44:51 INFO - Running setup.py install for mozprofile 08:44:51 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:44:51 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:44:51 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:44:51 INFO - Running setup.py install for mozrunner 08:44:52 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:44:52 INFO - Running setup.py install for mozscreenshot 08:44:52 INFO - Running setup.py install for moztest 08:44:52 INFO - Running setup.py install for mozversion 08:44:52 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:44:52 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:44:52 INFO - Cleaning up... 08:44:52 INFO - Return code: 0 08:44:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:44:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:44:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:44:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:52 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 0x10aa51b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa54768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91ba487ac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a82fd30>, '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 0x10a756db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a824be8>, '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.CTE536dfEn/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.Gm5BgvehfO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:44:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:44:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:44:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CTE536dfEn/Render', 08:44:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:44:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:44:52 INFO - 'HOME': '/Users/cltbld', 08:44:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:44:52 INFO - 'LOGNAME': 'cltbld', 08:44:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:52 INFO - 'MOZ_NO_REMOTE': '1', 08:44:52 INFO - 'NO_EM_RESTART': '1', 08:44:52 INFO - 'PAGER': '/bin/cat', 08:44:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:44:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:52 INFO - 'PWD': '/builds/slave/test', 08:44:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:44:52 INFO - 'SHELL': '/bin/bash', 08:44:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Gm5BgvehfO/Listeners', 08:44:52 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 08:44:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:44:52 INFO - 'USER': 'cltbld', 08:44:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:44:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:44:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:52 INFO - 'XPC_FLAGS': '0x0', 08:44:52 INFO - 'XPC_SERVICE_NAME': '0', 08:44:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:44:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:44:52 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-qmlHyQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:44:53 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 08:44:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:44:53 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-obi2rn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:44:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:44:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:44:53 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-t_O8pc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:44:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:44:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:44:53 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-zmSEdT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:44:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:44:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:44:53 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-g2L3n4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:44:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 08:44:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:44:53 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-8bQGKk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:44:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 08:44:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:44:53 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-31t2v4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:44:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 08:44:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:44:53 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-3g4YqY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:44:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 08:44:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:44:53 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-7s9EB0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:44:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 08:44:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:44:53 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-7OwK9Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:44:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:44:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:44:53 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-d9O02K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:44:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 08:44:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:44:54 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-B5nBuW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:44:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 08:44:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:44:54 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-PiHZtT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:44:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 08:44:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:44:54 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-I9C9D0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:44:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 08:44:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:44:54 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-ktQcKb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:44:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 08:44:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:44:54 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-LjDYrU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:44:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 08:44:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:44:54 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-jIC88Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:44:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 08:44:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:44:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:44:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:44:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:44:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:44:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:44:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:44:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:44:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:44:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:44:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:44:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:44:57 INFO - Downloading blessings-1.6.tar.gz 08:44:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:44:57 INFO - Installing collected packages: blessings 08:44:57 INFO - Running setup.py install for blessings 08:44:57 INFO - Successfully installed blessings 08:44:57 INFO - Cleaning up... 08:44:58 INFO - Return code: 0 08:44:58 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:44:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:44:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:44:58 INFO - Reading from file tmpfile_stdout 08:44:58 INFO - Current package versions: 08:44:58 INFO - blessings == 1.6 08:44:58 INFO - blobuploader == 1.2.4 08:44:58 INFO - docopt == 0.6.1 08:44:58 INFO - functools32 == 3.2.3-2 08:44:58 INFO - jsonschema == 2.5.1 08:44:58 INFO - manifestparser == 1.1 08:44:58 INFO - mozInstall == 1.12 08:44:58 INFO - mozcrash == 1.0 08:44:58 INFO - mozdebug == 0.1 08:44:58 INFO - mozdevice == 0.49 08:44:58 INFO - mozfile == 1.2 08:44:58 INFO - mozhttpd == 0.7 08:44:58 INFO - mozinfo == 0.9 08:44:58 INFO - mozleak == 0.1 08:44:58 INFO - mozlog == 3.4 08:44:58 INFO - moznetwork == 0.27 08:44:58 INFO - mozprocess == 0.24 08:44:58 INFO - mozprofile == 0.28 08:44:58 INFO - mozrunner == 6.13 08:44:58 INFO - mozscreenshot == 0.1 08:44:58 INFO - mozsystemmonitor == 0.3 08:44:58 INFO - moztest == 0.8 08:44:58 INFO - mozversion == 1.4 08:44:58 INFO - psutil == 3.1.1 08:44:58 INFO - requests == 1.2.3 08:44:58 INFO - wsgiref == 0.1.2 08:44:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:44:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:44:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:44:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:58 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 0x10aa51b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa54768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91ba487ac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a82fd30>, '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 0x10a756db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a824be8>, '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.CTE536dfEn/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.Gm5BgvehfO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:44:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 08:44:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:44:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CTE536dfEn/Render', 08:44:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:44:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:44:58 INFO - 'HOME': '/Users/cltbld', 08:44:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:44:58 INFO - 'LOGNAME': 'cltbld', 08:44:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:58 INFO - 'MOZ_NO_REMOTE': '1', 08:44:58 INFO - 'NO_EM_RESTART': '1', 08:44:58 INFO - 'PAGER': '/bin/cat', 08:44:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:44:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:58 INFO - 'PWD': '/builds/slave/test', 08:44:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:44:58 INFO - 'SHELL': '/bin/bash', 08:44:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Gm5BgvehfO/Listeners', 08:44:58 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 08:44:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:44:58 INFO - 'USER': 'cltbld', 08:44:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:44:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:44:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:58 INFO - 'XPC_FLAGS': '0x0', 08:44:58 INFO - 'XPC_SERVICE_NAME': '0', 08:44:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:44:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:44:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 08:44:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 08:44:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:44:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 08:44:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 08:44:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 08:44:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 08:44:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 08:44:58 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:44:58 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:44:58 INFO - Cleaning up... 08:44:59 INFO - Return code: 0 08:44:59 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:44:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:44:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:44:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:59 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 0x10aa51b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa54768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91ba487ac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a82fd30>, '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 0x10a756db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a824be8>, '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.CTE536dfEn/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.Gm5BgvehfO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:44:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:44:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:44:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CTE536dfEn/Render', 08:44:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:44:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:44:59 INFO - 'HOME': '/Users/cltbld', 08:44:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:44:59 INFO - 'LOGNAME': 'cltbld', 08:44:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:59 INFO - 'MOZ_NO_REMOTE': '1', 08:44:59 INFO - 'NO_EM_RESTART': '1', 08:44:59 INFO - 'PAGER': '/bin/cat', 08:44:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:44:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:59 INFO - 'PWD': '/builds/slave/test', 08:44:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:44:59 INFO - 'SHELL': '/bin/bash', 08:44:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Gm5BgvehfO/Listeners', 08:44:59 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 08:44:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:44:59 INFO - 'USER': 'cltbld', 08:44:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:44:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:44:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:59 INFO - 'XPC_FLAGS': '0x0', 08:44:59 INFO - 'XPC_SERVICE_NAME': '0', 08:44:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:44:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:59 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:44:59 INFO - Cleaning up... 08:44:59 INFO - Return code: 0 08:44:59 INFO - Running post-action listener: _resource_record_post_action 08:44:59 INFO - Running post-action listener: _start_resource_monitoring 08:44:59 INFO - Starting resource monitoring. 08:44:59 INFO - [mozharness: 2017-01-11 16:44:59.314879Z] Finished create-virtualenv step (success) 08:44:59 INFO - [mozharness: 2017-01-11 16:44:59.315922Z] Running install step. 08:44:59 INFO - Running pre-action listener: _resource_record_pre_action 08:44:59 INFO - Running main action method: install 08:44:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:44:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:44:59 INFO - Reading from file tmpfile_stdout 08:44:59 INFO - Detecting whether we're running mozinstall >=1.0... 08:44:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:44:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:44:59 INFO - Reading from file tmpfile_stdout 08:44:59 INFO - Output received: 08:44:59 INFO - Usage: mozinstall [options] installer 08:44:59 INFO - Options: 08:44:59 INFO - -h, --help show this help message and exit 08:44:59 INFO - -d DEST, --destination=DEST 08:44:59 INFO - Directory to install application into. [default: 08:44:59 INFO - "/builds/slave/test"] 08:44:59 INFO - --app=APP Application being installed. [default: firefox] 08:44:59 INFO - mkdir: /builds/slave/test/build/application 08:44:59 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'] 08:44:59 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 08:45:21 INFO - Reading from file tmpfile_stdout 08:45:21 INFO - Output received: 08:45:21 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:45:21 INFO - Running post-action listener: _resource_record_post_action 08:45:21 INFO - [mozharness: 2017-01-11 16:45:21.915231Z] Finished install step (success) 08:45:21 INFO - [mozharness: 2017-01-11 16:45:21.915372Z] Running run-tests step. 08:45:21 INFO - Running pre-action listener: _resource_record_pre_action 08:45:21 INFO - Running main action method: run_tests 08:45:21 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:45:21 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:45:21 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:45:21 INFO - 2017-01-11 08:45:21.930 screenresolution[1622:7322] starting screenresolution argv=screenresolution get 08:45:21 INFO - 2017-01-11 08:45:21.932 screenresolution[1622:7322] Display 0: 1600x1200x32@60 08:45:21 INFO - 2017-01-11 08:45:21.938 screenresolution[1623:7325] starting screenresolution argv=screenresolution list 08:45:21 INFO - Available Modes on Display 0 08:45:21 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:45:21 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:45:21 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:45:21 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:45:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:45:22 INFO - Intel Iris: 08:45:22 INFO - Chipset Model: Intel Iris 08:45:22 INFO - Type: GPU 08:45:22 INFO - Bus: Built-In 08:45:22 INFO - VRAM (Dynamic, Max): 1536 MB 08:45:22 INFO - Vendor: Intel (0x8086) 08:45:22 INFO - Device ID: 0x0a2e 08:45:22 INFO - Revision ID: 0x0009 08:45:22 INFO - Displays: 08:45:22 INFO - Display: 08:45:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:45:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:45:22 INFO - Display Serial Number: 1600x1200 60 08:45:22 INFO - Main Display: Yes 08:45:22 INFO - Mirror: Off 08:45:22 INFO - Online: Yes 08:45:22 INFO - Rotation: Supported 08:45:22 INFO - Return code: 0 08:45:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:45:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:45:22 INFO - Python 2.7.3 08:45:22 INFO - Return code: 0 08:45:22 INFO - grabbing minidump binary from tooltool 08:45: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')]} 08:45:22 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 0x10a82fd30>, '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 0x10a756db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a824be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:45:22 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 08:45:22 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 08:45:22 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 08:45:22 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:45:22 INFO - Return code: 0 08:45:22 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:45:22 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:45:22 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:45:22 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:45:22 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:45:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Z0PXP240R4S179nHyIJJAA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0036', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 08:45:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Z0PXP240R4S179nHyIJJAA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0036 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 08:45:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CTE536dfEn/Render', 08:45:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:45:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:45:22 INFO - 'HOME': '/Users/cltbld', 08:45:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:45:22 INFO - 'LOGNAME': 'cltbld', 08:45:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:45:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:45:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:45:22 INFO - 'MOZ_NO_REMOTE': '1', 08:45:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:45:22 INFO - 'NO_EM_RESTART': '1', 08:45:22 INFO - 'PAGER': '/bin/cat', 08:45:22 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:45:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:45:22 INFO - 'PWD': '/builds/slave/test', 08:45:22 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:45:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:45:22 INFO - 'SHELL': '/bin/bash', 08:45:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Gm5BgvehfO/Listeners', 08:45:22 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 08:45:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:45:22 INFO - 'USER': 'cltbld', 08:45:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:45:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:45:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:45:22 INFO - 'XPC_FLAGS': '0x0', 08:45:22 INFO - 'XPC_SERVICE_NAME': '0', 08:45:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:45:22 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Z0PXP240R4S179nHyIJJAA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0036', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 08:45:22 INFO - mozversion application_buildid: 20170111072506 08:45:22 INFO - mozversion application_changeset: 63ad56438630b389efdb01632e153143cf3008ec 08:45:22 INFO - mozversion application_display_name: Nightly 08:45:22 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:45:22 INFO - mozversion application_name: Firefox 08:45:22 INFO - mozversion application_remotingname: firefox 08:45:22 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:45:22 INFO - mozversion application_vendor: Mozilla 08:45:22 INFO - mozversion application_version: 53.0a1 08:45:22 INFO - mozversion platform_buildid: 20170111072506 08:45:22 INFO - mozversion platform_changeset: 63ad56438630b389efdb01632e153143cf3008ec 08:45:22 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:45:22 INFO - mozversion platform_version: 53.0a1 08:45:22 INFO - using testdate: 1484153122 08:45:22 INFO - actual date: 1484153122 08:45:22 INFO - starting webserver on 'localhost:49236' 08:45:22 INFO - SUITE-START | Running 2 tests 08:45:22 INFO - TEST-START | dromaeo_css 08:45:22 INFO - operating with platform_type : mac_ 08:45:22 INFO - Initialising browser for dromaeo_css test... 08:45:22 INFO - TEST-INFO | started process 1633 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/tmpMPpC5e/profile http://localhost:49236/getInfo.html) 08:45:25 INFO - PROCESS | 1633 | 2017-01-11 08:45:25.549 plugin-container[1635:7495] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d3f, name = 'com.apple.tsm.portname' 08:45:25 INFO - PROCESS | 1633 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:45:25 INFO - PROCESS | 1633 | 2017-01-11 08:45:25.553 plugin-container[1635:7495] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x930b, name = 'com.apple.CFPasteboardClient' 08:45:25 INFO - PROCESS | 1633 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:45:25 INFO - PROCESS | 1633 | 2017-01-11 08:45:25.553 plugin-container[1635:7495] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:45:25 INFO - PROCESS | 1633 | __metrics Screen width/height:1600/1200 08:45:25 INFO - PROCESS | 1633 | colorDepth:24 08:45:25 INFO - PROCESS | 1633 | Browser inner width/height: 1024/689 08:45:25 INFO - PROCESS | 1633 | __metrics 08:45:25 ERROR - PROCESS | 1633 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3242: TypeError: this.tabs is undefined 08:45:26 INFO - TEST-INFO | 1633: exit 0 08:45:26 INFO - Browser initialized. 08:45:26 INFO - Running cycle 1/1 for dromaeo_css test... 08:45:26 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/tmpMPpC5e/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:45:28 INFO - PROCESS | 1636 | 2017-01-11 08:45:28.847 plugin-container[1637:7612] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873b, name = 'com.apple.tsm.portname' 08:45:28 INFO - PROCESS | 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:45:28 INFO - PROCESS | 1636 | 2017-01-11 08:45:28.847 plugin-container[1637:7612] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4423, name = 'com.apple.CFPasteboardClient' 08:45:28 INFO - PROCESS | 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:45:28 INFO - PROCESS | 1636 | 2017-01-11 08:45:28.847 plugin-container[1637:7612] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:45:38 INFO - PROCESS | 1636 | RSS: Main: 233373696 08:45:38 INFO - PROCESS | 1636 | 08:48:41 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49236/tests/dromaeo/cssquery-ext.html) 08:48:44 INFO - PROCESS | 1636 | RSS: Main: 250769408 08:48:44 INFO - PROCESS | 1636 | 08:50:26 INFO - PROCESS | 1636 | ************************* 08:50:26 INFO - PROCESS | 1636 | A coding exception was thrown and uncaught in a Task. 08:50:26 INFO - PROCESS | 1636 | 08:50:26 INFO - PROCESS | 1636 | Full message: ReferenceError: fetch is not defined 08:50:26 INFO - PROCESS | 1636 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 08:50:26 INFO - PROCESS | 1636 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 08:50:26 INFO - PROCESS | 1636 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 08:50:26 INFO - PROCESS | 1636 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 08:50:26 INFO - PROCESS | 1636 | TaskImpl@resource://gre/modules/Task.jsm:277:3 08:50:26 INFO - PROCESS | 1636 | asyncFunction@resource://gre/modules/Task.jsm:252:14 08:50:26 INFO - PROCESS | 1636 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 08:50:26 INFO - PROCESS | 1636 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 08:50:26 INFO - PROCESS | 1636 | TaskImpl@resource://gre/modules/Task.jsm:277:3 08:50:26 INFO - PROCESS | 1636 | asyncFunction@resource://gre/modules/Task.jsm:252:14 08:50:26 INFO - PROCESS | 1636 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 08:50:26 INFO - PROCESS | 1636 | 08:50:26 INFO - PROCESS | 1636 | ************************* 08:51:46 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-ext.html (next: http://localhost:49236/tests/dromaeo/cssquery-jquery.html) 08:51:47 INFO - PROCESS | 1636 | RSS: Main: 262352896 08:51:47 INFO - PROCESS | 1636 | 08:54:49 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49236/tests/dromaeo/cssquery-mootools.html) 08:54:49 INFO - PROCESS | 1636 | RSS: Main: 270290944 08:54:49 INFO - PROCESS | 1636 | 08:57:51 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49236/tests/dromaeo/cssquery-prototype.html) 08:57:52 INFO - PROCESS | 1636 | RSS: Main: 266506240 08:57:52 INFO - PROCESS | 1636 | 09:00:55 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49236/tests/dromaeo/cssquery-yui.html) 09:00:55 INFO - PROCESS | 1636 | RSS: Main: 271065088 09:00:55 INFO - PROCESS | 1636 | 09:03:58 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-yui.html (next: http://localhost:49236/tests/dromaeo/cssquery-dojo.html) 09:03:58 INFO - PROCESS | 1636 | RSS: Main: 273633280 09:03:58 INFO - PROCESS | 1636 | 09:03:58 INFO - PROCESS | 1636 | __start_tp_report 09:03:58 INFO - PROCESS | 1636 | _x_x_mozilla_page_load 09:03:58 INFO - PROCESS | 1636 | _x_x_mozilla_page_load_details 09:03:58 INFO - PROCESS | 1636 | |i|pagename|runs| 09:03:58 INFO - PROCESS | 1636 | |0;dojo.html;2651.403434227299;3035.7419619332354;3290.9177270568216;3317.518959750836;3358.748093892957;5538.9169162462595;5546.5008149266605;5558.138488534279;5558.666480011202;5647.01177293974;5570.637908535942;5607.803726869561;5686.943130568703;5714.285714285715;5767.538596912247;4263.914721705564;5080.720560369178;5165.405978312486;5259.079661059319;5265.341832270966;5081.364829396325;5236.31927849379;5279.102552566073;5287.259783630295;5340.439253878345;5045.5459008689395;5089.974550127263;5196.610254230929;5206.453322401131;5228.895422091575;5073.213651883964;5117.283580298761;5172.172452407597;5191.688498690053;5250;4791.233402655558;4840.733759643218;5100.030994111107;5119.923201151985;5137.5633071188995;5453.836384908459;5572.721363931787;5616.0171969905105;5650.971745141289;5679.261695979496;6046.3651316612;6110.224634763485;6113.5371179039;6114.021756518936;6125.662799505643;6242.875142497124;6243.2508099027955;6253.187085678878;6257.2804127525005;6293.244810622708;5819.94180058194;5833.912491312633;5845.298564172284;5851.297844258674;5891.46976772084;5106.4382917879;6113.694315284218;6136.8772624548155;6163.414475624809;6168;4689.648276379286;4887.755612219375;5342.98483288174;5972.343042265347;6055.303640081359;6123.540734444845;6191.442770150708;6227.782027629011;6229.408206220402;6261.373862613702;5951.226340575698;5956.6723830188885;6030.819075427744;6090.969545152246;6126.816195514141;6137.193127861444;6216.564840461125;6229.937700622936;6235.438810507076;6247.062940558862;4888.902221955541;4975.751212439436;4981;4988.9501104988485;5001.149804533238;4375.409319741858;4402.559744025636;4457.064016556559;4478.619317358061;4497.100579884036;5712.714364281769;5749.108888122264;5784.4504772046585;5840.883182336414;5847.415258474203;6224.315325314212;6238.688065596702;6260.655663938527;6280.403361680633;6294.181756371734;5815;5825.504832089235;5841.979091026662;5867.823965281048;5909.320428150818;5283.498067683564;5341.252224688546;5345.679259244458;5360.517553420133;5365.517103460707;6159.106929495288;6209.720562574605;6211.720472578654;6245.843853903689;6270.310265870659;6230.096635987847;6255.218097737783;6255.593386429868;6278.6860656966055;6289.213848268966;5989.131575921554;6053.909191362045;6231.9688401557705;6257.186565746606;6265.154204182377;6042;6074.119252708421;6094.786682466092;6117.1130186123655;6126.387361263838;5917.615355001911;6137.070562331707;6141.51669281436;6148;6154.661493617894;6048.60684055535;6126.693665316806;6133.815985520441;6140.662263575547;6151.108089326933;6092.268927728701;6105.572609917263;6105.8168254952425;6113.29697084841;6133.202683651275;4568.17772800899;5769.94230057694;5800.158976948234;5859.267591551056;5869.178315035813;5973.91039134422;6067.757289708362;6068.9089663654195;6095.116208149882;6116.8776624468155;5865.618734782225;5909.409059094056;5911.379305173067;5953.613015154001;5972.372900845522 09:03:58 INFO - PROCESS | 1636 | |1;ext.html;465.60423639906065;485.92711093336004;499.38449117106165;521.7730287325014;540.5594440530964;37704.62295377046;42244.78877605611;42350.99999999996;44514.10971780562;44520.77739611297;32756;34561.30877382451;42685;43166.784166079124;43172.3524147138;8382.287505562035;9263.814723705538;9498.28762842786;9506.096920792514;9523.761905952337;8491.575421228945;9851.556679949419;11169.329840209613;11400.030997365235;11410.372429516374;11362.31826090433;11367;11377.886221137807;11380.089592832595;11384.260023098472;11336.43317834109;11394.658160255123;11399.658010259705;11416.54333826646;11432.771344573062;11306.37814920179;11359.31844089349;11361.545538178463;11367.715807104805;11379.715507112305;32509.349813003606;41104.794476027724;41201.79399103015;41297.79351103225;41854.581454185376;3911.8239679214503;3993.6605388542007;3996.360582306817;4036.9394459083137;4074.979625101885;23968.760312396826;28488.85755571209;29131.563026554617;29146.271343216376;29215.70784292151;2764.2260167153026;3006.293521022558;3178.55500229968;3247.626522949867;3256.902292931216;461195;465275;467108;467961;468803;57342.11894572678;57738.71130644404;57753.71123144357;57785;57838.7108064457;59345.40654593399;60017.69991150016;60221.795564088476;60289;60382;15357.462488812838;15515.84484155144;15583.14292713889;15596.84403155954;15609.843901561066;10314;10864.022237998619;11007.174461915229;11014.944925275482;12024.75950481003;17988.149373019107;18363.90818045928;21162.047707853184;21496;21821;7415.740449084256;8767.167119123673;8863;8961.193492585568;8964.955175224082;806.338802182205;811.6121432350628;814.1329484099415;826;840.7477756672973;28989.85505072461;29127.12618621445;29381.559276610868;29445.26386840346;29648.258793530335;956.5073986896754;993.438707130469;993.9006099390003;1036.108946306176;1060.2843080920347;14424.567262982127;14876.776848347283;14944.178070206026;15253.847461525465;15324.693506129815;635294;641325.5536056309;641579.5842041613;643548;645148;61908.69045654743;62111.37888621056;62448.687756560925;62522;62817.057744133876;1412.279737333967;1432.0763107795415;1448.8261408630822;1455.927203639835;1461.167134733213;24881.751182487944;26476.8676156618;26795.196144115707;27036;27431.725682743716;53117.7344113277;53765.924681506935;53792;53885.730571346896;53955.73022135021;483465;553705;553800;554194;555601;29224.853875730485;35940.10149746173;41447.96380090522;43092.784536078376;43529.78235108804;45584.77207613941;47724.761376192895;48019.75990120027;48257.5174248253;48705.75647121741;39463.01342466461;39520.604793951694;39778.40332395074;40099.39850902297;41001.38497922474;33872.32255354928;35991;39205.41191882067;39513.802430987664;39896.2020759589;32370.83814580912;37514.81242593769;37527.624723753506;38687.61312386952;38971.22057558889;33487.16282092967;34521.30957380788;34756.65243347534;34902;35171.648283516835;19880.70178947288;24000.63999039981;24208.75791242065;24268.150614729107;24559.772011399 09:03:58 INFO - PROCESS | 1636 | |2;jquery.html;324.8257548960508;385.1680370399937;392.2292694854611;399.1976127982754;414.33912908910276;18947.242110315587;20766;20952.580948381023;20992.95035248237;21101.472463188427;20526.384208473733;20617.690734638956;21315.57368852622;21499.785002149973;21856.562868742614;5639.15412688096;5710.857228569288;5758.280214973129;5841.503472204868;5881.764729410818;3799.8480060797533;4203.159368126372;5744.16709577111;5796.536277097843;5882.4705776480105;4355.32492463667;4371.322445021026;5880.911786323209;6513.218413790351;6547.410733034026;5690.48785609293;6590.1432813734145;6599.93400065998;6603.405693487561;6623.768168114093;5738.340090889559;5813.941860581424;5926.940730592725;5938.317093534256;5952.255968003999;13518.391672374668;15953.920230398773;20443.48891277715;24964.3758906027;25264.11575594864;2492.8005759539374;2608.3968667367094;2635.0645520840017;2641.524525585394;2667.9333016674545;10139.188864890792;10220.233482488844;10228.641997530125;10634.734131646694;10718.035376816124;1816.7910690270523;1839.8620103492292;1903.7049257365131;1905.1902941250053;1914.473519782057;121589.19340485768;129319.35340323333;131649;132706;132984.33507832495;10117.59529618807;11587.826182607268;12030.338331391682;12051.758964820654;12052.33712145823;12607.621771346874;13447.65523447667;14461.78307325391;15588.688226235412;15623.3750649975;8466.534340611326;8656.913430865736;8658.653653853775;8761.34289928258;8801;8346.081930987586;8377.70678026266;8428.620712067972;8554.743357699279;8569.357298202534;4743.644226682944;4821.180399332122;4829.154897892925;4832.855014349575;4916.754162291871;2043.6014977079624;2062.7215325930806;2064.183518503707;2065.308121779191;2067.824234940047;891.9509426981449;974.6637410093506;1007.3958816459387;1010.939343639384;1024.912882404989;21217.78782212158;21810;21928;21949.122035118417;22531.662025069636;1027.198784947742;1028.3829702178782;1029.5212726082407;1074.8763892152504;1078.962236321725;11302.491387887565;11572.016378607912;11621.941890290494;11626.302421854716;11636.534538618529;730434;737476;740434;744261;757665;153263;156166;158833.15185234824;163785;166690;1008.7276435362411;1008.8587597736323;1033.5917312661607;1038.6624347087227;1040.105509262034;19323.710144348126;19387.030648467804;19594.706079409105;19596.216151354358;19640.410787676392;59396.703016484644;61851.38148618456;62616.747665045536;63014.369856302685;63764.362356375845;67565.98651020337;86454.7031794511;89448;91751;91780;8155;8160.5103693778565;8268.37987150954;8311.95844020776;8539.188777066169;7664.4634875560405;8989.730308090768;11783.116266279892;12131.272123672608;12134.939325303318;11095.778084438105;11407.885921141014;11455.94272028668;11496.94251528737;11593.362365070003;11092.778144437227;11328.03711684516;11569.7107572308;11775.470103845519;11814.704632384259;8951.34400135977;10059.949700251453;10577.418241996456;11389.145814064144;11558.133140014364;6713.395794378529;8387.622556984828;8521.82956340882;8551.914480855112;8688.30493560501;10289.794204116026;11335.263207891461;11433.942830285796;11575.363355015555;11622.489076420012 09:03:58 INFO - PROCESS | 1636 | |3;mootools.html;1428.7772428970993;1440.7334643091035;1448.8333841608219;1455.788910607962;1457.701171259892;7570.583617901013;7827.530348179099;8213.178682131784;8589.785255368612;8610.052894181636;8065.072516660586;8297.875531867012;8338.624761885714;8443.873341899862;8476.32189424846;1956.9217231310731;1962.8233458988689;1998.5503261766132;2318.6637937499054;2344.554534638422;2342.2153578551206;2370.4547953970596;2372.596658568039;2372.8932198051043;2376.7266764322153;2324.93025209244;2330.82518811089;2354.081908055851;2363.9290821275304;2373.1931143411325;2314.6528020797014;2318.1538738360546;2340.5435939991708;2348.213348528245;2357.8113750899975;2353.64695295707;2363.3855197648563;2372.6796882420826;2379.4289370551114;2380.726216485109;7302.707891684327;8331.250187483156;8498.022727386367;8532.957335213347;8533.402661813614;4515.7516336601475;5103.438621751604;5222.556082732935;5251.921221181686;5254.0805359062015;3333.583302087239;3427.8971630851115;3574.571051473814;3590.425531914881;3594.6045934947133;1175.6414293640437;1226.3745489800176;1242.5961562492225;1246.5001345144024;1254.084518301645;10980.670579882617;11059.612913547988;11138.832917506244;11139.665810025712;11160.274582152135;4413.13943780966;4712.858614241578;4727.479977202505;4813.470518242991;4827.179379505493;9597.616095356108;9626.95186524077;9656.806863862825;9659.372140810825;9678.225741940629;5057.468965758542;5140.151874940668;5158.613104017214;5173.3533308336455;5282.207668849704;4148.23257697327;4688.718676879398;4703.506131856174;4799.712017278975;4844.128056949783;3791.563970143414;3842.9807850961124;3870.59358767331;3913.2760439318818;3927.6857851371824;1002.3284399452384;1261.8548866880242;1274.133589159362;1277.7955527115803;1288.0661520397823;673.7715329046537;786.8701664225339;898.3172788680649;906.1029580715044;927.5640448988964;4736.455307639597;5146;5611.887762244733;5686.658800471985;5934.406559344031;878.5519385113638;939.9530023498935;985.5466485416645;998.1715176403568;1029.9639512617023;3722.813859307024;3731.8728324854574;3739.775613463201;3759.2293579816073;3818.9236215276096;9993.350432221881;10309.639162629423;10330.638427654996;10333.121684656739;10343.120834728981;10157.339772914886;10170.745731356626;10218.437985910847;10285.845712314322;10313.793724125475;7716.228377162239;7733.961330193313;7881.960590197013;7957.880631790527;7992.000999875016;5538.6122971391615;5553.361363443256;5597;5701.372848986525;5746.197223613726;9232.907670923474;9317.813643726951;9361.425607957866;9395.436273823592;9458;9507.191888689262;9521.904780952378;9567.473788941676;9575.616975321187;9665.323427360017;6359.554831161774;6542.509311801538;6627.635480048643;6784.491163162684;6922;7113.679884405111;8107.1082180958865;8114.634841432267;8136.22705842944;8150.877736834071;7097.219305876246;7164.9641751790905;7226.74706385274;7269.672864721206;7340.412766978522;6027.969860150846;6233.252009758677;7124.251953544803;7150.463715221275;7249.673764680708;5805.129230615442;6085.813266413007;6379.4896408286295;6496.382843629847;6525.738970441319;4096.487939007624;4559.293309537027;4893.828715994923;4958.8760280993265;4986.975065124651;4367.432233809584;5088.211327244283;5116.9744151279;5144.562712169508;5157.716325602128 09:03:58 INFO - PROCESS | 1636 | |4;prototype.html;269.91092939330025;353.9504469374288;357.2889949001487;366.95963444021163;372.95897451280354;4641.373414589029;5250.842474725755;5402.567794576435;5493.148561972896;5498.790266141453;4334.696571240014;4735.786889589968;5148.407933087696;5347.518723314901;5367.35591728993;4163.812628431712;4224.577542245782;4227.471566054243;4237.216115018731;4237.8304867805255;2796.720327967207;3676.419139769898;4141.295979683446;4228.238916994939;4262.509811371701;3785.8107094645156;3796.8609417174875;4029.9597004030024;4032.9917520619847;4036;4004.979975100135;4015.518137823465;4021.577734337911;4042.393640953851;4046;3131.7181453669277;3533.6643018913164;3539.150603855056;3552.2007548301685;3579.355715971124;3781.7920014399197;4572.337011133401;4784.249056792323;5095.974520127375;5188;4077.8572749953905;4253.851115210984;4394.736315821061;4581.839635612738;4625.468071171825;4780.450248221464;5591.524720398771;5616.634918730269;5673.432656734335;5722.484976352148;3703.148275896559;3805.714571407128;3841.8271177797064;3852.7303088783797;3878.5733569307354;5318.175682769138;5431.87667091424;5463.52572201942;6006.459418652298;6044.60973975988;3211.582494275729;3471.687548120681;3913.3347330953807;3965.8810235692977;4012;3392.796432214075;3768.510093687803;3977.7016723745833;4019.718619696593;4047.5952404759287;4480.7535585542455;4561.407017087758;4586.4266966629175;4611.377464042312;4619.214733495314;5623.437656234426;5669.659820410789;5688.11834165705;5691.03252447085;5729.28383952006;1064.4411683866033;1247.4137155537064;1262.6994195978157;1289.9613011609667;1290.9741805163844;862.6032025268447;871.7515508080166;875.2472873328932;877.9122087791297;880.5553195636163;4654;5086.847394578169;5106.629624447846;5118.462561431071;5187.688738675692;4478.149151661174;4490.685652004328;5375.408705042442;5424.159255315432;5497.19642982074;4571;4736.3369128322065;5329.067413202752;5343.519083282523;5374.408815030343;2673.4118493931305;2863.6993115722967;2897.463969165711;2928.633920759905;2949.3658863344485;5117.52216810291;5275.208718692227;5367.463253674679;5804.622699524602;5877.500412464903;4899.501254360302;5685.260916080883;5739.651181972233;5775.27809023872;5796.681182535001;4063.878083657495;5078.771455284447;5229.450907654642;5388.299521062237;5462.262594549685;4913.680610760289;5381.434949330421;5594.762095161805;5655.699189186428;5770.884582308414;5340.572754179734;5797.9420205797405;5819.4180581943165;5830.446107619939;5844.532437404912;5604.467575580314;5732.34078081026;5771.480566749013;6082.878342433038;6091.715606647507;3817.9045523861014;3892.929444402812;3907.706921980806;3920.176762879713;3968.682505399619;5085.898282034413;5716.056850619768;5830.96789669378;5873.676947767914;5873.765049397976;4972.701637901738;5346.193248544545;5622.437756224344;5651.660900345993;5653.717314134195;5349.893002140013;5396.97457483077;5562.3881373048125;5579.609427340048;5661.433856614306;4470.955290447054;5145.305383773143;5295.867603309948;5475.452454754652;5578.553715702815;5403.05490023599;5580.77676892936;5592.468715472024;5613.522850557748;5726.914096288476;4715.740634265011;4815.831445899376;5162.915787684478;5307.734613269398;5538.750756215899 09:03:58 INFO - PROCESS | 1636 | |5;yui.html;690.1614538335924;692.9272426395231;748.4798065344585;807.5154907055767;854.2055888024139;14651.560453186408;15640.530784076482;15642.84357156428;15645.530634080968;15686.529404117866;11804.645860624174;14172;14380.712385752278;15777.60555986101;15788.921055394736;817.9427440079182;836.9414141010132;839.4249938791917;839.5172775653992;840.7351684219482;836.6820608168887;839.6515446089866;839.7061028639988;841.7054031089131;843.1442086282428;885.583775625455;886.5611522296472;888.4802390601506;888.7289376740124;889.3374436045156;878.1657425445788;881.3742243007472;882.496976723268;883.6597909804707;884.7390019944099;657.9638119903403;658.0524045374646;658.098405184895;659.6965395917883;660.3330636057616;15194.848051519564;15291.923540382228;15293.158876261801;15363.615909602238;15380.692386152214;12827.551035713705;13573.524926627619;14282.3572939218;14386.496472623512;14479.565613031626;10093.545790439448;10782.245242833005;11261.211715179941;11570.479328430156;11604.30374177552;3654.8355324010217;3689.9631003689888;3725;3763.9623603764157;3860.4402361657685;100393.49803250936;104541.47729261305;108377;108532.4573377136;109176;10472.895271047344;10938.726531836608;11023;11091.611793587186;11335.376554289429;23283.650745238836;23302.7669723304;23304.7669523306;23320.53358932812;23378.53242935132;7341.412686985135;7432.405407567489;7837.333826624801;7888.171741967011;8033.959830200928;4177.958220417818;4260.2970509866145;4261.488621365456;4271.67962402821;4307.289297265978;3623.7644553103964;3688.7233457490797;3691.9446208306895;3694.556653201633;3697.4638677391627;650.9218893732688;656.2354856592112;660.9669516524154;669.9732010719516;675.2437270257343;631.3717850738544;663.7676813115371;676.3202981004059;678.6878036103349;689.7965100295424;9440.339176257596;9810.950945245228;9843.950780246052;10002.299839011344;10181.694549163536;645.5577929118568;678.3216783216783;683.8427161752825;688.1673175457652;690.6118701410102;4813.470518242991;4946.950530494649;4960.578350840146;4961.305417241589;4985.651004429655;83973.16026839775;84340;103481;104884.47557762162;107254;69021;69873.65063174651;74440.88338674924;74925.25074749292;90194;49148;61112;62184.378156218765;65349.40578268146;65854.34145658482;10244.641437549948;10257.93356106726;10414.323069000491;10447.738806529898;10529.42088185157;43579;47797.522024779304;48815.75592122017;48925.77685557889;50229.74885125551;49412.50587494079;61726;65937.68124637385;66106;66773.3322666767;11309.491072901903;12499.687507812014;12714.745705086032;12874.485020599072;13347.399367028702;14436.018343781605;14459.710805783616;14476.986610937134;14930.77603835965;14989.325480353193;13551.390187441786;13582.117162384413;13596.66008349799;13605;14088.15471071739;13593.32033398346;13629.38667759973;13688.384022718803;13922.72154556883;14054.859451405355;12751.74496510046;13233.338333083115;13411.882176734898;13429.798553021517;13854.930725346308;10439.53022114022;10985.670429887115;11224.494897729784;11473;11771.646850594496;8647.394682372174;10151.289409741543;10287.53706083213;10450.372977621368;10573.04842564173 09:03:58 INFO - PROCESS | 1636 | __end_tp_report 09:03:58 INFO - PROCESS | 1636 | __start_cc_report 09:03:58 INFO - PROCESS | 1636 | _x_x_mozilla_cycle_collect,393 09:03:58 INFO - PROCESS | 1636 | __end_cc_report 09:03:58 INFO - PROCESS | 1636 | __startTimestamp1484154238958__endTimestamp 09:03:58 INFO - PROCESS | 1636 | 09:03:58 INFO - PROCESS | 1636 | ------- Summary: start ------- 09:03:58 INFO - PROCESS | 1636 | Number of tests: 6 09:03:58 INFO - PROCESS | 1636 | 09:03:58 INFO - PROCESS | 1636 | [#0] dojo.html Cycles:165 Average:5660.14 Median:5869.18 stddev:674.36 (11.5%) stddev-sans-first:633.78 09:03:58 INFO - PROCESS | 1636 | Values: 2651.4 3035.7 3290.9 3317.5 3358.7 5538.9 5546.5 5558.1 5558.7 5647.0 5570.6 5607.8 5686.9 5714.3 5767.5 4263.9 5080.7 5165.4 5259.1 5265.3 5081.4 5236.3 5279.1 5287.3 5340.4 5045.5 5090.0 5196.6 5206.5 5228.9 5073.2 5117.3 5172.2 5191.7 5250.0 4791.2 4840.7 5100.0 5119.9 5137.6 5453.8 5572.7 5616.0 5651.0 5679.3 6046.4 6110.2 6113.5 6114.0 6125.7 6242.9 6243.3 6253.2 6257.3 6293.2 5819.9 5833.9 5845.3 5851.3 5891.5 5106.4 6113.7 6136.9 6163.4 6168.0 4689.6 4887.8 5343.0 5972.3 6055.3 6123.5 6191.4 6227.8 6229.4 6261.4 5951.2 5956.7 6030.8 6091.0 6126.8 6137.2 6216.6 6229.9 6235.4 6247.1 4888.9 4975.8 4981.0 4989.0 5001.1 4375.4 4402.6 4457.1 4478.6 4497.1 5712.7 5749.1 5784.5 5840.9 5847.4 6224.3 6238.7 6260.7 6280.4 6294.2 5815.0 5825.5 5842.0 5867.8 5909.3 5283.5 5341.3 5345.7 5360.5 5365.5 6159.1 6209.7 6211.7 6245.8 6270.3 6230.1 6255.2 6255.6 6278.7 6289.2 5989.1 6053.9 6232.0 6257.2 6265.2 6042.0 6074.1 6094.8 6117.1 6126.4 5917.6 6137.1 6141.5 6148.0 6154.7 6048.6 6126.7 6133.8 6140.7 6151.1 6092.3 6105.6 6105.8 6113.3 6133.2 4568.2 5769.9 5800.2 5859.3 5869.2 5973.9 6067.8 6068.9 6095.1 6116.9 5865.6 5909.4 5911.4 5953.6 5972.4 09:03:58 INFO - PROCESS | 1636 | 09:03:58 INFO - PROCESS | 1636 | [#1] ext.html Cycles:180 Average:68897.32 Median:39488.41 stddev:147881.73 (374.5%) stddev-sans-first:148207.32 09:03:58 INFO - PROCESS | 1636 | Values: 465.6 485.9 499.4 521.8 540.6 37704.6 42244.8 42351.0 44514.1 44520.8 32756.0 34561.3 42685.0 43166.8 43172.4 8382.3 9263.8 9498.3 9506.1 9523.8 8491.6 9851.6 11169.3 11400.0 11410.4 11362.3 11367.0 11377.9 11380.1 11384.3 11336.4 11394.7 11399.7 11416.5 11432.8 11306.4 11359.3 11361.5 11367.7 11379.7 32509.3 41104.8 41201.8 41297.8 41854.6 3911.8 3993.7 3996.4 4036.9 4075.0 23968.8 28488.9 29131.6 29146.3 29215.7 2764.2 3006.3 3178.6 3247.6 3256.9 461195.0 465275.0 467108.0 467961.0 468803.0 57342.1 57738.7 57753.7 57785.0 57838.7 59345.4 60017.7 60221.8 60289.0 60382.0 15357.5 15515.8 15583.1 15596.8 15609.8 10314.0 10864.0 11007.2 11014.9 12024.8 17988.1 18363.9 21162.0 21496.0 21821.0 7415.7 8767.2 8863.0 8961.2 8965.0 806.3 811.6 814.1 826.0 840.7 28989.9 29127.1 29381.6 29445.3 29648.3 956.5 993.4 993.9 1036.1 1060.3 14424.6 14876.8 14944.2 15253.8 15324.7 635294.0 641325.6 641579.6 643548.0 645148.0 61908.7 62111.4 62448.7 62522.0 62817.1 1412.3 1432.1 1448.8 1455.9 1461.2 24881.8 26476.9 26795.2 27036.0 27431.7 53117.7 53765.9 53792.0 53885.7 53955.7 483465.0 553705.0 553800.0 554194.0 555601.0 29224.9 35940.1 41448.0 43092.8 43529.8 45584.8 47724.8 48019.8 48257.5 48705.8 39463.0 39520.6 39778.4 40099.4 41001.4 33872.3 35991.0 39205.4 39513.8 39896.2 32370.8 37514.8 37527.6 38687.6 38971.2 33487.2 34521.3 34756.7 34902.0 35171.6 19880.7 24000.6 24208.8 24268.2 24559.8 09:03:58 INFO - PROCESS | 1636 | 09:03:58 INFO - PROCESS | 1636 | [#2] jquery.html Cycles:180 Average:40618.80 Median:21266.68 stddev:123810.86 (582.2%) stddev-sans-first:124121.21 09:03:58 INFO - PROCESS | 1636 | Values: 324.8 385.2 392.2 399.2 414.3 18947.2 20766.0 20952.6 20993.0 21101.5 20526.4 20617.7 21315.6 21499.8 21856.6 5639.2 5710.9 5758.3 5841.5 5881.8 3799.8 4203.2 5744.2 5796.5 5882.5 4355.3 4371.3 5880.9 6513.2 6547.4 5690.5 6590.1 6599.9 6603.4 6623.8 5738.3 5813.9 5926.9 5938.3 5952.3 13518.4 15953.9 20443.5 24964.4 25264.1 2492.8 2608.4 2635.1 2641.5 2667.9 10139.2 10220.2 10228.6 10634.7 10718.0 1816.8 1839.9 1903.7 1905.2 1914.5 121589.2 129319.4 131649.0 132706.0 132984.3 10117.6 11587.8 12030.3 12051.8 12052.3 12607.6 13447.7 14461.8 15588.7 15623.4 8466.5 8656.9 8658.7 8761.3 8801.0 8346.1 8377.7 8428.6 8554.7 8569.4 4743.6 4821.2 4829.2 4832.9 4916.8 2043.6 2062.7 2064.2 2065.3 2067.8 892.0 974.7 1007.4 1010.9 1024.9 21217.8 21810.0 21928.0 21949.1 22531.7 1027.2 1028.4 1029.5 1074.9 1079.0 11302.5 11572.0 11621.9 11626.3 11636.5 730434.0 737476.0 740434.0 744261.0 757665.0 153263.0 156166.0 158833.2 163785.0 166690.0 1008.7 1008.9 1033.6 1038.7 1040.1 19323.7 19387.0 19594.7 19596.2 19640.4 59396.7 61851.4 62616.7 63014.4 63764.4 67566.0 86454.7 89448.0 91751.0 91780.0 8155.0 8160.5 8268.4 8312.0 8539.2 7664.5 8989.7 11783.1 12131.3 12134.9 11095.8 11407.9 11455.9 11496.9 11593.4 11092.8 11328.0 11569.7 11775.5 11814.7 8951.3 10059.9 10577.4 11389.1 11558.1 6713.4 8387.6 8521.8 8551.9 8688.3 10289.8 11335.3 11433.9 11575.4 11622.5 09:03:58 INFO - PROCESS | 1636 | 09:03:58 INFO - PROCESS | 1636 | [#3] mootools.html Cycles:180 Average:5450.03 Median:5110.21 stddev:3015.30 (59.0%) stddev-sans-first:3008.62 09:03:58 INFO - PROCESS | 1636 | Values: 1428.8 1440.7 1448.8 1455.8 1457.7 7570.6 7827.5 8213.2 8589.8 8610.1 8065.1 8297.9 8338.6 8443.9 8476.3 1956.9 1962.8 1998.6 2318.7 2344.6 2342.2 2370.5 2372.6 2372.9 2376.7 2324.9 2330.8 2354.1 2363.9 2373.2 2314.7 2318.2 2340.5 2348.2 2357.8 2353.6 2363.4 2372.7 2379.4 2380.7 7302.7 8331.3 8498.0 8533.0 8533.4 4515.8 5103.4 5222.6 5251.9 5254.1 3333.6 3427.9 3574.6 3590.4 3594.6 1175.6 1226.4 1242.6 1246.5 1254.1 10980.7 11059.6 11138.8 11139.7 11160.3 4413.1 4712.9 4727.5 4813.5 4827.2 9597.6 9627.0 9656.8 9659.4 9678.2 5057.5 5140.2 5158.6 5173.4 5282.2 4148.2 4688.7 4703.5 4799.7 4844.1 3791.6 3843.0 3870.6 3913.3 3927.7 1002.3 1261.9 1274.1 1277.8 1288.1 673.8 786.9 898.3 906.1 927.6 4736.5 5146.0 5611.9 5686.7 5934.4 878.6 940.0 985.5 998.2 1030.0 3722.8 3731.9 3739.8 3759.2 3818.9 9993.4 10309.6 10330.6 10333.1 10343.1 10157.3 10170.7 10218.4 10285.8 10313.8 7716.2 7734.0 7882.0 7957.9 7992.0 5538.6 5553.4 5597.0 5701.4 5746.2 9232.9 9317.8 9361.4 9395.4 9458.0 9507.2 9521.9 9567.5 9575.6 9665.3 6359.6 6542.5 6627.6 6784.5 6922.0 7113.7 8107.1 8114.6 8136.2 8150.9 7097.2 7165.0 7226.7 7269.7 7340.4 6028.0 6233.3 7124.3 7150.5 7249.7 5805.1 6085.8 6379.5 6496.4 6525.7 4096.5 4559.3 4893.8 4958.9 4987.0 4367.4 5088.2 5117.0 5144.6 5157.7 09:03:58 INFO - PROCESS | 1636 | 09:03:58 INFO - PROCESS | 1636 | [#4] prototype.html Cycles:180 Average:4488.48 Median:5086.37 stddev:1378.70 (27.1%) stddev-sans-first:1345.72 09:03:58 INFO - PROCESS | 1636 | Values: 269.9 354.0 357.3 367.0 373.0 4641.4 5250.8 5402.6 5493.1 5498.8 4334.7 4735.8 5148.4 5347.5 5367.4 4163.8 4224.6 4227.5 4237.2 4237.8 2796.7 3676.4 4141.3 4228.2 4262.5 3785.8 3796.9 4030.0 4033.0 4036.0 4005.0 4015.5 4021.6 4042.4 4046.0 3131.7 3533.7 3539.2 3552.2 3579.4 3781.8 4572.3 4784.2 5096.0 5188.0 4077.9 4253.9 4394.7 4581.8 4625.5 4780.5 5591.5 5616.6 5673.4 5722.5 3703.1 3805.7 3841.8 3852.7 3878.6 5318.2 5431.9 5463.5 6006.5 6044.6 3211.6 3471.7 3913.3 3965.9 4012.0 3392.8 3768.5 3977.7 4019.7 4047.6 4480.8 4561.4 4586.4 4611.4 4619.2 5623.4 5669.7 5688.1 5691.0 5729.3 1064.4 1247.4 1262.7 1290.0 1291.0 862.6 871.8 875.2 877.9 880.6 4654.0 5086.8 5106.6 5118.5 5187.7 4478.1 4490.7 5375.4 5424.2 5497.2 4571.0 4736.3 5329.1 5343.5 5374.4 2673.4 2863.7 2897.5 2928.6 2949.4 5117.5 5275.2 5367.5 5804.6 5877.5 4899.5 5685.3 5739.7 5775.3 5796.7 4063.9 5078.8 5229.5 5388.3 5462.3 4913.7 5381.4 5594.8 5655.7 5770.9 5340.6 5797.9 5819.4 5830.4 5844.5 5604.5 5732.3 5771.5 6082.9 6091.7 3817.9 3892.9 3907.7 3920.2 3968.7 5085.9 5716.1 5831.0 5873.7 5873.8 4972.7 5346.2 5622.4 5651.7 5653.7 5349.9 5397.0 5562.4 5579.6 5661.4 4471.0 5145.3 5295.9 5475.5 5578.6 5403.1 5580.8 5592.5 5613.5 5726.9 4715.7 4815.8 5162.9 5307.7 5538.8 09:03:58 INFO - PROCESS | 1636 | 09:03:58 INFO - PROCESS | 1636 | [#5] yui.html Cycles:180 Average:19286.22 Median:3711.23 stddev:27066.40 (729.3%) stddev-sans-first:27106.31 09:03:58 INFO - PROCESS | 1636 | Values: 690.2 692.9 748.5 807.5 854.2 14651.6 15640.5 15642.8 15645.5 15686.5 11804.6 14172.0 14380.7 15777.6 15788.9 817.9 836.9 839.4 839.5 840.7 836.7 839.7 839.7 841.7 843.1 885.6 886.6 888.5 888.7 889.3 878.2 881.4 882.5 883.7 884.7 658.0 658.1 658.1 659.7 660.3 15194.8 15291.9 15293.2 15363.6 15380.7 12827.6 13573.5 14282.4 14386.5 14479.6 10093.5 10782.2 11261.2 11570.5 11604.3 3654.8 3690.0 3725.0 3764.0 3860.4 100393.5 104541.5 108377.0 108532.5 109176.0 10472.9 10938.7 11023.0 11091.6 11335.4 23283.7 23302.8 23304.8 23320.5 23378.5 7341.4 7432.4 7837.3 7888.2 8034.0 4178.0 4260.3 4261.5 4271.7 4307.3 3623.8 3688.7 3691.9 3694.6 3697.5 650.9 656.2 661.0 670.0 675.2 631.4 663.8 676.3 678.7 689.8 9440.3 9811.0 9844.0 10002.3 10181.7 645.6 678.3 683.8 688.2 690.6 4813.5 4947.0 4960.6 4961.3 4985.7 83973.2 84340.0 103481.0 104884.5 107254.0 69021.0 69873.7 74440.9 74925.3 90194.0 49148.0 61112.0 62184.4 65349.4 65854.3 10244.6 10257.9 10414.3 10447.7 10529.4 43579.0 47797.5 48815.8 48925.8 50229.7 49412.5 61726.0 65937.7 66106.0 66773.3 11309.5 12499.7 12714.7 12874.5 13347.4 14436.0 14459.7 14477.0 14930.8 14989.3 13551.4 13582.1 13596.7 13605.0 14088.2 13593.3 13629.4 13688.4 13922.7 14054.9 12751.7 13233.3 13411.9 13429.8 13854.9 10439.5 10985.7 11224.5 11473.0 11771.6 8647.4 10151.3 10287.5 10450.4 10573.0 09:03:58 INFO - PROCESS | 1636 | -------- Summary: end -------- 09:03:58 INFO - PROCESS | 1636 | 09:03:59 INFO - TEST-INFO | 1636: exit 0 09:03:59 INFO - TEST-OK | dromaeo_css | took 1117103ms 09:03:59 INFO - TEST-START | kraken 09:03:59 INFO - operating with platform_type : mac_ 09:03:59 INFO - Initialising browser for kraken test... 09:03:59 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/tmpTVfiSZ/profile http://localhost:49236/getInfo.html) 09:04:02 INFO - PROCESS | 1647 | 2017-01-11 09:04:02.487 plugin-container[1648:10040] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x444b, name = 'com.apple.tsm.portname' 09:04:02 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:04:02 INFO - PROCESS | 1647 | 2017-01-11 09:04:02.490 plugin-container[1648:10040] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8837, name = 'com.apple.CFPasteboardClient' 09:04:02 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:04:02 INFO - PROCESS | 1647 | 2017-01-11 09:04:02.490 plugin-container[1648:10040] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:04:02 INFO - PROCESS | 1647 | __metrics Screen width/height:1600/1200 09:04:02 INFO - PROCESS | 1647 | colorDepth:24 09:04:02 INFO - PROCESS | 1647 | Browser inner width/height: 1024/689 09:04:02 INFO - PROCESS | 1647 | __metrics 09:04:02 ERROR - PROCESS | 1647 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3242: TypeError: this.tabs is undefined 09:04:03 INFO - TEST-INFO | 1647: exit 0 09:04:03 INFO - Browser initialized. 09:04:03 INFO - Running cycle 1/1 for kraken test... 09:04:03 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/tmpTVfiSZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:04:05 INFO - PROCESS | 1649 | 2017-01-11 09:04:05.903 plugin-container[1650:10150] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883b, name = 'com.apple.tsm.portname' 09:04:05 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:04:05 INFO - PROCESS | 1649 | 2017-01-11 09:04:05.904 plugin-container[1650:10150] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0b, name = 'com.apple.CFPasteboardClient' 09:04:05 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:04:05 INFO - PROCESS | 1649 | 2017-01-11 09:04:05.904 plugin-container[1650:10150] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:04:15 INFO - PROCESS | 1649 | RSS: Main: 233213952 09:04:15 INFO - PROCESS | 1649 | 09:04:18 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-beat-detection) 09:04:19 INFO - PROCESS | 1649 | RSS: Main: 246079488 09:04:19 INFO - PROCESS | 1649 | 09:04:22 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-dft) 09:04:22 INFO - PROCESS | 1649 | RSS: Main: 253730816 09:04:22 INFO - PROCESS | 1649 | 09:04:26 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-fft) 09:04:27 INFO - PROCESS | 1649 | RSS: Main: 256512000 09:04:27 INFO - PROCESS | 1649 | 09:04:29 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-oscillator) 09:04:29 INFO - PROCESS | 1649 | RSS: Main: 258641920 09:04:29 INFO - PROCESS | 1649 | 09:04:31 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:04:32 INFO - PROCESS | 1649 | RSS: Main: 258883584 09:04:32 INFO - PROCESS | 1649 | 09:04:35 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-darkroom) 09:04:35 INFO - PROCESS | 1649 | RSS: Main: 265048064 09:04:35 INFO - PROCESS | 1649 | 09:04:38 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-desaturate) 09:04:39 INFO - PROCESS | 1649 | RSS: Main: 263815168 09:04:39 INFO - PROCESS | 1649 | 09:04:42 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49236/tests/kraken/driver.html?testName=json-parse-financial) 09:04:43 INFO - PROCESS | 1649 | RSS: Main: 263749632 09:04:43 INFO - PROCESS | 1649 | 09:04:44 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49236/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:04:45 INFO - PROCESS | 1649 | RSS: Main: 263131136 09:04:45 INFO - PROCESS | 1649 | 09:04:47 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:04:48 INFO - PROCESS | 1649 | RSS: Main: 263131136 09:04:48 INFO - PROCESS | 1649 | 09:04:50 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:04:51 INFO - PROCESS | 1649 | RSS: Main: 263143424 09:04:51 INFO - PROCESS | 1649 | 09:04:54 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:04:55 INFO - PROCESS | 1649 | RSS: Main: 263421952 09:04:55 INFO - PROCESS | 1649 | 09:04:57 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:04:58 INFO - PROCESS | 1649 | RSS: Main: 263356416 09:04:58 INFO - PROCESS | 1649 | 09:05:00 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49236/tests/kraken/driver.html?testName=ai-astar) 09:05:00 INFO - PROCESS | 1649 | RSS: Main: 265175040 09:05:00 INFO - PROCESS | 1649 | 09:05:00 INFO - PROCESS | 1649 | __start_tp_report 09:05:00 INFO - PROCESS | 1649 | _x_x_mozilla_page_load 09:05:00 INFO - PROCESS | 1649 | _x_x_mozilla_page_load_details 09:05:00 INFO - PROCESS | 1649 | |i|pagename|runs| 09:05:00 INFO - PROCESS | 1649 | |0;ai-astar;116;117;177;160;120;121;122;165;173;157 09:05:00 INFO - PROCESS | 1649 | |1;audio-beat-detection;148;115;116;149;114;111;112;116;129;118 09:05:00 INFO - PROCESS | 1649 | |2;audio-dft;210;241;201;203;200;225;278;197;200;197 09:05:00 INFO - PROCESS | 1649 | |3;audio-fft;83;58;60;88;61;61;62;61;77;59 09:05:00 INFO - PROCESS | 1649 | |4;audio-oscillator;91;71;85;73;73;72;88;85;95;112 09:05:00 INFO - PROCESS | 1649 | |5;imaging-gaussian-blur;92;90;92;91;106;90;94;92;89;122 09:05:00 INFO - PROCESS | 1649 | |6;imaging-darkroom;103;103;102;100;104;104;101;102;134;116 09:05:00 INFO - PROCESS | 1649 | |7;imaging-desaturate;117;146;101;110;123;93;142;143;93;92 09:05:00 INFO - PROCESS | 1649 | |8;json-parse-financial;63;61;65;63;63;64;64;62;64;64 09:05:00 INFO - PROCESS | 1649 | |9;json-stringify-tinderbox;59;100;66;91;104;99;99;101;69;73 09:05:00 INFO - PROCESS | 1649 | |10;stanford-crypto-aes;102;85;90;94;86;95;114;108;89;88 09:05:00 INFO - PROCESS | 1649 | |11;stanford-crypto-ccm;143;144;141;138;154;153;160;169;194;154 09:05:00 INFO - PROCESS | 1649 | |12;stanford-crypto-pbkdf2;169;161;175;183;172;169;166;194;169;235 09:05:00 INFO - PROCESS | 1649 | |13;stanford-crypto-sha256-iterative;68;74;74;68;75;67;75;79;86;94 09:05:00 INFO - PROCESS | 1649 | __end_tp_report 09:05:00 INFO - PROCESS | 1649 | __start_cc_report 09:05:00 INFO - PROCESS | 1649 | _x_x_mozilla_cycle_collect,948 09:05:00 INFO - PROCESS | 1649 | __end_cc_report 09:05:00 INFO - PROCESS | 1649 | __startTimestamp1484154300712__endTimestamp 09:05:00 INFO - PROCESS | 1649 | 09:05:00 INFO - PROCESS | 1649 | ------- Summary: start ------- 09:05:00 INFO - PROCESS | 1649 | Number of tests: 14 09:05:00 INFO - PROCESS | 1649 | 09:05:00 INFO - PROCESS | 1649 | [#0] ai-astar Cycles:10 Average:142.80 Median:158.50 stddev:25.57 (16.1%) stddev-sans-first:25.21 09:05:00 INFO - PROCESS | 1649 | Values: 116.0 117.0 177.0 160.0 120.0 121.0 122.0 165.0 173.0 157.0 09:05:00 INFO - PROCESS | 1649 | 09:05:00 INFO - PROCESS | 1649 | [#1] audio-beat-detection Cycles:10 Average:122.80 Median:117.00 stddev:14.41 (12.3%) stddev-sans-first:12.06 09:05:00 INFO - PROCESS | 1649 | Values: 148.0 115.0 116.0 149.0 114.0 111.0 112.0 116.0 129.0 118.0 09:05:00 INFO - PROCESS | 1649 | 09:05:00 INFO - PROCESS | 1649 | [#2] audio-dft Cycles:10 Average:215.20 Median:206.50 stddev:26.26 (12.7%) stddev-sans-first:27.79 09:05:00 INFO - PROCESS | 1649 | Values: 210.0 241.0 201.0 203.0 200.0 225.0 278.0 197.0 200.0 197.0 09:05:00 INFO - PROCESS | 1649 | 09:05:00 INFO - PROCESS | 1649 | [#3] audio-fft Cycles:10 Average:67.00 Median:61.50 stddev:11.18 (18.2%) stddev-sans-first:10.24 09:05:00 INFO - PROCESS | 1649 | Values: 83.0 58.0 60.0 88.0 61.0 61.0 62.0 61.0 77.0 59.0 09:05:00 INFO - PROCESS | 1649 | 09:05:00 INFO - PROCESS | 1649 | [#4] audio-oscillator Cycles:10 Average:84.50 Median:85.00 stddev:13.01 (15.3%) stddev-sans-first:13.59 09:05:00 INFO - PROCESS | 1649 | Values: 91.0 71.0 85.0 73.0 73.0 72.0 88.0 85.0 95.0 112.0 09:05:00 INFO - PROCESS | 1649 | 09:05:00 INFO - PROCESS | 1649 | [#5] imaging-gaussian-blur Cycles:10 Average:95.80 Median:91.50 stddev:10.40 (11.4%) stddev-sans-first:10.94 09:05:00 INFO - PROCESS | 1649 | Values: 92.0 90.0 92.0 91.0 106.0 90.0 94.0 92.0 89.0 122.0 09:05:00 INFO - PROCESS | 1649 | 09:05:00 INFO - PROCESS | 1649 | [#6] imaging-darkroom Cycles:10 Average:106.90 Median:103.50 stddev:10.51 (10.2%) stddev-sans-first:11.06 09:05:00 INFO - PROCESS | 1649 | Values: 103.0 103.0 102.0 100.0 104.0 104.0 101.0 102.0 134.0 116.0 09:05:00 INFO - PROCESS | 1649 | 09:05:00 INFO - PROCESS | 1649 | [#7] imaging-desaturate Cycles:10 Average:116.00 Median:144.50 stddev:21.73 (15.0%) stddev-sans-first:23.05 09:05:00 INFO - PROCESS | 1649 | Values: 117.0 146.0 101.0 110.0 123.0 93.0 142.0 143.0 93.0 92.0 09:05:00 INFO - PROCESS | 1649 | 09:05:00 INFO - PROCESS | 1649 | [#8] json-parse-financial Cycles:10 Average:63.30 Median:64.00 stddev:1.16 (1.8%) stddev-sans-first:1.22 09:05:00 INFO - PROCESS | 1649 | Values: 63.0 61.0 65.0 63.0 63.0 64.0 64.0 62.0 64.0 64.0 09:05:00 INFO - PROCESS | 1649 | 09:05:00 INFO - PROCESS | 1649 | [#9] json-stringify-tinderbox Cycles:10 Average:86.10 Median:71.00 stddev:17.30 (24.4%) stddev-sans-first:15.33 09:05:00 INFO - PROCESS | 1649 | Values: 59.0 100.0 66.0 91.0 104.0 99.0 99.0 101.0 69.0 73.0 09:05:00 INFO - PROCESS | 1649 | 09:05:00 INFO - PROCESS | 1649 | [#10] stanford-crypto-aes Cycles:10 Average:95.10 Median:88.50 stddev:9.84 (11.1%) stddev-sans-first:10.11 09:05:00 INFO - PROCESS | 1649 | Values: 102.0 85.0 90.0 94.0 86.0 95.0 114.0 108.0 89.0 88.0 09:05:00 INFO - PROCESS | 1649 | 09:05:00 INFO - PROCESS | 1649 | [#11] stanford-crypto-ccm Cycles:10 Average:155.00 Median:154.00 stddev:16.66 (10.8%) stddev-sans-first:17.10 09:05:00 INFO - PROCESS | 1649 | Values: 143.0 144.0 141.0 138.0 154.0 153.0 160.0 169.0 194.0 154.0 09:05:00 INFO - PROCESS | 1649 | 09:05:00 INFO - PROCESS | 1649 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.30 Median:173.50 stddev:21.69 (12.5%) stddev-sans-first:22.68 09:05:00 INFO - PROCESS | 1649 | Values: 169.0 161.0 175.0 183.0 172.0 169.0 166.0 194.0 169.0 235.0 09:05:00 INFO - PROCESS | 1649 | 09:05:00 INFO - PROCESS | 1649 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.00 Median:75.00 stddev:8.51 (11.3%) stddev-sans-first:8.52 09:05:00 INFO - PROCESS | 1649 | Values: 68.0 74.0 74.0 68.0 75.0 67.0 75.0 79.0 86.0 94.0 09:05:00 INFO - PROCESS | 1649 | -------- Summary: end -------- 09:05:00 INFO - PROCESS | 1649 | 09:05:01 INFO - TEST-INFO | 1649: exit 0 09:05:01 INFO - TEST-OK | kraken | took 61972ms 09:05:01 INFO - SUITE-END | took 1179s 09:05:01 INFO - Completed test suite (00:19:39) 09:05:01 INFO - javascript benchmark 09:05:01 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7276.020339041167, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2651.403434227299, 3035.7419619332354, 3290.9177270568216, 3317.518959750836, 3358.748093892957, 5538.9169162462595, 5546.5008149266605, 5558.138488534279, 5558.666480011202, 5647.01177293974, 5570.637908535942, 5607.803726869561, 5686.943130568703, 5714.285714285715, 5767.538596912247, 4263.914721705564, 5080.720560369178, 5165.405978312486, 5259.079661059319, 5265.341832270966, 5081.364829396325, 5236.31927849379, 5279.102552566073, 5287.259783630295, 5340.439253878345, 5045.5459008689395, 5089.974550127263, 5196.610254230929, 5206.453322401131, 5228.895422091575, 5073.213651883964, 5117.283580298761, 5172.172452407597, 5191.688498690053, 5250.0, 4791.233402655558, 4840.733759643218, 5100.030994111107, 5119.923201151985, 5137.5633071188995, 5453.836384908459, 5572.721363931787, 5616.0171969905105, 5650.971745141289, 5679.261695979496, 6046.3651316612, 6110.224634763485, 6113.5371179039, 6114.021756518936, 6125.662799505643, 6242.875142497124, 6243.2508099027955, 6253.187085678878, 6257.2804127525005, 6293.244810622708, 5819.94180058194, 5833.912491312633, 5845.298564172284, 5851.297844258674, 5891.46976772084, 5106.4382917879, 6113.694315284218, 6136.8772624548155, 6163.414475624809, 6168.0, 4689.648276379286, 4887.755612219375, 5342.98483288174, 5972.343042265347, 6055.303640081359, 6123.540734444845, 6191.442770150708, 6227.782027629011, 6229.408206220402, 6261.373862613702, 5951.226340575698, 5956.6723830188885, 6030.819075427744, 6090.969545152246, 6126.816195514141, 6137.193127861444, 6216.564840461125, 6229.937700622936, 6235.438810507076, 6247.062940558862, 4888.902221955541, 4975.751212439436, 4981.0, 4988.9501104988485, 5001.149804533238, 4375.409319741858, 4402.559744025636, 4457.064016556559, 4478.619317358061, 4497.100579884036, 5712.714364281769, 5749.108888122264, 5784.4504772046585, 5840.883182336414, 5847.415258474203, 6224.315325314212, 6238.688065596702, 6260.655663938527, 6280.403361680633, 6294.181756371734, 5815.0, 5825.504832089235, 5841.979091026662, 5867.823965281048, 5909.320428150818, 5283.498067683564, 5341.252224688546, 5345.679259244458, 5360.517553420133, 5365.517103460707, 6159.106929495288, 6209.720562574605, 6211.720472578654, 6245.843853903689, 6270.310265870659, 6230.096635987847, 6255.218097737783, 6255.593386429868, 6278.6860656966055, 6289.213848268966, 5989.131575921554, 6053.909191362045, 6231.9688401557705, 6257.186565746606, 6265.154204182377, 6042.0, 6074.119252708421, 6094.786682466092, 6117.1130186123655, 6126.387361263838, 5917.615355001911, 6137.070562331707, 6141.51669281436, 6148.0, 6154.661493617894, 6048.60684055535, 6126.693665316806, 6133.815985520441, 6140.662263575547, 6151.108089326933, 6092.268927728701, 6105.572609917263, 6105.8168254952425, 6113.29697084841, 6133.202683651275, 4568.17772800899, 5769.94230057694, 5800.158976948234, 5859.267591551056, 5869.178315035813, 5973.91039134422, 6067.757289708362, 6068.9089663654195, 6095.116208149882, 6116.8776624468155, 5865.618734782225, 5909.409059094056, 5911.379305173067, 5953.613015154001, 5972.372900845522], "value": 5614.843329281402, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [465.60423639906065, 485.92711093336004, 499.38449117106165, 521.7730287325014, 540.5594440530964, 37704.62295377046, 42244.78877605611, 42350.99999999996, 44514.10971780562, 44520.77739611297, 32756.0, 34561.30877382451, 42685.0, 43166.784166079124, 43172.3524147138, 8382.287505562035, 9263.814723705538, 9498.28762842786, 9506.096920792514, 9523.761905952337, 8491.575421228945, 9851.556679949419, 11169.329840209613, 11400.030997365235, 11410.372429516374, 11362.31826090433, 11367.0, 11377.886221137807, 11380.089592832595, 11384.260023098472, 11336.43317834109, 11394.658160255123, 11399.658010259705, 11416.54333826646, 11432.771344573062, 11306.37814920179, 11359.31844089349, 11361.545538178463, 11367.715807104805, 11379.715507112305, 32509.349813003606, 41104.794476027724, 41201.79399103015, 41297.79351103225, 41854.581454185376, 3911.8239679214503, 3993.6605388542007, 3996.360582306817, 4036.9394459083137, 4074.979625101885, 23968.760312396826, 28488.85755571209, 29131.563026554617, 29146.271343216376, 29215.70784292151, 2764.2260167153026, 3006.293521022558, 3178.55500229968, 3247.626522949867, 3256.902292931216, 461195.0, 465275.0, 467108.0, 467961.0, 468803.0, 57342.11894572678, 57738.71130644404, 57753.71123144357, 57785.0, 57838.7108064457, 59345.40654593399, 60017.69991150016, 60221.795564088476, 60289.0, 60382.0, 15357.462488812838, 15515.84484155144, 15583.14292713889, 15596.84403155954, 15609.843901561066, 10314.0, 10864.022237998619, 11007.174461915229, 11014.944925275482, 12024.75950481003, 17988.149373019107, 18363.90818045928, 21162.047707853184, 21496.0, 21821.0, 7415.740449084256, 8767.167119123673, 8863.0, 8961.193492585568, 8964.955175224082, 806.338802182205, 811.6121432350628, 814.1329484099415, 826.0, 840.7477756672973, 28989.85505072461, 29127.12618621445, 29381.559276610868, 29445.26386840346, 29648.258793530335, 956.5073986896754, 993.438707130469, 993.9006099390003, 1036.108946306176, 1060.2843080920347, 14424.567262982127, 14876.776848347283, 14944.178070206026, 15253.847461525465, 15324.693506129815, 635294.0, 641325.5536056309, 641579.5842041613, 643548.0, 645148.0, 61908.69045654743, 62111.37888621056, 62448.687756560925, 62522.0, 62817.057744133876, 1412.279737333967, 1432.0763107795415, 1448.8261408630822, 1455.927203639835, 1461.167134733213, 24881.751182487944, 26476.8676156618, 26795.196144115707, 27036.0, 27431.725682743716, 53117.7344113277, 53765.924681506935, 53792.0, 53885.730571346896, 53955.73022135021, 483465.0, 553705.0, 553800.0, 554194.0, 555601.0, 29224.853875730485, 35940.10149746173, 41447.96380090522, 43092.784536078376, 43529.78235108804, 45584.77207613941, 47724.761376192895, 48019.75990120027, 48257.5174248253, 48705.75647121741, 39463.01342466461, 39520.604793951694, 39778.40332395074, 40099.39850902297, 41001.38497922474, 33872.32255354928, 35991.0, 39205.41191882067, 39513.802430987664, 39896.2020759589, 32370.83814580912, 37514.81242593769, 37527.624723753506, 38687.61312386952, 38971.22057558889, 33487.16282092967, 34521.30957380788, 34756.65243347534, 34902.0, 35171.648283516835, 19880.70178947288, 24000.63999039981, 24208.75791242065, 24268.150614729107, 24559.772011399], "value": 20236.2775464889, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [324.8257548960508, 385.1680370399937, 392.2292694854611, 399.1976127982754, 414.33912908910276, 18947.242110315587, 20766.0, 20952.580948381023, 20992.95035248237, 21101.472463188427, 20526.384208473733, 20617.690734638956, 21315.57368852622, 21499.785002149973, 21856.562868742614, 5639.15412688096, 5710.857228569288, 5758.280214973129, 5841.503472204868, 5881.764729410818, 3799.8480060797533, 4203.159368126372, 5744.16709577111, 5796.536277097843, 5882.4705776480105, 4355.32492463667, 4371.322445021026, 5880.911786323209, 6513.218413790351, 6547.410733034026, 5690.48785609293, 6590.1432813734145, 6599.93400065998, 6603.405693487561, 6623.768168114093, 5738.340090889559, 5813.941860581424, 5926.940730592725, 5938.317093534256, 5952.255968003999, 13518.391672374668, 15953.920230398773, 20443.48891277715, 24964.3758906027, 25264.11575594864, 2492.8005759539374, 2608.3968667367094, 2635.0645520840017, 2641.524525585394, 2667.9333016674545, 10139.188864890792, 10220.233482488844, 10228.641997530125, 10634.734131646694, 10718.035376816124, 1816.7910690270523, 1839.8620103492292, 1903.7049257365131, 1905.1902941250053, 1914.473519782057, 121589.19340485768, 129319.35340323333, 131649.0, 132706.0, 132984.33507832495, 10117.59529618807, 11587.826182607268, 12030.338331391682, 12051.758964820654, 12052.33712145823, 12607.621771346874, 13447.65523447667, 14461.78307325391, 15588.688226235412, 15623.3750649975, 8466.534340611326, 8656.913430865736, 8658.653653853775, 8761.34289928258, 8801.0, 8346.081930987586, 8377.70678026266, 8428.620712067972, 8554.743357699279, 8569.357298202534, 4743.644226682944, 4821.180399332122, 4829.154897892925, 4832.855014349575, 4916.754162291871, 2043.6014977079624, 2062.7215325930806, 2064.183518503707, 2065.308121779191, 2067.824234940047, 891.9509426981449, 974.6637410093506, 1007.3958816459387, 1010.939343639384, 1024.912882404989, 21217.78782212158, 21810.0, 21928.0, 21949.122035118417, 22531.662025069636, 1027.198784947742, 1028.3829702178782, 1029.5212726082407, 1074.8763892152504, 1078.962236321725, 11302.491387887565, 11572.016378607912, 11621.941890290494, 11626.302421854716, 11636.534538618529, 730434.0, 737476.0, 740434.0, 744261.0, 757665.0, 153263.0, 156166.0, 158833.15185234824, 163785.0, 166690.0, 1008.7276435362411, 1008.8587597736323, 1033.5917312661607, 1038.6624347087227, 1040.105509262034, 19323.710144348126, 19387.030648467804, 19594.706079409105, 19596.216151354358, 19640.410787676392, 59396.703016484644, 61851.38148618456, 62616.747665045536, 63014.369856302685, 63764.362356375845, 67565.98651020337, 86454.7031794511, 89448.0, 91751.0, 91780.0, 8155.0, 8160.5103693778565, 8268.37987150954, 8311.95844020776, 8539.188777066169, 7664.4634875560405, 8989.730308090768, 11783.116266279892, 12131.272123672608, 12134.939325303318, 11095.778084438105, 11407.885921141014, 11455.94272028668, 11496.94251528737, 11593.362365070003, 11092.778144437227, 11328.03711684516, 11569.7107572308, 11775.470103845519, 11814.704632384259, 8951.34400135977, 10059.949700251453, 10577.418241996456, 11389.145814064144, 11558.133140014364, 6713.395794378529, 8387.622556984828, 8521.82956340882, 8551.914480855112, 8688.30493560501, 10289.794204116026, 11335.263207891461, 11433.942830285796, 11575.363355015555, 11622.489076420012], "value": 9857.067111986727, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1428.7772428970993, 1440.7334643091035, 1448.8333841608219, 1455.788910607962, 1457.701171259892, 7570.583617901013, 7827.530348179099, 8213.178682131784, 8589.785255368612, 8610.052894181636, 8065.072516660586, 8297.875531867012, 8338.624761885714, 8443.873341899862, 8476.32189424846, 1956.9217231310731, 1962.8233458988689, 1998.5503261766132, 2318.6637937499054, 2344.554534638422, 2342.2153578551206, 2370.4547953970596, 2372.596658568039, 2372.8932198051043, 2376.7266764322153, 2324.93025209244, 2330.82518811089, 2354.081908055851, 2363.9290821275304, 2373.1931143411325, 2314.6528020797014, 2318.1538738360546, 2340.5435939991708, 2348.213348528245, 2357.8113750899975, 2353.64695295707, 2363.3855197648563, 2372.6796882420826, 2379.4289370551114, 2380.726216485109, 7302.707891684327, 8331.250187483156, 8498.022727386367, 8532.957335213347, 8533.402661813614, 4515.7516336601475, 5103.438621751604, 5222.556082732935, 5251.921221181686, 5254.0805359062015, 3333.583302087239, 3427.8971630851115, 3574.571051473814, 3590.425531914881, 3594.6045934947133, 1175.6414293640437, 1226.3745489800176, 1242.5961562492225, 1246.5001345144024, 1254.084518301645, 10980.670579882617, 11059.612913547988, 11138.832917506244, 11139.665810025712, 11160.274582152135, 4413.13943780966, 4712.858614241578, 4727.479977202505, 4813.470518242991, 4827.179379505493, 9597.616095356108, 9626.95186524077, 9656.806863862825, 9659.372140810825, 9678.225741940629, 5057.468965758542, 5140.151874940668, 5158.613104017214, 5173.3533308336455, 5282.207668849704, 4148.23257697327, 4688.718676879398, 4703.506131856174, 4799.712017278975, 4844.128056949783, 3791.563970143414, 3842.9807850961124, 3870.59358767331, 3913.2760439318818, 3927.6857851371824, 1002.3284399452384, 1261.8548866880242, 1274.133589159362, 1277.7955527115803, 1288.0661520397823, 673.7715329046537, 786.8701664225339, 898.3172788680649, 906.1029580715044, 927.5640448988964, 4736.455307639597, 5146.0, 5611.887762244733, 5686.658800471985, 5934.406559344031, 878.5519385113638, 939.9530023498935, 985.5466485416645, 998.1715176403568, 1029.9639512617023, 3722.813859307024, 3731.8728324854574, 3739.775613463201, 3759.2293579816073, 3818.9236215276096, 9993.350432221881, 10309.639162629423, 10330.638427654996, 10333.121684656739, 10343.120834728981, 10157.339772914886, 10170.745731356626, 10218.437985910847, 10285.845712314322, 10313.793724125475, 7716.228377162239, 7733.961330193313, 7881.960590197013, 7957.880631790527, 7992.000999875016, 5538.6122971391615, 5553.361363443256, 5597.0, 5701.372848986525, 5746.197223613726, 9232.907670923474, 9317.813643726951, 9361.425607957866, 9395.436273823592, 9458.0, 9507.191888689262, 9521.904780952378, 9567.473788941676, 9575.616975321187, 9665.323427360017, 6359.554831161774, 6542.509311801538, 6627.635480048643, 6784.491163162684, 6922.0, 7113.679884405111, 8107.1082180958865, 8114.634841432267, 8136.22705842944, 8150.877736834071, 7097.219305876246, 7164.9641751790905, 7226.74706385274, 7269.672864721206, 7340.412766978522, 6027.969860150846, 6233.252009758677, 7124.251953544803, 7150.463715221275, 7249.673764680708, 5805.129230615442, 6085.813266413007, 6379.4896408286295, 6496.382843629847, 6525.738970441319, 4096.487939007624, 4559.293309537027, 4893.828715994923, 4958.8760280993265, 4986.975065124651, 4367.432233809584, 5088.211327244283, 5116.9744151279, 5144.562712169508, 5157.716325602128], "value": 4418.070934014502, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [269.91092939330025, 353.9504469374288, 357.2889949001487, 366.95963444021163, 372.95897451280354, 4641.373414589029, 5250.842474725755, 5402.567794576435, 5493.148561972896, 5498.790266141453, 4334.696571240014, 4735.786889589968, 5148.407933087696, 5347.518723314901, 5367.35591728993, 4163.812628431712, 4224.577542245782, 4227.471566054243, 4237.216115018731, 4237.8304867805255, 2796.720327967207, 3676.419139769898, 4141.295979683446, 4228.238916994939, 4262.509811371701, 3785.8107094645156, 3796.8609417174875, 4029.9597004030024, 4032.9917520619847, 4036.0, 4004.979975100135, 4015.518137823465, 4021.577734337911, 4042.393640953851, 4046.0, 3131.7181453669277, 3533.6643018913164, 3539.150603855056, 3552.2007548301685, 3579.355715971124, 3781.7920014399197, 4572.337011133401, 4784.249056792323, 5095.974520127375, 5188.0, 4077.8572749953905, 4253.851115210984, 4394.736315821061, 4581.839635612738, 4625.468071171825, 4780.450248221464, 5591.524720398771, 5616.634918730269, 5673.432656734335, 5722.484976352148, 3703.148275896559, 3805.714571407128, 3841.8271177797064, 3852.7303088783797, 3878.5733569307354, 5318.175682769138, 5431.87667091424, 5463.52572201942, 6006.459418652298, 6044.60973975988, 3211.582494275729, 3471.687548120681, 3913.3347330953807, 3965.8810235692977, 4012.0, 3392.796432214075, 3768.510093687803, 3977.7016723745833, 4019.718619696593, 4047.5952404759287, 4480.7535585542455, 4561.407017087758, 4586.4266966629175, 4611.377464042312, 4619.214733495314, 5623.437656234426, 5669.659820410789, 5688.11834165705, 5691.03252447085, 5729.28383952006, 1064.4411683866033, 1247.4137155537064, 1262.6994195978157, 1289.9613011609667, 1290.9741805163844, 862.6032025268447, 871.7515508080166, 875.2472873328932, 877.9122087791297, 880.5553195636163, 4654.0, 5086.847394578169, 5106.629624447846, 5118.462561431071, 5187.688738675692, 4478.149151661174, 4490.685652004328, 5375.408705042442, 5424.159255315432, 5497.19642982074, 4571.0, 4736.3369128322065, 5329.067413202752, 5343.519083282523, 5374.408815030343, 2673.4118493931305, 2863.6993115722967, 2897.463969165711, 2928.633920759905, 2949.3658863344485, 5117.52216810291, 5275.208718692227, 5367.463253674679, 5804.622699524602, 5877.500412464903, 4899.501254360302, 5685.260916080883, 5739.651181972233, 5775.27809023872, 5796.681182535001, 4063.878083657495, 5078.771455284447, 5229.450907654642, 5388.299521062237, 5462.262594549685, 4913.680610760289, 5381.434949330421, 5594.762095161805, 5655.699189186428, 5770.884582308414, 5340.572754179734, 5797.9420205797405, 5819.4180581943165, 5830.446107619939, 5844.532437404912, 5604.467575580314, 5732.34078081026, 5771.480566749013, 6082.878342433038, 6091.715606647507, 3817.9045523861014, 3892.929444402812, 3907.706921980806, 3920.176762879713, 3968.682505399619, 5085.898282034413, 5716.056850619768, 5830.96789669378, 5873.676947767914, 5873.765049397976, 4972.701637901738, 5346.193248544545, 5622.437756224344, 5651.660900345993, 5653.717314134195, 5349.893002140013, 5396.97457483077, 5562.3881373048125, 5579.609427340048, 5661.433856614306, 4470.955290447054, 5145.305383773143, 5295.867603309948, 5475.452454754652, 5578.553715702815, 5403.05490023599, 5580.77676892936, 5592.468715472024, 5613.522850557748, 5726.914096288476, 4715.740634265011, 4815.831445899376, 5162.915787684478, 5307.734613269398, 5538.750756215899], "value": 4058.718687489365, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [690.1614538335924, 692.9272426395231, 748.4798065344585, 807.5154907055767, 854.2055888024139, 14651.560453186408, 15640.530784076482, 15642.84357156428, 15645.530634080968, 15686.529404117866, 11804.645860624174, 14172.0, 14380.712385752278, 15777.60555986101, 15788.921055394736, 817.9427440079182, 836.9414141010132, 839.4249938791917, 839.5172775653992, 840.7351684219482, 836.6820608168887, 839.6515446089866, 839.7061028639988, 841.7054031089131, 843.1442086282428, 885.583775625455, 886.5611522296472, 888.4802390601506, 888.7289376740124, 889.3374436045156, 878.1657425445788, 881.3742243007472, 882.496976723268, 883.6597909804707, 884.7390019944099, 657.9638119903403, 658.0524045374646, 658.098405184895, 659.6965395917883, 660.3330636057616, 15194.848051519564, 15291.923540382228, 15293.158876261801, 15363.615909602238, 15380.692386152214, 12827.551035713705, 13573.524926627619, 14282.3572939218, 14386.496472623512, 14479.565613031626, 10093.545790439448, 10782.245242833005, 11261.211715179941, 11570.479328430156, 11604.30374177552, 3654.8355324010217, 3689.9631003689888, 3725.0, 3763.9623603764157, 3860.4402361657685, 100393.49803250936, 104541.47729261305, 108377.0, 108532.4573377136, 109176.0, 10472.895271047344, 10938.726531836608, 11023.0, 11091.611793587186, 11335.376554289429, 23283.650745238836, 23302.7669723304, 23304.7669523306, 23320.53358932812, 23378.53242935132, 7341.412686985135, 7432.405407567489, 7837.333826624801, 7888.171741967011, 8033.959830200928, 4177.958220417818, 4260.2970509866145, 4261.488621365456, 4271.67962402821, 4307.289297265978, 3623.7644553103964, 3688.7233457490797, 3691.9446208306895, 3694.556653201633, 3697.4638677391627, 650.9218893732688, 656.2354856592112, 660.9669516524154, 669.9732010719516, 675.2437270257343, 631.3717850738544, 663.7676813115371, 676.3202981004059, 678.6878036103349, 689.7965100295424, 9440.339176257596, 9810.950945245228, 9843.950780246052, 10002.299839011344, 10181.694549163536, 645.5577929118568, 678.3216783216783, 683.8427161752825, 688.1673175457652, 690.6118701410102, 4813.470518242991, 4946.950530494649, 4960.578350840146, 4961.305417241589, 4985.651004429655, 83973.16026839775, 84340.0, 103481.0, 104884.47557762162, 107254.0, 69021.0, 69873.65063174651, 74440.88338674924, 74925.25074749292, 90194.0, 49148.0, 61112.0, 62184.378156218765, 65349.40578268146, 65854.34145658482, 10244.641437549948, 10257.93356106726, 10414.323069000491, 10447.738806529898, 10529.42088185157, 43579.0, 47797.522024779304, 48815.75592122017, 48925.77685557889, 50229.74885125551, 49412.50587494079, 61726.0, 65937.68124637385, 66106.0, 66773.3322666767, 11309.491072901903, 12499.687507812014, 12714.745705086032, 12874.485020599072, 13347.399367028702, 14436.018343781605, 14459.710805783616, 14476.986610937134, 14930.77603835965, 14989.325480353193, 13551.390187441786, 13582.117162384413, 13596.66008349799, 13605.0, 14088.15471071739, 13593.32033398346, 13629.38667759973, 13688.384022718803, 13922.72154556883, 14054.859451405355, 12751.74496510046, 13233.338333083115, 13411.882176734898, 13429.798553021517, 13854.930725346308, 10439.53022114022, 10985.670429887115, 11224.494897729784, 11473.0, 11771.646850594496, 8647.394682372174, 10151.289409741543, 10287.53706083213, 10450.372977621368, 10573.04842564173], "value": 7387.151242337651, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1605.7999999999997, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 177.0, 160.0, 120.0, 121.0, 122.0, 165.0, 173.0, 157.0], "value": 142.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 115.0, 116.0, 149.0, 114.0, 111.0, 112.0, 116.0, 129.0, 118.0], "value": 122.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [210.0, 241.0, 201.0, 203.0, 200.0, 225.0, 278.0, 197.0, 200.0, 197.0], "value": 215.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 58.0, 60.0, 88.0, 61.0, 61.0, 62.0, 61.0, 77.0, 59.0], "value": 67.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 71.0, 85.0, 73.0, 73.0, 72.0, 88.0, 85.0, 95.0, 112.0], "value": 84.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 90.0, 92.0, 91.0, 106.0, 90.0, 94.0, 92.0, 89.0, 122.0], "value": 95.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 103.0, 102.0, 100.0, 104.0, 104.0, 101.0, 102.0, 134.0, 116.0], "value": 106.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 146.0, 101.0, 110.0, 123.0, 93.0, 142.0, 143.0, 93.0, 92.0], "value": 116.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 61.0, 65.0, 63.0, 63.0, 64.0, 64.0, 62.0, 64.0, 64.0], "value": 63.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 100.0, 66.0, 91.0, 104.0, 99.0, 99.0, 101.0, 69.0, 73.0], "value": 86.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 85.0, 90.0, 94.0, 86.0, 95.0, 114.0, 108.0, 89.0, 88.0], "value": 95.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 144.0, 141.0, 138.0, 154.0, 153.0, 160.0, 169.0, 194.0, 154.0], "value": 155.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 161.0, 175.0, 183.0, 172.0, 169.0, 166.0, 194.0, 169.0, 235.0], "value": 179.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 74.0, 74.0, 68.0, 75.0, 67.0, 75.0, 79.0, 86.0, 94.0], "value": 76.0, "unit": "score"}]}]} 09:05:01 INFO - Return code: 0 09:05:01 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:05:01 ERROR - # TBPL SUCCESS # 09:05:01 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:05:01 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:05:01 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:05:01 INFO - 2017-01-11 09:05:01.976 screenresolution[1654:10538] starting screenresolution argv=screenresolution get 09:05:01 INFO - 2017-01-11 09:05:01.979 screenresolution[1654:10538] Display 0: 1600x1200x32@60 09:05:01 INFO - 2017-01-11 09:05:01.986 screenresolution[1655:10542] starting screenresolution argv=screenresolution list 09:05:01 INFO - Available Modes on Display 0 09:05:01 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:05:01 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:05:01 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:05:01 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:05:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:05:02 INFO - Intel Iris: 09:05:02 INFO - Chipset Model: Intel Iris 09:05:02 INFO - Type: GPU 09:05:02 INFO - Bus: Built-In 09:05:02 INFO - VRAM (Dynamic, Max): 1536 MB 09:05:02 INFO - Vendor: Intel (0x8086) 09:05:02 INFO - Device ID: 0x0a2e 09:05:02 INFO - Revision ID: 0x0009 09:05:02 INFO - Displays: 09:05:02 INFO - Display: 09:05:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:05:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:05:02 INFO - Display Serial Number: 1600x1200 60 09:05:02 INFO - Main Display: Yes 09:05:02 INFO - Mirror: Off 09:05:02 INFO - Online: Yes 09:05:02 INFO - Rotation: Supported 09:05:02 INFO - Return code: 0 09:05:02 INFO - Running post-action listener: _resource_record_post_action 09:05:02 INFO - [mozharness: 2017-01-11 17:05:02.228345Z] Finished run-tests step (success) 09:05:02 INFO - Running post-run listener: _resource_record_post_run 09:05:02 INFO - Total resource usage - Wall time: 1202s; CPU: 55.0%; Read bytes: 39938560; Write bytes: 555241472; Read time: 387; Write time: 1769 09:05:02 INFO - TinderboxPrint: CPU usage
54.5% 09:05:02 INFO - TinderboxPrint: I/O read bytes / time
39,938,560 / 387 09:05:02 INFO - TinderboxPrint: I/O write bytes / time
555,241,472 / 1,769 09:05:02 INFO - TinderboxPrint: CPU idle
2,187.8 (45.5%) 09:05:02 INFO - TinderboxPrint: CPU system
971.5 (20.2%) 09:05:02 INFO - TinderboxPrint: CPU user
1,650.5 (34.3%) 09:05:02 INFO - TinderboxPrint: Swap in / out
289,992,704 / 0 09:05:02 INFO - install - Wall time: 23s; CPU: 54.0%; Read bytes: 238041600; Write bytes: 233314304; Read time: 17449; Write time: 450 09:05:02 INFO - run-tests - Wall time: 1180s; CPU: 55.0%; Read bytes: 38107648; Write bytes: 300414976; Read time: 367; Write time: 1270 09:05:02 INFO - Running post-run listener: _upload_blobber_files 09:05:02 INFO - Blob upload gear active. 09:05:02 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:05:02 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:05:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:05:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:05:03 INFO - (blobuploader) - INFO - Open directory for files ... 09:05:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:05:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:05:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:05:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:05:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:05:04 INFO - (blobuploader) - INFO - Done attempting. 09:05:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:05:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:05:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:05:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:05:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:05:05 INFO - (blobuploader) - INFO - Done attempting. 09:05:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:05:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:05:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:05:06 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:05:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:05:06 INFO - (blobuploader) - INFO - Done attempting. 09:05:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:05:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:05:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:05:08 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:05:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:05:08 INFO - (blobuploader) - INFO - Done attempting. 09:05:08 INFO - (blobuploader) - INFO - Iteration through files over. 09:05:08 INFO - Return code: 0 09:05:08 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:05:08 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:05:08 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3fa6a0a080d57a556bc44464f7d7d364ca492bf61d1c6b665e8fcc5eba822cf9ef8c87b08a90412e36c4b49d5c05374d89f45472becc30db17e3830e8a9ed503", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4ecde585989166bab8af66b73049b72b4754a49e4bed0708b97013fbc3e8f84dca1383052fc4546774e9269fb5cfffe22f46ec696bd34b8866dd0511ddfd01dd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/73013a3583cfdf3f36f4dfcae330581d69dacbc73b06d1f307e0c05b1133a2fb747fbf7b4b907a2d9b27938c4004edfdc5e23291586bc827582924fa971af7ad", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9941f76f85107bb73dd5d6b02f907fdcca685a2aaca48351960a55e92e752f308edf409393a3911f196388bd0951d3a4b09d510a034d22e8608d441aad5d9782"} 09:05:08 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:05:08 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:05:08 INFO - Contents: 09:05:08 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3fa6a0a080d57a556bc44464f7d7d364ca492bf61d1c6b665e8fcc5eba822cf9ef8c87b08a90412e36c4b49d5c05374d89f45472becc30db17e3830e8a9ed503", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4ecde585989166bab8af66b73049b72b4754a49e4bed0708b97013fbc3e8f84dca1383052fc4546774e9269fb5cfffe22f46ec696bd34b8866dd0511ddfd01dd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/73013a3583cfdf3f36f4dfcae330581d69dacbc73b06d1f307e0c05b1133a2fb747fbf7b4b907a2d9b27938c4004edfdc5e23291586bc827582924fa971af7ad", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9941f76f85107bb73dd5d6b02f907fdcca685a2aaca48351960a55e92e752f308edf409393a3911f196388bd0951d3a4b09d510a034d22e8608d441aad5d9782"} 09:05:08 INFO - Running post-run listener: copy_logs_to_upload_dir 09:05:08 INFO - Copying logs to upload dir... 09:05:08 INFO - mkdir: /builds/slave/test/build/upload/logs 09:05:08 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.399700 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2017-01-11 09:05:08.983707) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-11 09:05:08.987160) ========= 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.CTE536dfEn/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.Gm5BgvehfO/Listeners TMPDIR=/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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/Firefox/sha512/3fa6a0a080d57a556bc44464f7d7d364ca492bf61d1c6b665e8fcc5eba822cf9ef8c87b08a90412e36c4b49d5c05374d89f45472becc30db17e3830e8a9ed503", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4ecde585989166bab8af66b73049b72b4754a49e4bed0708b97013fbc3e8f84dca1383052fc4546774e9269fb5cfffe22f46ec696bd34b8866dd0511ddfd01dd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/73013a3583cfdf3f36f4dfcae330581d69dacbc73b06d1f307e0c05b1133a2fb747fbf7b4b907a2d9b27938c4004edfdc5e23291586bc827582924fa971af7ad", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9941f76f85107bb73dd5d6b02f907fdcca685a2aaca48351960a55e92e752f308edf409393a3911f196388bd0951d3a4b09d510a034d22e8608d441aad5d9782"} build_url:https://queue.taskcluster.net/v1/task/Z0PXP240R4S179nHyIJJAA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011086 build_url: 'https://queue.taskcluster.net/v1/task/Z0PXP240R4S179nHyIJJAA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3fa6a0a080d57a556bc44464f7d7d364ca492bf61d1c6b665e8fcc5eba822cf9ef8c87b08a90412e36c4b49d5c05374d89f45472becc30db17e3830e8a9ed503", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4ecde585989166bab8af66b73049b72b4754a49e4bed0708b97013fbc3e8f84dca1383052fc4546774e9269fb5cfffe22f46ec696bd34b8866dd0511ddfd01dd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/73013a3583cfdf3f36f4dfcae330581d69dacbc73b06d1f307e0c05b1133a2fb747fbf7b4b907a2d9b27938c4004edfdc5e23291586bc827582924fa971af7ad", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9941f76f85107bb73dd5d6b02f907fdcca685a2aaca48351960a55e92e752f308edf409393a3911f196388bd0951d3a4b09d510a034d22e8608d441aad5d9782"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-11 09:05:09.021335) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-11 09:05:09.021718) ========= 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.CTE536dfEn/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.Gm5BgvehfO/Listeners TMPDIR=/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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.005322 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-11 09:05:09.071084) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-11 09:05:09.071425) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-11 09:05:09.071838) ========= ========= Total master_lag: 1.98 =========