builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0081 starttime: 1490009694.15 results: success (0) buildid: 20170320030209 builduid: 011dc9fd643d45f5989c80ac3ffb6b80 revision: 1b9293be51637f841275541d8991314ca56561a5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-20 04:34:54.146607) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-20 04:34:54.147006) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 1 secs) (at 2017-03-20 04:34:54.147298) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 1 secs) (at 2017-03-20 04:34:55.185643) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 04:34:55.186546) ========= 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.0lrExIFjWJ/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.3JUJ3O9Jln/Listeners TMPDIR=/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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-20 04:34:55-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 32.1M=0s 2017-03-20 04:34:55 (32.1 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.120936 ========= master_lag: 0.37 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 04:34:55.677810) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 04:34:55.678123) ========= 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.0lrExIFjWJ/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.3JUJ3O9Jln/Listeners TMPDIR=/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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.027854 ========= master_lag: 0.53 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 04:34:56.238119) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 10 secs) (at 2017-03-20 04:34:56.238466) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 1b9293be51637f841275541d8991314ca56561a5 --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 1b9293be51637f841275541d8991314ca56561a5 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.0lrExIFjWJ/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.3JUJ3O9Jln/Listeners TMPDIR=/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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-20 04:34:56,475 truncating revision to first 12 chars 2017-03-20 04:34:56,475 Setting DEBUG logging. 2017-03-20 04:34:56,475 attempt 1/10 2017-03-20 04:34:56,475 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/1b9293be5163?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-20 04:35:01,439 unpacking tar archive at: mozilla-central-1b9293be5163/testing/mozharness/ program finished with exit code 0 elapsedTime=9.888388 ========= master_lag: 0.28 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 10 secs) (at 2017-03-20 04:35:06.408108) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-20 04:35:06.408447) ========= script_repo_revision: 1b9293be51637f841275541d8991314ca56561a5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-20 04:35:06.408848) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 1 secs) (at 2017-03-20 04:35:06.409112) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 1 secs) (at 2017-03-20 04:35:07.485730) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 44 secs) (at 2017-03-20 04:35:07.486121) ========= /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.0lrExIFjWJ/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.3JUJ3O9Jln/Listeners TMPDIR=/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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 04:35:07 INFO - MultiFileLogger online at 20170320 04:35:07 in /builds/slave/test 04:35:07 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 04:35:07 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:35:07 INFO - {'append_to_log': False, 04:35:07 INFO - 'base_work_dir': '/builds/slave/test', 04:35:07 INFO - 'blob_upload_branch': 'Firefox', 04:35:07 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:35:07 INFO - 'branch': 'Firefox', 04:35:07 INFO - 'buildbot_json_path': 'buildprops.json', 04:35:07 INFO - 'config_files': ('talos/mac_config.py',), 04:35:07 INFO - 'default_actions': ('clobber', 04:35:07 INFO - 'read-buildbot-config', 04:35:07 INFO - 'download-and-extract', 04:35:07 INFO - 'populate-webroot', 04:35:07 INFO - 'create-virtualenv', 04:35:07 INFO - 'install', 04:35:07 INFO - 'run-tests'), 04:35:07 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:35:07 INFO - 'download_minidump_stackwalk': True, 04:35:07 INFO - 'download_symbols': 'ondemand', 04:35:07 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:35:07 INFO - 'tooltool.py': '/tools/tooltool.py', 04:35:07 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:35:07 INFO - '/tools/misc-python/virtualenv.py')}, 04:35:07 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:35:07 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:35:07 INFO - 'gecko_profile': False, 04:35:07 INFO - 'gecko_profile_interval': 0, 04:35:07 INFO - 'installer_path': 'installer.exe', 04:35:07 INFO - 'log_level': 'info', 04:35:07 INFO - 'log_name': 'talos', 04:35:07 INFO - 'log_to_console': True, 04:35:07 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:35:07 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:35:07 INFO - 'opt_config_files': (), 04:35:07 INFO - 'pip_index': False, 04:35:07 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:35:07 INFO - 'cmd': ('bash', 04:35:07 INFO - '-c', 04:35:07 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:35:07 INFO - 'enabled': True, 04:35:07 INFO - 'halt_on_failure': False, 04:35:07 INFO - 'name': 'check_screen_resolution'},), 04:35:07 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:35:07 INFO - 'cmd': ('bash', 04:35:07 INFO - '-c', 04:35:07 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:35:07 INFO - 'enabled': True, 04:35:07 INFO - 'halt_on_failure': False, 04:35:07 INFO - 'name': 'check_screen_resolution'},), 04:35:07 INFO - 'run_cmd_checks_enabled': True, 04:35:07 INFO - 'suite': 'dromaeojs-e10s', 04:35:07 INFO - 'system_bits': '32', 04:35:07 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:35:07 INFO - 'title': 't-yosemite-r7-0081', 04:35:07 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:35:07 INFO - 'use_talos_json': True, 04:35:07 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:35:07 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:35:07 INFO - 'work_dir': 'build'} 04:35:07 INFO - [mozharness: 2017-03-20 11:35:07.841501Z] Running clobber step. 04:35:07 INFO - Running pre-action listener: _resource_record_pre_action 04:35:07 INFO - Running main action method: clobber 04:35:07 INFO - rmtree: /builds/slave/test/build 04:35:07 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:35:10 INFO - Running post-action listener: _resource_record_post_action 04:35:10 INFO - [mozharness: 2017-03-20 11:35:10.466333Z] Finished clobber step (success) 04:35:10 INFO - [mozharness: 2017-03-20 11:35:10.466487Z] Running read-buildbot-config step. 04:35:10 INFO - Running pre-action listener: _resource_record_pre_action 04:35:10 INFO - Running main action method: read_buildbot_config 04:35:10 INFO - Using buildbot properties: 04:35:10 INFO - { 04:35:10 INFO - "project": "", 04:35:10 INFO - "product": "firefox", 04:35:10 INFO - "script_repo_revision": "production", 04:35:10 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 04:35:10 INFO - "repository": "", 04:35:10 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 04:35:10 INFO - "buildid": "20170320030209", 04:35:10 INFO - "stage_platform": "macosx64", 04:35:10 INFO - "basedir": "/builds/slave/test", 04:35:10 INFO - "nightly_build": "True", 04:35:10 INFO - "buildnumber": 21, 04:35:10 INFO - "slavename": "t-yosemite-r7-0081", 04:35:10 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 04:35:10 INFO - "platform": "yosemite_r7", 04:35:10 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 04:35:10 INFO - "branch": "mozilla-central", 04:35:10 INFO - "repo_path": "mozilla-central", 04:35:10 INFO - "revision": "1b9293be51637f841275541d8991314ca56561a5", 04:35:10 INFO - "pgo_build": "False", 04:35:10 INFO - "builduid": "011dc9fd643d45f5989c80ac3ffb6b80", 04:35:10 INFO - "slavebuilddir": "test" 04:35:10 INFO - } 04:35:10 INFO - Found installer url https://queue.taskcluster.net/v1/task/cu0MMLqFTw-tkoKsZavL5Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 04:35:10 INFO - Running post-action listener: _resource_record_post_action 04:35:10 INFO - [mozharness: 2017-03-20 11:35:10.470169Z] Finished read-buildbot-config step (success) 04:35:10 INFO - [mozharness: 2017-03-20 11:35:10.470306Z] Running download-and-extract step. 04:35:10 INFO - Running pre-action listener: _resource_record_pre_action 04:35:10 INFO - Running main action method: download_and_extract 04:35:10 INFO - mkdir: /builds/slave/test/build/tests 04:35:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:35:10 INFO - trying https://queue.taskcluster.net/v1/task/cu0MMLqFTw-tkoKsZavL5Q/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 04:35:10 INFO - Downloading https://queue.taskcluster.net/v1/task/cu0MMLqFTw-tkoKsZavL5Q/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 04:35:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cu0MMLqFTw-tkoKsZavL5Q/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 04:35:13 INFO - Downloaded 1325 bytes. 04:35:13 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 04:35:13 INFO - Using the following test package requirements: 04:35:13 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:35:13 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 04:35:13 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 04:35:13 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:35:13 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 04:35:13 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:35:13 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 04:35:13 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:35:13 INFO - u'jsshell-mac.zip'], 04:35:13 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:35:13 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 04:35:13 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 04:35:13 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:35:13 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 04:35:13 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:35:13 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 04:35:13 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:35:13 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 04:35:13 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:35:13 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 04:35:13 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:35:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cu0MMLqFTw-tkoKsZavL5Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 04:35:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cu0MMLqFTw-tkoKsZavL5Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:35:13 INFO - Fetch https://queue.taskcluster.net/v1/task/cu0MMLqFTw-tkoKsZavL5Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 04:35:14 INFO - Expected file size: 52473169 04:35:14 INFO - Obtained file size: 52473169 04:35:31 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 04:35:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cu0MMLqFTw-tkoKsZavL5Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 04:35:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cu0MMLqFTw-tkoKsZavL5Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:35:31 INFO - Fetch https://queue.taskcluster.net/v1/task/cu0MMLqFTw-tkoKsZavL5Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 04:35:33 INFO - Expected file size: 52473169 04:35:33 INFO - Obtained file size: 52473169 04:35:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cu0MMLqFTw-tkoKsZavL5Q/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 04:35:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cu0MMLqFTw-tkoKsZavL5Q/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:35:48 INFO - Fetch https://queue.taskcluster.net/v1/task/cu0MMLqFTw-tkoKsZavL5Q/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 04:35:50 INFO - Expected file size: 14404171 04:35:50 INFO - Obtained file size: 14404171 04:35:50 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')]} 04:35:50 INFO - trying https://queue.taskcluster.net/v1/task/cu0MMLqFTw-tkoKsZavL5Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:35:50 INFO - Downloading https://queue.taskcluster.net/v1/task/cu0MMLqFTw-tkoKsZavL5Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 04:35:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cu0MMLqFTw-tkoKsZavL5Q/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 04:35:54 INFO - Downloaded 75260959 bytes. 04:35:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cu0MMLqFTw-tkoKsZavL5Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:35:54 INFO - mkdir: /builds/slave/test/properties 04:35:54 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:35:54 INFO - Writing to file /builds/slave/test/properties/build_url 04:35:54 INFO - Contents: 04:35:54 INFO - build_url:https://queue.taskcluster.net/v1/task/cu0MMLqFTw-tkoKsZavL5Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:35:54 INFO - Running post-action listener: _resource_record_post_action 04:35:54 INFO - Running post-action listener: set_extra_try_arguments 04:35:54 INFO - [mozharness: 2017-03-20 11:35:54.925456Z] Finished download-and-extract step (success) 04:35:54 INFO - [mozharness: 2017-03-20 11:35:54.925601Z] Running populate-webroot step. 04:35:54 INFO - Running pre-action listener: _resource_record_pre_action 04:35:54 INFO - Running main action method: populate_webroot 04:35:54 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:35:54 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:35:54 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:35:54 INFO - '--tppagecycles', 04:35:54 INFO - '7'], 04:35:54 INFO - 'tests': ['tsvgm']}}, 04:35:54 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:35:54 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:35:54 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:35:54 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:35:54 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:35:54 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:35:54 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:35:54 INFO - 'talos_options': ['--disable-e10s'], 04:35:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:35:54 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:35:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:35:54 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:35:54 INFO - 'talos_options': ['--disable-e10s'], 04:35:54 INFO - 'tests': ['damp', 'tps']}, 04:35:54 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:35:54 INFO - 'tests': ['damp', 'tps']}, 04:35:54 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:35:54 INFO - 'tests': ['dromaeo_dom']}, 04:35:54 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:35:54 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:35:54 INFO - 'tests': ['basic_compositor_video']}, 04:35:54 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:35:54 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:35:54 INFO - 'tests': ['a11yr', 04:35:54 INFO - 'ts_paint', 04:35:54 INFO - 'tpaint', 04:35:54 INFO - 'sessionrestore', 04:35:54 INFO - 'sessionrestore_no_auto_restore', 04:35:54 INFO - 'tabpaint']}, 04:35:54 INFO - 'other-e10s': {'tests': ['a11yr', 04:35:54 INFO - 'ts_paint', 04:35:54 INFO - 'tpaint', 04:35:54 INFO - 'sessionrestore', 04:35:54 INFO - 'sessionrestore_no_auto_restore', 04:35:54 INFO - 'tabpaint']}, 04:35:54 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:35:54 INFO - 'ts_paint', 04:35:54 INFO - 'tpaint', 04:35:54 INFO - 'sessionrestore', 04:35:54 INFO - 'sessionrestore_no_auto_restore', 04:35:54 INFO - 'tabpaint']}, 04:35:54 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:35:54 INFO - 'ts_paint', 04:35:54 INFO - 'tpaint', 04:35:54 INFO - 'sessionrestore', 04:35:54 INFO - 'sessionrestore_no_auto_restore', 04:35:54 INFO - 'tabpaint']}, 04:35:54 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:35:54 INFO - 'tests': ['a11yr', 04:35:54 INFO - 'ts_paint', 04:35:54 INFO - 'tpaint', 04:35:54 INFO - 'sessionrestore', 04:35:54 INFO - 'sessionrestore_no_auto_restore', 04:35:54 INFO - 'tabpaint']}, 04:35:54 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:35:54 INFO - 'tests': ['a11yr', 04:35:54 INFO - 'ts_paint', 04:35:54 INFO - 'tpaint', 04:35:54 INFO - 'sessionrestore', 04:35:54 INFO - 'sessionrestore_no_auto_restore', 04:35:54 INFO - 'tabpaint']}, 04:35:54 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:35:54 INFO - 'tests': ['tsvgx', 04:35:54 INFO - 'tsvgr_opacity', 04:35:54 INFO - 'tart', 04:35:54 INFO - 'tscrollx', 04:35:54 INFO - 'cart', 04:35:54 INFO - 'tsvg_static']}, 04:35:54 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:35:54 INFO - 'tsvgr_opacity', 04:35:54 INFO - 'tart', 04:35:54 INFO - 'tscrollx', 04:35:54 INFO - 'cart', 04:35:54 INFO - 'tsvg_static']}, 04:35:54 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:35:54 INFO - 'talos_options': ['--disable-e10s'], 04:35:54 INFO - 'tests': ['tp5o']}, 04:35:54 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:35:54 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:35:54 INFO - 'talos_options': ['--disable-e10s', 04:35:54 INFO - '--xperf_path', 04:35:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:35:54 INFO - 'tests': ['tp5n']}, 04:35:54 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:35:54 INFO - 'talos_options': ['--xperf_path', 04:35:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:35:54 INFO - 'tests': ['tp5n']}}, 04:35:54 INFO - 'talos.zip': {'path': '', 04:35:54 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:35:54 INFO - Running post-action listener: _resource_record_post_action 04:35:54 INFO - [mozharness: 2017-03-20 11:35:54.944535Z] Finished populate-webroot step (success) 04:35:54 INFO - [mozharness: 2017-03-20 11:35:54.944647Z] Running create-virtualenv step. 04:35:54 INFO - Running pre-action listener: _resource_record_pre_action 04:35:54 INFO - Running main action method: create_virtualenv 04:35:54 INFO - Creating virtualenv /builds/slave/test/build/venv 04:35:54 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 04:35:54 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:35:55 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:35:55 INFO - Using real prefix '/tools/python27' 04:35:55 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:35:55 INFO - Installing distribute.............................................................................................................................................................................................done. 04:35:58 INFO - Installing pip.................done. 04:35:58 INFO - Return code: 0 04:35:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:35:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:35:58 INFO - Reading from file tmpfile_stdout 04:35:58 INFO - Output received: 04:35:58 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) 04:35:58 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:35:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:35:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:35:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:35:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:35:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:35:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:36:00 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 0x10e846b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e849768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb2e1d6c680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e624d30>, '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 0x10e54bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e619be8>, '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.0lrExIFjWJ/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.3JUJ3O9Jln/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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 04:36:00 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 04:36:00 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 04:36:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0lrExIFjWJ/Render', 04:36:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:36:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:36:00 INFO - 'HOME': '/Users/cltbld', 04:36:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:36:00 INFO - 'LOGNAME': 'cltbld', 04:36:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:36:00 INFO - 'MOZ_NO_REMOTE': '1', 04:36:00 INFO - 'NO_EM_RESTART': '1', 04:36:00 INFO - 'PAGER': '/bin/cat', 04:36:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:36:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:36:00 INFO - 'PWD': '/builds/slave/test', 04:36:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:36:00 INFO - 'SHELL': '/bin/bash', 04:36:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3JUJ3O9Jln/Listeners', 04:36:00 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 04:36:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:36:00 INFO - 'USER': 'cltbld', 04:36:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:36:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:36:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:36:00 INFO - 'XPC_FLAGS': '0x0', 04:36:00 INFO - 'XPC_SERVICE_NAME': '0', 04:36:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:36:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:36:00 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 04:36:00 INFO - Cleaning up... 04:36:00 INFO - Return code: 0 04:36:00 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:36:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:36:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:36:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:36:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:36:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:36:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:36:00 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 0x10e846b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e849768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb2e1d6c680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e624d30>, '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 0x10e54bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e619be8>, '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.0lrExIFjWJ/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.3JUJ3O9Jln/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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 04:36:00 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 04:36:00 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 04:36:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0lrExIFjWJ/Render', 04:36:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:36:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:36:00 INFO - 'HOME': '/Users/cltbld', 04:36:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:36:00 INFO - 'LOGNAME': 'cltbld', 04:36:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:36:00 INFO - 'MOZ_NO_REMOTE': '1', 04:36:00 INFO - 'NO_EM_RESTART': '1', 04:36:00 INFO - 'PAGER': '/bin/cat', 04:36:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:36:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:36:00 INFO - 'PWD': '/builds/slave/test', 04:36:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:36:00 INFO - 'SHELL': '/bin/bash', 04:36:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3JUJ3O9Jln/Listeners', 04:36:00 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 04:36:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:36:00 INFO - 'USER': 'cltbld', 04:36:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:36:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:36:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:36:00 INFO - 'XPC_FLAGS': '0x0', 04:36:00 INFO - 'XPC_SERVICE_NAME': '0', 04:36:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:36:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:36:01 INFO - Downloading/unpacking psutil>=3.1.1 04:36:01 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 04:36:01 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 04:36:01 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 04:36:01 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 04:36:01 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 04:36:01 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 04:36:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:36:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:36:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:36:08 INFO - Installing collected packages: psutil 04:36:08 INFO - Running setup.py install for psutil 04:36:08 INFO - building 'psutil._psutil_osx' extension 04:36:08 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 04:36:08 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 04:36:08 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 04:36:08 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 04:36:09 INFO - building 'psutil._psutil_posix' extension 04:36:09 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 04:36:09 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:36:09 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:36:09 INFO - ^ 04:36:09 INFO - 1 warning generated. 04:36:09 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 04:36:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:36:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:36:09 INFO - Successfully installed psutil 04:36:09 INFO - Cleaning up... 04:36:09 INFO - Return code: 0 04:36:09 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:36:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:36:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:36:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:36:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:36:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:36:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:36:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e846b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e849768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb2e1d6c680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e624d30>, '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 0x10e54bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e619be8>, '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.0lrExIFjWJ/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.3JUJ3O9Jln/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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 04:36:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:36:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 04:36:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0lrExIFjWJ/Render', 04:36:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:36:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:36:09 INFO - 'HOME': '/Users/cltbld', 04:36:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:36:09 INFO - 'LOGNAME': 'cltbld', 04:36:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:36:09 INFO - 'MOZ_NO_REMOTE': '1', 04:36:09 INFO - 'NO_EM_RESTART': '1', 04:36:09 INFO - 'PAGER': '/bin/cat', 04:36:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:36:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:36:09 INFO - 'PWD': '/builds/slave/test', 04:36:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:36:09 INFO - 'SHELL': '/bin/bash', 04:36:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3JUJ3O9Jln/Listeners', 04:36:09 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 04:36:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:36:09 INFO - 'USER': 'cltbld', 04:36:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:36:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:36:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:36:09 INFO - 'XPC_FLAGS': '0x0', 04:36:09 INFO - 'XPC_SERVICE_NAME': '0', 04:36:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:36:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:36:09 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:36:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:36:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:36:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:36:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:36:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:36:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:36:12 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:36:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:36:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:36:12 INFO - Installing collected packages: mozsystemmonitor 04:36:12 INFO - Running setup.py install for mozsystemmonitor 04:36:12 INFO - Successfully installed mozsystemmonitor 04:36:12 INFO - Cleaning up... 04:36:12 INFO - Return code: 0 04:36:12 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:36:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:36:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:36:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:36:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:36:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:36:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:36:12 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 0x10e846b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e849768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb2e1d6c680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e624d30>, '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 0x10e54bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e619be8>, '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.0lrExIFjWJ/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.3JUJ3O9Jln/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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 04:36:12 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 04:36:12 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 04:36:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0lrExIFjWJ/Render', 04:36:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:36:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:36:12 INFO - 'HOME': '/Users/cltbld', 04:36:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:36:12 INFO - 'LOGNAME': 'cltbld', 04:36:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:36:12 INFO - 'MOZ_NO_REMOTE': '1', 04:36:12 INFO - 'NO_EM_RESTART': '1', 04:36:12 INFO - 'PAGER': '/bin/cat', 04:36:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:36:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:36:12 INFO - 'PWD': '/builds/slave/test', 04:36:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:36:12 INFO - 'SHELL': '/bin/bash', 04:36:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3JUJ3O9Jln/Listeners', 04:36:12 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 04:36:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:36:12 INFO - 'USER': 'cltbld', 04:36:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:36:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:36:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:36:12 INFO - 'XPC_FLAGS': '0x0', 04:36:12 INFO - 'XPC_SERVICE_NAME': '0', 04:36:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:36:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:36:12 INFO - Downloading/unpacking jsonschema==2.5.1 04:36:12 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 04:36:12 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 04:36:12 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 04:36:12 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 04:36:12 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 04:36:12 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 04:36:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:36:22 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:36:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:36:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:36:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:36:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:36:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:36:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:36:23 INFO - Downloading functools32-3.2.3-2.tar.gz 04:36:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:36:23 INFO - warning: no files found matching '*.txt' 04:36:23 INFO - no previously-included directories found matching 'build' 04:36:23 INFO - no previously-included directories found matching 'dist' 04:36:23 INFO - no previously-included directories found matching '.git*' 04:36:23 INFO - Installing collected packages: jsonschema, functools32 04:36:23 INFO - Running setup.py install for functools32 04:36:23 INFO - warning: no files found matching '*.txt' 04:36:23 INFO - no previously-included directories found matching 'build' 04:36:23 INFO - no previously-included directories found matching 'dist' 04:36:23 INFO - no previously-included directories found matching '.git*' 04:36:23 INFO - Successfully installed jsonschema functools32 04:36:23 INFO - Cleaning up... 04:36:23 INFO - Return code: 0 04:36:23 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:36:23 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')]} 04:36:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:36:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:36:23 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')]} 04:36:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:36:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:36:23 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 0x10e846b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e849768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb2e1d6c680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e624d30>, '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 0x10e54bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e619be8>, '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.0lrExIFjWJ/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.3JUJ3O9Jln/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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 04:36:23 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 04:36:23 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 04:36:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0lrExIFjWJ/Render', 04:36:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:36:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:36:23 INFO - 'HOME': '/Users/cltbld', 04:36:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:36:23 INFO - 'LOGNAME': 'cltbld', 04:36:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:36:23 INFO - 'MOZ_NO_REMOTE': '1', 04:36:23 INFO - 'NO_EM_RESTART': '1', 04:36:23 INFO - 'PAGER': '/bin/cat', 04:36:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:36:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:36:23 INFO - 'PWD': '/builds/slave/test', 04:36:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:36:23 INFO - 'SHELL': '/bin/bash', 04:36:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3JUJ3O9Jln/Listeners', 04:36:23 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 04:36:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:36:23 INFO - 'USER': 'cltbld', 04:36:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:36:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:36:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:36:23 INFO - 'XPC_FLAGS': '0x0', 04:36:23 INFO - 'XPC_SERVICE_NAME': '0', 04:36:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:36:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:36:23 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:36:23 INFO - Cleaning up... 04:36:23 INFO - Return code: 0 04:36:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:36:23 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')]} 04:36:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:36:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:36:23 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')]} 04:36:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:36:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:36:23 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 0x10e846b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e849768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb2e1d6c680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e624d30>, '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 0x10e54bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e619be8>, '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.0lrExIFjWJ/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.3JUJ3O9Jln/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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 04:36:23 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 04:36:23 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 04:36:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0lrExIFjWJ/Render', 04:36:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:36:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:36:23 INFO - 'HOME': '/Users/cltbld', 04:36:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:36:23 INFO - 'LOGNAME': 'cltbld', 04:36:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:36:23 INFO - 'MOZ_NO_REMOTE': '1', 04:36:23 INFO - 'NO_EM_RESTART': '1', 04:36:23 INFO - 'PAGER': '/bin/cat', 04:36:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:36:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:36:23 INFO - 'PWD': '/builds/slave/test', 04:36:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:36:23 INFO - 'SHELL': '/bin/bash', 04:36:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3JUJ3O9Jln/Listeners', 04:36:23 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 04:36:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:36:23 INFO - 'USER': 'cltbld', 04:36:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:36:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:36:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:36:23 INFO - 'XPC_FLAGS': '0x0', 04:36:23 INFO - 'XPC_SERVICE_NAME': '0', 04:36:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:36:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:36:24 INFO - Downloading/unpacking blobuploader==1.2.4 04:36:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:36:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:36:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:36:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:36:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:36:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:36:29 INFO - Downloading blobuploader-1.2.4.tar.gz 04:36:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:36:29 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:36: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 04:36: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 04:36: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 04:36: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 04:36: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 04:36: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 04:36:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:36:32 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:36:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:36:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:36:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:36:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:36:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:36:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:36:33 INFO - Downloading docopt-0.6.1.tar.gz 04:36:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:36:33 INFO - Installing collected packages: blobuploader, requests, docopt 04:36:33 INFO - Running setup.py install for blobuploader 04:36:33 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:36:33 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:36:33 INFO - Running setup.py install for requests 04:36:34 INFO - Running setup.py install for docopt 04:36:34 INFO - Successfully installed blobuploader requests docopt 04:36:34 INFO - Cleaning up... 04:36:34 INFO - Return code: 0 04:36:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:36: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')]} 04:36:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:36:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:36: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')]} 04:36:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:36:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:36:34 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 0x10e846b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e849768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb2e1d6c680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e624d30>, '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 0x10e54bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e619be8>, '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.0lrExIFjWJ/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.3JUJ3O9Jln/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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 04:36:34 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 04:36:34 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 04:36:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0lrExIFjWJ/Render', 04:36:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:36:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:36:34 INFO - 'HOME': '/Users/cltbld', 04:36:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:36:34 INFO - 'LOGNAME': 'cltbld', 04:36:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:36:34 INFO - 'MOZ_NO_REMOTE': '1', 04:36:34 INFO - 'NO_EM_RESTART': '1', 04:36:34 INFO - 'PAGER': '/bin/cat', 04:36:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:36:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:36:34 INFO - 'PWD': '/builds/slave/test', 04:36:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:36:34 INFO - 'SHELL': '/bin/bash', 04:36:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3JUJ3O9Jln/Listeners', 04:36:34 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 04:36:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:36:34 INFO - 'USER': 'cltbld', 04:36:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:36:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:36:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:36:34 INFO - 'XPC_FLAGS': '0x0', 04:36:34 INFO - 'XPC_SERVICE_NAME': '0', 04:36:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:36:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:36:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:36:34 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-wcTOzE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:36:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:36:35 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-oRKHwU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:36:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:36:35 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-ku4vnl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:36:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:36:35 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-UFN3cu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:36:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:36:35 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-53AHci-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:36:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:36:35 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-TXs9BX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:36:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:36:35 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-tvlIzz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:36:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:36:35 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-qrUDqf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:36:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:36:35 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-2AluDe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:36:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:36:35 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-OFmzT4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:36:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:36:35 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-kbl1vs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:36:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:36:36 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-3RaRkb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:36:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:36:36 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-Ylq0EX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:36:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:36:36 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-O8s50u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:36:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:36:36 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-FurrHo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:36:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:36:36 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-GsFXLf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:36:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:36:36 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-YCNG_k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:36:36 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:36:36 INFO - Running setup.py install for manifestparser 04:36:36 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:36:36 INFO - Running setup.py install for mozcrash 04:36:37 INFO - Running setup.py install for mozdebug 04:36:37 INFO - Running setup.py install for mozdevice 04:36:37 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:36:37 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:36:37 INFO - Running setup.py install for mozfile 04:36:37 INFO - Running setup.py install for mozhttpd 04:36:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:36:37 INFO - Running setup.py install for mozinfo 04:36:37 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:36:37 INFO - Running setup.py install for mozInstall 04:36:37 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:36:37 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:36:37 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:36:37 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:36:37 INFO - Running setup.py install for mozleak 04:36:38 INFO - Running setup.py install for mozlog 04:36:38 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:36:38 INFO - Running setup.py install for moznetwork 04:36:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:36:38 INFO - Running setup.py install for mozprocess 04:36:38 INFO - Running setup.py install for mozprofile 04:36:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:36:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:36:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:36:38 INFO - Running setup.py install for mozrunner 04:36:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:36:38 INFO - Running setup.py install for mozscreenshot 04:36:38 INFO - Running setup.py install for moztest 04:36:39 INFO - Running setup.py install for mozversion 04:36:39 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:36:39 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:36:39 INFO - Cleaning up... 04:36:39 INFO - Return code: 0 04:36:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:36:39 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')]} 04:36:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:36:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:36:39 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')]} 04:36:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:36:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:36:39 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 0x10e846b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e849768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb2e1d6c680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e624d30>, '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 0x10e54bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e619be8>, '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.0lrExIFjWJ/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.3JUJ3O9Jln/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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 04:36:39 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 04:36:39 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 04:36:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0lrExIFjWJ/Render', 04:36:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:36:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:36:39 INFO - 'HOME': '/Users/cltbld', 04:36:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:36:39 INFO - 'LOGNAME': 'cltbld', 04:36:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:36:39 INFO - 'MOZ_NO_REMOTE': '1', 04:36:39 INFO - 'NO_EM_RESTART': '1', 04:36:39 INFO - 'PAGER': '/bin/cat', 04:36:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:36:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:36:39 INFO - 'PWD': '/builds/slave/test', 04:36:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:36:39 INFO - 'SHELL': '/bin/bash', 04:36:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3JUJ3O9Jln/Listeners', 04:36:39 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 04:36:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:36:39 INFO - 'USER': 'cltbld', 04:36:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:36:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:36:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:36:39 INFO - 'XPC_FLAGS': '0x0', 04:36:39 INFO - 'XPC_SERVICE_NAME': '0', 04:36:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:36:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:36:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:36:39 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-oxv_kx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:36:39 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)) 04:36:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:36:39 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-zhLGvu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:36:39 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)) 04:36:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:36:39 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-PoiKJx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:36:39 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)) 04:36:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:36:39 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-UfwElX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:36:40 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)) 04:36:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:36:40 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-jNBvOK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:36:40 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)) 04:36:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:36:40 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-7t3s6X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:36:40 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)) 04:36:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:36:40 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-JE3pTL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:36:40 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)) 04:36:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:36:40 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-nxEf2z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:36:40 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)) 04:36:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:36:40 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-kdf2RA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:36:40 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)) 04:36:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:36:40 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-NsvGGI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:36:40 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)) 04:36:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:36:40 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-p6fqZu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:36:40 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)) 04:36:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:36:40 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-vR6sY0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:36:40 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)) 04:36:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:36:40 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-WH6Ynb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:36:40 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)) 04:36:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:36:40 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-21S40l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:36:41 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)) 04:36:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:36:41 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-jHjWzi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:36:41 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)) 04:36:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:36:41 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-kqyNrK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:36:41 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)) 04:36:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:36:41 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-aFR1Pa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:36:41 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)) 04:36:41 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)) 04:36:41 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)) 04:36:41 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)) 04:36:41 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)) 04:36:41 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)) 04:36:41 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:36: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 04:36: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 04:36: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 04:36: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 04:36: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 04:36: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 04:36:44 INFO - Downloading blessings-1.6.tar.gz 04:36:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:36:44 INFO - Installing collected packages: blessings 04:36:44 INFO - Running setup.py install for blessings 04:36:44 INFO - Successfully installed blessings 04:36:44 INFO - Cleaning up... 04:36:44 INFO - Return code: 0 04:36:44 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:36:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:36:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:36:44 INFO - Reading from file tmpfile_stdout 04:36:44 INFO - Current package versions: 04:36:44 INFO - blessings == 1.6 04:36:44 INFO - blobuploader == 1.2.4 04:36:44 INFO - docopt == 0.6.1 04:36:44 INFO - functools32 == 3.2.3-2 04:36:44 INFO - jsonschema == 2.5.1 04:36:44 INFO - manifestparser == 1.1 04:36:44 INFO - mozInstall == 1.12 04:36:44 INFO - mozcrash == 1.0 04:36:44 INFO - mozdebug == 0.1 04:36:44 INFO - mozdevice == 0.49 04:36:44 INFO - mozfile == 1.2 04:36:44 INFO - mozhttpd == 0.7 04:36:44 INFO - mozinfo == 0.9 04:36:44 INFO - mozleak == 0.1 04:36:44 INFO - mozlog == 3.4 04:36:44 INFO - moznetwork == 0.27 04:36:44 INFO - mozprocess == 0.25 04:36:44 INFO - mozprofile == 0.28 04:36:44 INFO - mozrunner == 6.13 04:36:44 INFO - mozscreenshot == 0.1 04:36:44 INFO - mozsystemmonitor == 0.3 04:36:44 INFO - moztest == 0.8 04:36:44 INFO - mozversion == 1.4 04:36:44 INFO - psutil == 3.1.1 04:36:44 INFO - requests == 1.2.3 04:36:44 INFO - wsgiref == 0.1.2 04:36:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:36: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')]} 04:36:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:36:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:36: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')]} 04:36:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:36:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:36:44 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 0x10e846b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e849768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb2e1d6c680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e624d30>, '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 0x10e54bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e619be8>, '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.0lrExIFjWJ/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.3JUJ3O9Jln/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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 04:36:44 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 04:36:44 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 04:36:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0lrExIFjWJ/Render', 04:36:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:36:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:36:44 INFO - 'HOME': '/Users/cltbld', 04:36:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:36:44 INFO - 'LOGNAME': 'cltbld', 04:36:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:36:44 INFO - 'MOZ_NO_REMOTE': '1', 04:36:44 INFO - 'NO_EM_RESTART': '1', 04:36:44 INFO - 'PAGER': '/bin/cat', 04:36:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:36:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:36:44 INFO - 'PWD': '/builds/slave/test', 04:36:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:36:44 INFO - 'SHELL': '/bin/bash', 04:36:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3JUJ3O9Jln/Listeners', 04:36:44 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 04:36:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:36:44 INFO - 'USER': 'cltbld', 04:36:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:36:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:36:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:36:44 INFO - 'XPC_FLAGS': '0x0', 04:36:44 INFO - 'XPC_SERVICE_NAME': '0', 04:36:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:36:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:36:44 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)) 04:36:44 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)) 04:36:44 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)) 04:36:44 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)) 04:36:44 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)) 04:36:44 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)) 04:36:44 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)) 04:36:44 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)) 04:36:44 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)) 04:36:44 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)) 04:36:44 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)) 04:36:44 INFO - Cleaning up... 04:36:44 INFO - Return code: 0 04:36:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:36: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')]} 04:36:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:36:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:36: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')]} 04:36:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:36:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:36:44 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 0x10e846b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e849768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb2e1d6c680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e624d30>, '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 0x10e54bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e619be8>, '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.0lrExIFjWJ/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.3JUJ3O9Jln/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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 04:36:44 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 04:36:44 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 04:36:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0lrExIFjWJ/Render', 04:36:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:36:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:36:44 INFO - 'HOME': '/Users/cltbld', 04:36:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:36:44 INFO - 'LOGNAME': 'cltbld', 04:36:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:36:44 INFO - 'MOZ_NO_REMOTE': '1', 04:36:44 INFO - 'NO_EM_RESTART': '1', 04:36:44 INFO - 'PAGER': '/bin/cat', 04:36:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:36:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:36:44 INFO - 'PWD': '/builds/slave/test', 04:36:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:36:44 INFO - 'SHELL': '/bin/bash', 04:36:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3JUJ3O9Jln/Listeners', 04:36:44 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 04:36:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:36:44 INFO - 'USER': 'cltbld', 04:36:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:36:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:36:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:36:44 INFO - 'XPC_FLAGS': '0x0', 04:36:44 INFO - 'XPC_SERVICE_NAME': '0', 04:36:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:36:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:36:45 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:36:45 INFO - Cleaning up... 04:36:45 INFO - Return code: 0 04:36:45 INFO - Running post-action listener: _resource_record_post_action 04:36:45 INFO - Running post-action listener: _start_resource_monitoring 04:36:45 INFO - Starting resource monitoring. 04:36:45 INFO - [mozharness: 2017-03-20 11:36:45.239922Z] Finished create-virtualenv step (success) 04:36:45 INFO - [mozharness: 2017-03-20 11:36:45.240853Z] Running install step. 04:36:45 INFO - Running pre-action listener: _resource_record_pre_action 04:36:45 INFO - Running main action method: install 04:36:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:36:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:36:45 INFO - Reading from file tmpfile_stdout 04:36:45 INFO - Detecting whether we're running mozinstall >=1.0... 04:36:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:36:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:36:45 INFO - Reading from file tmpfile_stdout 04:36:45 INFO - Output received: 04:36:45 INFO - Usage: mozinstall [options] installer 04:36:45 INFO - Options: 04:36:45 INFO - -h, --help show this help message and exit 04:36:45 INFO - -d DEST, --destination=DEST 04:36:45 INFO - Directory to install application into. [default: 04:36:45 INFO - "/builds/slave/test"] 04:36:45 INFO - --app=APP Application being installed. [default: firefox] 04:36:45 INFO - mkdir: /builds/slave/test/build/application 04:36:45 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'] 04:36:45 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 04:37:06 INFO - Reading from file tmpfile_stdout 04:37:06 INFO - Output received: 04:37:06 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 04:37:06 INFO - Running post-action listener: _resource_record_post_action 04:37:06 INFO - [mozharness: 2017-03-20 11:37:06.067983Z] Finished install step (success) 04:37:06 INFO - [mozharness: 2017-03-20 11:37:06.068137Z] Running run-tests step. 04:37:06 INFO - Running pre-action listener: _resource_record_pre_action 04:37:06 INFO - Running main action method: run_tests 04:37:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:37:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:37:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:37:06 INFO - 2017-03-20 04:37:06.082 screenresolution[1628:7551] starting screenresolution argv=screenresolution get 04:37:06 INFO - 2017-03-20 04:37:06.085 screenresolution[1628:7551] Display 0: 1600x1200x32@60 04:37:06 INFO - 2017-03-20 04:37:06.091 screenresolution[1629:7554] starting screenresolution argv=screenresolution list 04:37:06 INFO - Available Modes on Display 0 04:37:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:37:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:37:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:37:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:37:06 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:37:06 INFO - Intel Iris: 04:37:06 INFO - Chipset Model: Intel Iris 04:37:06 INFO - Type: GPU 04:37:06 INFO - Bus: Built-In 04:37:06 INFO - VRAM (Dynamic, Max): 1536 MB 04:37:06 INFO - Vendor: Intel (0x8086) 04:37:06 INFO - Device ID: 0x0a2e 04:37:06 INFO - Revision ID: 0x0009 04:37:06 INFO - Displays: 04:37:06 INFO - Display: 04:37:06 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:37:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:37:06 INFO - Display Serial Number: 1600x1200 60 04:37:06 INFO - Main Display: Yes 04:37:06 INFO - Mirror: Off 04:37:06 INFO - Online: Yes 04:37:06 INFO - Rotation: Supported 04:37:06 INFO - Return code: 0 04:37:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:37:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:37:06 INFO - Python 2.7.3 04:37:06 INFO - Return code: 0 04:37:06 INFO - grabbing minidump binary from tooltool 04:37:06 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')]} 04:37:06 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 0x10e624d30>, '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 0x10e54bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e619be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:37:06 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 04:37:06 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 04:37:06 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 04:37:06 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:37:06 INFO - Return code: 0 04:37:06 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:37:06 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:37:06 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:37:06 INFO - ENV: RUST_BACKTRACE is now 1 04:37:06 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:37:06 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:37:06 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/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cu0MMLqFTw-tkoKsZavL5Q/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0081', '--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 04:37:06 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/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/cu0MMLqFTw-tkoKsZavL5Q/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0081 --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 04:37:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0lrExIFjWJ/Render', 04:37:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:37:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:37:06 INFO - 'HOME': '/Users/cltbld', 04:37:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:37:06 INFO - 'LOGNAME': 'cltbld', 04:37:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:37:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:37:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:37:06 INFO - 'MOZ_NO_REMOTE': '1', 04:37:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:37:06 INFO - 'NO_EM_RESTART': '1', 04:37:06 INFO - 'PAGER': '/bin/cat', 04:37:06 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:37:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:37:06 INFO - 'PWD': '/builds/slave/test', 04:37:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:37:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:37:06 INFO - 'RUST_BACKTRACE': '1', 04:37:06 INFO - 'SHELL': '/bin/bash', 04:37:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3JUJ3O9Jln/Listeners', 04:37:06 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 04:37:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:37:06 INFO - 'USER': 'cltbld', 04:37:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:37:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:37:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:37:06 INFO - 'XPC_FLAGS': '0x0', 04:37:06 INFO - 'XPC_SERVICE_NAME': '0', 04:37:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:37:06 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/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cu0MMLqFTw-tkoKsZavL5Q/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0081', '--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 04:37:06 INFO - mozversion application_buildid: 20170320030209 04:37:06 INFO - mozversion application_changeset: 1b9293be51637f841275541d8991314ca56561a5 04:37:06 INFO - mozversion application_display_name: Nightly 04:37:06 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:37:06 INFO - mozversion application_name: Firefox 04:37:06 INFO - mozversion application_remotingname: firefox 04:37:06 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:37:06 INFO - mozversion application_vendor: Mozilla 04:37:06 INFO - mozversion application_version: 55.0a1 04:37:06 INFO - mozversion platform_buildid: 20170320030209 04:37:06 INFO - mozversion platform_changeset: 1b9293be51637f841275541d8991314ca56561a5 04:37:06 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:37:06 INFO - mozversion platform_version: 55.0a1 04:37:06 INFO - using testdate: 1490009826 04:37:06 INFO - actual date: 1490009826 04:37:06 INFO - starting webserver on 'localhost:49242' 04:37:06 INFO - SUITE-START | Running 2 tests 04:37:06 INFO - TEST-START | dromaeo_css 04:37:06 INFO - operating with platform_type : mac_ 04:37:06 INFO - Initialising browser for dromaeo_css test... 04:37:06 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/tmpBi0gCZ/profile http://localhost:49242/getInfo.html) 04:37:09 INFO - PID 1639 | 2017-03-20 04:37:09.805 plugin-container[1642:7736] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9443, name = 'com.apple.tsm.portname' 04:37:09 INFO - PID 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:37:09 INFO - PID 1639 | 2017-03-20 04:37:09.805 plugin-container[1642:7736] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b17, name = 'com.apple.CFPasteboardClient' 04:37:09 INFO - PID 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:37:09 INFO - PID 1639 | 2017-03-20 04:37:09.806 plugin-container[1642:7736] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:37:09 INFO - PID 1639 | __metrics Screen width/height:1600/1200 04:37:09 INFO - PID 1639 | colorDepth:24 04:37:09 INFO - PID 1639 | Browser inner width/height: 1024/689 04:37:09 INFO - PID 1639 | __metrics 04:37:09 ERROR - PID 1639 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3350: TypeError: this.tabs is undefined 04:37:10 INFO - TEST-INFO | 1639: exit 0 04:37:10 INFO - Browser initialized. 04:37:10 INFO - Running cycle 1/1 for dromaeo_css test... 04:37:10 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/tmpBi0gCZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:37:13 INFO - PID 1643 | 2017-03-20 04:37:13.066 plugin-container[1644:7866] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893f, name = 'com.apple.tsm.portname' 04:37:13 INFO - PID 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:37:13 INFO - PID 1643 | 2017-03-20 04:37:13.066 plugin-container[1644:7866] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c23, name = 'com.apple.CFPasteboardClient' 04:37:13 INFO - PID 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:37:13 INFO - PID 1643 | 2017-03-20 04:37:13.066 plugin-container[1644:7866] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:37:22 INFO - PID 1643 | RSS: Main: 227807232 04:37:22 INFO - PID 1643 | 04:40:26 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49242/tests/dromaeo/cssquery-ext.html) 04:40:27 INFO - PID 1643 | RSS: Main: 253497344 04:40:27 INFO - PID 1643 | 04:42:11 INFO - PID 1643 | ************************* 04:42:11 INFO - PID 1643 | A coding exception was thrown and uncaught in a Task. 04:42:11 INFO - PID 1643 | 04:42:11 ERROR - PID 1643 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:42:11 INFO - PID 1643 | Full stack: 04:42:11 INFO - PID 1643 | ************************* 04:43:30 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-ext.html (next: http://localhost:49242/tests/dromaeo/cssquery-jquery.html) 04:43:30 INFO - PID 1643 | RSS: Main: 260554752 04:43:30 INFO - PID 1643 | 04:46:32 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49242/tests/dromaeo/cssquery-mootools.html) 04:46:33 INFO - PID 1643 | RSS: Main: 271814656 04:46:33 INFO - PID 1643 | 04:49:35 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49242/tests/dromaeo/cssquery-prototype.html) 04:49:35 INFO - PID 1643 | RSS: Main: 264564736 04:49:35 INFO - PID 1643 | 04:52:38 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49242/tests/dromaeo/cssquery-yui.html) 04:52:39 INFO - PID 1643 | RSS: Main: 274165760 04:52:39 INFO - PID 1643 | 04:55:42 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-yui.html (next: http://localhost:49242/tests/dromaeo/cssquery-dojo.html) 04:55:42 INFO - PID 1643 | RSS: Main: 270147584 04:55:42 INFO - PID 1643 | 04:55:42 INFO - PID 1643 | __start_tp_report 04:55:42 INFO - PID 1643 | _x_x_mozilla_page_load 04:55:42 INFO - PID 1643 | _x_x_mozilla_page_load_details 04:55:42 INFO - PID 1643 | |i|pagename|runs| 04:55:42 INFO - PID 1643 | |0;dojo.html;2882.437924604702;3429.9657003429957;3471.218975730461;3492.9870337602097;3515.7890526568417;5892.764289428418;5929.614575052624;5965.88068238635;6022.457978781908;6028.42729940656;5963.880722385549;6046.8488287792825;6084.178635884155;6122.173506576611;6137.416945390192;5320.14877619581;5391.865203369908;5392.704972538971;5402.513773760361;5463.344398672165;5429.647072940266;5442.156465747816;5535.308086489189;5559.19391688205;5587.3015873015875;5373.650712703662;5428.45715428458;5514.6691198528215;5535.640183388067;5540.944590554103;4534.31985202219;5413.160960051198;5415.323084614423;5452.536534394581;5473.7263136843;5366.329208848894;5396.379416367129;5403.675779453206;5426.050441172819;5436.184572314145;5510.421405752419;5859.4140585941495;5959.880802383928;5988.640681559121;5999.820005399845;6221.3156552779155;6309.148264984215;6360.077788720656;6377.872442551123;6378.107065010902;6429.35706429358;6441.742330306782;6461.6446095464735;6476.125723027425;6478.2873883872735;6134.04922237054;6167.814965551041;6210.627362358273;6226.159468471789;6268.780592679234;5274.3670759509105;6388.968055159695;6603.438707709803;6657.101291325609;6671.733130674816;6142.191841058572;6223.564350495421;6276.560640755103;6300;6341.302456729756;6270.655113968684;6370.203724534434;6422.4862011039;6444.194475690539;6494.9350506495275;5414.18787181918;6337.873242535124;6358.554901156875;6361.427471527492;6382.170317858649;6223.657698826518;6260.686965651699;6337.144485494399;6357.427831495188;6394.424501794861;4388;5186.299849520342;5204.115300398866;5240.71176085319;5240.947590524046;4604.58558729716;4620.052889157714;4621.98316370398;4632.884177895512;4646.00110976135;5127.717975511383;6096;6117.522930757332;6197.101420294032;6201.348858369896;6262.2172228471445;6359.332270111571;6367.044943258548;6421.775237866652;6429.260635026938;6203.441690247809;6245.594036387621;6263.561550691408;6266.874662506724;6271.184745982993;5690.630109042899;5744.827655170351;5767.221425107557;5772.2674131893455;5814.622049566765;6350.206224221973;6438.034294855715;6655.833604159935;6699.229588597179;6716.227633822173;6420.807375778734;6424.486041116793;6431.292557818542;6431.742730290736;6437.710303036468;6073.605215661145;6606.8017959461295;6645.169353830771;6652.767153149616;6696.665166741741;6239.844003899939;6253.312135664983;6276.089966954857;6280.371962803866;6310.810675679737;6339.366063393623;6367.98112302048;6374.203224596926;6379.3301703320485;6399;6200.472959798287;6258.280297765633;6259.280182778856;6265.530085243534;6268.686565671789;6245.656488892972;6247.937520624736;6287.779927702509;6303.369663033844;6382.266039405342;4634.675572709878;5637.351704554028;5771.1920331154515;6241.625502469867;6253.499720022478;6222.782202622887;6247.00047992319;6262.624242545462;6291.591046581958;6301.779437719842;6137.652792202512;6202.81391558254;6254.8123556293385;6291.213598300213;6310.716017779119 04:55:42 INFO - PID 1643 | |1;ext.html;703.3704834173415;717.7416130193131;798.9892785626182;899.4917871402653;906.9546522673865;36857.63142368576;37867.621323786756;37905.24189516208;37905.431418528744;38084.23831523368;36290.45564316531;36630.450543241874;36685;36742.81628591853;36818;7555.811104722371;7710.997570315879;9213.723588292361;10628.256022078458;10896.18278629106;10819;11035.779284414308;11105.444727763579;11120.666380008572;11162.720931976684;11080.224384293102;11184.105271578295;11193.66419007431;11201.271917325388;11208.271462354962;11080.168987325982;11110.500027498741;11152.721181970435;11157.38634375109;11159.995600395921;10830.837537436944;10860.6198783043;10894.782104357868;10898.618548350769;10928.508217130247;38017.23965520702;38043;38237;38274.234515309814;38288.04279893025;3808.7714737115584;3812.504374431306;3835.347990841564;3835.5397352317623;3842.1163132479405;23061.077556897704;27630.585541216897;27880.860595696893;28156.155315340573;28399.57400638992;3099.2406860319143;3101.7121850838184;3109.2537790930014;3119.859606317721;3162.604674415698;317617.41191294126;320101.3994930034;320563;320883;321647;49258;49284.75357623189;49316.75341623269;49321;49365.50634493609;51742.48257517452;51919.740401298506;52139.47860521422;52173.7391313041;52239;13299.401526931311;14076.80347170502;14401;14738.705225895423;14753.77869331961;11985.880141198477;11997.520099196108;12013;12021.33882636446;12284.262944223376;13713.45146194161;13799.51701690436;13826.101303415246;13831.930840345734;13949.442022319197;5983.940160598426;6042.879142417215;6065.6663883486835;6072.720730805316;6082.9695851520455;962.2975228083538;1008.9041541053588;1080.8167501646788;1092.6285063078592;1127.261643623428;25587.61618575723;26024.959001639723;26060.218193454228;26130.216093517225;26190.476190476464;1209.0992210803008;1209.7399059202314;1213.4236237786981;1258.4337048328289;1262.2868079535217;13458.596242112751;13503.459861605623;13674.453021879212;13709.657258568615;13787.51743688966;361033;366843;368418;368934;381135;56451.71774141103;57096.85806283934;57254.14118788222;57317.14024289639;57518;1235.8208059831275;1244.6390546741525;1350.3180893648644;1389.8679625435568;1404.4241860837008;23955.401114972265;26175.370824357236;26658.600120998588;27667.44665106727;28568.85715571409;39882.40176397414;40284;40302.39546406865;40310;40325.79837100796;399472;400043;401993;403454;405444;33734.662653373154;33759;34094.31811363709;35505.644943550236;35605.64394356127;43478;43869;44361.89095272644;44374.778126109166;44522;29251.414971700022;34111.48832767461;34809.8259508711;35400.82299588485;36603.816980914926;29164.7083529162;33766;35480;36155.81922090373;36170.63829361673;34286.828565858006;35333;36223;36236.63763362333;36361;28150.85924570364;28846.567301490075;30372.84813575918;30553.23616909595;30960.69039309668;23277.650835237146;23528.6470702943;23807.285781426584;23883.761162388848;24419.14532991337 04:55:42 INFO - PID 1643 | |2;jquery.html;402.44864535586254;412.9132882094761;417.4177023052843;424.25331416706587;428.24628653569715;19354.709679354823;20354.389368318956;20914;20973.47566310839;21166.89416552917;21136;21160.047797849096;21468.57062858742;21674.78325216747;22229.444263893412;6507.4143327100555;6859.554128981626;6871.14110736158;6901.965490172543;6928.61892595907;5108.361454818148;5998.160257563923;6436.839079023015;6497.837554061139;7032.964835175817;6483.643399613019;6660.700268487905;6696;6699.296573859748;6718.361755633231;5883.941160588403;6621.205455345365;6720.563163394364;6780.966095169542;6822.215445223813;5825.533957283407;5972.641641501524;6558.114654521626;7412.480441509478;7479.887801682979;26844.59733104005;27316.31709207266;28047.018354357704;28161.73272202756;28222.576661350096;2466.3464181991785;2930.6776254611977;2975.2710585906593;3005.1885990782366;3105.378924215166;7432.739854105134;13745.38145783442;16650.000999940043;16806.647069410294;17725.936443813283;1827.2964908510187;2026.1186383922893;2081.4172031831113;2089.1689430708825;2115.5874604452138;90144;146525;147625.99999999892;148558.25720871327;148712;17863.553411164823;23433.88283058574;23518.64722029171;23556.057757689843;23785.762142378702;36785.81607092001;36976.44535331972;36983.63016369856;37130.257394852495;37156.25687486289;10334.276600638033;10608.522616482123;10794.406307652998;10830.837537436944;10860.185486088574;7085.787426377217;8517.48895066298;8536.231739143383;8694.695685650971;8736.213740763234;10769.984404366789;10855;10933.945330273298;11093.896807468655;11098.778024439627;3892.766434013968;4289.6139347458875;4339.1972485090355;4445.73325600465;4461.732296062247;1036.4247842447514;1052.8262836632023;1055.3404122423485;1061.2147011211803;1074.4198133008088;19874.403767886986;22599.20902768395;22910.770892290864;22989.425264368525;23032.884835575715;1051.153821173947;1066.195022757813;1068.3002633275216;1093.5024563823442;1100.6808025672465;9096.408733432438;11275.32348059119;11720.413979301002;11960.521579136737;12034.097442691833;611121;616076;628204;628227;630099;118233;122029.77970220361;131211.3439432816;142919;161496;1168.4216312925157;1294.100600082952;1339.5378594384922;1355.8034085057611;1356.1049707193206;21639.45901352479;21801.672974905072;22160.77839221587;22333;22355.552888942457;54099.647508812595;56708;57834.26497205091;58259.126113107486;65996.67001664962;92253.15493690223;92255;92516.53741731249;93295;93304;11734.178607497393;12354.196977196454;12358.134930554776;12408.828455020515;12412.689682758004;13769.862301376857;13828.446862125402;13837.377318020512;14145.151290922578;14204.857951420354;8892.288616910502;11676.824847627122;11779.939805417554;11825.526978920747;11973.76052478963;9467.905320946702;11484.770304594029;11628.069754419796;11831.40842957866;11851.881481185077;11557.942210288895;11600.825987610362;11645.708857278636;11906.76186476283;11999.280043197436;11790.823137653113;11945.820812687643;12016.879831201577;12073.973712234558;12111.515539378326;10557.736056598647;11672.649820505398;11758.529658813894;11825.940870295593;11833.763324733629 04:55:42 INFO - PID 1643 | |3;mootools.html;1581.7943667323245;1596.529023937939;1605.9678806423865;1639.0083999180495;1640.9835901640984;10463.633772817953;10945.56217751291;10957.452127393639;11105.444727763599;11495.827562586568;11028.558857645705;11185.888141118587;11191.99272065514;11279.323240605569;11285.322880627167;1968.8383853526452;2331.4870728439714;2369.3602727263715;2370.845895016827;2372.051179528194;2325.1280769711357;2350.8236882233814;2362.681038059857;2363.657269695893;2373.1338061607476;1950.2101648832245;2336.252399232238;2346.5424242272766;2353.294011796454;2355.7879790818824;2294.4149241943283;2307.861528308307;2310.0644239083113;2318.246569864784;2320.7679232076744;2043.2439997200981;2244.517428752826;2355.7644235576477;2358;2363.054778088761;10136.94931525345;11156;11184;11266.267692600037;11307;5472.671639701631;5646.604737668365;5654.773809047633;5655.0386434306265;5663.830085097413;3379.7803142795888;3490.825458727079;3656.981715091434;3682.705383569335;3697.3529632314508;1126.3298337489182;1152.2855829385835;1217.190568272098;1226.2213494431012;1227.957021504252;13709;13876.444942202299;14963.700725985529;15170.089794612359;15333.463328783439;4551.8634440966825;4618.422697162855;4767.332573439721;4779.761011949388;4803.78382972767;9873.654422095193;11390.202685811913;11433.256838305484;11474.655760327203;11493.712657183638;5703.9429605704245;5783.710814459344;5789.189513468118;5797.507211887037;5814.098814683731;5156.63903526757;5236.633435659467;5303.04545181871;5304.389995150607;5361.51746342831;3783.810809459571;3810.5236845394325;3831.6934645228316;3892.552356479041;3907.2185562887535;972.9708108756748;1038.3717850700368;1039.5010395010438;1054.1198099586777;1054.1988089052375;914.5564401265381;936.3071327217809;943.6649989253854;953.0945601678433;992.9453880036523;5705.801781625905;6297.1813664223355;6490.837729056717;6598.175228096488;6644.269130395653;910.9635614575475;975.23444096384;979.6522234606755;990.2028866762181;1012.0436187802457;3429.7599168057995;3954.003228369655;4000.439938408625;4125.5874412558505;4151.896202594899;12859.742805143846;13153.21080735159;13260.336983150804;13277.402516886763;13359.53241636538;12595.937020315023;13048.804267935988;13050.955923526095;13056.020798440155;13119.344032798323;8597.441166324294;9903.752406189758;10092.899071009197;10189.949050254701;10194.592216311265;5825.3883342248455;6505.674716264073;6625.768098116543;6704;6746.595204287759;11719.589814356457;11802.586909458129;11987.76024479523;12056.035517158782;12166.817497737364;12055.638330850088;12152.939235303767;12283.447244874182;12337.629871103882;12451.190672606252;8903.465792052497;9185.219256363283;9193.080691930754;9196.080391961019;9203.493807840632;10139.898601013896;10248.590056397661;10294.845577316497;10302.175825934057;10311.175105991353;7687.769366919001;8325.125861784425;8684.088170741981;8981.685641002534;9188.21900138469;8714.69498567547;8853.335999800169;8863.379563430499;8916.73249802507;9003.099690031206;6464.353564643498;7563.621818908922;7670.964419803478;7687.654055567625;7704.768856934301;5012.248162775612;5681.261436013292;5721.22763426932;5802.361740208658;6003.039513677791;5078.746062696777;5306.602004849575;5833.7666493341485;5986.55100867428;5987.850303742267 04:55:42 INFO - PID 1643 | |4;prototype.html;353.75767599194546;368.42709586592855;373.95886452490237;388.1944964199287;392.7604161461509;4966.279889416037;5201.453847346031;5579.9163012554845;6003.879922401549;6184.845378865525;5487;5699;5932;5990.670513121777;6022.638641681502;4914.361133052698;4932.4216250799755;4941.901161976758;4942.703437793726;4954.677945933503;4783.473817880031;4902.828401005965;4913.606911447094;4928.827491037814;4949.331840201581;4046.8381264749373;4455.4207952966235;4829.778301981093;4848.2727590861305;4898.902021959559;4880.951190488103;4894.216925291972;4912.371881043625;4914.582260507861;4918.852434426973;4310.3103503439615;4429.146642421082;4480.215962206634;4487.19230538503;4489.977550112261;5029.2958985742025;5796.782675638121;5821.941780582182;5849.239598852122;5865;5604.579656525777;5733.254676892019;5759.712014399263;5796.97101514494;5807.796727114531;5364.383095943977;6352.948227467507;6474.352564743488;6510.609363438209;6525.205568468662;4529.184746745585;4821;5044.470330615306;5055.5702765265;5076.289319495274;5306.495882891158;6107.969460152671;6509.479241660656;6696.430803381719;6877.6217308048035;4667.159911215946;4885.975570122127;5270.288511051036;5309.831836995855;5315.574754019746;5048.368953880765;5093.439721630618;5150.536451719363;5394;5431.8098866539485;5545.916811247835;5772.9711351442975;5816.185733997244;5819.214406055213;5840.065589505659;6503.219613646393;6514.3811337922825;6530.2041938466355;6531.738730450728;6554.573952693059;1600.8079030516412;1791.1133988675429;1813.5012871460453;1821.3079029968528;1834.6514162309118;1183.4319526627266;1329.5612447892365;1339.7387509435762;1352.7767918293569;1359.456217512983;5003.924941125886;5128;5169.510580358243;5231.1891656792495;5457.83626491198;5956.9575324318075;6743.932560674429;6761.661916904135;6774.780539502838;6792.93207067923;5877.44164304399;6175.382461753788;6239.284196845903;6293.937060629335;6350.079238510482;3119.672434394401;3570.339487194877;3583.5341405617105;3590;3602.9279414411567;5725.883452726762;6839.350261725128;6918.169819621577;6963.164420269499;7035.753748618774;6862;6963.547369420971;6964.79105626832;7005.964970175219;7014.283359062348;5900.763969441175;6413.3266007069515;6421.940379837274;6664.333566643297;7079.327463890922;5168.53483186515;5808.970955145197;6788.287229840796;6799.864002719819;6802.387785099365;5141.845744627518;6508.958566629317;6524.2823289439075;6677.966610166918;6702.36327548882;7022.683979221049;7071.186813516511;7081.4334853210585;7092.822679433056;7128.786136415916;4140.855070072533;4721.834735784231;4940.629452790983;5224.947750522446;5255.02781985334;6681.732730690718;6741.393274605309;6783.491238157059;6788.389044985975;6796.456283497408;6013.248343957005;6228.4705800007505;6259.186305780219;6309.936900630935;6557.344265573404;5324.946750532445;5475.753591088331;6471.935280647133;6515.381038801306;6544.738210471528;5593.776698592536;5907.788903274904;5934.169216309807;6158.199434073542;6418.19772528434;5898.790747896756;6048.395160483917;6361.363863613602;6419.967900160469;6569.441597464269;6117.418845209698;6388.169537960035;6388.8083357499345;6390.392912673288;6415.294317624965 04:55:42 INFO - PID 1643 | |5;yui.html;719.9172095209052;731.7292601737358;761.5735188294556;821.712400659769;830.0993422136982;14932.552023439288;15349.923250383732;15367.923160384182;15373.77009839213;15404.306806193721;14074.507392241274;14122.505712300072;14391.2084835334;14942.402303907858;15142.848571514282;890.728327860004;893.3121496447732;908.3142227618139;916.4363916191561;917.0966597901062;894.6242578117174;919.3932004876783;924.5146298193436;924.6440120553568;929.1173385284008;967.6177909725654;985.5515740338165;986.3243678080502;986.4525188520404;987.5950860147341;981.2346369831472;982.5480279071636;984.0356450678304;984.3503287830033;989.3915242126077;720.5640587444625;731.3600599475459;731.882898736205;732.7325526182905;733.8385555177853;16061.277242524115;16563.751543726852;16576.83423165765;16730.74903876443;16805.41181058657;12130.939345303304;14540.563783086523;14753.926230368887;14794.40822367104;14809.851901480955;11442;11452.198346115776;11560.364179970098;11682.065434765283;11894.226875253082;3724.0875985383755;3741.7567858089137;3754.4931434256573;3828.9808550957346;3888.4556162137324;97612;99183.0081699181;109039;109792;110102.44948775205;10827.78344433107;11270.211085223955;11477.827832582518;11535.538578456935;11779.823302650468;22919.541609168056;23421.648675269884;23481;23548.175813846778;23627.700476473616;7693.6922523099565;7977.800554986055;8011.238932301422;8053.919460805434;8075.596220188967;4125.216208920296;4233.854952736536;4288.48538175421;4296.505901821268;4337.306031035082;3071.1400807773857;3450.5686789151355;3539.805310707936;3554.7689400188906;3556.8755093572117;524.7743470307804;606.8240210338952;615.5288859625499;619.4270299972508;622.3527531367328;636.7039326713101;648.5298158834902;649.8570314530796;684.1448189762797;709.3403135084318;9934.602615895428;10004.449755263531;10092.596296148073;10264.794704106025;10330.328528645614;603.2610052685425;605.6729366142322;630.5775130662468;654.4142992022208;662.1656712542232;4892.067698008658;4898.048433827122;4923.003745763723;4941.283513890466;4948.678335908155;100018.99981000097;101488.98511014943;102616;103187.48406258071;103529.48235258776;71657;86529;86849.56575217083;87151.25697486015;89598;79471;80448;80971;81463;81683.95790105176;9494.715158545254;9527.904720952702;9594.376365536218;9604.9039509604;9649.565769540248;52463;52560;53358;53560.732196338766;53572.732136339066;51211.74394128005;64850;66404.33595663981;66899.66550167218;67073.66463167653;10077;11685.591004314809;11781.175317727677;12150.21023633461;12179.330136842558;12556.372181391076;12638.747225055631;12660.810087848873;12872.197892294898;13208.603741887759;11569.942150289195;11785.941070294937;12070.939645301718;12155.353081942387;12303.261804291771;11809.199216706087;11828.34944078084;11943;11963;12113.460010692617;11461.197716159788;11536.677456843598;11605.535778568763;11615.651530453762;12324.198927069712;9923.454210018519;10099.94950025245;10131.64539241123;10264;10476.895231047592;8525;8596.968363796384;9484.668036618685;9949.900500995187;9973.252006099427 04:55:42 INFO - PID 1643 | __end_tp_report 04:55:42 INFO - PID 1643 | __start_cc_report 04:55:42 INFO - PID 1643 | _x_x_mozilla_cycle_collect,421 04:55:42 INFO - PID 1643 | __end_cc_report 04:55:42 INFO - PID 1643 | __startTimestamp1490010942500__endTimestamp 04:55:42 INFO - PID 1643 | 04:55:42 INFO - PID 1643 | ------- Summary: start ------- 04:55:42 INFO - PID 1643 | Number of tests: 6 04:55:42 INFO - PID 1643 | 04:55:42 INFO - PID 1643 | [#0] dojo.html Cycles:165 Average:5930.16 Median:6223.66 stddev:682.22 (11.0%) stddev-sans-first:641.06 04:55:42 INFO - PID 1643 | Values: 2882.4 3430.0 3471.2 3493.0 3515.8 5892.8 5929.6 5965.9 6022.5 6028.4 5963.9 6046.8 6084.2 6122.2 6137.4 5320.1 5391.9 5392.7 5402.5 5463.3 5429.6 5442.2 5535.3 5559.2 5587.3 5373.7 5428.5 5514.7 5535.6 5540.9 4534.3 5413.2 5415.3 5452.5 5473.7 5366.3 5396.4 5403.7 5426.1 5436.2 5510.4 5859.4 5959.9 5988.6 5999.8 6221.3 6309.1 6360.1 6377.9 6378.1 6429.4 6441.7 6461.6 6476.1 6478.3 6134.0 6167.8 6210.6 6226.2 6268.8 5274.4 6389.0 6603.4 6657.1 6671.7 6142.2 6223.6 6276.6 6300.0 6341.3 6270.7 6370.2 6422.5 6444.2 6494.9 5414.2 6337.9 6358.6 6361.4 6382.2 6223.7 6260.7 6337.1 6357.4 6394.4 4388.0 5186.3 5204.1 5240.7 5240.9 4604.6 4620.1 4622.0 4632.9 4646.0 5127.7 6096.0 6117.5 6197.1 6201.3 6262.2 6359.3 6367.0 6421.8 6429.3 6203.4 6245.6 6263.6 6266.9 6271.2 5690.6 5744.8 5767.2 5772.3 5814.6 6350.2 6438.0 6655.8 6699.2 6716.2 6420.8 6424.5 6431.3 6431.7 6437.7 6073.6 6606.8 6645.2 6652.8 6696.7 6239.8 6253.3 6276.1 6280.4 6310.8 6339.4 6368.0 6374.2 6379.3 6399.0 6200.5 6258.3 6259.3 6265.5 6268.7 6245.7 6247.9 6287.8 6303.4 6382.3 4634.7 5637.4 5771.2 6241.6 6253.5 6222.8 6247.0 6262.6 6291.6 6301.8 6137.7 6202.8 6254.8 6291.2 6310.7 04:55:42 INFO - PID 1643 | 04:55:42 INFO - PID 1643 | [#1] ext.html Cycles:180 Average:51196.70 Median:320723.00 stddev:96324.05 (30.0%) stddev-sans-first:96519.66 04:55:42 INFO - PID 1643 | Values: 703.4 717.7 799.0 899.5 907.0 36857.6 37867.6 37905.2 37905.4 38084.2 36290.5 36630.5 36685.0 36742.8 36818.0 7555.8 7711.0 9213.7 10628.3 10896.2 10819.0 11035.8 11105.4 11120.7 11162.7 11080.2 11184.1 11193.7 11201.3 11208.3 11080.2 11110.5 11152.7 11157.4 11160.0 10830.8 10860.6 10894.8 10898.6 10928.5 38017.2 38043.0 38237.0 38274.2 38288.0 3808.8 3812.5 3835.3 3835.5 3842.1 23061.1 27630.6 27880.9 28156.2 28399.6 3099.2 3101.7 3109.3 3119.9 3162.6 317617.4 320101.4 320563.0 320883.0 321647.0 49258.0 49284.8 49316.8 49321.0 49365.5 51742.5 51919.7 52139.5 52173.7 52239.0 13299.4 14076.8 14401.0 14738.7 14753.8 11985.9 11997.5 12013.0 12021.3 12284.3 13713.5 13799.5 13826.1 13831.9 13949.4 5983.9 6042.9 6065.7 6072.7 6083.0 962.3 1008.9 1080.8 1092.6 1127.3 25587.6 26025.0 26060.2 26130.2 26190.5 1209.1 1209.7 1213.4 1258.4 1262.3 13458.6 13503.5 13674.5 13709.7 13787.5 361033.0 366843.0 368418.0 368934.0 381135.0 56451.7 57096.9 57254.1 57317.1 57518.0 1235.8 1244.6 1350.3 1389.9 1404.4 23955.4 26175.4 26658.6 27667.4 28568.9 39882.4 40284.0 40302.4 40310.0 40325.8 399472.0 400043.0 401993.0 403454.0 405444.0 33734.7 33759.0 34094.3 35505.6 35605.6 43478.0 43869.0 44361.9 44374.8 44522.0 29251.4 34111.5 34809.8 35400.8 36603.8 29164.7 33766.0 35480.0 36155.8 36170.6 34286.8 35333.0 36223.0 36236.6 36361.0 28150.9 28846.6 30372.8 30553.2 30960.7 23277.7 23528.6 23807.3 23883.8 24419.1 04:55:42 INFO - PID 1643 | 04:55:42 INFO - PID 1643 | [#2] jquery.html Cycles:180 Average:39018.70 Median:21657.12 stddev:104263.93 (481.4%) stddev-sans-first:104516.11 04:55:42 INFO - PID 1643 | Values: 402.4 412.9 417.4 424.3 428.2 19354.7 20354.4 20914.0 20973.5 21166.9 21136.0 21160.0 21468.6 21674.8 22229.4 6507.4 6859.6 6871.1 6902.0 6928.6 5108.4 5998.2 6436.8 6497.8 7033.0 6483.6 6660.7 6696.0 6699.3 6718.4 5883.9 6621.2 6720.6 6781.0 6822.2 5825.5 5972.6 6558.1 7412.5 7479.9 26844.6 27316.3 28047.0 28161.7 28222.6 2466.3 2930.7 2975.3 3005.2 3105.4 7432.7 13745.4 16650.0 16806.6 17725.9 1827.3 2026.1 2081.4 2089.2 2115.6 90144.0 146525.0 147626.0 148558.3 148712.0 17863.6 23433.9 23518.6 23556.1 23785.8 36785.8 36976.4 36983.6 37130.3 37156.3 10334.3 10608.5 10794.4 10830.8 10860.2 7085.8 8517.5 8536.2 8694.7 8736.2 10770.0 10855.0 10933.9 11093.9 11098.8 3892.8 4289.6 4339.2 4445.7 4461.7 1036.4 1052.8 1055.3 1061.2 1074.4 19874.4 22599.2 22910.8 22989.4 23032.9 1051.2 1066.2 1068.3 1093.5 1100.7 9096.4 11275.3 11720.4 11960.5 12034.1 611121.0 616076.0 628204.0 628227.0 630099.0 118233.0 122029.8 131211.3 142919.0 161496.0 1168.4 1294.1 1339.5 1355.8 1356.1 21639.5 21801.7 22160.8 22333.0 22355.6 54099.6 56708.0 57834.3 58259.1 65996.7 92253.2 92255.0 92516.5 93295.0 93304.0 11734.2 12354.2 12358.1 12408.8 12412.7 13769.9 13828.4 13837.4 14145.2 14204.9 8892.3 11676.8 11779.9 11825.5 11973.8 9467.9 11484.8 11628.1 11831.4 11851.9 11557.9 11600.8 11645.7 11906.8 11999.3 11790.8 11945.8 12016.9 12074.0 12111.5 10557.7 11672.6 11758.5 11825.9 11833.8 04:55:42 INFO - PID 1643 | 04:55:42 INFO - PID 1643 | [#3] mootools.html Cycles:180 Average:6595.63 Median:2372.59 stddev:4113.40 (173.4%) stddev-sans-first:4107.69 04:55:42 INFO - PID 1643 | Values: 1581.8 1596.5 1606.0 1639.0 1641.0 10463.6 10945.6 10957.5 11105.4 11495.8 11028.6 11185.9 11192.0 11279.3 11285.3 1968.8 2331.5 2369.4 2370.8 2372.1 2325.1 2350.8 2362.7 2363.7 2373.1 1950.2 2336.3 2346.5 2353.3 2355.8 2294.4 2307.9 2310.1 2318.2 2320.8 2043.2 2244.5 2355.8 2358.0 2363.1 10136.9 11156.0 11184.0 11266.3 11307.0 5472.7 5646.6 5654.8 5655.0 5663.8 3379.8 3490.8 3657.0 3682.7 3697.4 1126.3 1152.3 1217.2 1226.2 1228.0 13709.0 13876.4 14963.7 15170.1 15333.5 4551.9 4618.4 4767.3 4779.8 4803.8 9873.7 11390.2 11433.3 11474.7 11493.7 5703.9 5783.7 5789.2 5797.5 5814.1 5156.6 5236.6 5303.0 5304.4 5361.5 3783.8 3810.5 3831.7 3892.6 3907.2 973.0 1038.4 1039.5 1054.1 1054.2 914.6 936.3 943.7 953.1 992.9 5705.8 6297.2 6490.8 6598.2 6644.3 911.0 975.2 979.7 990.2 1012.0 3429.8 3954.0 4000.4 4125.6 4151.9 12859.7 13153.2 13260.3 13277.4 13359.5 12595.9 13048.8 13051.0 13056.0 13119.3 8597.4 9903.8 10092.9 10189.9 10194.6 5825.4 6505.7 6625.8 6704.0 6746.6 11719.6 11802.6 11987.8 12056.0 12166.8 12055.6 12152.9 12283.4 12337.6 12451.2 8903.5 9185.2 9193.1 9196.1 9203.5 10139.9 10248.6 10294.8 10302.2 10311.2 7687.8 8325.1 8684.1 8981.7 9188.2 8714.7 8853.3 8863.4 8916.7 9003.1 6464.4 7563.6 7671.0 7687.7 7704.8 5012.2 5681.3 5721.2 5802.4 6003.0 5078.7 5306.6 5833.8 5986.6 5987.9 04:55:42 INFO - PID 1643 | 04:55:42 INFO - PID 1643 | [#4] prototype.html Cycles:180 Average:5388.59 Median:5802.38 stddev:1541.40 (26.6%) stddev-sans-first:1498.68 04:55:42 INFO - PID 1643 | Values: 353.8 368.4 374.0 388.2 392.8 4966.3 5201.5 5579.9 6003.9 6184.8 5487.0 5699.0 5932.0 5990.7 6022.6 4914.4 4932.4 4941.9 4942.7 4954.7 4783.5 4902.8 4913.6 4928.8 4949.3 4046.8 4455.4 4829.8 4848.3 4898.9 4881.0 4894.2 4912.4 4914.6 4918.9 4310.3 4429.1 4480.2 4487.2 4490.0 5029.3 5796.8 5821.9 5849.2 5865.0 5604.6 5733.3 5759.7 5797.0 5807.8 5364.4 6352.9 6474.4 6510.6 6525.2 4529.2 4821.0 5044.5 5055.6 5076.3 5306.5 6108.0 6509.5 6696.4 6877.6 4667.2 4886.0 5270.3 5309.8 5315.6 5048.4 5093.4 5150.5 5394.0 5431.8 5545.9 5773.0 5816.2 5819.2 5840.1 6503.2 6514.4 6530.2 6531.7 6554.6 1600.8 1791.1 1813.5 1821.3 1834.7 1183.4 1329.6 1339.7 1352.8 1359.5 5003.9 5128.0 5169.5 5231.2 5457.8 5957.0 6743.9 6761.7 6774.8 6792.9 5877.4 6175.4 6239.3 6293.9 6350.1 3119.7 3570.3 3583.5 3590.0 3602.9 5725.9 6839.4 6918.2 6963.2 7035.8 6862.0 6963.5 6964.8 7006.0 7014.3 5900.8 6413.3 6421.9 6664.3 7079.3 5168.5 5809.0 6788.3 6799.9 6802.4 5141.8 6509.0 6524.3 6678.0 6702.4 7022.7 7071.2 7081.4 7092.8 7128.8 4140.9 4721.8 4940.6 5224.9 5255.0 6681.7 6741.4 6783.5 6788.4 6796.5 6013.2 6228.5 6259.2 6309.9 6557.3 5324.9 5475.8 6471.9 6515.4 6544.7 5593.8 5907.8 5934.2 6158.2 6418.2 5898.8 6048.4 6361.4 6420.0 6569.4 6117.4 6388.2 6388.8 6390.4 6415.3 04:55:42 INFO - PID 1643 | 04:55:42 INFO - PID 1643 | [#5] yui.html Cycles:180 Average:20181.69 Median:4614.69 stddev:29136.51 (631.4%) stddev-sans-first:29181.60 04:55:42 INFO - PID 1643 | Values: 719.9 731.7 761.6 821.7 830.1 14932.6 15349.9 15367.9 15373.8 15404.3 14074.5 14122.5 14391.2 14942.4 15142.8 890.7 893.3 908.3 916.4 917.1 894.6 919.4 924.5 924.6 929.1 967.6 985.6 986.3 986.5 987.6 981.2 982.5 984.0 984.4 989.4 720.6 731.4 731.9 732.7 733.8 16061.3 16563.8 16576.8 16730.7 16805.4 12130.9 14540.6 14753.9 14794.4 14809.9 11442.0 11452.2 11560.4 11682.1 11894.2 3724.1 3741.8 3754.5 3829.0 3888.5 97612.0 99183.0 109039.0 109792.0 110102.4 10827.8 11270.2 11477.8 11535.5 11779.8 22919.5 23421.6 23481.0 23548.2 23627.7 7693.7 7977.8 8011.2 8053.9 8075.6 4125.2 4233.9 4288.5 4296.5 4337.3 3071.1 3450.6 3539.8 3554.8 3556.9 524.8 606.8 615.5 619.4 622.4 636.7 648.5 649.9 684.1 709.3 9934.6 10004.4 10092.6 10264.8 10330.3 603.3 605.7 630.6 654.4 662.2 4892.1 4898.0 4923.0 4941.3 4948.7 100019.0 101489.0 102616.0 103187.5 103529.5 71657.0 86529.0 86849.6 87151.3 89598.0 79471.0 80448.0 80971.0 81463.0 81684.0 9494.7 9527.9 9594.4 9604.9 9649.6 52463.0 52560.0 53358.0 53560.7 53572.7 51211.7 64850.0 66404.3 66899.7 67073.7 10077.0 11685.6 11781.2 12150.2 12179.3 12556.4 12638.7 12660.8 12872.2 13208.6 11569.9 11785.9 12070.9 12155.4 12303.3 11809.2 11828.3 11943.0 11963.0 12113.5 11461.2 11536.7 11605.5 11615.7 12324.2 9923.5 10099.9 10131.6 10264.0 10476.9 8525.0 8597.0 9484.7 9949.9 9973.3 04:55:42 INFO - PID 1643 | -------- Summary: end -------- 04:55:42 INFO - PID 1643 | 04:55:43 INFO - TEST-INFO | 1643: exit 0 04:55:43 INFO - TEST-OK | dromaeo_css | took 1116487ms 04:55:43 INFO - TEST-START | kraken 04:55:43 INFO - operating with platform_type : mac_ 04:55:43 INFO - Initialising browser for kraken test... 04:55:43 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/tmpEITKlf/profile http://localhost:49242/getInfo.html) 04:55:46 INFO - PID 1651 | 2017-03-20 04:55:46.121 plugin-container[1653:10230] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x434b, name = 'com.apple.tsm.portname' 04:55:46 INFO - PID 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:55:46 INFO - PID 1651 | 2017-03-20 04:55:46.123 plugin-container[1653:10230] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x910b, name = 'com.apple.CFPasteboardClient' 04:55:46 INFO - PID 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:55:46 INFO - PID 1651 | 2017-03-20 04:55:46.123 plugin-container[1653:10230] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:55:46 INFO - PID 1651 | __metrics Screen width/height:1600/1200 04:55:46 INFO - PID 1651 | colorDepth:24 04:55:46 INFO - PID 1651 | Browser inner width/height: 1024/689 04:55:46 INFO - PID 1651 | __metrics 04:55:46 ERROR - PID 1651 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3350: TypeError: this.tabs is undefined 04:55:47 INFO - TEST-INFO | 1651: exit 0 04:55:47 INFO - Browser initialized. 04:55:47 INFO - Running cycle 1/1 for kraken test... 04:55:47 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/tmpEITKlf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:55:49 INFO - PID 1654 | 2017-03-20 04:55:49.537 plugin-container[1655:10367] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4643, name = 'com.apple.tsm.portname' 04:55:49 INFO - PID 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:55:49 INFO - PID 1654 | 2017-03-20 04:55:49.537 plugin-container[1655:10367] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 04:55:49 INFO - PID 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:55:49 INFO - PID 1654 | 2017-03-20 04:55:49.537 plugin-container[1655:10367] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:55:59 INFO - PID 1654 | RSS: Main: 227491840 04:55:59 INFO - PID 1654 | 04:56:01 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection) 04:56:02 INFO - PID 1654 | RSS: Main: 241987584 04:56:02 INFO - PID 1654 | 04:56:05 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-dft) 04:56:06 INFO - PID 1654 | RSS: Main: 250322944 04:56:06 INFO - PID 1654 | 04:56:09 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-fft) 04:56:10 INFO - PID 1654 | RSS: Main: 252698624 04:56:10 INFO - PID 1654 | 04:56:12 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator) 04:56:13 INFO - PID 1654 | RSS: Main: 253992960 04:56:13 INFO - PID 1654 | 04:56:15 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:56:15 INFO - PID 1654 | RSS: Main: 254418944 04:56:15 INFO - PID 1654 | 04:56:18 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom) 04:56:19 INFO - PID 1654 | RSS: Main: 260964352 04:56:19 INFO - PID 1654 | 04:56:22 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate) 04:56:23 INFO - PID 1654 | RSS: Main: 261758976 04:56:23 INFO - PID 1654 | 04:56:26 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial) 04:56:26 INFO - PID 1654 | RSS: Main: 261840896 04:56:26 INFO - PID 1654 | 04:56:28 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:56:29 INFO - PID 1654 | RSS: Main: 261185536 04:56:29 INFO - PID 1654 | 04:56:31 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:56:31 INFO - PID 1654 | RSS: Main: 261226496 04:56:31 INFO - PID 1654 | 04:56:33 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:56:34 INFO - PID 1654 | RSS: Main: 261255168 04:56:34 INFO - PID 1654 | 04:56:37 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:56:38 INFO - PID 1654 | RSS: Main: 261656576 04:56:38 INFO - PID 1654 | 04:56:41 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:56:42 INFO - PID 1654 | RSS: Main: 261431296 04:56:42 INFO - PID 1654 | 04:56:43 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49242/tests/kraken/driver.html?testName=ai-astar) 04:56:44 INFO - PID 1654 | RSS: Main: 263061504 04:56:44 INFO - PID 1654 | 04:56:44 INFO - PID 1654 | __start_tp_report 04:56:44 INFO - PID 1654 | _x_x_mozilla_page_load 04:56:44 INFO - PID 1654 | _x_x_mozilla_page_load_details 04:56:44 INFO - PID 1654 | |i|pagename|runs| 04:56:44 INFO - PID 1654 | |0;ai-astar;108;122;120;120;122;122;121;119;118;122 04:56:44 INFO - PID 1654 | |1;audio-beat-detection;148;110;133;153;118;116;116;118;138;115 04:56:44 INFO - PID 1654 | |2;audio-dft;197;199;217;231;265;281;196;203;224;284 04:56:44 INFO - PID 1654 | |3;audio-fft;80;85;58;95;84;85;50;63;100;86 04:56:44 INFO - PID 1654 | |4;audio-oscillator;85;68;72;70;82;100;73;80;62;78 04:56:44 INFO - PID 1654 | |5;imaging-gaussian-blur;102;91;112;135;91;90;91;82;132;91 04:56:44 INFO - PID 1654 | |6;imaging-darkroom;154;108;101;99;98;98;98;99;97;105 04:56:44 INFO - PID 1654 | |7;imaging-desaturate;89;94;92;92;85;89;91;90;139;90 04:56:44 INFO - PID 1654 | |8;json-parse-financial;66;68;67;67;69;79;101;102;101;81 04:56:44 INFO - PID 1654 | |9;json-stringify-tinderbox;58;68;67;94;90;85;84;84;59;59 04:56:44 INFO - PID 1654 | |10;stanford-crypto-aes;84;84;84;83;84;84;85;87;84;82 04:56:44 INFO - PID 1654 | |11;stanford-crypto-ccm;143;144;139;139;141;141;142;137;182;146 04:56:44 INFO - PID 1654 | |12;stanford-crypto-pbkdf2;187;187;197;219;191;195;237;228;190;185 04:56:44 INFO - PID 1654 | |13;stanford-crypto-sha256-iterative;64;72;69;64;91;90;75;70;69;69 04:56:44 INFO - PID 1654 | __end_tp_report 04:56:44 INFO - PID 1654 | __start_cc_report 04:56:44 INFO - PID 1654 | _x_x_mozilla_cycle_collect,997 04:56:44 INFO - PID 1654 | __end_cc_report 04:56:44 INFO - PID 1654 | __startTimestamp1490011004038__endTimestamp 04:56:44 INFO - PID 1654 | 04:56:44 INFO - PID 1654 | ------- Summary: start ------- 04:56:44 INFO - PID 1654 | Number of tests: 14 04:56:44 INFO - PID 1654 | 04:56:44 INFO - PID 1654 | [#0] ai-astar Cycles:10 Average:119.40 Median:121.50 stddev:4.25 (3.5%) stddev-sans-first:1.50 04:56:44 INFO - PID 1654 | Values: 108.0 122.0 120.0 120.0 122.0 122.0 121.0 119.0 118.0 122.0 04:56:44 INFO - PID 1654 | 04:56:44 INFO - PID 1654 | [#1] audio-beat-detection Cycles:10 Average:126.50 Median:125.50 stddev:15.31 (12.2%) stddev-sans-first:14.12 04:56:44 INFO - PID 1654 | Values: 148.0 110.0 133.0 153.0 118.0 116.0 116.0 118.0 138.0 115.0 04:56:44 INFO - PID 1654 | 04:56:44 INFO - PID 1654 | [#2] audio-dft Cycles:10 Average:229.70 Median:227.50 stddev:34.77 (15.3%) stddev-sans-first:34.81 04:56:44 INFO - PID 1654 | Values: 197.0 199.0 217.0 231.0 265.0 281.0 196.0 203.0 224.0 284.0 04:56:44 INFO - PID 1654 | 04:56:44 INFO - PID 1654 | [#3] audio-fft Cycles:10 Average:78.60 Median:84.50 stddev:16.26 (19.2%) stddev-sans-first:17.24 04:56:44 INFO - PID 1654 | Values: 80.0 85.0 58.0 95.0 84.0 85.0 50.0 63.0 100.0 86.0 04:56:44 INFO - PID 1654 | 04:56:44 INFO - PID 1654 | [#4] audio-oscillator Cycles:10 Average:77.00 Median:75.50 stddev:10.67 (14.1%) stddev-sans-first:10.91 04:56:44 INFO - PID 1654 | Values: 85.0 68.0 72.0 70.0 82.0 100.0 73.0 80.0 62.0 78.0 04:56:44 INFO - PID 1654 | 04:56:44 INFO - PID 1654 | [#5] imaging-gaussian-blur Cycles:10 Average:101.70 Median:90.50 stddev:18.61 (20.6%) stddev-sans-first:19.74 04:56:44 INFO - PID 1654 | Values: 102.0 91.0 112.0 135.0 91.0 90.0 91.0 82.0 132.0 91.0 04:56:44 INFO - PID 1654 | 04:56:44 INFO - PID 1654 | [#6] imaging-darkroom Cycles:10 Average:105.70 Median:98.00 stddev:17.33 (17.7%) stddev-sans-first:3.74 04:56:44 INFO - PID 1654 | Values: 154.0 108.0 101.0 99.0 98.0 98.0 98.0 99.0 97.0 105.0 04:56:44 INFO - PID 1654 | 04:56:44 INFO - PID 1654 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:90.50 stddev:15.61 (17.2%) stddev-sans-first:16.40 04:56:44 INFO - PID 1654 | Values: 89.0 94.0 92.0 92.0 85.0 89.0 91.0 90.0 139.0 90.0 04:56:44 INFO - PID 1654 | 04:56:44 INFO - PID 1654 | [#8] json-parse-financial Cycles:10 Average:80.10 Median:67.50 stddev:15.52 (23.0%) stddev-sans-first:15.60 04:56:44 INFO - PID 1654 | Values: 66.0 68.0 67.0 67.0 69.0 79.0 101.0 102.0 101.0 81.0 04:56:44 INFO - PID 1654 | 04:56:44 INFO - PID 1654 | [#9] json-stringify-tinderbox Cycles:10 Average:74.80 Median:84.00 stddev:13.99 (16.7%) stddev-sans-first:13.45 04:56:44 INFO - PID 1654 | Values: 58.0 68.0 67.0 94.0 90.0 85.0 84.0 84.0 59.0 59.0 04:56:44 INFO - PID 1654 | 04:56:44 INFO - PID 1654 | [#10] stanford-crypto-aes Cycles:10 Average:84.10 Median:84.00 stddev:1.29 (1.5%) stddev-sans-first:1.36 04:56:44 INFO - PID 1654 | Values: 84.0 84.0 84.0 83.0 84.0 84.0 85.0 87.0 84.0 82.0 04:56:44 INFO - PID 1654 | 04:56:44 INFO - PID 1654 | [#11] stanford-crypto-ccm Cycles:10 Average:145.40 Median:142.50 stddev:13.13 (9.2%) stddev-sans-first:13.89 04:56:44 INFO - PID 1654 | Values: 143.0 144.0 139.0 139.0 141.0 141.0 142.0 137.0 182.0 146.0 04:56:44 INFO - PID 1654 | 04:56:44 INFO - PID 1654 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:201.60 Median:196.00 stddev:19.05 (9.7%) stddev-sans-first:19.46 04:56:44 INFO - PID 1654 | Values: 187.0 187.0 197.0 219.0 191.0 195.0 237.0 228.0 190.0 185.0 04:56:44 INFO - PID 1654 | 04:56:44 INFO - PID 1654 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.30 Median:71.00 stddev:9.64 (13.6%) stddev-sans-first:9.62 04:56:44 INFO - PID 1654 | Values: 64.0 72.0 69.0 64.0 91.0 90.0 75.0 70.0 69.0 69.0 04:56:44 INFO - PID 1654 | -------- Summary: end -------- 04:56:44 INFO - PID 1654 | 04:56:44 INFO - TEST-INFO | 1654: exit 0 04:56:45 INFO - TEST-OK | kraken | took 61849ms 04:56:45 INFO - SUITE-END | took 1178s 04:56:45 INFO - Completed test suite (00:19:38) 04:56:45 INFO - javascript benchmark 04:56:45 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7869.777228790983, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2882.437924604702, 3429.9657003429957, 3471.218975730461, 3492.9870337602097, 3515.7890526568417, 5892.764289428418, 5929.614575052624, 5965.88068238635, 6022.457978781908, 6028.42729940656, 5963.880722385549, 6046.8488287792825, 6084.178635884155, 6122.173506576611, 6137.416945390192, 5320.14877619581, 5391.865203369908, 5392.704972538971, 5402.513773760361, 5463.344398672165, 5429.647072940266, 5442.156465747816, 5535.308086489189, 5559.19391688205, 5587.3015873015875, 5373.650712703662, 5428.45715428458, 5514.6691198528215, 5535.640183388067, 5540.944590554103, 4534.31985202219, 5413.160960051198, 5415.323084614423, 5452.536534394581, 5473.7263136843, 5366.329208848894, 5396.379416367129, 5403.675779453206, 5426.050441172819, 5436.184572314145, 5510.421405752419, 5859.4140585941495, 5959.880802383928, 5988.640681559121, 5999.820005399845, 6221.3156552779155, 6309.148264984215, 6360.077788720656, 6377.872442551123, 6378.107065010902, 6429.35706429358, 6441.742330306782, 6461.6446095464735, 6476.125723027425, 6478.2873883872735, 6134.04922237054, 6167.814965551041, 6210.627362358273, 6226.159468471789, 6268.780592679234, 5274.3670759509105, 6388.968055159695, 6603.438707709803, 6657.101291325609, 6671.733130674816, 6142.191841058572, 6223.564350495421, 6276.560640755103, 6300.0, 6341.302456729756, 6270.655113968684, 6370.203724534434, 6422.4862011039, 6444.194475690539, 6494.9350506495275, 5414.18787181918, 6337.873242535124, 6358.554901156875, 6361.427471527492, 6382.170317858649, 6223.657698826518, 6260.686965651699, 6337.144485494399, 6357.427831495188, 6394.424501794861, 4388.0, 5186.299849520342, 5204.115300398866, 5240.71176085319, 5240.947590524046, 4604.58558729716, 4620.052889157714, 4621.98316370398, 4632.884177895512, 4646.00110976135, 5127.717975511383, 6096.0, 6117.522930757332, 6197.101420294032, 6201.348858369896, 6262.2172228471445, 6359.332270111571, 6367.044943258548, 6421.775237866652, 6429.260635026938, 6203.441690247809, 6245.594036387621, 6263.561550691408, 6266.874662506724, 6271.184745982993, 5690.630109042899, 5744.827655170351, 5767.221425107557, 5772.2674131893455, 5814.622049566765, 6350.206224221973, 6438.034294855715, 6655.833604159935, 6699.229588597179, 6716.227633822173, 6420.807375778734, 6424.486041116793, 6431.292557818542, 6431.742730290736, 6437.710303036468, 6073.605215661145, 6606.8017959461295, 6645.169353830771, 6652.767153149616, 6696.665166741741, 6239.844003899939, 6253.312135664983, 6276.089966954857, 6280.371962803866, 6310.810675679737, 6339.366063393623, 6367.98112302048, 6374.203224596926, 6379.3301703320485, 6399.0, 6200.472959798287, 6258.280297765633, 6259.280182778856, 6265.530085243534, 6268.686565671789, 6245.656488892972, 6247.937520624736, 6287.779927702509, 6303.369663033844, 6382.266039405342, 4634.675572709878, 5637.351704554028, 5771.1920331154515, 6241.625502469867, 6253.499720022478, 6222.782202622887, 6247.00047992319, 6262.624242545462, 6291.591046581958, 6301.779437719842, 6137.652792202512, 6202.81391558254, 6254.8123556293385, 6291.213598300213, 6310.716017779119], "value": 5886.755153089485, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [703.3704834173415, 717.7416130193131, 798.9892785626182, 899.4917871402653, 906.9546522673865, 36857.63142368576, 37867.621323786756, 37905.24189516208, 37905.431418528744, 38084.23831523368, 36290.45564316531, 36630.450543241874, 36685.0, 36742.81628591853, 36818.0, 7555.811104722371, 7710.997570315879, 9213.723588292361, 10628.256022078458, 10896.18278629106, 10819.0, 11035.779284414308, 11105.444727763579, 11120.666380008572, 11162.720931976684, 11080.224384293102, 11184.105271578295, 11193.66419007431, 11201.271917325388, 11208.271462354962, 11080.168987325982, 11110.500027498741, 11152.721181970435, 11157.38634375109, 11159.995600395921, 10830.837537436944, 10860.6198783043, 10894.782104357868, 10898.618548350769, 10928.508217130247, 38017.23965520702, 38043.0, 38237.0, 38274.234515309814, 38288.04279893025, 3808.7714737115584, 3812.504374431306, 3835.347990841564, 3835.5397352317623, 3842.1163132479405, 23061.077556897704, 27630.585541216897, 27880.860595696893, 28156.155315340573, 28399.57400638992, 3099.2406860319143, 3101.7121850838184, 3109.2537790930014, 3119.859606317721, 3162.604674415698, 317617.41191294126, 320101.3994930034, 320563.0, 320883.0, 321647.0, 49258.0, 49284.75357623189, 49316.75341623269, 49321.0, 49365.50634493609, 51742.48257517452, 51919.740401298506, 52139.47860521422, 52173.7391313041, 52239.0, 13299.401526931311, 14076.80347170502, 14401.0, 14738.705225895423, 14753.77869331961, 11985.880141198477, 11997.520099196108, 12013.0, 12021.33882636446, 12284.262944223376, 13713.45146194161, 13799.51701690436, 13826.101303415246, 13831.930840345734, 13949.442022319197, 5983.940160598426, 6042.879142417215, 6065.6663883486835, 6072.720730805316, 6082.9695851520455, 962.2975228083538, 1008.9041541053588, 1080.8167501646788, 1092.6285063078592, 1127.261643623428, 25587.61618575723, 26024.959001639723, 26060.218193454228, 26130.216093517225, 26190.476190476464, 1209.0992210803008, 1209.7399059202314, 1213.4236237786981, 1258.4337048328289, 1262.2868079535217, 13458.596242112751, 13503.459861605623, 13674.453021879212, 13709.657258568615, 13787.51743688966, 361033.0, 366843.0, 368418.0, 368934.0, 381135.0, 56451.71774141103, 57096.85806283934, 57254.14118788222, 57317.14024289639, 57518.0, 1235.8208059831275, 1244.6390546741525, 1350.3180893648644, 1389.8679625435568, 1404.4241860837008, 23955.401114972265, 26175.370824357236, 26658.600120998588, 27667.44665106727, 28568.85715571409, 39882.40176397414, 40284.0, 40302.39546406865, 40310.0, 40325.79837100796, 399472.0, 400043.0, 401993.0, 403454.0, 405444.0, 33734.662653373154, 33759.0, 34094.31811363709, 35505.644943550236, 35605.64394356127, 43478.0, 43869.0, 44361.89095272644, 44374.778126109166, 44522.0, 29251.414971700022, 34111.48832767461, 34809.8259508711, 35400.82299588485, 36603.816980914926, 29164.7083529162, 33766.0, 35480.0, 36155.81922090373, 36170.63829361673, 34286.828565858006, 35333.0, 36223.0, 36236.63763362333, 36361.0, 28150.85924570364, 28846.567301490075, 30372.84813575918, 30553.23616909595, 30960.69039309668, 23277.650835237146, 23528.6470702943, 23807.285781426584, 23883.761162388848, 24419.14532991337], "value": 18648.216649433914, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [402.44864535586254, 412.9132882094761, 417.4177023052843, 424.25331416706587, 428.24628653569715, 19354.709679354823, 20354.389368318956, 20914.0, 20973.47566310839, 21166.89416552917, 21136.0, 21160.047797849096, 21468.57062858742, 21674.78325216747, 22229.444263893412, 6507.4143327100555, 6859.554128981626, 6871.14110736158, 6901.965490172543, 6928.61892595907, 5108.361454818148, 5998.160257563923, 6436.839079023015, 6497.837554061139, 7032.964835175817, 6483.643399613019, 6660.700268487905, 6696.0, 6699.296573859748, 6718.361755633231, 5883.941160588403, 6621.205455345365, 6720.563163394364, 6780.966095169542, 6822.215445223813, 5825.533957283407, 5972.641641501524, 6558.114654521626, 7412.480441509478, 7479.887801682979, 26844.59733104005, 27316.31709207266, 28047.018354357704, 28161.73272202756, 28222.576661350096, 2466.3464181991785, 2930.6776254611977, 2975.2710585906593, 3005.1885990782366, 3105.378924215166, 7432.739854105134, 13745.38145783442, 16650.000999940043, 16806.647069410294, 17725.936443813283, 1827.2964908510187, 2026.1186383922893, 2081.4172031831113, 2089.1689430708825, 2115.5874604452138, 90144.0, 146525.0, 147625.99999999892, 148558.25720871327, 148712.0, 17863.553411164823, 23433.88283058574, 23518.64722029171, 23556.057757689843, 23785.762142378702, 36785.81607092001, 36976.44535331972, 36983.63016369856, 37130.257394852495, 37156.25687486289, 10334.276600638033, 10608.522616482123, 10794.406307652998, 10830.837537436944, 10860.185486088574, 7085.787426377217, 8517.48895066298, 8536.231739143383, 8694.695685650971, 8736.213740763234, 10769.984404366789, 10855.0, 10933.945330273298, 11093.896807468655, 11098.778024439627, 3892.766434013968, 4289.6139347458875, 4339.1972485090355, 4445.73325600465, 4461.732296062247, 1036.4247842447514, 1052.8262836632023, 1055.3404122423485, 1061.2147011211803, 1074.4198133008088, 19874.403767886986, 22599.20902768395, 22910.770892290864, 22989.425264368525, 23032.884835575715, 1051.153821173947, 1066.195022757813, 1068.3002633275216, 1093.5024563823442, 1100.6808025672465, 9096.408733432438, 11275.32348059119, 11720.413979301002, 11960.521579136737, 12034.097442691833, 611121.0, 616076.0, 628204.0, 628227.0, 630099.0, 118233.0, 122029.77970220361, 131211.3439432816, 142919.0, 161496.0, 1168.4216312925157, 1294.100600082952, 1339.5378594384922, 1355.8034085057611, 1356.1049707193206, 21639.45901352479, 21801.672974905072, 22160.77839221587, 22333.0, 22355.552888942457, 54099.647508812595, 56708.0, 57834.26497205091, 58259.126113107486, 65996.67001664962, 92253.15493690223, 92255.0, 92516.53741731249, 93295.0, 93304.0, 11734.178607497393, 12354.196977196454, 12358.134930554776, 12408.828455020515, 12412.689682758004, 13769.862301376857, 13828.446862125402, 13837.377318020512, 14145.151290922578, 14204.857951420354, 8892.288616910502, 11676.824847627122, 11779.939805417554, 11825.526978920747, 11973.76052478963, 9467.905320946702, 11484.770304594029, 11628.069754419796, 11831.40842957866, 11851.881481185077, 11557.942210288895, 11600.825987610362, 11645.708857278636, 11906.76186476283, 11999.280043197436, 11790.823137653113, 11945.820812687643, 12016.879831201577, 12073.973712234558, 12111.515539378326, 10557.736056598647, 11672.649820505398, 11758.529658813894, 11825.940870295593, 11833.763324733629], "value": 11749.849295378932, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1581.7943667323245, 1596.529023937939, 1605.9678806423865, 1639.0083999180495, 1640.9835901640984, 10463.633772817953, 10945.56217751291, 10957.452127393639, 11105.444727763599, 11495.827562586568, 11028.558857645705, 11185.888141118587, 11191.99272065514, 11279.323240605569, 11285.322880627167, 1968.8383853526452, 2331.4870728439714, 2369.3602727263715, 2370.845895016827, 2372.051179528194, 2325.1280769711357, 2350.8236882233814, 2362.681038059857, 2363.657269695893, 2373.1338061607476, 1950.2101648832245, 2336.252399232238, 2346.5424242272766, 2353.294011796454, 2355.7879790818824, 2294.4149241943283, 2307.861528308307, 2310.0644239083113, 2318.246569864784, 2320.7679232076744, 2043.2439997200981, 2244.517428752826, 2355.7644235576477, 2358.0, 2363.054778088761, 10136.94931525345, 11156.0, 11184.0, 11266.267692600037, 11307.0, 5472.671639701631, 5646.604737668365, 5654.773809047633, 5655.0386434306265, 5663.830085097413, 3379.7803142795888, 3490.825458727079, 3656.981715091434, 3682.705383569335, 3697.3529632314508, 1126.3298337489182, 1152.2855829385835, 1217.190568272098, 1226.2213494431012, 1227.957021504252, 13709.0, 13876.444942202299, 14963.700725985529, 15170.089794612359, 15333.463328783439, 4551.8634440966825, 4618.422697162855, 4767.332573439721, 4779.761011949388, 4803.78382972767, 9873.654422095193, 11390.202685811913, 11433.256838305484, 11474.655760327203, 11493.712657183638, 5703.9429605704245, 5783.710814459344, 5789.189513468118, 5797.507211887037, 5814.098814683731, 5156.63903526757, 5236.633435659467, 5303.04545181871, 5304.389995150607, 5361.51746342831, 3783.810809459571, 3810.5236845394325, 3831.6934645228316, 3892.552356479041, 3907.2185562887535, 972.9708108756748, 1038.3717850700368, 1039.5010395010438, 1054.1198099586777, 1054.1988089052375, 914.5564401265381, 936.3071327217809, 943.6649989253854, 953.0945601678433, 992.9453880036523, 5705.801781625905, 6297.1813664223355, 6490.837729056717, 6598.175228096488, 6644.269130395653, 910.9635614575475, 975.23444096384, 979.6522234606755, 990.2028866762181, 1012.0436187802457, 3429.7599168057995, 3954.003228369655, 4000.439938408625, 4125.5874412558505, 4151.896202594899, 12859.742805143846, 13153.21080735159, 13260.336983150804, 13277.402516886763, 13359.53241636538, 12595.937020315023, 13048.804267935988, 13050.955923526095, 13056.020798440155, 13119.344032798323, 8597.441166324294, 9903.752406189758, 10092.899071009197, 10189.949050254701, 10194.592216311265, 5825.3883342248455, 6505.674716264073, 6625.768098116543, 6704.0, 6746.595204287759, 11719.589814356457, 11802.586909458129, 11987.76024479523, 12056.035517158782, 12166.817497737364, 12055.638330850088, 12152.939235303767, 12283.447244874182, 12337.629871103882, 12451.190672606252, 8903.465792052497, 9185.219256363283, 9193.080691930754, 9196.080391961019, 9203.493807840632, 10139.898601013896, 10248.590056397661, 10294.845577316497, 10302.175825934057, 10311.175105991353, 7687.769366919001, 8325.125861784425, 8684.088170741981, 8981.685641002534, 9188.21900138469, 8714.69498567547, 8853.335999800169, 8863.379563430499, 8916.73249802507, 9003.099690031206, 6464.353564643498, 7563.621818908922, 7670.964419803478, 7687.654055567625, 7704.768856934301, 5012.248162775612, 5681.261436013292, 5721.22763426932, 5802.361740208658, 6003.039513677791, 5078.746062696777, 5306.602004849575, 5833.7666493341485, 5986.55100867428, 5987.850303742267], "value": 5038.386770253299, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [353.75767599194546, 368.42709586592855, 373.95886452490237, 388.1944964199287, 392.7604161461509, 4966.279889416037, 5201.453847346031, 5579.9163012554845, 6003.879922401549, 6184.845378865525, 5487.0, 5699.0, 5932.0, 5990.670513121777, 6022.638641681502, 4914.361133052698, 4932.4216250799755, 4941.901161976758, 4942.703437793726, 4954.677945933503, 4783.473817880031, 4902.828401005965, 4913.606911447094, 4928.827491037814, 4949.331840201581, 4046.8381264749373, 4455.4207952966235, 4829.778301981093, 4848.2727590861305, 4898.902021959559, 4880.951190488103, 4894.216925291972, 4912.371881043625, 4914.582260507861, 4918.852434426973, 4310.3103503439615, 4429.146642421082, 4480.215962206634, 4487.19230538503, 4489.977550112261, 5029.2958985742025, 5796.782675638121, 5821.941780582182, 5849.239598852122, 5865.0, 5604.579656525777, 5733.254676892019, 5759.712014399263, 5796.97101514494, 5807.796727114531, 5364.383095943977, 6352.948227467507, 6474.352564743488, 6510.609363438209, 6525.205568468662, 4529.184746745585, 4821.0, 5044.470330615306, 5055.5702765265, 5076.289319495274, 5306.495882891158, 6107.969460152671, 6509.479241660656, 6696.430803381719, 6877.6217308048035, 4667.159911215946, 4885.975570122127, 5270.288511051036, 5309.831836995855, 5315.574754019746, 5048.368953880765, 5093.439721630618, 5150.536451719363, 5394.0, 5431.8098866539485, 5545.916811247835, 5772.9711351442975, 5816.185733997244, 5819.214406055213, 5840.065589505659, 6503.219613646393, 6514.3811337922825, 6530.2041938466355, 6531.738730450728, 6554.573952693059, 1600.8079030516412, 1791.1133988675429, 1813.5012871460453, 1821.3079029968528, 1834.6514162309118, 1183.4319526627266, 1329.5612447892365, 1339.7387509435762, 1352.7767918293569, 1359.456217512983, 5003.924941125886, 5128.0, 5169.510580358243, 5231.1891656792495, 5457.83626491198, 5956.9575324318075, 6743.932560674429, 6761.661916904135, 6774.780539502838, 6792.93207067923, 5877.44164304399, 6175.382461753788, 6239.284196845903, 6293.937060629335, 6350.079238510482, 3119.672434394401, 3570.339487194877, 3583.5341405617105, 3590.0, 3602.9279414411567, 5725.883452726762, 6839.350261725128, 6918.169819621577, 6963.164420269499, 7035.753748618774, 6862.0, 6963.547369420971, 6964.79105626832, 7005.964970175219, 7014.283359062348, 5900.763969441175, 6413.3266007069515, 6421.940379837274, 6664.333566643297, 7079.327463890922, 5168.53483186515, 5808.970955145197, 6788.287229840796, 6799.864002719819, 6802.387785099365, 5141.845744627518, 6508.958566629317, 6524.2823289439075, 6677.966610166918, 6702.36327548882, 7022.683979221049, 7071.186813516511, 7081.4334853210585, 7092.822679433056, 7128.786136415916, 4140.855070072533, 4721.834735784231, 4940.629452790983, 5224.947750522446, 5255.02781985334, 6681.732730690718, 6741.393274605309, 6783.491238157059, 6788.389044985975, 6796.456283497408, 6013.248343957005, 6228.4705800007505, 6259.186305780219, 6309.936900630935, 6557.344265573404, 5324.946750532445, 5475.753591088331, 6471.935280647133, 6515.381038801306, 6544.738210471528, 5593.776698592536, 5907.788903274904, 5934.169216309807, 6158.199434073542, 6418.19772528434, 5898.790747896756, 6048.395160483917, 6361.363863613602, 6419.967900160469, 6569.441597464269, 6117.418845209698, 6388.169537960035, 6388.8083357499345, 6390.392912673288, 6415.294317624965], "value": 4920.193747392902, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [719.9172095209052, 731.7292601737358, 761.5735188294556, 821.712400659769, 830.0993422136982, 14932.552023439288, 15349.923250383732, 15367.923160384182, 15373.77009839213, 15404.306806193721, 14074.507392241274, 14122.505712300072, 14391.2084835334, 14942.402303907858, 15142.848571514282, 890.728327860004, 893.3121496447732, 908.3142227618139, 916.4363916191561, 917.0966597901062, 894.6242578117174, 919.3932004876783, 924.5146298193436, 924.6440120553568, 929.1173385284008, 967.6177909725654, 985.5515740338165, 986.3243678080502, 986.4525188520404, 987.5950860147341, 981.2346369831472, 982.5480279071636, 984.0356450678304, 984.3503287830033, 989.3915242126077, 720.5640587444625, 731.3600599475459, 731.882898736205, 732.7325526182905, 733.8385555177853, 16061.277242524115, 16563.751543726852, 16576.83423165765, 16730.74903876443, 16805.41181058657, 12130.939345303304, 14540.563783086523, 14753.926230368887, 14794.40822367104, 14809.851901480955, 11442.0, 11452.198346115776, 11560.364179970098, 11682.065434765283, 11894.226875253082, 3724.0875985383755, 3741.7567858089137, 3754.4931434256573, 3828.9808550957346, 3888.4556162137324, 97612.0, 99183.0081699181, 109039.0, 109792.0, 110102.44948775205, 10827.78344433107, 11270.211085223955, 11477.827832582518, 11535.538578456935, 11779.823302650468, 22919.541609168056, 23421.648675269884, 23481.0, 23548.175813846778, 23627.700476473616, 7693.6922523099565, 7977.800554986055, 8011.238932301422, 8053.919460805434, 8075.596220188967, 4125.216208920296, 4233.854952736536, 4288.48538175421, 4296.505901821268, 4337.306031035082, 3071.1400807773857, 3450.5686789151355, 3539.805310707936, 3554.7689400188906, 3556.8755093572117, 524.7743470307804, 606.8240210338952, 615.5288859625499, 619.4270299972508, 622.3527531367328, 636.7039326713101, 648.5298158834902, 649.8570314530796, 684.1448189762797, 709.3403135084318, 9934.602615895428, 10004.449755263531, 10092.596296148073, 10264.794704106025, 10330.328528645614, 603.2610052685425, 605.6729366142322, 630.5775130662468, 654.4142992022208, 662.1656712542232, 4892.067698008658, 4898.048433827122, 4923.003745763723, 4941.283513890466, 4948.678335908155, 100018.99981000097, 101488.98511014943, 102616.0, 103187.48406258071, 103529.48235258776, 71657.0, 86529.0, 86849.56575217083, 87151.25697486015, 89598.0, 79471.0, 80448.0, 80971.0, 81463.0, 81683.95790105176, 9494.715158545254, 9527.904720952702, 9594.376365536218, 9604.9039509604, 9649.565769540248, 52463.0, 52560.0, 53358.0, 53560.732196338766, 53572.732136339066, 51211.74394128005, 64850.0, 66404.33595663981, 66899.66550167218, 67073.66463167653, 10077.0, 11685.591004314809, 11781.175317727677, 12150.21023633461, 12179.330136842558, 12556.372181391076, 12638.747225055631, 12660.810087848873, 12872.197892294898, 13208.603741887759, 11569.942150289195, 11785.941070294937, 12070.939645301718, 12155.353081942387, 12303.261804291771, 11809.199216706087, 11828.34944078084, 11943.0, 11963.0, 12113.460010692617, 11461.197716159788, 11536.677456843598, 11605.535778568763, 11615.651530453762, 12324.198927069712, 9923.454210018519, 10099.94950025245, 10131.64539241123, 10264.0, 10476.895231047592, 8525.0, 8596.968363796384, 9484.668036618685, 9949.900500995187, 9973.252006099427], "value": 7428.814586056268, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1593.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 122.0, 120.0, 120.0, 122.0, 122.0, 121.0, 119.0, 118.0, 122.0], "value": 119.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 110.0, 133.0, 153.0, 118.0, 116.0, 116.0, 118.0, 138.0, 115.0], "value": 126.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 199.0, 217.0, 231.0, 265.0, 281.0, 196.0, 203.0, 224.0, 284.0], "value": 229.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 85.0, 58.0, 95.0, 84.0, 85.0, 50.0, 63.0, 100.0, 86.0], "value": 78.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 68.0, 72.0, 70.0, 82.0, 100.0, 73.0, 80.0, 62.0, 78.0], "value": 77.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 91.0, 112.0, 135.0, 91.0, 90.0, 91.0, 82.0, 132.0, 91.0], "value": 101.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 108.0, 101.0, 99.0, 98.0, 98.0, 98.0, 99.0, 97.0, 105.0], "value": 105.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 94.0, 92.0, 92.0, 85.0, 89.0, 91.0, 90.0, 139.0, 90.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 67.0, 67.0, 69.0, 79.0, 101.0, 102.0, 101.0, 81.0], "value": 80.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 68.0, 67.0, 94.0, 90.0, 85.0, 84.0, 84.0, 59.0, 59.0], "value": 74.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 84.0, 83.0, 84.0, 84.0, 85.0, 87.0, 84.0, 82.0], "value": 84.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 144.0, 139.0, 139.0, 141.0, 141.0, 142.0, 137.0, 182.0, 146.0], "value": 145.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 187.0, 197.0, 219.0, 191.0, 195.0, 237.0, 228.0, 190.0, 185.0], "value": 201.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 72.0, 69.0, 64.0, 91.0, 90.0, 75.0, 70.0, 69.0, 69.0], "value": 73.3, "unit": "score"}]}]} 04:56:45 INFO - Return code: 0 04:56:45 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:56:45 ERROR - # TBPL SUCCESS # 04:56:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:56:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:56:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:56:45 INFO - 2017-03-20 04:56:45.366 screenresolution[1659:10752] starting screenresolution argv=screenresolution get 04:56:45 INFO - 2017-03-20 04:56:45.369 screenresolution[1659:10752] Display 0: 1600x1200x32@60 04:56:45 INFO - 2017-03-20 04:56:45.375 screenresolution[1660:10755] starting screenresolution argv=screenresolution list 04:56:45 INFO - Available Modes on Display 0 04:56:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:56:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:56:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:56:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:56:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:56:45 INFO - Intel Iris: 04:56:45 INFO - Chipset Model: Intel Iris 04:56:45 INFO - Type: GPU 04:56:45 INFO - Bus: Built-In 04:56:45 INFO - VRAM (Dynamic, Max): 1536 MB 04:56:45 INFO - Vendor: Intel (0x8086) 04:56:45 INFO - Device ID: 0x0a2e 04:56:45 INFO - Revision ID: 0x0009 04:56:45 INFO - Displays: 04:56:45 INFO - Display: 04:56:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:56:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:56:45 INFO - Display Serial Number: 1600x1200 60 04:56:45 INFO - Main Display: Yes 04:56:45 INFO - Mirror: Off 04:56:45 INFO - Online: Yes 04:56:45 INFO - Rotation: Supported 04:56:45 INFO - Return code: 0 04:56:45 INFO - Running post-action listener: _resource_record_post_action 04:56:45 INFO - [mozharness: 2017-03-20 11:56:45.618439Z] Finished run-tests step (success) 04:56:45 INFO - Running post-run listener: _resource_record_post_run 04:56:45 INFO - Total resource usage - Wall time: 1199s; CPU: 54.0%; Read bytes: 41097216; Write bytes: 515247104; Read time: 400; Write time: 2121 04:56:45 INFO - TinderboxPrint: CPU usage
54.5% 04:56:45 INFO - TinderboxPrint: I/O read bytes / time
41,097,216 / 400 04:56:45 INFO - TinderboxPrint: I/O write bytes / time
515,247,104 / 2,121 04:56:45 INFO - TinderboxPrint: CPU idle
2,184.1 (45.5%) 04:56:45 INFO - TinderboxPrint: CPU system
968.1 (20.2%) 04:56:45 INFO - TinderboxPrint: CPU user
1,645.5 (34.3%) 04:56:45 INFO - TinderboxPrint: Swap in / out
250,671,104 / 0 04:56:45 INFO - install - Wall time: 21s; CPU: 55.0%; Read bytes: 208128512; Write bytes: 201461760; Read time: 15729; Write time: 373 04:56:45 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 34791424; Write bytes: 303836160; Read time: 319; Write time: 1716 04:56:46 INFO - Running post-run listener: _upload_blobber_files 04:56:46 INFO - Blob upload gear active. 04:56:46 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:56:46 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 04:56:46 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'] 04:56:46 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 04:56:46 INFO - (blobuploader) - INFO - Open directory for files ... 04:56:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:56:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:56:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:56:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:56:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:56:47 INFO - (blobuploader) - INFO - Done attempting. 04:56:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:56:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:56:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:56:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 04:56:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:56:48 INFO - (blobuploader) - INFO - Done attempting. 04:56:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:56:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:56:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:56:49 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:56:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:56:49 INFO - (blobuploader) - INFO - Done attempting. 04:56:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:56:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:56:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:56:52 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:56:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:56:52 INFO - (blobuploader) - INFO - Done attempting. 04:56:52 INFO - (blobuploader) - INFO - Iteration through files over. 04:56:52 INFO - Return code: 0 04:56:52 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:56:52 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:56:52 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/edcbc668b4a4704520f8c553bf491e4e79ff35e7b3e0561b4c670fa27d3e1fb89bd41495e04f332568ea612a0b6f2426347ee06d2f1e1207fe56f55df50fac88", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/16b1d5efb0b874c417664f016784f2e589ec7b64a6f76e1d0bc8e3010b9710eccdd6ca4cf95620532ae51cbc27974ca8b4189de157410c438c80ebf1ae4c632a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fd5a3bae9428432b123f2d2b201c46eed1e9f46f5e8850322b7546c4ae8b61ff2b335081e22fcdeec163983851a497b2e6c72dd80cb49261306a9c48bbd56b3a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1f57135190e40c4716d5736abd3d5e4c65f234ce3b7a61efadd33cf34c5c06025d27b6ec9f8bc415c4ed8072b773cb16fcb59a98f23c78021cca9ebcc37a5074"} 04:56:52 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:56:52 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:56:52 INFO - Contents: 04:56:52 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/edcbc668b4a4704520f8c553bf491e4e79ff35e7b3e0561b4c670fa27d3e1fb89bd41495e04f332568ea612a0b6f2426347ee06d2f1e1207fe56f55df50fac88", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/16b1d5efb0b874c417664f016784f2e589ec7b64a6f76e1d0bc8e3010b9710eccdd6ca4cf95620532ae51cbc27974ca8b4189de157410c438c80ebf1ae4c632a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fd5a3bae9428432b123f2d2b201c46eed1e9f46f5e8850322b7546c4ae8b61ff2b335081e22fcdeec163983851a497b2e6c72dd80cb49261306a9c48bbd56b3a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1f57135190e40c4716d5736abd3d5e4c65f234ce3b7a61efadd33cf34c5c06025d27b6ec9f8bc415c4ed8072b773cb16fcb59a98f23c78021cca9ebcc37a5074"} 04:56:52 INFO - Running post-run listener: copy_logs_to_upload_dir 04:56:52 INFO - Copying logs to upload dir... 04:56:52 INFO - mkdir: /builds/slave/test/build/upload/logs 04:56:52 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1304.733834 ========= master_lag: 0.18 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 44 secs) (at 2017-03-20 04:56:52.396319) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-20 04:56:52.399656) ========= 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.0lrExIFjWJ/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.3JUJ3O9Jln/Listeners TMPDIR=/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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/edcbc668b4a4704520f8c553bf491e4e79ff35e7b3e0561b4c670fa27d3e1fb89bd41495e04f332568ea612a0b6f2426347ee06d2f1e1207fe56f55df50fac88", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/16b1d5efb0b874c417664f016784f2e589ec7b64a6f76e1d0bc8e3010b9710eccdd6ca4cf95620532ae51cbc27974ca8b4189de157410c438c80ebf1ae4c632a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fd5a3bae9428432b123f2d2b201c46eed1e9f46f5e8850322b7546c4ae8b61ff2b335081e22fcdeec163983851a497b2e6c72dd80cb49261306a9c48bbd56b3a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1f57135190e40c4716d5736abd3d5e4c65f234ce3b7a61efadd33cf34c5c06025d27b6ec9f8bc415c4ed8072b773cb16fcb59a98f23c78021cca9ebcc37a5074"} build_url:https://queue.taskcluster.net/v1/task/cu0MMLqFTw-tkoKsZavL5Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011218 build_url: 'https://queue.taskcluster.net/v1/task/cu0MMLqFTw-tkoKsZavL5Q/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/edcbc668b4a4704520f8c553bf491e4e79ff35e7b3e0561b4c670fa27d3e1fb89bd41495e04f332568ea612a0b6f2426347ee06d2f1e1207fe56f55df50fac88", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/16b1d5efb0b874c417664f016784f2e589ec7b64a6f76e1d0bc8e3010b9710eccdd6ca4cf95620532ae51cbc27974ca8b4189de157410c438c80ebf1ae4c632a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fd5a3bae9428432b123f2d2b201c46eed1e9f46f5e8850322b7546c4ae8b61ff2b335081e22fcdeec163983851a497b2e6c72dd80cb49261306a9c48bbd56b3a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1f57135190e40c4716d5736abd3d5e4c65f234ce3b7a61efadd33cf34c5c06025d27b6ec9f8bc415c4ed8072b773cb16fcb59a98f23c78021cca9ebcc37a5074"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-20 04:56:52.437722) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 04:56:52.438078) ========= 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.0lrExIFjWJ/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.3JUJ3O9Jln/Listeners TMPDIR=/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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.005234 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-20 04:56:52.488429) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-20 04:56:52.488902) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-20 04:56:52.489424) ========= ========= Total master_lag: 1.43 =========