builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0292 starttime: 1489680942.03 results: success (0) buildid: 20170316075736 builduid: 57686e0e65984254a5d451875c6bab0a revision: 3945f2297b997d59662bdb8ac20e092363eea532 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-16 09:15:42.032867) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-16 09:15:42.033265) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-16 09:15:42.033570) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-16 09:15:42.058304) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-16 09:15:42.058676) ========= 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.nO1nZ5OIbD/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.IRdIXnM9Ji/Listeners TMPDIR=/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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:42-- 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% 161M=0s 2017-03-16 09:15:42 (161 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.123095 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-16 09:15:42.205096) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-16 09:15:42.205418) ========= 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.nO1nZ5OIbD/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.IRdIXnM9Ji/Listeners TMPDIR=/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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.021121 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-16 09:15:42.254278) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-16 09:15:42.254637) ========= 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.nO1nZ5OIbD/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.IRdIXnM9Ji/Listeners TMPDIR=/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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:42,343 truncating revision to first 12 chars 2017-03-16 09:15:42,343 Setting DEBUG logging. 2017-03-16 09:15:42,344 attempt 1/10 2017-03-16 09:15:42,344 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:43,567 unpacking tar archive at: mozilla-central-3945f2297b99/testing/mozharness/ program finished with exit code 0 elapsedTime=1.485586 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-16 09:15:43.764811) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-16 09:15:43.765267) ========= script_repo_revision: 3945f2297b997d59662bdb8ac20e092363eea532 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-16 09:15:43.765680) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-16 09:15:43.765949) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-16 09:15:43.786140) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2017-03-16 09:15:43.786549) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.nO1nZ5OIbD/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.IRdIXnM9Ji/Listeners TMPDIR=/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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:43 INFO - MultiFileLogger online at 20170316 09:15:43 in /builds/slave/test 09:15:43 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:15:43 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:15:43 INFO - {'append_to_log': False, 09:15:43 INFO - 'base_work_dir': '/builds/slave/test', 09:15:43 INFO - 'blob_upload_branch': 'Firefox', 09:15:43 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:15:43 INFO - 'branch': 'Firefox', 09:15:43 INFO - 'buildbot_json_path': 'buildprops.json', 09:15:43 INFO - 'config_files': ('talos/mac_config.py',), 09:15:43 INFO - 'default_actions': ('clobber', 09:15:43 INFO - 'read-buildbot-config', 09:15:43 INFO - 'download-and-extract', 09:15:43 INFO - 'populate-webroot', 09:15:43 INFO - 'create-virtualenv', 09:15:43 INFO - 'install', 09:15:43 INFO - 'run-tests'), 09:15:43 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:15:43 INFO - 'download_minidump_stackwalk': True, 09:15:43 INFO - 'download_symbols': 'ondemand', 09:15:43 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:15:43 INFO - 'tooltool.py': '/tools/tooltool.py', 09:15:43 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:15:43 INFO - '/tools/misc-python/virtualenv.py')}, 09:15:43 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:15:43 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:15:43 INFO - 'gecko_profile': False, 09:15:43 INFO - 'gecko_profile_interval': 0, 09:15:43 INFO - 'installer_path': 'installer.exe', 09:15:43 INFO - 'log_level': 'info', 09:15:43 INFO - 'log_name': 'talos', 09:15:43 INFO - 'log_to_console': True, 09:15:43 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:15:43 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:15:43 INFO - 'opt_config_files': (), 09:15:43 INFO - 'pip_index': False, 09:15:43 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:15:43 INFO - 'cmd': ('bash', 09:15:43 INFO - '-c', 09:15:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:15:43 INFO - 'enabled': True, 09:15:43 INFO - 'halt_on_failure': False, 09:15:43 INFO - 'name': 'check_screen_resolution'},), 09:15:43 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:15:43 INFO - 'cmd': ('bash', 09:15:43 INFO - '-c', 09:15:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:15:43 INFO - 'enabled': True, 09:15:43 INFO - 'halt_on_failure': False, 09:15:43 INFO - 'name': 'check_screen_resolution'},), 09:15:43 INFO - 'run_cmd_checks_enabled': True, 09:15:43 INFO - 'suite': 'dromaeojs-e10s', 09:15:43 INFO - 'system_bits': '32', 09:15:43 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:15:43 INFO - 'title': 't-yosemite-r7-0292', 09:15:43 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:15:43 INFO - 'use_talos_json': True, 09:15:43 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:15:43 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:15:43 INFO - 'work_dir': 'build'} 09:15:43 INFO - [mozharness: 2017-03-16 16:15:43.997255Z] Running clobber step. 09:15:43 INFO - Running pre-action listener: _resource_record_pre_action 09:15:43 INFO - Running main action method: clobber 09:15:43 INFO - rmtree: /builds/slave/test/build 09:15:44 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:15:44 INFO - Running post-action listener: _resource_record_post_action 09:15:44 INFO - [mozharness: 2017-03-16 16:15:44.159669Z] Finished clobber step (success) 09:15:44 INFO - [mozharness: 2017-03-16 16:15:44.159793Z] Running read-buildbot-config step. 09:15:44 INFO - Running pre-action listener: _resource_record_pre_action 09:15:44 INFO - Running main action method: read_buildbot_config 09:15:44 INFO - Using buildbot properties: 09:15:44 INFO - { 09:15:44 INFO - "project": "", 09:15:44 INFO - "product": "firefox", 09:15:44 INFO - "script_repo_revision": "production", 09:15:44 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 09:15:44 INFO - "repository": "", 09:15:44 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 09:15:44 INFO - "buildid": "20170316075736", 09:15:44 INFO - "pgo_build": "False", 09:15:44 INFO - "basedir": "/builds/slave/test", 09:15:44 INFO - "buildnumber": 14, 09:15:44 INFO - "slavename": "t-yosemite-r7-0292", 09:15:44 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 09:15:44 INFO - "platform": "yosemite_r7", 09:15:44 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 09:15:44 INFO - "branch": "mozilla-central", 09:15:44 INFO - "repo_path": "mozilla-central", 09:15:44 INFO - "revision": "3945f2297b997d59662bdb8ac20e092363eea532", 09:15:44 INFO - "stage_platform": "macosx64", 09:15:44 INFO - "builduid": "57686e0e65984254a5d451875c6bab0a", 09:15:44 INFO - "slavebuilddir": "test" 09:15:44 INFO - } 09:15:44 INFO - Found installer url https://queue.taskcluster.net/v1/task/Q_ukGa7XTaiLKPXZIhopOw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 09:15:44 INFO - Running post-action listener: _resource_record_post_action 09:15:44 INFO - [mozharness: 2017-03-16 16:15:44.163174Z] Finished read-buildbot-config step (success) 09:15:44 INFO - [mozharness: 2017-03-16 16:15:44.163281Z] Running download-and-extract step. 09:15:44 INFO - Running pre-action listener: _resource_record_pre_action 09:15:44 INFO - Running main action method: download_and_extract 09:15:44 INFO - mkdir: /builds/slave/test/build/tests 09:15:44 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:44 INFO - trying https://queue.taskcluster.net/v1/task/Q_ukGa7XTaiLKPXZIhopOw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 09:15:44 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:44 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:45 INFO - Downloaded 1325 bytes. 09:15:45 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 09:15:45 INFO - Using the following test package requirements: 09:15:45 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:15:45 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 09:15:45 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 09:15:45 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:15:45 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 09:15:45 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:15:45 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 09:15:45 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:15:45 INFO - u'jsshell-mac.zip'], 09:15:45 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:15:45 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 09:15:45 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 09:15:45 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:15:45 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 09:15:45 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:15:45 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 09:15:45 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:15:45 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 09:15:45 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:15:45 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 09:15:45 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:15:45 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:45 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:45 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:46 INFO - Expected file size: 52472932 09:15:46 INFO - Obtained file size: 52472932 09:16:03 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:16:03 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:16:03 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:16:03 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:16:04 INFO - Expected file size: 52472932 09:16:04 INFO - Obtained file size: 52472932 09:16:20 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:20 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:20 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:21 INFO - Expected file size: 14404452 09:16:21 INFO - Obtained file size: 14404452 09:16:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:22 INFO - trying https://queue.taskcluster.net/v1/task/Q_ukGa7XTaiLKPXZIhopOw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:16:22 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:22 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:24 INFO - Downloaded 75082191 bytes. 09:16:24 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:24 INFO - mkdir: /builds/slave/test/properties 09:16:24 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:16:24 INFO - Writing to file /builds/slave/test/properties/build_url 09:16:24 INFO - Contents: 09:16:24 INFO - build_url:https://queue.taskcluster.net/v1/task/Q_ukGa7XTaiLKPXZIhopOw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:16:25 INFO - Running post-action listener: _resource_record_post_action 09:16:25 INFO - Running post-action listener: set_extra_try_arguments 09:16:25 INFO - [mozharness: 2017-03-16 16:16:25.125567Z] Finished download-and-extract step (success) 09:16:25 INFO - [mozharness: 2017-03-16 16:16:25.125736Z] Running populate-webroot step. 09:16:25 INFO - Running pre-action listener: _resource_record_pre_action 09:16:25 INFO - Running main action method: populate_webroot 09:16:25 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:16:25 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:16:25 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:16:25 INFO - '--tppagecycles', 09:16:25 INFO - '7'], 09:16:25 INFO - 'tests': ['tsvgm']}}, 09:16:25 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:16:25 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:16:25 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:16:25 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:16:25 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:16:25 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:16:25 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:16:25 INFO - 'talos_options': ['--disable-e10s'], 09:16:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:16:25 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:16:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:16:25 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:16:25 INFO - 'talos_options': ['--disable-e10s'], 09:16:25 INFO - 'tests': ['damp', 'tps']}, 09:16:25 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:16:25 INFO - 'tests': ['damp', 'tps']}, 09:16:25 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:16:25 INFO - 'tests': ['dromaeo_dom']}, 09:16:25 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:16:25 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:16:25 INFO - 'tests': ['basic_compositor_video']}, 09:16:25 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:16:25 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:16:25 INFO - 'tests': ['a11yr', 09:16:25 INFO - 'ts_paint', 09:16:25 INFO - 'tpaint', 09:16:25 INFO - 'sessionrestore', 09:16:25 INFO - 'sessionrestore_no_auto_restore', 09:16:25 INFO - 'tabpaint']}, 09:16:25 INFO - 'other-e10s': {'tests': ['a11yr', 09:16:25 INFO - 'ts_paint', 09:16:25 INFO - 'tpaint', 09:16:25 INFO - 'sessionrestore', 09:16:25 INFO - 'sessionrestore_no_auto_restore', 09:16:25 INFO - 'tabpaint']}, 09:16:25 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:16:25 INFO - 'ts_paint', 09:16:25 INFO - 'tpaint', 09:16:25 INFO - 'sessionrestore', 09:16:25 INFO - 'sessionrestore_no_auto_restore', 09:16:25 INFO - 'tabpaint']}, 09:16:25 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:16:25 INFO - 'ts_paint', 09:16:25 INFO - 'tpaint', 09:16:25 INFO - 'sessionrestore', 09:16:25 INFO - 'sessionrestore_no_auto_restore', 09:16:25 INFO - 'tabpaint']}, 09:16:25 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:16:25 INFO - 'tests': ['a11yr', 09:16:25 INFO - 'ts_paint', 09:16:25 INFO - 'tpaint', 09:16:25 INFO - 'sessionrestore', 09:16:25 INFO - 'sessionrestore_no_auto_restore', 09:16:25 INFO - 'tabpaint']}, 09:16:25 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:16:25 INFO - 'tests': ['a11yr', 09:16:25 INFO - 'ts_paint', 09:16:25 INFO - 'tpaint', 09:16:25 INFO - 'sessionrestore', 09:16:25 INFO - 'sessionrestore_no_auto_restore', 09:16:25 INFO - 'tabpaint']}, 09:16:25 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:16:25 INFO - 'tests': ['tsvgx', 09:16:25 INFO - 'tsvgr_opacity', 09:16:25 INFO - 'tart', 09:16:25 INFO - 'tscrollx', 09:16:25 INFO - 'cart', 09:16:25 INFO - 'tsvg_static']}, 09:16:25 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:16:25 INFO - 'tsvgr_opacity', 09:16:25 INFO - 'tart', 09:16:25 INFO - 'tscrollx', 09:16:25 INFO - 'cart', 09:16:25 INFO - 'tsvg_static']}, 09:16:25 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:16:25 INFO - 'talos_options': ['--disable-e10s'], 09:16:25 INFO - 'tests': ['tp5o']}, 09:16:25 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:16:25 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:16:25 INFO - 'talos_options': ['--disable-e10s', 09:16:25 INFO - '--xperf_path', 09:16:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:16:25 INFO - 'tests': ['tp5n']}, 09:16:25 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:16:25 INFO - 'talos_options': ['--xperf_path', 09:16:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:16:25 INFO - 'tests': ['tp5n']}}, 09:16:25 INFO - 'talos.zip': {'path': '', 09:16:25 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:16:25 INFO - Running post-action listener: _resource_record_post_action 09:16:25 INFO - [mozharness: 2017-03-16 16:16:25.144753Z] Finished populate-webroot step (success) 09:16:25 INFO - [mozharness: 2017-03-16 16:16:25.144848Z] Running create-virtualenv step. 09:16:25 INFO - Running pre-action listener: _resource_record_pre_action 09:16:25 INFO - Running main action method: create_virtualenv 09:16:25 INFO - Creating virtualenv /builds/slave/test/build/venv 09:16:25 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:25 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:16:25 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:16:25 INFO - Using real prefix '/tools/python27' 09:16:25 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:16:25 INFO - Installing distribute.............................................................................................................................................................................................done. 09:16:28 INFO - Installing pip.................done. 09:16:28 INFO - Return code: 0 09:16:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:16:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:16:29 INFO - Reading from file tmpfile_stdout 09:16:29 INFO - Output received: 09:16:29 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:29 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:16:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:29 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 0x10515ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10515d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fedab576330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f38d30>, '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 0x104e5fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f2dbe8>, '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.nO1nZ5OIbD/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.IRdIXnM9Ji/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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:29 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:29 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:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nO1nZ5OIbD/Render', 09:16:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:16:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:16:29 INFO - 'HOME': '/Users/cltbld', 09:16:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:16:29 INFO - 'LOGNAME': 'cltbld', 09:16:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:29 INFO - 'MOZ_NO_REMOTE': '1', 09:16:29 INFO - 'NO_EM_RESTART': '1', 09:16:29 INFO - 'PAGER': '/bin/cat', 09:16:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:16:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:29 INFO - 'PWD': '/builds/slave/test', 09:16:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:16:29 INFO - 'SHELL': '/bin/bash', 09:16:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IRdIXnM9Ji/Listeners', 09:16:29 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 09:16:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:16:29 INFO - 'USER': 'cltbld', 09:16:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:16:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:16:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:29 INFO - 'XPC_FLAGS': '0x0', 09:16:29 INFO - 'XPC_SERVICE_NAME': '0', 09:16:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:16:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:29 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:29 INFO - Cleaning up... 09:16:29 INFO - Return code: 0 09:16:29 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:16:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:29 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 0x10515ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10515d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fedab576330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f38d30>, '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 0x104e5fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f2dbe8>, '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.nO1nZ5OIbD/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.IRdIXnM9Ji/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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:29 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:29 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:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nO1nZ5OIbD/Render', 09:16:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:16:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:16:29 INFO - 'HOME': '/Users/cltbld', 09:16:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:16:29 INFO - 'LOGNAME': 'cltbld', 09:16:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:29 INFO - 'MOZ_NO_REMOTE': '1', 09:16:29 INFO - 'NO_EM_RESTART': '1', 09:16:29 INFO - 'PAGER': '/bin/cat', 09:16:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:16:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:29 INFO - 'PWD': '/builds/slave/test', 09:16:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:16:29 INFO - 'SHELL': '/bin/bash', 09:16:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IRdIXnM9Ji/Listeners', 09:16:29 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 09:16:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:16:29 INFO - 'USER': 'cltbld', 09:16:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:16:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:16:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:29 INFO - 'XPC_FLAGS': '0x0', 09:16:29 INFO - 'XPC_SERVICE_NAME': '0', 09:16:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:16:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:29 INFO - Downloading/unpacking psutil>=3.1.1 09:16:29 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:29 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:29 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:29 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:29 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:29 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:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:16:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:16:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:16:33 INFO - Installing collected packages: psutil 09:16:33 INFO - Running setup.py install for psutil 09:16:33 INFO - building 'psutil._psutil_osx' extension 09:16:33 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:33 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:33 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:33 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:33 INFO - building 'psutil._psutil_posix' extension 09:16:33 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:33 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:16:33 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:16:33 INFO - ^ 09:16:33 INFO - 1 warning generated. 09:16:33 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:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:16:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:16:34 INFO - Successfully installed psutil 09:16:34 INFO - Cleaning up... 09:16:34 INFO - Return code: 0 09:16:34 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:16: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:16:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16: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:16:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10515ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10515d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fedab576330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f38d30>, '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 0x104e5fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f2dbe8>, '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.nO1nZ5OIbD/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.IRdIXnM9Ji/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:16:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:16:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nO1nZ5OIbD/Render', 09:16:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:16:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:16:34 INFO - 'HOME': '/Users/cltbld', 09:16:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:16:34 INFO - 'LOGNAME': 'cltbld', 09:16:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:34 INFO - 'MOZ_NO_REMOTE': '1', 09:16:34 INFO - 'NO_EM_RESTART': '1', 09:16:34 INFO - 'PAGER': '/bin/cat', 09:16:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:16:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:34 INFO - 'PWD': '/builds/slave/test', 09:16:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:16:34 INFO - 'SHELL': '/bin/bash', 09:16:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IRdIXnM9Ji/Listeners', 09:16:34 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 09:16:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:16:34 INFO - 'USER': 'cltbld', 09:16:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:16:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:16:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:34 INFO - 'XPC_FLAGS': '0x0', 09:16:34 INFO - 'XPC_SERVICE_NAME': '0', 09:16:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:16:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:34 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:16:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:37 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:16:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:16:37 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:37 INFO - Installing collected packages: mozsystemmonitor 09:16:37 INFO - Running setup.py install for mozsystemmonitor 09:16:37 INFO - Successfully installed mozsystemmonitor 09:16:37 INFO - Cleaning up... 09:16:37 INFO - Return code: 0 09:16:37 INFO - Installing jsonschema==2.5.1 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:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10515ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10515d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fedab576330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f38d30>, '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 0x104e5fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f2dbe8>, '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.nO1nZ5OIbD/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.IRdIXnM9Ji/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:16:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:16:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nO1nZ5OIbD/Render', 09:16:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:16:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:16:38 INFO - 'HOME': '/Users/cltbld', 09:16:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:16:38 INFO - 'LOGNAME': 'cltbld', 09:16:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:38 INFO - 'MOZ_NO_REMOTE': '1', 09:16:38 INFO - 'NO_EM_RESTART': '1', 09:16:38 INFO - 'PAGER': '/bin/cat', 09:16:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:16:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:38 INFO - 'PWD': '/builds/slave/test', 09:16:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:16:38 INFO - 'SHELL': '/bin/bash', 09:16:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IRdIXnM9Ji/Listeners', 09:16:38 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 09:16:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:16:38 INFO - 'USER': 'cltbld', 09:16:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:16:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:16:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:38 INFO - 'XPC_FLAGS': '0x0', 09:16:38 INFO - 'XPC_SERVICE_NAME': '0', 09:16:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:16:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:38 INFO - Downloading/unpacking jsonschema==2.5.1 09:16:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:16:41 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:16:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:41 INFO - Downloading functools32-3.2.3-2.tar.gz 09:16:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:16:41 INFO - warning: no files found matching '*.txt' 09:16:41 INFO - no previously-included directories found matching 'build' 09:16:41 INFO - no previously-included directories found matching 'dist' 09:16:41 INFO - no previously-included directories found matching '.git*' 09:16:41 INFO - Installing collected packages: jsonschema, functools32 09:16:41 INFO - Running setup.py install for functools32 09:16:42 INFO - warning: no files found matching '*.txt' 09:16:42 INFO - no previously-included directories found matching 'build' 09:16:42 INFO - no previously-included directories found matching 'dist' 09:16:42 INFO - no previously-included directories found matching '.git*' 09:16:42 INFO - Successfully installed jsonschema functools32 09:16:42 INFO - Cleaning up... 09:16:42 INFO - Return code: 0 09:16:42 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:16:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10515ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10515d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fedab576330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f38d30>, '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 0x104e5fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f2dbe8>, '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.nO1nZ5OIbD/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.IRdIXnM9Ji/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:16:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:16:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nO1nZ5OIbD/Render', 09:16:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:16:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:16:42 INFO - 'HOME': '/Users/cltbld', 09:16:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:16:42 INFO - 'LOGNAME': 'cltbld', 09:16:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:42 INFO - 'MOZ_NO_REMOTE': '1', 09:16:42 INFO - 'NO_EM_RESTART': '1', 09:16:42 INFO - 'PAGER': '/bin/cat', 09:16:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:16:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:42 INFO - 'PWD': '/builds/slave/test', 09:16:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:16:42 INFO - 'SHELL': '/bin/bash', 09:16:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IRdIXnM9Ji/Listeners', 09:16:42 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 09:16:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:16:42 INFO - 'USER': 'cltbld', 09:16:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:16:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:16:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:42 INFO - 'XPC_FLAGS': '0x0', 09:16:42 INFO - 'XPC_SERVICE_NAME': '0', 09:16:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:16:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:42 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:16:42 INFO - Cleaning up... 09:16:42 INFO - Return code: 0 09:16:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:16:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10515ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10515d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fedab576330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f38d30>, '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 0x104e5fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f2dbe8>, '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.nO1nZ5OIbD/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.IRdIXnM9Ji/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:16:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:16:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nO1nZ5OIbD/Render', 09:16:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:16:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:16:42 INFO - 'HOME': '/Users/cltbld', 09:16:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:16:42 INFO - 'LOGNAME': 'cltbld', 09:16:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:42 INFO - 'MOZ_NO_REMOTE': '1', 09:16:42 INFO - 'NO_EM_RESTART': '1', 09:16:42 INFO - 'PAGER': '/bin/cat', 09:16:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:16:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:42 INFO - 'PWD': '/builds/slave/test', 09:16:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:16:42 INFO - 'SHELL': '/bin/bash', 09:16:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IRdIXnM9Ji/Listeners', 09:16:42 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 09:16:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:16:42 INFO - 'USER': 'cltbld', 09:16:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:16:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:16:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:42 INFO - 'XPC_FLAGS': '0x0', 09:16:42 INFO - 'XPC_SERVICE_NAME': '0', 09:16:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:16:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:42 INFO - Downloading/unpacking blobuploader==1.2.4 09:16:42 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:42 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:42 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:42 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:42 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:42 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:45 INFO - Downloading blobuploader-1.2.4.tar.gz 09:16:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:16:45 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:16:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:16:46 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:16:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:46 INFO - Downloading docopt-0.6.1.tar.gz 09:16:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:16:46 INFO - Installing collected packages: blobuploader, requests, docopt 09:16:46 INFO - Running setup.py install for blobuploader 09:16:46 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:16:46 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:16:46 INFO - Running setup.py install for requests 09:16:47 INFO - Running setup.py install for docopt 09:16:47 INFO - Successfully installed blobuploader requests docopt 09:16:47 INFO - Cleaning up... 09:16:47 INFO - Return code: 0 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', '--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 0x10515ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10515d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fedab576330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f38d30>, '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 0x104e5fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f2dbe8>, '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.nO1nZ5OIbD/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.IRdIXnM9Ji/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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', '--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:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:16:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nO1nZ5OIbD/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.IRdIXnM9Ji/Listeners', 09:16:47 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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 - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:16:47 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-raQ2TG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:16:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:16:47 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-RwEtf9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:16:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:16:48 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-hPKPED-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:16:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:16:48 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-iT9CwL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:16:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:16:48 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-P_KU_s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:16:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:16:48 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-UwSojl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:16:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:16:48 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-IHmgiY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:16:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:16:48 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-EKdEQg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:16:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:16:48 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-a7JLz5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:16:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:16:48 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-hJyQM_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:16:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:16:48 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-fTcaZ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:16:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:16:48 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-Y_N4uA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:16:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:16:49 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-audsGQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:16:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:16:49 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-VMKymV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:16:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:16:49 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-Tu8tci-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:16:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:16:49 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-DZ1Sbm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:16:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:16:49 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-7UqbvG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:16:49 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:16:49 INFO - Running setup.py install for manifestparser 09:16:49 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:16:49 INFO - Running setup.py install for mozcrash 09:16:49 INFO - Running setup.py install for mozdebug 09:16:49 INFO - Running setup.py install for mozdevice 09:16:50 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:16:50 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:16:50 INFO - Running setup.py install for mozfile 09:16:50 INFO - Running setup.py install for mozhttpd 09:16:50 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:16:50 INFO - Running setup.py install for mozinfo 09:16:50 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:16:50 INFO - Running setup.py install for mozInstall 09:16:50 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:16:50 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:16:50 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:16:50 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:16:50 INFO - Running setup.py install for mozleak 09:16:50 INFO - Running setup.py install for mozlog 09:16:51 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:16:51 INFO - Running setup.py install for moznetwork 09:16:51 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:16:51 INFO - Running setup.py install for mozprocess 09:16:51 INFO - Running setup.py install for mozprofile 09:16:51 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:16:51 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:16:51 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:16:51 INFO - Running setup.py install for mozrunner 09:16:51 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:16:51 INFO - Running setup.py install for mozscreenshot 09:16:51 INFO - Running setup.py install for moztest 09:16:52 INFO - Running setup.py install for mozversion 09:16:52 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:16:52 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:16:52 INFO - Cleaning up... 09:16:52 INFO - Return code: 0 09:16:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:16:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:16:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10515ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10515d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fedab576330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f38d30>, '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 0x104e5fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f2dbe8>, '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.nO1nZ5OIbD/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.IRdIXnM9Ji/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:16:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:16:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nO1nZ5OIbD/Render', 09:16:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:16:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:16:52 INFO - 'HOME': '/Users/cltbld', 09:16:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:16:52 INFO - 'LOGNAME': 'cltbld', 09:16:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:52 INFO - 'MOZ_NO_REMOTE': '1', 09:16:52 INFO - 'NO_EM_RESTART': '1', 09:16:52 INFO - 'PAGER': '/bin/cat', 09:16:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:16:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:52 INFO - 'PWD': '/builds/slave/test', 09:16:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:16:52 INFO - 'SHELL': '/bin/bash', 09:16:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IRdIXnM9Ji/Listeners', 09:16:52 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 09:16:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:16:52 INFO - 'USER': 'cltbld', 09:16:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:16:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:16:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:52 INFO - 'XPC_FLAGS': '0x0', 09:16:52 INFO - 'XPC_SERVICE_NAME': '0', 09:16:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:16:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:16:52 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-ocJMs0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:16:52 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:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:16:52 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-kJiPIU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:16:52 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:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:16:52 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-Z3VJKq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:16:52 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:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:16:52 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-mZan1c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:16:52 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:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:16:52 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-UFo8T2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:16:52 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:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:16:52 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-EZojtW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:16:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:16:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:16:53 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-dSzYFG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:16:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:16:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:16:53 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-41EgG7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:16:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:16:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:16:53 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-V45rGW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:16:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:16:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:16:53 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-POa6_i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:16:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:16:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:16:53 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-wLCa2w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:16:53 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:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:16:53 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-F4yI3T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:16:53 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:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:16:53 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-YWTEfT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:16:53 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:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:16:53 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-oeviQl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:16:53 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:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:16:53 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-eRzNyv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:16:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:16:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:16:54 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-yIoajS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:16:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:16:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:16:54 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-_HOuLM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:16:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:16:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:16:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:16:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:16:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:16:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:16:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:16:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:56 INFO - Downloading blessings-1.6.tar.gz 09:16:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:16:56 INFO - Installing collected packages: blessings 09:16:56 INFO - Running setup.py install for blessings 09:16:57 INFO - Successfully installed blessings 09:16:57 INFO - Cleaning up... 09:16:57 INFO - Return code: 0 09:16:57 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:16:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:16:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:16:57 INFO - Reading from file tmpfile_stdout 09:16:57 INFO - Current package versions: 09:16:57 INFO - blessings == 1.6 09:16:57 INFO - blobuploader == 1.2.4 09:16:57 INFO - docopt == 0.6.1 09:16:57 INFO - functools32 == 3.2.3-2 09:16:57 INFO - jsonschema == 2.5.1 09:16:57 INFO - manifestparser == 1.1 09:16:57 INFO - mozInstall == 1.12 09:16:57 INFO - mozcrash == 1.0 09:16:57 INFO - mozdebug == 0.1 09:16:57 INFO - mozdevice == 0.49 09:16:57 INFO - mozfile == 1.2 09:16:57 INFO - mozhttpd == 0.7 09:16:57 INFO - mozinfo == 0.9 09:16:57 INFO - mozleak == 0.1 09:16:57 INFO - mozlog == 3.4 09:16:57 INFO - moznetwork == 0.27 09:16:57 INFO - mozprocess == 0.25 09:16:57 INFO - mozprofile == 0.28 09:16:57 INFO - mozrunner == 6.13 09:16:57 INFO - mozscreenshot == 0.1 09:16:57 INFO - mozsystemmonitor == 0.3 09:16:57 INFO - moztest == 0.8 09:16:57 INFO - mozversion == 1.4 09:16:57 INFO - psutil == 3.1.1 09:16:57 INFO - requests == 1.2.3 09:16:57 INFO - wsgiref == 0.1.2 09:16:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:16:57 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:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:57 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:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:57 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 0x10515ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10515d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fedab576330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f38d30>, '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 0x104e5fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f2dbe8>, '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.nO1nZ5OIbD/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.IRdIXnM9Ji/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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:57 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:57 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:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nO1nZ5OIbD/Render', 09:16:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:16:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:16:57 INFO - 'HOME': '/Users/cltbld', 09:16:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:16:57 INFO - 'LOGNAME': 'cltbld', 09:16:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:57 INFO - 'MOZ_NO_REMOTE': '1', 09:16:57 INFO - 'NO_EM_RESTART': '1', 09:16:57 INFO - 'PAGER': '/bin/cat', 09:16:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:16:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:57 INFO - 'PWD': '/builds/slave/test', 09:16:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:16:57 INFO - 'SHELL': '/bin/bash', 09:16:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IRdIXnM9Ji/Listeners', 09:16:57 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 09:16:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:16:57 INFO - 'USER': 'cltbld', 09:16:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:16:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:16:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:57 INFO - 'XPC_FLAGS': '0x0', 09:16:57 INFO - 'XPC_SERVICE_NAME': '0', 09:16:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:16:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:57 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:57 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:57 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:57 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:57 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:57 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:57 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:57 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:57 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:57 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:57 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:57 INFO - Cleaning up... 09:16:57 INFO - Return code: 0 09:16:57 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:16:57 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:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:57 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:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:57 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 0x10515ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10515d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fedab576330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f38d30>, '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 0x104e5fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f2dbe8>, '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.nO1nZ5OIbD/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.IRdIXnM9Ji/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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:57 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:57 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:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nO1nZ5OIbD/Render', 09:16:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:16:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:16:57 INFO - 'HOME': '/Users/cltbld', 09:16:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:16:57 INFO - 'LOGNAME': 'cltbld', 09:16:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:57 INFO - 'MOZ_NO_REMOTE': '1', 09:16:57 INFO - 'NO_EM_RESTART': '1', 09:16:57 INFO - 'PAGER': '/bin/cat', 09:16:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:16:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:57 INFO - 'PWD': '/builds/slave/test', 09:16:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:16:57 INFO - 'SHELL': '/bin/bash', 09:16:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IRdIXnM9Ji/Listeners', 09:16:57 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 09:16:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:16:57 INFO - 'USER': 'cltbld', 09:16:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:16:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:16:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:57 INFO - 'XPC_FLAGS': '0x0', 09:16:57 INFO - 'XPC_SERVICE_NAME': '0', 09:16:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:16:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:58 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:16:58 INFO - Cleaning up... 09:16:58 INFO - Return code: 0 09:16:58 INFO - Running post-action listener: _resource_record_post_action 09:16:58 INFO - Running post-action listener: _start_resource_monitoring 09:16:58 INFO - Starting resource monitoring. 09:16:58 INFO - [mozharness: 2017-03-16 16:16:58.050418Z] Finished create-virtualenv step (success) 09:16:58 INFO - [mozharness: 2017-03-16 16:16:58.051411Z] Running install step. 09:16:58 INFO - Running pre-action listener: _resource_record_pre_action 09:16:58 INFO - Running main action method: install 09:16:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:16:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:16:58 INFO - Reading from file tmpfile_stdout 09:16:58 INFO - Detecting whether we're running mozinstall >=1.0... 09:16:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:16:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:16:58 INFO - Reading from file tmpfile_stdout 09:16:58 INFO - Output received: 09:16:58 INFO - Usage: mozinstall [options] installer 09:16:58 INFO - Options: 09:16:58 INFO - -h, --help show this help message and exit 09:16:58 INFO - -d DEST, --destination=DEST 09:16:58 INFO - Directory to install application into. [default: 09:16:58 INFO - "/builds/slave/test"] 09:16:58 INFO - --app=APP Application being installed. [default: firefox] 09:16:58 INFO - mkdir: /builds/slave/test/build/application 09:16:58 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:58 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:18 INFO - Reading from file tmpfile_stdout 09:17:18 INFO - Output received: 09:17:18 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:17:18 INFO - Running post-action listener: _resource_record_post_action 09:17:18 INFO - [mozharness: 2017-03-16 16:17:18.552244Z] Finished install step (success) 09:17:18 INFO - [mozharness: 2017-03-16 16:17:18.552409Z] Running run-tests step. 09:17:18 INFO - Running pre-action listener: _resource_record_pre_action 09:17:18 INFO - Running main action method: run_tests 09:17:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:17:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:17:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:17:18 INFO - 2017-03-16 09:17:18.567 screenresolution[1886:9166] starting screenresolution argv=screenresolution get 09:17:18 INFO - 2017-03-16 09:17:18.570 screenresolution[1886:9166] Display 0: 1600x1200x32@60 09:17:18 INFO - 2017-03-16 09:17:18.575 screenresolution[1887:9169] starting screenresolution argv=screenresolution list 09:17:18 INFO - Available Modes on Display 0 09:17:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:17:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:17:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:17:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:17:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:17:18 INFO - Intel Iris: 09:17:18 INFO - Chipset Model: Intel Iris 09:17:18 INFO - Type: GPU 09:17:18 INFO - Bus: Built-In 09:17:18 INFO - VRAM (Dynamic, Max): 1536 MB 09:17:18 INFO - Vendor: Intel (0x8086) 09:17:18 INFO - Device ID: 0x0a2e 09:17:18 INFO - Revision ID: 0x0009 09:17:18 INFO - Displays: 09:17:18 INFO - Display: 09:17:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:17:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:17:18 INFO - Display Serial Number: 1600x1200 60 09:17:18 INFO - Main Display: Yes 09:17:18 INFO - Mirror: Off 09:17:18 INFO - Online: Yes 09:17:18 INFO - Rotation: Supported 09:17:18 INFO - Return code: 0 09:17:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:17:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:17:18 INFO - Python 2.7.3 09:17:18 INFO - Return code: 0 09:17:18 INFO - grabbing minidump binary from tooltool 09:17:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:17:18 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 0x104f38d30>, '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 0x104e5fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104f2dbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:17:18 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:18 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:18 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:18 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:17:18 INFO - Return code: 0 09:17:18 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:17:18 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:17:18 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:17:18 INFO - ENV: RUST_BACKTRACE is now 1 09:17:18 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:17:18 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:17:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Q_ukGa7XTaiLKPXZIhopOw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0292', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 09:17:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Q_ukGa7XTaiLKPXZIhopOw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0292 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 09:17:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nO1nZ5OIbD/Render', 09:17:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:17:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:17:18 INFO - 'HOME': '/Users/cltbld', 09:17:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:17:18 INFO - 'LOGNAME': 'cltbld', 09:17:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:17:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:17:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:17:18 INFO - 'MOZ_NO_REMOTE': '1', 09:17:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:17:18 INFO - 'NO_EM_RESTART': '1', 09:17:18 INFO - 'PAGER': '/bin/cat', 09:17:18 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:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:17:18 INFO - 'PWD': '/builds/slave/test', 09:17:18 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:17:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:17:18 INFO - 'RUST_BACKTRACE': '1', 09:17:18 INFO - 'SHELL': '/bin/bash', 09:17:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IRdIXnM9Ji/Listeners', 09:17:18 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 09:17:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:17:18 INFO - 'USER': 'cltbld', 09:17:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:17:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:17:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:17:18 INFO - 'XPC_FLAGS': '0x0', 09:17:18 INFO - 'XPC_SERVICE_NAME': '0', 09:17:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:17:18 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Q_ukGa7XTaiLKPXZIhopOw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0292', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 09:17:19 INFO - mozversion application_buildid: 20170316075736 09:17:19 INFO - mozversion application_changeset: 3945f2297b997d59662bdb8ac20e092363eea532 09:17:19 INFO - mozversion application_display_name: Nightly 09:17:19 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:17:19 INFO - mozversion application_name: Firefox 09:17:19 INFO - mozversion application_remotingname: firefox 09:17:19 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:17:19 INFO - mozversion application_vendor: Mozilla 09:17:19 INFO - mozversion application_version: 55.0a1 09:17:19 INFO - mozversion platform_buildid: 20170316075736 09:17:19 INFO - mozversion platform_changeset: 3945f2297b997d59662bdb8ac20e092363eea532 09:17:19 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:17:19 INFO - mozversion platform_version: 55.0a1 09:17:19 INFO - using testdate: 1489681039 09:17:19 INFO - actual date: 1489681039 09:17:19 INFO - starting webserver on 'localhost:49382' 09:17:19 INFO - SUITE-START | Running 2 tests 09:17:19 INFO - TEST-START | dromaeo_css 09:17:19 INFO - operating with platform_type : mac_ 09:17:19 INFO - Initialising browser for dromaeo_css test... 09:17:19 INFO - TEST-INFO | started process 1897 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/tmpUbm7Uc/profile http://localhost:49382/getInfo.html) 09:17:22 INFO - PID 1897 | 2017-03-16 09:17:22.164 plugin-container[1899:9335] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e3f, name = 'com.apple.tsm.portname' 09:17:22 INFO - PID 1897 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:17:22 INFO - PID 1897 | 2017-03-16 09:17:22.167 plugin-container[1899:9335] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9413, name = 'com.apple.CFPasteboardClient' 09:17:22 INFO - PID 1897 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:17:22 INFO - PID 1897 | 2017-03-16 09:17:22.167 plugin-container[1899:9335] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:17:22 INFO - PID 1897 | __metrics Screen width/height:1600/1200 09:17:22 INFO - PID 1897 | colorDepth:24 09:17:22 INFO - PID 1897 | Browser inner width/height: 1024/689 09:17:22 INFO - PID 1897 | __metrics 09:17:22 ERROR - PID 1897 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3350: TypeError: this.tabs is undefined 09:17:23 INFO - TEST-INFO | 1897: exit 0 09:17:23 INFO - Browser initialized. 09:17:23 INFO - Running cycle 1/1 for dromaeo_css test... 09:17:23 INFO - TEST-INFO | started process 1901 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/tmpUbm7Uc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:17:25 INFO - PID 1901 | 2017-03-16 09:17:25.473 plugin-container[1902:9472] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 09:17:25 INFO - PID 1901 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:17:25 INFO - PID 1901 | 2017-03-16 09:17:25.473 plugin-container[1902:9472] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4607, name = 'com.apple.CFPasteboardClient' 09:17:25 INFO - PID 1901 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:17:25 INFO - PID 1901 | 2017-03-16 09:17:25.473 plugin-container[1902:9472] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:17:35 INFO - PID 1901 | RSS: Main: 227295232 09:17:35 INFO - PID 1901 | 09:20:38 INFO - PID 1901 | Cycle 1(1): loaded http://localhost:49382/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49382/tests/dromaeo/cssquery-ext.html) 09:20:39 INFO - PID 1901 | RSS: Main: 251580416 09:20:39 INFO - PID 1901 | 09:22:23 INFO - PID 1901 | ************************* 09:22:23 INFO - PID 1901 | A coding exception was thrown and uncaught in a Task. 09:22:23 INFO - PID 1901 | 09:22:23 ERROR - PID 1901 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:22:23 INFO - PID 1901 | Full stack: 09:22:23 INFO - PID 1901 | ************************* 09:23:42 INFO - PID 1901 | Cycle 1(1): loaded http://localhost:49382/tests/dromaeo/cssquery-ext.html (next: http://localhost:49382/tests/dromaeo/cssquery-jquery.html) 09:23:42 INFO - PID 1901 | RSS: Main: 258490368 09:23:42 INFO - PID 1901 | 09:26:44 INFO - PID 1901 | Cycle 1(1): loaded http://localhost:49382/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49382/tests/dromaeo/cssquery-mootools.html) 09:26:45 INFO - PID 1901 | RSS: Main: 269467648 09:26:45 INFO - PID 1901 | 09:29:47 INFO - PID 1901 | Cycle 1(1): loaded http://localhost:49382/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49382/tests/dromaeo/cssquery-prototype.html) 09:29:47 INFO - PID 1901 | RSS: Main: 263122944 09:29:47 INFO - PID 1901 | 09:32:50 INFO - PID 1901 | Cycle 1(1): loaded http://localhost:49382/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49382/tests/dromaeo/cssquery-yui.html) 09:32:51 INFO - PID 1901 | RSS: Main: 273170432 09:32:51 INFO - PID 1901 | 09:35:54 INFO - PID 1901 | Cycle 1(1): loaded http://localhost:49382/tests/dromaeo/cssquery-yui.html (next: http://localhost:49382/tests/dromaeo/cssquery-dojo.html) 09:35:54 INFO - PID 1901 | RSS: Main: 268091392 09:35:54 INFO - PID 1901 | 09:35:54 INFO - PID 1901 | __start_tp_report 09:35:54 INFO - PID 1901 | _x_x_mozilla_page_load 09:35:54 INFO - PID 1901 | _x_x_mozilla_page_load_details 09:35:54 INFO - PID 1901 | |i|pagename|runs| 09:35:54 INFO - PID 1901 | |0;dojo.html;2894.3487715264064;3409.7272218222543;3439.985204364712;3442.1222588239993;3501.8249087545623;4996.825111121111;5691.544676425887;5820.126980952854;5868.647881127135;5896.174535565025;5745.971270143644;5780.4797568218855;5881.3530511643685;5934.88130237395;6006.129111278862;5276.841694749163;5340.732963351837;5342.385625653051;5345.037893179227;5467.808626698066;5330.28041214435;5363.731813409334;5425.674459532421;5445.156000819879;5512.035393806088;5238.816641417532;5290.365156181283;5397.892042159174;5401.135818269097;5406.189071639246;4126.566710495415;5313.973430132824;5348.331458567679;5367.194920761878;5406.756695948692;5225.686458812445;5271.841844744664;5299.814506492293;5299.947000530023;5314.548263397616;5329.760160792774;5699.886002279973;5840.561957853178;5921.644701317935;5950.732217050243;6241.251049874044;6280.371962803729;6297.2128483939505;6297.811065668037;6309.905351419732;6291.559590828644;6312.968435157794;6312.968435157841;6316.1788967434395;6317.778877739303;6024.036154215304;6025.614108755011;6033.909491357633;6047.7278522466595;6052.878942421215;5574.968630803314;5604.8318550443555;6241.469475094577;6243.157173781572;6299.464545513682;6140.662263575547;6144.4945988232275;6198.2335034514945;6214.533909956771;6329.240491141;4748.335233067373;6228.47058000066;6294.685265736786;6295.37046295376;6317.96841015792;6218.533609979269;6269.341719119427;6317.957537006342;6328.936710632926;6347.873042539216;6230.314665386803;6252.280987686474;6263.029230469285;6280.623162610258;6285.654289014148;4998.775055122528;5008.098542262428;5048.545630893163;5059.443461219263;5086.64393492452;4524.502304746476;4531.750753708578;4550.112728018071;4566.611837993808;4570.908581828411;5941.346451890288;5994.340622531518;6004.789832355847;6014.0377539593455;6024.668643224665;6239.844003899939;6251.249850018027;6259.4679452246155;6268.185135932391;6319.146915166394;5952.077427998667;5985.790497332572;5993.970030149909;5998.700064996733;6028.728707208186;5556.583256255716;5557.749901254453;5562.610617256834;5580.972095139498;5586.273784408001;6203.379662033761;6274.560780745486;6343.175387199819;6358.872822543431;6428.4214420702365;5458.590605704509;6208.906866396917;6236.282827474717;6264.310925798068;6268.843278918063;6271.31015588294;6272.435480806749;6277.686115694106;6289.528285378524;6316.05259211122;6146.416090471398;6152.230971128609;6157.815265542041;6203;6258.186435763504;5237.057329680694;6002.669853158118;6217.285012223652;6271.874562508633;6280.277768056732;6205.875882482234;6226.719797609209;6229.750809967732;6231.626102433868;6246.750129994931;6110.285381942539;6115.388461153849;6141.884937092248;6179.165812615419;6199.721012554356;5180.963807238492;6090.817275481742;6098.664573448503;6113.202667687911;6174.9073763892675;6252.187215662117;6282.528810339151;6286.717097730704;6293.622382657055;6319.6208227506495;5940.079287710412;5952.255968003999;6097.756089756537;6124.112003759341;6126.540509461719 09:35:54 INFO - PID 1901 | |1;ext.html;723.9710411583537;786.6263524825708;809.5992483720557;928.9256859451241;929.9860502092474;31802.36395272093;36784.63215367849;37394.8130259349;37820.43269350962;37969.531980421285;29704.55443168354;36574.45138322927;36916.44625330615;36917.81541092297;37030.62969370306;7630.8855367169535;7845.803854903616;10733.677989660322;10967.232293739442;11008.339499630009;10896.727581810439;11017.559297628126;11021.504032318524;11028.00747932686;11047.226694131414;11063.889361106407;11093.112550995942;11101.83347249792;11108.944455277713;11131.443427828617;9449.622015119387;11091;11157.832632510519;11159.051480624157;11285.266457680225;9918.908920018792;10382.22133339995;10871.782564348749;10923;10935.56257749689;38321.61678383208;38584;38593.61406385956;38603.8418847435;38672.61327386718;3446.3796516627;3830.8005110385384;3857.688385948763;3868.903277418087;4032.717709760318;27993;28099;28122.85938570294;28275.57586636202;29213.26966825825;3109.5024796032753;3114.0190839885363;3146.4179126861604;3151.2594540283017;3271.8691252350113;275400;326333;327310;328723;332523.337383314;50928.74535627372;50990.745046275275;50991.745041275295;51024.74487627538;51230.97538049293;53113.7344313276;53118.67203319871;53300.466995330324;53347.6663083426;53839.46160538345;14275.715185633342;14593.12441253507;14711;14715.779263311058;14721;11814.645560633195;11818.995385392338;11826.94086529579;11854.348010859485;11854.585089521825;13346.265955372548;13950.23273719955;13997.020208585302;14016.018878678598;14091.224982625848;5236.554892834151;6090.3605121461605;6164.8767024659255;6167.414095660905;6177.4749146322165;917.1745429113718;1106.8450416941473;1115.4924509348227;1124.381590125444;1171.2035815645272;27029.594556081676;27531.862340688167;27768.444631107264;27864.303392414946;28034.439311214068;863.1417563491184;1032.4940779018377;1042.854000439939;1049.7165765243494;1057.724991502215;14533.563993080224;14647.780283295759;14717.116972981656;14763.92618036903;14835.629109272355;434387;454717;456463;457248;463877;52804.47195528072;55930;56194.71902640461;56721;56856.71571642198;1265.4115836136425;1403.2983508245877;1430.8211473565805;1439.8272207334978;1457.5627311806415;24802.37994050091;28407.573886392132;28635.565976891365;28691.856540717163;29337.41325173442;46530.53469465262;46866.76566617145;46902;47057.2941405886;47162.056758863946;451320;455202;457239;458075;460214;27343.72656273412;27671.584926226526;34434.31131377409;34720.305593888486;35215.82392088023;42662.440253584886;43612.34581481344;44273;44451.55548444474;44555;28155.859220703765;35622.93131206068;35661;36899;37136;34088.82955585206;35539;35555.28889422249;36501.81749091238;36641;35014.47478287777;35838.10404739902;36209.27581448304;36248.275034498634;36419.817900910326;30427.695723042485;31634.841825790725;31881;31886.84056579702;31955.881544145843;22552.436189094748;23581.174658886855;23869.522609547363;23933.162339318736;24269.51460970806 09:35:54 INFO - PID 1901 | |2;jquery.html;396.3400937439164;413.42946733507733;414.85272728181496;428.7320424734541;428.85633312840196;19688.507787305305;20542;20767.584648307024;21127.049282782275;21270.14919403224;21613.67579486309;21936.122555097816;22263.666045009297;22707.318780436573;22906.083756649754;5648.915266271009;6682.93317066828;6699.19609646843;6705.463562914955;6721.63031033293;6430.646314452703;6552.443042341407;6558.2130144382745;6574.539782215247;6638.933610663904;6359.65021923794;6592.077109204715;6635.369639884203;6642.933570664304;6651.866962660744;6482.286948435668;6571.047198156289;6622.701978410983;6623.768168114141;6636.933630663704;6373.107764912916;6417.133686952248;6464;6472.352764723537;6472.8705425891685;26627.068052618066;26676.46647067067;26739.197824065308;26792.866035669893;26858.791354388904;2536.5053814506177;2843.40288539407;2845.9573106403423;2850.6009158717798;2857.6856545780156;15789.921050394674;16337.754933676004;16582.50252492415;16645.16774161287;17084.060376679277;1951.7057489596082;1996.4509759816167;2006.2576846566865;2010.9296174633816;2052.989735051315;147943;155621.88756224926;155703.221483893;156900.21549892292;158445;18744.437666870017;22701.43246418853;22762.886185569296;22795.44091181737;23001.309960701205;35661.82169089138;35778.821105894305;35838;35862.641373586455;35951.4607280891;10224.897751022543;10464.372137671764;10482.790344193227;10623.84064239037;10803.297785644065;8476.576171191417;8534.402591818638;8535.786605334917;8730.607122679494;8744.912550874536;10556.683299501028;10614.575416983234;10656.89343106559;10800.649918760155;10866.511007004703;3697.8890633280507;3770.4344348347418;4268.5913648495925;4376.387305777193;4378.956210437918;835.5863847395578;895.9606856047085;929.1266209762802;937.5124935033745;960.4669408478362;22361.664575031387;22630.094796208297;23789;23825.7617423827;23837.404064898517;911.8085202107632;930.9767255818658;939.3565407695751;966.5747071288611;973.2068364283086;11708.88291117095;12199.51201951912;12239.632811015685;12485.31330776816;12523.812142817866;612703;621741;629581;635179;661169;139592;143972.28013859864;146193;148750;148990.25504872622;1270.1363073110374;1273.3124112979074;1302.3358087375507;1312.1667740984544;1312.9409176587267;19224.327148549735;20410.489737756674;20681.379558613266;20694.482637934172;20751.377458676263;61088.69455652871;61099.47251318575;61178;62355.6882215586;62435.687821560605;76107.23892761223;85329;86883;87548.24903502021;88863;12171.81742273849;12376;12543.3101179436;12547.811782823448;12578.811317830057;13305.66735831612;13366.198028117955;13377.130486518345;13709;13750.381232844746;9585.760355990877;11739.706507337385;11795.764084718428;12175.391230438265;12175.45210465549;11557.768844623228;11769.47037383337;11906.761864762482;11991.280523168638;12191.573294934635;10882.61910833117;11910.034258477526;12097.637070887888;12097.850704183089;12207.633770986884;10442.791144177227;12177.269363838199;12485.188462749893;12508;12706.872931270938;10191.184705223712;11714.1800073994;11752.882471175179;12063.638090857288;12119.151659383759 09:35:54 INFO - PID 1901 | |3;mootools.html;1558.400015993842;1559.9610009749754;1566.0525382143803;1603.2865374908163;1662.6674665066985;9181.219596334311;10369.066783989449;10683.465826708652;10785.892141078597;11011.889881101186;10597.894021059787;10839.891601083988;10840.403777792219;10886.891131088687;10920.453977301142;2314.90740370385;2338.8479748816358;2350.811935045192;2354.2701762453607;2355.0462062864567;2317.756635553268;2344.9648255276184;2355.8115350771895;2360.87015214164;2365.4914193448403;2068.700038494425;2223.7213602178813;2314.2941402872116;2334.9649755253686;2335.4278201840575;2276.3057267533395;2287.2909398086645;2287.8169746420244;2289.267434420986;2295.8392912496133;2259.062489067035;2306.6540018997116;2315.120254303371;2320.8375413721046;2325.6744055832196;10906;10907.727306817393;10966.122710183246;10966.835497467544;10972.725681857937;5504.394516603171;5721.6853073080965;5737.39757325479;5742.770289188427;5774.393688662672;3353.295807880348;3377.223238655123;3635.8909232723063;3668.3396988542054;3679;1113.4989254835357;1168.7896178687834;1231.9630411087683;1239.510393394604;1245.8442694663167;11185.049270811991;13555.390007449689;13682.657933551642;15023.474178403812;15044.398224071025;3881.6894648428056;4503.707259028152;4605.65457590682;4643.233866412072;4654.557817007379;10847.837282440769;10905;10908.563657453613;10927.344359338465;10927.781444371069;5457.754401051962;5462.0987537056735;5471.2887324647545;5472.863178420492;5498.202760599691;5017.774200161001;5115.335006449212;5189.896202076013;5192.350956130484;5209.218617207449;3718.4980027696456;3826.7703937763267;3857.633524815138;3899.590542992945;3934.4688467057154;1034.8085604163102;1037.3153718545875;1038.2835843268122;1050.9527071281811;1051.7686109055996;772.4940164192462;780.9023872015998;792.1721800718263;798.3333916179938;807.0839597057383;6282.403171698681;6372.968135159294;6428.164338635947;6613.702383392662;6615.900761488581;893.1113542025726;910.6084383715066;933.8179055084194;981.8085473332632;1009.0615727373466;3990.182012687392;4067.89830254246;4098.6106319900155;4214.115035842446;4220.451341325608;12915.677108072374;13328.33358332099;13374.264415457243;13375.665608359868;13389.464421423228;9980.90210076891;13069.673258168621;13114.557398686136;13215.207087574776;13348.866511334956;8278.544680042656;9600.471974041495;9991.100800927807;10020;10064.295499315123;6075.210222671025;6466.900626893345;6469.385408386196;6555.475561955128;6792.320767923168;11640.592579259684;11866.644000679993;11972.64082077539;12000.219985700902;12048.457819397972;9655.855162172433;10537;11702.590409335973;12085.335306558223;12172.026237901124;8720.738377848675;8895;9089.909100908906;9170.95414522923;9198.49408282551;10103;10243.94878025605;10318.019788120117;10344.431056291976;10460.21548383859;9029.77425564366;9072.863907041532;9081.409708368936;9125.72239886403;9182.816343672956;8753.343499237455;8818.426802257833;8891.866622000805;8897.73306800797;8931.866022009544;7609.391248700202;7713.42149338813;7733.342665873463;7803;7832.334251588679;5790.855228619318;5832.183494310715;5899.115132730125;6009.399060093956;6044.969775151096;5866.794662186803;5895.911561326498;6012.398760123952;6068.362821903636;6136.539759517965 09:35:54 INFO - PID 1901 | |4;prototype.html;373.758925493057;384.2564637426578;385.6452064101027;391.38747859599727;393.1959143551437;4814.398200224972;5218.843434696961;5676.631018983773;5811.796587119452;5998.7000649967495;4760.738159401231;5642.009734840531;5853.5902486825835;5906.61606995545;5907.793227237047;5045.571126454256;5050.292958985754;5068.645194836363;5104.438511763703;5215.556677682402;4369.584889435499;4605.171069207541;4948.208286674133;5066.468020857794;5138.5889128869785;4096.713230073896;4311.892202694927;4628.72710004748;5015.022070696215;5085.898282034357;4660.557247061523;4859.975700121512;5109.156989096795;5119.8992216673405;5133.101707201225;3557.4663800429885;4748.620110391195;4780.665353425262;4823.4289395747965;4824.662273640846;4416.3375493675885;5251.501107394792;5726.856828579277;5753.539716822645;5771.595988280822;5029.7736601853;5123;5925.755591325827;5927.199828023205;5936.614120082181;5465.371482279549;5534.916976245359;5916.112583112567;6281.335446106739;6384.52116091295;5336.893262134774;5394.568434525228;5398.46015398457;5399.298091248152;5424.783008679648;5002.774875130628;6240.87518249637;6350.936490635081;6707.3292670732535;6732.93267067328;4802.823308289421;5466.508014278734;5482.150266708666;5557.694326811983;5563.666180029211;4206.390073439396;4686;5656.971715141398;5672.376038635747;5705.1727499512335;4820.6143508519235;5276.261323414725;5535.750891209906;5795.82612521625;5935.703214839241;5637.068724263442;6167.352427995085;6289.654069026247;6311.368863113744;6324.3991820776955;1759.698845475526;1770.5485101299087;1792.1755992243454;1794.47063116381;1798.1948160869085;1051.7694297671742;1110.2616759854768;1237.418413345726;1260.1368062876775;1307.2940612069376;4780.4741478437345;5850.531957443394;5855.970720146457;5901.29184497863;5987.401259874065;4642.535746425398;6166.105914642441;6203.348648391944;6295.527835412271;6422.875996700652;4541.115437093636;5864.354920958691;5883.058710606368;5888.175655408247;5935.459373579215;3414.4024795661157;3420.743444241692;3423.6747508986605;3444.4661077533024;3479.443289073736;6259;6665.533412661067;6730.192376914802;6768.424683901825;6783.38949494538;5812.779316343531;6284.591501552384;6511.218653761579;6579;6753.49348798842;5657.575681823797;6191.411815877575;6619.073329733841;6712.247978328862;6750.729970801113;6230.937690623217;6500.187476565429;6545.738170473318;6576.046473261446;6580.1116849224745;5911.07889935764;6329;6364.331745166876;6372.96813515948;6432.292447830641;6746.291639377795;6800.761973330909;6804.795856124324;6824.112865327475;6848.2124555674745;4472.600823374194;4872.4153101628035;4873.902521949471;5040.470750571138;5105.5149760772665;6480.708368123352;6488.935110649022;6495.870082598416;6523;6529.347065293433;5037.345145131109;5849.976254155591;5925.762969481172;6210;6310.810675679737;5526.944730552643;6286.9312216924145;6309.9588567885785;6327.936720632735;6336.17629708135;5534;5745.735938093612;6154.9384506154365;6170;6180.938190618036;5423.972880135732;6078.331383547717;6216.6323408849985;6264.185655864709;6273.843153921189;5096.541311282002;5142.820001299937;6127.754889804358;6145.047517634774;6209.534284928558 09:35:54 INFO - PID 1901 | |5;yui.html;731.7694926098279;745.8359160984584;773.636390896279;782.6712780632134;808.0989696488417;14906.254687265648;15700.528984130466;16128.274227659755;16144.75782863258;16166.75749863753;14636.268186590654;14820.629484262872;15063.397464101423;15090.849091509082;15220.467283645046;906.4153620914523;944.1502647617131;955.4506158958592;967.849983252597;974.6393834281325;986.1420564109235;992.4045572656421;993.0565962335774;993.6869885985927;994.174834887042;1027.3733022856052;1036.2642523808106;1036.818556752569;1037.6835065305079;1038.3095241664284;1020.0564477857952;1025.8666373371452;1026.4098143567478;1027.6043723166597;1032.0556690628066;758.9620518974084;759.6961215513784;760.5398733766048;761.9466637335389;765.9195784442664;13485.460581576725;16506.75239871403;16615.504604585647;16759.497215083567;16986.83013169877;14211.21838298893;14611.63470913225;14711.264436778118;14851.554453366416;15360.078395296317;11459.656210313704;11555.711107222387;11776.764464710657;11857.94071029648;11880.584179553674;3695.0762309422644;3725.105974566112;3757.304898593769;3883.0292426893275;3921.490206273195;100196;103465;104284;107330.46334768276;109999;10056.798864022825;10623.309484883612;11508.654740357802;11599.478023488964;11690.590829320932;24710.752892471206;24731.876340618182;24971.250862474153;25021.62467562988;25037.499250015262;7764.07250580905;7787.29914307715;7922.128565857751;7974.282314591715;8145.429819912668;4122.814473348706;4243.384709217146;4244.978775106105;4279.700420970565;4280.700350975464;3289.490129030004;3381.6111147217894;3382.475716263983;3385.8984230473125;3387.356402283558;638.4955884851008;641;646.5926466326184;663.9601623902581;667.2493444874516;592.6532978207797;625.8924440154653;650.8047585724264;658.5686375424104;687.8349196192878;9558.856617150748;10032.79934401308;10235.539400726986;10380.948095259475;10394.740131496774;659.22541014308;671.758167059858;686.6875571614903;691.7129391302653;697.6790676288863;4229.534751177368;4597.218472859622;4599.126166028511;4637.698549594265;4650.720956742606;95660.52169739107;96476.51761741146;97055;98397.50801245948;105107.4744626272;58578;60668;73203.63398183082;85139;87075.12924870796;66046;66060;67662;68222.3177768216;68246.65876670684;9546.140847323773;10570.735731606772;10764.569417223223;10987.670369888916;10999.285046471954;39317.6068239314;46562;46997.530024699314;47102.528974709814;50638.746806267205;40486;61555;62396.68801655962;62410.06384904139;62571.687141564;13285.126542825084;13404.530841420503;13507.000912300155;13520.053596248168;14008.64978375508;14337.426502939765;14412.063215890934;14429.278536072945;14536;14600.196989165697;12997.805032924325;13215.339233038118;13275.800862987257;13583.52457663977;13897.513587024405;12364.381780910739;12553.121281510572;12632.621021369374;13065.542706005244;13240.867591323964;11697.532098716296;13213.801792972921;13222.140560863512;13831.100978436369;13939.303034848013;10896.734150002128;11020.944895275472;11250.60622878228;11297.575957003453;11702.88297117052;8942.86585701228;10019.799604007732;10289.485525723834;10844.891551084704;11316.490757916079 09:35:54 INFO - PID 1901 | __end_tp_report 09:35:54 INFO - PID 1901 | __start_cc_report 09:35:54 INFO - PID 1901 | _x_x_mozilla_cycle_collect,425 09:35:54 INFO - PID 1901 | __end_cc_report 09:35:54 INFO - PID 1901 | __startTimestamp1489682154514__endTimestamp 09:35:54 INFO - PID 1901 | 09:35:54 INFO - PID 1901 | ------- Summary: start ------- 09:35:54 INFO - PID 1901 | Number of tests: 6 09:35:54 INFO - PID 1901 | 09:35:54 INFO - PID 1901 | [#0] dojo.html Cycles:165 Average:5800.79 Median:6047.73 stddev:651.30 (10.8%) stddev-sans-first:612.09 09:35:54 INFO - PID 1901 | Values: 2894.3 3409.7 3440.0 3442.1 3501.8 4996.8 5691.5 5820.1 5868.6 5896.2 5746.0 5780.5 5881.4 5934.9 6006.1 5276.8 5340.7 5342.4 5345.0 5467.8 5330.3 5363.7 5425.7 5445.2 5512.0 5238.8 5290.4 5397.9 5401.1 5406.2 4126.6 5314.0 5348.3 5367.2 5406.8 5225.7 5271.8 5299.8 5299.9 5314.5 5329.8 5699.9 5840.6 5921.6 5950.7 6241.3 6280.4 6297.2 6297.8 6309.9 6291.6 6313.0 6313.0 6316.2 6317.8 6024.0 6025.6 6033.9 6047.7 6052.9 5575.0 5604.8 6241.5 6243.2 6299.5 6140.7 6144.5 6198.2 6214.5 6329.2 4748.3 6228.5 6294.7 6295.4 6318.0 6218.5 6269.3 6318.0 6328.9 6347.9 6230.3 6252.3 6263.0 6280.6 6285.7 4998.8 5008.1 5048.5 5059.4 5086.6 4524.5 4531.8 4550.1 4566.6 4570.9 5941.3 5994.3 6004.8 6014.0 6024.7 6239.8 6251.2 6259.5 6268.2 6319.1 5952.1 5985.8 5994.0 5998.7 6028.7 5556.6 5557.7 5562.6 5581.0 5586.3 6203.4 6274.6 6343.2 6358.9 6428.4 5458.6 6208.9 6236.3 6264.3 6268.8 6271.3 6272.4 6277.7 6289.5 6316.1 6146.4 6152.2 6157.8 6203.0 6258.2 5237.1 6002.7 6217.3 6271.9 6280.3 6205.9 6226.7 6229.8 6231.6 6246.8 6110.3 6115.4 6141.9 6179.2 6199.7 5181.0 6090.8 6098.7 6113.2 6174.9 6252.2 6282.5 6286.7 6293.6 6319.6 5940.1 5952.3 6097.8 6124.1 6126.5 09:35:54 INFO - PID 1901 | 09:35:54 INFO - PID 1901 | [#1] ext.html Cycles:180 Average:55328.80 Median:165290.93 stddev:109878.51 (66.5%) stddev-sans-first:110110.26 09:35:54 INFO - PID 1901 | Values: 724.0 786.6 809.6 928.9 930.0 31802.4 36784.6 37394.8 37820.4 37969.5 29704.6 36574.5 36916.4 36917.8 37030.6 7630.9 7845.8 10733.7 10967.2 11008.3 10896.7 11017.6 11021.5 11028.0 11047.2 11063.9 11093.1 11101.8 11108.9 11131.4 9449.6 11091.0 11157.8 11159.1 11285.3 9918.9 10382.2 10871.8 10923.0 10935.6 38321.6 38584.0 38593.6 38603.8 38672.6 3446.4 3830.8 3857.7 3868.9 4032.7 27993.0 28099.0 28122.9 28275.6 29213.3 3109.5 3114.0 3146.4 3151.3 3271.9 275400.0 326333.0 327310.0 328723.0 332523.3 50928.7 50990.7 50991.7 51024.7 51231.0 53113.7 53118.7 53300.5 53347.7 53839.5 14275.7 14593.1 14711.0 14715.8 14721.0 11814.6 11819.0 11826.9 11854.3 11854.6 13346.3 13950.2 13997.0 14016.0 14091.2 5236.6 6090.4 6164.9 6167.4 6177.5 917.2 1106.8 1115.5 1124.4 1171.2 27029.6 27531.9 27768.4 27864.3 28034.4 863.1 1032.5 1042.9 1049.7 1057.7 14533.6 14647.8 14717.1 14763.9 14835.6 434387.0 454717.0 456463.0 457248.0 463877.0 52804.5 55930.0 56194.7 56721.0 56856.7 1265.4 1403.3 1430.8 1439.8 1457.6 24802.4 28407.6 28635.6 28691.9 29337.4 46530.5 46866.8 46902.0 47057.3 47162.1 451320.0 455202.0 457239.0 458075.0 460214.0 27343.7 27671.6 34434.3 34720.3 35215.8 42662.4 43612.3 44273.0 44451.6 44555.0 28155.9 35622.9 35661.0 36899.0 37136.0 34088.8 35539.0 35555.3 36501.8 36641.0 35014.5 35838.1 36209.3 36248.3 36419.8 30427.7 31634.8 31881.0 31886.8 31955.9 22552.4 23581.2 23869.5 23933.2 24269.5 09:35:54 INFO - PID 1901 | 09:35:54 INFO - PID 1901 | [#2] jquery.html Cycles:180 Average:39963.26 Median:22704.38 stddev:106347.53 (468.4%) stddev-sans-first:106604.37 09:35:54 INFO - PID 1901 | Values: 396.3 413.4 414.9 428.7 428.9 19688.5 20542.0 20767.6 21127.0 21270.1 21613.7 21936.1 22263.7 22707.3 22906.1 5648.9 6682.9 6699.2 6705.5 6721.6 6430.6 6552.4 6558.2 6574.5 6638.9 6359.7 6592.1 6635.4 6642.9 6651.9 6482.3 6571.0 6622.7 6623.8 6636.9 6373.1 6417.1 6464.0 6472.4 6472.9 26627.1 26676.5 26739.2 26792.9 26858.8 2536.5 2843.4 2846.0 2850.6 2857.7 15789.9 16337.8 16582.5 16645.2 17084.1 1951.7 1996.5 2006.3 2010.9 2053.0 147943.0 155621.9 155703.2 156900.2 158445.0 18744.4 22701.4 22762.9 22795.4 23001.3 35661.8 35778.8 35838.0 35862.6 35951.5 10224.9 10464.4 10482.8 10623.8 10803.3 8476.6 8534.4 8535.8 8730.6 8744.9 10556.7 10614.6 10656.9 10800.6 10866.5 3697.9 3770.4 4268.6 4376.4 4379.0 835.6 896.0 929.1 937.5 960.5 22361.7 22630.1 23789.0 23825.8 23837.4 911.8 931.0 939.4 966.6 973.2 11708.9 12199.5 12239.6 12485.3 12523.8 612703.0 621741.0 629581.0 635179.0 661169.0 139592.0 143972.3 146193.0 148750.0 148990.3 1270.1 1273.3 1302.3 1312.2 1312.9 19224.3 20410.5 20681.4 20694.5 20751.4 61088.7 61099.5 61178.0 62355.7 62435.7 76107.2 85329.0 86883.0 87548.2 88863.0 12171.8 12376.0 12543.3 12547.8 12578.8 13305.7 13366.2 13377.1 13709.0 13750.4 9585.8 11739.7 11795.8 12175.4 12175.5 11557.8 11769.5 11906.8 11991.3 12191.6 10882.6 11910.0 12097.6 12097.9 12207.6 10442.8 12177.3 12485.2 12508.0 12706.9 10191.2 11714.2 11752.9 12063.6 12119.2 09:35:54 INFO - PID 1901 | 09:35:54 INFO - PID 1901 | [#3] mootools.html Cycles:180 Average:6515.47 Median:3365.26 stddev:3997.02 (118.8%) stddev-sans-first:3990.87 09:35:54 INFO - PID 1901 | Values: 1558.4 1560.0 1566.1 1603.3 1662.7 9181.2 10369.1 10683.5 10785.9 11011.9 10597.9 10839.9 10840.4 10886.9 10920.5 2314.9 2338.8 2350.8 2354.3 2355.0 2317.8 2345.0 2355.8 2360.9 2365.5 2068.7 2223.7 2314.3 2335.0 2335.4 2276.3 2287.3 2287.8 2289.3 2295.8 2259.1 2306.7 2315.1 2320.8 2325.7 10906.0 10907.7 10966.1 10966.8 10972.7 5504.4 5721.7 5737.4 5742.8 5774.4 3353.3 3377.2 3635.9 3668.3 3679.0 1113.5 1168.8 1232.0 1239.5 1245.8 11185.0 13555.4 13682.7 15023.5 15044.4 3881.7 4503.7 4605.7 4643.2 4654.6 10847.8 10905.0 10908.6 10927.3 10927.8 5457.8 5462.1 5471.3 5472.9 5498.2 5017.8 5115.3 5189.9 5192.4 5209.2 3718.5 3826.8 3857.6 3899.6 3934.5 1034.8 1037.3 1038.3 1051.0 1051.8 772.5 780.9 792.2 798.3 807.1 6282.4 6373.0 6428.2 6613.7 6615.9 893.1 910.6 933.8 981.8 1009.1 3990.2 4067.9 4098.6 4214.1 4220.5 12915.7 13328.3 13374.3 13375.7 13389.5 9980.9 13069.7 13114.6 13215.2 13348.9 8278.5 9600.5 9991.1 10020.0 10064.3 6075.2 6466.9 6469.4 6555.5 6792.3 11640.6 11866.6 11972.6 12000.2 12048.5 9655.9 10537.0 11702.6 12085.3 12172.0 8720.7 8895.0 9089.9 9171.0 9198.5 10103.0 10243.9 10318.0 10344.4 10460.2 9029.8 9072.9 9081.4 9125.7 9182.8 8753.3 8818.4 8891.9 8897.7 8931.9 7609.4 7713.4 7733.3 7803.0 7832.3 5790.9 5832.2 5899.1 6009.4 6045.0 5866.8 5895.9 6012.4 6068.4 6136.5 09:35:54 INFO - PID 1901 | 09:35:54 INFO - PID 1901 | [#4] prototype.html Cycles:180 Average:5278.96 Median:5716.01 stddev:1480.35 (25.9%) stddev-sans-first:1437.99 09:35:54 INFO - PID 1901 | Values: 373.8 384.3 385.6 391.4 393.2 4814.4 5218.8 5676.6 5811.8 5998.7 4760.7 5642.0 5853.6 5906.6 5907.8 5045.6 5050.3 5068.6 5104.4 5215.6 4369.6 4605.2 4948.2 5066.5 5138.6 4096.7 4311.9 4628.7 5015.0 5085.9 4660.6 4860.0 5109.2 5119.9 5133.1 3557.5 4748.6 4780.7 4823.4 4824.7 4416.3 5251.5 5726.9 5753.5 5771.6 5029.8 5123.0 5925.8 5927.2 5936.6 5465.4 5534.9 5916.1 6281.3 6384.5 5336.9 5394.6 5398.5 5399.3 5424.8 5002.8 6240.9 6350.9 6707.3 6732.9 4802.8 5466.5 5482.2 5557.7 5563.7 4206.4 4686.0 5657.0 5672.4 5705.2 4820.6 5276.3 5535.8 5795.8 5935.7 5637.1 6167.4 6289.7 6311.4 6324.4 1759.7 1770.5 1792.2 1794.5 1798.2 1051.8 1110.3 1237.4 1260.1 1307.3 4780.5 5850.5 5856.0 5901.3 5987.4 4642.5 6166.1 6203.3 6295.5 6422.9 4541.1 5864.4 5883.1 5888.2 5935.5 3414.4 3420.7 3423.7 3444.5 3479.4 6259.0 6665.5 6730.2 6768.4 6783.4 5812.8 6284.6 6511.2 6579.0 6753.5 5657.6 6191.4 6619.1 6712.2 6750.7 6230.9 6500.2 6545.7 6576.0 6580.1 5911.1 6329.0 6364.3 6373.0 6432.3 6746.3 6800.8 6804.8 6824.1 6848.2 4472.6 4872.4 4873.9 5040.5 5105.5 6480.7 6488.9 6495.9 6523.0 6529.3 5037.3 5850.0 5925.8 6210.0 6310.8 5526.9 6286.9 6310.0 6327.9 6336.2 5534.0 5745.7 6154.9 6170.0 6180.9 5424.0 6078.3 6216.6 6264.2 6273.8 5096.5 5142.8 6127.8 6145.0 6209.5 09:35:54 INFO - PID 1901 | 09:35:54 INFO - PID 1901 | [#5] yui.html Cycles:180 Average:19389.05 Median:24996.44 stddev:27005.86 (108.0%) stddev-sans-first:27045.28 09:35:54 INFO - PID 1901 | Values: 731.8 745.8 773.6 782.7 808.1 14906.3 15700.5 16128.3 16144.8 16166.8 14636.3 14820.6 15063.4 15090.8 15220.5 906.4 944.2 955.5 967.8 974.6 986.1 992.4 993.1 993.7 994.2 1027.4 1036.3 1036.8 1037.7 1038.3 1020.1 1025.9 1026.4 1027.6 1032.1 759.0 759.7 760.5 761.9 765.9 13485.5 16506.8 16615.5 16759.5 16986.8 14211.2 14611.6 14711.3 14851.6 15360.1 11459.7 11555.7 11776.8 11857.9 11880.6 3695.1 3725.1 3757.3 3883.0 3921.5 100196.0 103465.0 104284.0 107330.5 109999.0 10056.8 10623.3 11508.7 11599.5 11690.6 24710.8 24731.9 24971.3 25021.6 25037.5 7764.1 7787.3 7922.1 7974.3 8145.4 4122.8 4243.4 4245.0 4279.7 4280.7 3289.5 3381.6 3382.5 3385.9 3387.4 638.5 641.0 646.6 664.0 667.2 592.7 625.9 650.8 658.6 687.8 9558.9 10032.8 10235.5 10380.9 10394.7 659.2 671.8 686.7 691.7 697.7 4229.5 4597.2 4599.1 4637.7 4650.7 95660.5 96476.5 97055.0 98397.5 105107.5 58578.0 60668.0 73203.6 85139.0 87075.1 66046.0 66060.0 67662.0 68222.3 68246.7 9546.1 10570.7 10764.6 10987.7 10999.3 39317.6 46562.0 46997.5 47102.5 50638.7 40486.0 61555.0 62396.7 62410.1 62571.7 13285.1 13404.5 13507.0 13520.1 14008.6 14337.4 14412.1 14429.3 14536.0 14600.2 12997.8 13215.3 13275.8 13583.5 13897.5 12364.4 12553.1 12632.6 13065.5 13240.9 11697.5 13213.8 13222.1 13831.1 13939.3 10896.7 11020.9 11250.6 11297.6 11702.9 8942.9 10019.8 10289.5 10844.9 11316.5 09:35:54 INFO - PID 1901 | -------- Summary: end -------- 09:35:54 INFO - PID 1901 | 09:35:55 INFO - TEST-INFO | 1901: exit 0 09:35:55 INFO - TEST-OK | dromaeo_css | took 1116040ms 09:35:55 INFO - TEST-START | kraken 09:35:55 INFO - operating with platform_type : mac_ 09:35:55 INFO - Initialising browser for kraken test... 09:35:55 INFO - TEST-INFO | started process 1909 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/tmptLQS13/profile http://localhost:49382/getInfo.html) 09:35:58 INFO - PID 1909 | 2017-03-16 09:35:58.246 plugin-container[1910:11858] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 09:35:58 INFO - PID 1909 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:35:58 INFO - PID 1909 | 2017-03-16 09:35:58.250 plugin-container[1910:11858] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d1f, name = 'com.apple.CFPasteboardClient' 09:35:58 INFO - PID 1909 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:35:58 INFO - PID 1909 | 2017-03-16 09:35:58.251 plugin-container[1910:11858] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:35:58 INFO - PID 1909 | __metrics Screen width/height:1600/1200 09:35:58 INFO - PID 1909 | colorDepth:24 09:35:58 INFO - PID 1909 | Browser inner width/height: 1024/689 09:35:58 INFO - PID 1909 | __metrics 09:35:58 ERROR - PID 1909 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3350: TypeError: this.tabs is undefined 09:35:59 INFO - TEST-INFO | 1909: exit 0 09:35:59 INFO - Browser initialized. 09:35:59 INFO - Running cycle 1/1 for kraken test... 09:35:59 INFO - TEST-INFO | started process 1911 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/tmptLQS13/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:36:01 INFO - PID 1911 | 2017-03-16 09:36:01.845 plugin-container[1912:11983] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 09:36:01 INFO - PID 1911 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:36:01 INFO - PID 1911 | 2017-03-16 09:36:01.847 plugin-container[1912:11983] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4343, name = 'com.apple.CFPasteboardClient' 09:36:01 INFO - PID 1911 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:36:01 INFO - PID 1911 | 2017-03-16 09:36:01.848 plugin-container[1912:11983] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:36:11 INFO - PID 1911 | RSS: Main: 228012032 09:36:11 INFO - PID 1911 | 09:36:14 INFO - PID 1911 | Cycle 1(1): loaded http://localhost:49382/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49382/tests/kraken/driver.html?testName=audio-beat-detection) 09:36:15 INFO - PID 1911 | RSS: Main: 242417664 09:36:15 INFO - PID 1911 | 09:36:18 INFO - PID 1911 | Cycle 1(1): loaded http://localhost:49382/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49382/tests/kraken/driver.html?testName=audio-dft) 09:36:18 INFO - PID 1911 | RSS: Main: 250654720 09:36:18 INFO - PID 1911 | 09:36:22 INFO - PID 1911 | Cycle 1(1): loaded http://localhost:49382/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49382/tests/kraken/driver.html?testName=audio-fft) 09:36:23 INFO - PID 1911 | RSS: Main: 253165568 09:36:23 INFO - PID 1911 | 09:36:24 INFO - PID 1911 | Cycle 1(1): loaded http://localhost:49382/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49382/tests/kraken/driver.html?testName=audio-oscillator) 09:36:25 INFO - PID 1911 | RSS: Main: 254324736 09:36:25 INFO - PID 1911 | 09:36:27 INFO - PID 1911 | Cycle 1(1): loaded http://localhost:49382/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49382/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:36:28 INFO - PID 1911 | RSS: Main: 255991808 09:36:28 INFO - PID 1911 | 09:36:31 INFO - PID 1911 | Cycle 1(1): loaded http://localhost:49382/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49382/tests/kraken/driver.html?testName=imaging-darkroom) 09:36:32 INFO - PID 1911 | RSS: Main: 261111808 09:36:32 INFO - PID 1911 | 09:36:35 INFO - PID 1911 | Cycle 1(1): loaded http://localhost:49382/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49382/tests/kraken/driver.html?testName=imaging-desaturate) 09:36:36 INFO - PID 1911 | RSS: Main: 261672960 09:36:36 INFO - PID 1911 | 09:36:39 INFO - PID 1911 | Cycle 1(1): loaded http://localhost:49382/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49382/tests/kraken/driver.html?testName=json-parse-financial) 09:36:40 INFO - PID 1911 | RSS: Main: 261459968 09:36:40 INFO - PID 1911 | 09:36:42 INFO - PID 1911 | Cycle 1(1): loaded http://localhost:49382/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49382/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:36:42 INFO - PID 1911 | RSS: Main: 261480448 09:36:42 INFO - PID 1911 | 09:36:44 INFO - PID 1911 | Cycle 1(1): loaded http://localhost:49382/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49382/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:36:45 INFO - PID 1911 | RSS: Main: 261369856 09:36:45 INFO - PID 1911 | 09:36:47 INFO - PID 1911 | Cycle 1(1): loaded http://localhost:49382/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49382/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:36:48 INFO - PID 1911 | RSS: Main: 261447680 09:36:48 INFO - PID 1911 | 09:36:51 INFO - PID 1911 | Cycle 1(1): loaded http://localhost:49382/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49382/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:36:51 INFO - PID 1911 | RSS: Main: 261926912 09:36:51 INFO - PID 1911 | 09:36:55 INFO - PID 1911 | Cycle 1(1): loaded http://localhost:49382/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49382/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:36:55 INFO - PID 1911 | RSS: Main: 261607424 09:36:55 INFO - PID 1911 | 09:36:57 INFO - PID 1911 | Cycle 1(1): loaded http://localhost:49382/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49382/tests/kraken/driver.html?testName=ai-astar) 09:36:57 INFO - PID 1911 | RSS: Main: 264695808 09:36:57 INFO - PID 1911 | 09:36:57 INFO - PID 1911 | __start_tp_report 09:36:57 INFO - PID 1911 | _x_x_mozilla_page_load 09:36:57 INFO - PID 1911 | _x_x_mozilla_page_load_details 09:36:57 INFO - PID 1911 | |i|pagename|runs| 09:36:57 INFO - PID 1911 | |0;ai-astar;91;182;178;127;119;120;119;119;119;119 09:36:57 INFO - PID 1911 | |1;audio-beat-detection;143;150;149;197;116;118;117;121;142;107 09:36:57 INFO - PID 1911 | |2;audio-dft;209;214;212;212;202;205;198;224;277;203 09:36:57 INFO - PID 1911 | |3;audio-fft;79;60;62;91;60;60;67;76;97;60 09:36:57 INFO - PID 1911 | |4;audio-oscillator;86;72;94;72;72;71;117;110;106;83 09:36:57 INFO - PID 1911 | |5;imaging-gaussian-blur;100;135;85;137;136;117;96;135;136;90 09:36:57 INFO - PID 1911 | |6;imaging-darkroom;152;153;97;97;97;127;114;151;135;97 09:36:57 INFO - PID 1911 | |7;imaging-desaturate;90;93;141;142;108;81;146;143;101;144 09:36:57 INFO - PID 1911 | |8;json-parse-financial;70;69;92;105;105;68;69;68;69;68 09:36:57 INFO - PID 1911 | |9;json-stringify-tinderbox;61;66;58;72;63;62;57;58;59;59 09:36:57 INFO - PID 1911 | |10;stanford-crypto-aes;84;86;85;84;85;87;84;87;84;87 09:36:57 INFO - PID 1911 | |11;stanford-crypto-ccm;158;158;159;151;135;151;161;153;203;171 09:36:57 INFO - PID 1911 | |12;stanford-crypto-pbkdf2;200;191;184;187;185;189;242;197;190;188 09:36:57 INFO - PID 1911 | |13;stanford-crypto-sha256-iterative;69;73;78;70;70;69;67;71;67;72 09:36:57 INFO - PID 1911 | __end_tp_report 09:36:57 INFO - PID 1911 | __start_cc_report 09:36:57 INFO - PID 1911 | _x_x_mozilla_cycle_collect,1004 09:36:57 INFO - PID 1911 | __end_cc_report 09:36:57 INFO - PID 1911 | __startTimestamp1489682217778__endTimestamp 09:36:57 INFO - PID 1911 | 09:36:57 INFO - PID 1911 | ------- Summary: start ------- 09:36:57 INFO - PID 1911 | Number of tests: 14 09:36:57 INFO - PID 1911 | 09:36:57 INFO - PID 1911 | [#0] ai-astar Cycles:10 Average:129.30 Median:123.50 stddev:28.36 (23.0%) stddev-sans-first:26.48 09:36:57 INFO - PID 1911 | Values: 91.0 182.0 178.0 127.0 119.0 120.0 119.0 119.0 119.0 119.0 09:36:57 INFO - PID 1911 | 09:36:57 INFO - PID 1911 | [#1] audio-beat-detection Cycles:10 Average:136.00 Median:142.50 stddev:26.50 (18.6%) stddev-sans-first:27.99 09:36:57 INFO - PID 1911 | Values: 143.0 150.0 149.0 197.0 116.0 118.0 117.0 121.0 142.0 107.0 09:36:57 INFO - PID 1911 | 09:36:57 INFO - PID 1911 | [#2] audio-dft Cycles:10 Average:215.60 Median:212.00 stddev:22.80 (10.8%) stddev-sans-first:24.06 09:36:57 INFO - PID 1911 | Values: 209.0 214.0 212.0 212.0 202.0 205.0 198.0 224.0 277.0 203.0 09:36:57 INFO - PID 1911 | 09:36:57 INFO - PID 1911 | [#3] audio-fft Cycles:10 Average:71.20 Median:71.50 stddev:13.93 (19.5%) stddev-sans-first:14.48 09:36:57 INFO - PID 1911 | Values: 79.0 60.0 62.0 91.0 60.0 60.0 67.0 76.0 97.0 60.0 09:36:57 INFO - PID 1911 | 09:36:57 INFO - PID 1911 | [#4] audio-oscillator Cycles:10 Average:88.30 Median:72.00 stddev:17.54 (24.4%) stddev-sans-first:18.59 09:36:57 INFO - PID 1911 | Values: 86.0 72.0 94.0 72.0 72.0 71.0 117.0 110.0 106.0 83.0 09:36:57 INFO - PID 1911 | 09:36:57 INFO - PID 1911 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:136.50 stddev:21.74 (15.9%) stddev-sans-first:22.20 09:36:57 INFO - PID 1911 | Values: 100.0 135.0 85.0 137.0 136.0 117.0 96.0 135.0 136.0 90.0 09:36:57 INFO - PID 1911 | 09:36:57 INFO - PID 1911 | [#6] imaging-darkroom Cycles:10 Average:122.00 Median:125.00 stddev:24.63 (19.7%) stddev-sans-first:23.61 09:36:57 INFO - PID 1911 | Values: 152.0 153.0 97.0 97.0 97.0 127.0 114.0 151.0 135.0 97.0 09:36:57 INFO - PID 1911 | 09:36:57 INFO - PID 1911 | [#7] imaging-desaturate Cycles:10 Average:118.90 Median:145.00 stddev:26.56 (18.3%) stddev-sans-first:26.03 09:36:57 INFO - PID 1911 | Values: 90.0 93.0 141.0 142.0 108.0 81.0 146.0 143.0 101.0 144.0 09:36:57 INFO - PID 1911 | 09:36:57 INFO - PID 1911 | [#8] json-parse-financial Cycles:10 Average:78.30 Median:69.00 stddev:15.85 (23.0%) stddev-sans-first:16.52 09:36:57 INFO - PID 1911 | Values: 70.0 69.0 92.0 105.0 105.0 68.0 69.0 68.0 69.0 68.0 09:36:57 INFO - PID 1911 | 09:36:57 INFO - PID 1911 | [#9] json-stringify-tinderbox Cycles:10 Average:61.50 Median:61.50 stddev:4.60 (7.5%) stddev-sans-first:4.88 09:36:57 INFO - PID 1911 | Values: 61.0 66.0 58.0 72.0 63.0 62.0 57.0 58.0 59.0 59.0 09:36:57 INFO - PID 1911 | 09:36:57 INFO - PID 1911 | [#10] stanford-crypto-aes Cycles:10 Average:85.30 Median:85.50 stddev:1.34 (1.6%) stddev-sans-first:1.33 09:36:57 INFO - PID 1911 | Values: 84.0 86.0 85.0 84.0 85.0 87.0 84.0 87.0 84.0 87.0 09:36:57 INFO - PID 1911 | 09:36:57 INFO - PID 1911 | [#11] stanford-crypto-ccm Cycles:10 Average:160.00 Median:158.50 stddev:17.69 (11.2%) stddev-sans-first:18.75 09:36:57 INFO - PID 1911 | Values: 158.0 158.0 159.0 151.0 135.0 151.0 161.0 153.0 203.0 171.0 09:36:57 INFO - PID 1911 | 09:36:57 INFO - PID 1911 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:195.30 Median:190.50 stddev:17.15 (9.0%) stddev-sans-first:18.11 09:36:57 INFO - PID 1911 | Values: 200.0 191.0 184.0 187.0 185.0 189.0 242.0 197.0 190.0 188.0 09:36:57 INFO - PID 1911 | 09:36:57 INFO - PID 1911 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.60 Median:70.50 stddev:3.24 (4.6%) stddev-sans-first:3.38 09:36:57 INFO - PID 1911 | Values: 69.0 73.0 78.0 70.0 70.0 69.0 67.0 71.0 67.0 72.0 09:36:57 INFO - PID 1911 | -------- Summary: end -------- 09:36:57 INFO - PID 1911 | 09:36:58 INFO - TEST-INFO | 1911: exit 0 09:36:58 INFO - TEST-OK | kraken | took 63497ms 09:36:58 INFO - SUITE-END | took 1179s 09:36:58 INFO - Completed test suite (00:19:39) 09:36:58 INFO - javascript benchmark 09:36:59 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7850.528061250522, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2894.3487715264064, 3409.7272218222543, 3439.985204364712, 3442.1222588239993, 3501.8249087545623, 4996.825111121111, 5691.544676425887, 5820.126980952854, 5868.647881127135, 5896.174535565025, 5745.971270143644, 5780.4797568218855, 5881.3530511643685, 5934.88130237395, 6006.129111278862, 5276.841694749163, 5340.732963351837, 5342.385625653051, 5345.037893179227, 5467.808626698066, 5330.28041214435, 5363.731813409334, 5425.674459532421, 5445.156000819879, 5512.035393806088, 5238.816641417532, 5290.365156181283, 5397.892042159174, 5401.135818269097, 5406.189071639246, 4126.566710495415, 5313.973430132824, 5348.331458567679, 5367.194920761878, 5406.756695948692, 5225.686458812445, 5271.841844744664, 5299.814506492293, 5299.947000530023, 5314.548263397616, 5329.760160792774, 5699.886002279973, 5840.561957853178, 5921.644701317935, 5950.732217050243, 6241.251049874044, 6280.371962803729, 6297.2128483939505, 6297.811065668037, 6309.905351419732, 6291.559590828644, 6312.968435157794, 6312.968435157841, 6316.1788967434395, 6317.778877739303, 6024.036154215304, 6025.614108755011, 6033.909491357633, 6047.7278522466595, 6052.878942421215, 5574.968630803314, 5604.8318550443555, 6241.469475094577, 6243.157173781572, 6299.464545513682, 6140.662263575547, 6144.4945988232275, 6198.2335034514945, 6214.533909956771, 6329.240491141, 4748.335233067373, 6228.47058000066, 6294.685265736786, 6295.37046295376, 6317.96841015792, 6218.533609979269, 6269.341719119427, 6317.957537006342, 6328.936710632926, 6347.873042539216, 6230.314665386803, 6252.280987686474, 6263.029230469285, 6280.623162610258, 6285.654289014148, 4998.775055122528, 5008.098542262428, 5048.545630893163, 5059.443461219263, 5086.64393492452, 4524.502304746476, 4531.750753708578, 4550.112728018071, 4566.611837993808, 4570.908581828411, 5941.346451890288, 5994.340622531518, 6004.789832355847, 6014.0377539593455, 6024.668643224665, 6239.844003899939, 6251.249850018027, 6259.4679452246155, 6268.185135932391, 6319.146915166394, 5952.077427998667, 5985.790497332572, 5993.970030149909, 5998.700064996733, 6028.728707208186, 5556.583256255716, 5557.749901254453, 5562.610617256834, 5580.972095139498, 5586.273784408001, 6203.379662033761, 6274.560780745486, 6343.175387199819, 6358.872822543431, 6428.4214420702365, 5458.590605704509, 6208.906866396917, 6236.282827474717, 6264.310925798068, 6268.843278918063, 6271.31015588294, 6272.435480806749, 6277.686115694106, 6289.528285378524, 6316.05259211122, 6146.416090471398, 6152.230971128609, 6157.815265542041, 6203.0, 6258.186435763504, 5237.057329680694, 6002.669853158118, 6217.285012223652, 6271.874562508633, 6280.277768056732, 6205.875882482234, 6226.719797609209, 6229.750809967732, 6231.626102433868, 6246.750129994931, 6110.285381942539, 6115.388461153849, 6141.884937092248, 6179.165812615419, 6199.721012554356, 5180.963807238492, 6090.817275481742, 6098.664573448503, 6113.202667687911, 6174.9073763892675, 6252.187215662117, 6282.528810339151, 6286.717097730704, 6293.622382657055, 6319.6208227506495, 5940.079287710412, 5952.255968003999, 6097.756089756537, 6124.112003759341, 6126.540509461719], "value": 5760.841376045861, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [723.9710411583537, 786.6263524825708, 809.5992483720557, 928.9256859451241, 929.9860502092474, 31802.36395272093, 36784.63215367849, 37394.8130259349, 37820.43269350962, 37969.531980421285, 29704.55443168354, 36574.45138322927, 36916.44625330615, 36917.81541092297, 37030.62969370306, 7630.8855367169535, 7845.803854903616, 10733.677989660322, 10967.232293739442, 11008.339499630009, 10896.727581810439, 11017.559297628126, 11021.504032318524, 11028.00747932686, 11047.226694131414, 11063.889361106407, 11093.112550995942, 11101.83347249792, 11108.944455277713, 11131.443427828617, 9449.622015119387, 11091.0, 11157.832632510519, 11159.051480624157, 11285.266457680225, 9918.908920018792, 10382.22133339995, 10871.782564348749, 10923.0, 10935.56257749689, 38321.61678383208, 38584.0, 38593.61406385956, 38603.8418847435, 38672.61327386718, 3446.3796516627, 3830.8005110385384, 3857.688385948763, 3868.903277418087, 4032.717709760318, 27993.0, 28099.0, 28122.85938570294, 28275.57586636202, 29213.26966825825, 3109.5024796032753, 3114.0190839885363, 3146.4179126861604, 3151.2594540283017, 3271.8691252350113, 275400.0, 326333.0, 327310.0, 328723.0, 332523.337383314, 50928.74535627372, 50990.745046275275, 50991.745041275295, 51024.74487627538, 51230.97538049293, 53113.7344313276, 53118.67203319871, 53300.466995330324, 53347.6663083426, 53839.46160538345, 14275.715185633342, 14593.12441253507, 14711.0, 14715.779263311058, 14721.0, 11814.645560633195, 11818.995385392338, 11826.94086529579, 11854.348010859485, 11854.585089521825, 13346.265955372548, 13950.23273719955, 13997.020208585302, 14016.018878678598, 14091.224982625848, 5236.554892834151, 6090.3605121461605, 6164.8767024659255, 6167.414095660905, 6177.4749146322165, 917.1745429113718, 1106.8450416941473, 1115.4924509348227, 1124.381590125444, 1171.2035815645272, 27029.594556081676, 27531.862340688167, 27768.444631107264, 27864.303392414946, 28034.439311214068, 863.1417563491184, 1032.4940779018377, 1042.854000439939, 1049.7165765243494, 1057.724991502215, 14533.563993080224, 14647.780283295759, 14717.116972981656, 14763.92618036903, 14835.629109272355, 434387.0, 454717.0, 456463.0, 457248.0, 463877.0, 52804.47195528072, 55930.0, 56194.71902640461, 56721.0, 56856.71571642198, 1265.4115836136425, 1403.2983508245877, 1430.8211473565805, 1439.8272207334978, 1457.5627311806415, 24802.37994050091, 28407.573886392132, 28635.565976891365, 28691.856540717163, 29337.41325173442, 46530.53469465262, 46866.76566617145, 46902.0, 47057.2941405886, 47162.056758863946, 451320.0, 455202.0, 457239.0, 458075.0, 460214.0, 27343.72656273412, 27671.584926226526, 34434.31131377409, 34720.305593888486, 35215.82392088023, 42662.440253584886, 43612.34581481344, 44273.0, 44451.55548444474, 44555.0, 28155.859220703765, 35622.93131206068, 35661.0, 36899.0, 37136.0, 34088.82955585206, 35539.0, 35555.28889422249, 36501.81749091238, 36641.0, 35014.47478287777, 35838.10404739902, 36209.27581448304, 36248.275034498634, 36419.817900910326, 30427.695723042485, 31634.841825790725, 31881.0, 31886.84056579702, 31955.881544145843, 22552.436189094748, 23581.174658886855, 23869.522609547363, 23933.162339318736, 24269.51460970806], "value": 18979.313957514147, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [396.3400937439164, 413.42946733507733, 414.85272728181496, 428.7320424734541, 428.85633312840196, 19688.507787305305, 20542.0, 20767.584648307024, 21127.049282782275, 21270.14919403224, 21613.67579486309, 21936.122555097816, 22263.666045009297, 22707.318780436573, 22906.083756649754, 5648.915266271009, 6682.93317066828, 6699.19609646843, 6705.463562914955, 6721.63031033293, 6430.646314452703, 6552.443042341407, 6558.2130144382745, 6574.539782215247, 6638.933610663904, 6359.65021923794, 6592.077109204715, 6635.369639884203, 6642.933570664304, 6651.866962660744, 6482.286948435668, 6571.047198156289, 6622.701978410983, 6623.768168114141, 6636.933630663704, 6373.107764912916, 6417.133686952248, 6464.0, 6472.352764723537, 6472.8705425891685, 26627.068052618066, 26676.46647067067, 26739.197824065308, 26792.866035669893, 26858.791354388904, 2536.5053814506177, 2843.40288539407, 2845.9573106403423, 2850.6009158717798, 2857.6856545780156, 15789.921050394674, 16337.754933676004, 16582.50252492415, 16645.16774161287, 17084.060376679277, 1951.7057489596082, 1996.4509759816167, 2006.2576846566865, 2010.9296174633816, 2052.989735051315, 147943.0, 155621.88756224926, 155703.221483893, 156900.21549892292, 158445.0, 18744.437666870017, 22701.43246418853, 22762.886185569296, 22795.44091181737, 23001.309960701205, 35661.82169089138, 35778.821105894305, 35838.0, 35862.641373586455, 35951.4607280891, 10224.897751022543, 10464.372137671764, 10482.790344193227, 10623.84064239037, 10803.297785644065, 8476.576171191417, 8534.402591818638, 8535.786605334917, 8730.607122679494, 8744.912550874536, 10556.683299501028, 10614.575416983234, 10656.89343106559, 10800.649918760155, 10866.511007004703, 3697.8890633280507, 3770.4344348347418, 4268.5913648495925, 4376.387305777193, 4378.956210437918, 835.5863847395578, 895.9606856047085, 929.1266209762802, 937.5124935033745, 960.4669408478362, 22361.664575031387, 22630.094796208297, 23789.0, 23825.7617423827, 23837.404064898517, 911.8085202107632, 930.9767255818658, 939.3565407695751, 966.5747071288611, 973.2068364283086, 11708.88291117095, 12199.51201951912, 12239.632811015685, 12485.31330776816, 12523.812142817866, 612703.0, 621741.0, 629581.0, 635179.0, 661169.0, 139592.0, 143972.28013859864, 146193.0, 148750.0, 148990.25504872622, 1270.1363073110374, 1273.3124112979074, 1302.3358087375507, 1312.1667740984544, 1312.9409176587267, 19224.327148549735, 20410.489737756674, 20681.379558613266, 20694.482637934172, 20751.377458676263, 61088.69455652871, 61099.47251318575, 61178.0, 62355.6882215586, 62435.687821560605, 76107.23892761223, 85329.0, 86883.0, 87548.24903502021, 88863.0, 12171.81742273849, 12376.0, 12543.3101179436, 12547.811782823448, 12578.811317830057, 13305.66735831612, 13366.198028117955, 13377.130486518345, 13709.0, 13750.381232844746, 9585.760355990877, 11739.706507337385, 11795.764084718428, 12175.391230438265, 12175.45210465549, 11557.768844623228, 11769.47037383337, 11906.761864762482, 11991.280523168638, 12191.573294934635, 10882.61910833117, 11910.034258477526, 12097.637070887888, 12097.850704183089, 12207.633770986884, 10442.791144177227, 12177.269363838199, 12485.188462749893, 12508.0, 12706.872931270938, 10191.184705223712, 11714.1800073994, 11752.882471175179, 12063.638090857288, 12119.151659383759], "value": 11739.959471009819, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1558.400015993842, 1559.9610009749754, 1566.0525382143803, 1603.2865374908163, 1662.6674665066985, 9181.219596334311, 10369.066783989449, 10683.465826708652, 10785.892141078597, 11011.889881101186, 10597.894021059787, 10839.891601083988, 10840.403777792219, 10886.891131088687, 10920.453977301142, 2314.90740370385, 2338.8479748816358, 2350.811935045192, 2354.2701762453607, 2355.0462062864567, 2317.756635553268, 2344.9648255276184, 2355.8115350771895, 2360.87015214164, 2365.4914193448403, 2068.700038494425, 2223.7213602178813, 2314.2941402872116, 2334.9649755253686, 2335.4278201840575, 2276.3057267533395, 2287.2909398086645, 2287.8169746420244, 2289.267434420986, 2295.8392912496133, 2259.062489067035, 2306.6540018997116, 2315.120254303371, 2320.8375413721046, 2325.6744055832196, 10906.0, 10907.727306817393, 10966.122710183246, 10966.835497467544, 10972.725681857937, 5504.394516603171, 5721.6853073080965, 5737.39757325479, 5742.770289188427, 5774.393688662672, 3353.295807880348, 3377.223238655123, 3635.8909232723063, 3668.3396988542054, 3679.0, 1113.4989254835357, 1168.7896178687834, 1231.9630411087683, 1239.510393394604, 1245.8442694663167, 11185.049270811991, 13555.390007449689, 13682.657933551642, 15023.474178403812, 15044.398224071025, 3881.6894648428056, 4503.707259028152, 4605.65457590682, 4643.233866412072, 4654.557817007379, 10847.837282440769, 10905.0, 10908.563657453613, 10927.344359338465, 10927.781444371069, 5457.754401051962, 5462.0987537056735, 5471.2887324647545, 5472.863178420492, 5498.202760599691, 5017.774200161001, 5115.335006449212, 5189.896202076013, 5192.350956130484, 5209.218617207449, 3718.4980027696456, 3826.7703937763267, 3857.633524815138, 3899.590542992945, 3934.4688467057154, 1034.8085604163102, 1037.3153718545875, 1038.2835843268122, 1050.9527071281811, 1051.7686109055996, 772.4940164192462, 780.9023872015998, 792.1721800718263, 798.3333916179938, 807.0839597057383, 6282.403171698681, 6372.968135159294, 6428.164338635947, 6613.702383392662, 6615.900761488581, 893.1113542025726, 910.6084383715066, 933.8179055084194, 981.8085473332632, 1009.0615727373466, 3990.182012687392, 4067.89830254246, 4098.6106319900155, 4214.115035842446, 4220.451341325608, 12915.677108072374, 13328.33358332099, 13374.264415457243, 13375.665608359868, 13389.464421423228, 9980.90210076891, 13069.673258168621, 13114.557398686136, 13215.207087574776, 13348.866511334956, 8278.544680042656, 9600.471974041495, 9991.100800927807, 10020.0, 10064.295499315123, 6075.210222671025, 6466.900626893345, 6469.385408386196, 6555.475561955128, 6792.320767923168, 11640.592579259684, 11866.644000679993, 11972.64082077539, 12000.219985700902, 12048.457819397972, 9655.855162172433, 10537.0, 11702.590409335973, 12085.335306558223, 12172.026237901124, 8720.738377848675, 8895.0, 9089.909100908906, 9170.95414522923, 9198.49408282551, 10103.0, 10243.94878025605, 10318.019788120117, 10344.431056291976, 10460.21548383859, 9029.77425564366, 9072.863907041532, 9081.409708368936, 9125.72239886403, 9182.816343672956, 8753.343499237455, 8818.426802257833, 8891.866622000805, 8897.73306800797, 8931.866022009544, 7609.391248700202, 7713.42149338813, 7733.342665873463, 7803.0, 7832.334251588679, 5790.855228619318, 5832.183494310715, 5899.115132730125, 6009.399060093956, 6044.969775151096, 5866.794662186803, 5895.911561326498, 6012.398760123952, 6068.362821903636, 6136.539759517965], "value": 4986.687510965658, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [373.758925493057, 384.2564637426578, 385.6452064101027, 391.38747859599727, 393.1959143551437, 4814.398200224972, 5218.843434696961, 5676.631018983773, 5811.796587119452, 5998.7000649967495, 4760.738159401231, 5642.009734840531, 5853.5902486825835, 5906.61606995545, 5907.793227237047, 5045.571126454256, 5050.292958985754, 5068.645194836363, 5104.438511763703, 5215.556677682402, 4369.584889435499, 4605.171069207541, 4948.208286674133, 5066.468020857794, 5138.5889128869785, 4096.713230073896, 4311.892202694927, 4628.72710004748, 5015.022070696215, 5085.898282034357, 4660.557247061523, 4859.975700121512, 5109.156989096795, 5119.8992216673405, 5133.101707201225, 3557.4663800429885, 4748.620110391195, 4780.665353425262, 4823.4289395747965, 4824.662273640846, 4416.3375493675885, 5251.501107394792, 5726.856828579277, 5753.539716822645, 5771.595988280822, 5029.7736601853, 5123.0, 5925.755591325827, 5927.199828023205, 5936.614120082181, 5465.371482279549, 5534.916976245359, 5916.112583112567, 6281.335446106739, 6384.52116091295, 5336.893262134774, 5394.568434525228, 5398.46015398457, 5399.298091248152, 5424.783008679648, 5002.774875130628, 6240.87518249637, 6350.936490635081, 6707.3292670732535, 6732.93267067328, 4802.823308289421, 5466.508014278734, 5482.150266708666, 5557.694326811983, 5563.666180029211, 4206.390073439396, 4686.0, 5656.971715141398, 5672.376038635747, 5705.1727499512335, 4820.6143508519235, 5276.261323414725, 5535.750891209906, 5795.82612521625, 5935.703214839241, 5637.068724263442, 6167.352427995085, 6289.654069026247, 6311.368863113744, 6324.3991820776955, 1759.698845475526, 1770.5485101299087, 1792.1755992243454, 1794.47063116381, 1798.1948160869085, 1051.7694297671742, 1110.2616759854768, 1237.418413345726, 1260.1368062876775, 1307.2940612069376, 4780.4741478437345, 5850.531957443394, 5855.970720146457, 5901.29184497863, 5987.401259874065, 4642.535746425398, 6166.105914642441, 6203.348648391944, 6295.527835412271, 6422.875996700652, 4541.115437093636, 5864.354920958691, 5883.058710606368, 5888.175655408247, 5935.459373579215, 3414.4024795661157, 3420.743444241692, 3423.6747508986605, 3444.4661077533024, 3479.443289073736, 6259.0, 6665.533412661067, 6730.192376914802, 6768.424683901825, 6783.38949494538, 5812.779316343531, 6284.591501552384, 6511.218653761579, 6579.0, 6753.49348798842, 5657.575681823797, 6191.411815877575, 6619.073329733841, 6712.247978328862, 6750.729970801113, 6230.937690623217, 6500.187476565429, 6545.738170473318, 6576.046473261446, 6580.1116849224745, 5911.07889935764, 6329.0, 6364.331745166876, 6372.96813515948, 6432.292447830641, 6746.291639377795, 6800.761973330909, 6804.795856124324, 6824.112865327475, 6848.2124555674745, 4472.600823374194, 4872.4153101628035, 4873.902521949471, 5040.470750571138, 5105.5149760772665, 6480.708368123352, 6488.935110649022, 6495.870082598416, 6523.0, 6529.347065293433, 5037.345145131109, 5849.976254155591, 5925.762969481172, 6210.0, 6310.810675679737, 5526.944730552643, 6286.9312216924145, 6309.9588567885785, 6327.936720632735, 6336.17629708135, 5534.0, 5745.735938093612, 6154.9384506154365, 6170.0, 6180.938190618036, 5423.972880135732, 6078.331383547717, 6216.6323408849985, 6264.185655864709, 6273.843153921189, 5096.541311282002, 5142.820001299937, 6127.754889804358, 6145.047517634774, 6209.534284928558], "value": 4831.793312864976, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [731.7694926098279, 745.8359160984584, 773.636390896279, 782.6712780632134, 808.0989696488417, 14906.254687265648, 15700.528984130466, 16128.274227659755, 16144.75782863258, 16166.75749863753, 14636.268186590654, 14820.629484262872, 15063.397464101423, 15090.849091509082, 15220.467283645046, 906.4153620914523, 944.1502647617131, 955.4506158958592, 967.849983252597, 974.6393834281325, 986.1420564109235, 992.4045572656421, 993.0565962335774, 993.6869885985927, 994.174834887042, 1027.3733022856052, 1036.2642523808106, 1036.818556752569, 1037.6835065305079, 1038.3095241664284, 1020.0564477857952, 1025.8666373371452, 1026.4098143567478, 1027.6043723166597, 1032.0556690628066, 758.9620518974084, 759.6961215513784, 760.5398733766048, 761.9466637335389, 765.9195784442664, 13485.460581576725, 16506.75239871403, 16615.504604585647, 16759.497215083567, 16986.83013169877, 14211.21838298893, 14611.63470913225, 14711.264436778118, 14851.554453366416, 15360.078395296317, 11459.656210313704, 11555.711107222387, 11776.764464710657, 11857.94071029648, 11880.584179553674, 3695.0762309422644, 3725.105974566112, 3757.304898593769, 3883.0292426893275, 3921.490206273195, 100196.0, 103465.0, 104284.0, 107330.46334768276, 109999.0, 10056.798864022825, 10623.309484883612, 11508.654740357802, 11599.478023488964, 11690.590829320932, 24710.752892471206, 24731.876340618182, 24971.250862474153, 25021.62467562988, 25037.499250015262, 7764.07250580905, 7787.29914307715, 7922.128565857751, 7974.282314591715, 8145.429819912668, 4122.814473348706, 4243.384709217146, 4244.978775106105, 4279.700420970565, 4280.700350975464, 3289.490129030004, 3381.6111147217894, 3382.475716263983, 3385.8984230473125, 3387.356402283558, 638.4955884851008, 641.0, 646.5926466326184, 663.9601623902581, 667.2493444874516, 592.6532978207797, 625.8924440154653, 650.8047585724264, 658.5686375424104, 687.8349196192878, 9558.856617150748, 10032.79934401308, 10235.539400726986, 10380.948095259475, 10394.740131496774, 659.22541014308, 671.758167059858, 686.6875571614903, 691.7129391302653, 697.6790676288863, 4229.534751177368, 4597.218472859622, 4599.126166028511, 4637.698549594265, 4650.720956742606, 95660.52169739107, 96476.51761741146, 97055.0, 98397.50801245948, 105107.4744626272, 58578.0, 60668.0, 73203.63398183082, 85139.0, 87075.12924870796, 66046.0, 66060.0, 67662.0, 68222.3177768216, 68246.65876670684, 9546.140847323773, 10570.735731606772, 10764.569417223223, 10987.670369888916, 10999.285046471954, 39317.6068239314, 46562.0, 46997.530024699314, 47102.528974709814, 50638.746806267205, 40486.0, 61555.0, 62396.68801655962, 62410.06384904139, 62571.687141564, 13285.126542825084, 13404.530841420503, 13507.000912300155, 13520.053596248168, 14008.64978375508, 14337.426502939765, 14412.063215890934, 14429.278536072945, 14536.0, 14600.196989165697, 12997.805032924325, 13215.339233038118, 13275.800862987257, 13583.52457663977, 13897.513587024405, 12364.381780910739, 12553.121281510572, 12632.621021369374, 13065.542706005244, 13240.867591323964, 11697.532098716296, 13213.801792972921, 13222.140560863512, 13831.100978436369, 13939.303034848013, 10896.734150002128, 11020.944895275472, 11250.60622878228, 11297.575957003453, 11702.88297117052, 8942.86585701228, 10019.799604007732, 10289.485525723834, 10844.891551084704, 11316.490757916079], "value": 7568.363931015352, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1648.9999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 182.0, 178.0, 127.0, 119.0, 120.0, 119.0, 119.0, 119.0, 119.0], "value": 129.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 150.0, 149.0, 197.0, 116.0, 118.0, 117.0, 121.0, 142.0, 107.0], "value": 136.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [209.0, 214.0, 212.0, 212.0, 202.0, 205.0, 198.0, 224.0, 277.0, 203.0], "value": 215.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 60.0, 62.0, 91.0, 60.0, 60.0, 67.0, 76.0, 97.0, 60.0], "value": 71.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 72.0, 94.0, 72.0, 72.0, 71.0, 117.0, 110.0, 106.0, 83.0], "value": 88.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 135.0, 85.0, 137.0, 136.0, 117.0, 96.0, 135.0, 136.0, 90.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 153.0, 97.0, 97.0, 97.0, 127.0, 114.0, 151.0, 135.0, 97.0], "value": 122.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 93.0, 141.0, 142.0, 108.0, 81.0, 146.0, 143.0, 101.0, 144.0], "value": 118.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 69.0, 92.0, 105.0, 105.0, 68.0, 69.0, 68.0, 69.0, 68.0], "value": 78.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 66.0, 58.0, 72.0, 63.0, 62.0, 57.0, 58.0, 59.0, 59.0], "value": 61.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 86.0, 85.0, 84.0, 85.0, 87.0, 84.0, 87.0, 84.0, 87.0], "value": 85.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 158.0, 159.0, 151.0, 135.0, 151.0, 161.0, 153.0, 203.0, 171.0], "value": 160.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 191.0, 184.0, 187.0, 185.0, 189.0, 242.0, 197.0, 190.0, 188.0], "value": 195.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 73.0, 78.0, 70.0, 70.0, 69.0, 67.0, 71.0, 67.0, 72.0], "value": 70.6, "unit": "score"}]}]} 09:36:59 INFO - Return code: 0 09:36:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:36:59 ERROR - # TBPL SUCCESS # 09:36:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:36:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:36:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:36:59 INFO - 2017-03-16 09:36:59.139 screenresolution[1916:12365] starting screenresolution argv=screenresolution get 09:36:59 INFO - 2017-03-16 09:36:59.142 screenresolution[1916:12365] Display 0: 1600x1200x32@60 09:36:59 INFO - 2017-03-16 09:36:59.148 screenresolution[1917:12368] starting screenresolution argv=screenresolution list 09:36:59 INFO - Available Modes on Display 0 09:36:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:36:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:36:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:36:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:36:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:36:59 INFO - Intel Iris: 09:36:59 INFO - Chipset Model: Intel Iris 09:36:59 INFO - Type: GPU 09:36:59 INFO - Bus: Built-In 09:36:59 INFO - VRAM (Dynamic, Max): 1536 MB 09:36:59 INFO - Vendor: Intel (0x8086) 09:36:59 INFO - Device ID: 0x0a2e 09:36:59 INFO - Revision ID: 0x0009 09:36:59 INFO - Displays: 09:36:59 INFO - Display: 09:36:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:36:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:36:59 INFO - Display Serial Number: 1600x1200 60 09:36:59 INFO - Main Display: Yes 09:36:59 INFO - Mirror: Off 09:36:59 INFO - Online: Yes 09:36:59 INFO - Rotation: Supported 09:36:59 INFO - Return code: 0 09:36:59 INFO - Running post-action listener: _resource_record_post_action 09:36:59 INFO - [mozharness: 2017-03-16 16:36:59.381500Z] Finished run-tests step (success) 09:36:59 INFO - Running post-run listener: _resource_record_post_run 09:36:59 INFO - Total resource usage - Wall time: 1200s; CPU: 54.0%; Read bytes: 3106304; Write bytes: 513497600; Read time: 26; Write time: 1603 09:36:59 INFO - TinderboxPrint: CPU usage
54.2% 09:36:59 INFO - TinderboxPrint: I/O read bytes / time
3,106,304 / 26 09:36:59 INFO - TinderboxPrint: I/O write bytes / time
513,497,600 / 1,603 09:36:59 INFO - TinderboxPrint: CPU idle
2,199.7 (45.8%) 09:36:59 INFO - TinderboxPrint: CPU system
965.2 (20.1%) 09:36:59 INFO - TinderboxPrint: CPU user
1,636.7 (34.1%) 09:36:59 INFO - TinderboxPrint: Swap in / out
213,610,496 / 0 09:36:59 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 205459968; Write bytes: 205104640; Read time: 15734; Write time: 431 09:36:59 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 2237952; Write bytes: 298644480; Read time: 22; Write time: 1136 09:36:59 INFO - Running post-run listener: _upload_blobber_files 09:36:59 INFO - Blob upload gear active. 09:36:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:36:59 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:59 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:59 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:37:00 INFO - (blobuploader) - INFO - Open directory for files ... 09:37:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:37:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:37:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:37:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:37:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:37:01 INFO - (blobuploader) - INFO - Done attempting. 09:37:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:37:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:37:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:37:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:37:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:37:02 INFO - (blobuploader) - INFO - Done attempting. 09:37:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:37:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:37:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:37:03 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:37:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:37:03 INFO - (blobuploader) - INFO - Done attempting. 09:37:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:37:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:37:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:37:05 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:37:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:37:05 INFO - (blobuploader) - INFO - Done attempting. 09:37:05 INFO - (blobuploader) - INFO - Iteration through files over. 09:37:05 INFO - Return code: 0 09:37:05 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:37:05 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:37:05 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dced3b8addb87166f3cac379c5b14c3a3b3ca750b7db76b4ce44a9a38d772031135b9462e1c50b7a318ebe53dab4444efc36fb67658d9e58a8147836f7f8c80b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ce9a24bf334213dc900ece297fb81c67877fd883b2ff3ccd682c5ba28124a564aebcb7fb9b8deb29728a3d4ec7aed2c5755c011be39d2a2045207d6ef0acfc43", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e926e5edee173670d214418a7dc688dbfc5a7f51c2df9bca192ab98fcb165beb7583c8f5eb6f8ef8b5d86177d70a178adae367415e2be346b4d4350fbdda8ee6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0e0f67bbfd0712983c93f7aab6b9fb5d86b64888613e50d8898c6a287b95523dc6b990251431c32993baf99c9c94d7e89c4a90b0395328762a952abea1a5c9b5"} 09:37:05 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:37:05 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:37:05 INFO - Contents: 09:37:05 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dced3b8addb87166f3cac379c5b14c3a3b3ca750b7db76b4ce44a9a38d772031135b9462e1c50b7a318ebe53dab4444efc36fb67658d9e58a8147836f7f8c80b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ce9a24bf334213dc900ece297fb81c67877fd883b2ff3ccd682c5ba28124a564aebcb7fb9b8deb29728a3d4ec7aed2c5755c011be39d2a2045207d6ef0acfc43", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e926e5edee173670d214418a7dc688dbfc5a7f51c2df9bca192ab98fcb165beb7583c8f5eb6f8ef8b5d86177d70a178adae367415e2be346b4d4350fbdda8ee6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0e0f67bbfd0712983c93f7aab6b9fb5d86b64888613e50d8898c6a287b95523dc6b990251431c32993baf99c9c94d7e89c4a90b0395328762a952abea1a5c9b5"} 09:37:05 INFO - Running post-run listener: copy_logs_to_upload_dir 09:37:05 INFO - Copying logs to upload dir... 09:37:05 INFO - mkdir: /builds/slave/test/build/upload/logs 09:37:05 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1281.806137 ========= master_lag: 6.08 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2017-03-16 09:37:11.669747) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2017-03-16 09:37:11.673737) ========= 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.nO1nZ5OIbD/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.IRdIXnM9Ji/Listeners TMPDIR=/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dced3b8addb87166f3cac379c5b14c3a3b3ca750b7db76b4ce44a9a38d772031135b9462e1c50b7a318ebe53dab4444efc36fb67658d9e58a8147836f7f8c80b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ce9a24bf334213dc900ece297fb81c67877fd883b2ff3ccd682c5ba28124a564aebcb7fb9b8deb29728a3d4ec7aed2c5755c011be39d2a2045207d6ef0acfc43", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e926e5edee173670d214418a7dc688dbfc5a7f51c2df9bca192ab98fcb165beb7583c8f5eb6f8ef8b5d86177d70a178adae367415e2be346b4d4350fbdda8ee6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0e0f67bbfd0712983c93f7aab6b9fb5d86b64888613e50d8898c6a287b95523dc6b990251431c32993baf99c9c94d7e89c4a90b0395328762a952abea1a5c9b5"} 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.011316 build_url: 'https://queue.taskcluster.net/v1/task/Q_ukGa7XTaiLKPXZIhopOw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dced3b8addb87166f3cac379c5b14c3a3b3ca750b7db76b4ce44a9a38d772031135b9462e1c50b7a318ebe53dab4444efc36fb67658d9e58a8147836f7f8c80b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ce9a24bf334213dc900ece297fb81c67877fd883b2ff3ccd682c5ba28124a564aebcb7fb9b8deb29728a3d4ec7aed2c5755c011be39d2a2045207d6ef0acfc43", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e926e5edee173670d214418a7dc688dbfc5a7f51c2df9bca192ab98fcb165beb7583c8f5eb6f8ef8b5d86177d70a178adae367415e2be346b4d4350fbdda8ee6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0e0f67bbfd0712983c93f7aab6b9fb5d86b64888613e50d8898c6a287b95523dc6b990251431c32993baf99c9c94d7e89c4a90b0395328762a952abea1a5c9b5"}' ========= master_lag: 1.10 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2017-03-16 09:37:12.785460) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-16 09:37:12.785781) ========= 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.nO1nZ5OIbD/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.IRdIXnM9Ji/Listeners TMPDIR=/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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.005270 ========= master_lag: 0.76 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-16 09:37:13.547919) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-16 09:37:13.548260) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-16 09:37:13.548895) ========= ========= Total master_lag: 8.01 =========