builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0316 starttime: 1490665071.13 results: success (0) buildid: 20170327171422 builduid: 879eb72ad7d04790b8ab9aae2ac17689 revision: 5182b2c4b963ed87d038c7d9a4021463917076cd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-27 18:37:51.128994) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-27 18:37:51.129514) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-27 18:37:51.130146) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-27 18:37:51.157544) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 18:37:51.157837) ========= 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.e6wBVfUPyX/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.SdzkxV9UgW/Listeners TMPDIR=/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/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-27 18:37:51-- 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% 18.9M=0.001s 2017-03-27 18:37:51 (18.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.122272 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 18:37:51.303958) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 18:37:51.304286) ========= 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.e6wBVfUPyX/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.SdzkxV9UgW/Listeners TMPDIR=/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/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.025959 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 18:37:51.352486) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-03-27 18:37:51.352854) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 5182b2c4b963ed87d038c7d9a4021463917076cd --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 5182b2c4b963ed87d038c7d9a4021463917076cd --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.e6wBVfUPyX/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.SdzkxV9UgW/Listeners TMPDIR=/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/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-27 18:37:51,469 truncating revision to first 12 chars 2017-03-27 18:37:51,469 Setting DEBUG logging. 2017-03-27 18:37:51,469 attempt 1/10 2017-03-27 18:37:51,469 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/5182b2c4b963?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-27 18:37:53,263 unpacking tar archive at: mozilla-central-5182b2c4b963/testing/mozharness/ program finished with exit code 0 elapsedTime=2.092526 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-03-27 18:37:53.471347) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-27 18:37:53.472165) ========= script_repo_revision: 5182b2c4b963ed87d038c7d9a4021463917076cd ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-27 18:37:53.472682) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-27 18:37:53.472980) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-27 18:37:53.493360) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 30 secs) (at 2017-03-27 18:37:53.494068) ========= /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.e6wBVfUPyX/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.SdzkxV9UgW/Listeners TMPDIR=/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/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 18:37:53 INFO - MultiFileLogger online at 20170327 18:37:53 in /builds/slave/test 18:37:53 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 18:37:53 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:37:53 INFO - {'append_to_log': False, 18:37:53 INFO - 'base_work_dir': '/builds/slave/test', 18:37:53 INFO - 'blob_upload_branch': 'Firefox', 18:37:53 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:37:53 INFO - 'branch': 'Firefox', 18:37:53 INFO - 'buildbot_json_path': 'buildprops.json', 18:37:53 INFO - 'config_files': ('talos/mac_config.py',), 18:37:53 INFO - 'default_actions': ('clobber', 18:37:53 INFO - 'read-buildbot-config', 18:37:53 INFO - 'download-and-extract', 18:37:53 INFO - 'populate-webroot', 18:37:53 INFO - 'create-virtualenv', 18:37:53 INFO - 'install', 18:37:53 INFO - 'run-tests'), 18:37:53 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:37:53 INFO - 'download_minidump_stackwalk': True, 18:37:53 INFO - 'download_symbols': 'ondemand', 18:37:53 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:37:53 INFO - 'tooltool.py': '/tools/tooltool.py', 18:37:53 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:37:53 INFO - '/tools/misc-python/virtualenv.py')}, 18:37:53 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:37:53 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:37:53 INFO - 'gecko_profile': False, 18:37:53 INFO - 'gecko_profile_interval': 0, 18:37:53 INFO - 'installer_path': 'installer.exe', 18:37:53 INFO - 'log_level': 'info', 18:37:53 INFO - 'log_name': 'talos', 18:37:53 INFO - 'log_to_console': True, 18:37:53 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:37:53 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:37:53 INFO - 'opt_config_files': (), 18:37:53 INFO - 'pip_index': False, 18:37:53 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:37:53 INFO - 'cmd': ('bash', 18:37:53 INFO - '-c', 18:37:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:37:53 INFO - 'enabled': True, 18:37:53 INFO - 'halt_on_failure': False, 18:37:53 INFO - 'name': 'check_screen_resolution'},), 18:37:53 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:37:53 INFO - 'cmd': ('bash', 18:37:53 INFO - '-c', 18:37:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:37:53 INFO - 'enabled': True, 18:37:53 INFO - 'halt_on_failure': False, 18:37:53 INFO - 'name': 'check_screen_resolution'},), 18:37:53 INFO - 'run_cmd_checks_enabled': True, 18:37:53 INFO - 'suite': 'dromaeojs', 18:37:53 INFO - 'system_bits': '32', 18:37:53 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:37:53 INFO - 'title': 't-yosemite-r7-0316', 18:37:53 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:37:53 INFO - 'use_talos_json': True, 18:37:53 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:37:53 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:37:53 INFO - 'work_dir': 'build'} 18:37:53 INFO - [mozharness: 2017-03-28 01:37:53.719649Z] Running clobber step. 18:37:53 INFO - Running pre-action listener: _resource_record_pre_action 18:37:53 INFO - Running main action method: clobber 18:37:53 INFO - rmtree: /builds/slave/test/build 18:37:53 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:37:56 INFO - Running post-action listener: _resource_record_post_action 18:37:56 INFO - [mozharness: 2017-03-28 01:37:56.075992Z] Finished clobber step (success) 18:37:56 INFO - [mozharness: 2017-03-28 01:37:56.076103Z] Running read-buildbot-config step. 18:37:56 INFO - Running pre-action listener: _resource_record_pre_action 18:37:56 INFO - Running main action method: read_buildbot_config 18:37:56 INFO - Using buildbot properties: 18:37:56 INFO - { 18:37:56 INFO - "project": "", 18:37:56 INFO - "product": "firefox", 18:37:56 INFO - "script_repo_revision": "production", 18:37:56 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 18:37:56 INFO - "repository": "", 18:37:56 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 18:37:56 INFO - "buildid": "20170327171422", 18:37:56 INFO - "pgo_build": "False", 18:37:56 INFO - "basedir": "/builds/slave/test", 18:37:56 INFO - "buildnumber": 37, 18:37:56 INFO - "slavename": "t-yosemite-r7-0316", 18:37:56 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 18:37:56 INFO - "platform": "yosemite_r7", 18:37:56 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 18:37:56 INFO - "branch": "mozilla-central", 18:37:56 INFO - "repo_path": "mozilla-central", 18:37:56 INFO - "revision": "5182b2c4b963ed87d038c7d9a4021463917076cd", 18:37:56 INFO - "stage_platform": "macosx64", 18:37:56 INFO - "builduid": "879eb72ad7d04790b8ab9aae2ac17689", 18:37:56 INFO - "slavebuilddir": "test" 18:37:56 INFO - } 18:37:56 INFO - Found installer url https://queue.taskcluster.net/v1/task/X8lSl9S6SX-lHF5wmppY_g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 18:37:56 INFO - Running post-action listener: _resource_record_post_action 18:37:56 INFO - [mozharness: 2017-03-28 01:37:56.079256Z] Finished read-buildbot-config step (success) 18:37:56 INFO - [mozharness: 2017-03-28 01:37:56.079369Z] Running download-and-extract step. 18:37:56 INFO - Running pre-action listener: _resource_record_pre_action 18:37:56 INFO - Running main action method: download_and_extract 18:37:56 INFO - mkdir: /builds/slave/test/build/tests 18:37: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')]} 18:37:56 INFO - trying https://queue.taskcluster.net/v1/task/X8lSl9S6SX-lHF5wmppY_g/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 18:37:56 INFO - Downloading https://queue.taskcluster.net/v1/task/X8lSl9S6SX-lHF5wmppY_g/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 18:37:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/X8lSl9S6SX-lHF5wmppY_g/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 18:37:57 INFO - Downloaded 1325 bytes. 18:37:57 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 18:37:57 INFO - Using the following test package requirements: 18:37:57 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:37:57 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 18:37:57 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 18:37:57 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:37:57 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 18:37:57 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:37:57 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 18:37:57 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:37:57 INFO - u'jsshell-mac.zip'], 18:37:57 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:37:57 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 18:37:57 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 18:37:57 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:37:57 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 18:37:57 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:37:57 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 18:37:57 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:37:57 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 18:37:57 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:37:57 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 18:37:57 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:37:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/X8lSl9S6SX-lHF5wmppY_g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 18:37:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/X8lSl9S6SX-lHF5wmppY_g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:37:57 INFO - Fetch https://queue.taskcluster.net/v1/task/X8lSl9S6SX-lHF5wmppY_g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 18:37:58 INFO - Expected file size: 52507270 18:37:58 INFO - Obtained file size: 52507270 18:38:14 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 18:38:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/X8lSl9S6SX-lHF5wmppY_g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 18:38:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/X8lSl9S6SX-lHF5wmppY_g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:38:14 INFO - Fetch https://queue.taskcluster.net/v1/task/X8lSl9S6SX-lHF5wmppY_g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 18:38:16 INFO - Expected file size: 52507270 18:38:16 INFO - Obtained file size: 52507270 18:38:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/X8lSl9S6SX-lHF5wmppY_g/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 18:38:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/X8lSl9S6SX-lHF5wmppY_g/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 18:38:31 INFO - Fetch https://queue.taskcluster.net/v1/task/X8lSl9S6SX-lHF5wmppY_g/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 18:38:32 INFO - Expected file size: 14404171 18:38:32 INFO - Obtained file size: 14404171 18:38:33 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')]} 18:38:33 INFO - trying https://queue.taskcluster.net/v1/task/X8lSl9S6SX-lHF5wmppY_g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:38:33 INFO - Downloading https://queue.taskcluster.net/v1/task/X8lSl9S6SX-lHF5wmppY_g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 18:38:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/X8lSl9S6SX-lHF5wmppY_g/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 18:38:35 INFO - Downloaded 75572932 bytes. 18:38:35 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/X8lSl9S6SX-lHF5wmppY_g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:38:35 INFO - mkdir: /builds/slave/test/properties 18:38:35 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:38:35 INFO - Writing to file /builds/slave/test/properties/build_url 18:38:35 INFO - Contents: 18:38:35 INFO - build_url:https://queue.taskcluster.net/v1/task/X8lSl9S6SX-lHF5wmppY_g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:38:35 INFO - Running post-action listener: _resource_record_post_action 18:38:35 INFO - Running post-action listener: set_extra_try_arguments 18:38:35 INFO - [mozharness: 2017-03-28 01:38:35.877392Z] Finished download-and-extract step (success) 18:38:35 INFO - [mozharness: 2017-03-28 01:38:35.877524Z] Running populate-webroot step. 18:38:35 INFO - Running pre-action listener: _resource_record_pre_action 18:38:35 INFO - Running main action method: populate_webroot 18:38:35 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:38:35 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:38:35 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:38:35 INFO - '--tppagecycles', 18:38:35 INFO - '7'], 18:38:35 INFO - 'tests': ['tsvgm']}}, 18:38:35 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:38:35 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:38:35 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:38:35 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:38:35 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:38:35 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:38:35 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 18:38:35 INFO - 'talos_options': ['--disable-e10s'], 18:38:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:38:35 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:38:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:38:35 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 18:38:35 INFO - 'talos_options': ['--disable-e10s'], 18:38:35 INFO - 'tests': ['damp', 'tps']}, 18:38:35 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:38:35 INFO - 'tests': ['damp', 'tps']}, 18:38:35 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:38:35 INFO - 'tests': ['dromaeo_dom']}, 18:38:35 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:38:35 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:38:35 INFO - 'tests': ['basic_compositor_video']}, 18:38:35 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:38:35 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:38:35 INFO - 'tests': ['a11yr', 18:38:35 INFO - 'ts_paint', 18:38:35 INFO - 'tpaint', 18:38:35 INFO - 'sessionrestore', 18:38:35 INFO - 'sessionrestore_no_auto_restore', 18:38:35 INFO - 'tabpaint']}, 18:38:35 INFO - 'other-e10s': {'tests': ['a11yr', 18:38:35 INFO - 'ts_paint', 18:38:35 INFO - 'tpaint', 18:38:35 INFO - 'sessionrestore', 18:38:35 INFO - 'sessionrestore_no_auto_restore', 18:38:35 INFO - 'tabpaint']}, 18:38:35 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:38:35 INFO - 'ts_paint', 18:38:35 INFO - 'tpaint', 18:38:35 INFO - 'sessionrestore', 18:38:35 INFO - 'sessionrestore_no_auto_restore', 18:38:35 INFO - 'tabpaint']}, 18:38:35 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:38:35 INFO - 'ts_paint', 18:38:35 INFO - 'tpaint', 18:38:35 INFO - 'sessionrestore', 18:38:35 INFO - 'sessionrestore_no_auto_restore', 18:38:35 INFO - 'tabpaint']}, 18:38:35 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:38:35 INFO - 'tests': ['a11yr', 18:38:35 INFO - 'ts_paint', 18:38:35 INFO - 'tpaint', 18:38:35 INFO - 'sessionrestore', 18:38:35 INFO - 'sessionrestore_no_auto_restore', 18:38:35 INFO - 'tabpaint']}, 18:38:35 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:38:35 INFO - 'tests': ['a11yr', 18:38:35 INFO - 'ts_paint', 18:38:35 INFO - 'tpaint', 18:38:35 INFO - 'sessionrestore', 18:38:35 INFO - 'sessionrestore_no_auto_restore', 18:38:35 INFO - 'tabpaint']}, 18:38:35 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:38:35 INFO - 'tests': ['tsvgx', 18:38:35 INFO - 'tsvgr_opacity', 18:38:35 INFO - 'tart', 18:38:35 INFO - 'tscrollx', 18:38:35 INFO - 'cart', 18:38:35 INFO - 'tsvg_static']}, 18:38:35 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:38:35 INFO - 'tsvgr_opacity', 18:38:35 INFO - 'tart', 18:38:35 INFO - 'tscrollx', 18:38:35 INFO - 'cart', 18:38:35 INFO - 'tsvg_static']}, 18:38:35 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 18:38:35 INFO - 'talos_options': ['--disable-e10s'], 18:38:35 INFO - 'tests': ['tp5o']}, 18:38:35 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:38:35 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 18:38:35 INFO - 'talos_options': ['--disable-e10s', 18:38:35 INFO - '--xperf_path', 18:38:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:38:35 INFO - 'tests': ['tp5n']}, 18:38:35 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:38:35 INFO - 'talos_options': ['--xperf_path', 18:38:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:38:35 INFO - 'tests': ['tp5n']}}, 18:38:35 INFO - 'talos.zip': {'path': '', 18:38:35 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:38:35 INFO - Running post-action listener: _resource_record_post_action 18:38:35 INFO - [mozharness: 2017-03-28 01:38:35.895657Z] Finished populate-webroot step (success) 18:38:35 INFO - [mozharness: 2017-03-28 01:38:35.895781Z] Running create-virtualenv step. 18:38:35 INFO - Running pre-action listener: _resource_record_pre_action 18:38:35 INFO - Running main action method: create_virtualenv 18:38:35 INFO - Creating virtualenv /builds/slave/test/build/venv 18:38:35 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 18:38:35 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:38:36 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:38:36 INFO - Using real prefix '/tools/python27' 18:38:36 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:38:36 INFO - Installing distribute.............................................................................................................................................................................................done. 18:38:39 INFO - Installing pip.................done. 18:38:39 INFO - Return code: 0 18:38:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:38:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:38:39 INFO - Reading from file tmpfile_stdout 18:38:39 INFO - Output received: 18:38:39 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) 18:38:39 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:38:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:38:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:38:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:38:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:38:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:38:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:38:39 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 0x10601db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106020768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fecb1465c00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105dfbd30>, '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 0x105d22db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105df0be8>, '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.e6wBVfUPyX/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.SdzkxV9UgW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/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 18:38:39 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 18:38:39 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 18:38:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.e6wBVfUPyX/Render', 18:38:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:38:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:38:39 INFO - 'HOME': '/Users/cltbld', 18:38:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:38:39 INFO - 'LOGNAME': 'cltbld', 18:38:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:38:39 INFO - 'MOZ_NO_REMOTE': '1', 18:38:39 INFO - 'NO_EM_RESTART': '1', 18:38:39 INFO - 'PAGER': '/bin/cat', 18:38:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:38:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:38:39 INFO - 'PWD': '/builds/slave/test', 18:38:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:38:39 INFO - 'SHELL': '/bin/bash', 18:38:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SdzkxV9UgW/Listeners', 18:38:39 INFO - 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/', 18:38:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:38:39 INFO - 'USER': 'cltbld', 18:38:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:38:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:38:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:38:39 INFO - 'XPC_FLAGS': '0x0', 18:38:39 INFO - 'XPC_SERVICE_NAME': '0', 18:38:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:38:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:38:40 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 18:38:40 INFO - Cleaning up... 18:38:40 INFO - Return code: 0 18:38:40 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:38:40 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')]} 18:38:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:38:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:38:40 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')]} 18:38:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:38:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:38:40 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 0x10601db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106020768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fecb1465c00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105dfbd30>, '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 0x105d22db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105df0be8>, '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.e6wBVfUPyX/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.SdzkxV9UgW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/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 18:38:40 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 18:38:40 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 18:38:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.e6wBVfUPyX/Render', 18:38:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:38:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:38:40 INFO - 'HOME': '/Users/cltbld', 18:38:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:38:40 INFO - 'LOGNAME': 'cltbld', 18:38:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:38:40 INFO - 'MOZ_NO_REMOTE': '1', 18:38:40 INFO - 'NO_EM_RESTART': '1', 18:38:40 INFO - 'PAGER': '/bin/cat', 18:38:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:38:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:38:40 INFO - 'PWD': '/builds/slave/test', 18:38:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:38:40 INFO - 'SHELL': '/bin/bash', 18:38:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SdzkxV9UgW/Listeners', 18:38:40 INFO - 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/', 18:38:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:38:40 INFO - 'USER': 'cltbld', 18:38:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:38:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:38:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:38:40 INFO - 'XPC_FLAGS': '0x0', 18:38:40 INFO - 'XPC_SERVICE_NAME': '0', 18:38:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:38:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:38:40 INFO - Downloading/unpacking psutil>=3.1.1 18:38:40 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 18:38:40 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 18:38:40 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 18:38:40 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 18:38:40 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 18:38:40 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 18:38:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:38:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:38:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:38:43 INFO - Installing collected packages: psutil 18:38:43 INFO - Running setup.py install for psutil 18:38:44 INFO - building 'psutil._psutil_osx' extension 18:38:44 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 18:38:44 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 18:38:44 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 18:38:44 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 18:38:44 INFO - building 'psutil._psutil_posix' extension 18:38:44 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 18:38:44 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:38:44 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:38:44 INFO - ^ 18:38:44 INFO - 1 warning generated. 18:38:44 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 18:38:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:38:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:38:45 INFO - Successfully installed psutil 18:38:45 INFO - Cleaning up... 18:38:45 INFO - Return code: 0 18:38:45 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:38:45 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')]} 18:38:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:38:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:38:45 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')]} 18:38:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:38:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:38:45 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 0x10601db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106020768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fecb1465c00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105dfbd30>, '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 0x105d22db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105df0be8>, '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.e6wBVfUPyX/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.SdzkxV9UgW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/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 18:38:45 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 18:38:45 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 18:38:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.e6wBVfUPyX/Render', 18:38:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:38:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:38:45 INFO - 'HOME': '/Users/cltbld', 18:38:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:38:45 INFO - 'LOGNAME': 'cltbld', 18:38:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:38:45 INFO - 'MOZ_NO_REMOTE': '1', 18:38:45 INFO - 'NO_EM_RESTART': '1', 18:38:45 INFO - 'PAGER': '/bin/cat', 18:38:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:38:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:38:45 INFO - 'PWD': '/builds/slave/test', 18:38:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:38:45 INFO - 'SHELL': '/bin/bash', 18:38:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SdzkxV9UgW/Listeners', 18:38:45 INFO - 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/', 18:38:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:38:45 INFO - 'USER': 'cltbld', 18:38:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:38:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:38:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:38:45 INFO - 'XPC_FLAGS': '0x0', 18:38:45 INFO - 'XPC_SERVICE_NAME': '0', 18:38:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:38:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:38:45 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:38:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:38:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:38:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:38:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:38:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:38:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:38:48 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:38:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:38:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:38:48 INFO - Installing collected packages: mozsystemmonitor 18:38:48 INFO - Running setup.py install for mozsystemmonitor 18:38:48 INFO - Successfully installed mozsystemmonitor 18:38:48 INFO - Cleaning up... 18:38:48 INFO - Return code: 0 18:38:48 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:38:48 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')]} 18:38:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:38:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:38:48 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')]} 18:38:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:38:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:38:49 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 0x10601db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106020768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fecb1465c00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105dfbd30>, '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 0x105d22db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105df0be8>, '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.e6wBVfUPyX/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.SdzkxV9UgW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/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 18:38:49 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 18:38:49 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 18:38:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.e6wBVfUPyX/Render', 18:38:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:38:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:38:49 INFO - 'HOME': '/Users/cltbld', 18:38:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:38:49 INFO - 'LOGNAME': 'cltbld', 18:38:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:38:49 INFO - 'MOZ_NO_REMOTE': '1', 18:38:49 INFO - 'NO_EM_RESTART': '1', 18:38:49 INFO - 'PAGER': '/bin/cat', 18:38:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:38:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:38:49 INFO - 'PWD': '/builds/slave/test', 18:38:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:38:49 INFO - 'SHELL': '/bin/bash', 18:38:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SdzkxV9UgW/Listeners', 18:38:49 INFO - 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/', 18:38:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:38:49 INFO - 'USER': 'cltbld', 18:38:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:38:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:38:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:38:49 INFO - 'XPC_FLAGS': '0x0', 18:38:49 INFO - 'XPC_SERVICE_NAME': '0', 18:38:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:38:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:38:49 INFO - Downloading/unpacking jsonschema==2.5.1 18:38:49 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 18:38:49 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 18:38:49 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 18:38:49 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 18:38:49 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 18:38:49 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 18:38:52 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:38:52 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:38:52 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 18:38:52 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 18:38:52 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 18:38:52 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 18:38:52 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 18:38:52 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 18:38:52 INFO - Downloading functools32-3.2.3-2.tar.gz 18:38:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:38:52 INFO - warning: no files found matching '*.txt' 18:38:52 INFO - no previously-included directories found matching 'build' 18:38:52 INFO - no previously-included directories found matching 'dist' 18:38:52 INFO - no previously-included directories found matching '.git*' 18:38:52 INFO - Installing collected packages: jsonschema, functools32 18:38:52 INFO - Running setup.py install for functools32 18:38:53 INFO - warning: no files found matching '*.txt' 18:38:53 INFO - no previously-included directories found matching 'build' 18:38:53 INFO - no previously-included directories found matching 'dist' 18:38:53 INFO - no previously-included directories found matching '.git*' 18:38:53 INFO - Successfully installed jsonschema functools32 18:38:53 INFO - Cleaning up... 18:38:53 INFO - Return code: 0 18:38:53 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:38:53 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')]} 18:38:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:38:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:38:53 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')]} 18:38:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:38:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:38:53 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 0x10601db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106020768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fecb1465c00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105dfbd30>, '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 0x105d22db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105df0be8>, '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.e6wBVfUPyX/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.SdzkxV9UgW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/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 18:38:53 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 18:38:53 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 18:38:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.e6wBVfUPyX/Render', 18:38:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:38:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:38:53 INFO - 'HOME': '/Users/cltbld', 18:38:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:38:53 INFO - 'LOGNAME': 'cltbld', 18:38:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:38:53 INFO - 'MOZ_NO_REMOTE': '1', 18:38:53 INFO - 'NO_EM_RESTART': '1', 18:38:53 INFO - 'PAGER': '/bin/cat', 18:38:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:38:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:38:53 INFO - 'PWD': '/builds/slave/test', 18:38:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:38:53 INFO - 'SHELL': '/bin/bash', 18:38:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SdzkxV9UgW/Listeners', 18:38:53 INFO - 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/', 18:38:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:38:53 INFO - 'USER': 'cltbld', 18:38:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:38:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:38:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:38:53 INFO - 'XPC_FLAGS': '0x0', 18:38:53 INFO - 'XPC_SERVICE_NAME': '0', 18:38:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:38:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:38:53 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:38:53 INFO - Cleaning up... 18:38:53 INFO - Return code: 0 18:38:53 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:38:53 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')]} 18:38:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:38:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:38:53 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')]} 18:38:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:38:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:38:53 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 0x10601db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106020768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fecb1465c00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105dfbd30>, '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 0x105d22db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105df0be8>, '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.e6wBVfUPyX/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.SdzkxV9UgW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/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 18:38:53 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 18:38:53 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 18:38:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.e6wBVfUPyX/Render', 18:38:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:38:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:38:53 INFO - 'HOME': '/Users/cltbld', 18:38:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:38:53 INFO - 'LOGNAME': 'cltbld', 18:38:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:38:53 INFO - 'MOZ_NO_REMOTE': '1', 18:38:53 INFO - 'NO_EM_RESTART': '1', 18:38:53 INFO - 'PAGER': '/bin/cat', 18:38:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:38:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:38:53 INFO - 'PWD': '/builds/slave/test', 18:38:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:38:53 INFO - 'SHELL': '/bin/bash', 18:38:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SdzkxV9UgW/Listeners', 18:38:53 INFO - 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/', 18:38:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:38:53 INFO - 'USER': 'cltbld', 18:38:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:38:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:38:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:38:53 INFO - 'XPC_FLAGS': '0x0', 18:38:53 INFO - 'XPC_SERVICE_NAME': '0', 18:38:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:38:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:38:53 INFO - Downloading/unpacking blobuploader==1.2.4 18:38:53 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 18:38:53 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 18:38:53 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 18:38:53 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 18:38:53 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 18:38:53 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 18:38:56 INFO - Downloading blobuploader-1.2.4.tar.gz 18:38:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:38:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:38: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 18:38: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 18:38: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 18:38: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 18:38: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 18:38: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 18:38:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:38:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:38:57 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 18:38:57 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 18:38:57 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 18:38:57 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 18:38:57 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 18:38:57 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 18:38:57 INFO - Downloading docopt-0.6.1.tar.gz 18:38:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:38:57 INFO - Installing collected packages: blobuploader, requests, docopt 18:38:57 INFO - Running setup.py install for blobuploader 18:38:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:38:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:38:57 INFO - Running setup.py install for requests 18:38:58 INFO - Running setup.py install for docopt 18:38:58 INFO - Successfully installed blobuploader requests docopt 18:38:58 INFO - Cleaning up... 18:38:58 INFO - Return code: 0 18:38:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:38:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:38:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:38:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:38:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:38:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:38:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:38:58 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 0x10601db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106020768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fecb1465c00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105dfbd30>, '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 0x105d22db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105df0be8>, '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.e6wBVfUPyX/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.SdzkxV9UgW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/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 18:38:58 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 18:38:58 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 18:38:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.e6wBVfUPyX/Render', 18:38:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:38:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:38:58 INFO - 'HOME': '/Users/cltbld', 18:38:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:38:58 INFO - 'LOGNAME': 'cltbld', 18:38:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:38:58 INFO - 'MOZ_NO_REMOTE': '1', 18:38:58 INFO - 'NO_EM_RESTART': '1', 18:38:58 INFO - 'PAGER': '/bin/cat', 18:38:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:38:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:38:58 INFO - 'PWD': '/builds/slave/test', 18:38:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:38:58 INFO - 'SHELL': '/bin/bash', 18:38:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SdzkxV9UgW/Listeners', 18:38:58 INFO - 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/', 18:38:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:38:58 INFO - 'USER': 'cltbld', 18:38:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:38:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:38:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:38:58 INFO - 'XPC_FLAGS': '0x0', 18:38:58 INFO - 'XPC_SERVICE_NAME': '0', 18:38:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:38:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:38:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:38:58 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-QUgwjx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:38:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:38:58 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-N6ADLC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:38:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:38:59 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-ypX4NG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:38:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:38:59 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-jDXl9S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:38:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:38:59 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-9yIFWg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:38:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:38:59 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-sxYKRa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:38:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:38:59 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-wILuJR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:38:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:38:59 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-esUqLv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:38:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:38:59 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-_Dh3Vy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:38:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:38:59 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-R3aZqR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:38:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:38:59 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-Eci2da-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:38:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:38:59 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-xZOvnM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:39:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:39:00 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-SI40Ee-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:39:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:39:00 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-zWFTl1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:39:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:39:00 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-EgadyI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:39:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:39:00 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-4XMCJ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:39:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:39:00 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-4H41lb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:39:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:39:00 INFO - Running setup.py install for manifestparser 18:39:00 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:39:00 INFO - Running setup.py install for mozcrash 18:39:00 INFO - Running setup.py install for mozdebug 18:39:01 INFO - Running setup.py install for mozdevice 18:39:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:39:01 INFO - Running setup.py install for mozfile 18:39:01 INFO - Running setup.py install for mozhttpd 18:39:01 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:39:01 INFO - Running setup.py install for mozinfo 18:39:01 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:39:01 INFO - Running setup.py install for mozInstall 18:39:01 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:39:01 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:39:01 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:39:01 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:39:01 INFO - Running setup.py install for mozleak 18:39:01 INFO - Running setup.py install for mozlog 18:39:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:39:02 INFO - Running setup.py install for moznetwork 18:39:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:39:02 INFO - Running setup.py install for mozprocess 18:39:02 INFO - Running setup.py install for mozprofile 18:39:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:39:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:39:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:39:02 INFO - Running setup.py install for mozrunner 18:39:02 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:39:02 INFO - Running setup.py install for mozscreenshot 18:39:02 INFO - Running setup.py install for moztest 18:39:03 INFO - Running setup.py install for mozversion 18:39:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:39:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:39:03 INFO - Cleaning up... 18:39:03 INFO - Return code: 0 18:39:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:39:03 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')]} 18:39:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:39:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:39:03 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')]} 18:39:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:39:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:39:03 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 0x10601db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106020768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fecb1465c00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105dfbd30>, '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 0x105d22db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105df0be8>, '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.e6wBVfUPyX/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.SdzkxV9UgW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/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 18:39:03 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 18:39:03 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 18:39:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.e6wBVfUPyX/Render', 18:39:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:39:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:39:03 INFO - 'HOME': '/Users/cltbld', 18:39:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:39:03 INFO - 'LOGNAME': 'cltbld', 18:39:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:39:03 INFO - 'MOZ_NO_REMOTE': '1', 18:39:03 INFO - 'NO_EM_RESTART': '1', 18:39:03 INFO - 'PAGER': '/bin/cat', 18:39:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:39:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:39:03 INFO - 'PWD': '/builds/slave/test', 18:39:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:39:03 INFO - 'SHELL': '/bin/bash', 18:39:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SdzkxV9UgW/Listeners', 18:39:03 INFO - 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/', 18:39:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:39:03 INFO - 'USER': 'cltbld', 18:39:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:39:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:39:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:39:03 INFO - 'XPC_FLAGS': '0x0', 18:39:03 INFO - 'XPC_SERVICE_NAME': '0', 18:39:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:39:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:39:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:39:03 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-hYJ_8q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:39:03 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)) 18:39:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:39:03 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-u6ZyLH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:39:03 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)) 18:39:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:39:03 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-qLLavJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:39:03 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)) 18:39:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:39:03 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-CwXZoS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:39:03 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)) 18:39:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:39:03 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-D18D9C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:39:03 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)) 18:39:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:39:03 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-SDcmGR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:39:04 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)) 18:39:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:39:04 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-HJOZvp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:39:04 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)) 18:39:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:39:04 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-EDwUab-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:39:04 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)) 18:39:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:39:04 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-5zMft2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:39:04 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)) 18:39:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:39:04 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-mTWSIo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:39:04 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)) 18:39:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:39:04 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-HWk9I8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:39:04 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)) 18:39:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:39:04 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-YqXATu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:39:04 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)) 18:39:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:39:04 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-aavuQY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:39:04 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)) 18:39:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:39:04 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-atk9ao-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:39:04 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)) 18:39:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:39:04 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-UdJ8Sf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:39:05 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)) 18:39:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:39:05 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-7UVSwF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:39:05 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)) 18:39:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:39:05 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-YwT8XE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:39:05 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)) 18:39:05 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)) 18:39:05 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)) 18:39:05 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)) 18:39:05 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)) 18:39:05 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)) 18:39:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:39:05 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 18:39:05 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 18:39:05 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 18:39:05 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 18:39:05 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 18:39:05 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 18:39:07 INFO - Downloading blessings-1.6.tar.gz 18:39:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:39:08 INFO - Installing collected packages: blessings 18:39:08 INFO - Running setup.py install for blessings 18:39:08 INFO - Successfully installed blessings 18:39:08 INFO - Cleaning up... 18:39:08 INFO - Return code: 0 18:39:08 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:39:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:39:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:39:08 INFO - Reading from file tmpfile_stdout 18:39:08 INFO - Current package versions: 18:39:08 INFO - blessings == 1.6 18:39:08 INFO - blobuploader == 1.2.4 18:39:08 INFO - docopt == 0.6.1 18:39:08 INFO - functools32 == 3.2.3-2 18:39:08 INFO - jsonschema == 2.5.1 18:39:08 INFO - manifestparser == 1.1 18:39:08 INFO - mozInstall == 1.12 18:39:08 INFO - mozcrash == 1.0 18:39:08 INFO - mozdebug == 0.1 18:39:08 INFO - mozdevice == 0.49 18:39:08 INFO - mozfile == 1.2 18:39:08 INFO - mozhttpd == 0.7 18:39:08 INFO - mozinfo == 0.9 18:39:08 INFO - mozleak == 0.1 18:39:08 INFO - mozlog == 3.4 18:39:08 INFO - moznetwork == 0.27 18:39:08 INFO - mozprocess == 0.25 18:39:08 INFO - mozprofile == 0.28 18:39:08 INFO - mozrunner == 6.13 18:39:08 INFO - mozscreenshot == 0.1 18:39:08 INFO - mozsystemmonitor == 0.3 18:39:08 INFO - moztest == 0.8 18:39:08 INFO - mozversion == 1.4 18:39:08 INFO - psutil == 3.1.1 18:39:08 INFO - requests == 1.2.3 18:39:08 INFO - wsgiref == 0.1.2 18:39:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:39:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:39:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:39:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:39:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:39:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:39:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:39:08 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 0x10601db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106020768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fecb1465c00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105dfbd30>, '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 0x105d22db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105df0be8>, '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.e6wBVfUPyX/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.SdzkxV9UgW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/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 18:39:08 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 18:39:08 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 18:39:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.e6wBVfUPyX/Render', 18:39:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:39:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:39:08 INFO - 'HOME': '/Users/cltbld', 18:39:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:39:08 INFO - 'LOGNAME': 'cltbld', 18:39:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:39:08 INFO - 'MOZ_NO_REMOTE': '1', 18:39:08 INFO - 'NO_EM_RESTART': '1', 18:39:08 INFO - 'PAGER': '/bin/cat', 18:39:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:39:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:39:08 INFO - 'PWD': '/builds/slave/test', 18:39:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:39:08 INFO - 'SHELL': '/bin/bash', 18:39:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SdzkxV9UgW/Listeners', 18:39:08 INFO - 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/', 18:39:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:39:08 INFO - 'USER': 'cltbld', 18:39:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:39:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:39:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:39:08 INFO - 'XPC_FLAGS': '0x0', 18:39:08 INFO - 'XPC_SERVICE_NAME': '0', 18:39:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:39:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:39:08 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)) 18:39:08 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)) 18:39:08 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)) 18:39:08 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)) 18:39:08 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)) 18:39:08 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)) 18:39:08 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)) 18:39:08 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)) 18:39:08 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)) 18:39:08 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)) 18:39:08 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)) 18:39:08 INFO - Cleaning up... 18:39:08 INFO - Return code: 0 18:39:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:39:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:39:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:39:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:39:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:39:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:39:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:39:08 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 0x10601db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106020768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fecb1465c00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105dfbd30>, '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 0x105d22db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105df0be8>, '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.e6wBVfUPyX/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.SdzkxV9UgW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/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 18:39:08 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 18:39:08 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 18:39:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.e6wBVfUPyX/Render', 18:39:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:39:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:39:08 INFO - 'HOME': '/Users/cltbld', 18:39:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:39:08 INFO - 'LOGNAME': 'cltbld', 18:39:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:39:08 INFO - 'MOZ_NO_REMOTE': '1', 18:39:08 INFO - 'NO_EM_RESTART': '1', 18:39:08 INFO - 'PAGER': '/bin/cat', 18:39:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:39:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:39:08 INFO - 'PWD': '/builds/slave/test', 18:39:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:39:08 INFO - 'SHELL': '/bin/bash', 18:39:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SdzkxV9UgW/Listeners', 18:39:08 INFO - 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/', 18:39:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:39:08 INFO - 'USER': 'cltbld', 18:39:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:39:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:39:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:39:08 INFO - 'XPC_FLAGS': '0x0', 18:39:08 INFO - 'XPC_SERVICE_NAME': '0', 18:39:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:39:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:39:09 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:39:09 INFO - Cleaning up... 18:39:09 INFO - Return code: 0 18:39:09 INFO - Running post-action listener: _resource_record_post_action 18:39:09 INFO - Running post-action listener: _start_resource_monitoring 18:39:09 INFO - Starting resource monitoring. 18:39:09 INFO - [mozharness: 2017-03-28 01:39:09.154082Z] Finished create-virtualenv step (success) 18:39:09 INFO - [mozharness: 2017-03-28 01:39:09.155051Z] Running install step. 18:39:09 INFO - Running pre-action listener: _resource_record_pre_action 18:39:09 INFO - Running main action method: install 18:39:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:39:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:39:09 INFO - Reading from file tmpfile_stdout 18:39:09 INFO - Detecting whether we're running mozinstall >=1.0... 18:39:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:39:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:39:09 INFO - Reading from file tmpfile_stdout 18:39:09 INFO - Output received: 18:39:09 INFO - Usage: mozinstall [options] installer 18:39:09 INFO - Options: 18:39:09 INFO - -h, --help show this help message and exit 18:39:09 INFO - -d DEST, --destination=DEST 18:39:09 INFO - Directory to install application into. [default: 18:39:09 INFO - "/builds/slave/test"] 18:39:09 INFO - --app=APP Application being installed. [default: firefox] 18:39:09 INFO - mkdir: /builds/slave/test/build/application 18:39:09 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'] 18:39:09 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 18:39:30 INFO - Reading from file tmpfile_stdout 18:39:30 INFO - Output received: 18:39:30 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:39:30 INFO - Running post-action listener: _resource_record_post_action 18:39:30 INFO - [mozharness: 2017-03-28 01:39:30.125072Z] Finished install step (success) 18:39:30 INFO - [mozharness: 2017-03-28 01:39:30.125221Z] Running run-tests step. 18:39:30 INFO - Running pre-action listener: _resource_record_pre_action 18:39:30 INFO - Running main action method: run_tests 18:39:30 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:39:30 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:39:30 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:39:30 INFO - 2017-03-27 18:39:30.139 screenresolution[1628:7301] starting screenresolution argv=screenresolution get 18:39:30 INFO - 2017-03-27 18:39:30.142 screenresolution[1628:7301] Display 0: 1600x1200x32@60 18:39:30 INFO - 2017-03-27 18:39:30.148 screenresolution[1629:7304] starting screenresolution argv=screenresolution list 18:39:30 INFO - Available Modes on Display 0 18:39:30 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:39:30 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:39:30 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:39:30 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:39:30 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:39:30 INFO - Intel Iris: 18:39:30 INFO - Chipset Model: Intel Iris 18:39:30 INFO - Type: GPU 18:39:30 INFO - Bus: Built-In 18:39:30 INFO - VRAM (Dynamic, Max): 1536 MB 18:39:30 INFO - Vendor: Intel (0x8086) 18:39:30 INFO - Device ID: 0x0a2e 18:39:30 INFO - Revision ID: 0x0009 18:39:30 INFO - Displays: 18:39:30 INFO - Display: 18:39:30 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:39:30 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:39:30 INFO - Display Serial Number: 1600x1200 60 18:39:30 INFO - Main Display: Yes 18:39:30 INFO - Mirror: Off 18:39:30 INFO - Online: Yes 18:39:30 INFO - Rotation: Supported 18:39:30 INFO - Return code: 0 18:39:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:39:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:39:30 INFO - Python 2.7.3 18:39:30 INFO - Return code: 0 18:39:30 INFO - grabbing minidump binary from tooltool 18:39:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:39:30 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 0x105dfbd30>, '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 0x105d22db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105df0be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:39:30 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 18:39:30 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 18:39:30 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 18:39:30 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:39:30 INFO - Return code: 0 18:39:30 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:39:30 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:39:30 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:39:30 INFO - ENV: RUST_BACKTRACE is now 1 18:39:30 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:39:30 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:39:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/X8lSl9S6SX-lHF5wmppY_g/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0316', '--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 18:39:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/X8lSl9S6SX-lHF5wmppY_g/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0316 --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 18:39:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.e6wBVfUPyX/Render', 18:39:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:39:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:39:30 INFO - 'HOME': '/Users/cltbld', 18:39:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:39:30 INFO - 'LOGNAME': 'cltbld', 18:39:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:39:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:39:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:39:30 INFO - 'MOZ_NO_REMOTE': '1', 18:39:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:39:30 INFO - 'NO_EM_RESTART': '1', 18:39:30 INFO - 'PAGER': '/bin/cat', 18:39:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:39:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:39:30 INFO - 'PWD': '/builds/slave/test', 18:39:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:39:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:39:30 INFO - 'RUST_BACKTRACE': '1', 18:39:30 INFO - 'SHELL': '/bin/bash', 18:39:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SdzkxV9UgW/Listeners', 18:39:30 INFO - 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/', 18:39:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:39:30 INFO - 'USER': 'cltbld', 18:39:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:39:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:39:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:39:30 INFO - 'XPC_FLAGS': '0x0', 18:39:30 INFO - 'XPC_SERVICE_NAME': '0', 18:39:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:39:30 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/X8lSl9S6SX-lHF5wmppY_g/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0316', '--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 18:39:30 INFO - mozversion application_buildid: 20170327171422 18:39:30 INFO - mozversion application_changeset: 5182b2c4b963ed87d038c7d9a4021463917076cd 18:39:30 INFO - mozversion application_display_name: Nightly 18:39:30 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:39:30 INFO - mozversion application_name: Firefox 18:39:30 INFO - mozversion application_remotingname: firefox 18:39:30 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:39:30 INFO - mozversion application_vendor: Mozilla 18:39:30 INFO - mozversion application_version: 55.0a1 18:39:30 INFO - mozversion platform_buildid: 20170327171422 18:39:30 INFO - mozversion platform_changeset: 5182b2c4b963ed87d038c7d9a4021463917076cd 18:39:30 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:39:30 INFO - mozversion platform_version: 55.0a1 18:39:30 INFO - using testdate: 1490665170 18:39:30 INFO - actual date: 1490665170 18:39:30 INFO - starting webserver on 'localhost:49235' 18:39:30 INFO - SUITE-START | Running 2 tests 18:39:30 INFO - TEST-START | dromaeo_css 18:39:30 INFO - operating with platform_type : mac_ 18:39:30 INFO - Initialising browser for dromaeo_css test... 18:39:30 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/tmpOhA8b2/profile http://localhost:49235/getInfo.html) 18:39:33 INFO - PID 1639 | __metrics Screen width/height:1600/1200 18:39:33 INFO - PID 1639 | colorDepth:24 18:39:33 INFO - PID 1639 | Browser inner width/height: 1024/689 18:39:33 INFO - PID 1639 | __metrics 18:39:33 ERROR - PID 1639 | 1490665173465 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/Nightly.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/Nightly.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 18:39:33 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" 18:39:33 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] 18:39:34 INFO - TEST-INFO | 1639: exit 0 18:39:34 INFO - Browser initialized. 18:39:34 INFO - Running cycle 1/1 for dromaeo_css test... 18:39:34 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/tmpOhA8b2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:39:46 INFO - PID 1641 | RSS: Main: 252633088 18:39:46 INFO - PID 1641 | 18:42:51 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49235/tests/dromaeo/cssquery-ext.html) 18:42:53 INFO - PID 1641 | RSS: Main: 417325056 18:42:53 INFO - PID 1641 | 18:44:36 INFO - PID 1641 | ************************* 18:44:36 INFO - PID 1641 | A coding exception was thrown and uncaught in a Task. 18:44:36 INFO - PID 1641 | 18:44:36 ERROR - PID 1641 | Full message: TypeError: NetworkError when attempting to fetch resource. 18:44:36 INFO - PID 1641 | Full stack: 18:44:36 INFO - PID 1641 | ************************* 18:44:36 INFO - PID 1641 | ************************* 18:44:36 INFO - PID 1641 | A coding exception was thrown and uncaught in a Task. 18:44:36 INFO - PID 1641 | 18:44:36 ERROR - PID 1641 | Full message: TypeError: NetworkError when attempting to fetch resource. 18:44:36 INFO - PID 1641 | Full stack: 18:44:36 INFO - PID 1641 | ************************* 18:44:36 INFO - PID 1641 | ************************* 18:44:36 INFO - PID 1641 | A coding exception was thrown and uncaught in a Task. 18:44:36 INFO - PID 1641 | 18:44:36 ERROR - PID 1641 | Full message: TypeError: NetworkError when attempting to fetch resource. 18:44:36 INFO - PID 1641 | Full stack: 18:44:36 INFO - PID 1641 | ************************* 18:44:36 INFO - PID 1641 | ************************* 18:44:36 INFO - PID 1641 | A coding exception was thrown and uncaught in a Task. 18:44:36 INFO - PID 1641 | 18:44:36 ERROR - PID 1641 | Full message: TypeError: NetworkError when attempting to fetch resource. 18:44:36 INFO - PID 1641 | Full stack: 18:44:36 INFO - PID 1641 | ************************* 18:45:56 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-ext.html (next: http://localhost:49235/tests/dromaeo/cssquery-jquery.html) 18:45:57 INFO - PID 1641 | RSS: Main: 384937984 18:45:57 INFO - PID 1641 | 18:48:59 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49235/tests/dromaeo/cssquery-mootools.html) 18:49:00 INFO - PID 1641 | RSS: Main: 357199872 18:49:00 INFO - PID 1641 | 18:52:02 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49235/tests/dromaeo/cssquery-prototype.html) 18:52:03 INFO - PID 1641 | RSS: Main: 345067520 18:52:03 INFO - PID 1641 | 18:55:06 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49235/tests/dromaeo/cssquery-yui.html) 18:55:07 INFO - PID 1641 | RSS: Main: 354709504 18:55:07 INFO - PID 1641 | 18:58:10 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-yui.html (next: http://localhost:49235/tests/dromaeo/cssquery-dojo.html) 18:58:11 INFO - PID 1641 | RSS: Main: 612663296 18:58:11 INFO - PID 1641 | 18:58:11 INFO - PID 1641 | __start_tp_report 18:58:11 INFO - PID 1641 | _x_x_mozilla_page_load 18:58:11 INFO - PID 1641 | _x_x_mozilla_page_load_details 18:58:11 INFO - PID 1641 | |i|pagename|runs| 18:58:11 INFO - PID 1641 | |0;dojo.html;3382.154461384654;3422.5208470814073;3435.776674516156;3461.7057550108248;3489.0928358626766;6101.065651476874;6137.41694539018;6141.608749743943;6144.494598823295;6162.445379915806;5706.486416222539;5870.119482077691;6066.332703402622;6112.290716697406;6127.662978536178;5441.047816632094;5472.753726082336;5495.945040549604;5502.889942201154;5601.747921343549;5378.139497680372;5463.043967305725;5534.197541356501;5559.138333558285;5561;5411.080116380225;5474.42518535552;5514.503694667459;5556.138798486241;5577.442255774431;5460.235567020621;5464.016477034133;5473.589480788957;5477.233187353773;5508.752106155192;5468.945310546884;5475.233467314579;5494.642848214853;5504.972475137639;5521.475459831309;5921.763129474816;5998;6009.789657362015;6031.517478601701;6037.577369584131;6282.842928926768;6309.810705678837;6339.302676705559;6352.872942541123;6353.047042943549;6413.390727880844;6512.576682515669;6541.443977261938;6556.246031706367;6622.106015687917;6266.060090986389;6268.216472940882;6285.717142728588;6287.968560157261;6289.0880822280515;6428.421442070143;6756.864862702817;6764.594124352555;6764.966175169191;6773.796786096425;4856.635752318591;6283.340249273851;6311.810645680637;6328.145700330489;6384.48924086072;6538.117354157223;6551.279359270477;6553.4101930826455;6554.279029306773;6572.277049524549;6426.807195784134;6429.54993150475;6435.195600549931;6450.612963222222;6463.25672547663;5242.5281724644965;6306.558540902184;6311.116443697886;6337.302896681454;6461.321561236096;5282.97358513205;5285.867853303621;5292.655977361459;5294.92057619136;5323.973380133152;4505.234110201273;4626.86119416418;4628.907421851544;4636.304554316812;4681.789319480631;5674.914876276859;5925.792597259076;6015.909761353583;6086.6348019119;6159.353267906895;6358.554901156967;6418.639833587066;6419.807405777834;6449.709763060673;6462.870742585216;6136.12084543884;6152.2924863640355;6154.19995400604;6155.415235552705;6173.32093469718;5484.478974497416;5771.394003629643;5775.88448231033;5785.247917770663;5797.304323481209;6429.421352078335;6497.155369801895;6635.369639884203;6664.266930637529;6719.395254427125;6489.448396886318;6545.901811472927;6564.8358791030605;6565.737370505126;6566.146400967844;6704.094947182068;6735.292794256533;6739.022841688025;6739.123913891162;6739.427148692416;6295.213098362705;6301.96849015752;6473.287938326686;6476.06097115925;6478.773242936474;6411.807645770635;6472.223333200046;6493.675316234264;6495.870082598416;6553.574017688835;6269.7178626962805;6321.051842223519;6324.30432652417;6327.145835312174;6336.936630633634;6297.4017468340435;6326.4622507087415;6364.172657554488;6373.267074286517;6380.553361264667;6176.7838125665385;6342.080398342122;6347.333529979285;6351.841203969938;6352.809415717536;6308.716107775253;6309.7160627770945;6318.178636777189;6335.113084168128;6372.617642941438;6310.4005119515405;6346.682665866781;6351.872962540816;6355.491560675227;6368.42684158428 18:58:11 INFO - PID 1641 | |1;ext.html;742.9739959101429;794.3327604811957;815.8001289684026;1140.4696815980567;1159.6057340504226;27234;36675.449868252;37051.814740926326;37062.62937370629;37090.258194836155;38102.04744881379;38457.42313865294;38523.42214866772;38534.229315413744;38620.03449913754;6037.396260373971;8449.746507604781;8607.526586037766;8626.956865215665;8640.524771137585;6130.386961303879;6216.689165541705;8312.459690120122;8512.063672995966;8534.189252021048;8524;8559.400841941067;8600.956995215047;8610.311175106008;8629.827403451927;8615.827683446327;8661.350398720058;8684.044755076937;8685.739427817143;8703.56482175895;7279.490435669505;8381.832363352698;8442.240198382175;8444.873326900102;8447.32421406292;37584.43623345652;37970.81014594937;37981.81009094964;38006.429903551194;38017.619823801964;3036.1195253376495;3038.8024778389554;3046.764996654652;3046.8019578727312;3067.92330191747;26901.181068909707;27026.594601081;27054.458910821675;27357.452850943067;27370.863145684343;2762.4751297253456;2768.709285525011;2791.232411086947;2828.4343131373807;2907.345847184388;228387;259538;307832;309212.90787092067;309806.99999999773;49189.262161067614;49308;49312.76718082077;49338.013239735;49453.75273123611;51383;51427.742861286206;51471.22793158106;51506.742466288175;51703.22445163326;13950.09324393911;14371.568852934428;14374.209418482083;14390.928045359706;14404.207768572829;11595.420228988516;11608.129390295593;11608.6517404478;11608.941955290338;11613.070954323633;10886.727831804268;11026.00765931054;11067.889321106686;11074.224804263786;11083.947025032609;4652.725844164471;5740.512056475163;5895.174675545427;5919.852003699942;5928.67392293428;943.3160958305174;945.5366870233673;969.8351280282369;985.2216748768393;989.7921436498416;23128.76871231266;24252.636210456858;24265;24269.271921842374;24299.51400972006;1097.314178638351;1131.332513816852;1131.6322195286564;1147.7474955509942;1152.1186292486254;10374.844377334346;10795.866434024472;11747.29516229029;11980.142421968467;12353.876461235273;406506;407033;408148;408213;408577;54211.45788542064;54720.45279547233;54806;54879;54881.45118548843;1469.2433396800657;1494.2379386513173;1547.064026264094;1554.5414102839688;1564.6792407556425;23509.52980940406;23525.882370588723;23562.41093972596;23658.988985936387;23692.407689807893;39033.41449878311;39175;39197.628083016956;39222.803885980386;39235;393215;393243;394210.02894985344;394335;394518;31189.37621247517;32065;32149.517757233192;32182.839085804422;32209.35581288408;42031;42140.157196856504;42404;42666.7199984001;43486.56513434825;29696.109116726533;30207.395852083275;30380.69619303867;30503;30550;29524.557131643473;29747.256318592208;30213.697863021967;30288.394232114795;30987.380252395276;34877.476837846945;35620.46569301411;35706.28587428289;35756.64243357533;35847.74532891336;29397.85301073481;29775.404491910474;30088.398232035674;30558.694413055586;30629.693703063575;19182.616347673247;20273.59452810965;23044.193453229058;23510.82445877665;23656.763432366144 18:58:11 INFO - PID 1641 | |2;jquery.html;390.0891418537714;415.9064210552624;427.5510713750561;428.37243438362793;439.9230134726424;14319;20388.388348349574;20546;20907.163713451482;20955.58088838226;19283;20184;20359.185632574718;21495.57008859822;21774.782252177472;5996.610220335686;6133.509319254471;6365.331640177784;6366.681665916709;6372.776952806653;5393.406725260218;6197.721102550396;6232.407921247497;6239.282482514501;6259.280182778971;5750.971245143789;5990.341062483123;6187.783427580036;6190.473809726179;6191.380861913818;5901.05583106703;5939.554533409968;5940.732667029994;5941.792037278675;5950.821475355746;5184.9740751296;5303.787848486056;5391.81128660499;5455.563554915597;5464.316960379952;26144.084957026604;27188.04841830546;27460.45079098427;27800.30499237515;28134.874605015775;1998.2306811877386;2007.5483016321357;2025.6556385414515;2028.8579799414047;2095.8218551423147;15262.694746105015;15482.845171548253;16384.344626214937;16489.34042638293;16793.83206167947;1657.627033917371;1662.7755253040805;1678.9412370567093;1683.638017826161;1685.6881476926685;155834;162712.3728762709;164697;165387;168714;11399.373034483184;12160.391980401122;12205.938970305091;12309.138360314691;12488.063395245394;14567.271636418349;14844.480443184653;14862.405503779728;14884.032537885003;14893.77659335111;7259;7855.13593504714;8903.287736981027;8989.280857531381;8991.055939126429;8255.298299644477;8399.682125560492;8427.157284271647;8583.785405364915;8605.483670979762;6303.621782693053;7613.352865006536;7630.528109710069;7635.427342949301;7653.57905315213;1815;1897.459224121119;2106.526031642899;2125.5111324395166;2286.622707253318;910.5947853205392;919.3656377099779;941.2705153506088;946.4508094645081;977.6969139566758;19438.4168474946;20427.387178384673;20566.897165514376;20632;20980.26569070075;811.9647449501814;898.2948385517468;927.5825878354767;963.3449254507002;984.3896783993827;10129.544170512345;10448.52981615814;10452.425116618508;10463.68608941733;10499.68500944973;629894;691043;691711;695291;705401;148589.25705371404;151750.24124879306;152035;152050;155102.22448887685;981.190517822799;1066.1470823341297;1095.6822521468684;1158.0561842098662;1161.2451906260999;17245.482635520955;17744.55638609045;17828.3760068397;17854.464366069038;18295.72556411628;39300.606993929694;55255.72372138275;56148.5962850932;56255.71872140777;56379.8724025509;70437;71547.28452715406;71586;71692;73430.63284683542;6475.417212450902;8355.415120941476;8526.701565445428;8730.34522410809;8854.513001784706;13187;13564.118332308368;13598.980076494105;13754.724905502035;13872.237026963614;11066.446677666245;11363.829542556703;11574.826377604175;11713.297202167898;11799;10370.481475926023;10389.792204155723;10517.526711298164;10711.517981690999;10712.625058122929;11557.595484158013;11929.403529823649;11979.640610781691;11992;12007.538454616957;8323.833523329378;8403.83192336162;8528.91471085306;8581.8712719308;8821.470711757314;11495.54017839277;11594.678439327035;11623.244489108181;11840.881591183977;11850.111241656738 18:58:11 INFO - PID 1641 | |3;mootools.html;1332.9800052999203;1390.2701081931984;1413.858614138587;1414.0596503325287;1417.6314158318835;10146.49267536623;10712.839307410395;10714.4107074111;10716.839247411295;10748.301360411577;10117.595296188143;10442.947785261063;10558.99999999998;10568.630097946574;10572.78854422911;2441.8046556275453;2460.855702098524;2463.3472129885504;2471.8393304435153;2474.3566672665147;2053.5276886316155;2305.3061539054434;2390.6892104026538;2434.561778879801;2436.2447641231274;2090.4042347930845;2114.703941448191;2116.9894150529226;2117.2695420080126;2119.957600847982;2345.041890021825;2435.9756402436014;2436.8294219404647;2436.8537887726793;2442.7312995570474;2086.54096098858;2182.7121998021244;2379.1672914479796;2394.6408038794148;2396.628522579003;9661.661841835572;10620.893791062144;10635.255532112755;10644.361338319648;10666.946665266702;6083.6045657032155;6344.6510441925675;6352.36476352361;6355.586886852339;6358.395952384516;3172.825494597796;3853.8265778040054;3976.5228172619472;4016.4577781999346;4023.6177563131455;1143.6054561176381;1144.250515912079;1185.1703807334814;1186.6380753870067;1206.5053328135423;14691.77962330566;14767.483138090116;14808.037477563925;14821;14830.629234269121;4807.423109226916;5208.5312321891215;5287.391949925808;5295.285136506523;5312.76092575835;10569.418681972566;10571.418571978462;10574.735631609272;10583.735406614742;10588.682339529827;6230.626162430269;6236.438720515175;6237.500999919996;6239.282482514569;6246.750129994749;5395.892082158335;5608.355039170466;5653.067243904709;5707.1153971134545;5745.109508026263;4003.5556603263894;4120.340745480708;4131.752094874317;4170.22850772608;4174.478190226222;1240.6154092231436;1243.9315837628835;1244.4835393311855;1262.066071107374;1266.7973124300067;706.6007705646293;717.3615482220828;740.8925705772741;744.6909532543933;775.0466925681443;5542.91685624716;5675.460831221028;5806.709664516842;5823.417658234142;5855.033919403336;554.1576803259023;554.8612846788303;580.2717589425329;581.5434883616341;584.1704779213527;3451.4995325678137;3941.4087886816633;3945.2504024235304;4163.937540936889;4217.219814334358;12761.915237204756;13166.736665266833;13845.653858653613;13851.099678520864;13869.653258668613;13068.607941761762;13355.933220334031;13363.93318033423;13365.53220637273;13381.66545836362;9564.473953932462;9886.4562449066;10000.299979001546;10020.34867733595;10042.096211341015;5717.342505611908;6422.582532135396;6596;6610.272869984394;6613.074169616161;8880.777980550538;12129.332886690963;12166;12281.631551053482;12318.014558835093;12244.510219591117;12275.631731048083;12388.31864247439;12424.813627795771;12485.87514124847;7647.770566883002;8797.560121993747;8822.779430514289;8845.955770221108;9004.613777348877;8466.534340611326;9851.901480985098;9924.305298629315;9937.850932235877;9940.204783617437;8304.837322774696;8403.369747268856;8494.023187333289;8515.829683406173;8580.14198580137;8246.917530824854;8267.958660206661;8326.125756795449;8371.33029357662;8377;7516.699332026877;7689.50018248812;7706.499077559941;7726.1114971776715;7732.497387669783;5560.944390556043;5680.630759000652;5722.284714410699;5774;5866.090755932837;5765.509931655856;5852.970735146297;5859.707014649336;5873.061889576344;5953.1070339449425 18:58:11 INFO - PID 1641 | |4;prototype.html;366.3497292306157;382.92341531693666;387.2236166486195;390.3753993610223;392.2116545743057;5819.359870414251;6093.51251899848;6121.112438696387;6123.887258895679;6166.753329866806;4937.950620493794;5780.4219578042175;5934.910976335347;5991.262533865174;5995.13070604762;4082.7958602069925;4365.912681746363;4471.9267375829895;4504.324351347292;4507.121111383273;3852.9229415411673;4184;4319.222539942809;4548.385967894342;4579.8779299071775;3792.355299599075;4507.211238033331;4538.841140560081;4544.182047231497;4547.909041819162;4261.808218630169;4472.396226509412;4516.796744146488;4544.272916333371;4545.386372839657;3836.9040773980596;3837.520309961255;3845.1540661054523;3853.402722578005;3868.4197370394386;4998.900241946766;5063.26582645518;5734.942650573483;5853.85365365865;5878.41215878413;4965.851024469272;5189.42916279209;5215.478452154792;5226.686398816045;5238.528532432061;6250.624962502219;6336.46140078094;6344.889644312227;6356.396142366468;6372.235331760172;3617.457381392786;3829.9234015319817;4290.828366865353;4307.375430562583;4317.97841010796;5564;5704.458076482686;6438.3883531064475;6754.5609535380045;6765.8646827063185;4214.220369231702;4334.653227741773;4361.953131248541;4373.409589705413;4374.300111982067;3574.1243395367783;3770.20825626616;4433.800478978389;4444.866654000385;4449;4608.769561521911;4897.951020489821;5146.330976972969;5208.296879921238;5222.686638801684;6391.520635952322;6398.776042838478;6422.935770642327;6437.2918978912285;6439.291677915426;1426.2369632246698;1511.3878879054;1516.893817432769;1517.704047710708;1523.6647937453743;848.4569875279826;946.7633091727068;965.9613615455303;1002.4536627538009;1010.5099026971913;4926.901461970812;5364.624476286623;5663.32040155184;5973.342932277446;5975.372585878507;6178.443940045418;6262.812115636538;6275.843103922439;6570.145881035435;6578.473722102316;5627.8593035174445;5896.174535565025;5898.705064746831;5948.524118070631;5983.311919129356;2923.0500087471655;3085.2595377109783;3085.783995120365;3472.7048200903205;3474.4093504103857;5782.971085144632;6351.745930162741;6712.83217919556;6804.625745583941;6883.690233939485;5673.602847800615;6441.162648855712;6720.185549901499;6861.142357205349;6912.101426814482;5540.667559946416;6357.777477788348;6599.406053455212;6884.483663725241;6944.826379340557;6281.403266689657;6286.842828929313;6380.680965951591;6659.800205993828;6700.391905942636;6587.769428070186;6600.702968366531;6606.0421238919125;6613.503987200883;6623.602583844985;5713.428657134254;5998.460138587374;6533.248676402084;6854.8286292841085;6888.552244104118;3872.786996715208;4024.9937515621095;4069.5320038195987;4278.5293617702655;4381.802818873095;5552.472515111058;6599.901001485077;6671.099401580918;6685.164354455693;6707.832304192434;5211.71335576547;6283.455200643747;6416.374748267214;6513.004018151703;6513.250976137805;6410.967945160244;6427.389398007182;6445.6454894981225;6451.516196243566;6465.191851018622;5829.183914251923;5998;6207.224096987877;6333.90499142504;6343.35072881045;5467.621584610548;5889.882202356014;5942.851428714317;6425.646589437626;6447.580907241202;6283.246010478589;6295.244570651368;6325.399087086719;6331.651759153291;6350.7459701611415 18:58:11 INFO - PID 1641 | |5;yui.html;703.8738019169326;710.3997122431543;737.8598066367391;739.9778006659799;767.0603510699394;15333.003354781931;16010.35958561659;16057.678846423065;16064.116473593962;16074.276657550408;14246;14589.197594132318;14622.707545849076;14647.267636618153;14719.779203311959;902.5261737587786;916.8487199612056;937.1565590968115;938.2259635800458;938.7653086728318;938.760616042908;949.6723630347519;952.1002652493396;952.261996952364;952.7713348796308;916.244098618639;967.4485543240356;990.069334825262;1013.2096964367768;1019.6737044145876;1006.808706345792;1007.7178390050798;1009.7475631092191;1011.0546638892658;1011.9342242754271;738.5052015149862;741.6996116572797;742.3319012888389;742.480263815326;742.9702811887518;14990.925045374812;15021.324040418207;15980.4406845761;16154.919225403915;16420;12445.377731113407;12686.55597054108;13060.638679583786;13507.122037067558;13670.384832682554;11248.493817778219;11732.237404568761;11762.882371176263;11907.16649834512;12472.126951113425;2999.8500074996164;3001.8048826826184;3021.728044475986;3031.439183751013;3064.923376915595;90039;106024;111051;112399;115006.42496787546;11031.558737650405;11127.944360278309;11211.775764484664;11500.827487587525;11592.942035289769;24524.509509809704;24975.250742477754;24996.875015624806;25249;25282.115125970504;7645.426593005547;7651.273129052731;7740.922590774133;7756.379489640815;7768.766936991787;3949.229900169497;3983.980080099581;4060.8578699745367;4076.857309994136;4079.89800254996;4039.3738970459626;4110.547839737626;4147.668186545069;4159.91680166395;4178.247915375262;776.8679324514846;780.157429975625;783.7883771381695;784.541043489565;803.746819751788;520.5393226994138;528.7409169506914;549.7471163264943;567.4864247855697;593.5103539579864;9155.13026262504;9857.162141217934;9981.45102019396;10138.898611013796;10150.441725705157;510.660410826796;518.199381954873;568.1506148308223;588.0708480600641;593.305832176346;4513.842015529441;4529.501754806968;4531.071130418256;4558.544145585415;4708.387909571734;88944.5552772245;89085.55457222802;89289;89789.55105224432;91959;59592;64151.679241603495;65285;75437.86843197356;77282.61358693283;58444.415555844746;66463.33536664669;68714;71548;71662;10377.325473844176;10688.951414757707;10804.67585972422;10832.837507437238;10872.619458319237;36253.731119410695;36376.81811590925;37145;37724.245515088995;37886.24227515379;47821.76089119671;56037.71981140068;64048.67975660092;67861.6606916982;68726;11991.64025079249;13003.739925201633;13048.804267936179;13553.390097445836;13592.86407135916;14243.0525929381;14297.928510357382;14474.421023158955;14574.271286435847;14607.415703371746;12969.221846689228;13198.472061117447;13392.26342551169;13543.86456135426;13841.861581384057;13297.534586289434;13352.532661356807;13386.866131338562;13528.797068044185;13685.794713079511;11030.338179709242;13010.804837927628;13101.606951791462;13220.206787592775;13845.376958037114;8216.958915205385;8820.162084601952;9573.952130239304;9671.17794987434;9803.509824508888;8716.757497245342;9724.617468719925;10984.121270298518;11057.612983545538;11067.557297708323 18:58:11 INFO - PID 1641 | __end_tp_report 18:58:11 INFO - PID 1641 | __start_cc_report 18:58:11 INFO - PID 1641 | _x_x_mozilla_cycle_collect,1938 18:58:11 INFO - PID 1641 | __end_cc_report 18:58:11 INFO - PID 1641 | __startTimestamp1490666291068__endTimestamp 18:58:11 INFO - PID 1641 | 18:58:11 INFO - PID 1641 | ------- Summary: start ------- 18:58:11 INFO - PID 1641 | Number of tests: 6 18:58:11 INFO - PID 1641 | 18:58:11 INFO - PID 1641 | [#0] dojo.html Cycles:165 Average:6018.75 Median:6297.40 stddev:672.29 (10.7%) stddev-sans-first:641.75 18:58:11 INFO - PID 1641 | Values: 3382.2 3422.5 3435.8 3461.7 3489.1 6101.1 6137.4 6141.6 6144.5 6162.4 5706.5 5870.1 6066.3 6112.3 6127.7 5441.0 5472.8 5495.9 5502.9 5601.7 5378.1 5463.0 5534.2 5559.1 5561.0 5411.1 5474.4 5514.5 5556.1 5577.4 5460.2 5464.0 5473.6 5477.2 5508.8 5468.9 5475.2 5494.6 5505.0 5521.5 5921.8 5998.0 6009.8 6031.5 6037.6 6282.8 6309.8 6339.3 6352.9 6353.0 6413.4 6512.6 6541.4 6556.2 6622.1 6266.1 6268.2 6285.7 6288.0 6289.1 6428.4 6756.9 6764.6 6765.0 6773.8 4856.6 6283.3 6311.8 6328.1 6384.5 6538.1 6551.3 6553.4 6554.3 6572.3 6426.8 6429.5 6435.2 6450.6 6463.3 5242.5 6306.6 6311.1 6337.3 6461.3 5283.0 5285.9 5292.7 5294.9 5324.0 4505.2 4626.9 4628.9 4636.3 4681.8 5674.9 5925.8 6015.9 6086.6 6159.4 6358.6 6418.6 6419.8 6449.7 6462.9 6136.1 6152.3 6154.2 6155.4 6173.3 5484.5 5771.4 5775.9 5785.2 5797.3 6429.4 6497.2 6635.4 6664.3 6719.4 6489.4 6545.9 6564.8 6565.7 6566.1 6704.1 6735.3 6739.0 6739.1 6739.4 6295.2 6302.0 6473.3 6476.1 6478.8 6411.8 6472.2 6493.7 6495.9 6553.6 6269.7 6321.1 6324.3 6327.1 6336.9 6297.4 6326.5 6364.2 6373.3 6380.6 6176.8 6342.1 6347.3 6351.8 6352.8 6308.7 6309.7 6318.2 6335.1 6372.6 6310.4 6346.7 6351.9 6355.5 6368.4 18:58:11 INFO - PID 1641 | 18:58:11 INFO - PID 1641 | [#1] ext.html Cycles:180 Average:49816.84 Median:35731.46 stddev:97044.34 (271.6%) stddev-sans-first:97246.63 18:58:11 INFO - PID 1641 | Values: 743.0 794.3 815.8 1140.5 1159.6 27234.0 36675.4 37051.8 37062.6 37090.3 38102.0 38457.4 38523.4 38534.2 38620.0 6037.4 8449.7 8607.5 8627.0 8640.5 6130.4 6216.7 8312.5 8512.1 8534.2 8524.0 8559.4 8601.0 8610.3 8629.8 8615.8 8661.4 8684.0 8685.7 8703.6 7279.5 8381.8 8442.2 8444.9 8447.3 37584.4 37970.8 37981.8 38006.4 38017.6 3036.1 3038.8 3046.8 3046.8 3067.9 26901.2 27026.6 27054.5 27357.5 27370.9 2762.5 2768.7 2791.2 2828.4 2907.3 228387.0 259538.0 307832.0 309212.9 309807.0 49189.3 49308.0 49312.8 49338.0 49453.8 51383.0 51427.7 51471.2 51506.7 51703.2 13950.1 14371.6 14374.2 14390.9 14404.2 11595.4 11608.1 11608.7 11608.9 11613.1 10886.7 11026.0 11067.9 11074.2 11083.9 4652.7 5740.5 5895.2 5919.9 5928.7 943.3 945.5 969.8 985.2 989.8 23128.8 24252.6 24265.0 24269.3 24299.5 1097.3 1131.3 1131.6 1147.7 1152.1 10374.8 10795.9 11747.3 11980.1 12353.9 406506.0 407033.0 408148.0 408213.0 408577.0 54211.5 54720.5 54806.0 54879.0 54881.5 1469.2 1494.2 1547.1 1554.5 1564.7 23509.5 23525.9 23562.4 23659.0 23692.4 39033.4 39175.0 39197.6 39222.8 39235.0 393215.0 393243.0 394210.0 394335.0 394518.0 31189.4 32065.0 32149.5 32182.8 32209.4 42031.0 42140.2 42404.0 42666.7 43486.6 29696.1 30207.4 30380.7 30503.0 30550.0 29524.6 29747.3 30213.7 30288.4 30987.4 34877.5 35620.5 35706.3 35756.6 35847.7 29397.9 29775.4 30088.4 30558.7 30629.7 19182.6 20273.6 23044.2 23510.8 23656.8 18:58:11 INFO - PID 1641 | 18:58:11 INFO - PID 1641 | [#2] jquery.html Cycles:180 Average:39455.22 Median:11810.54 stddev:114915.87 (973.0%) stddev-sans-first:115200.80 18:58:11 INFO - PID 1641 | Values: 390.1 415.9 427.6 428.4 439.9 14319.0 20388.4 20546.0 20907.2 20955.6 19283.0 20184.0 20359.2 21495.6 21774.8 5996.6 6133.5 6365.3 6366.7 6372.8 5393.4 6197.7 6232.4 6239.3 6259.3 5751.0 5990.3 6187.8 6190.5 6191.4 5901.1 5939.6 5940.7 5941.8 5950.8 5185.0 5303.8 5391.8 5455.6 5464.3 26144.1 27188.0 27460.5 27800.3 28134.9 1998.2 2007.5 2025.7 2028.9 2095.8 15262.7 15482.8 16384.3 16489.3 16793.8 1657.6 1662.8 1678.9 1683.6 1685.7 155834.0 162712.4 164697.0 165387.0 168714.0 11399.4 12160.4 12205.9 12309.1 12488.1 14567.3 14844.5 14862.4 14884.0 14893.8 7259.0 7855.1 8903.3 8989.3 8991.1 8255.3 8399.7 8427.2 8583.8 8605.5 6303.6 7613.4 7630.5 7635.4 7653.6 1815.0 1897.5 2106.5 2125.5 2286.6 910.6 919.4 941.3 946.5 977.7 19438.4 20427.4 20566.9 20632.0 20980.3 812.0 898.3 927.6 963.3 984.4 10129.5 10448.5 10452.4 10463.7 10499.7 629894.0 691043.0 691711.0 695291.0 705401.0 148589.3 151750.2 152035.0 152050.0 155102.2 981.2 1066.1 1095.7 1158.1 1161.2 17245.5 17744.6 17828.4 17854.5 18295.7 39300.6 55255.7 56148.6 56255.7 56379.9 70437.0 71547.3 71586.0 71692.0 73430.6 6475.4 8355.4 8526.7 8730.3 8854.5 13187.0 13564.1 13599.0 13754.7 13872.2 11066.4 11363.8 11574.8 11713.3 11799.0 10370.5 10389.8 10517.5 10711.5 10712.6 11557.6 11929.4 11979.6 11992.0 12007.5 8323.8 8403.8 8528.9 8581.9 8821.5 11495.5 11594.7 11623.2 11840.9 11850.1 18:58:11 INFO - PID 1641 | 18:58:11 INFO - PID 1641 | [#3] mootools.html Cycles:180 Average:6551.53 Median:3960.89 stddev:4051.29 (102.3%) stddev-sans-first:4043.68 18:58:11 INFO - PID 1641 | Values: 1333.0 1390.3 1413.9 1414.1 1417.6 10146.5 10712.8 10714.4 10716.8 10748.3 10117.6 10442.9 10559.0 10568.6 10572.8 2441.8 2460.9 2463.3 2471.8 2474.4 2053.5 2305.3 2390.7 2434.6 2436.2 2090.4 2114.7 2117.0 2117.3 2120.0 2345.0 2436.0 2436.8 2436.9 2442.7 2086.5 2182.7 2379.2 2394.6 2396.6 9661.7 10620.9 10635.3 10644.4 10666.9 6083.6 6344.7 6352.4 6355.6 6358.4 3172.8 3853.8 3976.5 4016.5 4023.6 1143.6 1144.3 1185.2 1186.6 1206.5 14691.8 14767.5 14808.0 14821.0 14830.6 4807.4 5208.5 5287.4 5295.3 5312.8 10569.4 10571.4 10574.7 10583.7 10588.7 6230.6 6236.4 6237.5 6239.3 6246.8 5395.9 5608.4 5653.1 5707.1 5745.1 4003.6 4120.3 4131.8 4170.2 4174.5 1240.6 1243.9 1244.5 1262.1 1266.8 706.6 717.4 740.9 744.7 775.0 5542.9 5675.5 5806.7 5823.4 5855.0 554.2 554.9 580.3 581.5 584.2 3451.5 3941.4 3945.3 4163.9 4217.2 12761.9 13166.7 13845.7 13851.1 13869.7 13068.6 13355.9 13363.9 13365.5 13381.7 9564.5 9886.5 10000.3 10020.3 10042.1 5717.3 6422.6 6596.0 6610.3 6613.1 8880.8 12129.3 12166.0 12281.6 12318.0 12244.5 12275.6 12388.3 12424.8 12485.9 7647.8 8797.6 8822.8 8846.0 9004.6 8466.5 9851.9 9924.3 9937.9 9940.2 8304.8 8403.4 8494.0 8515.8 8580.1 8246.9 8268.0 8326.1 8371.3 8377.0 7516.7 7689.5 7706.5 7726.1 7732.5 5560.9 5680.6 5722.3 5774.0 5866.1 5765.5 5853.0 5859.7 5873.1 5953.1 18:58:11 INFO - PID 1641 | 18:58:11 INFO - PID 1641 | [#4] prototype.html Cycles:180 Average:5136.80 Median:5824.27 stddev:1623.67 (27.9%) stddev-sans-first:1588.25 18:58:11 INFO - PID 1641 | Values: 366.3 382.9 387.2 390.4 392.2 5819.4 6093.5 6121.1 6123.9 6166.8 4938.0 5780.4 5934.9 5991.3 5995.1 4082.8 4365.9 4471.9 4504.3 4507.1 3852.9 4184.0 4319.2 4548.4 4579.9 3792.4 4507.2 4538.8 4544.2 4547.9 4261.8 4472.4 4516.8 4544.3 4545.4 3836.9 3837.5 3845.2 3853.4 3868.4 4998.9 5063.3 5734.9 5853.9 5878.4 4965.9 5189.4 5215.5 5226.7 5238.5 6250.6 6336.5 6344.9 6356.4 6372.2 3617.5 3829.9 4290.8 4307.4 4318.0 5564.0 5704.5 6438.4 6754.6 6765.9 4214.2 4334.7 4362.0 4373.4 4374.3 3574.1 3770.2 4433.8 4444.9 4449.0 4608.8 4898.0 5146.3 5208.3 5222.7 6391.5 6398.8 6422.9 6437.3 6439.3 1426.2 1511.4 1516.9 1517.7 1523.7 848.5 946.8 966.0 1002.5 1010.5 4926.9 5364.6 5663.3 5973.3 5975.4 6178.4 6262.8 6275.8 6570.1 6578.5 5627.9 5896.2 5898.7 5948.5 5983.3 2923.1 3085.3 3085.8 3472.7 3474.4 5783.0 6351.7 6712.8 6804.6 6883.7 5673.6 6441.2 6720.2 6861.1 6912.1 5540.7 6357.8 6599.4 6884.5 6944.8 6281.4 6286.8 6380.7 6659.8 6700.4 6587.8 6600.7 6606.0 6613.5 6623.6 5713.4 5998.5 6533.2 6854.8 6888.6 3872.8 4025.0 4069.5 4278.5 4381.8 5552.5 6599.9 6671.1 6685.2 6707.8 5211.7 6283.5 6416.4 6513.0 6513.3 6411.0 6427.4 6445.6 6451.5 6465.2 5829.2 5998.0 6207.2 6333.9 6343.4 5467.6 5889.9 5942.9 6425.6 6447.6 6283.2 6295.2 6325.4 6331.7 6350.7 18:58:11 INFO - PID 1641 | 18:58:11 INFO - PID 1641 | [#5] yui.html Cycles:180 Average:18770.75 Median:20917.74 stddev:26178.18 (125.1%) stddev-sans-first:26216.47 18:58:11 INFO - PID 1641 | Values: 703.9 710.4 737.9 740.0 767.1 15333.0 16010.4 16057.7 16064.1 16074.3 14246.0 14589.2 14622.7 14647.3 14719.8 902.5 916.8 937.2 938.2 938.8 938.8 949.7 952.1 952.3 952.8 916.2 967.4 990.1 1013.2 1019.7 1006.8 1007.7 1009.7 1011.1 1011.9 738.5 741.7 742.3 742.5 743.0 14990.9 15021.3 15980.4 16154.9 16420.0 12445.4 12686.6 13060.6 13507.1 13670.4 11248.5 11732.2 11762.9 11907.2 12472.1 2999.9 3001.8 3021.7 3031.4 3064.9 90039.0 106024.0 111051.0 112399.0 115006.4 11031.6 11127.9 11211.8 11500.8 11592.9 24524.5 24975.3 24996.9 25249.0 25282.1 7645.4 7651.3 7740.9 7756.4 7768.8 3949.2 3984.0 4060.9 4076.9 4079.9 4039.4 4110.5 4147.7 4159.9 4178.2 776.9 780.2 783.8 784.5 803.7 520.5 528.7 549.7 567.5 593.5 9155.1 9857.2 9981.5 10138.9 10150.4 510.7 518.2 568.2 588.1 593.3 4513.8 4529.5 4531.1 4558.5 4708.4 88944.6 89085.6 89289.0 89789.6 91959.0 59592.0 64151.7 65285.0 75437.9 77282.6 58444.4 66463.3 68714.0 71548.0 71662.0 10377.3 10689.0 10804.7 10832.8 10872.6 36253.7 36376.8 37145.0 37724.2 37886.2 47821.8 56037.7 64048.7 67861.7 68726.0 11991.6 13003.7 13048.8 13553.4 13592.9 14243.1 14297.9 14474.4 14574.3 14607.4 12969.2 13198.5 13392.3 13543.9 13841.9 13297.5 13352.5 13386.9 13528.8 13685.8 11030.3 13010.8 13101.6 13220.2 13845.4 8217.0 8820.2 9574.0 9671.2 9803.5 8716.8 9724.6 10984.1 11057.6 11067.6 18:58:11 INFO - PID 1641 | -------- Summary: end -------- 18:58:11 INFO - PID 1641 | 18:58:11 INFO - TEST-INFO | 1641: exit 0 18:58:11 INFO - TEST-OK | dromaeo_css | took 1121179ms 18:58:11 INFO - TEST-START | kraken 18:58:11 INFO - operating with platform_type : mac_ 18:58:11 INFO - Initialising browser for kraken test... 18:58:11 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/tmpkIuaIH/profile http://localhost:49235/getInfo.html) 18:58:14 INFO - PID 1651 | __metrics Screen width/height:1600/1200 18:58:14 INFO - PID 1651 | colorDepth:24 18:58:14 INFO - PID 1651 | Browser inner width/height: 1024/689 18:58:14 INFO - PID 1651 | __metrics 18:58:14 ERROR - PID 1651 | 1490666294321 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/Nightly.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/Nightly.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 18:58:14 INFO - PID 1651 | 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" 18:58:14 INFO - PID 1651 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:58:15 INFO - TEST-INFO | 1651: exit 0 18:58:15 INFO - Browser initialized. 18:58:15 INFO - Running cycle 1/1 for kraken test... 18:58:15 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/tmpkIuaIH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:58:27 INFO - PID 1652 | RSS: Main: 252747776 18:58:27 INFO - PID 1652 | 18:58:30 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-beat-detection) 18:58:31 INFO - PID 1652 | RSS: Main: 303140864 18:58:31 INFO - PID 1652 | 18:58:34 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-dft) 18:58:34 INFO - PID 1652 | RSS: Main: 305152000 18:58:34 INFO - PID 1652 | 18:58:38 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-fft) 18:58:39 INFO - PID 1652 | RSS: Main: 395010048 18:58:39 INFO - PID 1652 | 18:58:41 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-oscillator) 18:58:42 INFO - PID 1652 | RSS: Main: 296689664 18:58:42 INFO - PID 1652 | 18:58:44 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:58:44 INFO - PID 1652 | RSS: Main: 299843584 18:58:44 INFO - PID 1652 | 18:58:47 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-darkroom) 18:58:48 INFO - PID 1652 | RSS: Main: 341532672 18:58:48 INFO - PID 1652 | 18:58:51 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-desaturate) 18:58:52 INFO - PID 1652 | RSS: Main: 351830016 18:58:52 INFO - PID 1652 | 18:58:55 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49235/tests/kraken/driver.html?testName=json-parse-financial) 18:58:56 INFO - PID 1652 | RSS: Main: 356012032 18:58:56 INFO - PID 1652 | 18:58:57 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49235/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:58:58 INFO - PID 1652 | RSS: Main: 314826752 18:58:58 INFO - PID 1652 | 18:59:00 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:59:01 INFO - PID 1652 | RSS: Main: 319721472 18:59:01 INFO - PID 1652 | 18:59:03 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:59:03 INFO - PID 1652 | RSS: Main: 321695744 18:59:03 INFO - PID 1652 | 18:59:07 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:59:08 INFO - PID 1652 | RSS: Main: 340017152 18:59:08 INFO - PID 1652 | 18:59:11 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:59:11 INFO - PID 1652 | RSS: Main: 334323712 18:59:11 INFO - PID 1652 | 18:59:13 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49235/tests/kraken/driver.html?testName=ai-astar) 18:59:14 INFO - PID 1652 | RSS: Main: 396582912 18:59:14 INFO - PID 1652 | 18:59:14 INFO - PID 1652 | __start_tp_report 18:59:14 INFO - PID 1652 | _x_x_mozilla_page_load 18:59:14 INFO - PID 1652 | _x_x_mozilla_page_load_details 18:59:14 INFO - PID 1652 | |i|pagename|runs| 18:59:14 INFO - PID 1652 | |0;ai-astar;111;122;123;122;122;122;122;124;124;122 18:59:14 INFO - PID 1652 | |1;audio-beat-detection;159;121;104;182;153;155;121;118;148;118 18:59:14 INFO - PID 1652 | |2;audio-dft;220;213;219;240;290;212;215;208;216;208 18:59:14 INFO - PID 1652 | |3;audio-fft;86;64;93;91;94;92;89;57;94;92 18:59:14 INFO - PID 1652 | |4;audio-oscillator;94;77;94;77;74;76;93;75;79;123 18:59:14 INFO - PID 1652 | |5;imaging-gaussian-blur;92;91;92;92;92;91;90;91;91;91 18:59:14 INFO - PID 1652 | |6;imaging-darkroom;102;101;101;100;100;100;102;100;100;101 18:59:14 INFO - PID 1652 | |7;imaging-desaturate;102;102;102;102;102;102;101;102;103;99 18:59:14 INFO - PID 1652 | |8;json-parse-financial;64;63;63;64;65;65;65;64;65;65 18:59:14 INFO - PID 1652 | |9;json-stringify-tinderbox;63;66;73;66;65;65;67;65;65;65 18:59:14 INFO - PID 1652 | |10;stanford-crypto-aes;94;89;89;90;88;91;90;89;89;92 18:59:14 INFO - PID 1652 | |11;stanford-crypto-ccm;161;166;163;167;165;157;164;168;217;174 18:59:14 INFO - PID 1652 | |12;stanford-crypto-pbkdf2;241;200;197;200;201;204;203;201;204;205 18:59:14 INFO - PID 1652 | |13;stanford-crypto-sha256-iterative;71;74;73;74;72;74;71;73;73;73 18:59:14 INFO - PID 1652 | __end_tp_report 18:59:14 INFO - PID 1652 | __start_cc_report 18:59:14 INFO - PID 1652 | _x_x_mozilla_cycle_collect,2199 18:59:14 INFO - PID 1652 | __end_cc_report 18:59:14 INFO - PID 1652 | __startTimestamp1490666354041__endTimestamp 18:59:14 INFO - PID 1652 | 18:59:14 INFO - PID 1652 | ------- Summary: start ------- 18:59:14 INFO - PID 1652 | Number of tests: 14 18:59:14 INFO - PID 1652 | 18:59:14 INFO - PID 1652 | [#0] ai-astar Cycles:10 Average:121.40 Median:122.00 stddev:3.75 (3.1%) stddev-sans-first:0.88 18:59:14 INFO - PID 1652 | Values: 111.0 122.0 123.0 122.0 122.0 122.0 122.0 124.0 124.0 122.0 18:59:14 INFO - PID 1652 | 18:59:14 INFO - PID 1652 | [#1] audio-beat-detection Cycles:10 Average:137.90 Median:150.50 stddev:24.78 (16.5%) stddev-sans-first:25.08 18:59:14 INFO - PID 1652 | Values: 159.0 121.0 104.0 182.0 153.0 155.0 121.0 118.0 148.0 118.0 18:59:14 INFO - PID 1652 | 18:59:14 INFO - PID 1652 | [#2] audio-dft Cycles:10 Average:224.10 Median:217.50 stddev:24.89 (11.4%) stddev-sans-first:26.35 18:59:14 INFO - PID 1652 | Values: 220.0 213.0 219.0 240.0 290.0 212.0 215.0 208.0 216.0 208.0 18:59:14 INFO - PID 1652 | 18:59:14 INFO - PID 1652 | [#3] audio-fft Cycles:10 Average:85.20 Median:92.00 stddev:13.34 (14.5%) stddev-sans-first:14.15 18:59:14 INFO - PID 1652 | Values: 86.0 64.0 93.0 91.0 94.0 92.0 89.0 57.0 94.0 92.0 18:59:14 INFO - PID 1652 | 18:59:14 INFO - PID 1652 | [#4] audio-oscillator Cycles:10 Average:86.20 Median:78.00 stddev:15.35 (19.7%) stddev-sans-first:16.02 18:59:14 INFO - PID 1652 | Values: 94.0 77.0 94.0 77.0 74.0 76.0 93.0 75.0 79.0 123.0 18:59:14 INFO - PID 1652 | 18:59:14 INFO - PID 1652 | [#5] imaging-gaussian-blur Cycles:10 Average:91.30 Median:91.50 stddev:0.67 (0.7%) stddev-sans-first:0.67 18:59:14 INFO - PID 1652 | Values: 92.0 91.0 92.0 92.0 92.0 91.0 90.0 91.0 91.0 91.0 18:59:14 INFO - PID 1652 | 18:59:14 INFO - PID 1652 | [#6] imaging-darkroom Cycles:10 Average:100.70 Median:101.00 stddev:0.82 (0.8%) stddev-sans-first:0.73 18:59:14 INFO - PID 1652 | Values: 102.0 101.0 101.0 100.0 100.0 100.0 102.0 100.0 100.0 101.0 18:59:14 INFO - PID 1652 | 18:59:14 INFO - PID 1652 | [#7] imaging-desaturate Cycles:10 Average:101.70 Median:102.00 stddev:1.06 (1.0%) stddev-sans-first:1.12 18:59:14 INFO - PID 1652 | Values: 102.0 102.0 102.0 102.0 102.0 102.0 101.0 102.0 103.0 99.0 18:59:14 INFO - PID 1652 | 18:59:14 INFO - PID 1652 | [#8] json-parse-financial Cycles:10 Average:64.30 Median:65.00 stddev:0.82 (1.3%) stddev-sans-first:0.87 18:59:14 INFO - PID 1652 | Values: 64.0 63.0 63.0 64.0 65.0 65.0 65.0 64.0 65.0 65.0 18:59:14 INFO - PID 1652 | 18:59:14 INFO - PID 1652 | [#9] json-stringify-tinderbox Cycles:10 Average:66.00 Median:65.50 stddev:2.67 (4.1%) stddev-sans-first:2.60 18:59:14 INFO - PID 1652 | Values: 63.0 66.0 73.0 66.0 65.0 65.0 67.0 65.0 65.0 65.0 18:59:14 INFO - PID 1652 | 18:59:14 INFO - PID 1652 | [#10] stanford-crypto-aes Cycles:10 Average:90.10 Median:90.00 stddev:1.79 (2.0%) stddev-sans-first:1.22 18:59:14 INFO - PID 1652 | Values: 94.0 89.0 89.0 90.0 88.0 91.0 90.0 89.0 89.0 92.0 18:59:14 INFO - PID 1652 | 18:59:14 INFO - PID 1652 | [#11] stanford-crypto-ccm Cycles:10 Average:170.20 Median:166.50 stddev:17.04 (10.2%) stddev-sans-first:17.75 18:59:14 INFO - PID 1652 | Values: 161.0 166.0 163.0 167.0 165.0 157.0 164.0 168.0 217.0 174.0 18:59:14 INFO - PID 1652 | 18:59:14 INFO - PID 1652 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:205.60 Median:203.50 stddev:12.67 (6.2%) stddev-sans-first:2.55 18:59:14 INFO - PID 1652 | Values: 241.0 200.0 197.0 200.0 201.0 204.0 203.0 201.0 204.0 205.0 18:59:14 INFO - PID 1652 | 18:59:14 INFO - PID 1652 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.80 Median:73.00 stddev:1.14 (1.6%) stddev-sans-first:1.00 18:59:14 INFO - PID 1652 | Values: 71.0 74.0 73.0 74.0 72.0 74.0 71.0 73.0 73.0 73.0 18:59:14 INFO - PID 1652 | -------- Summary: end -------- 18:59:14 INFO - PID 1652 | 18:59:14 INFO - TEST-INFO | 1652: exit 0 18:59:14 INFO - TEST-OK | kraken | took 63040ms 18:59:14 INFO - SUITE-END | took 1184s 18:59:15 INFO - Completed test suite (00:19:44) 18:59:15 INFO - javascript benchmark 18:59:15 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7475.143342166634, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3382.154461384654, 3422.5208470814073, 3435.776674516156, 3461.7057550108248, 3489.0928358626766, 6101.065651476874, 6137.41694539018, 6141.608749743943, 6144.494598823295, 6162.445379915806, 5706.486416222539, 5870.119482077691, 6066.332703402622, 6112.290716697406, 6127.662978536178, 5441.047816632094, 5472.753726082336, 5495.945040549604, 5502.889942201154, 5601.747921343549, 5378.139497680372, 5463.043967305725, 5534.197541356501, 5559.138333558285, 5561.0, 5411.080116380225, 5474.42518535552, 5514.503694667459, 5556.138798486241, 5577.442255774431, 5460.235567020621, 5464.016477034133, 5473.589480788957, 5477.233187353773, 5508.752106155192, 5468.945310546884, 5475.233467314579, 5494.642848214853, 5504.972475137639, 5521.475459831309, 5921.763129474816, 5998.0, 6009.789657362015, 6031.517478601701, 6037.577369584131, 6282.842928926768, 6309.810705678837, 6339.302676705559, 6352.872942541123, 6353.047042943549, 6413.390727880844, 6512.576682515669, 6541.443977261938, 6556.246031706367, 6622.106015687917, 6266.060090986389, 6268.216472940882, 6285.717142728588, 6287.968560157261, 6289.0880822280515, 6428.421442070143, 6756.864862702817, 6764.594124352555, 6764.966175169191, 6773.796786096425, 4856.635752318591, 6283.340249273851, 6311.810645680637, 6328.145700330489, 6384.48924086072, 6538.117354157223, 6551.279359270477, 6553.4101930826455, 6554.279029306773, 6572.277049524549, 6426.807195784134, 6429.54993150475, 6435.195600549931, 6450.612963222222, 6463.25672547663, 5242.5281724644965, 6306.558540902184, 6311.116443697886, 6337.302896681454, 6461.321561236096, 5282.97358513205, 5285.867853303621, 5292.655977361459, 5294.92057619136, 5323.973380133152, 4505.234110201273, 4626.86119416418, 4628.907421851544, 4636.304554316812, 4681.789319480631, 5674.914876276859, 5925.792597259076, 6015.909761353583, 6086.6348019119, 6159.353267906895, 6358.554901156967, 6418.639833587066, 6419.807405777834, 6449.709763060673, 6462.870742585216, 6136.12084543884, 6152.2924863640355, 6154.19995400604, 6155.415235552705, 6173.32093469718, 5484.478974497416, 5771.394003629643, 5775.88448231033, 5785.247917770663, 5797.304323481209, 6429.421352078335, 6497.155369801895, 6635.369639884203, 6664.266930637529, 6719.395254427125, 6489.448396886318, 6545.901811472927, 6564.8358791030605, 6565.737370505126, 6566.146400967844, 6704.094947182068, 6735.292794256533, 6739.022841688025, 6739.123913891162, 6739.427148692416, 6295.213098362705, 6301.96849015752, 6473.287938326686, 6476.06097115925, 6478.773242936474, 6411.807645770635, 6472.223333200046, 6493.675316234264, 6495.870082598416, 6553.574017688835, 6269.7178626962805, 6321.051842223519, 6324.30432652417, 6327.145835312174, 6336.936630633634, 6297.4017468340435, 6326.4622507087415, 6364.172657554488, 6373.267074286517, 6380.553361264667, 6176.7838125665385, 6342.080398342122, 6347.333529979285, 6351.841203969938, 6352.809415717536, 6308.716107775253, 6309.7160627770945, 6318.178636777189, 6335.113084168128, 6372.617642941438, 6310.4005119515405, 6346.682665866781, 6351.872962540816, 6355.491560675227, 6368.42684158428], "value": 5975.369373581442, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [742.9739959101429, 794.3327604811957, 815.8001289684026, 1140.4696815980567, 1159.6057340504226, 27234.0, 36675.449868252, 37051.814740926326, 37062.62937370629, 37090.258194836155, 38102.04744881379, 38457.42313865294, 38523.42214866772, 38534.229315413744, 38620.03449913754, 6037.396260373971, 8449.746507604781, 8607.526586037766, 8626.956865215665, 8640.524771137585, 6130.386961303879, 6216.689165541705, 8312.459690120122, 8512.063672995966, 8534.189252021048, 8524.0, 8559.400841941067, 8600.956995215047, 8610.311175106008, 8629.827403451927, 8615.827683446327, 8661.350398720058, 8684.044755076937, 8685.739427817143, 8703.56482175895, 7279.490435669505, 8381.832363352698, 8442.240198382175, 8444.873326900102, 8447.32421406292, 37584.43623345652, 37970.81014594937, 37981.81009094964, 38006.429903551194, 38017.619823801964, 3036.1195253376495, 3038.8024778389554, 3046.764996654652, 3046.8019578727312, 3067.92330191747, 26901.181068909707, 27026.594601081, 27054.458910821675, 27357.452850943067, 27370.863145684343, 2762.4751297253456, 2768.709285525011, 2791.232411086947, 2828.4343131373807, 2907.345847184388, 228387.0, 259538.0, 307832.0, 309212.90787092067, 309806.99999999773, 49189.262161067614, 49308.0, 49312.76718082077, 49338.013239735, 49453.75273123611, 51383.0, 51427.742861286206, 51471.22793158106, 51506.742466288175, 51703.22445163326, 13950.09324393911, 14371.568852934428, 14374.209418482083, 14390.928045359706, 14404.207768572829, 11595.420228988516, 11608.129390295593, 11608.6517404478, 11608.941955290338, 11613.070954323633, 10886.727831804268, 11026.00765931054, 11067.889321106686, 11074.224804263786, 11083.947025032609, 4652.725844164471, 5740.512056475163, 5895.174675545427, 5919.852003699942, 5928.67392293428, 943.3160958305174, 945.5366870233673, 969.8351280282369, 985.2216748768393, 989.7921436498416, 23128.76871231266, 24252.636210456858, 24265.0, 24269.271921842374, 24299.51400972006, 1097.314178638351, 1131.332513816852, 1131.6322195286564, 1147.7474955509942, 1152.1186292486254, 10374.844377334346, 10795.866434024472, 11747.29516229029, 11980.142421968467, 12353.876461235273, 406506.0, 407033.0, 408148.0, 408213.0, 408577.0, 54211.45788542064, 54720.45279547233, 54806.0, 54879.0, 54881.45118548843, 1469.2433396800657, 1494.2379386513173, 1547.064026264094, 1554.5414102839688, 1564.6792407556425, 23509.52980940406, 23525.882370588723, 23562.41093972596, 23658.988985936387, 23692.407689807893, 39033.41449878311, 39175.0, 39197.628083016956, 39222.803885980386, 39235.0, 393215.0, 393243.0, 394210.02894985344, 394335.0, 394518.0, 31189.37621247517, 32065.0, 32149.517757233192, 32182.839085804422, 32209.35581288408, 42031.0, 42140.157196856504, 42404.0, 42666.7199984001, 43486.56513434825, 29696.109116726533, 30207.395852083275, 30380.69619303867, 30503.0, 30550.0, 29524.557131643473, 29747.256318592208, 30213.697863021967, 30288.394232114795, 30987.380252395276, 34877.476837846945, 35620.46569301411, 35706.28587428289, 35756.64243357533, 35847.74532891336, 29397.85301073481, 29775.404491910474, 30088.398232035674, 30558.694413055586, 30629.693703063575, 19182.616347673247, 20273.59452810965, 23044.193453229058, 23510.82445877665, 23656.763432366144], "value": 17245.668563593732, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [390.0891418537714, 415.9064210552624, 427.5510713750561, 428.37243438362793, 439.9230134726424, 14319.0, 20388.388348349574, 20546.0, 20907.163713451482, 20955.58088838226, 19283.0, 20184.0, 20359.185632574718, 21495.57008859822, 21774.782252177472, 5996.610220335686, 6133.509319254471, 6365.331640177784, 6366.681665916709, 6372.776952806653, 5393.406725260218, 6197.721102550396, 6232.407921247497, 6239.282482514501, 6259.280182778971, 5750.971245143789, 5990.341062483123, 6187.783427580036, 6190.473809726179, 6191.380861913818, 5901.05583106703, 5939.554533409968, 5940.732667029994, 5941.792037278675, 5950.821475355746, 5184.9740751296, 5303.787848486056, 5391.81128660499, 5455.563554915597, 5464.316960379952, 26144.084957026604, 27188.04841830546, 27460.45079098427, 27800.30499237515, 28134.874605015775, 1998.2306811877386, 2007.5483016321357, 2025.6556385414515, 2028.8579799414047, 2095.8218551423147, 15262.694746105015, 15482.845171548253, 16384.344626214937, 16489.34042638293, 16793.83206167947, 1657.627033917371, 1662.7755253040805, 1678.9412370567093, 1683.638017826161, 1685.6881476926685, 155834.0, 162712.3728762709, 164697.0, 165387.0, 168714.0, 11399.373034483184, 12160.391980401122, 12205.938970305091, 12309.138360314691, 12488.063395245394, 14567.271636418349, 14844.480443184653, 14862.405503779728, 14884.032537885003, 14893.77659335111, 7259.0, 7855.13593504714, 8903.287736981027, 8989.280857531381, 8991.055939126429, 8255.298299644477, 8399.682125560492, 8427.157284271647, 8583.785405364915, 8605.483670979762, 6303.621782693053, 7613.352865006536, 7630.528109710069, 7635.427342949301, 7653.57905315213, 1815.0, 1897.459224121119, 2106.526031642899, 2125.5111324395166, 2286.622707253318, 910.5947853205392, 919.3656377099779, 941.2705153506088, 946.4508094645081, 977.6969139566758, 19438.4168474946, 20427.387178384673, 20566.897165514376, 20632.0, 20980.26569070075, 811.9647449501814, 898.2948385517468, 927.5825878354767, 963.3449254507002, 984.3896783993827, 10129.544170512345, 10448.52981615814, 10452.425116618508, 10463.68608941733, 10499.68500944973, 629894.0, 691043.0, 691711.0, 695291.0, 705401.0, 148589.25705371404, 151750.24124879306, 152035.0, 152050.0, 155102.22448887685, 981.190517822799, 1066.1470823341297, 1095.6822521468684, 1158.0561842098662, 1161.2451906260999, 17245.482635520955, 17744.55638609045, 17828.3760068397, 17854.464366069038, 18295.72556411628, 39300.606993929694, 55255.72372138275, 56148.5962850932, 56255.71872140777, 56379.8724025509, 70437.0, 71547.28452715406, 71586.0, 71692.0, 73430.63284683542, 6475.417212450902, 8355.415120941476, 8526.701565445428, 8730.34522410809, 8854.513001784706, 13187.0, 13564.118332308368, 13598.980076494105, 13754.724905502035, 13872.237026963614, 11066.446677666245, 11363.829542556703, 11574.826377604175, 11713.297202167898, 11799.0, 10370.481475926023, 10389.792204155723, 10517.526711298164, 10711.517981690999, 10712.625058122929, 11557.595484158013, 11929.403529823649, 11979.640610781691, 11992.0, 12007.538454616957, 8323.833523329378, 8403.83192336162, 8528.91471085306, 8581.8712719308, 8821.470711757314, 11495.54017839277, 11594.678439327035, 11623.244489108181, 11840.881591183977, 11850.111241656738], "value": 10071.751383395373, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1332.9800052999203, 1390.2701081931984, 1413.858614138587, 1414.0596503325287, 1417.6314158318835, 10146.49267536623, 10712.839307410395, 10714.4107074111, 10716.839247411295, 10748.301360411577, 10117.595296188143, 10442.947785261063, 10558.99999999998, 10568.630097946574, 10572.78854422911, 2441.8046556275453, 2460.855702098524, 2463.3472129885504, 2471.8393304435153, 2474.3566672665147, 2053.5276886316155, 2305.3061539054434, 2390.6892104026538, 2434.561778879801, 2436.2447641231274, 2090.4042347930845, 2114.703941448191, 2116.9894150529226, 2117.2695420080126, 2119.957600847982, 2345.041890021825, 2435.9756402436014, 2436.8294219404647, 2436.8537887726793, 2442.7312995570474, 2086.54096098858, 2182.7121998021244, 2379.1672914479796, 2394.6408038794148, 2396.628522579003, 9661.661841835572, 10620.893791062144, 10635.255532112755, 10644.361338319648, 10666.946665266702, 6083.6045657032155, 6344.6510441925675, 6352.36476352361, 6355.586886852339, 6358.395952384516, 3172.825494597796, 3853.8265778040054, 3976.5228172619472, 4016.4577781999346, 4023.6177563131455, 1143.6054561176381, 1144.250515912079, 1185.1703807334814, 1186.6380753870067, 1206.5053328135423, 14691.77962330566, 14767.483138090116, 14808.037477563925, 14821.0, 14830.629234269121, 4807.423109226916, 5208.5312321891215, 5287.391949925808, 5295.285136506523, 5312.76092575835, 10569.418681972566, 10571.418571978462, 10574.735631609272, 10583.735406614742, 10588.682339529827, 6230.626162430269, 6236.438720515175, 6237.500999919996, 6239.282482514569, 6246.750129994749, 5395.892082158335, 5608.355039170466, 5653.067243904709, 5707.1153971134545, 5745.109508026263, 4003.5556603263894, 4120.340745480708, 4131.752094874317, 4170.22850772608, 4174.478190226222, 1240.6154092231436, 1243.9315837628835, 1244.4835393311855, 1262.066071107374, 1266.7973124300067, 706.6007705646293, 717.3615482220828, 740.8925705772741, 744.6909532543933, 775.0466925681443, 5542.91685624716, 5675.460831221028, 5806.709664516842, 5823.417658234142, 5855.033919403336, 554.1576803259023, 554.8612846788303, 580.2717589425329, 581.5434883616341, 584.1704779213527, 3451.4995325678137, 3941.4087886816633, 3945.2504024235304, 4163.937540936889, 4217.219814334358, 12761.915237204756, 13166.736665266833, 13845.653858653613, 13851.099678520864, 13869.653258668613, 13068.607941761762, 13355.933220334031, 13363.93318033423, 13365.53220637273, 13381.66545836362, 9564.473953932462, 9886.4562449066, 10000.299979001546, 10020.34867733595, 10042.096211341015, 5717.342505611908, 6422.582532135396, 6596.0, 6610.272869984394, 6613.074169616161, 8880.777980550538, 12129.332886690963, 12166.0, 12281.631551053482, 12318.014558835093, 12244.510219591117, 12275.631731048083, 12388.31864247439, 12424.813627795771, 12485.87514124847, 7647.770566883002, 8797.560121993747, 8822.779430514289, 8845.955770221108, 9004.613777348877, 8466.534340611326, 9851.901480985098, 9924.305298629315, 9937.850932235877, 9940.204783617437, 8304.837322774696, 8403.369747268856, 8494.023187333289, 8515.829683406173, 8580.14198580137, 8246.917530824854, 8267.958660206661, 8326.125756795449, 8371.33029357662, 8377.0, 7516.699332026877, 7689.50018248812, 7706.499077559941, 7726.1114971776715, 7732.497387669783, 5560.944390556043, 5680.630759000652, 5722.284714410699, 5774.0, 5866.090755932837, 5765.509931655856, 5852.970735146297, 5859.707014649336, 5873.061889576344, 5953.1070339449425], "value": 4953.569383509106, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [366.3497292306157, 382.92341531693666, 387.2236166486195, 390.3753993610223, 392.2116545743057, 5819.359870414251, 6093.51251899848, 6121.112438696387, 6123.887258895679, 6166.753329866806, 4937.950620493794, 5780.4219578042175, 5934.910976335347, 5991.262533865174, 5995.13070604762, 4082.7958602069925, 4365.912681746363, 4471.9267375829895, 4504.324351347292, 4507.121111383273, 3852.9229415411673, 4184.0, 4319.222539942809, 4548.385967894342, 4579.8779299071775, 3792.355299599075, 4507.211238033331, 4538.841140560081, 4544.182047231497, 4547.909041819162, 4261.808218630169, 4472.396226509412, 4516.796744146488, 4544.272916333371, 4545.386372839657, 3836.9040773980596, 3837.520309961255, 3845.1540661054523, 3853.402722578005, 3868.4197370394386, 4998.900241946766, 5063.26582645518, 5734.942650573483, 5853.85365365865, 5878.41215878413, 4965.851024469272, 5189.42916279209, 5215.478452154792, 5226.686398816045, 5238.528532432061, 6250.624962502219, 6336.46140078094, 6344.889644312227, 6356.396142366468, 6372.235331760172, 3617.457381392786, 3829.9234015319817, 4290.828366865353, 4307.375430562583, 4317.97841010796, 5564.0, 5704.458076482686, 6438.3883531064475, 6754.5609535380045, 6765.8646827063185, 4214.220369231702, 4334.653227741773, 4361.953131248541, 4373.409589705413, 4374.300111982067, 3574.1243395367783, 3770.20825626616, 4433.800478978389, 4444.866654000385, 4449.0, 4608.769561521911, 4897.951020489821, 5146.330976972969, 5208.296879921238, 5222.686638801684, 6391.520635952322, 6398.776042838478, 6422.935770642327, 6437.2918978912285, 6439.291677915426, 1426.2369632246698, 1511.3878879054, 1516.893817432769, 1517.704047710708, 1523.6647937453743, 848.4569875279826, 946.7633091727068, 965.9613615455303, 1002.4536627538009, 1010.5099026971913, 4926.901461970812, 5364.624476286623, 5663.32040155184, 5973.342932277446, 5975.372585878507, 6178.443940045418, 6262.812115636538, 6275.843103922439, 6570.145881035435, 6578.473722102316, 5627.8593035174445, 5896.174535565025, 5898.705064746831, 5948.524118070631, 5983.311919129356, 2923.0500087471655, 3085.2595377109783, 3085.783995120365, 3472.7048200903205, 3474.4093504103857, 5782.971085144632, 6351.745930162741, 6712.83217919556, 6804.625745583941, 6883.690233939485, 5673.602847800615, 6441.162648855712, 6720.185549901499, 6861.142357205349, 6912.101426814482, 5540.667559946416, 6357.777477788348, 6599.406053455212, 6884.483663725241, 6944.826379340557, 6281.403266689657, 6286.842828929313, 6380.680965951591, 6659.800205993828, 6700.391905942636, 6587.769428070186, 6600.702968366531, 6606.0421238919125, 6613.503987200883, 6623.602583844985, 5713.428657134254, 5998.460138587374, 6533.248676402084, 6854.8286292841085, 6888.552244104118, 3872.786996715208, 4024.9937515621095, 4069.5320038195987, 4278.5293617702655, 4381.802818873095, 5552.472515111058, 6599.901001485077, 6671.099401580918, 6685.164354455693, 6707.832304192434, 5211.71335576547, 6283.455200643747, 6416.374748267214, 6513.004018151703, 6513.250976137805, 6410.967945160244, 6427.389398007182, 6445.6454894981225, 6451.516196243566, 6465.191851018622, 5829.183914251923, 5998.0, 6207.224096987877, 6333.90499142504, 6343.35072881045, 5467.621584610548, 5889.882202356014, 5942.851428714317, 6425.646589437626, 6447.580907241202, 6283.246010478589, 6295.244570651368, 6325.399087086719, 6331.651759153291, 6350.7459701611415], "value": 4627.317858823683, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [703.8738019169326, 710.3997122431543, 737.8598066367391, 739.9778006659799, 767.0603510699394, 15333.003354781931, 16010.35958561659, 16057.678846423065, 16064.116473593962, 16074.276657550408, 14246.0, 14589.197594132318, 14622.707545849076, 14647.267636618153, 14719.779203311959, 902.5261737587786, 916.8487199612056, 937.1565590968115, 938.2259635800458, 938.7653086728318, 938.760616042908, 949.6723630347519, 952.1002652493396, 952.261996952364, 952.7713348796308, 916.244098618639, 967.4485543240356, 990.069334825262, 1013.2096964367768, 1019.6737044145876, 1006.808706345792, 1007.7178390050798, 1009.7475631092191, 1011.0546638892658, 1011.9342242754271, 738.5052015149862, 741.6996116572797, 742.3319012888389, 742.480263815326, 742.9702811887518, 14990.925045374812, 15021.324040418207, 15980.4406845761, 16154.919225403915, 16420.0, 12445.377731113407, 12686.55597054108, 13060.638679583786, 13507.122037067558, 13670.384832682554, 11248.493817778219, 11732.237404568761, 11762.882371176263, 11907.16649834512, 12472.126951113425, 2999.8500074996164, 3001.8048826826184, 3021.728044475986, 3031.439183751013, 3064.923376915595, 90039.0, 106024.0, 111051.0, 112399.0, 115006.42496787546, 11031.558737650405, 11127.944360278309, 11211.775764484664, 11500.827487587525, 11592.942035289769, 24524.509509809704, 24975.250742477754, 24996.875015624806, 25249.0, 25282.115125970504, 7645.426593005547, 7651.273129052731, 7740.922590774133, 7756.379489640815, 7768.766936991787, 3949.229900169497, 3983.980080099581, 4060.8578699745367, 4076.857309994136, 4079.89800254996, 4039.3738970459626, 4110.547839737626, 4147.668186545069, 4159.91680166395, 4178.247915375262, 776.8679324514846, 780.157429975625, 783.7883771381695, 784.541043489565, 803.746819751788, 520.5393226994138, 528.7409169506914, 549.7471163264943, 567.4864247855697, 593.5103539579864, 9155.13026262504, 9857.162141217934, 9981.45102019396, 10138.898611013796, 10150.441725705157, 510.660410826796, 518.199381954873, 568.1506148308223, 588.0708480600641, 593.305832176346, 4513.842015529441, 4529.501754806968, 4531.071130418256, 4558.544145585415, 4708.387909571734, 88944.5552772245, 89085.55457222802, 89289.0, 89789.55105224432, 91959.0, 59592.0, 64151.679241603495, 65285.0, 75437.86843197356, 77282.61358693283, 58444.415555844746, 66463.33536664669, 68714.0, 71548.0, 71662.0, 10377.325473844176, 10688.951414757707, 10804.67585972422, 10832.837507437238, 10872.619458319237, 36253.731119410695, 36376.81811590925, 37145.0, 37724.245515088995, 37886.24227515379, 47821.76089119671, 56037.71981140068, 64048.67975660092, 67861.6606916982, 68726.0, 11991.64025079249, 13003.739925201633, 13048.804267936179, 13553.390097445836, 13592.86407135916, 14243.0525929381, 14297.928510357382, 14474.421023158955, 14574.271286435847, 14607.415703371746, 12969.221846689228, 13198.472061117447, 13392.26342551169, 13543.86456135426, 13841.861581384057, 13297.534586289434, 13352.532661356807, 13386.866131338562, 13528.797068044185, 13685.794713079511, 11030.338179709242, 13010.804837927628, 13101.606951791462, 13220.206787592775, 13845.376958037114, 8216.958915205385, 8820.162084601952, 9573.952130239304, 9671.17794987434, 9803.509824508888, 8716.757497245342, 9724.617468719925, 10984.121270298518, 11057.612983545538, 11067.557297708323], "value": 7333.083701894152, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1617.5, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 122.0, 123.0, 122.0, 122.0, 122.0, 122.0, 124.0, 124.0, 122.0], "value": 121.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 121.0, 104.0, 182.0, 153.0, 155.0, 121.0, 118.0, 148.0, 118.0], "value": 137.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [220.0, 213.0, 219.0, 240.0, 290.0, 212.0, 215.0, 208.0, 216.0, 208.0], "value": 224.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 64.0, 93.0, 91.0, 94.0, 92.0, 89.0, 57.0, 94.0, 92.0], "value": 85.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 77.0, 94.0, 77.0, 74.0, 76.0, 93.0, 75.0, 79.0, 123.0], "value": 86.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 91.0, 92.0, 92.0, 92.0, 91.0, 90.0, 91.0, 91.0, 91.0], "value": 91.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 101.0, 101.0, 100.0, 100.0, 100.0, 102.0, 100.0, 100.0, 101.0], "value": 100.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 102.0, 102.0, 102.0, 102.0, 102.0, 101.0, 102.0, 103.0, 99.0], "value": 101.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 63.0, 64.0, 65.0, 65.0, 65.0, 64.0, 65.0, 65.0], "value": 64.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 66.0, 73.0, 66.0, 65.0, 65.0, 67.0, 65.0, 65.0, 65.0], "value": 66.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 89.0, 89.0, 90.0, 88.0, 91.0, 90.0, 89.0, 89.0, 92.0], "value": 90.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 166.0, 163.0, 167.0, 165.0, 157.0, 164.0, 168.0, 217.0, 174.0], "value": 170.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [241.0, 200.0, 197.0, 200.0, 201.0, 204.0, 203.0, 201.0, 204.0, 205.0], "value": 205.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 74.0, 73.0, 74.0, 72.0, 74.0, 71.0, 73.0, 73.0, 73.0], "value": 72.8, "unit": "score"}]}]} 18:59:15 INFO - Return code: 0 18:59:15 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:59:15 ERROR - # TBPL SUCCESS # 18:59:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:59:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:59:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:59:15 INFO - 2017-03-27 18:59:15.352 screenresolution[1656:9663] starting screenresolution argv=screenresolution get 18:59:15 INFO - 2017-03-27 18:59:15.355 screenresolution[1656:9663] Display 0: 1600x1200x32@60 18:59:15 INFO - 2017-03-27 18:59:15.362 screenresolution[1657:9666] starting screenresolution argv=screenresolution list 18:59:15 INFO - Available Modes on Display 0 18:59:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:59:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:59:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:59:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:59:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:59:15 INFO - Intel Iris: 18:59:15 INFO - Chipset Model: Intel Iris 18:59:15 INFO - Type: GPU 18:59:15 INFO - Bus: Built-In 18:59:15 INFO - VRAM (Dynamic, Max): 1536 MB 18:59:15 INFO - Vendor: Intel (0x8086) 18:59:15 INFO - Device ID: 0x0a2e 18:59:15 INFO - Revision ID: 0x0009 18:59:15 INFO - Displays: 18:59:15 INFO - Display: 18:59:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:59:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:59:15 INFO - Display Serial Number: 1600x1200 60 18:59:15 INFO - Main Display: Yes 18:59:15 INFO - Mirror: Off 18:59:15 INFO - Online: Yes 18:59:15 INFO - Rotation: Supported 18:59:15 INFO - Return code: 0 18:59:15 INFO - Running post-action listener: _resource_record_post_action 18:59:15 INFO - [mozharness: 2017-03-28 01:59:15.607809Z] Finished run-tests step (success) 18:59:15 INFO - Running post-run listener: _resource_record_post_run 18:59:15 INFO - Total resource usage - Wall time: 1205s; CPU: 54.0%; Read bytes: 36959232; Write bytes: 510619648; Read time: 241; Write time: 1540 18:59:15 INFO - TinderboxPrint: CPU usage
53.7% 18:59:15 INFO - TinderboxPrint: I/O read bytes / time
36,959,232 / 241 18:59:15 INFO - TinderboxPrint: I/O write bytes / time
510,619,648 / 1,540 18:59:15 INFO - TinderboxPrint: CPU idle
2,230.5 (46.3%) 18:59:15 INFO - TinderboxPrint: CPU system
965.3 (20.0%) 18:59:15 INFO - TinderboxPrint: CPU user
1,625.9 (33.7%) 18:59:15 INFO - TinderboxPrint: Swap in / out
248,246,272 / 0 18:59:15 INFO - install - Wall time: 21s; CPU: 55.0%; Read bytes: 200329728; Write bytes: 194221568; Read time: 15567; Write time: 396 18:59:15 INFO - run-tests - Wall time: 1185s; CPU: 54.0%; Read bytes: 36000768; Write bytes: 296565248; Read time: 228; Write time: 1083 18:59:16 INFO - Running post-run listener: _upload_blobber_files 18:59:16 INFO - Blob upload gear active. 18:59:16 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:59:16 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 18:59:16 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'] 18:59:16 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 18:59:16 INFO - (blobuploader) - INFO - Open directory for files ... 18:59:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:59:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:59:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:59:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:59:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:59:17 INFO - (blobuploader) - INFO - Done attempting. 18:59:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:59:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:59:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:59:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:59:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:59:18 INFO - (blobuploader) - INFO - Done attempting. 18:59:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:59:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:59:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:59:19 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:59:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:59:19 INFO - (blobuploader) - INFO - Done attempting. 18:59:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:59:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:59:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:59:22 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:59:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:59:22 INFO - (blobuploader) - INFO - Done attempting. 18:59:22 INFO - (blobuploader) - INFO - Iteration through files over. 18:59:22 INFO - Return code: 0 18:59:22 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:59:22 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:59:22 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6e38a892e23bc168e93aa0237cacdb5afea5620aeb3267052e84c62160162e5f7ff73b92c86134e59023b8ba1010aac1bee4ca36a60221bd74fe6e0036d2c7e9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d117d9bdb1622b3971d3cd9fa45d319ba2c23cf578a10ae394f95ee036f4dabe6331334ce06b3059cae45c2309ba0a8af067c4bb2f1ce5f40c9d8c6ff2b4230c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5bb0848d7fd5fbcee146d0b38b52b4ff74529e73677e003c9a16c970ed69f1b90cfc540b02033837acc581dbebe3e57702bee7710983704b1b843a52159b05e0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eadbe8809e26d5fcf34e522e1bdaa707825aeffe95d7aa100f486c18dfc6df72dac2dd82f7a3a5b862fe5c25718beba384106188184e8e785843a17fd2651785"} 18:59:22 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:59:22 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:59:22 INFO - Contents: 18:59:22 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6e38a892e23bc168e93aa0237cacdb5afea5620aeb3267052e84c62160162e5f7ff73b92c86134e59023b8ba1010aac1bee4ca36a60221bd74fe6e0036d2c7e9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d117d9bdb1622b3971d3cd9fa45d319ba2c23cf578a10ae394f95ee036f4dabe6331334ce06b3059cae45c2309ba0a8af067c4bb2f1ce5f40c9d8c6ff2b4230c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5bb0848d7fd5fbcee146d0b38b52b4ff74529e73677e003c9a16c970ed69f1b90cfc540b02033837acc581dbebe3e57702bee7710983704b1b843a52159b05e0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eadbe8809e26d5fcf34e522e1bdaa707825aeffe95d7aa100f486c18dfc6df72dac2dd82f7a3a5b862fe5c25718beba384106188184e8e785843a17fd2651785"} 18:59:22 INFO - Running post-run listener: copy_logs_to_upload_dir 18:59:22 INFO - Copying logs to upload dir... 18:59:22 INFO - mkdir: /builds/slave/test/build/upload/logs 18:59:22 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1288.705610 ========= master_lag: 1.82 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 30 secs) (at 2017-03-27 18:59:24.020593) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-27 18:59:24.025217) ========= 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.e6wBVfUPyX/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.SdzkxV9UgW/Listeners TMPDIR=/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/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/6e38a892e23bc168e93aa0237cacdb5afea5620aeb3267052e84c62160162e5f7ff73b92c86134e59023b8ba1010aac1bee4ca36a60221bd74fe6e0036d2c7e9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d117d9bdb1622b3971d3cd9fa45d319ba2c23cf578a10ae394f95ee036f4dabe6331334ce06b3059cae45c2309ba0a8af067c4bb2f1ce5f40c9d8c6ff2b4230c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5bb0848d7fd5fbcee146d0b38b52b4ff74529e73677e003c9a16c970ed69f1b90cfc540b02033837acc581dbebe3e57702bee7710983704b1b843a52159b05e0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eadbe8809e26d5fcf34e522e1bdaa707825aeffe95d7aa100f486c18dfc6df72dac2dd82f7a3a5b862fe5c25718beba384106188184e8e785843a17fd2651785"} build_url:https://queue.taskcluster.net/v1/task/X8lSl9S6SX-lHF5wmppY_g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011181 build_url: 'https://queue.taskcluster.net/v1/task/X8lSl9S6SX-lHF5wmppY_g/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/6e38a892e23bc168e93aa0237cacdb5afea5620aeb3267052e84c62160162e5f7ff73b92c86134e59023b8ba1010aac1bee4ca36a60221bd74fe6e0036d2c7e9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d117d9bdb1622b3971d3cd9fa45d319ba2c23cf578a10ae394f95ee036f4dabe6331334ce06b3059cae45c2309ba0a8af067c4bb2f1ce5f40c9d8c6ff2b4230c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5bb0848d7fd5fbcee146d0b38b52b4ff74529e73677e003c9a16c970ed69f1b90cfc540b02033837acc581dbebe3e57702bee7710983704b1b843a52159b05e0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eadbe8809e26d5fcf34e522e1bdaa707825aeffe95d7aa100f486c18dfc6df72dac2dd82f7a3a5b862fe5c25718beba384106188184e8e785843a17fd2651785"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-27 18:59:24.067585) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 18:59:24.067915) ========= 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.e6wBVfUPyX/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.SdzkxV9UgW/Listeners TMPDIR=/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/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.005063 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-27 18:59:24.115465) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-27 18:59:24.115762) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-27 18:59:24.116170) ========= ========= Total master_lag: 1.97 =========