builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0056 starttime: 1489680928.76 results: success (0) buildid: 20170316075736 builduid: 57686e0e65984254a5d451875c6bab0a revision: 3945f2297b997d59662bdb8ac20e092363eea532 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-16 09:15:28.756393) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-16 09:15:28.757249) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-16 09:15:28.757923) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-16 09:15:28.786536) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-16 09:15:28.787215) ========= 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.MyFldXG1fk/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.FEUrtapOYp/Listeners TMPDIR=/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-03-16 09:15:28-- 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% 20.4M=0.001s 2017-03-16 09:15:28 (20.4 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.149638 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-16 09:15:28.967540) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-16 09:15:28.968231) ========= 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.MyFldXG1fk/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.FEUrtapOYp/Listeners TMPDIR=/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/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.028253 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-16 09:15:29.022166) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-03-16 09:15:29.022983) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 3945f2297b997d59662bdb8ac20e092363eea532 --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 3945f2297b997d59662bdb8ac20e092363eea532 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.MyFldXG1fk/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.FEUrtapOYp/Listeners TMPDIR=/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-03-16 09:15:29,132 truncating revision to first 12 chars 2017-03-16 09:15:29,132 Setting DEBUG logging. 2017-03-16 09:15:29,133 attempt 1/10 2017-03-16 09:15:29,133 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/3945f2297b99?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-16 09:15:31,049 unpacking tar archive at: mozilla-central-3945f2297b99/testing/mozharness/ program finished with exit code 0 elapsedTime=2.200972 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-03-16 09:15:31.248591) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-16 09:15:31.248962) ========= script_repo_revision: 3945f2297b997d59662bdb8ac20e092363eea532 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-16 09:15:31.249419) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-16 09:15:31.249730) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-16 09:15:31.270479) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 25 secs) (at 2017-03-16 09:15:31.271128) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --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', '--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.MyFldXG1fk/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.FEUrtapOYp/Listeners TMPDIR=/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 09:15:31 INFO - MultiFileLogger online at 20170316 09:15:31 in /builds/slave/test 09:15:31 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:15:31 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:15:31 INFO - {'append_to_log': False, 09:15:31 INFO - 'base_work_dir': '/builds/slave/test', 09:15:31 INFO - 'blob_upload_branch': 'Firefox', 09:15:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:15:31 INFO - 'branch': 'Firefox', 09:15:31 INFO - 'buildbot_json_path': 'buildprops.json', 09:15:31 INFO - 'config_files': ('talos/mac_config.py',), 09:15:31 INFO - 'default_actions': ('clobber', 09:15:31 INFO - 'read-buildbot-config', 09:15:31 INFO - 'download-and-extract', 09:15:31 INFO - 'populate-webroot', 09:15:31 INFO - 'create-virtualenv', 09:15:31 INFO - 'install', 09:15:31 INFO - 'run-tests'), 09:15:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:15:31 INFO - 'download_minidump_stackwalk': True, 09:15:31 INFO - 'download_symbols': 'ondemand', 09:15:31 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:15:31 INFO - 'tooltool.py': '/tools/tooltool.py', 09:15:31 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:15:31 INFO - '/tools/misc-python/virtualenv.py')}, 09:15:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:15:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:15:31 INFO - 'gecko_profile': False, 09:15:31 INFO - 'gecko_profile_interval': 0, 09:15:31 INFO - 'installer_path': 'installer.exe', 09:15:31 INFO - 'log_level': 'info', 09:15:31 INFO - 'log_name': 'talos', 09:15:31 INFO - 'log_to_console': True, 09:15:31 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:15:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:15:31 INFO - 'opt_config_files': (), 09:15:31 INFO - 'pip_index': False, 09:15:31 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:15:31 INFO - 'cmd': ('bash', 09:15:31 INFO - '-c', 09:15:31 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:15:31 INFO - 'enabled': True, 09:15:31 INFO - 'halt_on_failure': False, 09:15:31 INFO - 'name': 'check_screen_resolution'},), 09:15:31 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:15:31 INFO - 'cmd': ('bash', 09:15:31 INFO - '-c', 09:15:31 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:15:31 INFO - 'enabled': True, 09:15:31 INFO - 'halt_on_failure': False, 09:15:31 INFO - 'name': 'check_screen_resolution'},), 09:15:31 INFO - 'run_cmd_checks_enabled': True, 09:15:31 INFO - 'suite': 'dromaeojs', 09:15:31 INFO - 'system_bits': '32', 09:15:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:15:31 INFO - 'title': 't-yosemite-r7-0056', 09:15:31 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:15:31 INFO - 'use_talos_json': True, 09:15:31 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:15:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:15:31 INFO - 'work_dir': 'build'} 09:15:31 INFO - [mozharness: 2017-03-16 16:15:31.483471Z] Running clobber step. 09:15:31 INFO - Running pre-action listener: _resource_record_pre_action 09:15:31 INFO - Running main action method: clobber 09:15:31 INFO - rmtree: /builds/slave/test/build 09:15:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:15:34 INFO - Running post-action listener: _resource_record_post_action 09:15:34 INFO - [mozharness: 2017-03-16 16:15:34.116426Z] Finished clobber step (success) 09:15:34 INFO - [mozharness: 2017-03-16 16:15:34.116671Z] Running read-buildbot-config step. 09:15:34 INFO - Running pre-action listener: _resource_record_pre_action 09:15:34 INFO - Running main action method: read_buildbot_config 09:15:34 INFO - Using buildbot properties: 09:15:34 INFO - { 09:15:34 INFO - "project": "", 09:15:34 INFO - "product": "firefox", 09:15:34 INFO - "script_repo_revision": "production", 09:15:34 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 09:15:34 INFO - "repository": "", 09:15:34 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 09:15:34 INFO - "buildid": "20170316075736", 09:15:34 INFO - "pgo_build": "False", 09:15:34 INFO - "basedir": "/builds/slave/test", 09:15:34 INFO - "buildnumber": 7, 09:15:34 INFO - "slavename": "t-yosemite-r7-0056", 09:15:34 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 09:15:34 INFO - "platform": "yosemite_r7", 09:15:34 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 09:15:34 INFO - "branch": "mozilla-central", 09:15:34 INFO - "repo_path": "mozilla-central", 09:15:34 INFO - "revision": "3945f2297b997d59662bdb8ac20e092363eea532", 09:15:34 INFO - "stage_platform": "macosx64", 09:15:34 INFO - "builduid": "57686e0e65984254a5d451875c6bab0a", 09:15:34 INFO - "slavebuilddir": "test" 09:15:34 INFO - } 09:15:34 INFO - Found installer url https://queue.taskcluster.net/v1/task/Q_ukGa7XTaiLKPXZIhopOw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 09:15:34 INFO - Running post-action listener: _resource_record_post_action 09:15:34 INFO - [mozharness: 2017-03-16 16:15:34.122523Z] Finished read-buildbot-config step (success) 09:15:34 INFO - [mozharness: 2017-03-16 16:15:34.122737Z] Running download-and-extract step. 09:15:34 INFO - Running pre-action listener: _resource_record_pre_action 09:15:34 INFO - Running main action method: download_and_extract 09:15:34 INFO - mkdir: /builds/slave/test/build/tests 09:15:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:15:34 INFO - trying https://queue.taskcluster.net/v1/task/Q_ukGa7XTaiLKPXZIhopOw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 09:15:34 INFO - Downloading https://queue.taskcluster.net/v1/task/Q_ukGa7XTaiLKPXZIhopOw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 09:15:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Q_ukGa7XTaiLKPXZIhopOw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 09:15:35 INFO - Downloaded 1325 bytes. 09:15:35 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 09:15:35 INFO - Using the following test package requirements: 09:15:35 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:15:35 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 09:15:35 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 09:15:35 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:15:35 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 09:15:35 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:15:35 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 09:15:35 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:15:35 INFO - u'jsshell-mac.zip'], 09:15:35 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:15:35 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 09:15:35 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 09:15:35 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:15:35 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 09:15:35 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:15:35 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 09:15:35 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:15:35 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 09:15:35 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:15:35 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 09:15:35 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:15:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Q_ukGa7XTaiLKPXZIhopOw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 09:15:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Q_ukGa7XTaiLKPXZIhopOw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:15:35 INFO - Fetch https://queue.taskcluster.net/v1/task/Q_ukGa7XTaiLKPXZIhopOw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 09:15:37 INFO - Expected file size: 52472932 09:15:37 INFO - Obtained file size: 52472932 09:15:53 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 09:15:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Q_ukGa7XTaiLKPXZIhopOw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 09:15:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Q_ukGa7XTaiLKPXZIhopOw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:15:53 INFO - Fetch https://queue.taskcluster.net/v1/task/Q_ukGa7XTaiLKPXZIhopOw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 09:15:55 INFO - Expected file size: 52472932 09:15:55 INFO - Obtained file size: 52472932 09:16:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Q_ukGa7XTaiLKPXZIhopOw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 09:16:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Q_ukGa7XTaiLKPXZIhopOw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:16:10 INFO - Fetch https://queue.taskcluster.net/v1/task/Q_ukGa7XTaiLKPXZIhopOw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 09:16:11 INFO - Expected file size: 14404452 09:16:11 INFO - Obtained file size: 14404452 09:16:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:12 INFO - trying https://queue.taskcluster.net/v1/task/Q_ukGa7XTaiLKPXZIhopOw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:16:12 INFO - Downloading https://queue.taskcluster.net/v1/task/Q_ukGa7XTaiLKPXZIhopOw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 09:16:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Q_ukGa7XTaiLKPXZIhopOw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 09:16:14 INFO - Downloaded 75082191 bytes. 09:16:14 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Q_ukGa7XTaiLKPXZIhopOw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:16:14 INFO - mkdir: /builds/slave/test/properties 09:16:14 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:16:14 INFO - Writing to file /builds/slave/test/properties/build_url 09:16:14 INFO - Contents: 09:16:14 INFO - build_url:https://queue.taskcluster.net/v1/task/Q_ukGa7XTaiLKPXZIhopOw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:16:15 INFO - Running post-action listener: _resource_record_post_action 09:16:15 INFO - Running post-action listener: set_extra_try_arguments 09:16:15 INFO - [mozharness: 2017-03-16 16:16:15.114867Z] Finished download-and-extract step (success) 09:16:15 INFO - [mozharness: 2017-03-16 16:16:15.115001Z] Running populate-webroot step. 09:16:15 INFO - Running pre-action listener: _resource_record_pre_action 09:16:15 INFO - Running main action method: populate_webroot 09:16:15 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:16:15 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:16:15 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:16:15 INFO - '--tppagecycles', 09:16:15 INFO - '7'], 09:16:15 INFO - 'tests': ['tsvgm']}}, 09:16:15 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:16:15 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:16:15 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:16:15 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:16:15 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:16:15 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:16:15 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:16:15 INFO - 'talos_options': ['--disable-e10s'], 09:16:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:16:15 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:16:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:16:15 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:16:15 INFO - 'talos_options': ['--disable-e10s'], 09:16:15 INFO - 'tests': ['damp', 'tps']}, 09:16:15 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:16:15 INFO - 'tests': ['damp', 'tps']}, 09:16:15 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:16:15 INFO - 'tests': ['dromaeo_dom']}, 09:16:15 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:16:15 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:16:15 INFO - 'tests': ['basic_compositor_video']}, 09:16:15 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:16:15 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:16:15 INFO - 'tests': ['a11yr', 09:16:15 INFO - 'ts_paint', 09:16:15 INFO - 'tpaint', 09:16:15 INFO - 'sessionrestore', 09:16:15 INFO - 'sessionrestore_no_auto_restore', 09:16:15 INFO - 'tabpaint']}, 09:16:15 INFO - 'other-e10s': {'tests': ['a11yr', 09:16:15 INFO - 'ts_paint', 09:16:15 INFO - 'tpaint', 09:16:15 INFO - 'sessionrestore', 09:16:15 INFO - 'sessionrestore_no_auto_restore', 09:16:15 INFO - 'tabpaint']}, 09:16:15 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:16:15 INFO - 'ts_paint', 09:16:15 INFO - 'tpaint', 09:16:15 INFO - 'sessionrestore', 09:16:15 INFO - 'sessionrestore_no_auto_restore', 09:16:15 INFO - 'tabpaint']}, 09:16:15 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:16:15 INFO - 'ts_paint', 09:16:15 INFO - 'tpaint', 09:16:15 INFO - 'sessionrestore', 09:16:15 INFO - 'sessionrestore_no_auto_restore', 09:16:15 INFO - 'tabpaint']}, 09:16:15 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:16:15 INFO - 'tests': ['a11yr', 09:16:15 INFO - 'ts_paint', 09:16:15 INFO - 'tpaint', 09:16:15 INFO - 'sessionrestore', 09:16:15 INFO - 'sessionrestore_no_auto_restore', 09:16:15 INFO - 'tabpaint']}, 09:16:15 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:16:15 INFO - 'tests': ['a11yr', 09:16:15 INFO - 'ts_paint', 09:16:15 INFO - 'tpaint', 09:16:15 INFO - 'sessionrestore', 09:16:15 INFO - 'sessionrestore_no_auto_restore', 09:16:15 INFO - 'tabpaint']}, 09:16:15 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:16:15 INFO - 'tests': ['tsvgx', 09:16:15 INFO - 'tsvgr_opacity', 09:16:15 INFO - 'tart', 09:16:15 INFO - 'tscrollx', 09:16:15 INFO - 'cart', 09:16:15 INFO - 'tsvg_static']}, 09:16:15 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:16:15 INFO - 'tsvgr_opacity', 09:16:15 INFO - 'tart', 09:16:15 INFO - 'tscrollx', 09:16:15 INFO - 'cart', 09:16:15 INFO - 'tsvg_static']}, 09:16:15 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:16:15 INFO - 'talos_options': ['--disable-e10s'], 09:16:15 INFO - 'tests': ['tp5o']}, 09:16:15 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:16:15 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:16:15 INFO - 'talos_options': ['--disable-e10s', 09:16:15 INFO - '--xperf_path', 09:16:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:16:15 INFO - 'tests': ['tp5n']}, 09:16:15 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:16:15 INFO - 'talos_options': ['--xperf_path', 09:16:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:16:15 INFO - 'tests': ['tp5n']}}, 09:16:15 INFO - 'talos.zip': {'path': '', 09:16:15 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:16:15 INFO - Running post-action listener: _resource_record_post_action 09:16:15 INFO - [mozharness: 2017-03-16 16:16:15.133190Z] Finished populate-webroot step (success) 09:16:15 INFO - [mozharness: 2017-03-16 16:16:15.133326Z] Running create-virtualenv step. 09:16:15 INFO - Running pre-action listener: _resource_record_pre_action 09:16:15 INFO - Running main action method: create_virtualenv 09:16:15 INFO - Creating virtualenv /builds/slave/test/build/venv 09:16:15 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:16:15 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:16:15 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:16:15 INFO - Using real prefix '/tools/python27' 09:16:15 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:16:15 INFO - Installing distribute.............................................................................................................................................................................................done. 09:16:18 INFO - Installing pip.................done. 09:16:18 INFO - Return code: 0 09:16:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:16:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:16:19 INFO - Reading from file tmpfile_stdout 09:16:19 INFO - Output received: 09:16:19 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 09:16:19 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:16:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:19 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 0x10a8b0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8b3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcbc857eba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a68ed30>, '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 0x10a5b5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a683be8>, '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.MyFldXG1fk/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.FEUrtapOYp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:16:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:16:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:16:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MyFldXG1fk/Render', 09:16:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:16:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:16:19 INFO - 'HOME': '/Users/cltbld', 09:16:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:16:19 INFO - 'LOGNAME': 'cltbld', 09:16:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:19 INFO - 'MOZ_NO_REMOTE': '1', 09:16:19 INFO - 'NO_EM_RESTART': '1', 09:16:19 INFO - 'PAGER': '/bin/cat', 09:16:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:16:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:19 INFO - 'PWD': '/builds/slave/test', 09:16:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:16:19 INFO - 'SHELL': '/bin/bash', 09:16:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FEUrtapOYp/Listeners', 09:16:19 INFO - 'TMPDIR': '/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/', 09:16:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:16:19 INFO - 'USER': 'cltbld', 09:16:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:16:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:16:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:19 INFO - 'XPC_FLAGS': '0x0', 09:16:19 INFO - 'XPC_SERVICE_NAME': '0', 09:16:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:16:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:19 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:16:19 INFO - Cleaning up... 09:16:19 INFO - Return code: 0 09:16:19 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:16:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:19 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 0x10a8b0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8b3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcbc857eba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a68ed30>, '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 0x10a5b5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a683be8>, '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.MyFldXG1fk/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.FEUrtapOYp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:16:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:16:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:16:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MyFldXG1fk/Render', 09:16:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:16:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:16:19 INFO - 'HOME': '/Users/cltbld', 09:16:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:16:19 INFO - 'LOGNAME': 'cltbld', 09:16:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:19 INFO - 'MOZ_NO_REMOTE': '1', 09:16:19 INFO - 'NO_EM_RESTART': '1', 09:16:19 INFO - 'PAGER': '/bin/cat', 09:16:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:16:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:19 INFO - 'PWD': '/builds/slave/test', 09:16:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:16:19 INFO - 'SHELL': '/bin/bash', 09:16:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FEUrtapOYp/Listeners', 09:16:19 INFO - 'TMPDIR': '/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/', 09:16:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:16:19 INFO - 'USER': 'cltbld', 09:16:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:16:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:16:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:19 INFO - 'XPC_FLAGS': '0x0', 09:16:19 INFO - 'XPC_SERVICE_NAME': '0', 09:16:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:16:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:19 INFO - Downloading/unpacking psutil>=3.1.1 09:16:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:16:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:16:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:16:23 INFO - Installing collected packages: psutil 09:16:23 INFO - Running setup.py install for psutil 09:16:23 INFO - building 'psutil._psutil_osx' extension 09:16:23 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:16:23 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:16:23 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:16:23 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 09:16:23 INFO - building 'psutil._psutil_posix' extension 09:16:23 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 09:16:24 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:16:24 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:16:24 INFO - ^ 09:16:24 INFO - 1 warning generated. 09:16:24 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 09:16:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:16:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:16:24 INFO - Successfully installed psutil 09:16:24 INFO - Cleaning up... 09:16:24 INFO - Return code: 0 09:16:24 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:16:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a8b0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8b3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcbc857eba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a68ed30>, '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 0x10a5b5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a683be8>, '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.MyFldXG1fk/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.FEUrtapOYp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:16:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:16:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:16:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MyFldXG1fk/Render', 09:16:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:16:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:16:24 INFO - 'HOME': '/Users/cltbld', 09:16:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:16:24 INFO - 'LOGNAME': 'cltbld', 09:16:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:24 INFO - 'MOZ_NO_REMOTE': '1', 09:16:24 INFO - 'NO_EM_RESTART': '1', 09:16:24 INFO - 'PAGER': '/bin/cat', 09:16:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:16:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:24 INFO - 'PWD': '/builds/slave/test', 09:16:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:16:24 INFO - 'SHELL': '/bin/bash', 09:16:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FEUrtapOYp/Listeners', 09:16:24 INFO - 'TMPDIR': '/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/', 09:16:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:16:24 INFO - 'USER': 'cltbld', 09:16:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:16:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:16:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:24 INFO - 'XPC_FLAGS': '0x0', 09:16:24 INFO - 'XPC_SERVICE_NAME': '0', 09:16:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:16:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:24 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:16:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:27 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:16:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:16:27 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:16:27 INFO - Installing collected packages: mozsystemmonitor 09:16:27 INFO - Running setup.py install for mozsystemmonitor 09:16:27 INFO - Successfully installed mozsystemmonitor 09:16:27 INFO - Cleaning up... 09:16:27 INFO - Return code: 0 09:16:27 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:16:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:28 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 0x10a8b0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8b3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcbc857eba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a68ed30>, '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 0x10a5b5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a683be8>, '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.MyFldXG1fk/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.FEUrtapOYp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:16:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:16:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:16:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MyFldXG1fk/Render', 09:16:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:16:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:16:28 INFO - 'HOME': '/Users/cltbld', 09:16:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:16:28 INFO - 'LOGNAME': 'cltbld', 09:16:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:28 INFO - 'MOZ_NO_REMOTE': '1', 09:16:28 INFO - 'NO_EM_RESTART': '1', 09:16:28 INFO - 'PAGER': '/bin/cat', 09:16:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:16:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:28 INFO - 'PWD': '/builds/slave/test', 09:16:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:16:28 INFO - 'SHELL': '/bin/bash', 09:16:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FEUrtapOYp/Listeners', 09:16:28 INFO - 'TMPDIR': '/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/', 09:16:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:16:28 INFO - 'USER': 'cltbld', 09:16:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:16:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:16:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:28 INFO - 'XPC_FLAGS': '0x0', 09:16:28 INFO - 'XPC_SERVICE_NAME': '0', 09:16:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:16:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:28 INFO - Downloading/unpacking jsonschema==2.5.1 09:16:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:16:31 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:16:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:31 INFO - Downloading functools32-3.2.3-2.tar.gz 09:16:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:16:31 INFO - warning: no files found matching '*.txt' 09:16:31 INFO - no previously-included directories found matching 'build' 09:16:31 INFO - no previously-included directories found matching 'dist' 09:16:31 INFO - no previously-included directories found matching '.git*' 09:16:31 INFO - Installing collected packages: jsonschema, functools32 09:16:31 INFO - Running setup.py install for functools32 09:16:31 INFO - warning: no files found matching '*.txt' 09:16:31 INFO - no previously-included directories found matching 'build' 09:16:31 INFO - no previously-included directories found matching 'dist' 09:16:31 INFO - no previously-included directories found matching '.git*' 09:16:31 INFO - Successfully installed jsonschema functools32 09:16:31 INFO - Cleaning up... 09:16:31 INFO - Return code: 0 09:16:31 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:16:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:31 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 0x10a8b0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8b3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcbc857eba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a68ed30>, '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 0x10a5b5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a683be8>, '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.MyFldXG1fk/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.FEUrtapOYp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:16:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:16:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:16:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MyFldXG1fk/Render', 09:16:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:16:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:16:31 INFO - 'HOME': '/Users/cltbld', 09:16:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:16:31 INFO - 'LOGNAME': 'cltbld', 09:16:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:31 INFO - 'MOZ_NO_REMOTE': '1', 09:16:31 INFO - 'NO_EM_RESTART': '1', 09:16:31 INFO - 'PAGER': '/bin/cat', 09:16:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:16:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:31 INFO - 'PWD': '/builds/slave/test', 09:16:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:16:31 INFO - 'SHELL': '/bin/bash', 09:16:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FEUrtapOYp/Listeners', 09:16:31 INFO - 'TMPDIR': '/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/', 09:16:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:16:31 INFO - 'USER': 'cltbld', 09:16:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:16:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:16:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:31 INFO - 'XPC_FLAGS': '0x0', 09:16:31 INFO - 'XPC_SERVICE_NAME': '0', 09:16:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:16:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:32 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:16:32 INFO - Cleaning up... 09:16:32 INFO - Return code: 0 09:16:32 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:16:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:32 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 0x10a8b0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8b3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcbc857eba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a68ed30>, '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 0x10a5b5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a683be8>, '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.MyFldXG1fk/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.FEUrtapOYp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:16:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:16:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:16:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MyFldXG1fk/Render', 09:16:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:16:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:16:32 INFO - 'HOME': '/Users/cltbld', 09:16:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:16:32 INFO - 'LOGNAME': 'cltbld', 09:16:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:32 INFO - 'MOZ_NO_REMOTE': '1', 09:16:32 INFO - 'NO_EM_RESTART': '1', 09:16:32 INFO - 'PAGER': '/bin/cat', 09:16:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:16:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:32 INFO - 'PWD': '/builds/slave/test', 09:16:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:16:32 INFO - 'SHELL': '/bin/bash', 09:16:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FEUrtapOYp/Listeners', 09:16:32 INFO - 'TMPDIR': '/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/', 09:16:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:16:32 INFO - 'USER': 'cltbld', 09:16:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:16:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:16:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:32 INFO - 'XPC_FLAGS': '0x0', 09:16:32 INFO - 'XPC_SERVICE_NAME': '0', 09:16:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:16:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:32 INFO - Downloading/unpacking blobuploader==1.2.4 09:16:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:34 INFO - Downloading blobuploader-1.2.4.tar.gz 09:16:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:16:35 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:16:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:16:35 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:16:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:36 INFO - Downloading docopt-0.6.1.tar.gz 09:16:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:16:36 INFO - Installing collected packages: blobuploader, requests, docopt 09:16:36 INFO - Running setup.py install for blobuploader 09:16:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:16:36 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:16:36 INFO - Running setup.py install for requests 09:16:37 INFO - Running setup.py install for docopt 09:16:37 INFO - Successfully installed blobuploader requests docopt 09:16:37 INFO - Cleaning up... 09:16:37 INFO - Return code: 0 09:16:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:16:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:37 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 0x10a8b0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8b3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcbc857eba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a68ed30>, '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 0x10a5b5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a683be8>, '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.MyFldXG1fk/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.FEUrtapOYp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:16:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:16:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:16:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MyFldXG1fk/Render', 09:16:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:16:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:16:37 INFO - 'HOME': '/Users/cltbld', 09:16:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:16:37 INFO - 'LOGNAME': 'cltbld', 09:16:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:37 INFO - 'MOZ_NO_REMOTE': '1', 09:16:37 INFO - 'NO_EM_RESTART': '1', 09:16:37 INFO - 'PAGER': '/bin/cat', 09:16:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:16:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:37 INFO - 'PWD': '/builds/slave/test', 09:16:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:16:37 INFO - 'SHELL': '/bin/bash', 09:16:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FEUrtapOYp/Listeners', 09:16:37 INFO - 'TMPDIR': '/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/', 09:16:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:16:37 INFO - 'USER': 'cltbld', 09:16:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:16:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:16:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:37 INFO - 'XPC_FLAGS': '0x0', 09:16:37 INFO - 'XPC_SERVICE_NAME': '0', 09:16:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:16:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:16:37 INFO - Running setup.py (path:/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/pip-d2MoYV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:16:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:16:37 INFO - Running setup.py (path:/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/pip-wJoxlR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:16:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:16:37 INFO - Running setup.py (path:/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/pip-JZIYgp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:16:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:16:37 INFO - Running setup.py (path:/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/pip-UbaUai-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:16:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:16:37 INFO - Running setup.py (path:/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/pip-nLOXYc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:16:38 INFO - Running setup.py (path:/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/pip-_bjRNW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:16:38 INFO - Running setup.py (path:/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/pip-S16UzE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:16:38 INFO - Running setup.py (path:/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/pip-b4X00w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:16:38 INFO - Running setup.py (path:/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/pip-RyKI4t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:16:38 INFO - Running setup.py (path:/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/pip-sE5agw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:16:38 INFO - Running setup.py (path:/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/pip-3SsnqZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:16:38 INFO - Running setup.py (path:/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/pip-J7hH2J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:16:38 INFO - Running setup.py (path:/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/pip-MxpO2X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:16:38 INFO - Running setup.py (path:/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/pip-yTYB7P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:16:38 INFO - Running setup.py (path:/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/pip-9PfvTw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:16:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:16:39 INFO - Running setup.py (path:/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/pip-QYhsYz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:16:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:16:39 INFO - Running setup.py (path:/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/pip-nPR5x7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:16:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:16:39 INFO - Running setup.py install for manifestparser 09:16:39 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:16:39 INFO - Running setup.py install for mozcrash 09:16:39 INFO - Running setup.py install for mozdebug 09:16:39 INFO - Running setup.py install for mozdevice 09:16:39 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:16:39 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:16:39 INFO - Running setup.py install for mozfile 09:16:40 INFO - Running setup.py install for mozhttpd 09:16:40 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:16:40 INFO - Running setup.py install for mozinfo 09:16:40 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:16:40 INFO - Running setup.py install for mozInstall 09:16:40 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:16:40 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:16:40 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:16:40 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:16:40 INFO - Running setup.py install for mozleak 09:16:40 INFO - Running setup.py install for mozlog 09:16:40 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:16:40 INFO - Running setup.py install for moznetwork 09:16:40 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:16:40 INFO - Running setup.py install for mozprocess 09:16:41 INFO - Running setup.py install for mozprofile 09:16:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:16:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:16:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:16:41 INFO - Running setup.py install for mozrunner 09:16:41 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:16:41 INFO - Running setup.py install for mozscreenshot 09:16:41 INFO - Running setup.py install for moztest 09:16:41 INFO - Running setup.py install for mozversion 09:16:41 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:16:41 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:16:41 INFO - Cleaning up... 09:16:41 INFO - Return code: 0 09:16:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:16:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:41 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 0x10a8b0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8b3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcbc857eba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a68ed30>, '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 0x10a5b5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a683be8>, '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.MyFldXG1fk/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.FEUrtapOYp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:16:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:16:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:16:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MyFldXG1fk/Render', 09:16:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:16:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:16:41 INFO - 'HOME': '/Users/cltbld', 09:16:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:16:41 INFO - 'LOGNAME': 'cltbld', 09:16:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:41 INFO - 'MOZ_NO_REMOTE': '1', 09:16:41 INFO - 'NO_EM_RESTART': '1', 09:16:41 INFO - 'PAGER': '/bin/cat', 09:16:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:16:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:41 INFO - 'PWD': '/builds/slave/test', 09:16:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:16:41 INFO - 'SHELL': '/bin/bash', 09:16:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FEUrtapOYp/Listeners', 09:16:41 INFO - 'TMPDIR': '/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/', 09:16:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:16:41 INFO - 'USER': 'cltbld', 09:16:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:16:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:16:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:41 INFO - 'XPC_FLAGS': '0x0', 09:16:41 INFO - 'XPC_SERVICE_NAME': '0', 09:16:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:16:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:16:42 INFO - Running setup.py (path:/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/pip-Pu7Qq4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:16:42 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:16:42 INFO - Running setup.py (path:/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/pip-ANquZ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:16:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:16:42 INFO - Running setup.py (path:/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/pip-odx1x4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:16:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:16:42 INFO - Running setup.py (path:/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/pip-9I5uff-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:16:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:16:42 INFO - Running setup.py (path:/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/pip-BgOgeA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:16:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:16:42 INFO - Running setup.py (path:/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/pip-L9J1yw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:16:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:16:42 INFO - Running setup.py (path:/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/pip-yAWSPc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:16:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:16:42 INFO - Running setup.py (path:/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/pip-0oX_d8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:16:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:16:43 INFO - Running setup.py (path:/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/pip-VuxHbh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:16:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:16:43 INFO - Running setup.py (path:/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/pip-8Kv9fC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:16:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:16:43 INFO - Running setup.py (path:/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/pip-8jGO2e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:16:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:16:43 INFO - Running setup.py (path:/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/pip-hHKXa0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:16:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:16:43 INFO - Running setup.py (path:/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/pip-ZaZ2BH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:16:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:16:43 INFO - Running setup.py (path:/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/pip-hk4aYE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:16:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:16:43 INFO - Running setup.py (path:/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/pip-0QAk9I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:16:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:16:43 INFO - Running setup.py (path:/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/pip-K0qFap-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:16:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:16:43 INFO - Running setup.py (path:/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/pip-BneQ4h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:16:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:16:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:16:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:16:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:16:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:16:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:16:43 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:16: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 09:16: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 09:16: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 09:16: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 09:16: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 09:16: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 09:16:46 INFO - Downloading blessings-1.6.tar.gz 09:16:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:16:46 INFO - Installing collected packages: blessings 09:16:46 INFO - Running setup.py install for blessings 09:16:46 INFO - Successfully installed blessings 09:16:46 INFO - Cleaning up... 09:16:47 INFO - Return code: 0 09:16:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:16:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:16:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:16:47 INFO - Reading from file tmpfile_stdout 09:16:47 INFO - Current package versions: 09:16:47 INFO - blessings == 1.6 09:16:47 INFO - blobuploader == 1.2.4 09:16:47 INFO - docopt == 0.6.1 09:16:47 INFO - functools32 == 3.2.3-2 09:16:47 INFO - jsonschema == 2.5.1 09:16:47 INFO - manifestparser == 1.1 09:16:47 INFO - mozInstall == 1.12 09:16:47 INFO - mozcrash == 1.0 09:16:47 INFO - mozdebug == 0.1 09:16:47 INFO - mozdevice == 0.49 09:16:47 INFO - mozfile == 1.2 09:16:47 INFO - mozhttpd == 0.7 09:16:47 INFO - mozinfo == 0.9 09:16:47 INFO - mozleak == 0.1 09:16:47 INFO - mozlog == 3.4 09:16:47 INFO - moznetwork == 0.27 09:16:47 INFO - mozprocess == 0.25 09:16:47 INFO - mozprofile == 0.28 09:16:47 INFO - mozrunner == 6.13 09:16:47 INFO - mozscreenshot == 0.1 09:16:47 INFO - mozsystemmonitor == 0.3 09:16:47 INFO - moztest == 0.8 09:16:47 INFO - mozversion == 1.4 09:16:47 INFO - psutil == 3.1.1 09:16:47 INFO - requests == 1.2.3 09:16:47 INFO - wsgiref == 0.1.2 09:16:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:16: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')]} 09:16:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16: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')]} 09:16:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:47 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 0x10a8b0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8b3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcbc857eba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a68ed30>, '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 0x10a5b5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a683be8>, '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.MyFldXG1fk/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.FEUrtapOYp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:16:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:16:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:16:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MyFldXG1fk/Render', 09:16:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:16:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:16:47 INFO - 'HOME': '/Users/cltbld', 09:16:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:16:47 INFO - 'LOGNAME': 'cltbld', 09:16:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:47 INFO - 'MOZ_NO_REMOTE': '1', 09:16:47 INFO - 'NO_EM_RESTART': '1', 09:16:47 INFO - 'PAGER': '/bin/cat', 09:16:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:16:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:47 INFO - 'PWD': '/builds/slave/test', 09:16:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:16:47 INFO - 'SHELL': '/bin/bash', 09:16:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FEUrtapOYp/Listeners', 09:16:47 INFO - 'TMPDIR': '/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/', 09:16:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:16:47 INFO - 'USER': 'cltbld', 09:16:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:16:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:16:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:47 INFO - 'XPC_FLAGS': '0x0', 09:16:47 INFO - 'XPC_SERVICE_NAME': '0', 09:16:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:16:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:16:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:16:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:16:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:16:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:16:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:16:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:16:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:16:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:16:47 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:16:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:16:47 INFO - Cleaning up... 09:16:47 INFO - Return code: 0 09:16:47 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:16: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')]} 09:16:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16: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')]} 09:16:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:47 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 0x10a8b0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8b3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcbc857eba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a68ed30>, '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 0x10a5b5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a683be8>, '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.MyFldXG1fk/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.FEUrtapOYp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:16:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:16:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:16:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MyFldXG1fk/Render', 09:16:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:16:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:16:47 INFO - 'HOME': '/Users/cltbld', 09:16:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:16:47 INFO - 'LOGNAME': 'cltbld', 09:16:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:47 INFO - 'MOZ_NO_REMOTE': '1', 09:16:47 INFO - 'NO_EM_RESTART': '1', 09:16:47 INFO - 'PAGER': '/bin/cat', 09:16:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:16:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:47 INFO - 'PWD': '/builds/slave/test', 09:16:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:16:47 INFO - 'SHELL': '/bin/bash', 09:16:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FEUrtapOYp/Listeners', 09:16:47 INFO - 'TMPDIR': '/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/', 09:16:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:16:47 INFO - 'USER': 'cltbld', 09:16:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:16:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:16:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:47 INFO - 'XPC_FLAGS': '0x0', 09:16:47 INFO - 'XPC_SERVICE_NAME': '0', 09:16:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:16:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:47 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:16:47 INFO - Cleaning up... 09:16:47 INFO - Return code: 0 09:16:47 INFO - Running post-action listener: _resource_record_post_action 09:16:47 INFO - Running post-action listener: _start_resource_monitoring 09:16:47 INFO - Starting resource monitoring. 09:16:47 INFO - [mozharness: 2017-03-16 16:16:47.839024Z] Finished create-virtualenv step (success) 09:16:47 INFO - [mozharness: 2017-03-16 16:16:47.839987Z] Running install step. 09:16:47 INFO - Running pre-action listener: _resource_record_pre_action 09:16:47 INFO - Running main action method: install 09:16:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:16:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:16:48 INFO - Reading from file tmpfile_stdout 09:16:48 INFO - Detecting whether we're running mozinstall >=1.0... 09:16:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:16:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:16:48 INFO - Reading from file tmpfile_stdout 09:16:48 INFO - Output received: 09:16:48 INFO - Usage: mozinstall [options] installer 09:16:48 INFO - Options: 09:16:48 INFO - -h, --help show this help message and exit 09:16:48 INFO - -d DEST, --destination=DEST 09:16:48 INFO - Directory to install application into. [default: 09:16:48 INFO - "/builds/slave/test"] 09:16:48 INFO - --app=APP Application being installed. [default: firefox] 09:16:48 INFO - mkdir: /builds/slave/test/build/application 09:16:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:16:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 09:17:08 INFO - Reading from file tmpfile_stdout 09:17:08 INFO - Output received: 09:17:08 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:17:08 INFO - Running post-action listener: _resource_record_post_action 09:17:08 INFO - [mozharness: 2017-03-16 16:17:08.365393Z] Finished install step (success) 09:17:08 INFO - [mozharness: 2017-03-16 16:17:08.365512Z] Running run-tests step. 09:17:08 INFO - Running pre-action listener: _resource_record_pre_action 09:17:08 INFO - Running main action method: run_tests 09:17:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:17:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:17:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:17:08 INFO - 2017-03-16 09:17:08.380 screenresolution[1617:7246] starting screenresolution argv=screenresolution get 09:17:08 INFO - 2017-03-16 09:17:08.382 screenresolution[1617:7246] Display 0: 1600x1200x32@60 09:17:08 INFO - 2017-03-16 09:17:08.388 screenresolution[1618:7249] starting screenresolution argv=screenresolution list 09:17:08 INFO - Available Modes on Display 0 09:17:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:17:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:17:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:17:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:17:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:17:08 INFO - Intel Iris: 09:17:08 INFO - Chipset Model: Intel Iris 09:17:08 INFO - Type: GPU 09:17:08 INFO - Bus: Built-In 09:17:08 INFO - VRAM (Dynamic, Max): 1536 MB 09:17:08 INFO - Vendor: Intel (0x8086) 09:17:08 INFO - Device ID: 0x0a2e 09:17:08 INFO - Revision ID: 0x0009 09:17:08 INFO - Displays: 09:17:08 INFO - Display: 09:17:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:17:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:17:08 INFO - Display Serial Number: 1600x1200 60 09:17:08 INFO - Main Display: Yes 09:17:08 INFO - Mirror: Off 09:17:08 INFO - Online: Yes 09:17:08 INFO - Rotation: Supported 09:17:08 INFO - Return code: 0 09:17:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:17:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:17:08 INFO - Python 2.7.3 09:17:08 INFO - Return code: 0 09:17:08 INFO - grabbing minidump binary from tooltool 09:17:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:08 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 0x10a68ed30>, '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 0x10a5b5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a683be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:17:08 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:17:08 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 09:17:08 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:17:08 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:17:08 INFO - Return code: 0 09:17:08 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:17:08 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:17:08 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:17:08 INFO - ENV: RUST_BACKTRACE is now 1 09:17:08 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:17:08 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:17:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Q_ukGa7XTaiLKPXZIhopOw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0056', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:17:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Q_ukGa7XTaiLKPXZIhopOw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0056 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:17:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MyFldXG1fk/Render', 09:17:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:17:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:17:08 INFO - 'HOME': '/Users/cltbld', 09:17:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:17:08 INFO - 'LOGNAME': 'cltbld', 09:17:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:17:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:17:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:08 INFO - 'MOZ_NO_REMOTE': '1', 09:17:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:17:08 INFO - 'NO_EM_RESTART': '1', 09:17:08 INFO - 'PAGER': '/bin/cat', 09:17:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:17:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:08 INFO - 'PWD': '/builds/slave/test', 09:17:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:17:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:17:08 INFO - 'RUST_BACKTRACE': '1', 09:17:08 INFO - 'SHELL': '/bin/bash', 09:17:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FEUrtapOYp/Listeners', 09:17:08 INFO - 'TMPDIR': '/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/', 09:17:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:17:08 INFO - 'USER': 'cltbld', 09:17:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:17:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:17:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:08 INFO - 'XPC_FLAGS': '0x0', 09:17:08 INFO - 'XPC_SERVICE_NAME': '0', 09:17:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:17:08 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Q_ukGa7XTaiLKPXZIhopOw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0056', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:17:08 INFO - mozversion application_buildid: 20170316075736 09:17:08 INFO - mozversion application_changeset: 3945f2297b997d59662bdb8ac20e092363eea532 09:17:08 INFO - mozversion application_display_name: Nightly 09:17:08 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:17:08 INFO - mozversion application_name: Firefox 09:17:08 INFO - mozversion application_remotingname: firefox 09:17:08 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:17:08 INFO - mozversion application_vendor: Mozilla 09:17:08 INFO - mozversion application_version: 55.0a1 09:17:08 INFO - mozversion platform_buildid: 20170316075736 09:17:08 INFO - mozversion platform_changeset: 3945f2297b997d59662bdb8ac20e092363eea532 09:17:08 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:17:08 INFO - mozversion platform_version: 55.0a1 09:17:08 INFO - using testdate: 1489681028 09:17:08 INFO - actual date: 1489681028 09:17:08 INFO - starting webserver on 'localhost:49235' 09:17:08 INFO - SUITE-START | Running 2 tests 09:17:08 INFO - TEST-START | dromaeo_css 09:17:08 INFO - operating with platform_type : mac_ 09:17:08 INFO - Initialising browser for dromaeo_css test... 09:17:08 INFO - TEST-INFO | started process 1628 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/tmpwsRqbg/profile http://localhost:49235/getInfo.html) 09:17:11 INFO - PID 1628 | __metrics Screen width/height:1600/1200 09:17:11 INFO - PID 1628 | colorDepth:24 09:17:11 INFO - PID 1628 | Browser inner width/height: 1024/689 09:17:11 INFO - PID 1628 | __metrics 09:17:11 INFO - PID 1628 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:17:11 INFO - PID 1628 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:17:12 INFO - TEST-INFO | 1628: exit 0 09:17:12 INFO - Browser initialized. 09:17:12 INFO - Running cycle 1/1 for dromaeo_css test... 09:17:12 INFO - TEST-INFO | started process 1630 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/tmpwsRqbg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:17:24 INFO - PID 1630 | RSS: Main: 233541632 09:17:24 INFO - PID 1630 | 09:20:28 INFO - PID 1630 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49235/tests/dromaeo/cssquery-ext.html) 09:20:30 INFO - PID 1630 | RSS: Main: 408219648 09:20:30 INFO - PID 1630 | 09:22:14 INFO - PID 1630 | ************************* 09:22:14 INFO - PID 1630 | A coding exception was thrown and uncaught in a Task. 09:22:14 INFO - PID 1630 | 09:22:14 ERROR - PID 1630 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:22:14 INFO - PID 1630 | Full stack: 09:22:14 INFO - PID 1630 | ************************* 09:23:33 INFO - PID 1630 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-ext.html (next: http://localhost:49235/tests/dromaeo/cssquery-jquery.html) 09:23:33 INFO - PID 1630 | RSS: Main: 501071872 09:23:33 INFO - PID 1630 | 09:26:36 INFO - PID 1630 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49235/tests/dromaeo/cssquery-mootools.html) 09:26:36 INFO - PID 1630 | RSS: Main: 328507392 09:26:36 INFO - PID 1630 | 09:29:39 INFO - PID 1630 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49235/tests/dromaeo/cssquery-prototype.html) 09:29:40 INFO - PID 1630 | RSS: Main: 325210112 09:29:40 INFO - PID 1630 | 09:32:43 INFO - PID 1630 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49235/tests/dromaeo/cssquery-yui.html) 09:32:44 INFO - PID 1630 | RSS: Main: 338837504 09:32:44 INFO - PID 1630 | 09:35:47 INFO - PID 1630 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-yui.html (next: http://localhost:49235/tests/dromaeo/cssquery-dojo.html) 09:35:48 INFO - PID 1630 | RSS: Main: 514424832 09:35:48 INFO - PID 1630 | 09:35:48 INFO - PID 1630 | __start_tp_report 09:35:48 INFO - PID 1630 | _x_x_mozilla_page_load 09:35:48 INFO - PID 1630 | _x_x_mozilla_page_load_details 09:35:48 INFO - PID 1630 | |i|pagename|runs| 09:35:48 INFO - PID 1630 | |0;dojo.html;2674.919752407427;3320.8671653133874;3330.150811543058;3364.5121457388673;3367.7137443317315;5582.581306402016;5609.382967873541;5652.773889044439;5655.293088363955;5697.515711164551;4631.7684115794245;5701.116326969316;5801.854953626162;5814.186013958049;5842.152887831262;5106.157484015133;5158.7936482540745;5179.766910489018;5203.98522288154;5236.842894713165;5270.367555893298;5282.947170528303;5303.602229832778;5303.787848486075;5313.521783039526;5113.795448182068;5159.380874295071;5176.2235664650225;5213.608979326527;5226.55574276187;5254.08053590624;5267.762950667229;5278.709670968095;5280.788768449257;5286.788528458857;5122.76947537358;5172.534471897509;5184.637075404723;5186.92219616706;5242.344706911636;5570.526505247019;5689.943100569024;5713.942860571383;5718.828435146911;5719.771209151629;5899.262592175978;5936.7922122725495;6242.313345531988;6276.623402595859;6302.086197501337;6226.097215903668;6304.621722696607;6304.684765761694;6322.52581056418;6346.587471814318;5788.479036886701;5848.912266316009;5852.67810270435;5855.531557475391;5890.97054514729;6192.783252586228;6218.129461875341;6280.874382512324;6295.842603934938;6297.275813281532;6212.285587157445;6228.532860035515;6242.687865606701;6251.531135164881;6253;6301.023341382093;6319.8104056878365;6324.620522768649;6335.081413195153;6337.493000559944;6104.298005729309;6105.206323177958;6121.696863452088;6291.339409362043;6292;6149.292831324366;6179.227596550431;6231.3145553989025;6273.027680709497;6279.748810047547;4220.873373798791;4880.780364883589;4988.825391111293;4997.800087996512;5030.748462576857;4415.381846541487;4461.7992190351515;4472.821087156477;4474.172278128557;4516;5607;5662.348829884616;5663.32040155184;5776.566757493205;5776.711164441761;5278.261043453919;6196.132541444202;6253.0620406938415;6271.717772700239;6288.088227207029;5725.599208055396;5728.398518155617;5733.6273142246455;5739.024365857814;5761.078227483583;5314.256004159421;5356.625036247426;5423.213634023124;5430.185472179205;5438.891222175614;5256.395514515727;6261.154744109487;6270.435660790459;6284.5600807943;6298.905516417166;6178.66017369049;6221;6223.37766223374;6258.655773932295;6272.153259310117;6287.182666253358;6292.244930608356;6295.496360291074;6301.96849015752;6321.0834429008455;6101.908471373022;6216.285127210248;6226.25284965807;6240.781572644935;6242.469390101892;5990.430909063632;6270.7491700331475;6284.4344009039405;6289.308176100534;6295.496360291074;6022.307434644897;6032.909506357496;6045.304789949212;6065.54508411862;6085.786997455068;6039.63762174271;6107.522607572365;6149.616336324291;6158.722857471335;6167.660778657217;6044.516438684983;6071.39286071407;6079.1185278133535;6088.269407671108;6095.908561371494;6181.474574661024;6194.4115309045565;6214;6226.439620434183;6230.501559875109;4885.853424397274;5791.986402379651;5840.2115714378015;5859.5019423349495;6048.879022419615 09:35:48 INFO - PID 1630 | |1;ext.html;731.8792399254123;779.6491578789544;794.8132188935601;859.4628357276706;874.8643960186173;27994.440111197764;28412.715872841265;28453.715462845365;28486.43919510061;28571.714282857167;37214.813925930335;38114;38179.42730859039;38184.04539886504;38229.61770382295;9005.909940900588;9212.078792120768;9249.398789078688;9330.813383732322;9358.953205233998;7180.784576462688;9119.270458363315;9300.906990930072;9302.348835581512;9309.813803723922;7409.70361185552;10600;10871.83692244617;10875.728106797354;10882.455877206148;10886.40124793136;10919.672409827717;10944.616938407196;10958.890411095947;10976.725581860517;7098.361147496698;10650;10799.892001079967;10931.125509959184;10955.945220273927;27943.58084628732;28295.717042829514;28326;28372.85813570919;36246.91259262226;3699.722520810923;3805.3721136012523;3809.3904975203836;3810.3903375459795;3820.083180036799;27476.45047099067;27652.58521122185;27754;28574;28659.283517912012;3075.0031199301134;3117.345357474933;3137.4823154179535;3149.3071524264624;3190.6171259448784;343827.2808635941;347519;347713;347858;350399;52766;53130.73434632879;53439.46560534422;53469.7326513365;53586.46413535815;55172;55173.44826551763;55419;55441;55482.16776748352;14255.002149849619;14582;14594.270286485633;14594.562163134911;14635.92682036583;11743.530258789553;11809.996150327146;11818;11846.170768046153;11850.644480665595;11822.88177118235;12005.579804706793;13758.31208439574;14100.506482273071;14181.14913105217;4886.2494653177555;4898.471844253724;6085.726142323606;6097.756089756449;6103.298120716086;979.8089372572423;1040.8862517106622;1119.9304664045978;1130.5873356225084;1176.2236923630362;20458;26172.083977060713;26329.341766455607;26329.868350658126;26356.472870542482;1228.2875931959443;1230.8399908011902;1237.5792230641625;1283.813846992181;1285.807128930668;12424.37878106091;12495.25028498293;13004.934975325063;13424.261665608485;13681.931590341985;451196;454420;455578.4442155602;456502;457323;57308.140377894364;57508.42491575031;57646.423535764945;57647.71176144092;57795;1427.229296180072;1456.5557504961089;1464.12152708372;1465.655570940828;1480.1267252321181;24692.13577524778;25205.495890082464;25632.48735025252;25865.48269034571;25871;47203.69574129778;47327.76336118297;47370.76314618405;47378;47452.288215676104;416950;419900;421059;421728.89135554124;421966;34378.484322735676;34882.47676284807;35035.824820875736;35114.47328290027;35141;31688.207794805316;32680.67319326776;40097.79951100226;44773.10453790971;44847.77576112099;31178.844105779324;33519.994400168034;34527.82736086303;34795.30409391849;35773.64226357703;34053.65946340505;34193.31613367768;34224.65775342215;35439;35440.759573414805;35044.82477587698;35512.28975420529;35656.286874262885;35691.393887274615;35808.10479738027;25398.619020714334;27173.86413067922;28600.42799143964;30025.3994920096;30477.390452191277;19182;22928.31215063551;23278.534429311658;23380.76619233786;23494.76505234926 09:35:48 INFO - PID 1630 | |2;jquery.html;397.90251388409825;408.24882216721227;415.2960731560006;417.1365273883062;432.9090890912908;19674.55695253332;20519.794802051994;21175.94120293983;21250.14999400024;21271.680924786142;17647.823521764778;21309.467263318424;22190.778092219072;22994.195203167892;23047.76952230477;6597.63712995785;6983.16202055754;6985.6507174641065;6996.3353481419435;7002.474814388916;6719.260881303053;6832.590044597315;6840.760573379933;6877.656117194146;6883.174019117713;6583;6862.691178896936;6884.449244060464;6903.689333979983;6921.723131074777;5973.731182096794;6785.864282714343;6910.689018994107;6931.376176144171;6946.861062778741;6372;6547.770828021044;6622.602643841385;6628.602283862983;6750.6498700260145;26896.865515672493;26911.86544067267;26935.057272995546;27027.72972270272;27194.456110877672;3311;3345.8327083645963;3361.6806403391884;3406.7615266931325;3519.1026288296575;16232.594185145466;16374.590635234214;16443.424480143138;16674.999500030037;17090.914545427193;1952.7754308254448;2013.3355992522434;2019.8687085339552;2023.4637820977453;2104.558042810996;118099.6909422295;132430;150085.9999999989;151030;153725;16375.91812040956;20733.89633051825;20831.583368332547;20892.58214835725;20913.47716307074;34636.78771243045;34709;34743.65256347404;34768.30463390768;34833;8381.49711017341;10123.240756943258;10227.386356818615;10438.843417348746;10560.841587376195;8335.333173346118;8360.707375241838;8379.413441059189;8572.828543429096;8587.141285871363;8225.71210007647;10081.747956301151;10232.283740138268;10330.845037324447;10375.56714836806;3388.610309814403;3932.6853851692367;4038.374052021941;4045.379385450006;4049.319532394082;1022.3763504262394;1038.896110388955;1052.9157667386592;1055.8152323343384;1105.1490352428584;17979.831310964746;21948.561028779655;22743.31770046901;22798.65802012971;22854.771452285597;1021.3922715984127;1022.5960745505483;1025.3079171559316;1037.190991027;1066.5200659703164;9443.214251795736;11710.88289117115;11904.226225295328;11917.702057448632;11991.880081199251;340876;522337;528422.3578882081;530665;566873;138055;140301.29849350688;145725.27137364246;146367.80448293284;146715;1268.1123213750227;1280.5326055989497;1329.7672907241194;1329.9958531308994;1333.180094242034;20303.390898273075;20370.185192592722;21225;21371.35885923425;21910.342689719335;45689.77155114336;57077.00230492079;63104.368956311686;63137.684311578145;64395.06814795564;70593.64703176451;82714.58642706748;83036.3392732154;83783;84257.41683248537;12225.266484010988;12328.876711232773;12328.938355308524;12478.937605311916;12616.621501354974;13850.376733046836;13925.72148557003;14142.929285353508;14303.856961430252;14361.281935903373;11527.538898443969;11832.881671183522;12101.515939362327;12128.393580320771;12161.209521381083;5673.375928647928;8812.559372031244;11454.484548195185;11640.185187036826;11944.48714071082;11825.053995680497;11881.940590296994;11979.221350612183;12038.217515861441;12073.396330183632;12210.877891220975;12390.592587038636;12452.190607610839;12553.811692824433;12727.618171454871;9342.95328523353;11340.473483715285;11411.771764564828;11529.82705259405;11564.537418503165 09:35:48 INFO - PID 1630 | |3;mootools.html;1349.5546469665012;1543.0972880864695;1572.6461546152111;1579.1630435868985;1648.7774150489684;9130.406523575972;9548.140667339938;10460.79078418432;10760.946195269033;10782.245242833005;10467.162626989842;10532.631357902475;10555.102816260627;10555.313904596196;10568.735781605465;2308.7806658367426;2343.8359314847967;2347.495288512969;2349.859008459498;2353.011735071274;1986.6622674145358;2072.844536659749;2267.9886600567056;2365.89353479093;2368.763123687626;2288.7711228877147;2333.4749681321737;2334.1480359668685;2338.485533182697;2340;2285.1430713482446;2345.29641107667;2351.6590094436383;2352.5177338645535;2357;2097.328854766475;2266.150193677371;2271.3072512883564;2341.309313752447;2345.64815277708;10681;10786;10803.891961080368;10804.945975270151;10833.349999000007;4855.465898751135;5477.534409575191;5697.515711164556;5701.372848986608;5710.514606258473;3341.7493687973256;3536.169000284931;3551.5027896094566;3602.6937710294656;3629.7096232301615;1151.7408583068827;1203.8314635950974;1207.5532053140307;1254.3163975633302;1257.346179986411;14631.707365852624;14730.55808325752;14926.850731492763;14928.850711492854;14975.32611032506;4012.799360031987;4660.697054691502;4728.612570710838;4780.259059845729;4869.415670119537;12140.635780926586;12148.939255303843;12156.845099715512;12157.392130393446;12167;5561.638493497991;5564.10974244119;5568.9443105569235;5569.275994120739;5577.860553486195;4427.446569178853;4938.555530002245;4940.629452791055;5093.23601459784;5105.285260063594;3624.7643903146213;3971.6425521703186;4082.1631565528996;4084.36692312692;4086.3870419436726;1104.276698762312;1138.299945533491;1143.3368646185193;1156.8553930758655;1159.113278342069;881.8809460722848;909.713440266314;911.5988964855443;936.842209287384;954.465499320369;6172.22847144107;6245.6252624842655;6343.492520598341;6384.07430922523;6502.577332473374;908.2597682888425;1011.4841430870309;1031.664708969588;1044.5143008500932;1071.2822408985999;3801.714871384602;3932.016995751062;4034.757914525138;4038.394240863894;4062.939055914164;12598.181118227287;12718.236905785498;13008.349582520836;13039.217646941213;13081.345932703327;10894.455277236106;12641.620751377473;12784.488620455078;12850;12896.097273190786;9543.093406126407;9806.16647584949;9901.702948911545;9905;9971.351862128939;6071.483923866521;6121.112438696453;6193.071039343954;6230.252369715663;6274.309825919237;11424.714382140513;11807.881921180679;11879.406029698654;11900.988415984739;11953.103517236068;11729.35488548138;11817.586384476503;11930.642080737593;11974.341411222118;11991.400429978641;7470.4770666052855;8763.956180219058;9071.410358326688;9086.273098152;9103;7694.433950964108;9806.8231812183;10072.899271007489;10097.848532271875;10112.544935477774;8552.743417697478;8703.390762646553;8761.211490965843;8768.605412756313;8931.866022009544;8572.52851093196;8582.313414926915;8601.13988601135;8642.740717778477;8690.565471726262;7589;7599.088109426904;7685.385169186564;7685.962395076593;7733.651985660546;4877.561019508261;5583.469570390969;5589.5807814413265;5749.166370876116;5906.763729450774;5693.373728889736;5783.277090363705;5800.24596802413;5925.140854575976;5985.072313791369 09:35:48 INFO - PID 1630 | |4;prototype.html;361.1530959899037;388.8541796826192;389.3070334804048;389.7934094929688;391.39724823771394;5430.8913821723545;5637.154426835977;5753.280839895013;5780.739866705998;5783.190353350529;4551.930296380348;4727.479977202505;4991.351124353829;5683.744231509581;5738.42615738426;4060.6751459883285;4558.156741002908;4751.453582837983;4774.355462012636;4961.8511444656715;4745.311929770181;4753.762311884409;4755.881102972436;4756.952430475685;4781.832635857746;4755.405574303212;4762;4774.212254977924;4786.162421576228;4798.256270278112;4645.590283497832;4664.86005419838;4722.851102748241;4774.785134668949;4805.90388192236;3547.3614749345365;3789.2421515696697;4241.833495788652;4316.6546676265825;4332.1119170569955;4630.351750754897;5579.246801681762;5612.719364031823;5658.896515179542;5671.914921276184;4817.903641927177;5223.37319521656;5477.972610136963;5512.614117011811;5582.771790206149;6115.938840611581;6126.540509461808;6133.386661333876;6154.661493617849;6260.342664020303;4843.733594652293;4857.198562237262;4871.56155945963;4878.878028049292;4909.8772530686665;4940.863601371669;6180.918339290651;6369.585976911486;6380.84047898797;6470;4090.5295890972325;4278.780547543997;4869.853904382874;5054.848354549369;5064.4935506449065;5153.896922061613;5162.238202561612;5171.172612382001;5184.714840683723;5199.194124910645;5185.429602743695;5383.327084114486;5486.451354864562;5492.972535137299;5518.641288316327;5562.276904002535;6022.45797878193;6172.8765424691255;6190.659513726699;6212.968935155386;1464.3044553837055;1593.4184022831814;1774.1572752942507;1782.4028950301536;1782.803891571926;1147.7991351513451;1230.5877531027027;1279.3667134768164;1292.7737645911927;1326.0916272353468;4801.623196517677;5573.693446860461;5808.854778630483;5812.854678633068;5822;5957.255343082114;5985.104671898177;5986.281646202484;6247.562670613013;6335.524835637346;4880.438749543777;5491.945080549223;5717.42825717425;5732.856678583069;5747.591839999227;3336.115929278743;3336.616289126733;3336.632970373257;3355.8086879158036;3365.74756893234;5474.91787623186;6213.850437669045;6247;6518.804435866931;6560.343965603402;6501.447376972916;6508.284088750233;6586.769463068769;6595.505337099737;6599.769008084694;5813.331466881363;6058.304813769188;6208.255009398811;6588.571742836701;6611.50413718973;5403.973245083422;6096.542759292982;6202.85247229264;6332.240131184288;6369.522285828489;5402.246023499296;5915.556333274935;6053.213082299273;6089.421504957022;6245.687715614292;5419.945800541944;6333.245038408307;6517.217933847968;6624.90062649051;6641.468682505485;5309.867253318698;5468.671879687232;5561.749721262472;5604.355499117653;5606.18710286998;5717.7712891485535;5965.717370765306;5983.37174596661;6354.714037868214;6488.183308673579;5471.480209380103;6231.22109736283;6235.189425374672;6245.094461303176;6285.842853928689;5825.679587622722;6084.14821924922;6157.96921015392;6174.086033329682;6236.095766113979;4939.357883475269;5961.582689211714;5988.910166347596;6000.069989151689;6034.426729460694;5339.252504649275;6063.757449701962;6109.725062372293;6153.84615384619;6159.260888693386;5999.88000239984;6000;6017.548683848817;6019.6990150493175;6042.969785151046 09:35:48 INFO - PID 1630 | |5;yui.html;756.8221467955033;761.9695212191513;822.1531822223109;825.5665775467878;836.932910539063;15106.24468776561;15895.364185432569;15895.841041589596;15898.682026359465;15926.681466370681;14382.352794124263;14512.854871451282;14727.779083313759;14740;14741.55775326742;942.6276620734806;960.2653969713175;961.4375590279694;967.8209531236744;970.175350951689;938.8357037518441;963.1668606655228;966.3911735606558;977.7799995001103;980.5587485631459;1028.9022542858454;1029.5933106422958;1032;1032.2825636182856;1033.886272510027;1020.9336393134497;1022.3916769522123;1023.5956797065118;1024.9641262555813;1030.6701855406272;764.6941223510585;765.4679997401807;765.555977533035;766.5707203965741;767.5126294802841;15527.611809704847;15877.682446351124;15951.521454356387;15997.840021599752;16122.113283769388;14116.132864709627;14319.57041288763;14620.488282910048;14667.266636668232;14738.189399583018;11486.195966282363;11742.941285293604;11825.408729563573;11848.940755296167;12099.274043557414;3669.3578623741005;3723.590405055442;3792.9569368423627;3867;3871.8064096795047;109335.35996960053;109394;110274.9999999992;110611.44694276557;112399.43800281028;9898.158656514277;10076.848847267296;10108.494575271206;10254.743631409125;10314.948425257826;24652;25032.4993500129;25092.874535627205;25118.12086576961;25140.12009579656;7735.61321933901;7824.804379890435;7828.491148075357;7922.36621070313;8054.476459030145;3715.8327875245677;4190.161967606491;4201.70588058833;4277.807498662568;4279.957200428018;3354.7148492377933;3508;3547.396942519778;3611.4041183205004;3629.1471504196493;617.8980468222783;635.6345101566618;635.707574515728;652.0805664013718;659.4724220623577;532.7229840483004;568.1506148308223;645.9224893012869;661.4113439039259;689.9689513971883;9518.238540916711;10170.03066615644;10203.846942295871;10235.43705096212;10380.377177369382;549;553.4216743503049;589.6874656432097;620.178189238961;704.3203308806924;4927.655064145475;4937.7777999990085;4943.110240156806;4949.381327334083;4950.331705219821;70360;97496;97736;101211;101762.4911875436;86873.56563217143;86962.56518717493;87205.56397217973;87744.24511509734;87777.68333475004;48520.75739621279;54567.45432545703;55766.72116639391;71760.28239717535;76096.23903760892;10601.46992650349;10698.358098514114;10860.78278434411;10990.615328463466;11000.944995274973;32354;47062;48113.75943120262;48166;48370.79073023202;61358;62028.75942481035;62098.689506552175;62534.06198906929;62593;13188.604341869375;13301.733965320833;13380.933095334462;13470.73058538804;14080.718385632435;14247.358868850719;14261.572152835432;14401.567952961428;14503.274836258355;14621.415143394144;11819.763604728028;13177.682231776746;13468.393922273717;13711;13850.238236896665;13137.408816603085;13150.802737958747;13461.259630720404;13469.259190744604;14038.78941815853;12064.819027714415;12158;13315.06794524374;13662;14003.159810411407;8504.489730616182;9396.67111651089;10449.373037617766;11022.338659680445;11420.657380278604;8896.510691912006;9283.62865485373;11084.40958257357;11279.548818047186;11327.207095503236 09:35:48 INFO - PID 1630 | __end_tp_report 09:35:48 INFO - PID 1630 | __start_cc_report 09:35:48 INFO - PID 1630 | _x_x_mozilla_cycle_collect,2028 09:35:48 INFO - PID 1630 | __end_cc_report 09:35:48 INFO - PID 1630 | __startTimestamp1489682148011__endTimestamp 09:35:48 INFO - PID 1630 | 09:35:48 INFO - PID 1630 | ------- Summary: start ------- 09:35:48 INFO - PID 1630 | Number of tests: 6 09:35:48 INFO - PID 1630 | 09:35:48 INFO - PID 1630 | [#0] dojo.html Cycles:165 Average:5729.06 Median:5936.79 stddev:673.88 (11.4%) stddev-sans-first:631.92 09:35:48 INFO - PID 1630 | Values: 2674.9 3320.9 3330.2 3364.5 3367.7 5582.6 5609.4 5652.8 5655.3 5697.5 4631.8 5701.1 5801.9 5814.2 5842.2 5106.2 5158.8 5179.8 5204.0 5236.8 5270.4 5282.9 5303.6 5303.8 5313.5 5113.8 5159.4 5176.2 5213.6 5226.6 5254.1 5267.8 5278.7 5280.8 5286.8 5122.8 5172.5 5184.6 5186.9 5242.3 5570.5 5689.9 5713.9 5718.8 5719.8 5899.3 5936.8 6242.3 6276.6 6302.1 6226.1 6304.6 6304.7 6322.5 6346.6 5788.5 5848.9 5852.7 5855.5 5891.0 6192.8 6218.1 6280.9 6295.8 6297.3 6212.3 6228.5 6242.7 6251.5 6253.0 6301.0 6319.8 6324.6 6335.1 6337.5 6104.3 6105.2 6121.7 6291.3 6292.0 6149.3 6179.2 6231.3 6273.0 6279.7 4220.9 4880.8 4988.8 4997.8 5030.7 4415.4 4461.8 4472.8 4474.2 4516.0 5607.0 5662.3 5663.3 5776.6 5776.7 5278.3 6196.1 6253.1 6271.7 6288.1 5725.6 5728.4 5733.6 5739.0 5761.1 5314.3 5356.6 5423.2 5430.2 5438.9 5256.4 6261.2 6270.4 6284.6 6298.9 6178.7 6221.0 6223.4 6258.7 6272.2 6287.2 6292.2 6295.5 6302.0 6321.1 6101.9 6216.3 6226.3 6240.8 6242.5 5990.4 6270.7 6284.4 6289.3 6295.5 6022.3 6032.9 6045.3 6065.5 6085.8 6039.6 6107.5 6149.6 6158.7 6167.7 6044.5 6071.4 6079.1 6088.3 6095.9 6181.5 6194.4 6214.0 6226.4 6230.5 4885.9 5792.0 5840.2 5859.5 6048.9 09:35:48 INFO - PID 1630 | 09:35:48 INFO - PID 1630 | [#1] ext.html Cycles:180 Average:54409.15 Median:34123.49 stddev:108762.41 (318.7%) stddev-sans-first:108992.85 09:35:48 INFO - PID 1630 | Values: 731.9 779.6 794.8 859.5 874.9 27994.4 28412.7 28453.7 28486.4 28571.7 37214.8 38114.0 38179.4 38184.0 38229.6 9005.9 9212.1 9249.4 9330.8 9359.0 7180.8 9119.3 9300.9 9302.3 9309.8 7409.7 10600.0 10871.8 10875.7 10882.5 10886.4 10919.7 10944.6 10958.9 10976.7 7098.4 10650.0 10799.9 10931.1 10955.9 27943.6 28295.7 28326.0 28372.9 36246.9 3699.7 3805.4 3809.4 3810.4 3820.1 27476.5 27652.6 27754.0 28574.0 28659.3 3075.0 3117.3 3137.5 3149.3 3190.6 343827.3 347519.0 347713.0 347858.0 350399.0 52766.0 53130.7 53439.5 53469.7 53586.5 55172.0 55173.4 55419.0 55441.0 55482.2 14255.0 14582.0 14594.3 14594.6 14635.9 11743.5 11810.0 11818.0 11846.2 11850.6 11822.9 12005.6 13758.3 14100.5 14181.1 4886.2 4898.5 6085.7 6097.8 6103.3 979.8 1040.9 1119.9 1130.6 1176.2 20458.0 26172.1 26329.3 26329.9 26356.5 1228.3 1230.8 1237.6 1283.8 1285.8 12424.4 12495.3 13004.9 13424.3 13681.9 451196.0 454420.0 455578.4 456502.0 457323.0 57308.1 57508.4 57646.4 57647.7 57795.0 1427.2 1456.6 1464.1 1465.7 1480.1 24692.1 25205.5 25632.5 25865.5 25871.0 47203.7 47327.8 47370.8 47378.0 47452.3 416950.0 419900.0 421059.0 421728.9 421966.0 34378.5 34882.5 35035.8 35114.5 35141.0 31688.2 32680.7 40097.8 44773.1 44847.8 31178.8 33520.0 34527.8 34795.3 35773.6 34053.7 34193.3 34224.7 35439.0 35440.8 35044.8 35512.3 35656.3 35691.4 35808.1 25398.6 27173.9 28600.4 30025.4 30477.4 19182.0 22928.3 23278.5 23380.8 23494.8 09:35:48 INFO - PID 1630 | 09:35:48 INFO - PID 1630 | [#2] jquery.html Cycles:180 Average:35430.93 Median:21200.47 stddev:86181.12 (406.5%) stddev-sans-first:86382.74 09:35:48 INFO - PID 1630 | Values: 397.9 408.2 415.3 417.1 432.9 19674.6 20519.8 21175.9 21250.1 21271.7 17647.8 21309.5 22190.8 22994.2 23047.8 6597.6 6983.2 6985.7 6996.3 7002.5 6719.3 6832.6 6840.8 6877.7 6883.2 6583.0 6862.7 6884.4 6903.7 6921.7 5973.7 6785.9 6910.7 6931.4 6946.9 6372.0 6547.8 6622.6 6628.6 6750.6 26896.9 26911.9 26935.1 27027.7 27194.5 3311.0 3345.8 3361.7 3406.8 3519.1 16232.6 16374.6 16443.4 16675.0 17090.9 1952.8 2013.3 2019.9 2023.5 2104.6 118099.7 132430.0 150086.0 151030.0 153725.0 16375.9 20733.9 20831.6 20892.6 20913.5 34636.8 34709.0 34743.7 34768.3 34833.0 8381.5 10123.2 10227.4 10438.8 10560.8 8335.3 8360.7 8379.4 8572.8 8587.1 8225.7 10081.7 10232.3 10330.8 10375.6 3388.6 3932.7 4038.4 4045.4 4049.3 1022.4 1038.9 1052.9 1055.8 1105.1 17979.8 21948.6 22743.3 22798.7 22854.8 1021.4 1022.6 1025.3 1037.2 1066.5 9443.2 11710.9 11904.2 11917.7 11991.9 340876.0 522337.0 528422.4 530665.0 566873.0 138055.0 140301.3 145725.3 146367.8 146715.0 1268.1 1280.5 1329.8 1330.0 1333.2 20303.4 20370.2 21225.0 21371.4 21910.3 45689.8 57077.0 63104.4 63137.7 64395.1 70593.6 82714.6 83036.3 83783.0 84257.4 12225.3 12328.9 12328.9 12478.9 12616.6 13850.4 13925.7 14142.9 14303.9 14361.3 11527.5 11832.9 12101.5 12128.4 12161.2 5673.4 8812.6 11454.5 11640.2 11944.5 11825.1 11881.9 11979.2 12038.2 12073.4 12210.9 12390.6 12452.2 12553.8 12727.6 9343.0 11340.5 11411.8 11529.8 11564.5 09:35:48 INFO - PID 1630 | 09:35:48 INFO - PID 1630 | [#3] mootools.html Cycles:180 Average:6493.68 Median:3438.96 stddev:4036.32 (117.4%) stddev-sans-first:4029.13 09:35:48 INFO - PID 1630 | Values: 1349.6 1543.1 1572.6 1579.2 1648.8 9130.4 9548.1 10460.8 10760.9 10782.2 10467.2 10532.6 10555.1 10555.3 10568.7 2308.8 2343.8 2347.5 2349.9 2353.0 1986.7 2072.8 2268.0 2365.9 2368.8 2288.8 2333.5 2334.1 2338.5 2340.0 2285.1 2345.3 2351.7 2352.5 2357.0 2097.3 2266.2 2271.3 2341.3 2345.6 10681.0 10786.0 10803.9 10804.9 10833.3 4855.5 5477.5 5697.5 5701.4 5710.5 3341.7 3536.2 3551.5 3602.7 3629.7 1151.7 1203.8 1207.6 1254.3 1257.3 14631.7 14730.6 14926.9 14928.9 14975.3 4012.8 4660.7 4728.6 4780.3 4869.4 12140.6 12148.9 12156.8 12157.4 12167.0 5561.6 5564.1 5568.9 5569.3 5577.9 4427.4 4938.6 4940.6 5093.2 5105.3 3624.8 3971.6 4082.2 4084.4 4086.4 1104.3 1138.3 1143.3 1156.9 1159.1 881.9 909.7 911.6 936.8 954.5 6172.2 6245.6 6343.5 6384.1 6502.6 908.3 1011.5 1031.7 1044.5 1071.3 3801.7 3932.0 4034.8 4038.4 4062.9 12598.2 12718.2 13008.3 13039.2 13081.3 10894.5 12641.6 12784.5 12850.0 12896.1 9543.1 9806.2 9901.7 9905.0 9971.4 6071.5 6121.1 6193.1 6230.3 6274.3 11424.7 11807.9 11879.4 11901.0 11953.1 11729.4 11817.6 11930.6 11974.3 11991.4 7470.5 8764.0 9071.4 9086.3 9103.0 7694.4 9806.8 10072.9 10097.8 10112.5 8552.7 8703.4 8761.2 8768.6 8931.9 8572.5 8582.3 8601.1 8642.7 8690.6 7589.0 7599.1 7685.4 7686.0 7733.7 4877.6 5583.5 5589.6 5749.2 5906.8 5693.4 5783.3 5800.2 5925.1 5985.1 09:35:48 INFO - PID 1630 | 09:35:48 INFO - PID 1630 | [#4] prototype.html Cycles:180 Average:5156.74 Median:5581.01 stddev:1440.46 (25.8%) stddev-sans-first:1398.81 09:35:48 INFO - PID 1630 | Values: 361.2 388.9 389.3 389.8 391.4 5430.9 5637.2 5753.3 5780.7 5783.2 4551.9 4727.5 4991.4 5683.7 5738.4 4060.7 4558.2 4751.5 4774.4 4961.9 4745.3 4753.8 4755.9 4757.0 4781.8 4755.4 4762.0 4774.2 4786.2 4798.3 4645.6 4664.9 4722.9 4774.8 4805.9 3547.4 3789.2 4241.8 4316.7 4332.1 4630.4 5579.2 5612.7 5658.9 5671.9 4817.9 5223.4 5478.0 5512.6 5582.8 6115.9 6126.5 6133.4 6154.7 6260.3 4843.7 4857.2 4871.6 4878.9 4909.9 4940.9 6180.9 6369.6 6380.8 6470.0 4090.5 4278.8 4869.9 5054.8 5064.5 5153.9 5162.2 5171.2 5184.7 5199.2 5185.4 5383.3 5486.5 5493.0 5518.6 5562.3 6022.5 6172.9 6190.7 6213.0 1464.3 1593.4 1774.2 1782.4 1782.8 1147.8 1230.6 1279.4 1292.8 1326.1 4801.6 5573.7 5808.9 5812.9 5822.0 5957.3 5985.1 5986.3 6247.6 6335.5 4880.4 5491.9 5717.4 5732.9 5747.6 3336.1 3336.6 3336.6 3355.8 3365.7 5474.9 6213.9 6247.0 6518.8 6560.3 6501.4 6508.3 6586.8 6595.5 6599.8 5813.3 6058.3 6208.3 6588.6 6611.5 5404.0 6096.5 6202.9 6332.2 6369.5 5402.2 5915.6 6053.2 6089.4 6245.7 5419.9 6333.2 6517.2 6624.9 6641.5 5309.9 5468.7 5561.7 5604.4 5606.2 5717.8 5965.7 5983.4 6354.7 6488.2 5471.5 6231.2 6235.2 6245.1 6285.8 5825.7 6084.1 6158.0 6174.1 6236.1 4939.4 5961.6 5988.9 6000.1 6034.4 5339.3 6063.8 6109.7 6153.8 6159.3 5999.9 6000.0 6017.5 6019.7 6043.0 09:35:48 INFO - PID 1630 | 09:35:48 INFO - PID 1630 | [#5] yui.html Cycles:180 Average:19670.19 Median:28747.06 stddev:27899.77 (97.1%) stddev-sans-first:27941.90 09:35:48 INFO - PID 1630 | Values: 756.8 762.0 822.2 825.6 836.9 15106.2 15895.4 15895.8 15898.7 15926.7 14382.4 14512.9 14727.8 14740.0 14741.6 942.6 960.3 961.4 967.8 970.2 938.8 963.2 966.4 977.8 980.6 1028.9 1029.6 1032.0 1032.3 1033.9 1020.9 1022.4 1023.6 1025.0 1030.7 764.7 765.5 765.6 766.6 767.5 15527.6 15877.7 15951.5 15997.8 16122.1 14116.1 14319.6 14620.5 14667.3 14738.2 11486.2 11742.9 11825.4 11848.9 12099.3 3669.4 3723.6 3793.0 3867.0 3871.8 109335.4 109394.0 110275.0 110611.4 112399.4 9898.2 10076.8 10108.5 10254.7 10314.9 24652.0 25032.5 25092.9 25118.1 25140.1 7735.6 7824.8 7828.5 7922.4 8054.5 3715.8 4190.2 4201.7 4277.8 4280.0 3354.7 3508.0 3547.4 3611.4 3629.1 617.9 635.6 635.7 652.1 659.5 532.7 568.2 645.9 661.4 690.0 9518.2 10170.0 10203.8 10235.4 10380.4 549.0 553.4 589.7 620.2 704.3 4927.7 4937.8 4943.1 4949.4 4950.3 70360.0 97496.0 97736.0 101211.0 101762.5 86873.6 86962.6 87205.6 87744.2 87777.7 48520.8 54567.5 55766.7 71760.3 76096.2 10601.5 10698.4 10860.8 10990.6 11000.9 32354.0 47062.0 48113.8 48166.0 48370.8 61358.0 62028.8 62098.7 62534.1 62593.0 13188.6 13301.7 13380.9 13470.7 14080.7 14247.4 14261.6 14401.6 14503.3 14621.4 11819.8 13177.7 13468.4 13711.0 13850.2 13137.4 13150.8 13461.3 13469.3 14038.8 12064.8 12158.0 13315.1 13662.0 14003.2 8504.5 9396.7 10449.4 11022.3 11420.7 8896.5 9283.6 11084.4 11279.5 11327.2 09:35:48 INFO - PID 1630 | -------- Summary: end -------- 09:35:48 INFO - PID 1630 | 09:35:48 INFO - TEST-INFO | 1630: exit 0 09:35:48 INFO - TEST-OK | dromaeo_css | took 1119705ms 09:35:48 INFO - TEST-START | kraken 09:35:48 INFO - operating with platform_type : mac_ 09:35:48 INFO - Initialising browser for kraken test... 09:35:48 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/tmpcInPLj/profile http://localhost:49235/getInfo.html) 09:35:51 INFO - PID 1641 | __metrics Screen width/height:1600/1200 09:35:51 INFO - PID 1641 | colorDepth:24 09:35:51 INFO - PID 1641 | Browser inner width/height: 1024/689 09:35:51 INFO - PID 1641 | __metrics 09:35:51 INFO - PID 1641 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:35:51 INFO - PID 1641 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:35:51 INFO - TEST-INFO | 1641: exit 0 09:35:51 INFO - Browser initialized. 09:35:51 INFO - Running cycle 1/1 for kraken test... 09:35:51 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/tmpcInPLj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:36:04 INFO - PID 1642 | RSS: Main: 233414656 09:36:04 INFO - PID 1642 | 09:36:06 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-beat-detection) 09:36:07 INFO - PID 1642 | RSS: Main: 284037120 09:36:07 INFO - PID 1642 | 09:36:10 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-dft) 09:36:10 INFO - PID 1642 | RSS: Main: 286318592 09:36:10 INFO - PID 1642 | 09:36:14 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-fft) 09:36:15 INFO - PID 1642 | RSS: Main: 377929728 09:36:15 INFO - PID 1642 | 09:36:17 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-oscillator) 09:36:17 INFO - PID 1642 | RSS: Main: 280551424 09:36:17 INFO - PID 1642 | 09:36:19 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:36:20 INFO - PID 1642 | RSS: Main: 283963392 09:36:20 INFO - PID 1642 | 09:36:23 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-darkroom) 09:36:24 INFO - PID 1642 | RSS: Main: 325165056 09:36:24 INFO - PID 1642 | 09:36:27 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-desaturate) 09:36:27 INFO - PID 1642 | RSS: Main: 336789504 09:36:27 INFO - PID 1642 | 09:36:30 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49235/tests/kraken/driver.html?testName=json-parse-financial) 09:36:31 INFO - PID 1642 | RSS: Main: 345632768 09:36:31 INFO - PID 1642 | 09:36:32 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49235/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:36:33 INFO - PID 1642 | RSS: Main: 301830144 09:36:33 INFO - PID 1642 | 09:36:35 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:36:36 INFO - PID 1642 | RSS: Main: 306282496 09:36:36 INFO - PID 1642 | 09:36:38 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:36:38 INFO - PID 1642 | RSS: Main: 308649984 09:36:38 INFO - PID 1642 | 09:36:41 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:36:42 INFO - PID 1642 | RSS: Main: 325718016 09:36:42 INFO - PID 1642 | 09:36:45 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:36:46 INFO - PID 1642 | RSS: Main: 319352832 09:36:46 INFO - PID 1642 | 09:36:47 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49235/tests/kraken/driver.html?testName=ai-astar) 09:36:48 INFO - PID 1642 | RSS: Main: 380989440 09:36:48 INFO - PID 1642 | 09:36:48 INFO - PID 1642 | __start_tp_report 09:36:48 INFO - PID 1642 | _x_x_mozilla_page_load 09:36:48 INFO - PID 1642 | _x_x_mozilla_page_load_details 09:36:48 INFO - PID 1642 | |i|pagename|runs| 09:36:48 INFO - PID 1642 | |0;ai-astar;115;115;115;115;116;116;117;115;115;116 09:36:48 INFO - PID 1642 | |1;audio-beat-detection;142;112;111;149;112;112;111;110;132;111 09:36:48 INFO - PID 1642 | |2;audio-dft;219;267;184;184;184;182;182;248;261;183 09:36:48 INFO - PID 1642 | |3;audio-fft;83;58;61;91;59;59;59;61;118;83 09:36:48 INFO - PID 1642 | |4;audio-oscillator;92;70;86;70;70;70;83;69;70;83 09:36:48 INFO - PID 1642 | |5;imaging-gaussian-blur;127;134;87;87;88;89;88;87;87;87 09:36:48 INFO - PID 1642 | |6;imaging-darkroom;94;94;93;94;94;94;94;93;94;94 09:36:48 INFO - PID 1642 | |7;imaging-desaturate;85;85;85;83;86;86;86;84;83;83 09:36:48 INFO - PID 1642 | |8;json-parse-financial;63;60;61;62;63;62;63;66;63;61 09:36:48 INFO - PID 1642 | |9;json-stringify-tinderbox;54;56;62;63;60;57;58;57;57;57 09:36:48 INFO - PID 1642 | |10;stanford-crypto-aes;84;82;82;83;83;82;83;84;82;83 09:36:48 INFO - PID 1642 | |11;stanford-crypto-ccm;138;139;138;139;138;135;137;139;183;140 09:36:48 INFO - PID 1642 | |12;stanford-crypto-pbkdf2;192;238;232;185;188;184;186;186;183;184 09:36:48 INFO - PID 1642 | |13;stanford-crypto-sha256-iterative;68;69;67;68;68;69;69;67;68;65 09:36:48 INFO - PID 1642 | __end_tp_report 09:36:48 INFO - PID 1642 | __start_cc_report 09:36:48 INFO - PID 1642 | _x_x_mozilla_cycle_collect,2082 09:36:48 INFO - PID 1642 | __end_cc_report 09:36:48 INFO - PID 1642 | __startTimestamp1489682208227__endTimestamp 09:36:48 INFO - PID 1642 | 09:36:48 INFO - PID 1642 | ------- Summary: start ------- 09:36:48 INFO - PID 1642 | Number of tests: 14 09:36:48 INFO - PID 1642 | 09:36:48 INFO - PID 1642 | [#0] ai-astar Cycles:10 Average:115.50 Median:115.50 stddev:0.71 (0.6%) stddev-sans-first:0.73 09:36:48 INFO - PID 1642 | Values: 115.0 115.0 115.0 115.0 116.0 116.0 117.0 115.0 115.0 116.0 09:36:48 INFO - PID 1642 | 09:36:48 INFO - PID 1642 | [#1] audio-beat-detection Cycles:10 Average:120.20 Median:112.00 stddev:14.92 (13.3%) stddev-sans-first:13.58 09:36:48 INFO - PID 1642 | Values: 142.0 112.0 111.0 149.0 112.0 112.0 111.0 110.0 132.0 111.0 09:36:48 INFO - PID 1642 | 09:36:48 INFO - PID 1642 | [#2] audio-dft Cycles:10 Average:209.40 Median:201.50 stddev:36.05 (17.9%) stddev-sans-first:38.07 09:36:48 INFO - PID 1642 | Values: 219.0 267.0 184.0 184.0 184.0 182.0 182.0 248.0 261.0 183.0 09:36:48 INFO - PID 1642 | 09:36:48 INFO - PID 1642 | [#3] audio-fft Cycles:10 Average:73.20 Median:61.00 stddev:20.14 (33.0%) stddev-sans-first:21.04 09:36:48 INFO - PID 1642 | Values: 83.0 58.0 61.0 91.0 59.0 59.0 59.0 61.0 118.0 83.0 09:36:48 INFO - PID 1642 | 09:36:48 INFO - PID 1642 | [#4] audio-oscillator Cycles:10 Average:76.30 Median:76.50 stddev:8.71 (11.4%) stddev-sans-first:7.14 09:36:48 INFO - PID 1642 | Values: 92.0 70.0 86.0 70.0 70.0 70.0 83.0 69.0 70.0 83.0 09:36:48 INFO - PID 1642 | 09:36:48 INFO - PID 1642 | [#5] imaging-gaussian-blur Cycles:10 Average:96.10 Median:87.00 stddev:18.22 (20.9%) stddev-sans-first:15.52 09:36:48 INFO - PID 1642 | Values: 127.0 134.0 87.0 87.0 88.0 89.0 88.0 87.0 87.0 87.0 09:36:48 INFO - PID 1642 | 09:36:48 INFO - PID 1642 | [#6] imaging-darkroom Cycles:10 Average:93.80 Median:94.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 09:36:48 INFO - PID 1642 | Values: 94.0 94.0 93.0 94.0 94.0 94.0 94.0 93.0 94.0 94.0 09:36:48 INFO - PID 1642 | 09:36:48 INFO - PID 1642 | [#7] imaging-desaturate Cycles:10 Average:84.60 Median:85.00 stddev:1.26 (1.5%) stddev-sans-first:1.33 09:36:48 INFO - PID 1642 | Values: 85.0 85.0 85.0 83.0 86.0 86.0 86.0 84.0 83.0 83.0 09:36:48 INFO - PID 1642 | 09:36:48 INFO - PID 1642 | [#8] json-parse-financial Cycles:10 Average:62.40 Median:63.00 stddev:1.65 (2.6%) stddev-sans-first:1.73 09:36:48 INFO - PID 1642 | Values: 63.0 60.0 61.0 62.0 63.0 62.0 63.0 66.0 63.0 61.0 09:36:48 INFO - PID 1642 | 09:36:48 INFO - PID 1642 | [#9] json-stringify-tinderbox Cycles:10 Average:58.10 Median:57.50 stddev:2.77 (4.8%) stddev-sans-first:2.51 09:36:48 INFO - PID 1642 | Values: 54.0 56.0 62.0 63.0 60.0 57.0 58.0 57.0 57.0 57.0 09:36:48 INFO - PID 1642 | 09:36:48 INFO - PID 1642 | [#10] stanford-crypto-aes Cycles:10 Average:82.80 Median:83.00 stddev:0.79 (1.0%) stddev-sans-first:0.71 09:36:48 INFO - PID 1642 | Values: 84.0 82.0 82.0 83.0 83.0 82.0 83.0 84.0 82.0 83.0 09:36:48 INFO - PID 1642 | 09:36:48 INFO - PID 1642 | [#11] stanford-crypto-ccm Cycles:10 Average:142.60 Median:139.00 stddev:14.26 (10.3%) stddev-sans-first:15.03 09:36:48 INFO - PID 1642 | Values: 138.0 139.0 138.0 139.0 138.0 135.0 137.0 139.0 183.0 140.0 09:36:48 INFO - PID 1642 | 09:36:48 INFO - PID 1642 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:195.80 Median:187.00 stddev:20.86 (11.2%) stddev-sans-first:22.08 09:36:48 INFO - PID 1642 | Values: 192.0 238.0 232.0 185.0 188.0 184.0 186.0 186.0 183.0 184.0 09:36:48 INFO - PID 1642 | 09:36:48 INFO - PID 1642 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.80 Median:68.00 stddev:1.23 (1.8%) stddev-sans-first:1.30 09:36:48 INFO - PID 1642 | Values: 68.0 69.0 67.0 68.0 68.0 69.0 69.0 67.0 68.0 65.0 09:36:48 INFO - PID 1642 | -------- Summary: end -------- 09:36:48 INFO - PID 1642 | 09:36:49 INFO - TEST-INFO | 1642: exit 0 09:36:49 INFO - TEST-OK | kraken | took 60494ms 09:36:49 INFO - SUITE-END | took 1180s 09:36:49 INFO - Completed test suite (00:19:40) 09:36:49 INFO - javascript benchmark 09:36:49 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7743.892271967318, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2674.919752407427, 3320.8671653133874, 3330.150811543058, 3364.5121457388673, 3367.7137443317315, 5582.581306402016, 5609.382967873541, 5652.773889044439, 5655.293088363955, 5697.515711164551, 4631.7684115794245, 5701.116326969316, 5801.854953626162, 5814.186013958049, 5842.152887831262, 5106.157484015133, 5158.7936482540745, 5179.766910489018, 5203.98522288154, 5236.842894713165, 5270.367555893298, 5282.947170528303, 5303.602229832778, 5303.787848486075, 5313.521783039526, 5113.795448182068, 5159.380874295071, 5176.2235664650225, 5213.608979326527, 5226.55574276187, 5254.08053590624, 5267.762950667229, 5278.709670968095, 5280.788768449257, 5286.788528458857, 5122.76947537358, 5172.534471897509, 5184.637075404723, 5186.92219616706, 5242.344706911636, 5570.526505247019, 5689.943100569024, 5713.942860571383, 5718.828435146911, 5719.771209151629, 5899.262592175978, 5936.7922122725495, 6242.313345531988, 6276.623402595859, 6302.086197501337, 6226.097215903668, 6304.621722696607, 6304.684765761694, 6322.52581056418, 6346.587471814318, 5788.479036886701, 5848.912266316009, 5852.67810270435, 5855.531557475391, 5890.97054514729, 6192.783252586228, 6218.129461875341, 6280.874382512324, 6295.842603934938, 6297.275813281532, 6212.285587157445, 6228.532860035515, 6242.687865606701, 6251.531135164881, 6253.0, 6301.023341382093, 6319.8104056878365, 6324.620522768649, 6335.081413195153, 6337.493000559944, 6104.298005729309, 6105.206323177958, 6121.696863452088, 6291.339409362043, 6292.0, 6149.292831324366, 6179.227596550431, 6231.3145553989025, 6273.027680709497, 6279.748810047547, 4220.873373798791, 4880.780364883589, 4988.825391111293, 4997.800087996512, 5030.748462576857, 4415.381846541487, 4461.7992190351515, 4472.821087156477, 4474.172278128557, 4516.0, 5607.0, 5662.348829884616, 5663.32040155184, 5776.566757493205, 5776.711164441761, 5278.261043453919, 6196.132541444202, 6253.0620406938415, 6271.717772700239, 6288.088227207029, 5725.599208055396, 5728.398518155617, 5733.6273142246455, 5739.024365857814, 5761.078227483583, 5314.256004159421, 5356.625036247426, 5423.213634023124, 5430.185472179205, 5438.891222175614, 5256.395514515727, 6261.154744109487, 6270.435660790459, 6284.5600807943, 6298.905516417166, 6178.66017369049, 6221.0, 6223.37766223374, 6258.655773932295, 6272.153259310117, 6287.182666253358, 6292.244930608356, 6295.496360291074, 6301.96849015752, 6321.0834429008455, 6101.908471373022, 6216.285127210248, 6226.25284965807, 6240.781572644935, 6242.469390101892, 5990.430909063632, 6270.7491700331475, 6284.4344009039405, 6289.308176100534, 6295.496360291074, 6022.307434644897, 6032.909506357496, 6045.304789949212, 6065.54508411862, 6085.786997455068, 6039.63762174271, 6107.522607572365, 6149.616336324291, 6158.722857471335, 6167.660778657217, 6044.516438684983, 6071.39286071407, 6079.1185278133535, 6088.269407671108, 6095.908561371494, 6181.474574661024, 6194.4115309045565, 6214.0, 6226.439620434183, 6230.501559875109, 4885.853424397274, 5791.986402379651, 5840.2115714378015, 5859.5019423349495, 6048.879022419615], "value": 5684.239063639741, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [731.8792399254123, 779.6491578789544, 794.8132188935601, 859.4628357276706, 874.8643960186173, 27994.440111197764, 28412.715872841265, 28453.715462845365, 28486.43919510061, 28571.714282857167, 37214.813925930335, 38114.0, 38179.42730859039, 38184.04539886504, 38229.61770382295, 9005.909940900588, 9212.078792120768, 9249.398789078688, 9330.813383732322, 9358.953205233998, 7180.784576462688, 9119.270458363315, 9300.906990930072, 9302.348835581512, 9309.813803723922, 7409.70361185552, 10600.0, 10871.83692244617, 10875.728106797354, 10882.455877206148, 10886.40124793136, 10919.672409827717, 10944.616938407196, 10958.890411095947, 10976.725581860517, 7098.361147496698, 10650.0, 10799.892001079967, 10931.125509959184, 10955.945220273927, 27943.58084628732, 28295.717042829514, 28326.0, 28372.85813570919, 36246.91259262226, 3699.722520810923, 3805.3721136012523, 3809.3904975203836, 3810.3903375459795, 3820.083180036799, 27476.45047099067, 27652.58521122185, 27754.0, 28574.0, 28659.283517912012, 3075.0031199301134, 3117.345357474933, 3137.4823154179535, 3149.3071524264624, 3190.6171259448784, 343827.2808635941, 347519.0, 347713.0, 347858.0, 350399.0, 52766.0, 53130.73434632879, 53439.46560534422, 53469.7326513365, 53586.46413535815, 55172.0, 55173.44826551763, 55419.0, 55441.0, 55482.16776748352, 14255.002149849619, 14582.0, 14594.270286485633, 14594.562163134911, 14635.92682036583, 11743.530258789553, 11809.996150327146, 11818.0, 11846.170768046153, 11850.644480665595, 11822.88177118235, 12005.579804706793, 13758.31208439574, 14100.506482273071, 14181.14913105217, 4886.2494653177555, 4898.471844253724, 6085.726142323606, 6097.756089756449, 6103.298120716086, 979.8089372572423, 1040.8862517106622, 1119.9304664045978, 1130.5873356225084, 1176.2236923630362, 20458.0, 26172.083977060713, 26329.341766455607, 26329.868350658126, 26356.472870542482, 1228.2875931959443, 1230.8399908011902, 1237.5792230641625, 1283.813846992181, 1285.807128930668, 12424.37878106091, 12495.25028498293, 13004.934975325063, 13424.261665608485, 13681.931590341985, 451196.0, 454420.0, 455578.4442155602, 456502.0, 457323.0, 57308.140377894364, 57508.42491575031, 57646.423535764945, 57647.71176144092, 57795.0, 1427.229296180072, 1456.5557504961089, 1464.12152708372, 1465.655570940828, 1480.1267252321181, 24692.13577524778, 25205.495890082464, 25632.48735025252, 25865.48269034571, 25871.0, 47203.69574129778, 47327.76336118297, 47370.76314618405, 47378.0, 47452.288215676104, 416950.0, 419900.0, 421059.0, 421728.89135554124, 421966.0, 34378.484322735676, 34882.47676284807, 35035.824820875736, 35114.47328290027, 35141.0, 31688.207794805316, 32680.67319326776, 40097.79951100226, 44773.10453790971, 44847.77576112099, 31178.844105779324, 33519.994400168034, 34527.82736086303, 34795.30409391849, 35773.64226357703, 34053.65946340505, 34193.31613367768, 34224.65775342215, 35439.0, 35440.759573414805, 35044.82477587698, 35512.28975420529, 35656.286874262885, 35691.393887274615, 35808.10479738027, 25398.619020714334, 27173.86413067922, 28600.42799143964, 30025.3994920096, 30477.390452191277, 19182.0, 22928.31215063551, 23278.534429311658, 23380.76619233786, 23494.76505234926], "value": 18368.50296155714, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [397.90251388409825, 408.24882216721227, 415.2960731560006, 417.1365273883062, 432.9090890912908, 19674.55695253332, 20519.794802051994, 21175.94120293983, 21250.14999400024, 21271.680924786142, 17647.823521764778, 21309.467263318424, 22190.778092219072, 22994.195203167892, 23047.76952230477, 6597.63712995785, 6983.16202055754, 6985.6507174641065, 6996.3353481419435, 7002.474814388916, 6719.260881303053, 6832.590044597315, 6840.760573379933, 6877.656117194146, 6883.174019117713, 6583.0, 6862.691178896936, 6884.449244060464, 6903.689333979983, 6921.723131074777, 5973.731182096794, 6785.864282714343, 6910.689018994107, 6931.376176144171, 6946.861062778741, 6372.0, 6547.770828021044, 6622.602643841385, 6628.602283862983, 6750.6498700260145, 26896.865515672493, 26911.86544067267, 26935.057272995546, 27027.72972270272, 27194.456110877672, 3311.0, 3345.8327083645963, 3361.6806403391884, 3406.7615266931325, 3519.1026288296575, 16232.594185145466, 16374.590635234214, 16443.424480143138, 16674.999500030037, 17090.914545427193, 1952.7754308254448, 2013.3355992522434, 2019.8687085339552, 2023.4637820977453, 2104.558042810996, 118099.6909422295, 132430.0, 150085.9999999989, 151030.0, 153725.0, 16375.91812040956, 20733.89633051825, 20831.583368332547, 20892.58214835725, 20913.47716307074, 34636.78771243045, 34709.0, 34743.65256347404, 34768.30463390768, 34833.0, 8381.49711017341, 10123.240756943258, 10227.386356818615, 10438.843417348746, 10560.841587376195, 8335.333173346118, 8360.707375241838, 8379.413441059189, 8572.828543429096, 8587.141285871363, 8225.71210007647, 10081.747956301151, 10232.283740138268, 10330.845037324447, 10375.56714836806, 3388.610309814403, 3932.6853851692367, 4038.374052021941, 4045.379385450006, 4049.319532394082, 1022.3763504262394, 1038.896110388955, 1052.9157667386592, 1055.8152323343384, 1105.1490352428584, 17979.831310964746, 21948.561028779655, 22743.31770046901, 22798.65802012971, 22854.771452285597, 1021.3922715984127, 1022.5960745505483, 1025.3079171559316, 1037.190991027, 1066.5200659703164, 9443.214251795736, 11710.88289117115, 11904.226225295328, 11917.702057448632, 11991.880081199251, 340876.0, 522337.0, 528422.3578882081, 530665.0, 566873.0, 138055.0, 140301.29849350688, 145725.27137364246, 146367.80448293284, 146715.0, 1268.1123213750227, 1280.5326055989497, 1329.7672907241194, 1329.9958531308994, 1333.180094242034, 20303.390898273075, 20370.185192592722, 21225.0, 21371.35885923425, 21910.342689719335, 45689.77155114336, 57077.00230492079, 63104.368956311686, 63137.684311578145, 64395.06814795564, 70593.64703176451, 82714.58642706748, 83036.3392732154, 83783.0, 84257.41683248537, 12225.266484010988, 12328.876711232773, 12328.938355308524, 12478.937605311916, 12616.621501354974, 13850.376733046836, 13925.72148557003, 14142.929285353508, 14303.856961430252, 14361.281935903373, 11527.538898443969, 11832.881671183522, 12101.515939362327, 12128.393580320771, 12161.209521381083, 5673.375928647928, 8812.559372031244, 11454.484548195185, 11640.185187036826, 11944.48714071082, 11825.053995680497, 11881.940590296994, 11979.221350612183, 12038.217515861441, 12073.396330183632, 12210.877891220975, 12390.592587038636, 12452.190607610839, 12553.811692824433, 12727.618171454871, 9342.95328523353, 11340.473483715285, 11411.771764564828, 11529.82705259405, 11564.537418503165], "value": 11614.052556096569, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1349.5546469665012, 1543.0972880864695, 1572.6461546152111, 1579.1630435868985, 1648.7774150489684, 9130.406523575972, 9548.140667339938, 10460.79078418432, 10760.946195269033, 10782.245242833005, 10467.162626989842, 10532.631357902475, 10555.102816260627, 10555.313904596196, 10568.735781605465, 2308.7806658367426, 2343.8359314847967, 2347.495288512969, 2349.859008459498, 2353.011735071274, 1986.6622674145358, 2072.844536659749, 2267.9886600567056, 2365.89353479093, 2368.763123687626, 2288.7711228877147, 2333.4749681321737, 2334.1480359668685, 2338.485533182697, 2340.0, 2285.1430713482446, 2345.29641107667, 2351.6590094436383, 2352.5177338645535, 2357.0, 2097.328854766475, 2266.150193677371, 2271.3072512883564, 2341.309313752447, 2345.64815277708, 10681.0, 10786.0, 10803.891961080368, 10804.945975270151, 10833.349999000007, 4855.465898751135, 5477.534409575191, 5697.515711164556, 5701.372848986608, 5710.514606258473, 3341.7493687973256, 3536.169000284931, 3551.5027896094566, 3602.6937710294656, 3629.7096232301615, 1151.7408583068827, 1203.8314635950974, 1207.5532053140307, 1254.3163975633302, 1257.346179986411, 14631.707365852624, 14730.55808325752, 14926.850731492763, 14928.850711492854, 14975.32611032506, 4012.799360031987, 4660.697054691502, 4728.612570710838, 4780.259059845729, 4869.415670119537, 12140.635780926586, 12148.939255303843, 12156.845099715512, 12157.392130393446, 12167.0, 5561.638493497991, 5564.10974244119, 5568.9443105569235, 5569.275994120739, 5577.860553486195, 4427.446569178853, 4938.555530002245, 4940.629452791055, 5093.23601459784, 5105.285260063594, 3624.7643903146213, 3971.6425521703186, 4082.1631565528996, 4084.36692312692, 4086.3870419436726, 1104.276698762312, 1138.299945533491, 1143.3368646185193, 1156.8553930758655, 1159.113278342069, 881.8809460722848, 909.713440266314, 911.5988964855443, 936.842209287384, 954.465499320369, 6172.22847144107, 6245.6252624842655, 6343.492520598341, 6384.07430922523, 6502.577332473374, 908.2597682888425, 1011.4841430870309, 1031.664708969588, 1044.5143008500932, 1071.2822408985999, 3801.714871384602, 3932.016995751062, 4034.757914525138, 4038.394240863894, 4062.939055914164, 12598.181118227287, 12718.236905785498, 13008.349582520836, 13039.217646941213, 13081.345932703327, 10894.455277236106, 12641.620751377473, 12784.488620455078, 12850.0, 12896.097273190786, 9543.093406126407, 9806.16647584949, 9901.702948911545, 9905.0, 9971.351862128939, 6071.483923866521, 6121.112438696453, 6193.071039343954, 6230.252369715663, 6274.309825919237, 11424.714382140513, 11807.881921180679, 11879.406029698654, 11900.988415984739, 11953.103517236068, 11729.35488548138, 11817.586384476503, 11930.642080737593, 11974.341411222118, 11991.400429978641, 7470.4770666052855, 8763.956180219058, 9071.410358326688, 9086.273098152, 9103.0, 7694.433950964108, 9806.8231812183, 10072.899271007489, 10097.848532271875, 10112.544935477774, 8552.743417697478, 8703.390762646553, 8761.211490965843, 8768.605412756313, 8931.866022009544, 8572.52851093196, 8582.313414926915, 8601.13988601135, 8642.740717778477, 8690.565471726262, 7589.0, 7599.088109426904, 7685.385169186564, 7685.962395076593, 7733.651985660546, 4877.561019508261, 5583.469570390969, 5589.5807814413265, 5749.166370876116, 5906.763729450774, 5693.373728889736, 5783.277090363705, 5800.24596802413, 5925.140854575976, 5985.072313791369], "value": 4990.420263673392, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [361.1530959899037, 388.8541796826192, 389.3070334804048, 389.7934094929688, 391.39724823771394, 5430.8913821723545, 5637.154426835977, 5753.280839895013, 5780.739866705998, 5783.190353350529, 4551.930296380348, 4727.479977202505, 4991.351124353829, 5683.744231509581, 5738.42615738426, 4060.6751459883285, 4558.156741002908, 4751.453582837983, 4774.355462012636, 4961.8511444656715, 4745.311929770181, 4753.762311884409, 4755.881102972436, 4756.952430475685, 4781.832635857746, 4755.405574303212, 4762.0, 4774.212254977924, 4786.162421576228, 4798.256270278112, 4645.590283497832, 4664.86005419838, 4722.851102748241, 4774.785134668949, 4805.90388192236, 3547.3614749345365, 3789.2421515696697, 4241.833495788652, 4316.6546676265825, 4332.1119170569955, 4630.351750754897, 5579.246801681762, 5612.719364031823, 5658.896515179542, 5671.914921276184, 4817.903641927177, 5223.37319521656, 5477.972610136963, 5512.614117011811, 5582.771790206149, 6115.938840611581, 6126.540509461808, 6133.386661333876, 6154.661493617849, 6260.342664020303, 4843.733594652293, 4857.198562237262, 4871.56155945963, 4878.878028049292, 4909.8772530686665, 4940.863601371669, 6180.918339290651, 6369.585976911486, 6380.84047898797, 6470.0, 4090.5295890972325, 4278.780547543997, 4869.853904382874, 5054.848354549369, 5064.4935506449065, 5153.896922061613, 5162.238202561612, 5171.172612382001, 5184.714840683723, 5199.194124910645, 5185.429602743695, 5383.327084114486, 5486.451354864562, 5492.972535137299, 5518.641288316327, 5562.276904002535, 6022.45797878193, 6172.8765424691255, 6190.659513726699, 6212.968935155386, 1464.3044553837055, 1593.4184022831814, 1774.1572752942507, 1782.4028950301536, 1782.803891571926, 1147.7991351513451, 1230.5877531027027, 1279.3667134768164, 1292.7737645911927, 1326.0916272353468, 4801.623196517677, 5573.693446860461, 5808.854778630483, 5812.854678633068, 5822.0, 5957.255343082114, 5985.104671898177, 5986.281646202484, 6247.562670613013, 6335.524835637346, 4880.438749543777, 5491.945080549223, 5717.42825717425, 5732.856678583069, 5747.591839999227, 3336.115929278743, 3336.616289126733, 3336.632970373257, 3355.8086879158036, 3365.74756893234, 5474.91787623186, 6213.850437669045, 6247.0, 6518.804435866931, 6560.343965603402, 6501.447376972916, 6508.284088750233, 6586.769463068769, 6595.505337099737, 6599.769008084694, 5813.331466881363, 6058.304813769188, 6208.255009398811, 6588.571742836701, 6611.50413718973, 5403.973245083422, 6096.542759292982, 6202.85247229264, 6332.240131184288, 6369.522285828489, 5402.246023499296, 5915.556333274935, 6053.213082299273, 6089.421504957022, 6245.687715614292, 5419.945800541944, 6333.245038408307, 6517.217933847968, 6624.90062649051, 6641.468682505485, 5309.867253318698, 5468.671879687232, 5561.749721262472, 5604.355499117653, 5606.18710286998, 5717.7712891485535, 5965.717370765306, 5983.37174596661, 6354.714037868214, 6488.183308673579, 5471.480209380103, 6231.22109736283, 6235.189425374672, 6245.094461303176, 6285.842853928689, 5825.679587622722, 6084.14821924922, 6157.96921015392, 6174.086033329682, 6236.095766113979, 4939.357883475269, 5961.582689211714, 5988.910166347596, 6000.069989151689, 6034.426729460694, 5339.252504649275, 6063.757449701962, 6109.725062372293, 6153.84615384619, 6159.260888693386, 5999.88000239984, 6000.0, 6017.548683848817, 6019.6990150493175, 6042.969785151046], "value": 4722.2553729754145, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [756.8221467955033, 761.9695212191513, 822.1531822223109, 825.5665775467878, 836.932910539063, 15106.24468776561, 15895.364185432569, 15895.841041589596, 15898.682026359465, 15926.681466370681, 14382.352794124263, 14512.854871451282, 14727.779083313759, 14740.0, 14741.55775326742, 942.6276620734806, 960.2653969713175, 961.4375590279694, 967.8209531236744, 970.175350951689, 938.8357037518441, 963.1668606655228, 966.3911735606558, 977.7799995001103, 980.5587485631459, 1028.9022542858454, 1029.5933106422958, 1032.0, 1032.2825636182856, 1033.886272510027, 1020.9336393134497, 1022.3916769522123, 1023.5956797065118, 1024.9641262555813, 1030.6701855406272, 764.6941223510585, 765.4679997401807, 765.555977533035, 766.5707203965741, 767.5126294802841, 15527.611809704847, 15877.682446351124, 15951.521454356387, 15997.840021599752, 16122.113283769388, 14116.132864709627, 14319.57041288763, 14620.488282910048, 14667.266636668232, 14738.189399583018, 11486.195966282363, 11742.941285293604, 11825.408729563573, 11848.940755296167, 12099.274043557414, 3669.3578623741005, 3723.590405055442, 3792.9569368423627, 3867.0, 3871.8064096795047, 109335.35996960053, 109394.0, 110274.9999999992, 110611.44694276557, 112399.43800281028, 9898.158656514277, 10076.848847267296, 10108.494575271206, 10254.743631409125, 10314.948425257826, 24652.0, 25032.4993500129, 25092.874535627205, 25118.12086576961, 25140.12009579656, 7735.61321933901, 7824.804379890435, 7828.491148075357, 7922.36621070313, 8054.476459030145, 3715.8327875245677, 4190.161967606491, 4201.70588058833, 4277.807498662568, 4279.957200428018, 3354.7148492377933, 3508.0, 3547.396942519778, 3611.4041183205004, 3629.1471504196493, 617.8980468222783, 635.6345101566618, 635.707574515728, 652.0805664013718, 659.4724220623577, 532.7229840483004, 568.1506148308223, 645.9224893012869, 661.4113439039259, 689.9689513971883, 9518.238540916711, 10170.03066615644, 10203.846942295871, 10235.43705096212, 10380.377177369382, 549.0, 553.4216743503049, 589.6874656432097, 620.178189238961, 704.3203308806924, 4927.655064145475, 4937.7777999990085, 4943.110240156806, 4949.381327334083, 4950.331705219821, 70360.0, 97496.0, 97736.0, 101211.0, 101762.4911875436, 86873.56563217143, 86962.56518717493, 87205.56397217973, 87744.24511509734, 87777.68333475004, 48520.75739621279, 54567.45432545703, 55766.72116639391, 71760.28239717535, 76096.23903760892, 10601.46992650349, 10698.358098514114, 10860.78278434411, 10990.615328463466, 11000.944995274973, 32354.0, 47062.0, 48113.75943120262, 48166.0, 48370.79073023202, 61358.0, 62028.75942481035, 62098.689506552175, 62534.06198906929, 62593.0, 13188.604341869375, 13301.733965320833, 13380.933095334462, 13470.73058538804, 14080.718385632435, 14247.358868850719, 14261.572152835432, 14401.567952961428, 14503.274836258355, 14621.415143394144, 11819.763604728028, 13177.682231776746, 13468.393922273717, 13711.0, 13850.238236896665, 13137.408816603085, 13150.802737958747, 13461.259630720404, 13469.259190744604, 14038.78941815853, 12064.819027714415, 12158.0, 13315.06794524374, 13662.0, 14003.159810411407, 8504.489730616182, 9396.67111651089, 10449.373037617766, 11022.338659680445, 11420.657380278604, 8896.510691912006, 9283.62865485373, 11084.40958257357, 11279.548818047186, 11327.207095503236], "value": 7545.740323882927, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1478.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 115.0, 115.0, 115.0, 116.0, 116.0, 117.0, 115.0, 115.0, 116.0], "value": 115.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 112.0, 111.0, 149.0, 112.0, 112.0, 111.0, 110.0, 132.0, 111.0], "value": 120.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [219.0, 267.0, 184.0, 184.0, 184.0, 182.0, 182.0, 248.0, 261.0, 183.0], "value": 209.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 58.0, 61.0, 91.0, 59.0, 59.0, 59.0, 61.0, 118.0, 83.0], "value": 73.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 70.0, 86.0, 70.0, 70.0, 70.0, 83.0, 69.0, 70.0, 83.0], "value": 76.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 134.0, 87.0, 87.0, 88.0, 89.0, 88.0, 87.0, 87.0, 87.0], "value": 96.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 93.0, 94.0, 94.0, 94.0, 94.0, 93.0, 94.0, 94.0], "value": 93.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 85.0, 83.0, 86.0, 86.0, 86.0, 84.0, 83.0, 83.0], "value": 84.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 60.0, 61.0, 62.0, 63.0, 62.0, 63.0, 66.0, 63.0, 61.0], "value": 62.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 56.0, 62.0, 63.0, 60.0, 57.0, 58.0, 57.0, 57.0, 57.0], "value": 58.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 82.0, 82.0, 83.0, 83.0, 82.0, 83.0, 84.0, 82.0, 83.0], "value": 82.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 139.0, 138.0, 139.0, 138.0, 135.0, 137.0, 139.0, 183.0, 140.0], "value": 142.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [192.0, 238.0, 232.0, 185.0, 188.0, 184.0, 186.0, 186.0, 183.0, 184.0], "value": 195.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 67.0, 68.0, 68.0, 69.0, 69.0, 67.0, 68.0, 65.0], "value": 67.8, "unit": "score"}]}]} 09:36:49 INFO - Return code: 0 09:36:49 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:36:49 ERROR - # TBPL SUCCESS # 09:36:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:36:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:36:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:36:49 INFO - 2017-03-16 09:36:49.561 screenresolution[1646:9693] starting screenresolution argv=screenresolution get 09:36:49 INFO - 2017-03-16 09:36:49.563 screenresolution[1646:9693] Display 0: 1600x1200x32@60 09:36:49 INFO - 2017-03-16 09:36:49.569 screenresolution[1647:9696] starting screenresolution argv=screenresolution list 09:36:49 INFO - Available Modes on Display 0 09:36:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:36:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:36:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:36:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:36:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:36:49 INFO - Intel Iris: 09:36:49 INFO - Chipset Model: Intel Iris 09:36:49 INFO - Type: GPU 09:36:49 INFO - Bus: Built-In 09:36:49 INFO - VRAM (Dynamic, Max): 1536 MB 09:36:49 INFO - Vendor: Intel (0x8086) 09:36:49 INFO - Device ID: 0x0a2e 09:36:49 INFO - Revision ID: 0x0009 09:36:49 INFO - Displays: 09:36:49 INFO - Display: 09:36:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:36:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:36:49 INFO - Display Serial Number: 1600x1200 60 09:36:49 INFO - Main Display: Yes 09:36:49 INFO - Mirror: Off 09:36:49 INFO - Online: Yes 09:36:49 INFO - Rotation: Supported 09:36:49 INFO - Return code: 0 09:36:49 INFO - Running post-action listener: _resource_record_post_action 09:36:49 INFO - [mozharness: 2017-03-16 16:36:49.804417Z] Finished run-tests step (success) 09:36:49 INFO - Running post-run listener: _resource_record_post_run 09:36:49 INFO - Total resource usage - Wall time: 1201s; CPU: 53.0%; Read bytes: 36979712; Write bytes: 512428544; Read time: 391; Write time: 1617 09:36:49 INFO - TinderboxPrint: CPU usage
53.3% 09:36:49 INFO - TinderboxPrint: I/O read bytes / time
36,979,712 / 391 09:36:49 INFO - TinderboxPrint: I/O write bytes / time
512,428,544 / 1,617 09:36:49 INFO - TinderboxPrint: CPU idle
2,246.3 (46.7%) 09:36:49 INFO - TinderboxPrint: CPU system
951.0 (19.8%) 09:36:49 INFO - TinderboxPrint: CPU user
1,608.4 (33.5%) 09:36:49 INFO - TinderboxPrint: Swap in / out
246,968,320 / 0 09:36:49 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 205718016; Write bytes: 202597888; Read time: 15615; Write time: 391 09:36:49 INFO - run-tests - Wall time: 1181s; CPU: 53.0%; Read bytes: 34980864; Write bytes: 299025408; Read time: 369; Write time: 1193 09:36:50 INFO - Running post-run listener: _upload_blobber_files 09:36:50 INFO - Blob upload gear active. 09:36:50 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:36:50 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:36:50 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:36:50 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:36:50 INFO - (blobuploader) - INFO - Open directory for files ... 09:36:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:36:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:36:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:36:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:36:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:36:51 INFO - (blobuploader) - INFO - Done attempting. 09:36:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:36:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:36:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:36:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:36:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:36:53 INFO - (blobuploader) - INFO - Done attempting. 09:36:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:36:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:36:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:36:54 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:36:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:36:54 INFO - (blobuploader) - INFO - Done attempting. 09:36:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:36:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:36:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:36:56 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:36:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:36:56 INFO - (blobuploader) - INFO - Done attempting. 09:36:56 INFO - (blobuploader) - INFO - Iteration through files over. 09:36:56 INFO - Return code: 0 09:36:56 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:36:56 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:36:56 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a68dd1577dade9ec534d88e1005d21003d62b48e2560b53430259095df6f5914344f89898d1941bb3964c286191652ad4e4a6ec5808728c5cf805088d5019420", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9432baf8fd9508c38769262c73ca2c6cf08d2b5baebb177fd84d70177caffb744c39bd3ef70ba5fa900874de9aa1d1dc3f9ee332ab9bfa21867bf0997c07f23f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d660334c869498280e606b2f49ce1324eb669a813ebb24c7770247f395274338f3572077045cc8f36508595a37e70a1720a639e653557de1292f94df09b42bfb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5a33fb91f556c0e88acfcd3eb324299dcb8b165a5b8f9601f2e58d2789be674b0d8553d7d759a6b02276873d42aa967b6c719fe2f383f6348d64befa1ffabb42"} 09:36:56 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:36:56 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:36:56 INFO - Contents: 09:36:56 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a68dd1577dade9ec534d88e1005d21003d62b48e2560b53430259095df6f5914344f89898d1941bb3964c286191652ad4e4a6ec5808728c5cf805088d5019420", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9432baf8fd9508c38769262c73ca2c6cf08d2b5baebb177fd84d70177caffb744c39bd3ef70ba5fa900874de9aa1d1dc3f9ee332ab9bfa21867bf0997c07f23f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d660334c869498280e606b2f49ce1324eb669a813ebb24c7770247f395274338f3572077045cc8f36508595a37e70a1720a639e653557de1292f94df09b42bfb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5a33fb91f556c0e88acfcd3eb324299dcb8b165a5b8f9601f2e58d2789be674b0d8553d7d759a6b02276873d42aa967b6c719fe2f383f6348d64befa1ffabb42"} 09:36:56 INFO - Running post-run listener: copy_logs_to_upload_dir 09:36:56 INFO - Copying logs to upload dir... 09:36:56 INFO - mkdir: /builds/slave/test/build/upload/logs 09:36:56 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1285.444484 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 25 secs) (at 2017-03-16 09:36:56.755777) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-16 09:36:56.759732) ========= 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.MyFldXG1fk/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.FEUrtapOYp/Listeners TMPDIR=/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a68dd1577dade9ec534d88e1005d21003d62b48e2560b53430259095df6f5914344f89898d1941bb3964c286191652ad4e4a6ec5808728c5cf805088d5019420", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9432baf8fd9508c38769262c73ca2c6cf08d2b5baebb177fd84d70177caffb744c39bd3ef70ba5fa900874de9aa1d1dc3f9ee332ab9bfa21867bf0997c07f23f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d660334c869498280e606b2f49ce1324eb669a813ebb24c7770247f395274338f3572077045cc8f36508595a37e70a1720a639e653557de1292f94df09b42bfb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5a33fb91f556c0e88acfcd3eb324299dcb8b165a5b8f9601f2e58d2789be674b0d8553d7d759a6b02276873d42aa967b6c719fe2f383f6348d64befa1ffabb42"} build_url:https://queue.taskcluster.net/v1/task/Q_ukGa7XTaiLKPXZIhopOw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010599 build_url: 'https://queue.taskcluster.net/v1/task/Q_ukGa7XTaiLKPXZIhopOw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a68dd1577dade9ec534d88e1005d21003d62b48e2560b53430259095df6f5914344f89898d1941bb3964c286191652ad4e4a6ec5808728c5cf805088d5019420", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9432baf8fd9508c38769262c73ca2c6cf08d2b5baebb177fd84d70177caffb744c39bd3ef70ba5fa900874de9aa1d1dc3f9ee332ab9bfa21867bf0997c07f23f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d660334c869498280e606b2f49ce1324eb669a813ebb24c7770247f395274338f3572077045cc8f36508595a37e70a1720a639e653557de1292f94df09b42bfb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5a33fb91f556c0e88acfcd3eb324299dcb8b165a5b8f9601f2e58d2789be674b0d8553d7d759a6b02276873d42aa967b6c719fe2f383f6348d64befa1ffabb42"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-16 09:36:56.794132) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-16 09:36:56.794479) ========= 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.MyFldXG1fk/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.FEUrtapOYp/Listeners TMPDIR=/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/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.005192 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-16 09:36:56.843324) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-16 09:36:56.843640) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-16 09:36:56.843962) ========= ========= Total master_lag: 0.19 =========