builder: pine_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0025 starttime: 1491531729.22 results: success (0) buildid: 20170406133233 builduid: 5e1ce408cfe846c98b01b3dc74d2d9a6 revision: b66522c95dc82eb812b70cd2612e9409ac6da799 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-06 19:22:09.223711) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-06 19:22:09.224110) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-06 19:22:09.224385) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-06 19:22:09.251691) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 19:22:09.252090) ========= 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.zWaNJ50CG2/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.FU7sQJNkPj/Listeners TMPDIR=/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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-04-06 19:22:09-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 15.4M=0.001s 2017-04-06 19:22:09 (15.4 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.558603 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 19:22:09.852976) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 19:22:09.853247) ========= 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.zWaNJ50CG2/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.FU7sQJNkPj/Listeners TMPDIR=/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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.029307 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 19:22:09.926751) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2017-04-06 19:22:09.927085) ========= bash -c 'python archiver_client.py mozharness --repo projects/pine --rev b66522c95dc82eb812b70cd2612e9409ac6da799 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/pine --rev b66522c95dc82eb812b70cd2612e9409ac6da799 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.zWaNJ50CG2/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.FU7sQJNkPj/Listeners TMPDIR=/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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-04-06 19:22:10,037 truncating revision to first 12 chars 2017-04-06 19:22:10,037 Setting DEBUG logging. 2017-04-06 19:22:10,037 attempt 1/10 2017-04-06 19:22:10,037 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/pine/b66522c95dc8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-06 19:22:11,193 unpacking tar archive at: pine-b66522c95dc8/testing/mozharness/ program finished with exit code 0 elapsedTime=1.426657 ========= master_lag: 3.19 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2017-04-06 19:22:14.544551) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-06 19:22:14.544825) ========= script_repo_revision: b66522c95dc82eb812b70cd2612e9409ac6da799 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-06 19:22:14.545205) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-06 19:22:14.545486) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-06 19:22:14.585646) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 29 secs) (at 2017-04-06 19:22:14.585908) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Pine --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Pine 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', 'Pine', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Pine'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.zWaNJ50CG2/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.FU7sQJNkPj/Listeners TMPDIR=/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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 19:22:14 INFO - MultiFileLogger online at 20170406 19:22:14 in /builds/slave/test 19:22:14 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Pine --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Pine 19:22:14 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:22:14 INFO - {'append_to_log': False, 19:22:14 INFO - 'base_work_dir': '/builds/slave/test', 19:22:14 INFO - 'blob_upload_branch': 'Pine', 19:22:14 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:22:14 INFO - 'branch': 'Pine', 19:22:14 INFO - 'buildbot_json_path': 'buildprops.json', 19:22:14 INFO - 'config_files': ('talos/mac_config.py',), 19:22:14 INFO - 'default_actions': ('clobber', 19:22:14 INFO - 'read-buildbot-config', 19:22:14 INFO - 'download-and-extract', 19:22:14 INFO - 'populate-webroot', 19:22:14 INFO - 'create-virtualenv', 19:22:14 INFO - 'install', 19:22:14 INFO - 'run-tests'), 19:22:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:22:14 INFO - 'download_minidump_stackwalk': True, 19:22:14 INFO - 'download_symbols': 'ondemand', 19:22:14 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:22:14 INFO - 'tooltool.py': '/tools/tooltool.py', 19:22:14 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:22:14 INFO - '/tools/misc-python/virtualenv.py')}, 19:22:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:22:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:22:14 INFO - 'gecko_profile': False, 19:22:14 INFO - 'gecko_profile_interval': 0, 19:22:14 INFO - 'installer_path': 'installer.exe', 19:22:14 INFO - 'log_level': 'info', 19:22:14 INFO - 'log_name': 'talos', 19:22:14 INFO - 'log_to_console': True, 19:22:14 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:22:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:22:14 INFO - 'opt_config_files': (), 19:22:14 INFO - 'pip_index': False, 19:22:14 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:22:14 INFO - 'cmd': ('bash', 19:22:14 INFO - '-c', 19:22:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:22:14 INFO - 'enabled': True, 19:22:14 INFO - 'halt_on_failure': False, 19:22:14 INFO - 'name': 'check_screen_resolution'},), 19:22:14 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:22:14 INFO - 'cmd': ('bash', 19:22:14 INFO - '-c', 19:22:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:22:14 INFO - 'enabled': True, 19:22:14 INFO - 'halt_on_failure': False, 19:22:14 INFO - 'name': 'check_screen_resolution'},), 19:22:14 INFO - 'run_cmd_checks_enabled': True, 19:22:14 INFO - 'suite': 'dromaeojs', 19:22:14 INFO - 'system_bits': '32', 19:22:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:22:14 INFO - 'title': 't-yosemite-r7-0025', 19:22:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:22:14 INFO - 'use_talos_json': True, 19:22:14 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:22:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:22:14 INFO - 'work_dir': 'build'} 19:22:14 INFO - [mozharness: 2017-04-07 02:22:14.811036Z] Running clobber step. 19:22:14 INFO - Running pre-action listener: _resource_record_pre_action 19:22:14 INFO - Running main action method: clobber 19:22:14 INFO - rmtree: /builds/slave/test/build 19:22:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:22:17 INFO - Running post-action listener: _resource_record_post_action 19:22:17 INFO - [mozharness: 2017-04-07 02:22:17.336544Z] Finished clobber step (success) 19:22:17 INFO - [mozharness: 2017-04-07 02:22:17.336714Z] Running read-buildbot-config step. 19:22:17 INFO - Running pre-action listener: _resource_record_pre_action 19:22:17 INFO - Running main action method: read_buildbot_config 19:22:17 INFO - Using buildbot properties: 19:22:17 INFO - { 19:22:17 INFO - "project": "", 19:22:17 INFO - "product": "firefox", 19:22:17 INFO - "script_repo_revision": "default", 19:22:17 INFO - "builddir": "pine_yosemite_r7_test-dromaeojs", 19:22:17 INFO - "repository": "", 19:22:17 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 pine talos dromaeojs", 19:22:17 INFO - "buildid": "20170406133233", 19:22:17 INFO - "pgo_build": "False", 19:22:17 INFO - "basedir": "/builds/slave/test", 19:22:17 INFO - "buildnumber": 3, 19:22:17 INFO - "slavename": "t-yosemite-r7-0025", 19:22:17 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 19:22:17 INFO - "platform": "yosemite_r7", 19:22:17 INFO - "scheduler": "tests-pine-macosx64-talos", 19:22:17 INFO - "branch": "pine", 19:22:17 INFO - "repo_path": "projects/pine", 19:22:17 INFO - "revision": "b66522c95dc82eb812b70cd2612e9409ac6da799", 19:22:17 INFO - "stage_platform": "macosx64", 19:22:17 INFO - "builduid": "5e1ce408cfe846c98b01b3dc74d2d9a6", 19:22:17 INFO - "slavebuilddir": "test" 19:22:17 INFO - } 19:22:17 INFO - Found installer url https://queue.taskcluster.net/v1/task/MkZ5ojULSnGSwujJH3O1Bg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 19:22:17 INFO - Running post-action listener: _resource_record_post_action 19:22:17 INFO - [mozharness: 2017-04-07 02:22:17.339866Z] Finished read-buildbot-config step (success) 19:22:17 INFO - [mozharness: 2017-04-07 02:22:17.340015Z] Running download-and-extract step. 19:22:17 INFO - Running pre-action listener: _resource_record_pre_action 19:22:17 INFO - Running main action method: download_and_extract 19:22:17 INFO - mkdir: /builds/slave/test/build/tests 19:22:17 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')]} 19:22:17 INFO - trying https://queue.taskcluster.net/v1/task/MkZ5ojULSnGSwujJH3O1Bg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 19:22:17 INFO - Downloading https://queue.taskcluster.net/v1/task/MkZ5ojULSnGSwujJH3O1Bg/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 19:22:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MkZ5ojULSnGSwujJH3O1Bg/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 19:22:18 INFO - Downloaded 1325 bytes. 19:22:18 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 19:22:18 INFO - Using the following test package requirements: 19:22:18 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:22:18 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 19:22:18 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 19:22:18 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:22:18 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 19:22:18 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:22:18 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 19:22:18 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:22:18 INFO - u'jsshell-mac.zip'], 19:22:18 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:22:18 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 19:22:18 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 19:22:18 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:22:18 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 19:22:18 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:22:18 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 19:22:18 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:22:18 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 19:22:18 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:22:18 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 19:22:18 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 19:22:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MkZ5ojULSnGSwujJH3O1Bg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 19:22:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MkZ5ojULSnGSwujJH3O1Bg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:22:18 INFO - Fetch https://queue.taskcluster.net/v1/task/MkZ5ojULSnGSwujJH3O1Bg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 19:22:20 INFO - Expected file size: 52521830 19:22:20 INFO - Obtained file size: 52521830 19:22:36 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 19:22:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MkZ5ojULSnGSwujJH3O1Bg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 19:22:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MkZ5ojULSnGSwujJH3O1Bg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:22:36 INFO - Fetch https://queue.taskcluster.net/v1/task/MkZ5ojULSnGSwujJH3O1Bg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 19:22:38 INFO - Expected file size: 52521830 19:22:38 INFO - Obtained file size: 52521830 19:22:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MkZ5ojULSnGSwujJH3O1Bg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 19:22:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MkZ5ojULSnGSwujJH3O1Bg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 19:22:54 INFO - Fetch https://queue.taskcluster.net/v1/task/MkZ5ojULSnGSwujJH3O1Bg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 19:22:55 INFO - Expected file size: 14404138 19:22:55 INFO - Obtained file size: 14404138 19:22:55 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')]} 19:22:55 INFO - trying https://queue.taskcluster.net/v1/task/MkZ5ojULSnGSwujJH3O1Bg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 19:22:55 INFO - Downloading https://queue.taskcluster.net/v1/task/MkZ5ojULSnGSwujJH3O1Bg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 19:22:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MkZ5ojULSnGSwujJH3O1Bg/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 19:22:57 INFO - Downloaded 71108834 bytes. 19:22:57 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/MkZ5ojULSnGSwujJH3O1Bg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 19:22:57 INFO - mkdir: /builds/slave/test/properties 19:22:57 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:22:57 INFO - Writing to file /builds/slave/test/properties/build_url 19:22:57 INFO - Contents: 19:22:57 INFO - build_url:https://queue.taskcluster.net/v1/task/MkZ5ojULSnGSwujJH3O1Bg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 19:22:58 INFO - Running post-action listener: _resource_record_post_action 19:22:58 INFO - Running post-action listener: set_extra_try_arguments 19:22:58 INFO - [mozharness: 2017-04-07 02:22:58.672224Z] Finished download-and-extract step (success) 19:22:58 INFO - [mozharness: 2017-04-07 02:22:58.672369Z] Running populate-webroot step. 19:22:58 INFO - Running pre-action listener: _resource_record_pre_action 19:22:58 INFO - Running main action method: populate_webroot 19:22:58 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:22:58 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:22:58 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:22:58 INFO - '--tppagecycles', 19:22:58 INFO - '7'], 19:22:58 INFO - 'tests': ['tsvgm']}}, 19:22:58 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:22:58 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:22:58 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:22:58 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:22:58 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:22:58 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:22:58 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 19:22:58 INFO - 'talos_options': ['--disable-e10s'], 19:22:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:22:58 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 19:22:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:22:58 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 19:22:58 INFO - 'talos_options': ['--disable-e10s'], 19:22:58 INFO - 'tests': ['damp', 'tps']}, 19:22:58 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 19:22:58 INFO - 'tests': ['damp', 'tps']}, 19:22:58 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:22:58 INFO - 'tests': ['dromaeo_dom']}, 19:22:58 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:22:58 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:22:58 INFO - 'tests': ['basic_compositor_video']}, 19:22:58 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:22:58 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:22:58 INFO - 'tests': ['a11yr', 19:22:58 INFO - 'ts_paint', 19:22:58 INFO - 'tpaint', 19:22:58 INFO - 'sessionrestore', 19:22:58 INFO - 'sessionrestore_no_auto_restore', 19:22:58 INFO - 'tabpaint']}, 19:22:58 INFO - 'other-e10s': {'tests': ['a11yr', 19:22:58 INFO - 'ts_paint', 19:22:58 INFO - 'tpaint', 19:22:58 INFO - 'sessionrestore', 19:22:58 INFO - 'sessionrestore_no_auto_restore', 19:22:58 INFO - 'tabpaint']}, 19:22:58 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:22:58 INFO - 'ts_paint', 19:22:58 INFO - 'tpaint', 19:22:58 INFO - 'sessionrestore', 19:22:58 INFO - 'sessionrestore_no_auto_restore', 19:22:58 INFO - 'tabpaint']}, 19:22:58 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:22:58 INFO - 'ts_paint', 19:22:58 INFO - 'tpaint', 19:22:58 INFO - 'sessionrestore', 19:22:58 INFO - 'sessionrestore_no_auto_restore', 19:22:58 INFO - 'tabpaint']}, 19:22:58 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:22:58 INFO - 'tests': ['a11yr', 19:22:58 INFO - 'ts_paint', 19:22:58 INFO - 'tpaint', 19:22:58 INFO - 'sessionrestore', 19:22:58 INFO - 'sessionrestore_no_auto_restore', 19:22:58 INFO - 'tabpaint']}, 19:22:58 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:22:58 INFO - 'tests': ['a11yr', 19:22:58 INFO - 'ts_paint', 19:22:58 INFO - 'tpaint', 19:22:58 INFO - 'sessionrestore', 19:22:58 INFO - 'sessionrestore_no_auto_restore', 19:22:58 INFO - 'tabpaint']}, 19:22:58 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:22:58 INFO - 'tests': ['tsvgx', 19:22:58 INFO - 'tsvgr_opacity', 19:22:58 INFO - 'tart', 19:22:58 INFO - 'tscrollx', 19:22:58 INFO - 'cart', 19:22:58 INFO - 'tsvg_static']}, 19:22:58 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:22:58 INFO - 'tsvgr_opacity', 19:22:58 INFO - 'tart', 19:22:58 INFO - 'tscrollx', 19:22:58 INFO - 'cart', 19:22:58 INFO - 'tsvg_static']}, 19:22:58 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 19:22:58 INFO - 'talos_options': ['--disable-e10s'], 19:22:58 INFO - 'tests': ['tp5o']}, 19:22:58 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 19:22:58 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 19:22:58 INFO - 'talos_options': ['--disable-e10s', 19:22:58 INFO - '--xperf_path', 19:22:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:22:58 INFO - 'tests': ['tp5n']}, 19:22:58 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 19:22:58 INFO - 'talos_options': ['--xperf_path', 19:22:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:22:58 INFO - 'tests': ['tp5n']}}, 19:22:58 INFO - 'talos.zip': {'path': '', 19:22:58 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:22:58 INFO - Running post-action listener: _resource_record_post_action 19:22:58 INFO - [mozharness: 2017-04-07 02:22:58.691004Z] Finished populate-webroot step (success) 19:22:58 INFO - [mozharness: 2017-04-07 02:22:58.691132Z] Running create-virtualenv step. 19:22:58 INFO - Running pre-action listener: _resource_record_pre_action 19:22:58 INFO - Running main action method: create_virtualenv 19:22:58 INFO - Creating virtualenv /builds/slave/test/build/venv 19:22:58 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 19:22:58 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:22:58 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:22:58 INFO - Using real prefix '/tools/python27' 19:22:58 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:22:59 INFO - Installing distribute.............................................................................................................................................................................................done. 19:23:02 INFO - Installing pip.................done. 19:23:02 INFO - Return code: 0 19:23:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 19:23:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 19:23:02 INFO - Reading from file tmpfile_stdout 19:23:02 INFO - Output received: 19:23:02 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) 19:23:02 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:23:02 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')]} 19:23:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:23:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:23:02 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')]} 19:23:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:23:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:23:02 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 0x100f82b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f85768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb558ca9bb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d60d30>, '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 0x100c87db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100d55be8>, '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.zWaNJ50CG2/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.FU7sQJNkPj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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 19:23:02 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 19:23:02 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 19:23:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zWaNJ50CG2/Render', 19:23:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:23:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:23:02 INFO - 'HOME': '/Users/cltbld', 19:23:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:23:02 INFO - 'LOGNAME': 'cltbld', 19:23:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:23:02 INFO - 'MOZ_NO_REMOTE': '1', 19:23:02 INFO - 'NO_EM_RESTART': '1', 19:23:02 INFO - 'PAGER': '/bin/cat', 19:23:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:23:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:23:02 INFO - 'PWD': '/builds/slave/test', 19:23:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:23:02 INFO - 'SHELL': '/bin/bash', 19:23:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FU7sQJNkPj/Listeners', 19:23:02 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 19:23:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:23:02 INFO - 'USER': 'cltbld', 19:23:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:23:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:23:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:23:02 INFO - 'XPC_FLAGS': '0x0', 19:23:02 INFO - 'XPC_SERVICE_NAME': '0', 19:23:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:23:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:23:02 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 19:23:02 INFO - Cleaning up... 19:23:03 INFO - Return code: 0 19:23:03 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:23: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')]} 19:23:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:23:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:23: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')]} 19:23:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:23:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:23:03 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 0x100f82b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f85768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb558ca9bb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d60d30>, '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 0x100c87db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100d55be8>, '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.zWaNJ50CG2/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.FU7sQJNkPj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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 19:23:03 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 19:23:03 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 19:23:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zWaNJ50CG2/Render', 19:23:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:23:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:23:03 INFO - 'HOME': '/Users/cltbld', 19:23:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:23:03 INFO - 'LOGNAME': 'cltbld', 19:23:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:23:03 INFO - 'MOZ_NO_REMOTE': '1', 19:23:03 INFO - 'NO_EM_RESTART': '1', 19:23:03 INFO - 'PAGER': '/bin/cat', 19:23:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:23:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:23:03 INFO - 'PWD': '/builds/slave/test', 19:23:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:23:03 INFO - 'SHELL': '/bin/bash', 19:23:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FU7sQJNkPj/Listeners', 19:23:03 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 19:23:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:23:03 INFO - 'USER': 'cltbld', 19:23:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:23:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:23:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:23:03 INFO - 'XPC_FLAGS': '0x0', 19:23:03 INFO - 'XPC_SERVICE_NAME': '0', 19:23:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:23:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:23:03 INFO - Downloading/unpacking psutil>=3.1.1 19:23:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:23:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:23:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:23:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:23:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:23:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:23:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:23:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:23:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:23:07 INFO - Installing collected packages: psutil 19:23:07 INFO - Running setup.py install for psutil 19:23:07 INFO - building 'psutil._psutil_osx' extension 19:23:07 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 19:23:07 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 19:23:07 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 19:23:08 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 19:23:08 INFO - building 'psutil._psutil_posix' extension 19:23:08 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 19:23:08 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:23:08 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:23:08 INFO - ^ 19:23:08 INFO - 1 warning generated. 19:23:08 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 19:23:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:23:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:23:08 INFO - Successfully installed psutil 19:23:08 INFO - Cleaning up... 19:23:08 INFO - Return code: 0 19:23:08 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:23: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')]} 19:23:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:23:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:23: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')]} 19:23:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:23:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:23: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100f82b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f85768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb558ca9bb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d60d30>, '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 0x100c87db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100d55be8>, '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.zWaNJ50CG2/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.FU7sQJNkPj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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 19:23: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 19:23: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 mozsystemmonitor==0.3 19:23:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zWaNJ50CG2/Render', 19:23:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:23:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:23:08 INFO - 'HOME': '/Users/cltbld', 19:23:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:23:08 INFO - 'LOGNAME': 'cltbld', 19:23:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:23:08 INFO - 'MOZ_NO_REMOTE': '1', 19:23:08 INFO - 'NO_EM_RESTART': '1', 19:23:08 INFO - 'PAGER': '/bin/cat', 19:23:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:23:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:23:08 INFO - 'PWD': '/builds/slave/test', 19:23:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:23:08 INFO - 'SHELL': '/bin/bash', 19:23:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FU7sQJNkPj/Listeners', 19:23:08 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 19:23:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:23:08 INFO - 'USER': 'cltbld', 19:23:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:23:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:23:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:23:08 INFO - 'XPC_FLAGS': '0x0', 19:23:08 INFO - 'XPC_SERVICE_NAME': '0', 19:23:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:23:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:23:08 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:23:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:23:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:23:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:23:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:23:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:23:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:23:11 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:23:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:23:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:23:11 INFO - Installing collected packages: mozsystemmonitor 19:23:11 INFO - Running setup.py install for mozsystemmonitor 19:23:11 INFO - Successfully installed mozsystemmonitor 19:23:11 INFO - Cleaning up... 19:23:11 INFO - Return code: 0 19:23:11 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:23:11 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')]} 19:23:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:23:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:23:11 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')]} 19:23:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:23:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:23:11 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 0x100f82b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f85768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb558ca9bb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d60d30>, '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 0x100c87db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100d55be8>, '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.zWaNJ50CG2/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.FU7sQJNkPj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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 19:23:11 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 19:23:11 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 19:23:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zWaNJ50CG2/Render', 19:23:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:23:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:23:11 INFO - 'HOME': '/Users/cltbld', 19:23:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:23:11 INFO - 'LOGNAME': 'cltbld', 19:23:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:23:11 INFO - 'MOZ_NO_REMOTE': '1', 19:23:11 INFO - 'NO_EM_RESTART': '1', 19:23:11 INFO - 'PAGER': '/bin/cat', 19:23:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:23:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:23:11 INFO - 'PWD': '/builds/slave/test', 19:23:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:23:11 INFO - 'SHELL': '/bin/bash', 19:23:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FU7sQJNkPj/Listeners', 19:23:11 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 19:23:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:23:11 INFO - 'USER': 'cltbld', 19:23:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:23:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:23:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:23:11 INFO - 'XPC_FLAGS': '0x0', 19:23:11 INFO - 'XPC_SERVICE_NAME': '0', 19:23:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:23:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:23:11 INFO - Downloading/unpacking jsonschema==2.5.1 19:23:11 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 19:23:11 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 19:23:11 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 19:23:11 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 19:23:11 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 19:23:11 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 19:23:14 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:23:14 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:23:14 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 19:23:14 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 19:23:14 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 19:23:14 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 19:23:14 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 19:23:14 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 19:23:14 INFO - Downloading functools32-3.2.3-2.tar.gz 19:23:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:23:14 INFO - warning: no files found matching '*.txt' 19:23:14 INFO - no previously-included directories found matching 'build' 19:23:14 INFO - no previously-included directories found matching 'dist' 19:23:14 INFO - no previously-included directories found matching '.git*' 19:23:14 INFO - Installing collected packages: jsonschema, functools32 19:23:14 INFO - Running setup.py install for functools32 19:23:15 INFO - warning: no files found matching '*.txt' 19:23:15 INFO - no previously-included directories found matching 'build' 19:23:15 INFO - no previously-included directories found matching 'dist' 19:23:15 INFO - no previously-included directories found matching '.git*' 19:23:15 INFO - Successfully installed jsonschema functools32 19:23:15 INFO - Cleaning up... 19:23:15 INFO - Return code: 0 19:23:15 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:23:15 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')]} 19:23:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:23:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:23:15 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')]} 19:23:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:23:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:23:15 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 0x100f82b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f85768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb558ca9bb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d60d30>, '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 0x100c87db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100d55be8>, '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.zWaNJ50CG2/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.FU7sQJNkPj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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 19:23:15 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 19:23:15 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 19:23:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zWaNJ50CG2/Render', 19:23:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:23:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:23:15 INFO - 'HOME': '/Users/cltbld', 19:23:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:23:15 INFO - 'LOGNAME': 'cltbld', 19:23:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:23:15 INFO - 'MOZ_NO_REMOTE': '1', 19:23:15 INFO - 'NO_EM_RESTART': '1', 19:23:15 INFO - 'PAGER': '/bin/cat', 19:23:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:23:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:23:15 INFO - 'PWD': '/builds/slave/test', 19:23:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:23:15 INFO - 'SHELL': '/bin/bash', 19:23:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FU7sQJNkPj/Listeners', 19:23:15 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 19:23:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:23:15 INFO - 'USER': 'cltbld', 19:23:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:23:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:23:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:23:15 INFO - 'XPC_FLAGS': '0x0', 19:23:15 INFO - 'XPC_SERVICE_NAME': '0', 19:23:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:23:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:23:15 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:23:15 INFO - Cleaning up... 19:23:15 INFO - Return code: 0 19:23:15 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:23:15 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')]} 19:23:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:23:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:23:15 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')]} 19:23:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:23:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:23:15 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 0x100f82b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f85768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb558ca9bb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d60d30>, '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 0x100c87db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100d55be8>, '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.zWaNJ50CG2/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.FU7sQJNkPj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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 19:23:15 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 19:23:15 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 19:23:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zWaNJ50CG2/Render', 19:23:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:23:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:23:15 INFO - 'HOME': '/Users/cltbld', 19:23:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:23:15 INFO - 'LOGNAME': 'cltbld', 19:23:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:23:15 INFO - 'MOZ_NO_REMOTE': '1', 19:23:15 INFO - 'NO_EM_RESTART': '1', 19:23:15 INFO - 'PAGER': '/bin/cat', 19:23:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:23:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:23:15 INFO - 'PWD': '/builds/slave/test', 19:23:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:23:15 INFO - 'SHELL': '/bin/bash', 19:23:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FU7sQJNkPj/Listeners', 19:23:15 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 19:23:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:23:15 INFO - 'USER': 'cltbld', 19:23:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:23:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:23:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:23:15 INFO - 'XPC_FLAGS': '0x0', 19:23:15 INFO - 'XPC_SERVICE_NAME': '0', 19:23:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:23:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:23:15 INFO - Downloading/unpacking blobuploader==1.2.4 19:23:15 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 19:23:15 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 19:23:15 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 19:23:15 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 19:23:15 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 19:23:15 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 19:23:18 INFO - Downloading blobuploader-1.2.4.tar.gz 19:23:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:23:18 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:23:18 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 19:23:18 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 19:23:18 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 19:23:18 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 19:23:18 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 19:23:18 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 19:23:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:23:19 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:23:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:23:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:23:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:23:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:23:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:23:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:23:19 INFO - Downloading docopt-0.6.1.tar.gz 19:23:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:23:19 INFO - Installing collected packages: blobuploader, requests, docopt 19:23:19 INFO - Running setup.py install for blobuploader 19:23:19 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:23:19 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:23:19 INFO - Running setup.py install for requests 19:23:20 INFO - Running setup.py install for docopt 19:23:20 INFO - Successfully installed blobuploader requests docopt 19:23:20 INFO - Cleaning up... 19:23:20 INFO - Return code: 0 19:23:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:23:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:23:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:23:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:23:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:23:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:23:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:23:20 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 0x100f82b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f85768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb558ca9bb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d60d30>, '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 0x100c87db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100d55be8>, '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.zWaNJ50CG2/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.FU7sQJNkPj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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 19:23:20 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 19:23:20 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 19:23:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zWaNJ50CG2/Render', 19:23:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:23:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:23:20 INFO - 'HOME': '/Users/cltbld', 19:23:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:23:20 INFO - 'LOGNAME': 'cltbld', 19:23:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:23:20 INFO - 'MOZ_NO_REMOTE': '1', 19:23:20 INFO - 'NO_EM_RESTART': '1', 19:23:20 INFO - 'PAGER': '/bin/cat', 19:23:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:23:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:23:20 INFO - 'PWD': '/builds/slave/test', 19:23:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:23:20 INFO - 'SHELL': '/bin/bash', 19:23:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FU7sQJNkPj/Listeners', 19:23:20 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 19:23:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:23:20 INFO - 'USER': 'cltbld', 19:23:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:23:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:23:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:23:20 INFO - 'XPC_FLAGS': '0x0', 19:23:20 INFO - 'XPC_SERVICE_NAME': '0', 19:23:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:23:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:23:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:23:20 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-8kBbTY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:23:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:23:20 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-ksjbv2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:23:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:23:21 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-9kuMU4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:23:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:23:21 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-ZLKeaO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:23:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:23:21 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-rZMRpp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:23:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:23:21 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-NMOalM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:23:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:23:21 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-hvcCgX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:23:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:23:21 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-ajfEw1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:23:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:23:21 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-VqKJzi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:23:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:23:21 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-nYhSPk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:23:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:23:21 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-Hjcu68-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:23:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:23:21 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-aq8Rc1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:23:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:23:22 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-FKkwet-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:23:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:23:22 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-6awmPu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:23:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:23:22 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-vI4Tr8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:23:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:23:22 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-mBLrty-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:23:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:23:22 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-FbPhvG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:23:22 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:23:22 INFO - Running setup.py install for manifestparser 19:23:22 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:23:22 INFO - Running setup.py install for mozcrash 19:23:22 INFO - Running setup.py install for mozdebug 19:23:22 INFO - Running setup.py install for mozdevice 19:23:23 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:23:23 INFO - Running setup.py install for mozfile 19:23:23 INFO - Running setup.py install for mozhttpd 19:23:23 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:23:23 INFO - Running setup.py install for mozinfo 19:23:23 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:23:23 INFO - Running setup.py install for mozInstall 19:23:23 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:23:23 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:23:23 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:23:23 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:23:23 INFO - Running setup.py install for mozleak 19:23:23 INFO - Running setup.py install for mozlog 19:23:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:23:24 INFO - Running setup.py install for moznetwork 19:23:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:23:24 INFO - Running setup.py install for mozprocess 19:23:24 INFO - Running setup.py install for mozprofile 19:23:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:23:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:23:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:23:24 INFO - Running setup.py install for mozrunner 19:23:24 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:23:24 INFO - Running setup.py install for mozscreenshot 19:23:24 INFO - Running setup.py install for moztest 19:23:24 INFO - Running setup.py install for mozversion 19:23:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:23:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:23:25 INFO - Cleaning up... 19:23:25 INFO - Return code: 0 19:23:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:23:25 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')]} 19:23:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:23:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:23:25 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')]} 19:23:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:23:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:23:25 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 0x100f82b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f85768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb558ca9bb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d60d30>, '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 0x100c87db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100d55be8>, '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.zWaNJ50CG2/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.FU7sQJNkPj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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 19:23:25 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 19:23:25 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 19:23:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zWaNJ50CG2/Render', 19:23:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:23:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:23:25 INFO - 'HOME': '/Users/cltbld', 19:23:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:23:25 INFO - 'LOGNAME': 'cltbld', 19:23:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:23:25 INFO - 'MOZ_NO_REMOTE': '1', 19:23:25 INFO - 'NO_EM_RESTART': '1', 19:23:25 INFO - 'PAGER': '/bin/cat', 19:23:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:23:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:23:25 INFO - 'PWD': '/builds/slave/test', 19:23:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:23:25 INFO - 'SHELL': '/bin/bash', 19:23:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FU7sQJNkPj/Listeners', 19:23:25 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 19:23:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:23:25 INFO - 'USER': 'cltbld', 19:23:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:23:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:23:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:23:25 INFO - 'XPC_FLAGS': '0x0', 19:23:25 INFO - 'XPC_SERVICE_NAME': '0', 19:23:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:23:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:23:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:23:25 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-gyGBIO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:23:25 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)) 19:23:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:23:25 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-UycMbG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:23:25 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)) 19:23:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:23:25 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-tqppMn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:23:25 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)) 19:23:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:23:25 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-qRbIbA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:23:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 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)) 19:23:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:23:25 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-fmVqOE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:23:25 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)) 19:23:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:23:25 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-5ZK7Rp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:23:26 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)) 19:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:23:26 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-T36nUk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:23:26 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)) 19:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:23:26 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-Aqjomt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:23:26 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)) 19:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:23:26 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-DbvbtY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:23:26 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)) 19:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:23:26 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-6FEACd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:23:26 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)) 19:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:23:26 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-Sa3WeV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:23:26 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)) 19:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:23:26 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-xDNNoJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:23:26 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)) 19:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:23:26 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-QhmiDI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:23:26 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)) 19:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:23:26 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-V51P4i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:23:26 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)) 19:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:23:26 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-7imbqt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:23:26 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)) 19:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:23:26 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-3krmrD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:23:27 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)) 19:23:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:23:27 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-RqRRGV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:23:27 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)) 19:23:27 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)) 19:23:27 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)) 19:23:27 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)) 19:23:27 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:23:27 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:23:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:23:27 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 19:23:27 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 19:23:27 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 19:23:27 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 19:23:27 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 19:23:27 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 19:23:29 INFO - Downloading blessings-1.6.tar.gz 19:23:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:23:29 INFO - Installing collected packages: blessings 19:23:29 INFO - Running setup.py install for blessings 19:23:30 INFO - Successfully installed blessings 19:23:30 INFO - Cleaning up... 19:23:30 INFO - Return code: 0 19:23:30 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:23:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:23:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:23:30 INFO - Reading from file tmpfile_stdout 19:23:30 INFO - Current package versions: 19:23:30 INFO - blessings == 1.6 19:23:30 INFO - blobuploader == 1.2.4 19:23:30 INFO - docopt == 0.6.1 19:23:30 INFO - functools32 == 3.2.3-2 19:23:30 INFO - jsonschema == 2.5.1 19:23:30 INFO - manifestparser == 1.1 19:23:30 INFO - mozInstall == 1.12 19:23:30 INFO - mozcrash == 1.0 19:23:30 INFO - mozdebug == 0.1 19:23:30 INFO - mozdevice == 0.50 19:23:30 INFO - mozfile == 1.2 19:23:30 INFO - mozhttpd == 0.7 19:23:30 INFO - mozinfo == 0.9 19:23:30 INFO - mozleak == 0.1 19:23:30 INFO - mozlog == 3.4 19:23:30 INFO - moznetwork == 0.27 19:23:30 INFO - mozprocess == 0.25 19:23:30 INFO - mozprofile == 0.28 19:23:30 INFO - mozrunner == 6.13 19:23:30 INFO - mozscreenshot == 0.1 19:23:30 INFO - mozsystemmonitor == 0.3 19:23:30 INFO - moztest == 0.8 19:23:30 INFO - mozversion == 1.4 19:23:30 INFO - psutil == 3.1.1 19:23:30 INFO - requests == 1.2.3 19:23:30 INFO - wsgiref == 0.1.2 19:23:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:23: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')]} 19:23:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:23:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:23: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')]} 19:23:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:23:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:23:30 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 0x100f82b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f85768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb558ca9bb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d60d30>, '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 0x100c87db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100d55be8>, '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.zWaNJ50CG2/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.FU7sQJNkPj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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 19:23:30 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 19:23:30 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 19:23:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zWaNJ50CG2/Render', 19:23:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:23:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:23:30 INFO - 'HOME': '/Users/cltbld', 19:23:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:23:30 INFO - 'LOGNAME': 'cltbld', 19:23:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:23:30 INFO - 'MOZ_NO_REMOTE': '1', 19:23:30 INFO - 'NO_EM_RESTART': '1', 19:23:30 INFO - 'PAGER': '/bin/cat', 19:23:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:23:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:23:30 INFO - 'PWD': '/builds/slave/test', 19:23:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:23:30 INFO - 'SHELL': '/bin/bash', 19:23:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FU7sQJNkPj/Listeners', 19:23:30 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 19:23:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:23:30 INFO - 'USER': 'cltbld', 19:23:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:23:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:23:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:23:30 INFO - 'XPC_FLAGS': '0x0', 19:23:30 INFO - 'XPC_SERVICE_NAME': '0', 19:23:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:23:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:23:30 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)) 19:23:30 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)) 19:23:30 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)) 19:23:30 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)) 19:23:30 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)) 19:23:30 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)) 19:23:30 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)) 19:23:30 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)) 19:23:30 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)) 19:23:30 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)) 19:23:30 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)) 19:23:30 INFO - Cleaning up... 19:23:30 INFO - Return code: 0 19:23:30 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:23: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')]} 19:23:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:23:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:23: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')]} 19:23:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:23:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:23:30 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 0x100f82b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f85768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb558ca9bb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d60d30>, '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 0x100c87db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100d55be8>, '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.zWaNJ50CG2/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.FU7sQJNkPj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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 19:23:30 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 19:23:30 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 19:23:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zWaNJ50CG2/Render', 19:23:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:23:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:23:30 INFO - 'HOME': '/Users/cltbld', 19:23:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:23:30 INFO - 'LOGNAME': 'cltbld', 19:23:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:23:30 INFO - 'MOZ_NO_REMOTE': '1', 19:23:30 INFO - 'NO_EM_RESTART': '1', 19:23:30 INFO - 'PAGER': '/bin/cat', 19:23:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:23:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:23:30 INFO - 'PWD': '/builds/slave/test', 19:23:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:23:30 INFO - 'SHELL': '/bin/bash', 19:23:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FU7sQJNkPj/Listeners', 19:23:30 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 19:23:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:23:30 INFO - 'USER': 'cltbld', 19:23:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:23:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:23:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:23:30 INFO - 'XPC_FLAGS': '0x0', 19:23:30 INFO - 'XPC_SERVICE_NAME': '0', 19:23:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:23:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:23:30 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:23:30 INFO - Cleaning up... 19:23:30 INFO - Return code: 0 19:23:30 INFO - Running post-action listener: _resource_record_post_action 19:23:30 INFO - Running post-action listener: _start_resource_monitoring 19:23:30 INFO - Starting resource monitoring. 19:23:30 INFO - [mozharness: 2017-04-07 02:23:30.985279Z] Finished create-virtualenv step (success) 19:23:30 INFO - [mozharness: 2017-04-07 02:23:30.986332Z] Running install step. 19:23:30 INFO - Running pre-action listener: _resource_record_pre_action 19:23:30 INFO - Running main action method: install 19:23:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:23:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:23:31 INFO - Reading from file tmpfile_stdout 19:23:31 INFO - Detecting whether we're running mozinstall >=1.0... 19:23:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:23:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:23:31 INFO - Reading from file tmpfile_stdout 19:23:31 INFO - Output received: 19:23:31 INFO - Usage: mozinstall [options] installer 19:23:31 INFO - Options: 19:23:31 INFO - -h, --help show this help message and exit 19:23:31 INFO - -d DEST, --destination=DEST 19:23:31 INFO - Directory to install application into. [default: 19:23:31 INFO - "/builds/slave/test"] 19:23:31 INFO - --app=APP Application being installed. [default: firefox] 19:23:31 INFO - mkdir: /builds/slave/test/build/application 19:23:31 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'] 19:23:31 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 19:23:51 INFO - Reading from file tmpfile_stdout 19:23:51 INFO - Output received: 19:23:51 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 19:23:51 INFO - Running post-action listener: _resource_record_post_action 19:23:51 INFO - [mozharness: 2017-04-07 02:23:51.280997Z] Finished install step (success) 19:23:51 INFO - [mozharness: 2017-04-07 02:23:51.281124Z] Running run-tests step. 19:23:51 INFO - Running pre-action listener: _resource_record_pre_action 19:23:51 INFO - Running main action method: run_tests 19:23:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:23:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:23:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:23:51 INFO - 2017-04-06 19:23:51.295 screenresolution[1624:7695] starting screenresolution argv=screenresolution get 19:23:51 INFO - 2017-04-06 19:23:51.298 screenresolution[1624:7695] Display 0: 1600x1200x32@60 19:23:51 INFO - 2017-04-06 19:23:51.304 screenresolution[1625:7698] starting screenresolution argv=screenresolution list 19:23:51 INFO - Available Modes on Display 0 19:23:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:23:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:23:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:23:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:23:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:23:51 INFO - Intel Iris: 19:23:51 INFO - Chipset Model: Intel Iris 19:23:51 INFO - Type: GPU 19:23:51 INFO - Bus: Built-In 19:23:51 INFO - VRAM (Dynamic, Max): 1536 MB 19:23:51 INFO - Vendor: Intel (0x8086) 19:23:51 INFO - Device ID: 0x0a2e 19:23:51 INFO - Revision ID: 0x0009 19:23:51 INFO - Displays: 19:23:51 INFO - Display: 19:23:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:23:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:23:51 INFO - Display Serial Number: 1600x1200 60 19:23:51 INFO - Main Display: Yes 19:23:51 INFO - Mirror: Off 19:23:51 INFO - Online: Yes 19:23:51 INFO - Rotation: Supported 19:23:51 INFO - Return code: 0 19:23:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:23:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:23:51 INFO - Python 2.7.3 19:23:51 INFO - Return code: 0 19:23:51 INFO - grabbing minidump binary from tooltool 19:23:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:23:51 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 0x100d60d30>, '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 0x100c87db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100d55be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:23:51 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 19:23:51 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 19:23:51 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 19:23:51 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:23:51 INFO - Return code: 0 19:23:51 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:23:51 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:23:51 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:23:51 INFO - ENV: RUST_BACKTRACE is now 1 19:23:51 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:23:51 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:23:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Pine', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MkZ5ojULSnGSwujJH3O1Bg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0025', '--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 19:23:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Pine --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/MkZ5ojULSnGSwujJH3O1Bg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0025 --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 19:23:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zWaNJ50CG2/Render', 19:23:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:23:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:23:51 INFO - 'HOME': '/Users/cltbld', 19:23:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:23:51 INFO - 'LOGNAME': 'cltbld', 19:23:51 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:23:51 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:23:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:23:51 INFO - 'MOZ_NO_REMOTE': '1', 19:23:51 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:23:51 INFO - 'NO_EM_RESTART': '1', 19:23:51 INFO - 'PAGER': '/bin/cat', 19:23:51 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:23:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:23:51 INFO - 'PWD': '/builds/slave/test', 19:23:51 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:23:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:23:51 INFO - 'RUST_BACKTRACE': '1', 19:23:51 INFO - 'SHELL': '/bin/bash', 19:23:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FU7sQJNkPj/Listeners', 19:23:51 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 19:23:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:23:51 INFO - 'USER': 'cltbld', 19:23:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:23:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:23:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:23:51 INFO - 'XPC_FLAGS': '0x0', 19:23:51 INFO - 'XPC_SERVICE_NAME': '0', 19:23:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:23:51 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Pine', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MkZ5ojULSnGSwujJH3O1Bg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0025', '--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 19:23:51 INFO - mozversion application_buildid: 20170406133233 19:23:51 INFO - mozversion application_changeset: b66522c95dc82eb812b70cd2612e9409ac6da799 19:23:51 INFO - mozversion application_display_name: Nightly 19:23:51 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:23:51 INFO - mozversion application_name: Firefox 19:23:51 INFO - mozversion application_remotingname: firefox 19:23:51 INFO - mozversion application_repository: https://hg.mozilla.org/projects/pine 19:23:51 INFO - mozversion application_vendor: Mozilla 19:23:51 INFO - mozversion application_version: 55.0a1 19:23:51 INFO - mozversion platform_buildid: 20170406133233 19:23:51 INFO - mozversion platform_changeset: b66522c95dc82eb812b70cd2612e9409ac6da799 19:23:51 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/pine 19:23:51 INFO - mozversion platform_version: 55.0a1 19:23:51 INFO - using testdate: 1491531831 19:23:51 INFO - actual date: 1491531831 19:23:51 INFO - starting webserver on 'localhost:49240' 19:23:51 INFO - SUITE-START | Running 2 tests 19:23:51 INFO - TEST-START | dromaeo_css 19:23:51 INFO - operating with platform_type : mac_ 19:23:51 INFO - Initialising browser for dromaeo_css test... 19:23:51 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/tmpdAajc2/profile http://localhost:49240/getInfo.html) 19:23:52 INFO - PID 1635 | 1491531832571 addons.xpi WARN Error loading bootstrap.js for deployment-checker@mozilla.org: Error opening input stream (invalid filename?): jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/deployment-checker@mozilla.org.xpi!/bootstrap.js 19:23:52 INFO - PID 1635 | 1491531832572 addons.xpi WARN Add-on deployment-checker@mozilla.org is missing bootstrap method install 19:23:52 INFO - PID 1635 | 1491531832635 addons.xpi WARN Add-on deployment-checker@mozilla.org is missing bootstrap method startup 19:23:54 INFO - PID 1635 | __metrics Screen width/height:1600/1200 19:23:54 INFO - PID 1635 | colorDepth:24 19:23:54 INFO - PID 1635 | Browser inner width/height: 1024/689 19:23:54 INFO - PID 1635 | __metrics 19:23:54 INFO - PID 1635 | 1491531834764 addons.xpi WARN Add-on deployment-checker@mozilla.org is missing bootstrap method shutdown 19:23:54 INFO - PID 1635 | 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" 19:23:54 INFO - PID 1635 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 304: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:23:55 INFO - TEST-INFO | 1635: exit 0 19:23:55 INFO - Browser initialized. 19:23:55 INFO - Running cycle 1/1 for dromaeo_css test... 19:23:55 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/tmpdAajc2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:23:56 INFO - PID 1637 | 1491531836399 addons.xpi WARN Error loading bootstrap.js for deployment-checker@mozilla.org: Error opening input stream (invalid filename?): jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/deployment-checker@mozilla.org.xpi!/bootstrap.js 19:23:56 INFO - PID 1637 | 1491531836401 addons.xpi WARN Add-on deployment-checker@mozilla.org is missing bootstrap method startup 19:24:08 INFO - PID 1637 | RSS: Main: 239783936 19:24:08 INFO - PID 1637 | 19:27:13 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49240/tests/dromaeo/cssquery-ext.html) 19:27:15 INFO - PID 1637 | RSS: Main: 381022208 19:27:15 INFO - PID 1637 | 19:28:57 INFO - PID 1637 | ************************* 19:28:57 INFO - PID 1637 | A coding exception was thrown and uncaught in a Task. 19:28:57 INFO - PID 1637 | 19:28:57 ERROR - PID 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 19:28:57 INFO - PID 1637 | Full stack: 19:28:57 INFO - PID 1637 | ************************* 19:28:57 INFO - PID 1637 | ************************* 19:28:57 INFO - PID 1637 | A coding exception was thrown and uncaught in a Task. 19:28:57 INFO - PID 1637 | 19:28:57 ERROR - PID 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 19:28:57 INFO - PID 1637 | Full stack: 19:28:57 INFO - PID 1637 | ************************* 19:28:57 INFO - PID 1637 | ************************* 19:28:57 INFO - PID 1637 | A coding exception was thrown and uncaught in a Task. 19:28:57 INFO - PID 1637 | 19:28:57 ERROR - PID 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 19:28:57 INFO - PID 1637 | Full stack: 19:28:57 INFO - PID 1637 | ************************* 19:28:57 INFO - PID 1637 | ************************* 19:28:57 INFO - PID 1637 | A coding exception was thrown and uncaught in a Task. 19:28:57 INFO - PID 1637 | 19:28:57 ERROR - PID 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 19:28:57 INFO - PID 1637 | Full stack: 19:28:57 INFO - PID 1637 | ************************* 19:30:18 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-ext.html (next: http://localhost:49240/tests/dromaeo/cssquery-jquery.html) 19:30:19 INFO - PID 1637 | RSS: Main: 372039680 19:30:19 INFO - PID 1637 | 19:33:21 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49240/tests/dromaeo/cssquery-mootools.html) 19:33:22 INFO - PID 1637 | RSS: Main: 344465408 19:33:22 INFO - PID 1637 | 19:36:24 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49240/tests/dromaeo/cssquery-prototype.html) 19:36:25 INFO - PID 1637 | RSS: Main: 339603456 19:36:25 INFO - PID 1637 | 19:39:28 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49240/tests/dromaeo/cssquery-yui.html) 19:39:29 INFO - PID 1637 | RSS: Main: 346542080 19:39:29 INFO - PID 1637 | 19:42:32 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-yui.html (next: http://localhost:49240/tests/dromaeo/cssquery-dojo.html) 19:42:32 INFO - PID 1637 | RSS: Main: 466268160 19:42:32 INFO - PID 1637 | 19:42:32 INFO - PID 1637 | __start_tp_report 19:42:32 INFO - PID 1637 | _x_x_mozilla_page_load 19:42:32 INFO - PID 1637 | _x_x_mozilla_page_load_details 19:42:32 INFO - PID 1637 | |i|pagename|runs| 19:42:32 INFO - PID 1637 | |0;dojo.html;3545.450455179447;3560.9109772255692;3588.210593669395;3592.7125829933607;3634.291313193928;5209.947900520993;6194;6260.217472815898;6301.842453938648;6328.3671632836695;5874.794382196614;6240.344763799803;6244.594101383407;6326.8101956941255;6349.36506349366;5472.671639701611;5482;5501.092319767234;5506.394296627388;5686.545076393879;5368.677879327233;5454.4545545445335;5468.644538105031;5568.721563921808;5588.3573389060375;5466.289382380265;5471.917921231165;5483.917741233885;5489.176623506466;5490.094134467808;5426.077566813651;5471.890562188734;5478.123500239943;5481.232627432163;5496.7526461309335;5383.327084114486;5435.7825686972865;5446.537044351235;5453.345598528163;5463.180522921593;6178.876422471525;6222.937770622327;6236.781712640036;6236.906446403307;6253.437190652818;6596.703148358288;6683.732650693966;6699.732010719566;6793.252742198354;6805.523613347068;6894.207166175904;6896.344847239504;6911;6918.0314755934205;6919.757808476679;6379.904301435482;6397.296297407282;6397.424231819158;6404.583702059304;6413.454856337264;6787.966060169668;6862.759803406857;6874.862502749917;6956.026156338116;7063.964680176567;6643.302453242437;6652;6698.96650516754;6701.9947007949195;6708.161479815023;6870.759523416757;6887.380135787803;6887.483438742114;6887.691338645642;6907.13660792401;6482.254540727877;6589.53873228879;6671;6707.228668703162;6770.39066484009;6706.497012723968;6750.763723269662;6783.864322713518;6784.253732089466;6815.795526134224;5327.706976116351;5381.838544843661;5410.431904650034;5412.972935135378;5448;4717.551832575899;4746.335513028179;4799.520047995173;4799.832005879777;4815.22956326986;6254.749810007549;6265.780697675559;6275.96862015687;6289.0880822280515;6289.7484100636375;6695.899561506581;6700.396964273239;6706.027625994204;6710.429613482811;6780.593164410151;6291.339409362043;6312.968435157794;6322.209723784527;6350.714217860207;6362.840928976812;5995.2206213192;6004.249468816398;6011.609245399035;6035.818925432244;6056.848578785565;6567.737290508518;6875.174979002351;6950.096487456598;7022.613756243456;7027.086478757831;6597.2742998271115;6665.0668906352175;6681.565698229794;6693.631850248283;6721.630310332783;6821.420179284816;6837;6849.349311815369;7011.4741394394605;7029.578225306498;6555.770548030796;6560.639164845979;6634.635095069818;6690.83272918181;6734.528582999143;6431.356864313531;6488.772892948724;6505.089287499659;6532.118164047793;6578.210614726264;6424.550281480439;6432.13166222554;6478.060681201294;6519.152510173647;6519.934800651933;5250.816221432232;6396;6423.164988551458;6432.807015789534;6551.934480655323;6127.29536103336;6176.042713379434;6313.589616674902;6325.146105275729;6476.222853257639;6243.406876346739;6314.589551679126;6396.584222025554;6428.710708018057;6428.8071357859335;5323.733813309242;6274.686265686789;6278.434940855345;6334.841628959313;6425.261094973951 19:42:32 INFO - PID 1637 | |1;ext.html;657.6317262333093;799.9880151608215;1092.486531330275;1120.019982515299;1121.4841173060393;35166.82416587917;36329.818350908274;36699.08252293691;37045.07387315318;37647.43528847066;39086.413703794395;39162.60837391625;39437.60562394375;39732.00669983252;40328.79835600825;5919.141724449953;8483.15168483153;8489.533075680834;8495.023072346667;8515.616797244124;6096;8351.665933362658;8457.281131103864;8459.323254139654;8464.492130472192;6887.690053947559;8533.274671652916;8553.529555874424;8590.312774977987;8778.517181555011;8432.198941100585;8506;8525.976882774044;8530.104339044436;8531.701390451366;7181.102362204724;8078.555679437629;8228.053773815967;8404.915950840474;8426.157384261585;36128.63871361279;36336.27327453436;36358.90923272306;36965.63034369676;37376.065598359986;3430.6397828227928;3433.3133373325436;3435;3473.8436770345397;3511.736619753529;28650.71071801774;28698.569521457193;29044.27389315263;29163.125106246633;29579.408411831857;3021.0936718984217;3041.3305200446816;3062.3875224954877;3080.291532947412;3155.7790954633183;184029;279093;279696.99999999796;283377;285567;37661.24677506435;38040.66857660024;47478;48734.75632621814;48939;41196.79401602973;41340.79329603333;41697.58302416937;42420.363694544605;42495;13415.195088294538;13796.172229666252;13873.375698093409;13876.58370248894;14240.857591424161;11126.998570128728;11175.72060698489;11190.552377904793;11366.829497557543;11413.144014198968;9522.619095236252;12333.691657708629;12357.382130893599;12628.116031877864;12766.17019893704;4361.869143925687;5356.098585002268;5358.758855851496;5461.808836690697;5463.48096930791;818.7257268815014;944.4474982135528;970.8883478400074;993.2897977945803;1016.0043157705343;16395.754063689055;22157.210254362228;23874.641880371808;24035.27894163178;29420.264493387407;1008.3647302199714;1054.7310435838813;1081.6376513867942;1099.9945000274947;1115.7656892052598;9702.369345992487;11257;12312.322822244863;12389.814152787716;12479.188852724545;387185;390231;390529.0473547614;394795;397068;51649.48350516447;51776.96446071057;52177.7391113042;52495;53303;1457.6355911022245;1472.904261223017;1519.5365413548973;1537.4388348252808;1572.536101849957;15717.685646287238;23526.353155279652;23601.52796944086;23880.80595970229;23886.241810601685;38610.80694596509;38624.03439914025;39447.60552394554;39562.80218598888;39940;338150;339645;340259;342841;346945;26593.202203933914;27013.594796077683;29214.707852921198;32940.34119317648;33478.99563013113;39983.400663973116;40680.79659601683;41155;41830.37254441125;42066.789666051474;26021;27778;28224.43551129007;28664.713352866205;28840.855795721727;27059.594106088032;28153.296167595974;28780.280492987004;29272.121836344944;29759.25601859971;32928.67071329256;33834.8308258467;34402.65597343994;34722.82638586892;34879.47680784841;27793.202965616598;27862.16413507517;28810.703518341306;29132.854335729033;29401.558976615797;18933.308004938906;20767.027132422394;22211.77788222097;22517.986690598635;23301.76698232996 19:42:32 INFO - PID 1637 | |2;jquery.html;354.7038223083725;410.1284769864037;413.5657559562459;435.94550681164856;437.9474463064433;19772.681954137588;22213.555728885414;22531.54936901265;22787;23188.536229275447;22275.554488910213;22436.326910192678;22603.434914127156;23805.5238895222;23892;5958.8808223835385;6168.383161683841;6253.1558239637525;6288.371162883721;6375.744970201186;4817.759112044401;5447.509724124828;6289.716962736665;6435.646039467827;6516;6226.221722284715;6272.6236425814595;6302.653354065525;6345.904811427832;6483.254425741029;5887;6106.755729770804;6113.755449782004;6178.62928224308;6284.654344011101;5352.304200453916;5499.230107784913;5540.50135487804;5631.9718401408145;5642.351129620105;26463.338416539547;27745.722542774514;27960.881564737385;28177.57733633997;28594;2121.321177223289;2139.358192542231;2141.850070495066;2143.453419378064;2147.5812216617765;16901.91549042259;17229.91385043067;17420.128993550272;17513.56216094608;17848.821511784845;2043.8467114966288;2057.4753437873396;2110.5778844231063;2131.7868213178713;2167.5448155887284;131750.38873639377;133211;160759;164509;166346;12159.93920030412;12386;12410.75178496443;12545.560905368267;12607.180533265308;16477.752833707505;16514.2568584414;16613.50159495217;16945.49163525096;16965.491035268962;6882.931170688329;8962.283017358595;9108.225800807006;9146.588403521724;9280.907190928005;8609.61256743448;8613.181747733955;8621.396502244783;8784.077671844365;9055.773605659779;7637.923620763832;7821.726239581587;7845.803854903673;7959.442839001214;8000.959995199987;2058.4039939773274;2066.5763518478675;2072.958540829205;2094.5182608000246;2300.0914638717613;705.7670968580356;888.2050564744646;891.2825175733597;901.8466860633708;906.4380084347615;17694.380696675813;19591.412257632295;20136.39590812278;20158.496037599176;20786.06462709182;891.9509426981449;928.90853247434;933.7292185266333;935.4621092871625;935.9485228312508;10240.897591023995;10318.793624127475;10498.685039448828;10582.365058096539;10670.466476676134;675720;684597;687843;702968;703269;118229.54163667058;145939;153585.49803027586;156802.2159889193;157119;1086.983695244572;1139.7948369293606;1140.452582760263;1147.7647082348099;1172;12794.744105118032;17233.913830430767;17590.91204543969;17847.553811154827;18698.158582864075;46744.29883551681;62437.43906402194;63487.682561586895;63567.6821615889;64446.67776661087;85384.86537836604;85638;89408.37691100115;90406.54796725974;91253;8463.322934165375;8535.487870727777;9059.43106175936;9149.130832570896;9378.48418336998;13922.930385348009;14084.225367604471;14150.78773818414;14449.422023118957;14969.550913472613;11381.772364552497;11642.592509262475;11990;12173.330466824409;12378.195417297848;10792.676219713421;10806.243562950518;10868.510917008596;10923.289986150874;11245.437728113726;10945.616903408343;11481.196316257781;11765.470553824927;12342.44458999365;12423.813642795547;7402.7409040683315;8687.174718401742;8851.070637582961;9098.31762617793;9150.176484116462;11919.225250358699;12054.578089766816;12095.5204183144;12207.145499815282;12488.313142777235 19:42:32 INFO - PID 1637 | |3;mootools.html;1139.9772004559914;1245.4582256718322;1386.3969173409564;1397.0779285671454;1475.9225765191413;9201.723948281546;10097;11356.03473704735;11785;11981.940090299537;11374;11488.770224595502;11552.826707599394;11669.24149930254;11818.40907954603;2480.0575781203206;2481.838680485772;2482.379405148713;2493.326801763523;2519.395345117168;2142.185969331652;2169.13234706118;2411.2284069097896;2515.4843257132334;2566.5508536006123;2479.1818699829105;2480.7519248075228;2481.1812102006384;2487.776100150986;2545.9872700636565;2384.7257565379855;2454.901803927841;2491.700995880488;2494.326531836412;2508.9121880734087;2375.70303712036;2398.6761787158684;2407.374082738483;2425.854448733082;2482.379405148713;11587.073034157247;11669.70825729355;11684.240524365974;11793.11551633631;11959.222650527774;5898.233229680157;6175.691215439255;6326.588771729823;6668.899966500507;6791.4906382021545;3190.952135717966;4111.403846442279;4140.441040459529;4162.708610397273;4183.895402614929;1132.167856625388;1144.1361771862191;1187.9227850189795;1188.8573371195425;1225.2587184753274;16719.83280167195;16767.496975090766;16803.15984200785;16951.576210594834;17078.316867325415;5477.4248703886315;5606.943930560724;5853.765849366063;5860.062390017577;6028.065649824284;13639;13650.453981840814;13681.31593420325;13752.243626600433;14072.85927140736;6651.501137414713;6661.16735408074;6675.232348279913;6738.696758645872;6794.082798822095;5498.862528436821;5918.763249470059;6011.1884895539415;6084.087386892002;6144.477719393813;3928.941065884014;4119.9794001030405;4133.648639865645;4576;4602.677812553156;1187.9940600297116;1377.7795552711518;1381.3507651403825;1388.632012516684;1407.5495841330676;745.8657441660445;764.9273319034683;768.3699366519401;777.6189667063098;807.5290205741843;6082.452579267799;6292.528060395489;6594;6622.602643841288;6733.427658648971;853.4239388412797;864.2524216552637;864.943778654398;904.4980036080038;914.1270087066836;3565.16218688608;3680.6871415929945;3721.962780372216;3755.211405604799;3799.6580307772433;14149.726524612834;14475.78286325706;14764.261786910827;15171.24143792806;15409.229538523028;14798.63003424923;14874;14907.850921490863;14958.850411495963;15025.774613380809;10598.788024239471;10610.628627997983;10736.30214036085;10759.838602421127;11168.766246750682;5076.314697515936;5440.918386224131;6279.183706118175;6402.423781859655;6626.403623673892;11010.339379637247;13459.394327255493;13544.32278386057;13734.725305494034;13895.861041389457;13329.600111996655;13640;13842.377093031038;13894.444222230997;13996.370163342877;9525.666601668909;9625.51872406391;9629.422234665943;9635.614575416905;9853.556589953612;8965.686200982935;9623.422594644344;10514.737131571772;10857.185711071543;10885.836712449161;7532.849343012999;8915.866262006204;9129.817403652023;9191;9205.723828285161;8671.739847804576;8862.734117976472;8885.91114088851;8975.057618949917;9133.360664753403;7915.683372665029;8092.514449133071;8099.109097999333;8144.796380090545;8207.507549546808;4751;5309.230161626465;5899.793507227227;5917;6001.789937352171;5875;5969.492593129633;5992.0712289595185;6055.848603784941;6152.723127459365 19:42:32 INFO - PID 1637 | |4;prototype.html;324.2251020062052;385.7299890076946;389.7213492352967;392.63484958988164;398.5934346966094;5739;5801.912971305428;5844;5884.205632239647;5940.28716554013;5428.61999660024;5568.192612071247;5637.408072152426;5701.629394089381;5808.5353171746265;4237.237297286487;4264.253755592775;4299.14017196562;4329.7618630975285;4393.1872603568445;3597.4244120940652;4133.5659755725665;4359.149965756678;4375.474943006843;4384.947612572992;3696.963030369702;4012.217617564576;4234.618884300412;4268.231718290706;4286.721363111388;3399.269157131216;4023.545637161329;4169.852525949108;4205.201011807747;4348.412964249833;2934.7212014858555;3438.673326034023;3714.758540694846;3741.401375779862;3743.644353786386;5293.3647962244395;5568.331800183963;5635.464630860061;5691.345495268056;5767.625104368202;4654.5810877020895;4936.308916751657;5059.974700126512;5084.288199652052;5159.916417552318;4334.45819272591;6013.767177728599;6053.848653783647;6087.939120608826;6207.441330280297;3460.9480857787153;4140.8136633851545;4153.563875793058;4163.6669066474915;4210.179015092058;6235.906461403082;6356.396142366514;6469.644169570672;6519.674016299166;6600.372964568358;4156.875293741132;4186.853460128942;4196.328587425997;4236.915261694749;4306.978465107654;4261.722988005769;4269.978650106729;4345.500267469281;4394.780260986938;4398.494173170063;4822.662413631012;4978.551930326288;5017.172166592544;5053.267276244924;5160.845174644766;6124.142620033199;6128.571000030044;6135;6183.628982260989;6273.686315684197;1194.6953526850598;1443.5669299210194;1462.597785608966;1479.9260036998107;1488.843671414486;786.6263524825662;954.737447202025;962.1581116523042;974.1135566634329;992.4789485520015;5350.946490535045;5648.293963254593;5726.883257764779;5745.569082318842;5836.678982655994;4878.609711223094;5839.562032847553;6337.33831790649;6338.271098823602;6434.453071488976;5618.678986140591;5685.744141513559;5691.178822776672;5792.507636850831;5908.9614083142105;3261.5759951206664;3284.786488878215;3316.5688460499982;3350.9329813403597;3360.3447327771337;6190.690465476709;6451.387118223761;6462.741490340428;6502.934970650327;6550.475961923034;5933.110033495011;6069.2702579764555;6141.355157708376;6460.709268082947;6582.37467440602;5942.524598032147;6002.789902353571;6326.2092238470195;6334;6433.163688720437;5492.47821456961;5818.44724751148;6296.748130074746;6417.91417789211;6440.516961227833;5832.491163258477;6222.564420490522;6224.253089629274;6324.873502530016;6370.649614271259;5454.790809722069;6455.709493072917;6480.157579514822;6523;6660.333966603301;4184.330507118846;4200.978995105005;4222.662187025092;4247.135107052567;4272.850450234351;4852.514748525119;6237.96881015592;6324.557280990287;6358.936410635834;6554.573952693059;5008.348914641073;5159.509846564571;5197.81807636746;6163.537734669828;6233.345498722569;4821.300071959714;6116;6179.938200618116;6196.969015154896;6321.367863213826;5935.495482883831;5966.164736936919;5995.670238136944;5997.640141591519;6144.969275153774;5163.8192663258105;5387.730613469389;6157.751151705309;6236.844078898064;6259.436650701458;4905.9942711744725;5934.169216309634;5949.315828679757;5962.940370596239;5985.324109249417 19:42:32 INFO - PID 1637 | |5;yui.html;776.9223077692232;788.2078511096355;817.7383237364043;824.7608193623846;841.730646193218;15514.767278490832;15791.842081579181;15843.049417034958;15879.364825407;16308;13433.328333583306;14667.706645867076;14817.18505482198;14835.629109272273;15074.75892683073;950.0262231212993;972.367960825462;977.7848873247874;993.6273897288517;1021.4280003198232;968.1480297338333;972.2659392158945;975.0931633580767;975.3708857786723;978.0464047553614;1027.8201314769922;1028.0695970146992;1028.1569113327075;1032.566322144701;1060.76132870104;832.488019867783;1034.6430481483876;1038.3717850700368;1038.8857225705167;1041.1306559023224;764.9808754781119;767.3170877918657;767.6890859202032;774.4307933668749;783.0525064671754;15548.689026219412;16233.675326493521;16436.424725134562;16587.170641467877;16783.91608041952;14114;14180.50368237117;14197.36730276021;14294.857051429457;14625.70748585033;13233.86766132336;13328.800067998987;13334.799978000337;13404.46382144713;13547.187168769906;3274.983625081883;3339.398908196548;3344.6320904700465;3354.480055591363;3360.6135294441206;106219.46890265576;108561.45719271433;109955.45022274918;111350.44324778325;113621.43189284;7830.807373935308;10056.849147262797;11414.600488982845;11566.768664626661;11706.239094458833;23117.306480805604;23508.882455587613;23542.7645723544;23985.28044158678;24568.262952111465;7041.964790176016;7145.1425828900865;8117.310028647631;8129.105798362176;8361.247487726012;3957.604239576019;4045.4943132108488;4047.8583249586122;4088.693347998912;4219.38818871262;3402.217489977319;4105.835766569304;4144.70987030905;4192.266353388145;4288.078063216423;905.2079430498314;916.903725108854;926.2034650200821;928.6146249306462;948.1608776233084;599.5443462968176;601.290477236856;608.2336256316984;615.0773839241139;652.8628987912497;7821.569813660303;9679.564419601134;9692.612295508241;10170.440625765506;10177.796444071226;497.72873783787924;571.8191933656985;600.5525883216944;602.4246844263718;620.5190976992776;4167;4618.56123668251;4627.329037289642;4648.442186937589;4673.135469938073;105001.47499262454;107668;109902;111235;111936;93532;97474.51262743642;97929.2070792915;99725.50137249267;99933;84664;84795.5760221195;84999.15000850036;88313;88911;9277.814443710953;9354.625814967325;9502.239820814457;9627.759306017406;10514.947425262824;37128.81435592805;37479.62520374871;37751;38495.615043849204;39687.40468893027;71532;72774;73492;73549.6322518384;74670.25329746633;13082.28047457399;13386.799198011842;13545.255010974492;13575.660608484866;13771.173729576258;11166.385848778395;14124.78812817829;14161.104672075095;14202.502912398017;14678.41286348534;13368;13372.732545349234;13449.193048417126;13648.93175534116;13713.520026799413;11946.701332466759;13108.868911311147;13323.467061317438;13899.791503127664;14029.368678409292;13004.284764337674;13113.868861311264;13573.38919748594;13671.38478768438;13747.450101995808;9431.764205894908;9527.302646252638;9685.41887486753;9924.206063514757;10223.233257505866;8362.581870906552;9117.270618350649;9538.380005299634;10636.255462117577;10879.782404352027 19:42:32 INFO - PID 1637 | __end_tp_report 19:42:32 INFO - PID 1637 | __start_cc_report 19:42:32 INFO - PID 1637 | _x_x_mozilla_cycle_collect,2128 19:42:32 INFO - PID 1637 | __end_cc_report 19:42:32 INFO - PID 1637 | __startTimestamp1491532952393__endTimestamp 19:42:32 INFO - PID 1637 | 19:42:32 INFO - PID 1637 | ------- Summary: start ------- 19:42:32 INFO - PID 1637 | Number of tests: 6 19:42:32 INFO - PID 1637 | 19:42:32 INFO - PID 1637 | [#0] dojo.html Cycles:165 Average:6181.27 Median:6396.00 stddev:726.12 (11.4%) stddev-sans-first:698.29 19:42:32 INFO - PID 1637 | Values: 3545.5 3560.9 3588.2 3592.7 3634.3 5209.9 6194.0 6260.2 6301.8 6328.4 5874.8 6240.3 6244.6 6326.8 6349.4 5472.7 5482.0 5501.1 5506.4 5686.5 5368.7 5454.5 5468.6 5568.7 5588.4 5466.3 5471.9 5483.9 5489.2 5490.1 5426.1 5471.9 5478.1 5481.2 5496.8 5383.3 5435.8 5446.5 5453.3 5463.2 6178.9 6222.9 6236.8 6236.9 6253.4 6596.7 6683.7 6699.7 6793.3 6805.5 6894.2 6896.3 6911.0 6918.0 6919.8 6379.9 6397.3 6397.4 6404.6 6413.5 6788.0 6862.8 6874.9 6956.0 7064.0 6643.3 6652.0 6699.0 6702.0 6708.2 6870.8 6887.4 6887.5 6887.7 6907.1 6482.3 6589.5 6671.0 6707.2 6770.4 6706.5 6750.8 6783.9 6784.3 6815.8 5327.7 5381.8 5410.4 5413.0 5448.0 4717.6 4746.3 4799.5 4799.8 4815.2 6254.7 6265.8 6276.0 6289.1 6289.7 6695.9 6700.4 6706.0 6710.4 6780.6 6291.3 6313.0 6322.2 6350.7 6362.8 5995.2 6004.2 6011.6 6035.8 6056.8 6567.7 6875.2 6950.1 7022.6 7027.1 6597.3 6665.1 6681.6 6693.6 6721.6 6821.4 6837.0 6849.3 7011.5 7029.6 6555.8 6560.6 6634.6 6690.8 6734.5 6431.4 6488.8 6505.1 6532.1 6578.2 6424.6 6432.1 6478.1 6519.2 6519.9 5250.8 6396.0 6423.2 6432.8 6551.9 6127.3 6176.0 6313.6 6325.1 6476.2 6243.4 6314.6 6396.6 6428.7 6428.8 5323.7 6274.7 6278.4 6334.8 6425.3 19:42:32 INFO - PID 1637 | 19:42:32 INFO - PID 1637 | [#1] ext.html Cycles:180 Average:46609.41 Median:18918.83 stddev:89107.89 (471.0%) stddev-sans-first:89291.07 19:42:32 INFO - PID 1637 | Values: 657.6 800.0 1092.5 1120.0 1121.5 35166.8 36329.8 36699.1 37045.1 37647.4 39086.4 39162.6 39437.6 39732.0 40328.8 5919.1 8483.2 8489.5 8495.0 8515.6 6096.0 8351.7 8457.3 8459.3 8464.5 6887.7 8533.3 8553.5 8590.3 8778.5 8432.2 8506.0 8526.0 8530.1 8531.7 7181.1 8078.6 8228.1 8404.9 8426.2 36128.6 36336.3 36358.9 36965.6 37376.1 3430.6 3433.3 3435.0 3473.8 3511.7 28650.7 28698.6 29044.3 29163.1 29579.4 3021.1 3041.3 3062.4 3080.3 3155.8 184029.0 279093.0 279697.0 283377.0 285567.0 37661.2 38040.7 47478.0 48734.8 48939.0 41196.8 41340.8 41697.6 42420.4 42495.0 13415.2 13796.2 13873.4 13876.6 14240.9 11127.0 11175.7 11190.6 11366.8 11413.1 9522.6 12333.7 12357.4 12628.1 12766.2 4361.9 5356.1 5358.8 5461.8 5463.5 818.7 944.4 970.9 993.3 1016.0 16395.8 22157.2 23874.6 24035.3 29420.3 1008.4 1054.7 1081.6 1100.0 1115.8 9702.4 11257.0 12312.3 12389.8 12479.2 387185.0 390231.0 390529.0 394795.0 397068.0 51649.5 51777.0 52177.7 52495.0 53303.0 1457.6 1472.9 1519.5 1537.4 1572.5 15717.7 23526.4 23601.5 23880.8 23886.2 38610.8 38624.0 39447.6 39562.8 39940.0 338150.0 339645.0 340259.0 342841.0 346945.0 26593.2 27013.6 29214.7 32940.3 33479.0 39983.4 40680.8 41155.0 41830.4 42066.8 26021.0 27778.0 28224.4 28664.7 28840.9 27059.6 28153.3 28780.3 29272.1 29759.3 32928.7 33834.8 34402.7 34722.8 34879.5 27793.2 27862.2 28810.7 29132.9 29401.6 18933.3 20767.0 22211.8 22518.0 23301.8 19:42:32 INFO - PID 1637 | 19:42:32 INFO - PID 1637 | [#2] jquery.html Cycles:180 Average:40198.54 Median:12543.55 stddev:115809.03 (923.3%) stddev-sans-first:116095.26 19:42:32 INFO - PID 1637 | Values: 354.7 410.1 413.6 435.9 437.9 19772.7 22213.6 22531.5 22787.0 23188.5 22275.6 22436.3 22603.4 23805.5 23892.0 5958.9 6168.4 6253.2 6288.4 6375.7 4817.8 5447.5 6289.7 6435.6 6516.0 6226.2 6272.6 6302.7 6345.9 6483.3 5887.0 6106.8 6113.8 6178.6 6284.7 5352.3 5499.2 5540.5 5632.0 5642.4 26463.3 27745.7 27960.9 28177.6 28594.0 2121.3 2139.4 2141.9 2143.5 2147.6 16901.9 17229.9 17420.1 17513.6 17848.8 2043.8 2057.5 2110.6 2131.8 2167.5 131750.4 133211.0 160759.0 164509.0 166346.0 12159.9 12386.0 12410.8 12545.6 12607.2 16477.8 16514.3 16613.5 16945.5 16965.5 6882.9 8962.3 9108.2 9146.6 9280.9 8609.6 8613.2 8621.4 8784.1 9055.8 7637.9 7821.7 7845.8 7959.4 8001.0 2058.4 2066.6 2073.0 2094.5 2300.1 705.8 888.2 891.3 901.8 906.4 17694.4 19591.4 20136.4 20158.5 20786.1 892.0 928.9 933.7 935.5 935.9 10240.9 10318.8 10498.7 10582.4 10670.5 675720.0 684597.0 687843.0 702968.0 703269.0 118229.5 145939.0 153585.5 156802.2 157119.0 1087.0 1139.8 1140.5 1147.8 1172.0 12794.7 17233.9 17590.9 17847.6 18698.2 46744.3 62437.4 63487.7 63567.7 64446.7 85384.9 85638.0 89408.4 90406.5 91253.0 8463.3 8535.5 9059.4 9149.1 9378.5 13922.9 14084.2 14150.8 14449.4 14969.6 11381.8 11642.6 11990.0 12173.3 12378.2 10792.7 10806.2 10868.5 10923.3 11245.4 10945.6 11481.2 11765.5 12342.4 12423.8 7402.7 8687.2 8851.1 9098.3 9150.2 11919.2 12054.6 12095.5 12207.1 12488.3 19:42:32 INFO - PID 1637 | 19:42:32 INFO - PID 1637 | [#3] mootools.html Cycles:180 Average:7118.21 Median:2556.27 stddev:4621.44 (180.8%) stddev-sans-first:4612.57 19:42:32 INFO - PID 1637 | Values: 1140.0 1245.5 1386.4 1397.1 1475.9 9201.7 10097.0 11356.0 11785.0 11981.9 11374.0 11488.8 11552.8 11669.2 11818.4 2480.1 2481.8 2482.4 2493.3 2519.4 2142.2 2169.1 2411.2 2515.5 2566.6 2479.2 2480.8 2481.2 2487.8 2546.0 2384.7 2454.9 2491.7 2494.3 2508.9 2375.7 2398.7 2407.4 2425.9 2482.4 11587.1 11669.7 11684.2 11793.1 11959.2 5898.2 6175.7 6326.6 6668.9 6791.5 3191.0 4111.4 4140.4 4162.7 4183.9 1132.2 1144.1 1187.9 1188.9 1225.3 16719.8 16767.5 16803.2 16951.6 17078.3 5477.4 5606.9 5853.8 5860.1 6028.1 13639.0 13650.5 13681.3 13752.2 14072.9 6651.5 6661.2 6675.2 6738.7 6794.1 5498.9 5918.8 6011.2 6084.1 6144.5 3928.9 4120.0 4133.6 4576.0 4602.7 1188.0 1377.8 1381.4 1388.6 1407.5 745.9 764.9 768.4 777.6 807.5 6082.5 6292.5 6594.0 6622.6 6733.4 853.4 864.3 864.9 904.5 914.1 3565.2 3680.7 3722.0 3755.2 3799.7 14149.7 14475.8 14764.3 15171.2 15409.2 14798.6 14874.0 14907.9 14958.9 15025.8 10598.8 10610.6 10736.3 10759.8 11168.8 5076.3 5440.9 6279.2 6402.4 6626.4 11010.3 13459.4 13544.3 13734.7 13895.9 13329.6 13640.0 13842.4 13894.4 13996.4 9525.7 9625.5 9629.4 9635.6 9853.6 8965.7 9623.4 10514.7 10857.2 10885.8 7532.8 8915.9 9129.8 9191.0 9205.7 8671.7 8862.7 8885.9 8975.1 9133.4 7915.7 8092.5 8099.1 8144.8 8207.5 4751.0 5309.2 5899.8 5917.0 6001.8 5875.0 5969.5 5992.1 6055.8 6152.7 19:42:32 INFO - PID 1637 | 19:42:32 INFO - PID 1637 | [#4] prototype.html Cycles:180 Average:4953.96 Median:5667.02 stddev:1548.77 (27.3%) stddev-sans-first:1513.63 19:42:32 INFO - PID 1637 | Values: 324.2 385.7 389.7 392.6 398.6 5739.0 5801.9 5844.0 5884.2 5940.3 5428.6 5568.2 5637.4 5701.6 5808.5 4237.2 4264.3 4299.1 4329.8 4393.2 3597.4 4133.6 4359.1 4375.5 4384.9 3697.0 4012.2 4234.6 4268.2 4286.7 3399.3 4023.5 4169.9 4205.2 4348.4 2934.7 3438.7 3714.8 3741.4 3743.6 5293.4 5568.3 5635.5 5691.3 5767.6 4654.6 4936.3 5060.0 5084.3 5159.9 4334.5 6013.8 6053.8 6087.9 6207.4 3460.9 4140.8 4153.6 4163.7 4210.2 6235.9 6356.4 6469.6 6519.7 6600.4 4156.9 4186.9 4196.3 4236.9 4307.0 4261.7 4270.0 4345.5 4394.8 4398.5 4822.7 4978.6 5017.2 5053.3 5160.8 6124.1 6128.6 6135.0 6183.6 6273.7 1194.7 1443.6 1462.6 1479.9 1488.8 786.6 954.7 962.2 974.1 992.5 5350.9 5648.3 5726.9 5745.6 5836.7 4878.6 5839.6 6337.3 6338.3 6434.5 5618.7 5685.7 5691.2 5792.5 5909.0 3261.6 3284.8 3316.6 3350.9 3360.3 6190.7 6451.4 6462.7 6502.9 6550.5 5933.1 6069.3 6141.4 6460.7 6582.4 5942.5 6002.8 6326.2 6334.0 6433.2 5492.5 5818.4 6296.7 6417.9 6440.5 5832.5 6222.6 6224.3 6324.9 6370.6 5454.8 6455.7 6480.2 6523.0 6660.3 4184.3 4201.0 4222.7 4247.1 4272.9 4852.5 6238.0 6324.6 6358.9 6554.6 5008.3 5159.5 5197.8 6163.5 6233.3 4821.3 6116.0 6179.9 6197.0 6321.4 5935.5 5966.2 5995.7 5997.6 6145.0 5163.8 5387.7 6157.8 6236.8 6259.4 4906.0 5934.2 5949.3 5962.9 5985.3 19:42:32 INFO - PID 1637 | 19:42:32 INFO - PID 1637 | [#5] yui.html Cycles:180 Average:21125.32 Median:20265.52 stddev:31257.82 (154.2%) stddev-sans-first:31308.16 19:42:32 INFO - PID 1637 | Values: 776.9 788.2 817.7 824.8 841.7 15514.8 15791.8 15843.0 15879.4 16308.0 13433.3 14667.7 14817.2 14835.6 15074.8 950.0 972.4 977.8 993.6 1021.4 968.1 972.3 975.1 975.4 978.0 1027.8 1028.1 1028.2 1032.6 1060.8 832.5 1034.6 1038.4 1038.9 1041.1 765.0 767.3 767.7 774.4 783.1 15548.7 16233.7 16436.4 16587.2 16783.9 14114.0 14180.5 14197.4 14294.9 14625.7 13233.9 13328.8 13334.8 13404.5 13547.2 3275.0 3339.4 3344.6 3354.5 3360.6 106219.5 108561.5 109955.5 111350.4 113621.4 7830.8 10056.8 11414.6 11566.8 11706.2 23117.3 23508.9 23542.8 23985.3 24568.3 7042.0 7145.1 8117.3 8129.1 8361.2 3957.6 4045.5 4047.9 4088.7 4219.4 3402.2 4105.8 4144.7 4192.3 4288.1 905.2 916.9 926.2 928.6 948.2 599.5 601.3 608.2 615.1 652.9 7821.6 9679.6 9692.6 10170.4 10177.8 497.7 571.8 600.6 602.4 620.5 4167.0 4618.6 4627.3 4648.4 4673.1 105001.5 107668.0 109902.0 111235.0 111936.0 93532.0 97474.5 97929.2 99725.5 99933.0 84664.0 84795.6 84999.2 88313.0 88911.0 9277.8 9354.6 9502.2 9627.8 10514.9 37128.8 37479.6 37751.0 38495.6 39687.4 71532.0 72774.0 73492.0 73549.6 74670.3 13082.3 13386.8 13545.3 13575.7 13771.2 11166.4 14124.8 14161.1 14202.5 14678.4 13368.0 13372.7 13449.2 13648.9 13713.5 11946.7 13108.9 13323.5 13899.8 14029.4 13004.3 13113.9 13573.4 13671.4 13747.5 9431.8 9527.3 9685.4 9924.2 10223.2 8362.6 9117.3 9538.4 10636.3 10879.8 19:42:32 INFO - PID 1637 | -------- Summary: end -------- 19:42:32 INFO - PID 1637 | 19:42:32 INFO - PID 1637 | 1491532952420 addons.xpi WARN Add-on deployment-checker@mozilla.org is missing bootstrap method shutdown 19:42:33 INFO - TEST-INFO | 1637: exit 0 19:42:33 INFO - TEST-OK | dromaeo_css | took 1121201ms 19:42:33 INFO - TEST-START | kraken 19:42:33 INFO - operating with platform_type : mac_ 19:42:33 INFO - Initialising browser for kraken test... 19:42:33 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/tmpg65V72/profile http://localhost:49240/getInfo.html) 19:42:33 INFO - PID 1645 | 1491532953823 addons.xpi WARN Error loading bootstrap.js for deployment-checker@mozilla.org: Error opening input stream (invalid filename?): jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/deployment-checker@mozilla.org.xpi!/bootstrap.js 19:42:33 INFO - PID 1645 | 1491532953824 addons.xpi WARN Add-on deployment-checker@mozilla.org is missing bootstrap method install 19:42:33 INFO - PID 1645 | 1491532953888 addons.xpi WARN Add-on deployment-checker@mozilla.org is missing bootstrap method startup 19:42:35 INFO - PID 1645 | __metrics Screen width/height:1600/1200 19:42:35 INFO - PID 1645 | colorDepth:24 19:42:35 INFO - PID 1645 | Browser inner width/height: 1024/689 19:42:35 INFO - PID 1645 | __metrics 19:42:35 INFO - PID 1645 | 1491532955860 addons.xpi WARN Add-on deployment-checker@mozilla.org is missing bootstrap method shutdown 19:42:35 INFO - PID 1645 | 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" 19:42:35 INFO - PID 1645 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 304: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:42:36 INFO - TEST-INFO | 1645: exit 0 19:42:36 INFO - Browser initialized. 19:42:36 INFO - Running cycle 1/1 for kraken test... 19:42:36 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/tmpg65V72/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:42:37 INFO - PID 1646 | 1491532957319 addons.xpi WARN Error loading bootstrap.js for deployment-checker@mozilla.org: Error opening input stream (invalid filename?): jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/deployment-checker@mozilla.org.xpi!/bootstrap.js 19:42:37 INFO - PID 1646 | 1491532957320 addons.xpi WARN Add-on deployment-checker@mozilla.org is missing bootstrap method startup 19:42:49 INFO - PID 1646 | RSS: Main: 239271936 19:42:49 INFO - PID 1646 | 19:42:52 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection) 19:42:53 INFO - PID 1646 | RSS: Main: 293113856 19:42:53 INFO - PID 1646 | 19:42:56 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-dft) 19:42:56 INFO - PID 1646 | RSS: Main: 296128512 19:42:56 INFO - PID 1646 | 19:43:00 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-fft) 19:43:01 INFO - PID 1646 | RSS: Main: 389578752 19:43:01 INFO - PID 1646 | 19:43:03 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator) 19:43:03 INFO - PID 1646 | RSS: Main: 290885632 19:43:03 INFO - PID 1646 | 19:43:05 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:43:06 INFO - PID 1646 | RSS: Main: 293355520 19:43:06 INFO - PID 1646 | 19:43:09 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom) 19:43:10 INFO - PID 1646 | RSS: Main: 334979072 19:43:10 INFO - PID 1646 | 19:43:13 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate) 19:43:14 INFO - PID 1646 | RSS: Main: 344543232 19:43:14 INFO - PID 1646 | 19:43:17 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial) 19:43:18 INFO - PID 1646 | RSS: Main: 344817664 19:43:18 INFO - PID 1646 | 19:43:19 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:43:20 INFO - PID 1646 | RSS: Main: 310476800 19:43:20 INFO - PID 1646 | 19:43:22 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:43:22 INFO - PID 1646 | RSS: Main: 312414208 19:43:22 INFO - PID 1646 | 19:43:25 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:43:25 INFO - PID 1646 | RSS: Main: 314740736 19:43:25 INFO - PID 1646 | 19:43:29 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:43:29 INFO - PID 1646 | RSS: Main: 332713984 19:43:29 INFO - PID 1646 | 19:43:33 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:43:33 INFO - PID 1646 | RSS: Main: 315764736 19:43:33 INFO - PID 1646 | 19:43:35 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49240/tests/kraken/driver.html?testName=ai-astar) 19:43:36 INFO - PID 1646 | RSS: Main: 381935616 19:43:36 INFO - PID 1646 | 19:43:36 INFO - PID 1646 | __start_tp_report 19:43:36 INFO - PID 1646 | _x_x_mozilla_page_load 19:43:36 INFO - PID 1646 | _x_x_mozilla_page_load_details 19:43:36 INFO - PID 1646 | |i|pagename|runs| 19:43:36 INFO - PID 1646 | |0;ai-astar;109;122;122;123;124;160;180;124;123;123 19:43:36 INFO - PID 1646 | |1;audio-beat-detection;176;154;150;196;118;121;114;119;144;119 19:43:36 INFO - PID 1646 | |2;audio-dft;222;216;217;210;218;214;219;208;212;210 19:43:36 INFO - PID 1646 | |3;audio-fft;86;64;67;100;68;68;61;61;82;63 19:43:36 INFO - PID 1646 | |4;audio-oscillator;95;73;96;74;74;75;91;73;74;92 19:43:36 INFO - PID 1646 | |5;imaging-gaussian-blur;92;92;94;93;93;93;92;93;92;95 19:43:36 INFO - PID 1646 | |6;imaging-darkroom;100;101;102;103;100;101;102;102;101;101 19:43:36 INFO - PID 1646 | |7;imaging-desaturate;101;103;124;155;100;100;100;101;101;100 19:43:36 INFO - PID 1646 | |8;json-parse-financial;51;51;51;52;53;52;53;51;54;81 19:43:36 INFO - PID 1646 | |9;json-stringify-tinderbox;68;65;67;70;67;64;65;64;66;67 19:43:36 INFO - PID 1646 | |10;stanford-crypto-aes;107;108;93;92;93;92;91;93;93;92 19:43:36 INFO - PID 1646 | |11;stanford-crypto-ccm;169;166;142;165;167;172;161;155;196;184 19:43:36 INFO - PID 1646 | |12;stanford-crypto-pbkdf2;257;203;202;209;206;204;207;202;207;209 19:43:36 INFO - PID 1646 | |13;stanford-crypto-sha256-iterative;72;76;73;74;74;73;73;73;77;73 19:43:36 INFO - PID 1646 | __end_tp_report 19:43:36 INFO - PID 1646 | __start_cc_report 19:43:36 INFO - PID 1646 | _x_x_mozilla_cycle_collect,2216 19:43:36 INFO - PID 1646 | __end_cc_report 19:43:36 INFO - PID 1646 | __startTimestamp1491533016021__endTimestamp 19:43:36 INFO - PID 1646 | 19:43:36 INFO - PID 1646 | ------- Summary: start ------- 19:43:36 INFO - PID 1646 | Number of tests: 14 19:43:36 INFO - PID 1646 | 19:43:36 INFO - PID 1646 | [#0] ai-astar Cycles:10 Average:131.00 Median:123.50 stddev:21.55 (17.4%) stddev-sans-first:21.33 19:43:36 INFO - PID 1646 | Values: 109.0 122.0 122.0 123.0 124.0 160.0 180.0 124.0 123.0 123.0 19:43:36 INFO - PID 1646 | 19:43:36 INFO - PID 1646 | [#1] audio-beat-detection Cycles:10 Average:141.10 Median:147.00 stddev:28.16 (19.2%) stddev-sans-first:26.88 19:43:36 INFO - PID 1646 | Values: 176.0 154.0 150.0 196.0 118.0 121.0 114.0 119.0 144.0 119.0 19:43:36 INFO - PID 1646 | 19:43:36 INFO - PID 1646 | [#2] audio-dft Cycles:10 Average:214.60 Median:216.50 stddev:4.55 (2.1%) stddev-sans-first:3.96 19:43:36 INFO - PID 1646 | Values: 222.0 216.0 217.0 210.0 218.0 214.0 219.0 208.0 212.0 210.0 19:43:36 INFO - PID 1646 | 19:43:36 INFO - PID 1646 | [#3] audio-fft Cycles:10 Average:72.00 Median:67.50 stddev:13.01 (19.3%) stddev-sans-first:12.78 19:43:36 INFO - PID 1646 | Values: 86.0 64.0 67.0 100.0 68.0 68.0 61.0 61.0 82.0 63.0 19:43:36 INFO - PID 1646 | 19:43:36 INFO - PID 1646 | [#4] audio-oscillator Cycles:10 Average:81.70 Median:83.00 stddev:10.26 (12.4%) stddev-sans-first:9.69 19:43:36 INFO - PID 1646 | Values: 95.0 73.0 96.0 74.0 74.0 75.0 91.0 73.0 74.0 92.0 19:43:36 INFO - PID 1646 | 19:43:36 INFO - PID 1646 | [#5] imaging-gaussian-blur Cycles:10 Average:92.90 Median:93.00 stddev:0.99 (1.1%) stddev-sans-first:1.00 19:43:36 INFO - PID 1646 | Values: 92.0 92.0 94.0 93.0 93.0 93.0 92.0 93.0 92.0 95.0 19:43:36 INFO - PID 1646 | 19:43:36 INFO - PID 1646 | [#6] imaging-darkroom Cycles:10 Average:101.30 Median:101.50 stddev:0.95 (0.9%) stddev-sans-first:0.88 19:43:36 INFO - PID 1646 | Values: 100.0 101.0 102.0 103.0 100.0 101.0 102.0 102.0 101.0 101.0 19:43:36 INFO - PID 1646 | 19:43:36 INFO - PID 1646 | [#7] imaging-desaturate Cycles:10 Average:108.50 Median:101.00 stddev:17.92 (17.7%) stddev-sans-first:18.80 19:43:36 INFO - PID 1646 | Values: 101.0 103.0 124.0 155.0 100.0 100.0 100.0 101.0 101.0 100.0 19:43:36 INFO - PID 1646 | 19:43:36 INFO - PID 1646 | [#8] json-parse-financial Cycles:10 Average:54.90 Median:52.50 stddev:9.23 (17.6%) stddev-sans-first:9.68 19:43:36 INFO - PID 1646 | Values: 51.0 51.0 51.0 52.0 53.0 52.0 53.0 51.0 54.0 81.0 19:43:36 INFO - PID 1646 | 19:43:36 INFO - PID 1646 | [#9] json-stringify-tinderbox Cycles:10 Average:66.30 Median:67.00 stddev:1.89 (2.8%) stddev-sans-first:1.90 19:43:36 INFO - PID 1646 | Values: 68.0 65.0 67.0 70.0 67.0 64.0 65.0 64.0 66.0 67.0 19:43:36 INFO - PID 1646 | 19:43:36 INFO - PID 1646 | [#10] stanford-crypto-aes Cycles:10 Average:95.40 Median:92.50 stddev:6.42 (6.9%) stddev-sans-first:5.25 19:43:36 INFO - PID 1646 | Values: 107.0 108.0 93.0 92.0 93.0 92.0 91.0 93.0 93.0 92.0 19:43:36 INFO - PID 1646 | 19:43:36 INFO - PID 1646 | [#11] stanford-crypto-ccm Cycles:10 Average:167.70 Median:168.00 stddev:14.77 (8.8%) stddev-sans-first:15.66 19:43:36 INFO - PID 1646 | Values: 169.0 166.0 142.0 165.0 167.0 172.0 161.0 155.0 196.0 184.0 19:43:36 INFO - PID 1646 | 19:43:36 INFO - PID 1646 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:210.60 Median:207.00 stddev:16.51 (8.0%) stddev-sans-first:2.79 19:43:36 INFO - PID 1646 | Values: 257.0 203.0 202.0 209.0 206.0 204.0 207.0 202.0 207.0 209.0 19:43:36 INFO - PID 1646 | 19:43:36 INFO - PID 1646 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.80 Median:73.50 stddev:1.55 (2.1%) stddev-sans-first:1.50 19:43:36 INFO - PID 1646 | Values: 72.0 76.0 73.0 74.0 74.0 73.0 73.0 73.0 77.0 73.0 19:43:36 INFO - PID 1646 | -------- Summary: end -------- 19:43:36 INFO - PID 1646 | 19:43:36 INFO - PID 1646 | 1491533016046 addons.xpi WARN Add-on deployment-checker@mozilla.org is missing bootstrap method shutdown 19:43:37 INFO - TEST-INFO | 1646: exit 0 19:43:37 INFO - TEST-OK | kraken | took 64205ms 19:43:37 INFO - SUITE-END | took 1185s 19:43:37 INFO - Completed test suite (00:19:45) 19:43:37 INFO - javascript benchmark 19:43:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7630.083886246328, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3545.450455179447, 3560.9109772255692, 3588.210593669395, 3592.7125829933607, 3634.291313193928, 5209.947900520993, 6194.0, 6260.217472815898, 6301.842453938648, 6328.3671632836695, 5874.794382196614, 6240.344763799803, 6244.594101383407, 6326.8101956941255, 6349.36506349366, 5472.671639701611, 5482.0, 5501.092319767234, 5506.394296627388, 5686.545076393879, 5368.677879327233, 5454.4545545445335, 5468.644538105031, 5568.721563921808, 5588.3573389060375, 5466.289382380265, 5471.917921231165, 5483.917741233885, 5489.176623506466, 5490.094134467808, 5426.077566813651, 5471.890562188734, 5478.123500239943, 5481.232627432163, 5496.7526461309335, 5383.327084114486, 5435.7825686972865, 5446.537044351235, 5453.345598528163, 5463.180522921593, 6178.876422471525, 6222.937770622327, 6236.781712640036, 6236.906446403307, 6253.437190652818, 6596.703148358288, 6683.732650693966, 6699.732010719566, 6793.252742198354, 6805.523613347068, 6894.207166175904, 6896.344847239504, 6911.0, 6918.0314755934205, 6919.757808476679, 6379.904301435482, 6397.296297407282, 6397.424231819158, 6404.583702059304, 6413.454856337264, 6787.966060169668, 6862.759803406857, 6874.862502749917, 6956.026156338116, 7063.964680176567, 6643.302453242437, 6652.0, 6698.96650516754, 6701.9947007949195, 6708.161479815023, 6870.759523416757, 6887.380135787803, 6887.483438742114, 6887.691338645642, 6907.13660792401, 6482.254540727877, 6589.53873228879, 6671.0, 6707.228668703162, 6770.39066484009, 6706.497012723968, 6750.763723269662, 6783.864322713518, 6784.253732089466, 6815.795526134224, 5327.706976116351, 5381.838544843661, 5410.431904650034, 5412.972935135378, 5448.0, 4717.551832575899, 4746.335513028179, 4799.520047995173, 4799.832005879777, 4815.22956326986, 6254.749810007549, 6265.780697675559, 6275.96862015687, 6289.0880822280515, 6289.7484100636375, 6695.899561506581, 6700.396964273239, 6706.027625994204, 6710.429613482811, 6780.593164410151, 6291.339409362043, 6312.968435157794, 6322.209723784527, 6350.714217860207, 6362.840928976812, 5995.2206213192, 6004.249468816398, 6011.609245399035, 6035.818925432244, 6056.848578785565, 6567.737290508518, 6875.174979002351, 6950.096487456598, 7022.613756243456, 7027.086478757831, 6597.2742998271115, 6665.0668906352175, 6681.565698229794, 6693.631850248283, 6721.630310332783, 6821.420179284816, 6837.0, 6849.349311815369, 7011.4741394394605, 7029.578225306498, 6555.770548030796, 6560.639164845979, 6634.635095069818, 6690.83272918181, 6734.528582999143, 6431.356864313531, 6488.772892948724, 6505.089287499659, 6532.118164047793, 6578.210614726264, 6424.550281480439, 6432.13166222554, 6478.060681201294, 6519.152510173647, 6519.934800651933, 5250.816221432232, 6396.0, 6423.164988551458, 6432.807015789534, 6551.934480655323, 6127.29536103336, 6176.042713379434, 6313.589616674902, 6325.146105275729, 6476.222853257639, 6243.406876346739, 6314.589551679126, 6396.584222025554, 6428.710708018057, 6428.8071357859335, 5323.733813309242, 6274.686265686789, 6278.434940855345, 6334.841628959313, 6425.261094973951], "value": 6133.1863432654445, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [657.6317262333093, 799.9880151608215, 1092.486531330275, 1120.019982515299, 1121.4841173060393, 35166.82416587917, 36329.818350908274, 36699.08252293691, 37045.07387315318, 37647.43528847066, 39086.413703794395, 39162.60837391625, 39437.60562394375, 39732.00669983252, 40328.79835600825, 5919.141724449953, 8483.15168483153, 8489.533075680834, 8495.023072346667, 8515.616797244124, 6096.0, 8351.665933362658, 8457.281131103864, 8459.323254139654, 8464.492130472192, 6887.690053947559, 8533.274671652916, 8553.529555874424, 8590.312774977987, 8778.517181555011, 8432.198941100585, 8506.0, 8525.976882774044, 8530.104339044436, 8531.701390451366, 7181.102362204724, 8078.555679437629, 8228.053773815967, 8404.915950840474, 8426.157384261585, 36128.63871361279, 36336.27327453436, 36358.90923272306, 36965.63034369676, 37376.065598359986, 3430.6397828227928, 3433.3133373325436, 3435.0, 3473.8436770345397, 3511.736619753529, 28650.71071801774, 28698.569521457193, 29044.27389315263, 29163.125106246633, 29579.408411831857, 3021.0936718984217, 3041.3305200446816, 3062.3875224954877, 3080.291532947412, 3155.7790954633183, 184029.0, 279093.0, 279696.99999999796, 283377.0, 285567.0, 37661.24677506435, 38040.66857660024, 47478.0, 48734.75632621814, 48939.0, 41196.79401602973, 41340.79329603333, 41697.58302416937, 42420.363694544605, 42495.0, 13415.195088294538, 13796.172229666252, 13873.375698093409, 13876.58370248894, 14240.857591424161, 11126.998570128728, 11175.72060698489, 11190.552377904793, 11366.829497557543, 11413.144014198968, 9522.619095236252, 12333.691657708629, 12357.382130893599, 12628.116031877864, 12766.17019893704, 4361.869143925687, 5356.098585002268, 5358.758855851496, 5461.808836690697, 5463.48096930791, 818.7257268815014, 944.4474982135528, 970.8883478400074, 993.2897977945803, 1016.0043157705343, 16395.754063689055, 22157.210254362228, 23874.641880371808, 24035.27894163178, 29420.264493387407, 1008.3647302199714, 1054.7310435838813, 1081.6376513867942, 1099.9945000274947, 1115.7656892052598, 9702.369345992487, 11257.0, 12312.322822244863, 12389.814152787716, 12479.188852724545, 387185.0, 390231.0, 390529.0473547614, 394795.0, 397068.0, 51649.48350516447, 51776.96446071057, 52177.7391113042, 52495.0, 53303.0, 1457.6355911022245, 1472.904261223017, 1519.5365413548973, 1537.4388348252808, 1572.536101849957, 15717.685646287238, 23526.353155279652, 23601.52796944086, 23880.80595970229, 23886.241810601685, 38610.80694596509, 38624.03439914025, 39447.60552394554, 39562.80218598888, 39940.0, 338150.0, 339645.0, 340259.0, 342841.0, 346945.0, 26593.202203933914, 27013.594796077683, 29214.707852921198, 32940.34119317648, 33478.99563013113, 39983.400663973116, 40680.79659601683, 41155.0, 41830.37254441125, 42066.789666051474, 26021.0, 27778.0, 28224.43551129007, 28664.713352866205, 28840.855795721727, 27059.594106088032, 28153.296167595974, 28780.280492987004, 29272.121836344944, 29759.25601859971, 32928.67071329256, 33834.8308258467, 34402.65597343994, 34722.82638586892, 34879.47680784841, 27793.202965616598, 27862.16413507517, 28810.703518341306, 29132.854335729033, 29401.558976615797, 18933.308004938906, 20767.027132422394, 22211.77788222097, 22517.986690598635, 23301.76698232996], "value": 16830.20021761681, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [354.7038223083725, 410.1284769864037, 413.5657559562459, 435.94550681164856, 437.9474463064433, 19772.681954137588, 22213.555728885414, 22531.54936901265, 22787.0, 23188.536229275447, 22275.554488910213, 22436.326910192678, 22603.434914127156, 23805.5238895222, 23892.0, 5958.8808223835385, 6168.383161683841, 6253.1558239637525, 6288.371162883721, 6375.744970201186, 4817.759112044401, 5447.509724124828, 6289.716962736665, 6435.646039467827, 6516.0, 6226.221722284715, 6272.6236425814595, 6302.653354065525, 6345.904811427832, 6483.254425741029, 5887.0, 6106.755729770804, 6113.755449782004, 6178.62928224308, 6284.654344011101, 5352.304200453916, 5499.230107784913, 5540.50135487804, 5631.9718401408145, 5642.351129620105, 26463.338416539547, 27745.722542774514, 27960.881564737385, 28177.57733633997, 28594.0, 2121.321177223289, 2139.358192542231, 2141.850070495066, 2143.453419378064, 2147.5812216617765, 16901.91549042259, 17229.91385043067, 17420.128993550272, 17513.56216094608, 17848.821511784845, 2043.8467114966288, 2057.4753437873396, 2110.5778844231063, 2131.7868213178713, 2167.5448155887284, 131750.38873639377, 133211.0, 160759.0, 164509.0, 166346.0, 12159.93920030412, 12386.0, 12410.75178496443, 12545.560905368267, 12607.180533265308, 16477.752833707505, 16514.2568584414, 16613.50159495217, 16945.49163525096, 16965.491035268962, 6882.931170688329, 8962.283017358595, 9108.225800807006, 9146.588403521724, 9280.907190928005, 8609.61256743448, 8613.181747733955, 8621.396502244783, 8784.077671844365, 9055.773605659779, 7637.923620763832, 7821.726239581587, 7845.803854903673, 7959.442839001214, 8000.959995199987, 2058.4039939773274, 2066.5763518478675, 2072.958540829205, 2094.5182608000246, 2300.0914638717613, 705.7670968580356, 888.2050564744646, 891.2825175733597, 901.8466860633708, 906.4380084347615, 17694.380696675813, 19591.412257632295, 20136.39590812278, 20158.496037599176, 20786.06462709182, 891.9509426981449, 928.90853247434, 933.7292185266333, 935.4621092871625, 935.9485228312508, 10240.897591023995, 10318.793624127475, 10498.685039448828, 10582.365058096539, 10670.466476676134, 675720.0, 684597.0, 687843.0, 702968.0, 703269.0, 118229.54163667058, 145939.0, 153585.49803027586, 156802.2159889193, 157119.0, 1086.983695244572, 1139.7948369293606, 1140.452582760263, 1147.7647082348099, 1172.0, 12794.744105118032, 17233.913830430767, 17590.91204543969, 17847.553811154827, 18698.158582864075, 46744.29883551681, 62437.43906402194, 63487.682561586895, 63567.6821615889, 64446.67776661087, 85384.86537836604, 85638.0, 89408.37691100115, 90406.54796725974, 91253.0, 8463.322934165375, 8535.487870727777, 9059.43106175936, 9149.130832570896, 9378.48418336998, 13922.930385348009, 14084.225367604471, 14150.78773818414, 14449.422023118957, 14969.550913472613, 11381.772364552497, 11642.592509262475, 11990.0, 12173.330466824409, 12378.195417297848, 10792.676219713421, 10806.243562950518, 10868.510917008596, 10923.289986150874, 11245.437728113726, 10945.616903408343, 11481.196316257781, 11765.470553824927, 12342.44458999365, 12423.813642795547, 7402.7409040683315, 8687.174718401742, 8851.070637582961, 9098.31762617793, 9150.176484116462, 11919.225250358699, 12054.578089766816, 12095.5204183144, 12207.145499815282, 12488.313142777235], "value": 10451.334552136621, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1139.9772004559914, 1245.4582256718322, 1386.3969173409564, 1397.0779285671454, 1475.9225765191413, 9201.723948281546, 10097.0, 11356.03473704735, 11785.0, 11981.940090299537, 11374.0, 11488.770224595502, 11552.826707599394, 11669.24149930254, 11818.40907954603, 2480.0575781203206, 2481.838680485772, 2482.379405148713, 2493.326801763523, 2519.395345117168, 2142.185969331652, 2169.13234706118, 2411.2284069097896, 2515.4843257132334, 2566.5508536006123, 2479.1818699829105, 2480.7519248075228, 2481.1812102006384, 2487.776100150986, 2545.9872700636565, 2384.7257565379855, 2454.901803927841, 2491.700995880488, 2494.326531836412, 2508.9121880734087, 2375.70303712036, 2398.6761787158684, 2407.374082738483, 2425.854448733082, 2482.379405148713, 11587.073034157247, 11669.70825729355, 11684.240524365974, 11793.11551633631, 11959.222650527774, 5898.233229680157, 6175.691215439255, 6326.588771729823, 6668.899966500507, 6791.4906382021545, 3190.952135717966, 4111.403846442279, 4140.441040459529, 4162.708610397273, 4183.895402614929, 1132.167856625388, 1144.1361771862191, 1187.9227850189795, 1188.8573371195425, 1225.2587184753274, 16719.83280167195, 16767.496975090766, 16803.15984200785, 16951.576210594834, 17078.316867325415, 5477.4248703886315, 5606.943930560724, 5853.765849366063, 5860.062390017577, 6028.065649824284, 13639.0, 13650.453981840814, 13681.31593420325, 13752.243626600433, 14072.85927140736, 6651.501137414713, 6661.16735408074, 6675.232348279913, 6738.696758645872, 6794.082798822095, 5498.862528436821, 5918.763249470059, 6011.1884895539415, 6084.087386892002, 6144.477719393813, 3928.941065884014, 4119.9794001030405, 4133.648639865645, 4576.0, 4602.677812553156, 1187.9940600297116, 1377.7795552711518, 1381.3507651403825, 1388.632012516684, 1407.5495841330676, 745.8657441660445, 764.9273319034683, 768.3699366519401, 777.6189667063098, 807.5290205741843, 6082.452579267799, 6292.528060395489, 6594.0, 6622.602643841288, 6733.427658648971, 853.4239388412797, 864.2524216552637, 864.943778654398, 904.4980036080038, 914.1270087066836, 3565.16218688608, 3680.6871415929945, 3721.962780372216, 3755.211405604799, 3799.6580307772433, 14149.726524612834, 14475.78286325706, 14764.261786910827, 15171.24143792806, 15409.229538523028, 14798.63003424923, 14874.0, 14907.850921490863, 14958.850411495963, 15025.774613380809, 10598.788024239471, 10610.628627997983, 10736.30214036085, 10759.838602421127, 11168.766246750682, 5076.314697515936, 5440.918386224131, 6279.183706118175, 6402.423781859655, 6626.403623673892, 11010.339379637247, 13459.394327255493, 13544.32278386057, 13734.725305494034, 13895.861041389457, 13329.600111996655, 13640.0, 13842.377093031038, 13894.444222230997, 13996.370163342877, 9525.666601668909, 9625.51872406391, 9629.422234665943, 9635.614575416905, 9853.556589953612, 8965.686200982935, 9623.422594644344, 10514.737131571772, 10857.185711071543, 10885.836712449161, 7532.849343012999, 8915.866262006204, 9129.817403652023, 9191.0, 9205.723828285161, 8671.739847804576, 8862.734117976472, 8885.91114088851, 8975.057618949917, 9133.360664753403, 7915.683372665029, 8092.514449133071, 8099.109097999333, 8144.796380090545, 8207.507549546808, 4751.0, 5309.230161626465, 5899.793507227227, 5917.0, 6001.789937352171, 5875.0, 5969.492593129633, 5992.0712289595185, 6055.848603784941, 6152.723127459365], "value": 5320.684533122068, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [324.2251020062052, 385.7299890076946, 389.7213492352967, 392.63484958988164, 398.5934346966094, 5739.0, 5801.912971305428, 5844.0, 5884.205632239647, 5940.28716554013, 5428.61999660024, 5568.192612071247, 5637.408072152426, 5701.629394089381, 5808.5353171746265, 4237.237297286487, 4264.253755592775, 4299.14017196562, 4329.7618630975285, 4393.1872603568445, 3597.4244120940652, 4133.5659755725665, 4359.149965756678, 4375.474943006843, 4384.947612572992, 3696.963030369702, 4012.217617564576, 4234.618884300412, 4268.231718290706, 4286.721363111388, 3399.269157131216, 4023.545637161329, 4169.852525949108, 4205.201011807747, 4348.412964249833, 2934.7212014858555, 3438.673326034023, 3714.758540694846, 3741.401375779862, 3743.644353786386, 5293.3647962244395, 5568.331800183963, 5635.464630860061, 5691.345495268056, 5767.625104368202, 4654.5810877020895, 4936.308916751657, 5059.974700126512, 5084.288199652052, 5159.916417552318, 4334.45819272591, 6013.767177728599, 6053.848653783647, 6087.939120608826, 6207.441330280297, 3460.9480857787153, 4140.8136633851545, 4153.563875793058, 4163.6669066474915, 4210.179015092058, 6235.906461403082, 6356.396142366514, 6469.644169570672, 6519.674016299166, 6600.372964568358, 4156.875293741132, 4186.853460128942, 4196.328587425997, 4236.915261694749, 4306.978465107654, 4261.722988005769, 4269.978650106729, 4345.500267469281, 4394.780260986938, 4398.494173170063, 4822.662413631012, 4978.551930326288, 5017.172166592544, 5053.267276244924, 5160.845174644766, 6124.142620033199, 6128.571000030044, 6135.0, 6183.628982260989, 6273.686315684197, 1194.6953526850598, 1443.5669299210194, 1462.597785608966, 1479.9260036998107, 1488.843671414486, 786.6263524825662, 954.737447202025, 962.1581116523042, 974.1135566634329, 992.4789485520015, 5350.946490535045, 5648.293963254593, 5726.883257764779, 5745.569082318842, 5836.678982655994, 4878.609711223094, 5839.562032847553, 6337.33831790649, 6338.271098823602, 6434.453071488976, 5618.678986140591, 5685.744141513559, 5691.178822776672, 5792.507636850831, 5908.9614083142105, 3261.5759951206664, 3284.786488878215, 3316.5688460499982, 3350.9329813403597, 3360.3447327771337, 6190.690465476709, 6451.387118223761, 6462.741490340428, 6502.934970650327, 6550.475961923034, 5933.110033495011, 6069.2702579764555, 6141.355157708376, 6460.709268082947, 6582.37467440602, 5942.524598032147, 6002.789902353571, 6326.2092238470195, 6334.0, 6433.163688720437, 5492.47821456961, 5818.44724751148, 6296.748130074746, 6417.91417789211, 6440.516961227833, 5832.491163258477, 6222.564420490522, 6224.253089629274, 6324.873502530016, 6370.649614271259, 5454.790809722069, 6455.709493072917, 6480.157579514822, 6523.0, 6660.333966603301, 4184.330507118846, 4200.978995105005, 4222.662187025092, 4247.135107052567, 4272.850450234351, 4852.514748525119, 6237.96881015592, 6324.557280990287, 6358.936410635834, 6554.573952693059, 5008.348914641073, 5159.509846564571, 5197.81807636746, 6163.537734669828, 6233.345498722569, 4821.300071959714, 6116.0, 6179.938200618116, 6196.969015154896, 6321.367863213826, 5935.495482883831, 5966.164736936919, 5995.670238136944, 5997.640141591519, 6144.969275153774, 5163.8192663258105, 5387.730613469389, 6157.751151705309, 6236.844078898064, 6259.436650701458, 4905.9942711744725, 5934.169216309634, 5949.315828679757, 5962.940370596239, 5985.324109249417], "value": 4471.273409511213, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [776.9223077692232, 788.2078511096355, 817.7383237364043, 824.7608193623846, 841.730646193218, 15514.767278490832, 15791.842081579181, 15843.049417034958, 15879.364825407, 16308.0, 13433.328333583306, 14667.706645867076, 14817.18505482198, 14835.629109272273, 15074.75892683073, 950.0262231212993, 972.367960825462, 977.7848873247874, 993.6273897288517, 1021.4280003198232, 968.1480297338333, 972.2659392158945, 975.0931633580767, 975.3708857786723, 978.0464047553614, 1027.8201314769922, 1028.0695970146992, 1028.1569113327075, 1032.566322144701, 1060.76132870104, 832.488019867783, 1034.6430481483876, 1038.3717850700368, 1038.8857225705167, 1041.1306559023224, 764.9808754781119, 767.3170877918657, 767.6890859202032, 774.4307933668749, 783.0525064671754, 15548.689026219412, 16233.675326493521, 16436.424725134562, 16587.170641467877, 16783.91608041952, 14114.0, 14180.50368237117, 14197.36730276021, 14294.857051429457, 14625.70748585033, 13233.86766132336, 13328.800067998987, 13334.799978000337, 13404.46382144713, 13547.187168769906, 3274.983625081883, 3339.398908196548, 3344.6320904700465, 3354.480055591363, 3360.6135294441206, 106219.46890265576, 108561.45719271433, 109955.45022274918, 111350.44324778325, 113621.43189284, 7830.807373935308, 10056.849147262797, 11414.600488982845, 11566.768664626661, 11706.239094458833, 23117.306480805604, 23508.882455587613, 23542.7645723544, 23985.28044158678, 24568.262952111465, 7041.964790176016, 7145.1425828900865, 8117.310028647631, 8129.105798362176, 8361.247487726012, 3957.604239576019, 4045.4943132108488, 4047.8583249586122, 4088.693347998912, 4219.38818871262, 3402.217489977319, 4105.835766569304, 4144.70987030905, 4192.266353388145, 4288.078063216423, 905.2079430498314, 916.903725108854, 926.2034650200821, 928.6146249306462, 948.1608776233084, 599.5443462968176, 601.290477236856, 608.2336256316984, 615.0773839241139, 652.8628987912497, 7821.569813660303, 9679.564419601134, 9692.612295508241, 10170.440625765506, 10177.796444071226, 497.72873783787924, 571.8191933656985, 600.5525883216944, 602.4246844263718, 620.5190976992776, 4167.0, 4618.56123668251, 4627.329037289642, 4648.442186937589, 4673.135469938073, 105001.47499262454, 107668.0, 109902.0, 111235.0, 111936.0, 93532.0, 97474.51262743642, 97929.2070792915, 99725.50137249267, 99933.0, 84664.0, 84795.5760221195, 84999.15000850036, 88313.0, 88911.0, 9277.814443710953, 9354.625814967325, 9502.239820814457, 9627.759306017406, 10514.947425262824, 37128.81435592805, 37479.62520374871, 37751.0, 38495.615043849204, 39687.40468893027, 71532.0, 72774.0, 73492.0, 73549.6322518384, 74670.25329746633, 13082.28047457399, 13386.799198011842, 13545.255010974492, 13575.660608484866, 13771.173729576258, 11166.385848778395, 14124.78812817829, 14161.104672075095, 14202.502912398017, 14678.41286348534, 13368.0, 13372.732545349234, 13449.193048417126, 13648.93175534116, 13713.520026799413, 11946.701332466759, 13108.868911311147, 13323.467061317438, 13899.791503127664, 14029.368678409292, 13004.284764337674, 13113.868861311264, 13573.38919748594, 13671.38478768438, 13747.450101995808, 9431.764205894908, 9527.302646252638, 9685.41887486753, 9924.206063514757, 10223.233257505866, 8362.581870906552, 9117.270618350649, 9538.380005299634, 10636.255462117577, 10879.782404352027], "value": 7687.728768517254, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1611.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 122.0, 122.0, 123.0, 124.0, 160.0, 180.0, 124.0, 123.0, 123.0], "value": 131.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 154.0, 150.0, 196.0, 118.0, 121.0, 114.0, 119.0, 144.0, 119.0], "value": 141.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [222.0, 216.0, 217.0, 210.0, 218.0, 214.0, 219.0, 208.0, 212.0, 210.0], "value": 214.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 64.0, 67.0, 100.0, 68.0, 68.0, 61.0, 61.0, 82.0, 63.0], "value": 72.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 73.0, 96.0, 74.0, 74.0, 75.0, 91.0, 73.0, 74.0, 92.0], "value": 81.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 94.0, 93.0, 93.0, 93.0, 92.0, 93.0, 92.0, 95.0], "value": 92.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 101.0, 102.0, 103.0, 100.0, 101.0, 102.0, 102.0, 101.0, 101.0], "value": 101.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 103.0, 124.0, 155.0, 100.0, 100.0, 100.0, 101.0, 101.0, 100.0], "value": 108.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 51.0, 51.0, 52.0, 53.0, 52.0, 53.0, 51.0, 54.0, 81.0], "value": 54.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 65.0, 67.0, 70.0, 67.0, 64.0, 65.0, 64.0, 66.0, 67.0], "value": 66.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 108.0, 93.0, 92.0, 93.0, 92.0, 91.0, 93.0, 93.0, 92.0], "value": 95.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 166.0, 142.0, 165.0, 167.0, 172.0, 161.0, 155.0, 196.0, 184.0], "value": 167.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [257.0, 203.0, 202.0, 209.0, 206.0, 204.0, 207.0, 202.0, 207.0, 209.0], "value": 210.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 76.0, 73.0, 74.0, 74.0, 73.0, 73.0, 73.0, 77.0, 73.0], "value": 73.8, "unit": "score"}]}]} 19:43:37 INFO - Return code: 0 19:43:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:43:37 ERROR - # TBPL SUCCESS # 19:43:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:43:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:43:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:43:37 INFO - 2017-04-06 19:43:37.824 screenresolution[1650:10050] starting screenresolution argv=screenresolution get 19:43:37 INFO - 2017-04-06 19:43:37.827 screenresolution[1650:10050] Display 0: 1600x1200x32@60 19:43:37 INFO - 2017-04-06 19:43:37.833 screenresolution[1651:10053] starting screenresolution argv=screenresolution list 19:43:37 INFO - Available Modes on Display 0 19:43:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:43:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:43:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:43:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:43:38 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:43:38 INFO - Intel Iris: 19:43:38 INFO - Chipset Model: Intel Iris 19:43:38 INFO - Type: GPU 19:43:38 INFO - Bus: Built-In 19:43:38 INFO - VRAM (Dynamic, Max): 1536 MB 19:43:38 INFO - Vendor: Intel (0x8086) 19:43:38 INFO - Device ID: 0x0a2e 19:43:38 INFO - Revision ID: 0x0009 19:43:38 INFO - Displays: 19:43:38 INFO - Display: 19:43:38 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:43:38 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:43:38 INFO - Display Serial Number: 1600x1200 60 19:43:38 INFO - Main Display: Yes 19:43:38 INFO - Mirror: Off 19:43:38 INFO - Online: Yes 19:43:38 INFO - Rotation: Supported 19:43:38 INFO - Return code: 0 19:43:38 INFO - Running post-action listener: _resource_record_post_action 19:43:38 INFO - [mozharness: 2017-04-07 02:43:38.071779Z] Finished run-tests step (success) 19:43:38 INFO - Running post-run listener: _resource_record_post_run 19:43:38 INFO - Total resource usage - Wall time: 1206s; CPU: 54.0%; Read bytes: 33844224; Write bytes: 521584640; Read time: 313; Write time: 1711 19:43:38 INFO - TinderboxPrint: CPU usage
53.6% 19:43:38 INFO - TinderboxPrint: I/O read bytes / time
33,844,224 / 313 19:43:38 INFO - TinderboxPrint: I/O write bytes / time
521,584,640 / 1,711 19:43:38 INFO - TinderboxPrint: CPU idle
2,238.7 (46.4%) 19:43:38 INFO - TinderboxPrint: CPU system
988.8 (20.5%) 19:43:38 INFO - TinderboxPrint: CPU user
1,598.2 (33.1%) 19:43:38 INFO - TinderboxPrint: Swap in / out
237,154,304 / 0 19:43:38 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 205038080; Write bytes: 202616832; Read time: 15910; Write time: 370 19:43:38 INFO - run-tests - Wall time: 1187s; CPU: 54.0%; Read bytes: 31767552; Write bytes: 311828480; Read time: 291; Write time: 1312 19:43:38 INFO - Running post-run listener: _upload_blobber_files 19:43:38 INFO - Blob upload gear active. 19:43:38 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:43:38 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 19:43:38 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', 'Pine', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:43:38 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 Pine -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:43:38 INFO - (blobuploader) - INFO - Open directory for files ... 19:43:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:43:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:43:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:43:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:43:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:43:40 INFO - (blobuploader) - INFO - Done attempting. 19:43:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:43:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:43:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:43:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:43:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:43:41 INFO - (blobuploader) - INFO - Done attempting. 19:43:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 19:43:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:43:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:43:42 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 19:43:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:43:42 INFO - (blobuploader) - INFO - Done attempting. 19:43:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:43:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:43:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:43:44 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:43:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:43:44 INFO - (blobuploader) - INFO - Done attempting. 19:43:44 INFO - (blobuploader) - INFO - Iteration through files over. 19:43:44 INFO - Return code: 0 19:43:44 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:43:44 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:43:44 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/98fb8487c00dbd04773a35c0b361b0610bc57829e312477daeeba1edf99dce606fa6968d4177b29355277783659bea58a781c832b5c1051f274042ca00908b67", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/6d532d22cd092216dbb898790a25c0d751a9559a40be2ccf192e16846482d90596c021a997c0096c10dbcf4e3647673229a14f91ad70b4ad2e785bd11f9114cb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/c36492e728b6b030cb94c394997a2dae4bad9dfd382e4e0c1ffa2a066504ab32d1beea9b1628e5ceb7a3c7b22378f335cb5ba5ad7122e576b67e8f35d6451558", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/907c8fd5f290e75028ece3bb536e91fef7cc022ebb79a6bf0a239d7e88793b7284ff4a049309b1b41ec3bf7bc023e8ec20ed8599ab3b3da9218b8ac07860b417"} 19:43:44 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:43:44 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:43:44 INFO - Contents: 19:43:44 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/98fb8487c00dbd04773a35c0b361b0610bc57829e312477daeeba1edf99dce606fa6968d4177b29355277783659bea58a781c832b5c1051f274042ca00908b67", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/6d532d22cd092216dbb898790a25c0d751a9559a40be2ccf192e16846482d90596c021a997c0096c10dbcf4e3647673229a14f91ad70b4ad2e785bd11f9114cb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/c36492e728b6b030cb94c394997a2dae4bad9dfd382e4e0c1ffa2a066504ab32d1beea9b1628e5ceb7a3c7b22378f335cb5ba5ad7122e576b67e8f35d6451558", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/907c8fd5f290e75028ece3bb536e91fef7cc022ebb79a6bf0a239d7e88793b7284ff4a049309b1b41ec3bf7bc023e8ec20ed8599ab3b3da9218b8ac07860b417"} 19:43:44 INFO - Running post-run listener: copy_logs_to_upload_dir 19:43:44 INFO - Copying logs to upload dir... 19:43:44 INFO - mkdir: /builds/slave/test/build/upload/logs 19:43:44 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1289.676431 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 29 secs) (at 2017-04-06 19:43:44.307529) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-06 19:43:44.310803) ========= 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.zWaNJ50CG2/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.FU7sQJNkPj/Listeners TMPDIR=/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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/Pine/sha512/98fb8487c00dbd04773a35c0b361b0610bc57829e312477daeeba1edf99dce606fa6968d4177b29355277783659bea58a781c832b5c1051f274042ca00908b67", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/6d532d22cd092216dbb898790a25c0d751a9559a40be2ccf192e16846482d90596c021a997c0096c10dbcf4e3647673229a14f91ad70b4ad2e785bd11f9114cb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/c36492e728b6b030cb94c394997a2dae4bad9dfd382e4e0c1ffa2a066504ab32d1beea9b1628e5ceb7a3c7b22378f335cb5ba5ad7122e576b67e8f35d6451558", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/907c8fd5f290e75028ece3bb536e91fef7cc022ebb79a6bf0a239d7e88793b7284ff4a049309b1b41ec3bf7bc023e8ec20ed8599ab3b3da9218b8ac07860b417"} build_url:https://queue.taskcluster.net/v1/task/MkZ5ojULSnGSwujJH3O1Bg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011234 build_url: 'https://queue.taskcluster.net/v1/task/MkZ5ojULSnGSwujJH3O1Bg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/98fb8487c00dbd04773a35c0b361b0610bc57829e312477daeeba1edf99dce606fa6968d4177b29355277783659bea58a781c832b5c1051f274042ca00908b67", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/6d532d22cd092216dbb898790a25c0d751a9559a40be2ccf192e16846482d90596c021a997c0096c10dbcf4e3647673229a14f91ad70b4ad2e785bd11f9114cb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/c36492e728b6b030cb94c394997a2dae4bad9dfd382e4e0c1ffa2a066504ab32d1beea9b1628e5ceb7a3c7b22378f335cb5ba5ad7122e576b67e8f35d6451558", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/907c8fd5f290e75028ece3bb536e91fef7cc022ebb79a6bf0a239d7e88793b7284ff4a049309b1b41ec3bf7bc023e8ec20ed8599ab3b3da9218b8ac07860b417"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-06 19:43:44.344870) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 19:43:44.345208) ========= 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.zWaNJ50CG2/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.FU7sQJNkPj/Listeners TMPDIR=/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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.005301 ========= master_lag: 0.07 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 19:43:44.416570) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-06 19:43:44.416879) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-06 19:43:44.447194) ========= ========= Total master_lag: 3.41 =========