builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0272 starttime: 1490874241.87 results: success (0) buildid: 20170330030213 builduid: 1515308678f04286ab5bbb5e8901ef1f revision: 60d7a0496a3673450ddbc37ec387525148c32604 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-30 04:44:01.865473) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-30 04:44:01.865924) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-30 04:44:01.866224) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-30 04:44:01.892532) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 04:44:01.892866) ========= 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.5YAaPzz5IK/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.UYlVaK9ZZq/Listeners TMPDIR=/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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-30 04:44:01-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 142M=0s 2017-03-30 04:44:02 (142 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.178859 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 04:44:02.095951) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 04:44:02.096315) ========= 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.5YAaPzz5IK/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.UYlVaK9ZZq/Listeners TMPDIR=/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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.040231 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 04:44:02.159892) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-30 04:44:02.160295) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 60d7a0496a3673450ddbc37ec387525148c32604 --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 60d7a0496a3673450ddbc37ec387525148c32604 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.5YAaPzz5IK/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.UYlVaK9ZZq/Listeners TMPDIR=/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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-30 04:44:02,280 truncating revision to first 12 chars 2017-03-30 04:44:02,281 Setting DEBUG logging. 2017-03-30 04:44:02,281 attempt 1/10 2017-03-30 04:44:02,281 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/60d7a0496a36?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-30 04:44:03,311 unpacking tar archive at: mozilla-central-60d7a0496a36/testing/mozharness/ program finished with exit code 0 elapsedTime=1.302960 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-30 04:44:03.487006) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-30 04:44:03.487349) ========= script_repo_revision: 60d7a0496a3673450ddbc37ec387525148c32604 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-30 04:44:03.487756) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-30 04:44:03.488029) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-30 04:44:03.507839) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 29 secs) (at 2017-03-30 04:44:03.508246) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.5YAaPzz5IK/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.UYlVaK9ZZq/Listeners TMPDIR=/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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:44:03 INFO - MultiFileLogger online at 20170330 04:44:03 in /builds/slave/test 04:44:03 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 04:44:03 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:44:03 INFO - {'append_to_log': False, 04:44:03 INFO - 'base_work_dir': '/builds/slave/test', 04:44:03 INFO - 'blob_upload_branch': 'Firefox', 04:44:03 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:44:03 INFO - 'branch': 'Firefox', 04:44:03 INFO - 'buildbot_json_path': 'buildprops.json', 04:44:03 INFO - 'config_files': ('talos/mac_config.py',), 04:44:03 INFO - 'default_actions': ('clobber', 04:44:03 INFO - 'read-buildbot-config', 04:44:03 INFO - 'download-and-extract', 04:44:03 INFO - 'populate-webroot', 04:44:03 INFO - 'create-virtualenv', 04:44:03 INFO - 'install', 04:44:03 INFO - 'run-tests'), 04:44:03 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:44:03 INFO - 'download_minidump_stackwalk': True, 04:44:03 INFO - 'download_symbols': 'ondemand', 04:44:03 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:44:03 INFO - 'tooltool.py': '/tools/tooltool.py', 04:44:03 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:44:03 INFO - '/tools/misc-python/virtualenv.py')}, 04:44:03 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:44:03 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:44:03 INFO - 'gecko_profile': False, 04:44:03 INFO - 'gecko_profile_interval': 0, 04:44:03 INFO - 'installer_path': 'installer.exe', 04:44:03 INFO - 'log_level': 'info', 04:44:03 INFO - 'log_name': 'talos', 04:44:03 INFO - 'log_to_console': True, 04:44:03 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:44:03 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:44:03 INFO - 'opt_config_files': (), 04:44:03 INFO - 'pip_index': False, 04:44:03 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:44:03 INFO - 'cmd': ('bash', 04:44:03 INFO - '-c', 04:44:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:44:03 INFO - 'enabled': True, 04:44:03 INFO - 'halt_on_failure': False, 04:44:03 INFO - 'name': 'check_screen_resolution'},), 04:44:03 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:44:03 INFO - 'cmd': ('bash', 04:44:03 INFO - '-c', 04:44:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:44:03 INFO - 'enabled': True, 04:44:03 INFO - 'halt_on_failure': False, 04:44:03 INFO - 'name': 'check_screen_resolution'},), 04:44:03 INFO - 'run_cmd_checks_enabled': True, 04:44:03 INFO - 'suite': 'dromaeojs', 04:44:03 INFO - 'system_bits': '32', 04:44:03 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:44:03 INFO - 'title': 't-yosemite-r7-0272', 04:44:03 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:44:03 INFO - 'use_talos_json': True, 04:44:03 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:44:03 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:44:03 INFO - 'work_dir': 'build'} 04:44:03 INFO - [mozharness: 2017-03-30 11:44:03.731332Z] Running clobber step. 04:44:03 INFO - Running pre-action listener: _resource_record_pre_action 04:44:03 INFO - Running main action method: clobber 04:44:03 INFO - rmtree: /builds/slave/test/build 04:44:03 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:44:06 INFO - Running post-action listener: _resource_record_post_action 04:44:06 INFO - [mozharness: 2017-03-30 11:44:06.204468Z] Finished clobber step (success) 04:44:06 INFO - [mozharness: 2017-03-30 11:44:06.204593Z] Running read-buildbot-config step. 04:44:06 INFO - Running pre-action listener: _resource_record_pre_action 04:44:06 INFO - Running main action method: read_buildbot_config 04:44:06 INFO - Using buildbot properties: 04:44:06 INFO - { 04:44:06 INFO - "project": "", 04:44:06 INFO - "product": "firefox", 04:44:06 INFO - "script_repo_revision": "production", 04:44:06 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 04:44:06 INFO - "repository": "", 04:44:06 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 04:44:06 INFO - "buildid": "20170330030213", 04:44:06 INFO - "stage_platform": "macosx64", 04:44:06 INFO - "basedir": "/builds/slave/test", 04:44:06 INFO - "nightly_build": "True", 04:44:06 INFO - "buildnumber": 12, 04:44:06 INFO - "slavename": "t-yosemite-r7-0272", 04:44:06 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 04:44:06 INFO - "platform": "yosemite_r7", 04:44:06 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 04:44:06 INFO - "branch": "mozilla-central", 04:44:06 INFO - "repo_path": "mozilla-central", 04:44:06 INFO - "revision": "60d7a0496a3673450ddbc37ec387525148c32604", 04:44:06 INFO - "pgo_build": "False", 04:44:06 INFO - "builduid": "1515308678f04286ab5bbb5e8901ef1f", 04:44:06 INFO - "slavebuilddir": "test" 04:44:06 INFO - } 04:44:06 INFO - Found installer url https://queue.taskcluster.net/v1/task/NT-b7EMFRvKwTQPTzQrgZw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 04:44:06 INFO - Running post-action listener: _resource_record_post_action 04:44:06 INFO - [mozharness: 2017-03-30 11:44:06.208027Z] Finished read-buildbot-config step (success) 04:44:06 INFO - [mozharness: 2017-03-30 11:44:06.208172Z] Running download-and-extract step. 04:44:06 INFO - Running pre-action listener: _resource_record_pre_action 04:44:06 INFO - Running main action method: download_and_extract 04:44:06 INFO - mkdir: /builds/slave/test/build/tests 04:44: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:44:06 INFO - trying https://queue.taskcluster.net/v1/task/NT-b7EMFRvKwTQPTzQrgZw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 04:44:06 INFO - Downloading https://queue.taskcluster.net/v1/task/NT-b7EMFRvKwTQPTzQrgZw/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:44:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NT-b7EMFRvKwTQPTzQrgZw/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:44:07 INFO - Downloaded 1325 bytes. 04:44:07 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 04:44:07 INFO - Using the following test package requirements: 04:44:07 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:44:07 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 04:44:07 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 04:44:07 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:44:07 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 04:44:07 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:44:07 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 04:44:07 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:44:07 INFO - u'jsshell-mac.zip'], 04:44:07 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:44:07 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 04:44:07 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 04:44:07 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:44:07 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 04:44:07 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:44:07 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 04:44:07 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:44:07 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 04:44:07 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:44:07 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 04:44:07 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:44:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NT-b7EMFRvKwTQPTzQrgZw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 04:44:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NT-b7EMFRvKwTQPTzQrgZw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:44:07 INFO - Fetch https://queue.taskcluster.net/v1/task/NT-b7EMFRvKwTQPTzQrgZw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 04:44:09 INFO - Expected file size: 52505186 04:44:09 INFO - Obtained file size: 52505186 04:44:25 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:44:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NT-b7EMFRvKwTQPTzQrgZw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 04:44:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NT-b7EMFRvKwTQPTzQrgZw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:44:25 INFO - Fetch https://queue.taskcluster.net/v1/task/NT-b7EMFRvKwTQPTzQrgZw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 04:44:27 INFO - Expected file size: 52505186 04:44:27 INFO - Obtained file size: 52505186 04:44:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NT-b7EMFRvKwTQPTzQrgZw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 04:44:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NT-b7EMFRvKwTQPTzQrgZw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:44:42 INFO - Fetch https://queue.taskcluster.net/v1/task/NT-b7EMFRvKwTQPTzQrgZw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 04:44:44 INFO - Expected file size: 14404171 04:44:44 INFO - Obtained file size: 14404171 04:44: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:44:44 INFO - trying https://queue.taskcluster.net/v1/task/NT-b7EMFRvKwTQPTzQrgZw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:44:44 INFO - Downloading https://queue.taskcluster.net/v1/task/NT-b7EMFRvKwTQPTzQrgZw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 04:44:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NT-b7EMFRvKwTQPTzQrgZw/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:44:46 INFO - Downloaded 75636347 bytes. 04:44:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NT-b7EMFRvKwTQPTzQrgZw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:44:46 INFO - mkdir: /builds/slave/test/properties 04:44:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:44:46 INFO - Writing to file /builds/slave/test/properties/build_url 04:44:46 INFO - Contents: 04:44:46 INFO - build_url:https://queue.taskcluster.net/v1/task/NT-b7EMFRvKwTQPTzQrgZw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:44:47 INFO - Running post-action listener: _resource_record_post_action 04:44:47 INFO - Running post-action listener: set_extra_try_arguments 04:44:47 INFO - [mozharness: 2017-03-30 11:44:47.282163Z] Finished download-and-extract step (success) 04:44:47 INFO - [mozharness: 2017-03-30 11:44:47.282292Z] Running populate-webroot step. 04:44:47 INFO - Running pre-action listener: _resource_record_pre_action 04:44:47 INFO - Running main action method: populate_webroot 04:44:47 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:44:47 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:44:47 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:44:47 INFO - '--tppagecycles', 04:44:47 INFO - '7'], 04:44:47 INFO - 'tests': ['tsvgm']}}, 04:44:47 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:44:47 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:44:47 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:44:47 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:44:47 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:44:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:44:47 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:44:47 INFO - 'talos_options': ['--disable-e10s'], 04:44:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:44:47 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:44:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:44:47 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:44:47 INFO - 'talos_options': ['--disable-e10s'], 04:44:47 INFO - 'tests': ['damp', 'tps']}, 04:44:47 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:44:47 INFO - 'tests': ['damp', 'tps']}, 04:44:47 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:44:47 INFO - 'tests': ['dromaeo_dom']}, 04:44:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:44:47 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:44:47 INFO - 'tests': ['basic_compositor_video']}, 04:44:47 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:44:47 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:44:47 INFO - 'tests': ['a11yr', 04:44:47 INFO - 'ts_paint', 04:44:47 INFO - 'tpaint', 04:44:47 INFO - 'sessionrestore', 04:44:47 INFO - 'sessionrestore_no_auto_restore', 04:44:47 INFO - 'tabpaint']}, 04:44:47 INFO - 'other-e10s': {'tests': ['a11yr', 04:44:47 INFO - 'ts_paint', 04:44:47 INFO - 'tpaint', 04:44:47 INFO - 'sessionrestore', 04:44:47 INFO - 'sessionrestore_no_auto_restore', 04:44:47 INFO - 'tabpaint']}, 04:44:47 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:44:47 INFO - 'ts_paint', 04:44:47 INFO - 'tpaint', 04:44:47 INFO - 'sessionrestore', 04:44:47 INFO - 'sessionrestore_no_auto_restore', 04:44:47 INFO - 'tabpaint']}, 04:44:47 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:44:47 INFO - 'ts_paint', 04:44:47 INFO - 'tpaint', 04:44:47 INFO - 'sessionrestore', 04:44:47 INFO - 'sessionrestore_no_auto_restore', 04:44:47 INFO - 'tabpaint']}, 04:44:47 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:44:47 INFO - 'tests': ['a11yr', 04:44:47 INFO - 'ts_paint', 04:44:47 INFO - 'tpaint', 04:44:47 INFO - 'sessionrestore', 04:44:47 INFO - 'sessionrestore_no_auto_restore', 04:44:47 INFO - 'tabpaint']}, 04:44:47 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:44:47 INFO - 'tests': ['a11yr', 04:44:47 INFO - 'ts_paint', 04:44:47 INFO - 'tpaint', 04:44:47 INFO - 'sessionrestore', 04:44:47 INFO - 'sessionrestore_no_auto_restore', 04:44:47 INFO - 'tabpaint']}, 04:44:47 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:44:47 INFO - 'tests': ['tsvgx', 04:44:47 INFO - 'tsvgr_opacity', 04:44:47 INFO - 'tart', 04:44:47 INFO - 'tscrollx', 04:44:47 INFO - 'cart', 04:44:47 INFO - 'tsvg_static']}, 04:44:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:44:47 INFO - 'tsvgr_opacity', 04:44:47 INFO - 'tart', 04:44:47 INFO - 'tscrollx', 04:44:47 INFO - 'cart', 04:44:47 INFO - 'tsvg_static']}, 04:44:47 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:44:47 INFO - 'talos_options': ['--disable-e10s'], 04:44:47 INFO - 'tests': ['tp5o']}, 04:44:47 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:44:47 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:44:47 INFO - 'talos_options': ['--disable-e10s', 04:44:47 INFO - '--xperf_path', 04:44:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:44:47 INFO - 'tests': ['tp5n']}, 04:44:47 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:44:47 INFO - 'talos_options': ['--xperf_path', 04:44:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:44:47 INFO - 'tests': ['tp5n']}}, 04:44:47 INFO - 'talos.zip': {'path': '', 04:44:47 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:44:47 INFO - Running post-action listener: _resource_record_post_action 04:44:47 INFO - [mozharness: 2017-03-30 11:44:47.300837Z] Finished populate-webroot step (success) 04:44:47 INFO - [mozharness: 2017-03-30 11:44:47.300971Z] Running create-virtualenv step. 04:44:47 INFO - Running pre-action listener: _resource_record_pre_action 04:44:47 INFO - Running main action method: create_virtualenv 04:44:47 INFO - Creating virtualenv /builds/slave/test/build/venv 04:44:47 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:44:47 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:44:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:44:47 INFO - Using real prefix '/tools/python27' 04:44:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:44:48 INFO - Installing distribute.............................................................................................................................................................................................done. 04:44:51 INFO - Installing pip.................done. 04:44:51 INFO - Return code: 0 04:44:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:44:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:44:51 INFO - Reading from file tmpfile_stdout 04:44:51 INFO - Output received: 04:44:51 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:44:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:44:51 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:44:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:44:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:44:51 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:44:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:44:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:44:51 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 0x104fc2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104fc5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99994f6880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104da0d30>, '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 0x104cc7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104d95be8>, '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.5YAaPzz5IK/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.UYlVaK9ZZq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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:44:51 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:44:51 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:44:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5YAaPzz5IK/Render', 04:44:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:44:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:44:51 INFO - 'HOME': '/Users/cltbld', 04:44:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:44:51 INFO - 'LOGNAME': 'cltbld', 04:44:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:44:51 INFO - 'MOZ_NO_REMOTE': '1', 04:44:51 INFO - 'NO_EM_RESTART': '1', 04:44:51 INFO - 'PAGER': '/bin/cat', 04:44:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:44:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:44:51 INFO - 'PWD': '/builds/slave/test', 04:44:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:44:51 INFO - 'SHELL': '/bin/bash', 04:44:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UYlVaK9ZZq/Listeners', 04:44:51 INFO - 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/', 04:44:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:44:51 INFO - 'USER': 'cltbld', 04:44:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:44:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:44:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:44:51 INFO - 'XPC_FLAGS': '0x0', 04:44:51 INFO - 'XPC_SERVICE_NAME': '0', 04:44:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:44:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:44:51 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:44:51 INFO - Cleaning up... 04:44:51 INFO - Return code: 0 04:44:51 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:44:51 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:44:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:44:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:44:51 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:44:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:44:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:44:51 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 0x104fc2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104fc5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99994f6880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104da0d30>, '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 0x104cc7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104d95be8>, '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.5YAaPzz5IK/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.UYlVaK9ZZq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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:44:51 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:44:51 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:44:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5YAaPzz5IK/Render', 04:44:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:44:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:44:51 INFO - 'HOME': '/Users/cltbld', 04:44:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:44:51 INFO - 'LOGNAME': 'cltbld', 04:44:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:44:51 INFO - 'MOZ_NO_REMOTE': '1', 04:44:51 INFO - 'NO_EM_RESTART': '1', 04:44:51 INFO - 'PAGER': '/bin/cat', 04:44:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:44:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:44:51 INFO - 'PWD': '/builds/slave/test', 04:44:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:44:51 INFO - 'SHELL': '/bin/bash', 04:44:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UYlVaK9ZZq/Listeners', 04:44:51 INFO - 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/', 04:44:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:44:51 INFO - 'USER': 'cltbld', 04:44:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:44:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:44:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:44:51 INFO - 'XPC_FLAGS': '0x0', 04:44:51 INFO - 'XPC_SERVICE_NAME': '0', 04:44:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:44:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:44:51 INFO - Downloading/unpacking psutil>=3.1.1 04:44:51 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:44:51 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:44:51 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:44:51 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:44:51 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:44:51 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:44:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:44:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:44:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:44:55 INFO - Installing collected packages: psutil 04:44:55 INFO - Running setup.py install for psutil 04:44:55 INFO - building 'psutil._psutil_osx' extension 04:44:55 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:44:56 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:44:56 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:44:56 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:44:56 INFO - building 'psutil._psutil_posix' extension 04:44:56 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:44:56 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:44:56 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:44:56 INFO - ^ 04:44:56 INFO - 1 warning generated. 04:44:56 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:44:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:44:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:44:56 INFO - Successfully installed psutil 04:44:56 INFO - Cleaning up... 04:44:56 INFO - Return code: 0 04:44:56 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:44:56 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:44:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:44:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:44:56 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:44:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:44:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:44:56 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 0x104fc2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104fc5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99994f6880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104da0d30>, '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 0x104cc7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104d95be8>, '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.5YAaPzz5IK/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.UYlVaK9ZZq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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:44:56 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:44:56 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:44:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5YAaPzz5IK/Render', 04:44:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:44:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:44:56 INFO - 'HOME': '/Users/cltbld', 04:44:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:44:56 INFO - 'LOGNAME': 'cltbld', 04:44:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:44:56 INFO - 'MOZ_NO_REMOTE': '1', 04:44:56 INFO - 'NO_EM_RESTART': '1', 04:44:56 INFO - 'PAGER': '/bin/cat', 04:44:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:44:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:44:56 INFO - 'PWD': '/builds/slave/test', 04:44:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:44:56 INFO - 'SHELL': '/bin/bash', 04:44:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UYlVaK9ZZq/Listeners', 04:44:56 INFO - 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/', 04:44:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:44:56 INFO - 'USER': 'cltbld', 04:44:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:44:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:44:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:44:56 INFO - 'XPC_FLAGS': '0x0', 04:44:56 INFO - 'XPC_SERVICE_NAME': '0', 04:44:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:44:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:44:56 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:44:56 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:44:56 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:44:56 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:44:56 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:44:56 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:44:56 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:44:59 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:44:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:44:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:44:59 INFO - Installing collected packages: mozsystemmonitor 04:44:59 INFO - Running setup.py install for mozsystemmonitor 04:44:59 INFO - Successfully installed mozsystemmonitor 04:44:59 INFO - Cleaning up... 04:44:59 INFO - Return code: 0 04:44:59 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:44:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:44:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45: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:45:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45: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', '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 0x104fc2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104fc5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99994f6880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104da0d30>, '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 0x104cc7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104d95be8>, '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.5YAaPzz5IK/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.UYlVaK9ZZq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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:45: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 04:45: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 jsonschema==2.5.1 04:45:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5YAaPzz5IK/Render', 04:45:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:45:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:45:00 INFO - 'HOME': '/Users/cltbld', 04:45:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:45:00 INFO - 'LOGNAME': 'cltbld', 04:45:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:00 INFO - 'MOZ_NO_REMOTE': '1', 04:45:00 INFO - 'NO_EM_RESTART': '1', 04:45:00 INFO - 'PAGER': '/bin/cat', 04:45:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:45:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:00 INFO - 'PWD': '/builds/slave/test', 04:45:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:45:00 INFO - 'SHELL': '/bin/bash', 04:45:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UYlVaK9ZZq/Listeners', 04:45:00 INFO - 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/', 04:45:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:45:00 INFO - 'USER': 'cltbld', 04:45:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:45:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:45:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:00 INFO - 'XPC_FLAGS': '0x0', 04:45:00 INFO - 'XPC_SERVICE_NAME': '0', 04:45:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:45:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:00 INFO - Downloading/unpacking jsonschema==2.5.1 04:45:00 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:45:00 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:45:00 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:45:00 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:45:00 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:45:00 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:45:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:45:03 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:45:03 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:45:03 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:45:03 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:45:03 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:45:03 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:45:03 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:45:04 INFO - Downloading functools32-3.2.3-2.tar.gz 04:45:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:45:04 INFO - warning: no files found matching '*.txt' 04:45:04 INFO - no previously-included directories found matching 'build' 04:45:04 INFO - no previously-included directories found matching 'dist' 04:45:04 INFO - no previously-included directories found matching '.git*' 04:45:04 INFO - Installing collected packages: jsonschema, functools32 04:45:04 INFO - Running setup.py install for functools32 04:45:04 INFO - warning: no files found matching '*.txt' 04:45:04 INFO - no previously-included directories found matching 'build' 04:45:04 INFO - no previously-included directories found matching 'dist' 04:45:04 INFO - no previously-included directories found matching '.git*' 04:45:04 INFO - Successfully installed jsonschema functools32 04:45:04 INFO - Cleaning up... 04:45:04 INFO - Return code: 0 04:45:04 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:45:04 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:45:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:04 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:45:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:04 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 0x104fc2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104fc5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99994f6880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104da0d30>, '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 0x104cc7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104d95be8>, '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.5YAaPzz5IK/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.UYlVaK9ZZq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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:45:04 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:45:04 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:45:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5YAaPzz5IK/Render', 04:45:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:45:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:45:04 INFO - 'HOME': '/Users/cltbld', 04:45:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:45:04 INFO - 'LOGNAME': 'cltbld', 04:45:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:04 INFO - 'MOZ_NO_REMOTE': '1', 04:45:04 INFO - 'NO_EM_RESTART': '1', 04:45:04 INFO - 'PAGER': '/bin/cat', 04:45:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:45:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:04 INFO - 'PWD': '/builds/slave/test', 04:45:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:45:04 INFO - 'SHELL': '/bin/bash', 04:45:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UYlVaK9ZZq/Listeners', 04:45:04 INFO - 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/', 04:45:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:45:04 INFO - 'USER': 'cltbld', 04:45:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:45:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:45:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:04 INFO - 'XPC_FLAGS': '0x0', 04:45:04 INFO - 'XPC_SERVICE_NAME': '0', 04:45:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:45:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:04 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:45:04 INFO - Cleaning up... 04:45:04 INFO - Return code: 0 04:45:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:45:04 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:45:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:04 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:45:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:04 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 0x104fc2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104fc5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99994f6880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104da0d30>, '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 0x104cc7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104d95be8>, '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.5YAaPzz5IK/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.UYlVaK9ZZq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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:45:04 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:45:04 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:45:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5YAaPzz5IK/Render', 04:45:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:45:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:45:04 INFO - 'HOME': '/Users/cltbld', 04:45:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:45:04 INFO - 'LOGNAME': 'cltbld', 04:45:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:04 INFO - 'MOZ_NO_REMOTE': '1', 04:45:04 INFO - 'NO_EM_RESTART': '1', 04:45:04 INFO - 'PAGER': '/bin/cat', 04:45:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:45:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:04 INFO - 'PWD': '/builds/slave/test', 04:45:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:45:04 INFO - 'SHELL': '/bin/bash', 04:45:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UYlVaK9ZZq/Listeners', 04:45:04 INFO - 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/', 04:45:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:45:04 INFO - 'USER': 'cltbld', 04:45:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:45:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:45:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:04 INFO - 'XPC_FLAGS': '0x0', 04:45:04 INFO - 'XPC_SERVICE_NAME': '0', 04:45:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:45:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:04 INFO - Downloading/unpacking blobuploader==1.2.4 04:45:04 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:45:04 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:45:04 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:45:04 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:45:04 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:45:04 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:45:07 INFO - Downloading blobuploader-1.2.4.tar.gz 04:45:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:45:07 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:45:07 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:45:07 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:45:07 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:45:07 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:45:07 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:45:07 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:45:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:45:08 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:45:08 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:45:08 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:45:08 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:45:08 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:45:08 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:45:08 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:45:08 INFO - Downloading docopt-0.6.1.tar.gz 04:45:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:45:08 INFO - Installing collected packages: blobuploader, requests, docopt 04:45:08 INFO - Running setup.py install for blobuploader 04:45:09 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:45:09 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:45:09 INFO - Running setup.py install for requests 04:45:09 INFO - Running setup.py install for docopt 04:45:09 INFO - Successfully installed blobuploader requests docopt 04:45:09 INFO - Cleaning up... 04:45:09 INFO - Return code: 0 04:45:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:45: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:45:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45: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:45:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:09 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 0x104fc2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104fc5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99994f6880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104da0d30>, '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 0x104cc7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104d95be8>, '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.5YAaPzz5IK/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.UYlVaK9ZZq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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:45:09 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:45:09 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:45:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5YAaPzz5IK/Render', 04:45:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:45:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:45:09 INFO - 'HOME': '/Users/cltbld', 04:45:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:45:09 INFO - 'LOGNAME': 'cltbld', 04:45:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:09 INFO - 'MOZ_NO_REMOTE': '1', 04:45:09 INFO - 'NO_EM_RESTART': '1', 04:45:09 INFO - 'PAGER': '/bin/cat', 04:45:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:45:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:09 INFO - 'PWD': '/builds/slave/test', 04:45:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:45:09 INFO - 'SHELL': '/bin/bash', 04:45:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UYlVaK9ZZq/Listeners', 04:45:09 INFO - 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/', 04:45:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:45:09 INFO - 'USER': 'cltbld', 04:45:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:45:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:45:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:09 INFO - 'XPC_FLAGS': '0x0', 04:45:09 INFO - 'XPC_SERVICE_NAME': '0', 04:45:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:45:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:45:10 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-A3XpeQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:45:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:45:10 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-HxroDC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:45:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:45:10 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-_mAFn6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:45:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:45:10 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-v5dnSM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:45:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:45:10 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-gK8AJi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:45:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:45:10 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-oBH4xa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:45:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:45:10 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-gtSeeQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:45:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:45:10 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-vuxGdp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:45:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:45:10 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-fCTEn0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:45:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:45:11 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-K67vu6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:45:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:45:11 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-YhvJun-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:45:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:45:11 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-6AKtJy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:45:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:45:11 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-Dmszgd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:45:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:45:11 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-df9TEG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:45:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:45:11 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-J29wqt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:45:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:45:11 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-cf44hf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:45:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:45:11 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-eaOcn0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:45:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:45:11 INFO - Running setup.py install for manifestparser 04:45:11 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:45:11 INFO - Running setup.py install for mozcrash 04:45:12 INFO - Running setup.py install for mozdebug 04:45:12 INFO - Running setup.py install for mozdevice 04:45:12 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:45:12 INFO - Running setup.py install for mozfile 04:45:12 INFO - Running setup.py install for mozhttpd 04:45:12 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:45:12 INFO - Running setup.py install for mozinfo 04:45:12 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:45:12 INFO - Running setup.py install for mozInstall 04:45:12 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:45:12 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:45:12 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:45:12 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:45:12 INFO - Running setup.py install for mozleak 04:45:13 INFO - Running setup.py install for mozlog 04:45:13 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:45:13 INFO - Running setup.py install for moznetwork 04:45:13 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:45:13 INFO - Running setup.py install for mozprocess 04:45:13 INFO - Running setup.py install for mozprofile 04:45:13 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:45:13 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:45:13 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:45:13 INFO - Running setup.py install for mozrunner 04:45:13 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:45:13 INFO - Running setup.py install for mozscreenshot 04:45:14 INFO - Running setup.py install for moztest 04:45:14 INFO - Running setup.py install for mozversion 04:45:14 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:45:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:45:14 INFO - Cleaning up... 04:45:14 INFO - Return code: 0 04:45:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:45:14 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:45:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:14 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:45:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:14 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 0x104fc2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104fc5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99994f6880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104da0d30>, '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 0x104cc7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104d95be8>, '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.5YAaPzz5IK/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.UYlVaK9ZZq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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:45:14 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:45:14 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:45:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5YAaPzz5IK/Render', 04:45:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:45:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:45:14 INFO - 'HOME': '/Users/cltbld', 04:45:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:45:14 INFO - 'LOGNAME': 'cltbld', 04:45:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:14 INFO - 'MOZ_NO_REMOTE': '1', 04:45:14 INFO - 'NO_EM_RESTART': '1', 04:45:14 INFO - 'PAGER': '/bin/cat', 04:45:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:45:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:14 INFO - 'PWD': '/builds/slave/test', 04:45:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:45:14 INFO - 'SHELL': '/bin/bash', 04:45:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UYlVaK9ZZq/Listeners', 04:45:14 INFO - 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/', 04:45:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:45:14 INFO - 'USER': 'cltbld', 04:45:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:45:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:45:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:14 INFO - 'XPC_FLAGS': '0x0', 04:45:14 INFO - 'XPC_SERVICE_NAME': '0', 04:45:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:45:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:45:14 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-LTi4dF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:45:14 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:45:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:45:14 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-ZM8TXy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:45:14 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:45:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:45:14 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-jtPsvr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:45:14 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:45:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:45:14 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-Eczgvv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:45:15 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:45:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:45:15 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-XpLSK_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:45:15 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:45:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:45:15 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-e7h_zO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:45:15 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:45:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:45:15 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-erVkIJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:45:15 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:45:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:45:15 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-wfqdgu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:45:15 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:45:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:45:15 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-Yx62Af-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:45:15 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:45:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:45:15 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-Ua7f7y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:45:15 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:45:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:45:15 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-a0yKJ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:45:15 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:45:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:45:15 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-Gq88h9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:45:15 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:45:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:45:15 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-U2Oogo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:45:16 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:45:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:45:16 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-RMG3QI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:45:16 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:45:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:45:16 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-GwCRTH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:45:16 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:45:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:45:16 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-lg72Uy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:45:16 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:45:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:45:16 INFO - Running setup.py (path:/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/pip-CY82lB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:45:16 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:45:16 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:45:16 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:45:16 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:45:16 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:45:16 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:45:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:45:16 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:45:16 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:45:16 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:45:16 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:45:16 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:45:16 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:45:19 INFO - Downloading blessings-1.6.tar.gz 04:45:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:45:19 INFO - Installing collected packages: blessings 04:45:19 INFO - Running setup.py install for blessings 04:45:19 INFO - Successfully installed blessings 04:45:19 INFO - Cleaning up... 04:45:19 INFO - Return code: 0 04:45:19 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:45:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:45:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:45:19 INFO - Reading from file tmpfile_stdout 04:45:19 INFO - Current package versions: 04:45:19 INFO - blessings == 1.6 04:45:19 INFO - blobuploader == 1.2.4 04:45:19 INFO - docopt == 0.6.1 04:45:19 INFO - functools32 == 3.2.3-2 04:45:19 INFO - jsonschema == 2.5.1 04:45:19 INFO - manifestparser == 1.1 04:45:19 INFO - mozInstall == 1.12 04:45:19 INFO - mozcrash == 1.0 04:45:19 INFO - mozdebug == 0.1 04:45:19 INFO - mozdevice == 0.49 04:45:19 INFO - mozfile == 1.2 04:45:19 INFO - mozhttpd == 0.7 04:45:19 INFO - mozinfo == 0.9 04:45:19 INFO - mozleak == 0.1 04:45:19 INFO - mozlog == 3.4 04:45:19 INFO - moznetwork == 0.27 04:45:19 INFO - mozprocess == 0.25 04:45:19 INFO - mozprofile == 0.28 04:45:19 INFO - mozrunner == 6.13 04:45:19 INFO - mozscreenshot == 0.1 04:45:19 INFO - mozsystemmonitor == 0.3 04:45:19 INFO - moztest == 0.8 04:45:19 INFO - mozversion == 1.4 04:45:19 INFO - psutil == 3.1.1 04:45:19 INFO - requests == 1.2.3 04:45:19 INFO - wsgiref == 0.1.2 04:45:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:45:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:45:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:45:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:19 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 0x104fc2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104fc5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99994f6880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104da0d30>, '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 0x104cc7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104d95be8>, '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.5YAaPzz5IK/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.UYlVaK9ZZq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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:45:19 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:45:19 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:45:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5YAaPzz5IK/Render', 04:45:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:45:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:45:19 INFO - 'HOME': '/Users/cltbld', 04:45:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:45:19 INFO - 'LOGNAME': 'cltbld', 04:45:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:19 INFO - 'MOZ_NO_REMOTE': '1', 04:45:19 INFO - 'NO_EM_RESTART': '1', 04:45:19 INFO - 'PAGER': '/bin/cat', 04:45:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:45:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:19 INFO - 'PWD': '/builds/slave/test', 04:45:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:45:19 INFO - 'SHELL': '/bin/bash', 04:45:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UYlVaK9ZZq/Listeners', 04:45:19 INFO - 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/', 04:45:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:45:19 INFO - 'USER': 'cltbld', 04:45:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:45:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:45:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:19 INFO - 'XPC_FLAGS': '0x0', 04:45:19 INFO - 'XPC_SERVICE_NAME': '0', 04:45:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:45:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:20 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:45:20 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:45:20 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:45:20 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:45:20 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:45:20 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:45:20 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:45:20 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:45:20 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:45:20 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:45:20 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:45:20 INFO - Cleaning up... 04:45:20 INFO - Return code: 0 04:45:20 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:45:20 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:45:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:20 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:45:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:20 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 0x104fc2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104fc5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99994f6880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104da0d30>, '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 0x104cc7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104d95be8>, '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.5YAaPzz5IK/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.UYlVaK9ZZq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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:45:20 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:45:20 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:45:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5YAaPzz5IK/Render', 04:45:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:45:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:45:20 INFO - 'HOME': '/Users/cltbld', 04:45:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:45:20 INFO - 'LOGNAME': 'cltbld', 04:45:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:20 INFO - 'MOZ_NO_REMOTE': '1', 04:45:20 INFO - 'NO_EM_RESTART': '1', 04:45:20 INFO - 'PAGER': '/bin/cat', 04:45:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:45:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:20 INFO - 'PWD': '/builds/slave/test', 04:45:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:45:20 INFO - 'SHELL': '/bin/bash', 04:45:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UYlVaK9ZZq/Listeners', 04:45:20 INFO - 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/', 04:45:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:45:20 INFO - 'USER': 'cltbld', 04:45:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:45:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:45:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:20 INFO - 'XPC_FLAGS': '0x0', 04:45:20 INFO - 'XPC_SERVICE_NAME': '0', 04:45:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:45:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:20 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:45:20 INFO - Cleaning up... 04:45:20 INFO - Return code: 0 04:45:20 INFO - Running post-action listener: _resource_record_post_action 04:45:20 INFO - Running post-action listener: _start_resource_monitoring 04:45:20 INFO - Starting resource monitoring. 04:45:20 INFO - [mozharness: 2017-03-30 11:45:20.436113Z] Finished create-virtualenv step (success) 04:45:20 INFO - [mozharness: 2017-03-30 11:45:20.437042Z] Running install step. 04:45:20 INFO - Running pre-action listener: _resource_record_pre_action 04:45:20 INFO - Running main action method: install 04:45:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:45:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:45:20 INFO - Reading from file tmpfile_stdout 04:45:20 INFO - Detecting whether we're running mozinstall >=1.0... 04:45:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:45:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:45:20 INFO - Reading from file tmpfile_stdout 04:45:20 INFO - Output received: 04:45:20 INFO - Usage: mozinstall [options] installer 04:45:20 INFO - Options: 04:45:20 INFO - -h, --help show this help message and exit 04:45:20 INFO - -d DEST, --destination=DEST 04:45:20 INFO - Directory to install application into. [default: 04:45:20 INFO - "/builds/slave/test"] 04:45:20 INFO - --app=APP Application being installed. [default: firefox] 04:45:20 INFO - mkdir: /builds/slave/test/build/application 04:45:20 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:45:20 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:45:40 INFO - Reading from file tmpfile_stdout 04:45:40 INFO - Output received: 04:45:40 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 04:45:40 INFO - Running post-action listener: _resource_record_post_action 04:45:40 INFO - [mozharness: 2017-03-30 11:45:40.866264Z] Finished install step (success) 04:45:40 INFO - [mozharness: 2017-03-30 11:45:40.866422Z] Running run-tests step. 04:45:40 INFO - Running pre-action listener: _resource_record_pre_action 04:45:40 INFO - Running main action method: run_tests 04:45:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:45:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:45:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:45:40 INFO - 2017-03-30 04:45:40.881 screenresolution[1628:7351] starting screenresolution argv=screenresolution get 04:45:40 INFO - 2017-03-30 04:45:40.883 screenresolution[1628:7351] Display 0: 1600x1200x32@60 04:45:40 INFO - 2017-03-30 04:45:40.889 screenresolution[1629:7354] starting screenresolution argv=screenresolution list 04:45:40 INFO - Available Modes on Display 0 04:45:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:45:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:45:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:45:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:45:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:45:41 INFO - Intel Iris: 04:45:41 INFO - Chipset Model: Intel Iris 04:45:41 INFO - Type: GPU 04:45:41 INFO - Bus: Built-In 04:45:41 INFO - VRAM (Dynamic, Max): 1536 MB 04:45:41 INFO - Vendor: Intel (0x8086) 04:45:41 INFO - Device ID: 0x0a2e 04:45:41 INFO - Revision ID: 0x0009 04:45:41 INFO - Displays: 04:45:41 INFO - Display: 04:45:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:45:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:45:41 INFO - Display Serial Number: 1600x1200 60 04:45:41 INFO - Main Display: Yes 04:45:41 INFO - Mirror: Off 04:45:41 INFO - Online: Yes 04:45:41 INFO - Rotation: Supported 04:45:41 INFO - Return code: 0 04:45:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:45:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:45:41 INFO - Python 2.7.3 04:45:41 INFO - Return code: 0 04:45:41 INFO - grabbing minidump binary from tooltool 04:45:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:45:41 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 0x104da0d30>, '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 0x104cc7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104d95be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:45:41 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:45:41 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:45:41 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:45:41 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:45:41 INFO - Return code: 0 04:45:41 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:45:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:45:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:45:41 INFO - ENV: RUST_BACKTRACE is now 1 04:45:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:45:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:45:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NT-b7EMFRvKwTQPTzQrgZw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0272', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 04:45:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/NT-b7EMFRvKwTQPTzQrgZw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0272 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 04:45:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5YAaPzz5IK/Render', 04:45:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:45:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:45:41 INFO - 'HOME': '/Users/cltbld', 04:45:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:45:41 INFO - 'LOGNAME': 'cltbld', 04:45:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:45:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:45:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:41 INFO - 'MOZ_NO_REMOTE': '1', 04:45:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:45:41 INFO - 'NO_EM_RESTART': '1', 04:45:41 INFO - 'PAGER': '/bin/cat', 04:45:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:45:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:41 INFO - 'PWD': '/builds/slave/test', 04:45:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:45:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:45:41 INFO - 'RUST_BACKTRACE': '1', 04:45:41 INFO - 'SHELL': '/bin/bash', 04:45:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UYlVaK9ZZq/Listeners', 04:45:41 INFO - 'TMPDIR': '/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/', 04:45:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:45:41 INFO - 'USER': 'cltbld', 04:45:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:45:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:45:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:41 INFO - 'XPC_FLAGS': '0x0', 04:45:41 INFO - 'XPC_SERVICE_NAME': '0', 04:45:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:45:41 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NT-b7EMFRvKwTQPTzQrgZw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0272', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 04:45:41 INFO - mozversion application_buildid: 20170330030213 04:45:41 INFO - mozversion application_changeset: 60d7a0496a3673450ddbc37ec387525148c32604 04:45:41 INFO - mozversion application_display_name: Nightly 04:45:41 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:45:41 INFO - mozversion application_name: Firefox 04:45:41 INFO - mozversion application_remotingname: firefox 04:45:41 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:45:41 INFO - mozversion application_vendor: Mozilla 04:45:41 INFO - mozversion application_version: 55.0a1 04:45:41 INFO - mozversion platform_buildid: 20170330030213 04:45:41 INFO - mozversion platform_changeset: 60d7a0496a3673450ddbc37ec387525148c32604 04:45:41 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:45:41 INFO - mozversion platform_version: 55.0a1 04:45:41 INFO - using testdate: 1490874341 04:45:41 INFO - actual date: 1490874341 04:45:41 INFO - starting webserver on 'localhost:49246' 04:45:41 INFO - SUITE-START | Running 2 tests 04:45:41 INFO - TEST-START | dromaeo_css 04:45:41 INFO - operating with platform_type : mac_ 04:45:41 INFO - Initialising browser for dromaeo_css test... 04:45:41 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/tmp_ueoSZ/profile http://localhost:49246/getInfo.html) 04:45:44 INFO - PID 1639 | __metrics Screen width/height:1600/1200 04:45:44 INFO - PID 1639 | colorDepth:24 04:45:44 INFO - PID 1639 | Browser inner width/height: 1024/689 04:45:44 INFO - PID 1639 | __metrics 04:45:44 ERROR - PID 1639 | 1490874344360 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxNightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxNightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 04:45:44 INFO - PID 1639 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:45:44 INFO - PID 1639 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:45:45 INFO - TEST-INFO | 1639: exit 0 04:45:45 INFO - Browser initialized. 04:45:45 INFO - Running cycle 1/1 for dromaeo_css test... 04:45:45 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/tmp_ueoSZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:45:57 INFO - PID 1642 | RSS: Main: 234164224 04:45:57 INFO - PID 1642 | 04:49:03 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49246/tests/dromaeo/cssquery-ext.html) 04:49:05 INFO - PID 1642 | RSS: Main: 408391680 04:49:05 INFO - PID 1642 | 04:50:47 INFO - PID 1642 | ************************* 04:50:47 INFO - PID 1642 | A coding exception was thrown and uncaught in a Task. 04:50:47 INFO - PID 1642 | 04:50:47 ERROR - PID 1642 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:50:47 INFO - PID 1642 | Full stack: 04:50:47 INFO - PID 1642 | ************************* 04:50:47 INFO - PID 1642 | ************************* 04:50:47 INFO - PID 1642 | A coding exception was thrown and uncaught in a Task. 04:50:47 INFO - PID 1642 | 04:50:47 ERROR - PID 1642 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:50:47 INFO - PID 1642 | Full stack: 04:50:47 INFO - PID 1642 | ************************* 04:50:47 INFO - PID 1642 | ************************* 04:50:47 INFO - PID 1642 | A coding exception was thrown and uncaught in a Task. 04:50:47 INFO - PID 1642 | 04:50:47 ERROR - PID 1642 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:50:47 INFO - PID 1642 | Full stack: 04:50:47 INFO - PID 1642 | ************************* 04:50:47 INFO - PID 1642 | ************************* 04:50:47 INFO - PID 1642 | A coding exception was thrown and uncaught in a Task. 04:50:47 INFO - PID 1642 | 04:50:47 ERROR - PID 1642 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:50:47 INFO - PID 1642 | Full stack: 04:50:47 INFO - PID 1642 | ************************* 04:52:08 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-ext.html (next: http://localhost:49246/tests/dromaeo/cssquery-jquery.html) 04:52:08 INFO - PID 1642 | RSS: Main: 373104640 04:52:08 INFO - PID 1642 | 04:55:11 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49246/tests/dromaeo/cssquery-mootools.html) 04:55:11 INFO - PID 1642 | RSS: Main: 330579968 04:55:11 INFO - PID 1642 | 04:58:14 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49246/tests/dromaeo/cssquery-prototype.html) 04:58:14 INFO - PID 1642 | RSS: Main: 334548992 04:58:14 INFO - PID 1642 | 05:01:18 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49246/tests/dromaeo/cssquery-yui.html) 05:01:19 INFO - PID 1642 | RSS: Main: 332034048 05:01:19 INFO - PID 1642 | 05:04:22 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-yui.html (next: http://localhost:49246/tests/dromaeo/cssquery-dojo.html) 05:04:22 INFO - PID 1642 | RSS: Main: 548638720 05:04:22 INFO - PID 1642 | 05:04:22 INFO - PID 1642 | __start_tp_report 05:04:22 INFO - PID 1642 | _x_x_mozilla_page_load 05:04:22 INFO - PID 1642 | _x_x_mozilla_page_load_details 05:04:22 INFO - PID 1642 | |i|pagename|runs| 05:04:22 INFO - PID 1642 | |0;dojo.html;3093.7215650591447;3341.4987751837216;3502.5271588335568;3515.876944306948;3569.8036607986564;6115.571909966304;6175.228096487939;6185.3505381934865;6233.968830155849;6283.874322513547;5252.6848389096685;6187.814365569029;6205.658688772127;6240.15757872687;6277.591956522834;5106.974465127669;5468.3711373192;5532.114861622142;5557;5754.482096611304;5469.796644738151;5725.026745453283;5730.140478928173;5731.168980497825;5736.885262294752;5023.623228257895;5435.402105768362;5514.200440936042;5515.227868098469;5549.250851135126;5527.834164975057;5528.889422211533;5539.169124631298;5548.4174161712845;5561.998840208761;5444.265024221759;5499.890002199933;5509.614326997112;5517.972410137963;5524.502794748492;6138.2964379703635;6145.231846019247;6223.035429508388;6285.025820997753;6300.117983482316;6676.699548520328;6678.866422671568;6685.0640910272605;6695.397414232693;6700.530962832604;6739.898901516482;6740.93259067408;6794.388505034571;6822.079019332376;6824.454043676544;6188.938110618881;6224.751009959551;6259.906101408483;6264.9976003839165;6289.559730818844;6915.861682766316;6916.066331045345;6936.306369363124;6939.826504337331;6955.6869940851775;6289.7484100635465;6347.365263473708;6367.522435817332;6420.325865784119;6595.637239951753;6728.394444500019;6736.05695202672;6746.628935408501;6751.696173672197;6752.831179220559;6340.461060809791;6643.833904152435;6650.201975762842;6658.4673226141795;6665.333466653296;6439.839004024843;6447.806565803034;6589.604623722592;6619.933800661932;6624.304448032984;5281.471852814765;5350.117230656976;5357.276767636397;5362.195670649356;5363.973180134074;4728.338032675429;4765.69023013503;4778.546038126373;4779.139754844092;4820.783064762094;6179.938200618026;6205.100260462298;6271.874562508724;6279.591826531261;6339.873202536016;6736.696848641823;6789.456843452512;6789.6605169741315;6808.319168083152;6813;6353.555251132469;6356.491480681534;6359.523035772336;6368.808935731936;6382.5532212744665;5769.221155144001;5834.504067154255;5961.493273071751;5999.190109335271;6008;6738.35985581369;6850.965745171442;6926.549774264657;6929.099217101744;6930.27232140618;6730.562513436611;6774.356436138559;6789.981502774624;6790.830229244309;6806.557573757888;5694.231278777312;6912.412444942236;6918.481113916376;6979.057827193264;6980.7905762827195;5432.972835135957;6035.1249068885645;6404.231492220963;6466.288708242184;6565;6751.594904305757;6761.459083273228;6769.763058292936;6772.018057381751;6773.729050837911;5744.3681195067675;6115.270764408704;6299.401556852092;6324.083007963727;6378.97936330185;6383.808485745435;6398.1362516059735;6398.71205795731;6410.647414392067;6476.158099447231;6432.388923052303;6447.387498187664;6448.709808058742;6449.7420103195345;6453.548251622529;5639.718014099197;6198.163247961467;6345.302016778059;6580.5722628029025;6604.240512341143;6290.402411770874;6308.873822523616;6343.175387199634;6344.524160687874;6363.109164717036 05:04:22 INFO - PID 1642 | |1;ext.html;740.6407892172299;794.5947566740962;814.5194335342147;1127.7237076916151;1142.708609304627;27617.585736213972;28064.579031314523;28082.85958570207;36681.63318366816;37154.256914861755;38236.80881595595;38453.23093538135;38540;38557.807210963976;38618.8069059655;8535.146485351463;8697.739067827975;8698.08670089641;8704.129587041309;8709.695160669346;6133.049377346519;6280.340564240758;8365.749027529153;8496.150384961486;8584.828303433927;8620.870686939701;8649.870251946226;8675.349348798834;8680.739577812676;8686.782830429227;8582.356323275748;8669.913300866974;8672.826543469095;8674.56627168639;8682.26200772932;8498.150184981514;8551.957240213758;8566.70016549424;8573.571321433903;8573.699920502815;37124.628753712386;37343.81328093342;37367;37411.94234317139;37433.43849842255;3011.5633233181065;3015.1256135720832;3037.8329191894436;3043.5282531207476;3044.9391012179854;25837.483250335077;26105.869470652524;26480.86759566209;26911.461770764476;27271.590926136123;2793.315637668764;2798.8320700758018;2806.28439747865;2807.7753779697778;2816.8732407041734;275871;275946;277442;277522;277815;46104.308435373496;46414.76792616015;46415.535844641796;46460.5353946463;46470.5352946473;48389.516104839204;48448;48450;48511;48567;14083.366248518842;14477.348519316656;14493.130412175304;14528.927355363156;14535;11768.470418831173;11791.705207369712;11792.23350482216;11793.29240245588;11800.17398782077;10584.782749983697;10670.039696427359;11634.883651163549;13235.66910827237;13517.797233041512;5712.485876271156;5920.164748927846;5922.733476993546;5935.317438494629;5939.57450211946;942.4439580647314;954.346272803132;981.6515137126217;1035.9378437293788;1039.8492218628257;24106.276811695327;24116.63825042626;24128.552286862843;24133.637995430083;24335.391615209406;1073.8294961032693;1078.8813230544633;1103.4813637590319;1132.7168207948014;1134.2400591603646;9438.24494040489;11703.590374336858;12104.971077458515;12248.65264820869;12258.14193006481;484470;485949;486912;489270;489661;53391.46608533942;53406.73296633492;53538.39384818462;53569.73215133899;53587.497750314986;1425.8146440962817;1448.123885049572;1498.2958009735407;1516.385863725193;1516.5601975427003;23753.762462375154;23862.16482423107;23896.64155037641;23897.880510597337;24014;37289.81355093207;49935.500644994536;50892.23661645004;50994.74502627463;51061.74469127779;472254;476530;476651;477449;477565;34376.65623343835;34689.306213875076;34781.9565413038;34790.47814282737;34978.65021349856;41984.58015419807;42241.78879105585;42342.78828605837;42675.3598696026;43241.567584325014;29246.561301580066;29886.402271954874;30034.04778689378;30098.097057088322;30108.84945575258;28706.56940145858;30072.54891176678;30092.24769380783;30146.698533015267;30182;34887.47668785021;35546.64453355433;35876;35943.10142246465;35988;28802.567961480174;29061.41877162487;29353;30216.84891575528;30370.392592148477;21342;22196.77803221947;24172.51654966926;24314;24334.148304809245 05:04:22 INFO - PID 1642 | |2;jquery.html;383.8694843753123;421.55284739786913;434.5307068366164;434.90866917947227;447.23299541286684;17053.40313089042;21384.358469245908;22232.777672223314;22949.19677811275;23308.06767729289;22174.778252217475;22243.77756222441;22272.218222542197;22341.55316893665;22402.32793016208;5978.252718410199;6022.578419510625;6146.815595532141;6249.437550620443;6292.748290068392;3631.7276204284785;3831.386978083507;6074.149619053336;6176.166217560639;6181.752729890799;5017.724025178632;6188.721507532149;6207.751689932397;6218.440340369366;6218.626882387071;5928.170056192136;6081.726322315462;6154.78458253959;6194.163787888645;6214.782482613087;5417.187421886709;5443.129099344125;5445.918311225335;5463.344398672185;5470.972645136789;27022.189334319806;27281.86258580269;27325.453490930267;27399.178024659293;27740.029098981642;2039.2352867674622;2043.305276206097;2055.9074841632164;2069.4722845674405;2133.221374198422;15891.92054039734;16430.83569164317;16469.91765041179;16713.83286167147;16875.66248675032;1610.719478014981;1627.8616317613016;1633.3303345628235;1645.6708658268394;1678.5719641491464;118322.4083879575;121959;137158;146932;149640;10475.947620262003;10661.78676426467;11630.53477860893;11827.822582661267;12000.75998480043;14674.266286685623;15191.392344306105;15581.220938953007;15772.369105235892;15815.604609884846;7645.808854778675;8777.210051095431;8842.955785221033;9008.41445306066;9029.909700902906;7250.601216933014;8526.058509814104;8527.786805329917;8544.914550854412;8729;6211.378862113843;7377.0409846719585;7515.586642734702;7535.962320188473;7553.357964573072;1775.8046614872353;1822.2528763207022;1969.0450131686407;2026.9695954560693;2107.673310636854;944.650479322655;947.5972711597543;958.2142643032646;977.8924318324979;987.1905037868878;19533.730307529964;21165.365039048855;21191.576168476546;21197.15753407604;21472.892635537035;948.9572969216401;982.2761054349571;1001.5342865567546;1016.8271393863062;1018.9592416303414;10391.53238104272;10876.02115809581;10912.781744365067;10988.395638239974;11015.28400653971;594504;624734;627269;635376;643822;149820.2508987448;152739;154119;161450.19274903552;162307;1188.916775825684;1191.8808119188186;1195.4500929572494;1223.5289413575838;1226.178460431513;18347.633047339245;18359.54101147482;18367.540811479284;18399.908000459913;18503.167357468676;56009.439905600426;56048.10893494438;59658.70170649119;61869.38130618816;62110.689446552475;67745.66127169333;74936;75979.24020759722;76087.61956190184;77474.45051099059;6437.613743175424;8523.636218205058;8590.82818343642;8748.868766968373;8775.42959707617;13843;14113.717725645225;14165.220912849893;14579.271036447923;14598.927005364905;11754.941225293818;11764.411779410824;11935.880641193477;12091.879081209076;12097.334646594521;11397.886021140013;11453.885461145283;11601.361925094201;11691.649250522158;11774.470148843151;11831.822522661994;11841.5855445059;12098.939505302416;12107.878921210675;12184.390780461119;8620.439671421338;8808;8908.777280567778;9145.908540914506;9147.0852914708;10935.28920620157;11778.705532361759;12082.939585302018;12129.939350303192;12145.574904878287 05:04:22 INFO - PID 1642 | |3;mootools.html;1316.828812254407;1438.0868148725563;1466.0763718857124;1472.6465648244412;1490.2995592071725;10687.358758474487;11167.441627918592;11194.992450679438;11195.27230730004;11199.608013719522;10837.078848297884;10857.185711071681;11200.159988000913;11214.943925280382;11217.802926166045;2114.4396734865195;2445.217530390276;2492.9529597568976;2494.289127598635;2497.2008957133726;2125.4580082079046;2327.173853282086;2464.7658472445087;2472.0235506974736;2483.3915690655726;2072.3264938894845;2157.1587081038406;2158;2159.6328624133857;2161.8054375106235;2094.759102703186;2126.170793390579;2390.151496218852;2479.5164942836154;2480.6155045967903;2460.729319774823;2461.88921498533;2463.5811911975006;2464.3592665906995;2465.457599328145;10950.06924411426;11008.504617292241;11018.944905275423;11024.944875275653;11037.448127593669;5492;5707.229524014247;5711.457411545896;5723.112917497753;6161.353057928943;3500;3599.8380072896784;4040.272750904836;4147.108371700068;4155.480564929384;1183.9052875769917;1192.5647138794363;1204.476052916975;1218.695326168458;1554.3316373959194;15398.384064637401;15469.458568950145;15490.767638485431;15529.45646902353;15570.377184912702;4244.808983595745;5332.840014799563;5411.754122039389;5426.213199086111;5428.619996600279;11465.14011449128;11466.197366184288;11475.655730328104;11511;11516.30902145874;6292.528060395489;6295.559310848196;6301.96849015752;6309.369063093745;6312.495000399957;5736.6558006519745;5859.150423188699;5944.286685597756;6014.969925150346;6019.217501724747;3917.843286268517;4039.1315867088715;4067.511898572131;4102.2821006323775;4102.9127281270485;1156.7512984708328;1162.0529268646026;1162.4826952006445;1172.9706757331135;1186.3781043893093;749.2994050562742;749.5502698381036;780.6252998560723;782.9295363417319;797.0353079658554;5910.468057874726;5994.790182343684;6052.878942421215;6109.266887973383;6115.847103822351;580.451473357673;599.2658992733866;800.7877912353231;863.9848178390491;922.3170985689193;3166.5250212468313;4116.958830411718;4126.690498212586;4167.166566686675;4169.708120431601;13737.519186828413;14376.424943002163;14378.784318235235;14379.209143496999;14395.136291822315;14273;14279.643008924651;14291.857081429052;14293.142411455137;14300.213488258245;9760.46317452547;10152.898471015344;10404.68785936408;10410.115140213167;10419.426931518688;5975.880482390415;6358.205224346957;6528.445082168069;6529.640869752209;6565.540412171294;10842.457877106272;12411.441485133371;12822.871771282167;12835.03737219731;12839.679008024874;10466.372017678963;12757.872421275668;13084.28036458004;13115.803262951255;13273.469061237442;8275.586220689062;8463.703770368007;8879.556022198993;9219.03200163973;9277.304202184998;10287.53706083213;10307.056743927578;10372.948135259578;10404.58381664725;10405.531751071068;8655.653773849228;8716.651333946571;8737.956310218407;8895.955520222358;8998.280137588847;8479.576021198793;8490.448120872123;8557.187067228604;8557.315414766928;8588.355873309401;7722.884156737766;7739.92260077392;7892.0529536456;7893;7899.802504937423;5948.345681975065;5985.40145985398;6003.2796064470795;6020.591763294718;6051.334353221054;5950.851228719317;5990.101484777319;6060.848478788065;6100.877982440237;6101.664408457577 05:04:22 INFO - PID 1642 | |4;prototype.html;394.42807928503674;399.70621593129044;402.6517062740729;409.6927304521609;410.9691773117015;4818.686785358954;6231.283402408724;6304.369563043693;6317.1156038154695;6350.491960643149;5646.89885472333;5894.675792831393;5921.927325436095;6062.969685151579;6162.784302549411;3957.7823219722904;3975.5428125765475;4560.8403705870305;4594.862154135381;4620.468646105691;4036.1927614477077;4603.631709463235;4603.884902877421;4631.698939568917;4639.605633521155;4572.497025327211;4582.587567118959;4587.541245875419;4588.495265520807;4591.334256532818;4459.420275364214;4491.371208030879;4558.133954548625;4587.931181032304;4618.722876627413;3889.397143442771;3893.007283142809;3899.5320561532512;3900.980495097535;3925.7055720821054;4892.404082999028;5592.4407559244155;5927.555433342473;5927.555433342516;5928.347881733006;4291.592298731615;4875.829345972909;4952.504749525055;5111;5179.61152913533;5452;5687.687177205252;6481;6485.351464853524;6517.348265173492;3678.668919797204;4348.73907565544;4349.608535231812;4359.5204527502265;4366.366876802846;6351.793159299718;6605;6776.122235930584;6846.520743547904;6878.074139241021;4395.956040439619;4399.560043995575;4406.27296496081;4408.250597398386;4409.404730361424;3966.5011043043273;4490.483594386622;4499.797509112098;4502.414686090788;4509.323601459807;4571.862844114616;4810.63920205979;5021.698698078127;5269.973650131801;5303.893922121536;5501.944980550224;6466.741330346827;6483.471446136011;6507.258326462304;6546.9017964730565;1497.9550613481613;1532.685799411118;1536.178144692584;1536.7464368379315;1538.261764206462;877.6313948141795;959.9424034557949;1003.9870060528739;1008.6923488336128;1017.4099022566893;5477.315335583052;5482.451754824486;5988.790392336247;6158.568900176944;6220.34686357935;6502.772402965874;6782.593044417351;6817.5909445433435;6827.180738311435;6849.7670419324;5507.339119305709;5646.97176514123;5727.8568035799435;6180.227471566054;6218.284897236786;3530.9646903530634;3560.8041557714573;3573.3210689968823;3576.6065732769894;3581.265840502743;5498.395176530578;6452.6772011737485;6636.2368327642935;6952.895706564405;6975.546589471668;6649.4347980421235;6978.546394484342;6994.230634630187;6996.335348141918;6998.335158159966;5819.330776960619;6643.7674681385915;6801.829954251183;6969.128858892638;6972.965135174291;5241.475852414728;6648.800535983928;6789.626570538668;6793.218779840382;6802.897956530755;5321.866953326198;6344.9048264277;6639.734410623521;6683.699233534406;6686.331366863469;5909.2908850938165;6676.732930682719;6950.513464057467;6983.825404364931;7016;4210.621044106045;4380;4393;4406.79660169915;4431.8005689743395;6216.595921265103;6710.396064354231;6750.6624668767345;6769.93230067693;6791.7283308669075;5412.052890744183;6255.749770009149;6270.620463498023;6520.18497687789;6734.645956464525;5743.511801496752;6566.277709451861;6578.243501997332;6609.239937407067;6621.834454138492;5123.769430375568;5345.866353341197;5849.736761845642;6479.870402591828;6495.350464953467;5505.752241149078;6203.751849925952;6491.513136514686;6597;6607.372299631527;5380.461953804745;6392.520560957853;6407.71165297572;6449.516286278454;6487.296709885295 05:04:22 INFO - PID 1642 | |5;yui.html;665.2383021440451;722.2163952111962;725.8366867454827;730.7554458205565;744.824966132959;15125.24373781312;15772.44796432125;16504.174791260433;16613.08628025458;16729.996200227994;13052.673683157926;15140.924295378507;15304.540863774077;15420.61448463789;15432;928.0997432490474;950.9239260859149;976.584951395654;976.9853452198223;977.3061126600122;965.2181732796422;1005.0703099632847;1005.7435353984723;1007.8740157480315;1014.7158795537262;1081.0324759340385;1082.8538147350087;1084.5661735305862;1086.858708367911;1091.639758879572;1073.4471747050275;1075.688050465364;1075.811732946735;1076.633944458884;1077.6443773554786;807.1323327422986;807.5356669914764;807.5719868469721;808.1151139502294;808.7331180710352;16269.674606507922;16642.916785415997;16749.413770517975;16764.32942682291;16974.745378819327;14794;14820.900427209956;14864.554063378117;15008.24958752069;15679;11571.710707232387;11603.4198290086;11677.299362038304;11831.467583958742;11916.582919597859;3040.802347847383;3043.2087657209063;3062.096681478969;3063.1116976077133;3112.081935828936;115281;117416;117461.41269293684;117541;121529;10870.945645271722;11360.772784544262;11505.482253298622;11897.225565809174;12045.518179272905;21239.256626018014;25371.11201107989;25375.873120634278;25490.362740931625;25635.102771403286;7013.509054366247;8082.393820463489;8121.79695507617;8144.959275203586;8175.457748226;4110.136871257069;4166.375043743463;4169.364236695624;4199.811008504625;4211.473565804275;4139.482564679415;4170.83316667336;4200.726952748061;4219.303814870574;4258.276093064124;836.1178956201267;838.5807096451774;848.9480318255984;857.5326447086353;860.6101146648118;503.28282197868623;532.2947095099009;541.4369056181528;586.7154430101398;591.8313280714975;9037.683681071263;9041.90958090424;9687.51562421876;10261.819701658895;10513.316634418738;566.1196838915444;583.1136672258144;588.9146073819272;589.2987345059365;600.4625859855405;4507.661925355612;4745.454272758608;4774.259989701602;4806.927896081562;4866.562009419169;98824;102970.45544316841;103492;104430;107007.9299206998;89257.66113508302;89648;90953;91027;91842.08157918468;69386.30613693799;70299;70320;70604.6469767648;70668;9516.095970882756;9662.323637345318;9690.37012594179;9690.903090969001;9724.513774311115;35820.64179358173;36296.81851590725;36318.63681363153;36429.63570364263;37289.62710372861;49829;50208.74895625498;50522.74738626283;50604.49395505998;51173;11593.478293476644;11710.648680539598;11740.823887641864;11872.228305160137;12254.509819607472;12767.425465853874;12786.232826030468;12853.807192891927;12922.741545168856;12970.740585188432;11866.703332416413;11875.465604048011;11985.161038727205;12371.13402061847;12524.123311368481;12172.57395991136;12209.511619535118;12400.875991240333;12482.687932801753;12525;10154.79690406173;11660.708482287671;11719.648410547697;12014.399280035788;12166.330851802883;8998.235150012322;9073.682421115229;9208.401453905475;9214.308926830381;9847.409155450696;8630.654773808978;8883.42257753244;10107.69676909694;10154;10204.18366530661 05:04:22 INFO - PID 1642 | __end_tp_report 05:04:22 INFO - PID 1642 | __start_cc_report 05:04:22 INFO - PID 1642 | _x_x_mozilla_cycle_collect,2027 05:04:22 INFO - PID 1642 | __end_cc_report 05:04:22 INFO - PID 1642 | __startTimestamp1490875462421__endTimestamp 05:04:22 INFO - PID 1642 | 05:04:22 INFO - PID 1642 | ------- Summary: start ------- 05:04:22 INFO - PID 1642 | Number of tests: 6 05:04:22 INFO - PID 1642 | 05:04:22 INFO - PID 1642 | [#0] dojo.html Cycles:165 Average:6144.55 Median:6340.46 stddev:729.63 (11.5%) stddev-sans-first:691.51 05:04:22 INFO - PID 1642 | Values: 3093.7 3341.5 3502.5 3515.9 3569.8 6115.6 6175.2 6185.4 6234.0 6283.9 5252.7 6187.8 6205.7 6240.2 6277.6 5107.0 5468.4 5532.1 5557.0 5754.5 5469.8 5725.0 5730.1 5731.2 5736.9 5023.6 5435.4 5514.2 5515.2 5549.3 5527.8 5528.9 5539.2 5548.4 5562.0 5444.3 5499.9 5509.6 5518.0 5524.5 6138.3 6145.2 6223.0 6285.0 6300.1 6676.7 6678.9 6685.1 6695.4 6700.5 6739.9 6740.9 6794.4 6822.1 6824.5 6188.9 6224.8 6259.9 6265.0 6289.6 6915.9 6916.1 6936.3 6939.8 6955.7 6289.7 6347.4 6367.5 6420.3 6595.6 6728.4 6736.1 6746.6 6751.7 6752.8 6340.5 6643.8 6650.2 6658.5 6665.3 6439.8 6447.8 6589.6 6619.9 6624.3 5281.5 5350.1 5357.3 5362.2 5364.0 4728.3 4765.7 4778.5 4779.1 4820.8 6179.9 6205.1 6271.9 6279.6 6339.9 6736.7 6789.5 6789.7 6808.3 6813.0 6353.6 6356.5 6359.5 6368.8 6382.6 5769.2 5834.5 5961.5 5999.2 6008.0 6738.4 6851.0 6926.5 6929.1 6930.3 6730.6 6774.4 6790.0 6790.8 6806.6 5694.2 6912.4 6918.5 6979.1 6980.8 5433.0 6035.1 6404.2 6466.3 6565.0 6751.6 6761.5 6769.8 6772.0 6773.7 5744.4 6115.3 6299.4 6324.1 6379.0 6383.8 6398.1 6398.7 6410.6 6476.2 6432.4 6447.4 6448.7 6449.7 6453.5 5639.7 6198.2 6345.3 6580.6 6604.2 6290.4 6308.9 6343.2 6344.5 6363.1 05:04:22 INFO - PID 1642 | 05:04:22 INFO - PID 1642 | [#1] ext.html Cycles:180 Average:54222.02 Median:34933.06 stddev:113217.99 (324.1%) stddev-sans-first:113464.39 05:04:22 INFO - PID 1642 | Values: 740.6 794.6 814.5 1127.7 1142.7 27617.6 28064.6 28082.9 36681.6 37154.3 38236.8 38453.2 38540.0 38557.8 38618.8 8535.1 8697.7 8698.1 8704.1 8709.7 6133.0 6280.3 8365.7 8496.2 8584.8 8620.9 8649.9 8675.3 8680.7 8686.8 8582.4 8669.9 8672.8 8674.6 8682.3 8498.2 8552.0 8566.7 8573.6 8573.7 37124.6 37343.8 37367.0 37411.9 37433.4 3011.6 3015.1 3037.8 3043.5 3044.9 25837.5 26105.9 26480.9 26911.5 27271.6 2793.3 2798.8 2806.3 2807.8 2816.9 275871.0 275946.0 277442.0 277522.0 277815.0 46104.3 46414.8 46415.5 46460.5 46470.5 48389.5 48448.0 48450.0 48511.0 48567.0 14083.4 14477.3 14493.1 14528.9 14535.0 11768.5 11791.7 11792.2 11793.3 11800.2 10584.8 10670.0 11634.9 13235.7 13517.8 5712.5 5920.2 5922.7 5935.3 5939.6 942.4 954.3 981.7 1035.9 1039.8 24106.3 24116.6 24128.6 24133.6 24335.4 1073.8 1078.9 1103.5 1132.7 1134.2 9438.2 11703.6 12105.0 12248.7 12258.1 484470.0 485949.0 486912.0 489270.0 489661.0 53391.5 53406.7 53538.4 53569.7 53587.5 1425.8 1448.1 1498.3 1516.4 1516.6 23753.8 23862.2 23896.6 23897.9 24014.0 37289.8 49935.5 50892.2 50994.7 51061.7 472254.0 476530.0 476651.0 477449.0 477565.0 34376.7 34689.3 34782.0 34790.5 34978.7 41984.6 42241.8 42342.8 42675.4 43241.6 29246.6 29886.4 30034.0 30098.1 30108.8 28706.6 30072.5 30092.2 30146.7 30182.0 34887.5 35546.6 35876.0 35943.1 35988.0 28802.6 29061.4 29353.0 30216.8 30370.4 21342.0 22196.8 24172.5 24314.0 24334.1 05:04:22 INFO - PID 1642 | 05:04:22 INFO - PID 1642 | [#2] jquery.html Cycles:180 Average:37639.69 Median:22203.78 stddev:105225.06 (473.9%) stddev-sans-first:105483.06 05:04:22 INFO - PID 1642 | Values: 383.9 421.6 434.5 434.9 447.2 17053.4 21384.4 22232.8 22949.2 23308.1 22174.8 22243.8 22272.2 22341.6 22402.3 5978.3 6022.6 6146.8 6249.4 6292.7 3631.7 3831.4 6074.1 6176.2 6181.8 5017.7 6188.7 6207.8 6218.4 6218.6 5928.2 6081.7 6154.8 6194.2 6214.8 5417.2 5443.1 5445.9 5463.3 5471.0 27022.2 27281.9 27325.5 27399.2 27740.0 2039.2 2043.3 2055.9 2069.5 2133.2 15891.9 16430.8 16469.9 16713.8 16875.7 1610.7 1627.9 1633.3 1645.7 1678.6 118322.4 121959.0 137158.0 146932.0 149640.0 10475.9 10661.8 11630.5 11827.8 12000.8 14674.3 15191.4 15581.2 15772.4 15815.6 7645.8 8777.2 8843.0 9008.4 9029.9 7250.6 8526.1 8527.8 8544.9 8729.0 6211.4 7377.0 7515.6 7536.0 7553.4 1775.8 1822.3 1969.0 2027.0 2107.7 944.7 947.6 958.2 977.9 987.2 19533.7 21165.4 21191.6 21197.2 21472.9 949.0 982.3 1001.5 1016.8 1019.0 10391.5 10876.0 10912.8 10988.4 11015.3 594504.0 624734.0 627269.0 635376.0 643822.0 149820.3 152739.0 154119.0 161450.2 162307.0 1188.9 1191.9 1195.5 1223.5 1226.2 18347.6 18359.5 18367.5 18399.9 18503.2 56009.4 56048.1 59658.7 61869.4 62110.7 67745.7 74936.0 75979.2 76087.6 77474.5 6437.6 8523.6 8590.8 8748.9 8775.4 13843.0 14113.7 14165.2 14579.3 14598.9 11754.9 11764.4 11935.9 12091.9 12097.3 11397.9 11453.9 11601.4 11691.6 11774.5 11831.8 11841.6 12098.9 12107.9 12184.4 8620.4 8808.0 8908.8 9145.9 9147.1 10935.3 11778.7 12082.9 12129.9 12145.6 05:04:22 INFO - PID 1642 | 05:04:22 INFO - PID 1642 | [#3] mootools.html Cycles:180 Average:6798.56 Median:2488.17 stddev:4296.60 (172.7%) stddev-sans-first:4288.90 05:04:22 INFO - PID 1642 | Values: 1316.8 1438.1 1466.1 1472.6 1490.3 10687.4 11167.4 11195.0 11195.3 11199.6 10837.1 10857.2 11200.2 11214.9 11217.8 2114.4 2445.2 2493.0 2494.3 2497.2 2125.5 2327.2 2464.8 2472.0 2483.4 2072.3 2157.2 2158.0 2159.6 2161.8 2094.8 2126.2 2390.2 2479.5 2480.6 2460.7 2461.9 2463.6 2464.4 2465.5 10950.1 11008.5 11018.9 11024.9 11037.4 5492.0 5707.2 5711.5 5723.1 6161.4 3500.0 3599.8 4040.3 4147.1 4155.5 1183.9 1192.6 1204.5 1218.7 1554.3 15398.4 15469.5 15490.8 15529.5 15570.4 4244.8 5332.8 5411.8 5426.2 5428.6 11465.1 11466.2 11475.7 11511.0 11516.3 6292.5 6295.6 6302.0 6309.4 6312.5 5736.7 5859.2 5944.3 6015.0 6019.2 3917.8 4039.1 4067.5 4102.3 4102.9 1156.8 1162.1 1162.5 1173.0 1186.4 749.3 749.6 780.6 782.9 797.0 5910.5 5994.8 6052.9 6109.3 6115.8 580.5 599.3 800.8 864.0 922.3 3166.5 4117.0 4126.7 4167.2 4169.7 13737.5 14376.4 14378.8 14379.2 14395.1 14273.0 14279.6 14291.9 14293.1 14300.2 9760.5 10152.9 10404.7 10410.1 10419.4 5975.9 6358.2 6528.4 6529.6 6565.5 10842.5 12411.4 12822.9 12835.0 12839.7 10466.4 12757.9 13084.3 13115.8 13273.5 8275.6 8463.7 8879.6 9219.0 9277.3 10287.5 10307.1 10372.9 10404.6 10405.5 8655.7 8716.7 8738.0 8896.0 8998.3 8479.6 8490.4 8557.2 8557.3 8588.4 7722.9 7739.9 7892.1 7893.0 7899.8 5948.3 5985.4 6003.3 6020.6 6051.3 5950.9 5990.1 6060.8 6100.9 6101.7 05:04:22 INFO - PID 1642 | 05:04:22 INFO - PID 1642 | [#4] prototype.html Cycles:180 Average:5223.20 Median:5667.33 stddev:1637.21 (28.9%) stddev-sans-first:1601.18 05:04:22 INFO - PID 1642 | Values: 394.4 399.7 402.7 409.7 411.0 4818.7 6231.3 6304.4 6317.1 6350.5 5646.9 5894.7 5921.9 6063.0 6162.8 3957.8 3975.5 4560.8 4594.9 4620.5 4036.2 4603.6 4603.9 4631.7 4639.6 4572.5 4582.6 4587.5 4588.5 4591.3 4459.4 4491.4 4558.1 4587.9 4618.7 3889.4 3893.0 3899.5 3901.0 3925.7 4892.4 5592.4 5927.6 5927.6 5928.3 4291.6 4875.8 4952.5 5111.0 5179.6 5452.0 5687.7 6481.0 6485.4 6517.3 3678.7 4348.7 4349.6 4359.5 4366.4 6351.8 6605.0 6776.1 6846.5 6878.1 4396.0 4399.6 4406.3 4408.3 4409.4 3966.5 4490.5 4499.8 4502.4 4509.3 4571.9 4810.6 5021.7 5270.0 5303.9 5501.9 6466.7 6483.5 6507.3 6546.9 1498.0 1532.7 1536.2 1536.7 1538.3 877.6 959.9 1004.0 1008.7 1017.4 5477.3 5482.5 5988.8 6158.6 6220.3 6502.8 6782.6 6817.6 6827.2 6849.8 5507.3 5647.0 5727.9 6180.2 6218.3 3531.0 3560.8 3573.3 3576.6 3581.3 5498.4 6452.7 6636.2 6952.9 6975.5 6649.4 6978.5 6994.2 6996.3 6998.3 5819.3 6643.8 6801.8 6969.1 6973.0 5241.5 6648.8 6789.6 6793.2 6802.9 5321.9 6344.9 6639.7 6683.7 6686.3 5909.3 6676.7 6950.5 6983.8 7016.0 4210.6 4380.0 4393.0 4406.8 4431.8 6216.6 6710.4 6750.7 6769.9 6791.7 5412.1 6255.7 6270.6 6520.2 6734.6 5743.5 6566.3 6578.2 6609.2 6621.8 5123.8 5345.9 5849.7 6479.9 6495.4 5505.8 6203.8 6491.5 6597.0 6607.4 5380.5 6392.5 6407.7 6449.5 6487.3 05:04:22 INFO - PID 1642 | 05:04:22 INFO - PID 1642 | [#5] yui.html Cycles:180 Average:19726.84 Median:3062.60 stddev:29278.52 (956.0%) stddev-sans-first:29325.67 05:04:22 INFO - PID 1642 | Values: 665.2 722.2 725.8 730.8 744.8 15125.2 15772.4 16504.2 16613.1 16730.0 13052.7 15140.9 15304.5 15420.6 15432.0 928.1 950.9 976.6 977.0 977.3 965.2 1005.1 1005.7 1007.9 1014.7 1081.0 1082.9 1084.6 1086.9 1091.6 1073.4 1075.7 1075.8 1076.6 1077.6 807.1 807.5 807.6 808.1 808.7 16269.7 16642.9 16749.4 16764.3 16974.7 14794.0 14820.9 14864.6 15008.2 15679.0 11571.7 11603.4 11677.3 11831.5 11916.6 3040.8 3043.2 3062.1 3063.1 3112.1 115281.0 117416.0 117461.4 117541.0 121529.0 10870.9 11360.8 11505.5 11897.2 12045.5 21239.3 25371.1 25375.9 25490.4 25635.1 7013.5 8082.4 8121.8 8145.0 8175.5 4110.1 4166.4 4169.4 4199.8 4211.5 4139.5 4170.8 4200.7 4219.3 4258.3 836.1 838.6 848.9 857.5 860.6 503.3 532.3 541.4 586.7 591.8 9037.7 9041.9 9687.5 10261.8 10513.3 566.1 583.1 588.9 589.3 600.5 4507.7 4745.5 4774.3 4806.9 4866.6 98824.0 102970.5 103492.0 104430.0 107007.9 89257.7 89648.0 90953.0 91027.0 91842.1 69386.3 70299.0 70320.0 70604.6 70668.0 9516.1 9662.3 9690.4 9690.9 9724.5 35820.6 36296.8 36318.6 36429.6 37289.6 49829.0 50208.7 50522.7 50604.5 51173.0 11593.5 11710.6 11740.8 11872.2 12254.5 12767.4 12786.2 12853.8 12922.7 12970.7 11866.7 11875.5 11985.2 12371.1 12524.1 12172.6 12209.5 12400.9 12482.7 12525.0 10154.8 11660.7 11719.6 12014.4 12166.3 8998.2 9073.7 9208.4 9214.3 9847.4 8630.7 8883.4 10107.7 10154.0 10204.2 05:04:22 INFO - PID 1642 | -------- Summary: end -------- 05:04:22 INFO - PID 1642 | 05:04:23 INFO - TEST-INFO | 1642: exit 0 05:04:23 INFO - TEST-OK | dromaeo_css | took 1121689ms 05:04:23 INFO - TEST-START | kraken 05:04:23 INFO - operating with platform_type : mac_ 05:04:23 INFO - Initialising browser for kraken test... 05:04:23 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/tmp6p3VWW/profile http://localhost:49246/getInfo.html) 05:04:25 INFO - PID 1652 | __metrics Screen width/height:1600/1200 05:04:25 INFO - PID 1652 | colorDepth:24 05:04:25 INFO - PID 1652 | Browser inner width/height: 1024/689 05:04:25 INFO - PID 1652 | __metrics 05:04:25 ERROR - PID 1652 | 1490875465702 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxNightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxNightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 05:04:25 INFO - PID 1652 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:04:25 INFO - PID 1652 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:04:26 INFO - TEST-INFO | 1652: exit 0 05:04:26 INFO - Browser initialized. 05:04:26 INFO - Running cycle 1/1 for kraken test... 05:04:26 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/tmp6p3VWW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:04:38 INFO - PID 1654 | RSS: Main: 234143744 05:04:38 INFO - PID 1654 | 05:04:41 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-beat-detection) 05:04:42 INFO - PID 1654 | RSS: Main: 287285248 05:04:42 INFO - PID 1654 | 05:04:45 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-dft) 05:04:46 INFO - PID 1654 | RSS: Main: 286203904 05:04:46 INFO - PID 1654 | 05:04:49 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-fft) 05:04:50 INFO - PID 1654 | RSS: Main: 382676992 05:04:50 INFO - PID 1654 | 05:04:52 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-oscillator) 05:04:53 INFO - PID 1654 | RSS: Main: 285286400 05:04:53 INFO - PID 1654 | 05:04:55 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49246/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:04:55 INFO - PID 1654 | RSS: Main: 286769152 05:04:55 INFO - PID 1654 | 05:04:58 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49246/tests/kraken/driver.html?testName=imaging-darkroom) 05:04:59 INFO - PID 1654 | RSS: Main: 326995968 05:04:59 INFO - PID 1654 | 05:05:02 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49246/tests/kraken/driver.html?testName=imaging-desaturate) 05:05:03 INFO - PID 1654 | RSS: Main: 342155264 05:05:03 INFO - PID 1654 | 05:05:06 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49246/tests/kraken/driver.html?testName=json-parse-financial) 05:05:07 INFO - PID 1654 | RSS: Main: 351698944 05:05:07 INFO - PID 1654 | 05:05:08 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49246/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:05:09 INFO - PID 1654 | RSS: Main: 304508928 05:05:09 INFO - PID 1654 | 05:05:11 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:05:12 INFO - PID 1654 | RSS: Main: 309551104 05:05:12 INFO - PID 1654 | 05:05:14 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:05:14 INFO - PID 1654 | RSS: Main: 312111104 05:05:14 INFO - PID 1654 | 05:05:18 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:05:18 INFO - PID 1654 | RSS: Main: 327716864 05:05:18 INFO - PID 1654 | 05:05:22 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:05:22 INFO - PID 1654 | RSS: Main: 310591488 05:05:22 INFO - PID 1654 | 05:05:24 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49246/tests/kraken/driver.html?testName=ai-astar) 05:05:25 INFO - PID 1654 | RSS: Main: 378159104 05:05:25 INFO - PID 1654 | 05:05:25 INFO - PID 1654 | __start_tp_report 05:05:25 INFO - PID 1654 | _x_x_mozilla_page_load 05:05:25 INFO - PID 1654 | _x_x_mozilla_page_load_details 05:05:25 INFO - PID 1654 | |i|pagename|runs| 05:05:25 INFO - PID 1654 | |0;ai-astar;111;123;121;133;108;170;180;137;122;123 05:05:25 INFO - PID 1654 | |1;audio-beat-detection;160;121;120;165;147;155;160;121;143;121 05:05:25 INFO - PID 1654 | |2;audio-dft;207;215;210;212;205;210;208;214;211;218 05:05:25 INFO - PID 1654 | |3;audio-fft;83;66;62;128;95;90;61;63;87;62 05:05:25 INFO - PID 1654 | |4;audio-oscillator;95;78;97;75;75;75;89;74;75;91 05:05:25 INFO - PID 1654 | |5;imaging-gaussian-blur;91;90;91;90;92;92;91;91;96;91 05:05:25 INFO - PID 1654 | |6;imaging-darkroom;99;99;101;100;99;100;98;99;102;99 05:05:25 INFO - PID 1654 | |7;imaging-desaturate;101;102;108;101;101;99;99;101;101;101 05:05:25 INFO - PID 1654 | |8;json-parse-financial;61;64;65;64;64;65;65;65;62;64 05:05:25 INFO - PID 1654 | |9;json-stringify-tinderbox;58;67;60;75;69;66;63;65;67;65 05:05:25 INFO - PID 1654 | |10;stanford-crypto-aes;93;90;90;91;88;91;89;90;88;91 05:05:25 INFO - PID 1654 | |11;stanford-crypto-ccm;151;160;154;156;166;170;155;171;218;161 05:05:25 INFO - PID 1654 | |12;stanford-crypto-pbkdf2;203;244;259;216;203;202;213;196;202;196 05:05:25 INFO - PID 1654 | |13;stanford-crypto-sha256-iterative;71;71;75;74;72;75;67;64;99;78 05:05:25 INFO - PID 1654 | __end_tp_report 05:05:25 INFO - PID 1654 | __start_cc_report 05:05:25 INFO - PID 1654 | _x_x_mozilla_cycle_collect,2163 05:05:25 INFO - PID 1654 | __end_cc_report 05:05:25 INFO - PID 1654 | __startTimestamp1490875525153__endTimestamp 05:05:25 INFO - PID 1654 | 05:05:25 INFO - PID 1654 | ------- Summary: start ------- 05:05:25 INFO - PID 1654 | Number of tests: 14 05:05:25 INFO - PID 1654 | 05:05:25 INFO - PID 1654 | [#0] ai-astar Cycles:10 Average:132.80 Median:128.00 stddev:23.96 (18.7%) stddev-sans-first:24.08 05:05:25 INFO - PID 1654 | Values: 111.0 123.0 121.0 133.0 108.0 170.0 180.0 137.0 122.0 123.0 05:05:25 INFO - PID 1654 | 05:05:25 INFO - PID 1654 | [#1] audio-beat-detection Cycles:10 Average:141.30 Median:151.00 stddev:18.78 (12.4%) stddev-sans-first:18.66 05:05:25 INFO - PID 1654 | Values: 160.0 121.0 120.0 165.0 147.0 155.0 160.0 121.0 143.0 121.0 05:05:25 INFO - PID 1654 | 05:05:25 INFO - PID 1654 | [#2] audio-dft Cycles:10 Average:211.00 Median:211.50 stddev:3.92 (1.9%) stddev-sans-first:3.88 05:05:25 INFO - PID 1654 | Values: 207.0 215.0 210.0 212.0 205.0 210.0 208.0 214.0 211.0 218.0 05:05:25 INFO - PID 1654 | 05:05:25 INFO - PID 1654 | [#3] audio-fft Cycles:10 Average:79.70 Median:74.50 stddev:21.55 (28.9%) stddev-sans-first:22.83 05:05:25 INFO - PID 1654 | Values: 83.0 66.0 62.0 128.0 95.0 90.0 61.0 63.0 87.0 62.0 05:05:25 INFO - PID 1654 | 05:05:25 INFO - PID 1654 | [#4] audio-oscillator Cycles:10 Average:82.40 Median:83.50 stddev:9.42 (11.3%) stddev-sans-first:8.82 05:05:25 INFO - PID 1654 | Values: 95.0 78.0 97.0 75.0 75.0 75.0 89.0 74.0 75.0 91.0 05:05:25 INFO - PID 1654 | 05:05:25 INFO - PID 1654 | [#5] imaging-gaussian-blur Cycles:10 Average:91.50 Median:91.00 stddev:1.72 (1.9%) stddev-sans-first:1.81 05:05:25 INFO - PID 1654 | Values: 91.0 90.0 91.0 90.0 92.0 92.0 91.0 91.0 96.0 91.0 05:05:25 INFO - PID 1654 | 05:05:25 INFO - PID 1654 | [#6] imaging-darkroom Cycles:10 Average:99.60 Median:99.00 stddev:1.17 (1.2%) stddev-sans-first:1.22 05:05:25 INFO - PID 1654 | Values: 99.0 99.0 101.0 100.0 99.0 100.0 98.0 99.0 102.0 99.0 05:05:25 INFO - PID 1654 | 05:05:25 INFO - PID 1654 | [#7] imaging-desaturate Cycles:10 Average:101.40 Median:101.50 stddev:2.50 (2.5%) stddev-sans-first:2.65 05:05:25 INFO - PID 1654 | Values: 101.0 102.0 108.0 101.0 101.0 99.0 99.0 101.0 101.0 101.0 05:05:25 INFO - PID 1654 | 05:05:25 INFO - PID 1654 | [#8] json-parse-financial Cycles:10 Average:63.90 Median:64.50 stddev:1.37 (2.1%) stddev-sans-first:0.97 05:05:25 INFO - PID 1654 | Values: 61.0 64.0 65.0 64.0 64.0 65.0 65.0 65.0 62.0 64.0 05:05:25 INFO - PID 1654 | 05:05:25 INFO - PID 1654 | [#9] json-stringify-tinderbox Cycles:10 Average:65.50 Median:66.50 stddev:4.72 (7.1%) stddev-sans-first:4.15 05:05:25 INFO - PID 1654 | Values: 58.0 67.0 60.0 75.0 69.0 66.0 63.0 65.0 67.0 65.0 05:05:25 INFO - PID 1654 | 05:05:25 INFO - PID 1654 | [#10] stanford-crypto-aes Cycles:10 Average:90.10 Median:90.50 stddev:1.52 (1.7%) stddev-sans-first:1.20 05:05:25 INFO - PID 1654 | Values: 93.0 90.0 90.0 91.0 88.0 91.0 89.0 90.0 88.0 91.0 05:05:25 INFO - PID 1654 | 05:05:25 INFO - PID 1654 | [#11] stanford-crypto-ccm Cycles:10 Average:166.20 Median:163.50 stddev:19.42 (11.9%) stddev-sans-first:19.81 05:05:25 INFO - PID 1654 | Values: 151.0 160.0 154.0 156.0 166.0 170.0 155.0 171.0 218.0 161.0 05:05:25 INFO - PID 1654 | 05:05:25 INFO - PID 1654 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:213.40 Median:208.00 stddev:21.36 (10.3%) stddev-sans-first:22.32 05:05:25 INFO - PID 1654 | Values: 203.0 244.0 259.0 216.0 203.0 202.0 213.0 196.0 202.0 196.0 05:05:25 INFO - PID 1654 | 05:05:25 INFO - PID 1654 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.60 Median:74.50 stddev:9.49 (12.7%) stddev-sans-first:9.97 05:05:25 INFO - PID 1654 | Values: 71.0 71.0 75.0 74.0 72.0 75.0 67.0 64.0 99.0 78.0 05:05:25 INFO - PID 1654 | -------- Summary: end -------- 05:05:25 INFO - PID 1654 | 05:05:26 INFO - TEST-INFO | 1654: exit 0 05:05:26 INFO - TEST-OK | kraken | took 63013ms 05:05:26 INFO - SUITE-END | took 1184s 05:05:26 INFO - Completed test suite (00:19:44) 05:05:26 INFO - javascript benchmark 05:05:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7619.667873735735, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3093.7215650591447, 3341.4987751837216, 3502.5271588335568, 3515.876944306948, 3569.8036607986564, 6115.571909966304, 6175.228096487939, 6185.3505381934865, 6233.968830155849, 6283.874322513547, 5252.6848389096685, 6187.814365569029, 6205.658688772127, 6240.15757872687, 6277.591956522834, 5106.974465127669, 5468.3711373192, 5532.114861622142, 5557.0, 5754.482096611304, 5469.796644738151, 5725.026745453283, 5730.140478928173, 5731.168980497825, 5736.885262294752, 5023.623228257895, 5435.402105768362, 5514.200440936042, 5515.227868098469, 5549.250851135126, 5527.834164975057, 5528.889422211533, 5539.169124631298, 5548.4174161712845, 5561.998840208761, 5444.265024221759, 5499.890002199933, 5509.614326997112, 5517.972410137963, 5524.502794748492, 6138.2964379703635, 6145.231846019247, 6223.035429508388, 6285.025820997753, 6300.117983482316, 6676.699548520328, 6678.866422671568, 6685.0640910272605, 6695.397414232693, 6700.530962832604, 6739.898901516482, 6740.93259067408, 6794.388505034571, 6822.079019332376, 6824.454043676544, 6188.938110618881, 6224.751009959551, 6259.906101408483, 6264.9976003839165, 6289.559730818844, 6915.861682766316, 6916.066331045345, 6936.306369363124, 6939.826504337331, 6955.6869940851775, 6289.7484100635465, 6347.365263473708, 6367.522435817332, 6420.325865784119, 6595.637239951753, 6728.394444500019, 6736.05695202672, 6746.628935408501, 6751.696173672197, 6752.831179220559, 6340.461060809791, 6643.833904152435, 6650.201975762842, 6658.4673226141795, 6665.333466653296, 6439.839004024843, 6447.806565803034, 6589.604623722592, 6619.933800661932, 6624.304448032984, 5281.471852814765, 5350.117230656976, 5357.276767636397, 5362.195670649356, 5363.973180134074, 4728.338032675429, 4765.69023013503, 4778.546038126373, 4779.139754844092, 4820.783064762094, 6179.938200618026, 6205.100260462298, 6271.874562508724, 6279.591826531261, 6339.873202536016, 6736.696848641823, 6789.456843452512, 6789.6605169741315, 6808.319168083152, 6813.0, 6353.555251132469, 6356.491480681534, 6359.523035772336, 6368.808935731936, 6382.5532212744665, 5769.221155144001, 5834.504067154255, 5961.493273071751, 5999.190109335271, 6008.0, 6738.35985581369, 6850.965745171442, 6926.549774264657, 6929.099217101744, 6930.27232140618, 6730.562513436611, 6774.356436138559, 6789.981502774624, 6790.830229244309, 6806.557573757888, 5694.231278777312, 6912.412444942236, 6918.481113916376, 6979.057827193264, 6980.7905762827195, 5432.972835135957, 6035.1249068885645, 6404.231492220963, 6466.288708242184, 6565.0, 6751.594904305757, 6761.459083273228, 6769.763058292936, 6772.018057381751, 6773.729050837911, 5744.3681195067675, 6115.270764408704, 6299.401556852092, 6324.083007963727, 6378.97936330185, 6383.808485745435, 6398.1362516059735, 6398.71205795731, 6410.647414392067, 6476.158099447231, 6432.388923052303, 6447.387498187664, 6448.709808058742, 6449.7420103195345, 6453.548251622529, 5639.718014099197, 6198.163247961467, 6345.302016778059, 6580.5722628029025, 6604.240512341143, 6290.402411770874, 6308.873822523616, 6343.175387199634, 6344.524160687874, 6363.109164717036], "value": 6094.945870804869, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [740.6407892172299, 794.5947566740962, 814.5194335342147, 1127.7237076916151, 1142.708609304627, 27617.585736213972, 28064.579031314523, 28082.85958570207, 36681.63318366816, 37154.256914861755, 38236.80881595595, 38453.23093538135, 38540.0, 38557.807210963976, 38618.8069059655, 8535.146485351463, 8697.739067827975, 8698.08670089641, 8704.129587041309, 8709.695160669346, 6133.049377346519, 6280.340564240758, 8365.749027529153, 8496.150384961486, 8584.828303433927, 8620.870686939701, 8649.870251946226, 8675.349348798834, 8680.739577812676, 8686.782830429227, 8582.356323275748, 8669.913300866974, 8672.826543469095, 8674.56627168639, 8682.26200772932, 8498.150184981514, 8551.957240213758, 8566.70016549424, 8573.571321433903, 8573.699920502815, 37124.628753712386, 37343.81328093342, 37367.0, 37411.94234317139, 37433.43849842255, 3011.5633233181065, 3015.1256135720832, 3037.8329191894436, 3043.5282531207476, 3044.9391012179854, 25837.483250335077, 26105.869470652524, 26480.86759566209, 26911.461770764476, 27271.590926136123, 2793.315637668764, 2798.8320700758018, 2806.28439747865, 2807.7753779697778, 2816.8732407041734, 275871.0, 275946.0, 277442.0, 277522.0, 277815.0, 46104.308435373496, 46414.76792616015, 46415.535844641796, 46460.5353946463, 46470.5352946473, 48389.516104839204, 48448.0, 48450.0, 48511.0, 48567.0, 14083.366248518842, 14477.348519316656, 14493.130412175304, 14528.927355363156, 14535.0, 11768.470418831173, 11791.705207369712, 11792.23350482216, 11793.29240245588, 11800.17398782077, 10584.782749983697, 10670.039696427359, 11634.883651163549, 13235.66910827237, 13517.797233041512, 5712.485876271156, 5920.164748927846, 5922.733476993546, 5935.317438494629, 5939.57450211946, 942.4439580647314, 954.346272803132, 981.6515137126217, 1035.9378437293788, 1039.8492218628257, 24106.276811695327, 24116.63825042626, 24128.552286862843, 24133.637995430083, 24335.391615209406, 1073.8294961032693, 1078.8813230544633, 1103.4813637590319, 1132.7168207948014, 1134.2400591603646, 9438.24494040489, 11703.590374336858, 12104.971077458515, 12248.65264820869, 12258.14193006481, 484470.0, 485949.0, 486912.0, 489270.0, 489661.0, 53391.46608533942, 53406.73296633492, 53538.39384818462, 53569.73215133899, 53587.497750314986, 1425.8146440962817, 1448.123885049572, 1498.2958009735407, 1516.385863725193, 1516.5601975427003, 23753.762462375154, 23862.16482423107, 23896.64155037641, 23897.880510597337, 24014.0, 37289.81355093207, 49935.500644994536, 50892.23661645004, 50994.74502627463, 51061.74469127779, 472254.0, 476530.0, 476651.0, 477449.0, 477565.0, 34376.65623343835, 34689.306213875076, 34781.9565413038, 34790.47814282737, 34978.65021349856, 41984.58015419807, 42241.78879105585, 42342.78828605837, 42675.3598696026, 43241.567584325014, 29246.561301580066, 29886.402271954874, 30034.04778689378, 30098.097057088322, 30108.84945575258, 28706.56940145858, 30072.54891176678, 30092.24769380783, 30146.698533015267, 30182.0, 34887.47668785021, 35546.64453355433, 35876.0, 35943.10142246465, 35988.0, 28802.567961480174, 29061.41877162487, 29353.0, 30216.84891575528, 30370.392592148477, 21342.0, 22196.77803221947, 24172.51654966926, 24314.0, 24334.148304809245], "value": 17544.90233097048, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [383.8694843753123, 421.55284739786913, 434.5307068366164, 434.90866917947227, 447.23299541286684, 17053.40313089042, 21384.358469245908, 22232.777672223314, 22949.19677811275, 23308.06767729289, 22174.778252217475, 22243.77756222441, 22272.218222542197, 22341.55316893665, 22402.32793016208, 5978.252718410199, 6022.578419510625, 6146.815595532141, 6249.437550620443, 6292.748290068392, 3631.7276204284785, 3831.386978083507, 6074.149619053336, 6176.166217560639, 6181.752729890799, 5017.724025178632, 6188.721507532149, 6207.751689932397, 6218.440340369366, 6218.626882387071, 5928.170056192136, 6081.726322315462, 6154.78458253959, 6194.163787888645, 6214.782482613087, 5417.187421886709, 5443.129099344125, 5445.918311225335, 5463.344398672185, 5470.972645136789, 27022.189334319806, 27281.86258580269, 27325.453490930267, 27399.178024659293, 27740.029098981642, 2039.2352867674622, 2043.305276206097, 2055.9074841632164, 2069.4722845674405, 2133.221374198422, 15891.92054039734, 16430.83569164317, 16469.91765041179, 16713.83286167147, 16875.66248675032, 1610.719478014981, 1627.8616317613016, 1633.3303345628235, 1645.6708658268394, 1678.5719641491464, 118322.4083879575, 121959.0, 137158.0, 146932.0, 149640.0, 10475.947620262003, 10661.78676426467, 11630.53477860893, 11827.822582661267, 12000.75998480043, 14674.266286685623, 15191.392344306105, 15581.220938953007, 15772.369105235892, 15815.604609884846, 7645.808854778675, 8777.210051095431, 8842.955785221033, 9008.41445306066, 9029.909700902906, 7250.601216933014, 8526.058509814104, 8527.786805329917, 8544.914550854412, 8729.0, 6211.378862113843, 7377.0409846719585, 7515.586642734702, 7535.962320188473, 7553.357964573072, 1775.8046614872353, 1822.2528763207022, 1969.0450131686407, 2026.9695954560693, 2107.673310636854, 944.650479322655, 947.5972711597543, 958.2142643032646, 977.8924318324979, 987.1905037868878, 19533.730307529964, 21165.365039048855, 21191.576168476546, 21197.15753407604, 21472.892635537035, 948.9572969216401, 982.2761054349571, 1001.5342865567546, 1016.8271393863062, 1018.9592416303414, 10391.53238104272, 10876.02115809581, 10912.781744365067, 10988.395638239974, 11015.28400653971, 594504.0, 624734.0, 627269.0, 635376.0, 643822.0, 149820.2508987448, 152739.0, 154119.0, 161450.19274903552, 162307.0, 1188.916775825684, 1191.8808119188186, 1195.4500929572494, 1223.5289413575838, 1226.178460431513, 18347.633047339245, 18359.54101147482, 18367.540811479284, 18399.908000459913, 18503.167357468676, 56009.439905600426, 56048.10893494438, 59658.70170649119, 61869.38130618816, 62110.689446552475, 67745.66127169333, 74936.0, 75979.24020759722, 76087.61956190184, 77474.45051099059, 6437.613743175424, 8523.636218205058, 8590.82818343642, 8748.868766968373, 8775.42959707617, 13843.0, 14113.717725645225, 14165.220912849893, 14579.271036447923, 14598.927005364905, 11754.941225293818, 11764.411779410824, 11935.880641193477, 12091.879081209076, 12097.334646594521, 11397.886021140013, 11453.885461145283, 11601.361925094201, 11691.649250522158, 11774.470148843151, 11831.822522661994, 11841.5855445059, 12098.939505302416, 12107.878921210675, 12184.390780461119, 8620.439671421338, 8808.0, 8908.777280567778, 9145.908540914506, 9147.0852914708, 10935.28920620157, 11778.705532361759, 12082.939585302018, 12129.939350303192, 12145.574904878287], "value": 10191.57660435477, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1316.828812254407, 1438.0868148725563, 1466.0763718857124, 1472.6465648244412, 1490.2995592071725, 10687.358758474487, 11167.441627918592, 11194.992450679438, 11195.27230730004, 11199.608013719522, 10837.078848297884, 10857.185711071681, 11200.159988000913, 11214.943925280382, 11217.802926166045, 2114.4396734865195, 2445.217530390276, 2492.9529597568976, 2494.289127598635, 2497.2008957133726, 2125.4580082079046, 2327.173853282086, 2464.7658472445087, 2472.0235506974736, 2483.3915690655726, 2072.3264938894845, 2157.1587081038406, 2158.0, 2159.6328624133857, 2161.8054375106235, 2094.759102703186, 2126.170793390579, 2390.151496218852, 2479.5164942836154, 2480.6155045967903, 2460.729319774823, 2461.88921498533, 2463.5811911975006, 2464.3592665906995, 2465.457599328145, 10950.06924411426, 11008.504617292241, 11018.944905275423, 11024.944875275653, 11037.448127593669, 5492.0, 5707.229524014247, 5711.457411545896, 5723.112917497753, 6161.353057928943, 3500.0, 3599.8380072896784, 4040.272750904836, 4147.108371700068, 4155.480564929384, 1183.9052875769917, 1192.5647138794363, 1204.476052916975, 1218.695326168458, 1554.3316373959194, 15398.384064637401, 15469.458568950145, 15490.767638485431, 15529.45646902353, 15570.377184912702, 4244.808983595745, 5332.840014799563, 5411.754122039389, 5426.213199086111, 5428.619996600279, 11465.14011449128, 11466.197366184288, 11475.655730328104, 11511.0, 11516.30902145874, 6292.528060395489, 6295.559310848196, 6301.96849015752, 6309.369063093745, 6312.495000399957, 5736.6558006519745, 5859.150423188699, 5944.286685597756, 6014.969925150346, 6019.217501724747, 3917.843286268517, 4039.1315867088715, 4067.511898572131, 4102.2821006323775, 4102.9127281270485, 1156.7512984708328, 1162.0529268646026, 1162.4826952006445, 1172.9706757331135, 1186.3781043893093, 749.2994050562742, 749.5502698381036, 780.6252998560723, 782.9295363417319, 797.0353079658554, 5910.468057874726, 5994.790182343684, 6052.878942421215, 6109.266887973383, 6115.847103822351, 580.451473357673, 599.2658992733866, 800.7877912353231, 863.9848178390491, 922.3170985689193, 3166.5250212468313, 4116.958830411718, 4126.690498212586, 4167.166566686675, 4169.708120431601, 13737.519186828413, 14376.424943002163, 14378.784318235235, 14379.209143496999, 14395.136291822315, 14273.0, 14279.643008924651, 14291.857081429052, 14293.142411455137, 14300.213488258245, 9760.46317452547, 10152.898471015344, 10404.68785936408, 10410.115140213167, 10419.426931518688, 5975.880482390415, 6358.205224346957, 6528.445082168069, 6529.640869752209, 6565.540412171294, 10842.457877106272, 12411.441485133371, 12822.871771282167, 12835.03737219731, 12839.679008024874, 10466.372017678963, 12757.872421275668, 13084.28036458004, 13115.803262951255, 13273.469061237442, 8275.586220689062, 8463.703770368007, 8879.556022198993, 9219.03200163973, 9277.304202184998, 10287.53706083213, 10307.056743927578, 10372.948135259578, 10404.58381664725, 10405.531751071068, 8655.653773849228, 8716.651333946571, 8737.956310218407, 8895.955520222358, 8998.280137588847, 8479.576021198793, 8490.448120872123, 8557.187067228604, 8557.315414766928, 8588.355873309401, 7722.884156737766, 7739.92260077392, 7892.0529536456, 7893.0, 7899.802504937423, 5948.345681975065, 5985.40145985398, 6003.2796064470795, 6020.591763294718, 6051.334353221054, 5950.851228719317, 5990.101484777319, 6060.848478788065, 6100.877982440237, 6101.664408457577], "value": 5123.120535843232, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [394.42807928503674, 399.70621593129044, 402.6517062740729, 409.6927304521609, 410.9691773117015, 4818.686785358954, 6231.283402408724, 6304.369563043693, 6317.1156038154695, 6350.491960643149, 5646.89885472333, 5894.675792831393, 5921.927325436095, 6062.969685151579, 6162.784302549411, 3957.7823219722904, 3975.5428125765475, 4560.8403705870305, 4594.862154135381, 4620.468646105691, 4036.1927614477077, 4603.631709463235, 4603.884902877421, 4631.698939568917, 4639.605633521155, 4572.497025327211, 4582.587567118959, 4587.541245875419, 4588.495265520807, 4591.334256532818, 4459.420275364214, 4491.371208030879, 4558.133954548625, 4587.931181032304, 4618.722876627413, 3889.397143442771, 3893.007283142809, 3899.5320561532512, 3900.980495097535, 3925.7055720821054, 4892.404082999028, 5592.4407559244155, 5927.555433342473, 5927.555433342516, 5928.347881733006, 4291.592298731615, 4875.829345972909, 4952.504749525055, 5111.0, 5179.61152913533, 5452.0, 5687.687177205252, 6481.0, 6485.351464853524, 6517.348265173492, 3678.668919797204, 4348.73907565544, 4349.608535231812, 4359.5204527502265, 4366.366876802846, 6351.793159299718, 6605.0, 6776.122235930584, 6846.520743547904, 6878.074139241021, 4395.956040439619, 4399.560043995575, 4406.27296496081, 4408.250597398386, 4409.404730361424, 3966.5011043043273, 4490.483594386622, 4499.797509112098, 4502.414686090788, 4509.323601459807, 4571.862844114616, 4810.63920205979, 5021.698698078127, 5269.973650131801, 5303.893922121536, 5501.944980550224, 6466.741330346827, 6483.471446136011, 6507.258326462304, 6546.9017964730565, 1497.9550613481613, 1532.685799411118, 1536.178144692584, 1536.7464368379315, 1538.261764206462, 877.6313948141795, 959.9424034557949, 1003.9870060528739, 1008.6923488336128, 1017.4099022566893, 5477.315335583052, 5482.451754824486, 5988.790392336247, 6158.568900176944, 6220.34686357935, 6502.772402965874, 6782.593044417351, 6817.5909445433435, 6827.180738311435, 6849.7670419324, 5507.339119305709, 5646.97176514123, 5727.8568035799435, 6180.227471566054, 6218.284897236786, 3530.9646903530634, 3560.8041557714573, 3573.3210689968823, 3576.6065732769894, 3581.265840502743, 5498.395176530578, 6452.6772011737485, 6636.2368327642935, 6952.895706564405, 6975.546589471668, 6649.4347980421235, 6978.546394484342, 6994.230634630187, 6996.335348141918, 6998.335158159966, 5819.330776960619, 6643.7674681385915, 6801.829954251183, 6969.128858892638, 6972.965135174291, 5241.475852414728, 6648.800535983928, 6789.626570538668, 6793.218779840382, 6802.897956530755, 5321.866953326198, 6344.9048264277, 6639.734410623521, 6683.699233534406, 6686.331366863469, 5909.2908850938165, 6676.732930682719, 6950.513464057467, 6983.825404364931, 7016.0, 4210.621044106045, 4380.0, 4393.0, 4406.79660169915, 4431.8005689743395, 6216.595921265103, 6710.396064354231, 6750.6624668767345, 6769.93230067693, 6791.7283308669075, 5412.052890744183, 6255.749770009149, 6270.620463498023, 6520.18497687789, 6734.645956464525, 5743.511801496752, 6566.277709451861, 6578.243501997332, 6609.239937407067, 6621.834454138492, 5123.769430375568, 5345.866353341197, 5849.736761845642, 6479.870402591828, 6495.350464953467, 5505.752241149078, 6203.751849925952, 6491.513136514686, 6597.0, 6607.372299631527, 5380.461953804745, 6392.520560957853, 6407.71165297572, 6449.516286278454, 6487.296709885295], "value": 4717.385573408269, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [665.2383021440451, 722.2163952111962, 725.8366867454827, 730.7554458205565, 744.824966132959, 15125.24373781312, 15772.44796432125, 16504.174791260433, 16613.08628025458, 16729.996200227994, 13052.673683157926, 15140.924295378507, 15304.540863774077, 15420.61448463789, 15432.0, 928.0997432490474, 950.9239260859149, 976.584951395654, 976.9853452198223, 977.3061126600122, 965.2181732796422, 1005.0703099632847, 1005.7435353984723, 1007.8740157480315, 1014.7158795537262, 1081.0324759340385, 1082.8538147350087, 1084.5661735305862, 1086.858708367911, 1091.639758879572, 1073.4471747050275, 1075.688050465364, 1075.811732946735, 1076.633944458884, 1077.6443773554786, 807.1323327422986, 807.5356669914764, 807.5719868469721, 808.1151139502294, 808.7331180710352, 16269.674606507922, 16642.916785415997, 16749.413770517975, 16764.32942682291, 16974.745378819327, 14794.0, 14820.900427209956, 14864.554063378117, 15008.24958752069, 15679.0, 11571.710707232387, 11603.4198290086, 11677.299362038304, 11831.467583958742, 11916.582919597859, 3040.802347847383, 3043.2087657209063, 3062.096681478969, 3063.1116976077133, 3112.081935828936, 115281.0, 117416.0, 117461.41269293684, 117541.0, 121529.0, 10870.945645271722, 11360.772784544262, 11505.482253298622, 11897.225565809174, 12045.518179272905, 21239.256626018014, 25371.11201107989, 25375.873120634278, 25490.362740931625, 25635.102771403286, 7013.509054366247, 8082.393820463489, 8121.79695507617, 8144.959275203586, 8175.457748226, 4110.136871257069, 4166.375043743463, 4169.364236695624, 4199.811008504625, 4211.473565804275, 4139.482564679415, 4170.83316667336, 4200.726952748061, 4219.303814870574, 4258.276093064124, 836.1178956201267, 838.5807096451774, 848.9480318255984, 857.5326447086353, 860.6101146648118, 503.28282197868623, 532.2947095099009, 541.4369056181528, 586.7154430101398, 591.8313280714975, 9037.683681071263, 9041.90958090424, 9687.51562421876, 10261.819701658895, 10513.316634418738, 566.1196838915444, 583.1136672258144, 588.9146073819272, 589.2987345059365, 600.4625859855405, 4507.661925355612, 4745.454272758608, 4774.259989701602, 4806.927896081562, 4866.562009419169, 98824.0, 102970.45544316841, 103492.0, 104430.0, 107007.9299206998, 89257.66113508302, 89648.0, 90953.0, 91027.0, 91842.08157918468, 69386.30613693799, 70299.0, 70320.0, 70604.6469767648, 70668.0, 9516.095970882756, 9662.323637345318, 9690.37012594179, 9690.903090969001, 9724.513774311115, 35820.64179358173, 36296.81851590725, 36318.63681363153, 36429.63570364263, 37289.62710372861, 49829.0, 50208.74895625498, 50522.74738626283, 50604.49395505998, 51173.0, 11593.478293476644, 11710.648680539598, 11740.823887641864, 11872.228305160137, 12254.509819607472, 12767.425465853874, 12786.232826030468, 12853.807192891927, 12922.741545168856, 12970.740585188432, 11866.703332416413, 11875.465604048011, 11985.161038727205, 12371.13402061847, 12524.123311368481, 12172.57395991136, 12209.511619535118, 12400.875991240333, 12482.687932801753, 12525.0, 10154.79690406173, 11660.708482287671, 11719.648410547697, 12014.399280035788, 12166.330851802883, 8998.235150012322, 9073.682421115229, 9208.401453905475, 9214.308926830381, 9847.409155450696, 8630.654773808978, 8883.42257753244, 10107.69676909694, 10154.0, 10204.18366530661], "value": 7429.955296353956, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1613.3999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 123.0, 121.0, 133.0, 108.0, 170.0, 180.0, 137.0, 122.0, 123.0], "value": 132.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 121.0, 120.0, 165.0, 147.0, 155.0, 160.0, 121.0, 143.0, 121.0], "value": 141.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [207.0, 215.0, 210.0, 212.0, 205.0, 210.0, 208.0, 214.0, 211.0, 218.0], "value": 211.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 66.0, 62.0, 128.0, 95.0, 90.0, 61.0, 63.0, 87.0, 62.0], "value": 79.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 78.0, 97.0, 75.0, 75.0, 75.0, 89.0, 74.0, 75.0, 91.0], "value": 82.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 90.0, 91.0, 90.0, 92.0, 92.0, 91.0, 91.0, 96.0, 91.0], "value": 91.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 99.0, 101.0, 100.0, 99.0, 100.0, 98.0, 99.0, 102.0, 99.0], "value": 99.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 102.0, 108.0, 101.0, 101.0, 99.0, 99.0, 101.0, 101.0, 101.0], "value": 101.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 64.0, 65.0, 64.0, 64.0, 65.0, 65.0, 65.0, 62.0, 64.0], "value": 63.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 67.0, 60.0, 75.0, 69.0, 66.0, 63.0, 65.0, 67.0, 65.0], "value": 65.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 90.0, 90.0, 91.0, 88.0, 91.0, 89.0, 90.0, 88.0, 91.0], "value": 90.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 160.0, 154.0, 156.0, 166.0, 170.0, 155.0, 171.0, 218.0, 161.0], "value": 166.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [203.0, 244.0, 259.0, 216.0, 203.0, 202.0, 213.0, 196.0, 202.0, 196.0], "value": 213.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 71.0, 75.0, 74.0, 72.0, 75.0, 67.0, 64.0, 99.0, 78.0], "value": 74.6, "unit": "score"}]}]} 05:05:26 INFO - Return code: 0 05:05:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:05:26 ERROR - # TBPL SUCCESS # 05:05:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:05:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:05:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:05:26 INFO - 2017-03-30 05:05:26.457 screenresolution[1658:9798] starting screenresolution argv=screenresolution get 05:05:26 INFO - 2017-03-30 05:05:26.460 screenresolution[1658:9798] Display 0: 1600x1200x32@60 05:05:26 INFO - 2017-03-30 05:05:26.467 screenresolution[1659:9801] starting screenresolution argv=screenresolution list 05:05:26 INFO - Available Modes on Display 0 05:05:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:05:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:05:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:05:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:05:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:05:26 INFO - Intel Iris: 05:05:26 INFO - Chipset Model: Intel Iris 05:05:26 INFO - Type: GPU 05:05:26 INFO - Bus: Built-In 05:05:26 INFO - VRAM (Dynamic, Max): 1536 MB 05:05:26 INFO - Vendor: Intel (0x8086) 05:05:26 INFO - Device ID: 0x0a2e 05:05:26 INFO - Revision ID: 0x0009 05:05:26 INFO - Displays: 05:05:26 INFO - Display: 05:05:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:05:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:05:26 INFO - Display Serial Number: 1600x1200 60 05:05:26 INFO - Main Display: Yes 05:05:26 INFO - Mirror: Off 05:05:26 INFO - Online: Yes 05:05:26 INFO - Rotation: Supported 05:05:26 INFO - Return code: 0 05:05:26 INFO - Running post-action listener: _resource_record_post_action 05:05:26 INFO - [mozharness: 2017-03-30 12:05:26.715398Z] Finished run-tests step (success) 05:05:26 INFO - Running post-run listener: _resource_record_post_run 05:05:26 INFO - Total resource usage - Wall time: 1205s; CPU: 54.0%; Read bytes: 38208512; Write bytes: 513354752; Read time: 243; Write time: 1603 05:05:26 INFO - TinderboxPrint: CPU usage
53.6% 05:05:26 INFO - TinderboxPrint: I/O read bytes / time
38,208,512 / 243 05:05:26 INFO - TinderboxPrint: I/O write bytes / time
513,354,752 / 1,603 05:05:26 INFO - TinderboxPrint: CPU idle
2,237.0 (46.4%) 05:05:26 INFO - TinderboxPrint: CPU system
964.6 (20.0%) 05:05:26 INFO - TinderboxPrint: CPU user
1,620.1 (33.6%) 05:05:26 INFO - TinderboxPrint: Swap in / out
249,782,272 / 0 05:05:26 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 207168000; Write bytes: 204318720; Read time: 15629; Write time: 416 05:05:26 INFO - run-tests - Wall time: 1186s; CPU: 54.0%; Read bytes: 36152320; Write bytes: 298132480; Read time: 224; Write time: 1150 05:05:27 INFO - Running post-run listener: _upload_blobber_files 05:05:27 INFO - Blob upload gear active. 05:05:27 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:05:27 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 05:05:27 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'] 05:05:27 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 05:05:27 INFO - (blobuploader) - INFO - Open directory for files ... 05:05:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:05:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:05:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:05:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:05:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:05:28 INFO - (blobuploader) - INFO - Done attempting. 05:05:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:05:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:05:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:05:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:05:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:05:29 INFO - (blobuploader) - INFO - Done attempting. 05:05:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:05:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:05:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:05:30 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:05:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:05:30 INFO - (blobuploader) - INFO - Done attempting. 05:05:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:05:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:05:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:05:32 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:05:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:05:32 INFO - (blobuploader) - INFO - Done attempting. 05:05:32 INFO - (blobuploader) - INFO - Iteration through files over. 05:05:32 INFO - Return code: 0 05:05:32 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:05:32 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:05:32 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7aae805b80cefa6709a2caa659642c98f488cecba58451843cb7be0d6d07e196b12cb52c1e324b28921aef4c47d6947e716e1037edbb6117465bf04569cbc724", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5335f71dc6aa40ecd7e872ac81bf65696d09a7a646ef72caea42c3ecb6a26dcd53809b092df592a447308216a8a5cb17368a067cf9dcd5b70d570eed5f1bc60c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/086013c4dd8c229d71718b245464ebf74c297ae1f473ddd8ea2d8253899a9ba9f6e9331aec9999056bc91d0ad750845e29c3f949bd50a697766df6772316f611", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/018d59cd670f660b03c1d77eb120e4f647e8939be52504b21386f6116d612db1320d1083baa631efbb248a1cfb454bda1ebcdb41b82d8b1a3278c043cc57255b"} 05:05:32 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:05:32 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:05:32 INFO - Contents: 05:05:32 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7aae805b80cefa6709a2caa659642c98f488cecba58451843cb7be0d6d07e196b12cb52c1e324b28921aef4c47d6947e716e1037edbb6117465bf04569cbc724", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5335f71dc6aa40ecd7e872ac81bf65696d09a7a646ef72caea42c3ecb6a26dcd53809b092df592a447308216a8a5cb17368a067cf9dcd5b70d570eed5f1bc60c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/086013c4dd8c229d71718b245464ebf74c297ae1f473ddd8ea2d8253899a9ba9f6e9331aec9999056bc91d0ad750845e29c3f949bd50a697766df6772316f611", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/018d59cd670f660b03c1d77eb120e4f647e8939be52504b21386f6116d612db1320d1083baa631efbb248a1cfb454bda1ebcdb41b82d8b1a3278c043cc57255b"} 05:05:32 INFO - Running post-run listener: copy_logs_to_upload_dir 05:05:32 INFO - Copying logs to upload dir... 05:05:32 INFO - mkdir: /builds/slave/test/build/upload/logs 05:05:32 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1289.393120 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 29 secs) (at 2017-03-30 05:05:32.939051) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-30 05:05:32.942572) ========= 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.5YAaPzz5IK/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.UYlVaK9ZZq/Listeners TMPDIR=/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7aae805b80cefa6709a2caa659642c98f488cecba58451843cb7be0d6d07e196b12cb52c1e324b28921aef4c47d6947e716e1037edbb6117465bf04569cbc724", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5335f71dc6aa40ecd7e872ac81bf65696d09a7a646ef72caea42c3ecb6a26dcd53809b092df592a447308216a8a5cb17368a067cf9dcd5b70d570eed5f1bc60c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/086013c4dd8c229d71718b245464ebf74c297ae1f473ddd8ea2d8253899a9ba9f6e9331aec9999056bc91d0ad750845e29c3f949bd50a697766df6772316f611", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/018d59cd670f660b03c1d77eb120e4f647e8939be52504b21386f6116d612db1320d1083baa631efbb248a1cfb454bda1ebcdb41b82d8b1a3278c043cc57255b"} build_url:https://queue.taskcluster.net/v1/task/NT-b7EMFRvKwTQPTzQrgZw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011173 build_url: 'https://queue.taskcluster.net/v1/task/NT-b7EMFRvKwTQPTzQrgZw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7aae805b80cefa6709a2caa659642c98f488cecba58451843cb7be0d6d07e196b12cb52c1e324b28921aef4c47d6947e716e1037edbb6117465bf04569cbc724", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5335f71dc6aa40ecd7e872ac81bf65696d09a7a646ef72caea42c3ecb6a26dcd53809b092df592a447308216a8a5cb17368a067cf9dcd5b70d570eed5f1bc60c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/086013c4dd8c229d71718b245464ebf74c297ae1f473ddd8ea2d8253899a9ba9f6e9331aec9999056bc91d0ad750845e29c3f949bd50a697766df6772316f611", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/018d59cd670f660b03c1d77eb120e4f647e8939be52504b21386f6116d612db1320d1083baa631efbb248a1cfb454bda1ebcdb41b82d8b1a3278c043cc57255b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-30 05:05:32.976632) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 05:05:32.976965) ========= 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.5YAaPzz5IK/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.UYlVaK9ZZq/Listeners TMPDIR=/var/folders/8z/76ns34yj20gfly9p4jksxfwm00000w/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.005259 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 05:05:33.025258) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-30 05:05:33.025591) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-30 05:05:33.025940) ========= ========= Total master_lag: 0.17 =========