builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0165 starttime: 1472753599.73 results: success (0) buildid: 20160901091421 builduid: 3aabef45f06745d6b5934445159520f9 revision: 4dd14de3fa420bf5d750ca7c969ace2a9a9931ac ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-01 11:13:19.726731) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-01 11:13:19.727212) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-01 11:13:19.727509) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-01 11:13:19.751308) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 11:13:19.751719) ========= 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.c87tQgaOMl/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.uffSaAg7Fw/Listeners TMPDIR=/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/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 --2016-09-01 11:13:19-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 123M=0s 2016-09-01 11:13:19 (123 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.130434 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 11:13:19.904869) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 11:13:19.905235) ========= 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.c87tQgaOMl/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.uffSaAg7Fw/Listeners TMPDIR=/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/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.021376 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 11:13:19.954019) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-09-01 11:13:19.954375) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 4dd14de3fa420bf5d750ca7c969ace2a9a9931ac --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 4dd14de3fa420bf5d750ca7c969ace2a9a9931ac --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.c87tQgaOMl/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.uffSaAg7Fw/Listeners TMPDIR=/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/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 2016-09-01 11:13:20,029 truncating revision to first 12 chars 2016-09-01 11:13:20,029 Setting DEBUG logging. 2016-09-01 11:13:20,030 attempt 1/10 2016-09-01 11:13:20,030 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/4dd14de3fa42?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-01 11:13:22,187 unpacking tar archive at: fx-team-4dd14de3fa42/testing/mozharness/ program finished with exit code 0 elapsedTime=2.409864 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-09-01 11:13:22.387226) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-01 11:13:22.387599) ========= script_repo_revision: 4dd14de3fa420bf5d750ca7c969ace2a9a9931ac ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-01 11:13:22.388030) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-01 11:13:22.388320) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-01 11:13:22.407673) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-09-01 11:13:22.408125) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.c87tQgaOMl/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.uffSaAg7Fw/Listeners TMPDIR=/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/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 11:13:22 INFO - MultiFileLogger online at 20160901 11:13:22 in /builds/slave/test 11:13:22 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 11:13:22 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:13:22 INFO - {'append_to_log': False, 11:13:22 INFO - 'base_work_dir': '/builds/slave/test', 11:13:22 INFO - 'blob_upload_branch': 'Fx-Team', 11:13:22 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:13:22 INFO - 'branch': 'Fx-Team', 11:13:22 INFO - 'buildbot_json_path': 'buildprops.json', 11:13:22 INFO - 'config_files': ('talos/mac_config.py',), 11:13:22 INFO - 'default_actions': ('clobber', 11:13:22 INFO - 'read-buildbot-config', 11:13:22 INFO - 'download-and-extract', 11:13:22 INFO - 'populate-webroot', 11:13:22 INFO - 'create-virtualenv', 11:13:22 INFO - 'install', 11:13:22 INFO - 'run-tests'), 11:13:22 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:13:22 INFO - 'download_minidump_stackwalk': True, 11:13:22 INFO - 'download_symbols': 'ondemand', 11:13:22 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:13:22 INFO - 'tooltool.py': '/tools/tooltool.py', 11:13:22 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:13:22 INFO - '/tools/misc-python/virtualenv.py')}, 11:13:22 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:13:22 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:13:22 INFO - 'installer_path': 'installer.exe', 11:13:22 INFO - 'log_level': 'info', 11:13:22 INFO - 'log_name': 'talos', 11:13:22 INFO - 'log_to_console': True, 11:13:22 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:13:22 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:13:22 INFO - 'opt_config_files': (), 11:13:22 INFO - 'pip_index': False, 11:13:22 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:13:22 INFO - 'cmd': ('bash', 11:13:22 INFO - '-c', 11:13:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:13:22 INFO - 'enabled': True, 11:13:22 INFO - 'halt_on_failure': False, 11:13:22 INFO - 'name': 'check_screen_resolution'},), 11:13:22 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:13:22 INFO - 'cmd': ('bash', 11:13:22 INFO - '-c', 11:13:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:13:22 INFO - 'enabled': True, 11:13:22 INFO - 'halt_on_failure': False, 11:13:22 INFO - 'name': 'check_screen_resolution'},), 11:13:22 INFO - 'run_cmd_checks_enabled': True, 11:13:22 INFO - 'sps_profile': False, 11:13:22 INFO - 'sps_profile_interval': 0, 11:13:22 INFO - 'suite': 'dromaeojs', 11:13:22 INFO - 'system_bits': '32', 11:13:22 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:13:22 INFO - 'title': 't-yosemite-r7-0165', 11:13:22 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:13:22 INFO - 'use_talos_json': True, 11:13:22 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:13:22 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:13:22 INFO - 'work_dir': 'build'} 11:13:22 INFO - [mozharness: 2016-09-01 18:13:22.612608Z] Running clobber step. 11:13:22 INFO - Running pre-action listener: _resource_record_pre_action 11:13:22 INFO - Running main action method: clobber 11:13:22 INFO - rmtree: /builds/slave/test/build 11:13:22 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:13:23 INFO - Running post-action listener: _resource_record_post_action 11:13:23 INFO - [mozharness: 2016-09-01 18:13:23.679712Z] Finished clobber step (success) 11:13:23 INFO - [mozharness: 2016-09-01 18:13:23.679842Z] Running read-buildbot-config step. 11:13:23 INFO - Running pre-action listener: _resource_record_pre_action 11:13:23 INFO - Running main action method: read_buildbot_config 11:13:23 INFO - Using buildbot properties: 11:13:23 INFO - { 11:13:23 INFO - "project": "", 11:13:23 INFO - "product": "firefox", 11:13:23 INFO - "script_repo_revision": "production", 11:13:23 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 11:13:23 INFO - "repository": "", 11:13:23 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 11:13:23 INFO - "buildid": "20160901091421", 11:13:23 INFO - "pgo_build": "False", 11:13:23 INFO - "basedir": "/builds/slave/test", 11:13:23 INFO - "buildnumber": 138, 11:13:23 INFO - "slavename": "t-yosemite-r7-0165", 11:13:23 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 11:13:23 INFO - "platform": "yosemite_r7", 11:13:23 INFO - "scheduler": "tests-fx-team-macosx64-talos", 11:13:23 INFO - "branch": "fx-team", 11:13:23 INFO - "repo_path": "integration/fx-team", 11:13:23 INFO - "revision": "4dd14de3fa420bf5d750ca7c969ace2a9a9931ac", 11:13:23 INFO - "stage_platform": "macosx64", 11:13:23 INFO - "builduid": "3aabef45f06745d6b5934445159520f9", 11:13:23 INFO - "slavebuilddir": "test" 11:13:23 INFO - } 11:13:23 INFO - Found installer url https://queue.taskcluster.net/v1/task/B6vVkrNFS9iI0m8-A_XEAg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 11:13:23 INFO - Running post-action listener: _resource_record_post_action 11:13:23 INFO - [mozharness: 2016-09-01 18:13:23.683075Z] Finished read-buildbot-config step (success) 11:13:23 INFO - [mozharness: 2016-09-01 18:13:23.683177Z] Running download-and-extract step. 11:13:23 INFO - Running pre-action listener: _resource_record_pre_action 11:13:23 INFO - Running main action method: download_and_extract 11:13:23 INFO - mkdir: /builds/slave/test/build/tests 11:13:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:13:23 INFO - trying https://queue.taskcluster.net/v1/task/B6vVkrNFS9iI0m8-A_XEAg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 11:13:23 INFO - Downloading https://queue.taskcluster.net/v1/task/B6vVkrNFS9iI0m8-A_XEAg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 11:13:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/B6vVkrNFS9iI0m8-A_XEAg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 11:13:24 INFO - Downloaded 1199 bytes. 11:13:24 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 11:13:24 INFO - Using the following test package requirements: 11:13:24 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 11:13:24 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:13:24 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 11:13:24 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:13:24 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 11:13:24 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:13:24 INFO - u'jsshell-mac.zip'], 11:13:24 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:13:24 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 11:13:24 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 11:13:24 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:13:24 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 11:13:24 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:13:24 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 11:13:24 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:13:24 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 11:13:24 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:13:24 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 11:13:24 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 11:13:24 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')]} 11:13:24 INFO - trying https://queue.taskcluster.net/v1/task/B6vVkrNFS9iI0m8-A_XEAg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 11:13:24 INFO - Downloading https://queue.taskcluster.net/v1/task/B6vVkrNFS9iI0m8-A_XEAg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 11:13:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/B6vVkrNFS9iI0m8-A_XEAg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 11:13:25 INFO - Downloaded 16399216 bytes. 11:13:25 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 11:13:32 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 11:13:32 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')]} 11:13:32 INFO - trying https://queue.taskcluster.net/v1/task/B6vVkrNFS9iI0m8-A_XEAg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 11:13:32 INFO - Downloading https://queue.taskcluster.net/v1/task/B6vVkrNFS9iI0m8-A_XEAg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 11:13:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/B6vVkrNFS9iI0m8-A_XEAg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 11:13:33 INFO - Downloaded 16399216 bytes. 11:13:33 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 11:13:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:13:40 INFO - trying https://queue.taskcluster.net/v1/task/B6vVkrNFS9iI0m8-A_XEAg/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 11:13:40 INFO - Downloading https://queue.taskcluster.net/v1/task/B6vVkrNFS9iI0m8-A_XEAg/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip 11:13:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/B6vVkrNFS9iI0m8-A_XEAg/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip'}, attempt #1 11:13:41 INFO - Downloaded 14413998 bytes. 11:13:41 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 11:13:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:13:42 INFO - trying https://queue.taskcluster.net/v1/task/B6vVkrNFS9iI0m8-A_XEAg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 11:13:42 INFO - Downloading https://queue.taskcluster.net/v1/task/B6vVkrNFS9iI0m8-A_XEAg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 11:13:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/B6vVkrNFS9iI0m8-A_XEAg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 11:13:45 INFO - Downloaded 111567720 bytes. 11:13:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/B6vVkrNFS9iI0m8-A_XEAg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 11:13:45 INFO - mkdir: /builds/slave/test/properties 11:13:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:13:45 INFO - Writing to file /builds/slave/test/properties/build_url 11:13:45 INFO - Contents: 11:13:45 INFO - build_url:https://queue.taskcluster.net/v1/task/B6vVkrNFS9iI0m8-A_XEAg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 11:13:46 INFO - Running post-action listener: _resource_record_post_action 11:13:46 INFO - Running post-action listener: set_extra_try_arguments 11:13:46 INFO - [mozharness: 2016-09-01 18:13:46.027712Z] Finished download-and-extract step (success) 11:13:46 INFO - [mozharness: 2016-09-01 18:13:46.027868Z] Running populate-webroot step. 11:13:46 INFO - Running pre-action listener: _resource_record_pre_action 11:13:46 INFO - Running main action method: populate_webroot 11:13:46 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:13:46 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:13:46 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:13:46 INFO - '--tppagecycles', 11:13:46 INFO - '7'], 11:13:46 INFO - 'tests': ['tsvgm']}}, 11:13:46 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:13:46 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:13:46 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:13:46 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:13:46 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:13:46 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:13:46 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:13:46 INFO - 'talos_options': ['--disable-e10s'], 11:13:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:13:46 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:13:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:13:46 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:13:46 INFO - 'talos_options': ['--disable-e10s'], 11:13:46 INFO - 'tests': ['damp', 'tps']}, 11:13:46 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:13:46 INFO - 'tests': ['damp', 'tps']}, 11:13:46 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:13:46 INFO - 'tests': ['dromaeo_dom']}, 11:13:46 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:13:46 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:13:46 INFO - 'tests': ['basic_compositor_video']}, 11:13:46 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:13:46 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:13:46 INFO - 'tests': ['a11yr', 11:13:46 INFO - 'ts_paint', 11:13:46 INFO - 'tpaint', 11:13:46 INFO - 'sessionrestore', 11:13:46 INFO - 'sessionrestore_no_auto_restore', 11:13:46 INFO - 'tabpaint']}, 11:13:46 INFO - 'other-e10s': {'tests': ['a11yr', 11:13:46 INFO - 'ts_paint', 11:13:46 INFO - 'tpaint', 11:13:46 INFO - 'sessionrestore', 11:13:46 INFO - 'sessionrestore_no_auto_restore', 11:13:46 INFO - 'tabpaint']}, 11:13:46 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:13:46 INFO - 'ts_paint', 11:13:46 INFO - 'tpaint', 11:13:46 INFO - 'sessionrestore', 11:13:46 INFO - 'sessionrestore_no_auto_restore', 11:13:46 INFO - 'tabpaint']}, 11:13:46 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:13:46 INFO - 'ts_paint', 11:13:46 INFO - 'tpaint', 11:13:46 INFO - 'sessionrestore', 11:13:46 INFO - 'sessionrestore_no_auto_restore', 11:13:46 INFO - 'tabpaint']}, 11:13:46 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:13:46 INFO - 'tests': ['a11yr', 11:13:46 INFO - 'ts_paint', 11:13:46 INFO - 'tpaint', 11:13:46 INFO - 'sessionrestore', 11:13:46 INFO - 'sessionrestore_no_auto_restore', 11:13:46 INFO - 'tabpaint']}, 11:13:46 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:13:46 INFO - 'tests': ['a11yr', 11:13:46 INFO - 'ts_paint', 11:13:46 INFO - 'tpaint', 11:13:46 INFO - 'sessionrestore', 11:13:46 INFO - 'sessionrestore_no_auto_restore', 11:13:46 INFO - 'tabpaint']}, 11:13:46 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:13:46 INFO - 'tests': ['tsvgx', 11:13:46 INFO - 'tsvgr_opacity', 11:13:46 INFO - 'tart', 11:13:46 INFO - 'tscrollx', 11:13:46 INFO - 'cart']}, 11:13:46 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:13:46 INFO - 'tsvgr_opacity', 11:13:46 INFO - 'tart', 11:13:46 INFO - 'tscrollx', 11:13:46 INFO - 'cart']}, 11:13:46 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:13:46 INFO - 'talos_options': ['--disable-e10s'], 11:13:46 INFO - 'tests': ['tp5o']}, 11:13:46 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:13:46 INFO - 'tests': ['tp5o']}, 11:13:46 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:13:46 INFO - 'talos_options': ['--disable-e10s', 11:13:46 INFO - '--xperf_path', 11:13:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:13:46 INFO - 'tests': ['tp5n']}, 11:13:46 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:13:46 INFO - 'talos_options': ['--xperf_path', 11:13:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:13:46 INFO - 'tests': ['tp5n']}}, 11:13:46 INFO - 'talos.zip': {'path': '', 11:13:46 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:13:46 INFO - Running post-action listener: _resource_record_post_action 11:13:46 INFO - [mozharness: 2016-09-01 18:13:46.046476Z] Finished populate-webroot step (success) 11:13:46 INFO - [mozharness: 2016-09-01 18:13:46.046588Z] Running create-virtualenv step. 11:13:46 INFO - Running pre-action listener: _resource_record_pre_action 11:13:46 INFO - Running main action method: create_virtualenv 11:13:46 INFO - Creating virtualenv /builds/slave/test/build/venv 11:13:46 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 11:13:46 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:13:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:13:46 INFO - Using real prefix '/tools/python27' 11:13:46 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:13:46 INFO - Installing distribute.............................................................................................................................................................................................done. 11:13:49 INFO - Installing pip.................done. 11:13:49 INFO - Return code: 0 11:13:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:13:49 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')]} 11:13:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:13:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:13:49 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')]} 11:13:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:13:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:13:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d102a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d197618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbc53c9d020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d066d30>, '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 0x10cdcbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d05eb20>, '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.c87tQgaOMl/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.uffSaAg7Fw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/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 11:13:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 11:13:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 11:13:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.c87tQgaOMl/Render', 11:13:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:13:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:13:49 INFO - 'HOME': '/Users/cltbld', 11:13:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:13:49 INFO - 'LOGNAME': 'cltbld', 11:13:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:13:49 INFO - 'MOZ_NO_REMOTE': '1', 11:13:49 INFO - 'NO_EM_RESTART': '1', 11:13:49 INFO - 'PAGER': '/bin/cat', 11:13:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:13:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:13:49 INFO - 'PWD': '/builds/slave/test', 11:13:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:13:49 INFO - 'SHELL': '/bin/bash', 11:13:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uffSaAg7Fw/Listeners', 11:13:49 INFO - 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/', 11:13:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:13:49 INFO - 'USER': 'cltbld', 11:13:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:13:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:13:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:13:49 INFO - 'XPC_FLAGS': '0x0', 11:13:49 INFO - 'XPC_SERVICE_NAME': '0', 11:13:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:13:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:13:49 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 11:13:49 INFO - Cleaning up... 11:13:49 INFO - Return code: 0 11:13:49 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:13:49 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')]} 11:13:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:13:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:13:49 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')]} 11:13:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:13:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:13:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10d102a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d197618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbc53c9d020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d066d30>, '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 0x10cdcbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d05eb20>, '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.c87tQgaOMl/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.uffSaAg7Fw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/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 11:13:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 11:13:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 11:13:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.c87tQgaOMl/Render', 11:13:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:13:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:13:49 INFO - 'HOME': '/Users/cltbld', 11:13:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:13:49 INFO - 'LOGNAME': 'cltbld', 11:13:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:13:49 INFO - 'MOZ_NO_REMOTE': '1', 11:13:49 INFO - 'NO_EM_RESTART': '1', 11:13:49 INFO - 'PAGER': '/bin/cat', 11:13:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:13:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:13:49 INFO - 'PWD': '/builds/slave/test', 11:13:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:13:49 INFO - 'SHELL': '/bin/bash', 11:13:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uffSaAg7Fw/Listeners', 11:13:49 INFO - 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/', 11:13:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:13:49 INFO - 'USER': 'cltbld', 11:13:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:13:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:13:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:13:49 INFO - 'XPC_FLAGS': '0x0', 11:13:49 INFO - 'XPC_SERVICE_NAME': '0', 11:13:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:13:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:13:50 INFO - Downloading/unpacking psutil>=3.1.1 11:13:50 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 11:13:50 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 11:13:50 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 11:13:50 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 11:13:50 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 11:13:50 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 11:13:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:13:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:13:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:13:53 INFO - Installing collected packages: psutil 11:13:53 INFO - Running setup.py install for psutil 11:13:53 INFO - building 'psutil._psutil_osx' extension 11:13:53 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 11:13:53 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 11:13:53 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 11:13:53 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 11:13:53 INFO - building 'psutil._psutil_posix' extension 11:13:53 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 11:13:53 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:13:53 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:13:53 INFO - ^ 11:13:53 INFO - 1 warning generated. 11:13:53 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 11:13:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:13:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:13:54 INFO - Successfully installed psutil 11:13:54 INFO - Cleaning up... 11:13:54 INFO - Return code: 0 11:13:54 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:13:54 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')]} 11:13:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:13:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:13:54 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')]} 11:13:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:13:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:13:54 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 0x10d102a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d197618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbc53c9d020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d066d30>, '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 0x10cdcbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d05eb20>, '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.c87tQgaOMl/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.uffSaAg7Fw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/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 11:13:54 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 11:13:54 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 11:13:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.c87tQgaOMl/Render', 11:13:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:13:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:13:54 INFO - 'HOME': '/Users/cltbld', 11:13:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:13:54 INFO - 'LOGNAME': 'cltbld', 11:13:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:13:54 INFO - 'MOZ_NO_REMOTE': '1', 11:13:54 INFO - 'NO_EM_RESTART': '1', 11:13:54 INFO - 'PAGER': '/bin/cat', 11:13:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:13:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:13:54 INFO - 'PWD': '/builds/slave/test', 11:13:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:13:54 INFO - 'SHELL': '/bin/bash', 11:13:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uffSaAg7Fw/Listeners', 11:13:54 INFO - 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/', 11:13:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:13:54 INFO - 'USER': 'cltbld', 11:13:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:13:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:13:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:13:54 INFO - 'XPC_FLAGS': '0x0', 11:13:54 INFO - 'XPC_SERVICE_NAME': '0', 11:13:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:13:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:13:54 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:13:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:13:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:13:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:13:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:13:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:13:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:13:56 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:13:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:13:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:13:57 INFO - Installing collected packages: mozsystemmonitor 11:13:57 INFO - Running setup.py install for mozsystemmonitor 11:13:57 INFO - Successfully installed mozsystemmonitor 11:13:57 INFO - Cleaning up... 11:13:57 INFO - Return code: 0 11:13:57 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:13:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:13:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:13:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:13:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:13:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:13:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:13:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d102a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d197618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbc53c9d020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d066d30>, '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 0x10cdcbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d05eb20>, '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.c87tQgaOMl/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.uffSaAg7Fw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/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 11:13:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 11:13:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 11:13:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.c87tQgaOMl/Render', 11:13:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:13:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:13:57 INFO - 'HOME': '/Users/cltbld', 11:13:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:13:57 INFO - 'LOGNAME': 'cltbld', 11:13:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:13:57 INFO - 'MOZ_NO_REMOTE': '1', 11:13:57 INFO - 'NO_EM_RESTART': '1', 11:13:57 INFO - 'PAGER': '/bin/cat', 11:13:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:13:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:13:57 INFO - 'PWD': '/builds/slave/test', 11:13:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:13:57 INFO - 'SHELL': '/bin/bash', 11:13:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uffSaAg7Fw/Listeners', 11:13:57 INFO - 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/', 11:13:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:13:57 INFO - 'USER': 'cltbld', 11:13:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:13:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:13:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:13:57 INFO - 'XPC_FLAGS': '0x0', 11:13:57 INFO - 'XPC_SERVICE_NAME': '0', 11:13:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:13:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:13:57 INFO - Downloading/unpacking jsonschema==2.5.1 11:13:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:13:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:13:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:13:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:13:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:13:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:13:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:13:59 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:13:59 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 11:13:59 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 11:13:59 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 11:13:59 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 11:13:59 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 11:13:59 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 11:14:00 INFO - Downloading functools32-3.2.3-2.tar.gz 11:14:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:14:00 INFO - warning: no files found matching '*.txt' 11:14:00 INFO - no previously-included directories found matching 'build' 11:14:00 INFO - no previously-included directories found matching 'dist' 11:14:00 INFO - no previously-included directories found matching '.git*' 11:14:00 INFO - Installing collected packages: jsonschema, functools32 11:14:00 INFO - Running setup.py install for functools32 11:14:00 INFO - warning: no files found matching '*.txt' 11:14:00 INFO - no previously-included directories found matching 'build' 11:14:00 INFO - no previously-included directories found matching 'dist' 11:14:00 INFO - no previously-included directories found matching '.git*' 11:14:00 INFO - Successfully installed jsonschema functools32 11:14:00 INFO - Cleaning up... 11:14:00 INFO - Return code: 0 11:14:00 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:14:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:14:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:14:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:14:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:14:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:14:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:14:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10d102a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d197618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbc53c9d020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d066d30>, '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 0x10cdcbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d05eb20>, '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.c87tQgaOMl/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.uffSaAg7Fw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/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 11:14:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 11:14:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 11:14:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.c87tQgaOMl/Render', 11:14:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:14:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:14:00 INFO - 'HOME': '/Users/cltbld', 11:14:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:14:00 INFO - 'LOGNAME': 'cltbld', 11:14:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:14:00 INFO - 'MOZ_NO_REMOTE': '1', 11:14:00 INFO - 'NO_EM_RESTART': '1', 11:14:00 INFO - 'PAGER': '/bin/cat', 11:14:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:14:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:14:00 INFO - 'PWD': '/builds/slave/test', 11:14:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:14:00 INFO - 'SHELL': '/bin/bash', 11:14:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uffSaAg7Fw/Listeners', 11:14:00 INFO - 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/', 11:14:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:14:00 INFO - 'USER': 'cltbld', 11:14:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:14:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:14:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:14:00 INFO - 'XPC_FLAGS': '0x0', 11:14:00 INFO - 'XPC_SERVICE_NAME': '0', 11:14:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:14:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:14:00 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:14:00 INFO - Cleaning up... 11:14:00 INFO - Return code: 0 11:14:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:14:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:14:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:14:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:14:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:14:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:14:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:14:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10d102a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d197618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbc53c9d020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d066d30>, '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 0x10cdcbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d05eb20>, '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.c87tQgaOMl/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.uffSaAg7Fw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/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 11:14:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 11:14:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 11:14:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.c87tQgaOMl/Render', 11:14:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:14:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:14:00 INFO - 'HOME': '/Users/cltbld', 11:14:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:14:00 INFO - 'LOGNAME': 'cltbld', 11:14:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:14:00 INFO - 'MOZ_NO_REMOTE': '1', 11:14:00 INFO - 'NO_EM_RESTART': '1', 11:14:00 INFO - 'PAGER': '/bin/cat', 11:14:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:14:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:14:00 INFO - 'PWD': '/builds/slave/test', 11:14:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:14:00 INFO - 'SHELL': '/bin/bash', 11:14:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uffSaAg7Fw/Listeners', 11:14:00 INFO - 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/', 11:14:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:14:00 INFO - 'USER': 'cltbld', 11:14:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:14:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:14:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:14:00 INFO - 'XPC_FLAGS': '0x0', 11:14:00 INFO - 'XPC_SERVICE_NAME': '0', 11:14:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:14:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:14:01 INFO - Downloading/unpacking blobuploader==1.2.4 11:14:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:14:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:14:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:14:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:14:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:14:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:14:03 INFO - Downloading blobuploader-1.2.4.tar.gz 11:14:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:14:03 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:14: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 11:14: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 11:14: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 11:14: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 11:14: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 11:14: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 11:14:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:14:04 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:14:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:14:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:14:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:14:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:14:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:14:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:14:04 INFO - Downloading docopt-0.6.1.tar.gz 11:14:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:14:04 INFO - Installing collected packages: blobuploader, requests, docopt 11:14:04 INFO - Running setup.py install for blobuploader 11:14:04 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:14:05 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:14:05 INFO - Running setup.py install for requests 11:14:05 INFO - Running setup.py install for docopt 11:14:05 INFO - Successfully installed blobuploader requests docopt 11:14:05 INFO - Cleaning up... 11:14:05 INFO - Return code: 0 11:14:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:14:05 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')]} 11:14:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:14:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:14:05 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')]} 11:14:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:14:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:14:05 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 0x10d102a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d197618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbc53c9d020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d066d30>, '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 0x10cdcbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d05eb20>, '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.c87tQgaOMl/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.uffSaAg7Fw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/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 11:14:05 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 11:14:05 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 11:14:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.c87tQgaOMl/Render', 11:14:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:14:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:14:05 INFO - 'HOME': '/Users/cltbld', 11:14:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:14:05 INFO - 'LOGNAME': 'cltbld', 11:14:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:14:05 INFO - 'MOZ_NO_REMOTE': '1', 11:14:05 INFO - 'NO_EM_RESTART': '1', 11:14:05 INFO - 'PAGER': '/bin/cat', 11:14:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:14:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:14:05 INFO - 'PWD': '/builds/slave/test', 11:14:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:14:05 INFO - 'SHELL': '/bin/bash', 11:14:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uffSaAg7Fw/Listeners', 11:14:05 INFO - 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/', 11:14:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:14:05 INFO - 'USER': 'cltbld', 11:14:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:14:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:14:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:14:05 INFO - 'XPC_FLAGS': '0x0', 11:14:05 INFO - 'XPC_SERVICE_NAME': '0', 11:14:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:14:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:14:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:14:06 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-GQkbp3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:14:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:14:06 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-1dgRoA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:14:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:14:06 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-0M5uKq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:14:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:14:06 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-kMFw8L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:14:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:14:06 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-qZ_zXX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:14:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:14:06 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-fGJF66-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:14:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:14:06 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-IM0faC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:14:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:14:06 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-n9t6t4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:14:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:14:06 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-0XwZLT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:14:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:14:06 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-mw2Sf6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:14:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:14:07 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-OWpq6e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:14:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:14:07 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-SBMEr1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:14:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:14:07 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-mE0Xk_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:14:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:14:07 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-u1xR3r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:14:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:14:07 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-zIf3mX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:14:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:14:07 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-Bp6WJ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:14:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:14:07 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-VrkNEG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:14:07 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:14:07 INFO - Running setup.py install for manifestparser 11:14:07 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:14:07 INFO - Running setup.py install for mozcrash 11:14:08 INFO - Running setup.py install for mozdebug 11:14:08 INFO - Running setup.py install for mozdevice 11:14:08 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:14:08 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:14:08 INFO - Running setup.py install for mozfile 11:14:08 INFO - Running setup.py install for mozhttpd 11:14:08 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:14:08 INFO - Running setup.py install for mozinfo 11:14:08 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:14:08 INFO - Running setup.py install for mozInstall 11:14:08 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:14:08 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:14:08 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:14:08 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:14:08 INFO - Running setup.py install for mozleak 11:14:09 INFO - Running setup.py install for mozlog 11:14:09 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:14:09 INFO - Running setup.py install for moznetwork 11:14:09 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:14:09 INFO - Running setup.py install for mozprocess 11:14:09 INFO - Running setup.py install for mozprofile 11:14:09 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:14:09 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:14:09 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:14:09 INFO - Running setup.py install for mozrunner 11:14:09 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:14:09 INFO - Running setup.py install for mozscreenshot 11:14:10 INFO - Running setup.py install for moztest 11:14:10 INFO - Running setup.py install for mozversion 11:14:10 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:14:10 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:14:10 INFO - Cleaning up... 11:14:10 INFO - Return code: 0 11:14:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:14:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:14:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:14:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:14:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:14:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:14:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:14:10 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 0x10d102a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d197618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbc53c9d020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d066d30>, '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 0x10cdcbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d05eb20>, '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.c87tQgaOMl/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.uffSaAg7Fw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/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 11:14:10 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 11:14:10 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 11:14:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.c87tQgaOMl/Render', 11:14:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:14:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:14:10 INFO - 'HOME': '/Users/cltbld', 11:14:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:14:10 INFO - 'LOGNAME': 'cltbld', 11:14:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:14:10 INFO - 'MOZ_NO_REMOTE': '1', 11:14:10 INFO - 'NO_EM_RESTART': '1', 11:14:10 INFO - 'PAGER': '/bin/cat', 11:14:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:14:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:14:10 INFO - 'PWD': '/builds/slave/test', 11:14:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:14:10 INFO - 'SHELL': '/bin/bash', 11:14:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uffSaAg7Fw/Listeners', 11:14:10 INFO - 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/', 11:14:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:14:10 INFO - 'USER': 'cltbld', 11:14:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:14:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:14:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:14:10 INFO - 'XPC_FLAGS': '0x0', 11:14:10 INFO - 'XPC_SERVICE_NAME': '0', 11:14:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:14:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:14:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:14:10 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-P71dKK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:14:10 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)) 11:14:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:14:10 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-K4v2cH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:14:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 11:14:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:14:10 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-CJzAE3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:14:10 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)) 11:14:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:14:10 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-pSoYpN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:14:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 11:14:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:14:11 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-sjGJWN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:14:11 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)) 11:14:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:14:11 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-kCHmSP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:14:11 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)) 11:14:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:14:11 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-4QEtwx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:14:11 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)) 11:14:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:14:11 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-o8sqYI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:14:11 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)) 11:14:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:14:11 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-hVdJlz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:14:11 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)) 11:14:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:14:11 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-3GYoOm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:14:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 11:14:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:14:11 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-gNqUWO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:14:11 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)) 11:14:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:14:11 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-FgkUcT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:14:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 11:14:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:14:11 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-uPGeFv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:14:12 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)) 11:14:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:14:12 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-_Qww7s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:14:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 11:14:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:14:12 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-z8KWLe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:14:12 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)) 11:14:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:14:12 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-X7dCvU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:14:12 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 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)) 11:14:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:14:12 INFO - Running setup.py (path:/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/pip-HeO1t3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:14:12 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)) 11:14:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:14:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:14:12 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)) 11:14:12 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:14:12 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:14:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:14:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:14:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:14:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:14:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:14:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:14:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:14:14 INFO - Downloading blessings-1.6.tar.gz 11:14:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:14:15 INFO - Installing collected packages: blessings 11:14:15 INFO - Running setup.py install for blessings 11:14:15 INFO - Successfully installed blessings 11:14:15 INFO - Cleaning up... 11:14:15 INFO - Return code: 0 11:14:15 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:14:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:14:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:14:15 INFO - Reading from file tmpfile_stdout 11:14:15 INFO - Current package versions: 11:14:15 INFO - blessings == 1.6 11:14:15 INFO - blobuploader == 1.2.4 11:14:15 INFO - docopt == 0.6.1 11:14:15 INFO - functools32 == 3.2.3-2 11:14:15 INFO - jsonschema == 2.5.1 11:14:15 INFO - manifestparser == 1.1 11:14:15 INFO - mozInstall == 1.12 11:14:15 INFO - mozcrash == 0.17 11:14:15 INFO - mozdebug == 0.1 11:14:15 INFO - mozdevice == 0.48 11:14:15 INFO - mozfile == 1.2 11:14:15 INFO - mozhttpd == 0.7 11:14:15 INFO - mozinfo == 0.9 11:14:15 INFO - mozleak == 0.1 11:14:15 INFO - mozlog == 3.3 11:14:15 INFO - moznetwork == 0.27 11:14:15 INFO - mozprocess == 0.23 11:14:15 INFO - mozprofile == 0.28 11:14:15 INFO - mozrunner == 6.12 11:14:15 INFO - mozscreenshot == 0.1 11:14:15 INFO - mozsystemmonitor == 0.3 11:14:15 INFO - moztest == 0.7 11:14:15 INFO - mozversion == 1.4 11:14:15 INFO - psutil == 3.1.1 11:14:15 INFO - requests == 1.2.3 11:14:15 INFO - wsgiref == 0.1.2 11:14:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:14: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')]} 11:14:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:14:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:14:16 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')]} 11:14:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:14:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:14:16 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 0x10d102a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d197618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbc53c9d020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d066d30>, '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 0x10cdcbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d05eb20>, '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.c87tQgaOMl/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.uffSaAg7Fw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/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 11:14:16 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 11:14:16 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 11:14:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.c87tQgaOMl/Render', 11:14:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:14:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:14:16 INFO - 'HOME': '/Users/cltbld', 11:14:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:14:16 INFO - 'LOGNAME': 'cltbld', 11:14:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:14:16 INFO - 'MOZ_NO_REMOTE': '1', 11:14:16 INFO - 'NO_EM_RESTART': '1', 11:14:16 INFO - 'PAGER': '/bin/cat', 11:14:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:14:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:14:16 INFO - 'PWD': '/builds/slave/test', 11:14:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:14:16 INFO - 'SHELL': '/bin/bash', 11:14:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uffSaAg7Fw/Listeners', 11:14:16 INFO - 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/', 11:14:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:14:16 INFO - 'USER': 'cltbld', 11:14:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:14:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:14:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:14:16 INFO - 'XPC_FLAGS': '0x0', 11:14:16 INFO - 'XPC_SERVICE_NAME': '0', 11:14:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:14:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:14:16 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)) 11:14:16 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)) 11:14:16 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)) 11:14:16 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)) 11:14:16 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)) 11:14:16 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)) 11:14:16 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)) 11:14:16 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)) 11:14:16 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)) 11:14:16 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)) 11:14:16 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)) 11:14:16 INFO - Cleaning up... 11:14:16 INFO - Return code: 0 11:14:16 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:14:16 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')]} 11:14:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:14:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:14:16 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')]} 11:14:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:14:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:14:16 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 0x10d102a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d197618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbc53c9d020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d066d30>, '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 0x10cdcbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d05eb20>, '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.c87tQgaOMl/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.uffSaAg7Fw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/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 11:14:16 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 11:14:16 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 11:14:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.c87tQgaOMl/Render', 11:14:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:14:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:14:16 INFO - 'HOME': '/Users/cltbld', 11:14:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:14:16 INFO - 'LOGNAME': 'cltbld', 11:14:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:14:16 INFO - 'MOZ_NO_REMOTE': '1', 11:14:16 INFO - 'NO_EM_RESTART': '1', 11:14:16 INFO - 'PAGER': '/bin/cat', 11:14:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:14:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:14:16 INFO - 'PWD': '/builds/slave/test', 11:14:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:14:16 INFO - 'SHELL': '/bin/bash', 11:14:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uffSaAg7Fw/Listeners', 11:14:16 INFO - 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/', 11:14:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:14:16 INFO - 'USER': 'cltbld', 11:14:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:14:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:14:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:14:16 INFO - 'XPC_FLAGS': '0x0', 11:14:16 INFO - 'XPC_SERVICE_NAME': '0', 11:14:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:14:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:14:16 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:14:16 INFO - Cleaning up... 11:14:16 INFO - Return code: 0 11:14:16 INFO - Running post-action listener: _resource_record_post_action 11:14:16 INFO - Running post-action listener: _start_resource_monitoring 11:14:16 INFO - Starting resource monitoring. 11:14:16 INFO - [mozharness: 2016-09-01 18:14:16.941801Z] Finished create-virtualenv step (success) 11:14:16 INFO - [mozharness: 2016-09-01 18:14:16.942809Z] Running install step. 11:14:16 INFO - Running pre-action listener: _resource_record_pre_action 11:14:16 INFO - Running main action method: install 11:14:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:14:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:14:17 INFO - Reading from file tmpfile_stdout 11:14:17 INFO - Detecting whether we're running mozinstall >=1.0... 11:14:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:14:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:14:17 INFO - Reading from file tmpfile_stdout 11:14:17 INFO - Output received: 11:14:17 INFO - Usage: mozinstall [options] installer 11:14:17 INFO - Options: 11:14:17 INFO - -h, --help show this help message and exit 11:14:17 INFO - -d DEST, --destination=DEST 11:14:17 INFO - Directory to install application into. [default: 11:14:17 INFO - "/builds/slave/test"] 11:14:17 INFO - --app=APP Application being installed. [default: firefox] 11:14:17 INFO - mkdir: /builds/slave/test/build/application 11:14:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 11:14:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 11:14:51 INFO - Reading from file tmpfile_stdout 11:14:51 INFO - Output received: 11:14:51 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 11:14:51 INFO - Running post-action listener: _resource_record_post_action 11:14:51 INFO - [mozharness: 2016-09-01 18:14:51.251486Z] Finished install step (success) 11:14:51 INFO - [mozharness: 2016-09-01 18:14:51.251644Z] Running run-tests step. 11:14:51 INFO - Running pre-action listener: _resource_record_pre_action 11:14:51 INFO - Running main action method: run_tests 11:14:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:14:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:14:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:14:51 INFO - 2016-09-01 11:14:51.266 screenresolution[1903:14407] starting screenresolution argv=screenresolution get 11:14:51 INFO - 2016-09-01 11:14:51.269 screenresolution[1903:14407] Display 0: 1600x1200x32@60 11:14:51 INFO - 2016-09-01 11:14:51.275 screenresolution[1904:14409] starting screenresolution argv=screenresolution list 11:14:51 INFO - Available Modes on Display 0 11:14:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:14:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:14:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:14:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:14:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:14:51 INFO - Intel Iris: 11:14:51 INFO - Chipset Model: Intel Iris 11:14:51 INFO - Type: GPU 11:14:51 INFO - Bus: Built-In 11:14:51 INFO - VRAM (Dynamic, Max): 1536 MB 11:14:51 INFO - Vendor: Intel (0x8086) 11:14:51 INFO - Device ID: 0x0a2e 11:14:51 INFO - Revision ID: 0x0009 11:14:51 INFO - Displays: 11:14:51 INFO - Display: 11:14:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:14:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:14:51 INFO - Display Serial Number: 1600x1200 60 11:14:51 INFO - Main Display: Yes 11:14:51 INFO - Mirror: Off 11:14:51 INFO - Online: Yes 11:14:51 INFO - Rotation: Supported 11:14:51 INFO - Return code: 0 11:14:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:14:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:14:51 INFO - Python 2.7.3 11:14:51 INFO - Return code: 0 11:14:51 INFO - grabbing minidump binary from tooltool 11:14: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')]} 11:14: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 0x10d066d30>, '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 0x10cdcbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d05eb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:14: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 11:14: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 11:14: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 11:14:51 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:14:51 INFO - Return code: 0 11:14:51 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:14:51 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:14:51 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:14:51 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:14:51 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:14:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/B6vVkrNFS9iI0m8-A_XEAg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0165', '--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 11:14:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/B6vVkrNFS9iI0m8-A_XEAg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0165 --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 11:14:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.c87tQgaOMl/Render', 11:14:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:14:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:14:51 INFO - 'HOME': '/Users/cltbld', 11:14:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:14:51 INFO - 'LOGNAME': 'cltbld', 11:14:51 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:14:51 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:14:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:14:51 INFO - 'MOZ_NO_REMOTE': '1', 11:14:51 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:14:51 INFO - 'NO_EM_RESTART': '1', 11:14:51 INFO - 'PAGER': '/bin/cat', 11:14:51 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:14:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:14:51 INFO - 'PWD': '/builds/slave/test', 11:14:51 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:14:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:14:51 INFO - 'SHELL': '/bin/bash', 11:14:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uffSaAg7Fw/Listeners', 11:14:51 INFO - 'TMPDIR': '/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/', 11:14:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:14:51 INFO - 'USER': 'cltbld', 11:14:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:14:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:14:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:14:51 INFO - 'XPC_FLAGS': '0x0', 11:14:51 INFO - 'XPC_SERVICE_NAME': '0', 11:14:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:14:51 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/B6vVkrNFS9iI0m8-A_XEAg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0165', '--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 11:14:51 INFO - mozversion application_buildid: 20160901091421 11:14:51 INFO - mozversion application_changeset: 4dd14de3fa420bf5d750ca7c969ace2a9a9931ac 11:14:51 INFO - mozversion application_display_name: Nightly 11:14:51 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:14:51 INFO - mozversion application_name: Firefox 11:14:51 INFO - mozversion application_remotingname: firefox 11:14:51 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 11:14:51 INFO - mozversion application_vendor: Mozilla 11:14:51 INFO - mozversion application_version: 51.0a1 11:14:51 INFO - mozversion platform_buildid: 20160901091421 11:14:51 INFO - mozversion platform_changeset: 4dd14de3fa420bf5d750ca7c969ace2a9a9931ac 11:14:51 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 11:14:51 INFO - mozversion platform_version: 51.0a1 11:14:51 INFO - using testdate: 1472753691 11:14:51 INFO - actual date: 1472753691 11:14:51 INFO - starting webserver on 'localhost:49426' 11:14:51 INFO - SUITE-START | Running 2 tests 11:14:51 INFO - TEST-START | dromaeo_css 11:14:51 INFO - operating with platform_type : mac_ 11:14:51 INFO - Initialising browser for dromaeo_css test... 11:14:51 INFO - TEST-INFO | started process 1914 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/tmpa2gD5D/profile http://localhost:49426/getInfo.html) 11:14:54 INFO - PROCESS | 1914 | __metrics Screen width/height:1600/1200 11:14:54 INFO - PROCESS | 1914 | colorDepth:24 11:14:54 INFO - PROCESS | 1914 | Browser inner width/height: 1024/689 11:14:54 INFO - PROCESS | 1914 | __metrics 11:14:54 INFO - PROCESS | 1914 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 11:14:54 INFO - PROCESS | 1914 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:14:55 INFO - TEST-INFO | 1914: exit 0 11:14:55 INFO - Browser initialized. 11:14:55 INFO - Running cycle 1/1 for dromaeo_css test... 11:14:55 INFO - TEST-INFO | started process 1916 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/tmpa2gD5D/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:15:07 INFO - PROCESS | 1916 | RSS: Main: 233861120 11:15:07 INFO - PROCESS | 1916 | 11:15:58 INFO - PROCESS | 1916 | 1472753758587 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 11:18:10 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:49426/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49426/tests/dromaeo/cssquery-ext.html) 11:18:12 INFO - PROCESS | 1916 | RSS: Main: 412827648 11:18:12 INFO - PROCESS | 1916 | 11:21:15 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:49426/tests/dromaeo/cssquery-ext.html (next: http://localhost:49426/tests/dromaeo/cssquery-jquery.html) 11:21:15 INFO - PROCESS | 1916 | RSS: Main: 372654080 11:21:15 INFO - PROCESS | 1916 | 11:24:18 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:49426/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49426/tests/dromaeo/cssquery-mootools.html) 11:24:18 INFO - PROCESS | 1916 | RSS: Main: 355803136 11:24:18 INFO - PROCESS | 1916 | 11:27:21 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:49426/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49426/tests/dromaeo/cssquery-prototype.html) 11:27:21 INFO - PROCESS | 1916 | RSS: Main: 340213760 11:27:21 INFO - PROCESS | 1916 | 11:30:24 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:49426/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49426/tests/dromaeo/cssquery-yui.html) 11:30:25 INFO - PROCESS | 1916 | RSS: Main: 335073280 11:30:25 INFO - PROCESS | 1916 | 11:33:29 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:49426/tests/dromaeo/cssquery-yui.html (next: http://localhost:49426/tests/dromaeo/cssquery-dojo.html) 11:33:29 INFO - PROCESS | 1916 | RSS: Main: 425672704 11:33:29 INFO - PROCESS | 1916 | 11:33:29 INFO - PROCESS | 1916 | __start_tp_report 11:33:29 INFO - PROCESS | 1916 | _x_x_mozilla_page_load 11:33:29 INFO - PROCESS | 1916 | _x_x_mozilla_page_load_details 11:33:29 INFO - PROCESS | 1916 | |i|pagename|runs| 11:33:29 INFO - PROCESS | 1916 | |0;dojo.html;3271.9509207361893;3280.1143691203383;3318.1704573856523;3357.3117510910265;3357.714594259488;5486.780528778854;5569.0254205513975;5585.134304182848;5624.915626265604;5627.352854421738;5572.637778544392;5581.665100093997;5596.328440587125;5608.186812912125;5608.2428872102255;5069.391672999236;5084.644074914758;5093.134167191568;5164.04465173944;5197.792088316482;5114.846554603368;5179.482051794828;5195.792168313282;5198.45416231296;5200.713960732158;5157.355330583677;5161.251618515313;5182.352205974254;5196.324477817879;5201.453847346031;5215.791368345281;5235.528802407802;5237.869053273661;5255.605829562798;5290.841274761763;5020.146575082244;5043.974780126113;5063.746812659389;5151.7681704323395;5177.223416487519;5530.474604912527;5565.026120428909;5610.579206559483;5622.156676498517;5673.943260567383;6144.907826382608;6164.291106522717;6170.228721409824;6175.505959523227;6182.319944806068;6053.213082299273;6119.380693981191;6175.104609831549;6206.007038873804;6215.627062376272;5723.456271654186;5741.425857414225;5741.942580574224;5756.33802112754;5805.7387417566315;6226.159468471789;6229.96885015572;6243.125962365272;6243.250809902841;6250.718717657716;5717.42825717425;6058.333583305833;6090.9390906090375;6091.908621370683;6107.297660769068;6183.592230103847;6243.906341404882;6245;6265.185525881698;6271.843203919847;6100.115483254903;6161.8459538511;6170.629762214281;6182;6198.287196972406;5947.583669143119;5986.790462333797;5988.970055149696;6177.62934223948;6198.163247961557;4821.831235906727;4831.347768051268;4838.516148385133;4839.516048395203;4839.685420447659;4323.697341186087;4324.221640104748;4352.847650332224;4363.672724545671;4369.868903932887;5624.3813180550105;5633.352164501053;5634.549236061105;5660.943390566124;5666.433356664301;6119.785807496716;6233.781817636361;6243.37566243372;6249.750009999549;6253.937460625336;4767.523247675274;5453.590980676465;5532.225488431623;5606.21512988182;5613.607047506635;4994.375703037121;5352.090144675337;5358.356997160287;5391.056565101092;5413.621046526784;6243.282022567281;6270.968645156745;6294.590851594723;6296.622202667854;6310.337414571496;6078.908816367846;6097.0710051834385;6204.286507051746;6211.3167551570195;6233.8779019776875;6150.892839288971;6179;6195.132681424514;6277.5605707600025;6283.905741413791;6131.386861314011;6131.417515336036;6138.877222455437;6144.56988010835;6149.108379285068;6000.189974353581;6041.909371359346;6174.197354343906;6195.287541932736;6219;5856.0630299151935;5856.502197313276;5957.106434034756;5965.403459654;5994.520438364833;5965.9105113422465;5974.61165024272;5976.013957696931;5977.880442391041;5988.341282459013;5784.971075144766;5809.390014048633;5828.067509198508;5856.853578660568;5857.121431785266;6031.336552979081;6031.487323577545;6084;6106.2179897963915;6117.265928088658;5849.239598852122;5860.326062502696;5861.794837180678;5889.263842019747;5891.646501209942 11:33:29 INFO - PROCESS | 1916 | |1;ext.html;467.93448917151596;473.37987236719897;513.3224144129749;522.1228336394857;548.7777450132697;25039.624405633906;39807.800960995155;40771;41733.79133104338;41844.744657660245;43408.91477713059;43626.90932726683;43842.342364864555;43867.78066109673;43947.3407898881;9743.123118919279;9877.259205559576;9928.751781205492;9939.006099390039;9941.602335906555;7706.6917323307;8824.382293239476;9035.683751068715;9076.818463630723;9096.135867092615;12021.579244726436;12264.81602775959;12283.263004219776;12511.374431278446;12584.622461326175;12269.730099931541;12349.93825030869;12357.25856448607;12367.505299787998;12400.379981000959;10546.578136874516;11608.129390295762;12034.81947770784;12042.879571204263;12053.758924821455;40616.796916015526;40915.13594048388;40958.38562421566;41052.589474105174;41206.1758764826;3353.6814002669953;3358.647342029101;3569.5181150544604;3598.2623562169692;3833.8274777635074;30961.845190774126;31177.22056948572;31578.052658420285;31633.52549711756;31862.840685796655;2869.913902582926;3106.7359274461696;3248.886288979898;3267.036224313833;3329.6503867093847;503878;507585.46207269095;508750;511810;517375;60975;61210;61284;61380.38619613836;61400;34567.4814877777;65935;65991;65995.67002164958;66000;15746.448874289345;16576.75134872978;16637.667246655;16652.417165399394;16673.24970376336;12722.363881805873;12844;12848.807267890988;12860.678483037811;12881.291528965809;16154.515364539082;16560.834391655928;19093;19094.809051909582;19267;7609.239076092457;7683.500572462772;7742.7677169685;7744.574048427278;7756.379489640815;770.2951799103869;916.5829547555846;966.4442945306477;1018.1040684198007;1037.9792404151874;29529.26176845553;29939.251518712208;30073.69926300753;30742.538861917088;30806.845965770477;1038.4651904269308;1078.4607696151925;1083.3770581915273;1127.5658871334595;1131.8472006279046;14970.550883473514;15354.539363819104;15600.609984750472;15953.760693589606;16070.276837542338;683226;688463;688926.6627004193;689286;690209;50029.49970500248;50835.7458212714;59080.40919590835;60063.69968150131;60254.0961885572;1318.854925958144;1343.2947702456288;1362.3937347880099;1362.7070179911366;1369.9383527741275;30186.396272074875;31057.844710776302;31143;31902.662387650507;32122;51558.48441515537;51982.48017519928;52020.219696703825;52163.47836521586;52179.217311739594;650045;666033;666918;840708;841944;41008.79495602503;41883;42122;42145.78927105345;42407.575924240366;46262;46777.25781937505;46978.76510617425;47323.76338118287;47437.52562474469;38158;38388;38741.61258387493;39493.60506395014;40146.19707605773;31038.844805775825;38083;38155.618443815205;39266.80366598149;39342.21315573615;37178.25643487169;37445;38115;38215.61784382121;38250.617493824706;32984.83507582447;33002.66997329996;33385.99842004647;34151.316973659894;34245.657543425245;24904.875475622506;24948.0020799166;25065.373365666004;25238.53554916906;25346.366340841625 11:33:29 INFO - PROCESS | 1916 | |2;jquery.html;344.64501563389683;360.5799243880879;365.7275329879239;371.2760117770347;392.33695055356446;15301.464448744297;15335;17062.488125356245;20469.99999999998;20987.370378888656;17302.56743581411;20845.270415535422;20860.269890553835;21125.683114753254;21400.785992140074;5374.301340825707;5856.736446859891;6153.446189842914;6157.322694503601;6164.938350616481;5119.334486516747;6094.725737341831;6154.230721159855;6156.445919867211;6162.168107305523;5878.382769809173;6006.309274433431;6052.909206361908;6105.633661980273;6119.816405507842;6042.244719410074;6092.66490343031;6097.3292937776805;6099.817005489843;6104.603200791934;5208.427073021964;5276.393214780311;5482.945170548283;5547.11246200606;5570.969370666439;12754;12795.424205910756;12800.935995319964;12992.285424301659;13002.60992170227;2097.119209931817;2252.2004688335733;2341.555104530151;2606.908758193454;2795.35706787438;9798.314118011742;10086.344387614856;10388.688339349756;12579.182353147107;14844;1821.9180136893872;1849.861260405475;1850.7964123946356;1917.587718640499;1943.6112777444425;117663;121922.39038804927;123853.38073309576;124024;130426;11129.443527823576;11222.66332010041;11253.71865703364;11387.37369444672;11570.132240082028;14203.360848761831;14220.928895355457;14225.359858806378;14230.28848557568;14254.572362829133;8197.508149511048;8480.077822556645;8515.829683406297;8626.439281446686;8718.215360617574;7845.686172553148;7905.881411778828;8565.743027709179;8582.785430364165;8792.824143517222;4278.465191851019;4384.978075109604;4386.605205531518;4452.933206001912;4470.105978804252;1804.630050839601;1843.3548258109554;1854.8794328368613;1858.7026075828073;1881.294514557041;823.2097186700835;846.9540117954322;866.441145461174;877.6006916852816;900.4147304252289;18978.240870365305;20121.39635810928;21806.672899906513;21878.671819922714;21979.560408791735;837.539353355652;921.3366376209119;936.3913456253354;944.2917811641269;947.5357075033185;10478.528466219039;10969.945150274198;11345.943270283597;11347.432628368548;11456.427178641035;446481;457565;578183;595571;602913;105671.41492877612;124119.3794031024;134689;139789;140990.2950485241;884.4604791077437;965.546193289139;1001.5943542865151;1009.7879445316419;1011.4335971855637;18826.717599236297;19131.521711957314;19333.226670933003;19432.902835485733;19473.707894381878;37524.249515010095;52010.65952032092;54067.72966135144;54862.45137548733;55714;82088.58955705183;82890;83543;83550;83833.04056212209;6942.8611427772175;7914.44598878073;8076.394270429624;8079.919200808152;8204.917950820654;10453.843192352273;12881.35593220354;13336.599902002956;13406.994475414187;13493;9766.75583110428;11175.745107927783;11208.383538905438;11398.544058237578;11736.530538778694;11166.050885674484;11266.66200014001;11414.885851141382;11596.641165139234;11803.173777835469;11487;11673.883261167279;11730.354830484064;11976.940115299367;12019;7178.820529486805;7352.191258961403;7374.815629609303;7480.738174164096;7581.355584775135;9750.658726944523;10679.786404272027;10684.305520141166;11025.83461248066;11720.589779357682 11:33:29 INFO - PROCESS | 1916 | |3;mootools.html;1329.667583104224;1465.846086160953;1499.6325900154463;1703.812580616133;1879.5395128193586;7781.2996830285265;8568.957155214222;8871.911280887198;8877.334199934998;9015.278777697786;8775.429597076201;8834.514101724419;8866.202041816236;8881.289496840254;8883.777905552364;2390.139549762089;2419.9758002420012;2421.6367544868235;2424.599941009731;2429.137656132074;2116.195845578678;2361.197192954395;2367.7750613691674;2393.85636861788;2412.4571971306405;2366.171839856045;2387.9761202387926;2397.8441401308946;2404.939876503093;2406.0496104038893;2326.5230627721276;2346.096752750195;2349.4243910242017;2359.8584084954787;2385.153270588933;2331.2773040357374;2367.6685264063044;2367.7513861044513;2371.67982322386;2375.8930848111877;8647.264982476498;8772.517511536864;8828.558572071435;8883.866741998876;8913.688020919237;5147.974260128713;5356.6518176318805;5359.169328754049;5364.785408583652;5365.678059316453;3043.45217860785;3541.8406171722336;3673.8897833064784;3751;3766.3597188478025;976.1507488484992;1093.0763504838399;1098.1653943003296;1106.839508271304;1115.252780636975;11665.358405287705;11728.88271117295;11731.94134029333;11805.881941180563;11809.763804723943;3843.6925045996813;4380;4615.423072115986;4632.675712700078;4644.790984405709;9229.907700923039;9232.769180770534;9233.307501937381;9236.07639236071;9237.769055773659;5254.7635356409055;5257.631965762436;5260.763265653055;5267.552258058108;5290;4242.766647834337;4319.049809042006;4380.080183161508;4550.317452382169;4917.385326834145;3114.672959339282;3721.4417837324618;3793;3831.9079062467063;3838.6353296436796;1108.684025052856;1116.2186469471403;1122;1131.7566723154562;1142.4459137318395;672.7981605518324;810.5420437452821;885.7608445719619;887.454215657379;932.9020452852488;5220.686758794484;5500.9273191728025;5764.7117644117625;6051.939480605226;6164.445199932027;872.1017352127319;906.551257127726;923.7782932096383;942.5569982108398;966.6230170233614;3712.2946640138825;3962.7424217425773;4050.23045621331;4074.5517993020744;4097.282975479279;10657.253992220625;10872.565097396016;10975.615853445252;10980.835287470694;10995.34027958325;10571.312864663772;10805.40570268627;10910.672679819618;11032.669019929255;11038.448077596087;7777.805554861174;8267.173282671685;8407.789805254917;8409.74770756866;8436.620352084172;5895.587308888336;5936.673482958478;6101.694915254131;6143.262808463013;6226.097215903578;9628.085331893459;9974.850377244202;10086.8486972694;10311.22665800053;10320.535575899252;9375.578098985425;10001.74995625086;10234.846477302995;10335.431551264752;10425;6935.618540980295;7330.523515971445;7597.772066838003;7711.498752581064;7725;8240;8298.377621678277;8341.082480927214;8412.453190542596;8416.326693864354;7554.848903021799;7584.2794934481135;7600.201978792147;7684.269994350528;7689.769306920802;7388.298111679382;7408.518446300973;7422.740204092831;7501.399888009056;7519.172890982097;6725.932740672531;6994.090768200102;7019.543729657556;7071.681774320271;7108.964455177898;5026.748662566784;5044.520770526794;5048.79401579992;5055.216441451581;5195.792168313376;4402.383666286659;4592.380028696217;5143.691378517301;5171.637985341141;5183.948160518346 11:33:29 INFO - PROCESS | 1916 | |4;prototype.html;300.33176182992844;301.09670987038885;304.95552731893287;308.4802108447266;314.41989529318414;4333.913321733564;4870.85387438377;5134.9743251283735;5138.7173705446185;5168.68198609355;4911.656184067117;4948.480409556999;5039.757307997942;5145.279660847485;5196.324477817888;3929.1355901701722;3961.6632586230135;3979.4229836673667;3984.880453586397;4024.6579040781567;3824;3997.4603428537066;4013.8193781279765;4026.4966879140106;4048;3123.5627012218306;3944.5266568011734;3966.643002129808;3983.780892050936;4028.4964379452567;3237.4981877808978;3614.168980880262;3670.284294562561;3950.743201691895;3978.303796835571;3261.0650414627025;3434.8969530914114;3472.1840367513614;3505.333986542574;3511.964880351215;4342.370356298319;4639.744814035227;4865.70805751656;5019.247112933052;5054.9494505054845;4033.838646454138;4514.164879497303;4556.908861822745;4576.519465456113;4577.633789296849;5425.267588875491;5442.809501667462;5483;5504.862378440531;5529.640573362719;2976.7618590512707;3714.7213958953184;3826.7321287509885;3834.961650383488;3851.499305090348;4963.379577552806;5542.949108829718;5642.605017648766;5776.624519406268;5793.928123297162;3215.5980502437196;3792.7724336539895;3796.367561948389;3797.18360552477;3839.4432807242792;3679.1537946272506;3890.4747859038666;3899.727019108635;3906.3163946309282;3909.2049184832413;4001.8999525011527;4202.432671589358;4250.957490425056;4493.236149854532;4536.228841097021;5307.044731948209;5481.342238931354;5491.972540137273;5521.889562208733;5561.665200351887;1183.8461000069935;1192.3025030357164;1217.6529689038582;1220.6277085488834;1314.993425032888;818.5988865456003;824.4146655874276;855.7860534866284;864.222200019987;886.609891647673;4251.957480425218;4614.093121333538;5038.153443304521;5049.495050494995;5111.591072714174;5307.840764777063;5370.0333939890415;5545.805896793673;5603.2349809809775;5635.5773317001385;4737.313089601988;5008.198688209942;5012.223105418666;5030.4466508684745;5056.6713163644245;2134.722486076831;2425.108415435963;2450.840695354796;2451.8721388161644;2457.4716435965997;4263.637590804817;4701.811927522861;4818.903621927542;4834.540718631724;4874.439439464436;4184.309588917795;4185.560516145822;4404.4494438195225;4541.137183935041;4719.787609557578;4321.891952701208;4942.55517003457;5913.408659134052;5916.5742045761035;5937.495312898452;4756.6908150970075;5728.8567785804025;5731.449504970248;5743.4543718346695;5757;4919.720872573085;5176.534111929945;5254.737263136904;5631.63394379364;5683.204351390891;4914.70511769295;5844.12338149264;5920.644761314335;5937.762489500372;5952;3196.6803319667847;3813.6949044075855;3833.3674943633987;3841.327767640596;3844.9231015380096;4604.907901842012;4862.902741945212;5743.712814359349;5748.5688573358;5798.826035218782;4611.608013318905;5384.569234461155;5514.856531776849;5540.861478463071;5549.667019978814;4585.656075794395;4655.138799322136;5506;5544.7782088716;5602.215689803512;5066.240063990431;5380.704061276668;5421.647592906448;5432.701201433959;5458.9454105458435;5037.672551284301;5474.616776825584;5526.696031718133;5530.7511161997;5533.335999680065;4538.750368729751;4602.7008244464005;4784.30627558995;5155.639105262596;5434.782608695607 11:33:29 INFO - PROCESS | 1916 | |5;yui.html;657.3886285754248;761.9276168763969;787.1144961917843;804.5020409780844;810.3679130278383;15207;15845.762313565307;15876.761848572252;15905.045697258143;15976.520704378872;12143.817842732366;14096.013279070443;14226.217558034305;14334.784978225334;14910.552683419488;667.126064855039;829.709601639426;832.2052439919893;858.3262139220712;864.3085531574715;851.1658574596898;852.2329903087209;853.5732133933034;854.3293514591047;855.6021450025737;907.0566610724871;909.9808214799434;911.138973669879;911.9316051296146;913.8263729891332;702.6170736948376;884.3941899798594;902.3142411767104;912.4707669551644;915.555955361649;681.4752640466813;682.0383259603934;682.6962001909152;683.3166833166833;686.1731613405834;14925.626859328495;14963.551093467213;14967.625809354744;14983;14985.475508357264;14139.646508837259;14262.57212283633;14279.143251404948;14305.57083287503;14499;11615.780343064025;12768.80846787299;13250.86749132506;13342.332883355793;13347.132436391603;4623.2371658676275;4647.326137710047;4696.365990591261;4832.9275060874115;4868.805247790084;120378;123336.533269335;123560.38219808844;123937.74715907696;126304.36847815794;11025.779484410266;11279.379634120201;11370.317780933005;11412.942935285271;11597.826032609517;22953.655695164587;22982.425439363815;22998.19506317271;23028.539429211323;23045.654315185286;7834.7257845976255;7912.248336408032;7970.840583188304;8027.478213916193;8205.466644668077;4186.434831297796;4247.660187185018;4400.075984043387;4483.708558943658;4558.749268790249;3442.845071971767;3496.947545786815;3541.5041894134843;3541.964580354215;3545.290941811648;523.2778765303856;542.3708498142137;629.1789215074372;654.6268626882727;659.1069101367674;599.8140576421323;627.645380360095;660.978787772892;663.7743167323133;667.9279755991661;8700.869486957701;9008.729738107868;9262.536873156316;9268.490233037113;9293.907060929305;542.8832800947815;621.1366200980641;634.4446871462941;655.4428735574724;662.413763819014;3453.706434953057;4206.978965105155;4471.731696098244;4490.685652004328;4513.044040741767;102750;110571.89428105616;142350.15299694156;167746.09650372688;171161;125053;128331;130891;131433.68566314405;139302.98015728118;111838.44080779869;112207;115446.84553154529;121700.39149804371;127575.6909170887;8259.752207433787;8719.65121395137;8890.155435233642;9001.44717800305;9004.189622933967;47783.52216477791;66890.33109669035;68985.65507172432;69582.65208673924;71516.64241678758;80465;99038.5048074755;100380;103580.48209758903;104692.47653761682;11708.70728231801;12062.51749929993;12113.212641178296;12315.568955086528;12846.03654725918;13186.274754888573;13718.862811371759;13738.587842364745;13811.930940345233;13888;10329.328593641389;11321.886781132413;13730.382132803848;14161.433542658178;14573.125612463286;13689.86310136886;14026;14165;14285.42858285657;14490.420383184555;13446.596602101952;13769.586912392644;13793.24137172465;13929;14217.50238741639;9035.909640903506;11262.267952583055;11323.547058117912;11596.36220007874;11612.6516204514;9533.427994320364;11369.43152842338;11566.768664626828;11673.182877198515;11682.649520514398 11:33:29 INFO - PROCESS | 1916 | __end_tp_report 11:33:29 INFO - PROCESS | 1916 | __start_cc_report 11:33:29 INFO - PROCESS | 1916 | _x_x_mozilla_cycle_collect,1689 11:33:29 INFO - PROCESS | 1916 | __end_cc_report 11:33:29 INFO - PROCESS | 1916 | __startTimestamp1472754809296__endTimestamp 11:33:29 INFO - PROCESS | 1916 | 11:33:29 INFO - PROCESS | 1916 | ------- Summary: start ------- 11:33:29 INFO - PROCESS | 1916 | Number of tests: 6 11:33:29 INFO - PROCESS | 1916 | 11:33:29 INFO - PROCESS | 1916 | [#0] dojo.html Cycles:165 Average:5668.04 Median:5856.85 stddev:639.38 (10.9%) stddev-sans-first:613.09 11:33:29 INFO - PROCESS | 1916 | Values: 3272.0 3280.1 3318.2 3357.3 3357.7 5486.8 5569.0 5585.1 5624.9 5627.4 5572.6 5581.7 5596.3 5608.2 5608.2 5069.4 5084.6 5093.1 5164.0 5197.8 5114.8 5179.5 5195.8 5198.5 5200.7 5157.4 5161.3 5182.4 5196.3 5201.5 5215.8 5235.5 5237.9 5255.6 5290.8 5020.1 5044.0 5063.7 5151.8 5177.2 5530.5 5565.0 5610.6 5622.2 5673.9 6144.9 6164.3 6170.2 6175.5 6182.3 6053.2 6119.4 6175.1 6206.0 6215.6 5723.5 5741.4 5741.9 5756.3 5805.7 6226.2 6230.0 6243.1 6243.3 6250.7 5717.4 6058.3 6090.9 6091.9 6107.3 6183.6 6243.9 6245.0 6265.2 6271.8 6100.1 6161.8 6170.6 6182.0 6198.3 5947.6 5986.8 5989.0 6177.6 6198.2 4821.8 4831.3 4838.5 4839.5 4839.7 4323.7 4324.2 4352.8 4363.7 4369.9 5624.4 5633.4 5634.5 5660.9 5666.4 6119.8 6233.8 6243.4 6249.8 6253.9 4767.5 5453.6 5532.2 5606.2 5613.6 4994.4 5352.1 5358.4 5391.1 5413.6 6243.3 6271.0 6294.6 6296.6 6310.3 6078.9 6097.1 6204.3 6211.3 6233.9 6150.9 6179.0 6195.1 6277.6 6283.9 6131.4 6131.4 6138.9 6144.6 6149.1 6000.2 6041.9 6174.2 6195.3 6219.0 5856.1 5856.5 5957.1 5965.4 5994.5 5965.9 5974.6 5976.0 5977.9 5988.3 5785.0 5809.4 5828.1 5856.9 5857.1 6031.3 6031.5 6084.0 6106.2 6117.3 5849.2 5860.3 5861.8 5889.3 5891.6 11:33:29 INFO - PROCESS | 1916 | 11:33:29 INFO - PROCESS | 1916 | [#1] ext.html Cycles:180 Average:77033.40 Median:38233.12 stddev:175139.36 (458.1%) stddev-sans-first:175536.33 11:33:29 INFO - PROCESS | 1916 | Values: 467.9 473.4 513.3 522.1 548.8 25039.6 39807.8 40771.0 41733.8 41844.7 43408.9 43626.9 43842.3 43867.8 43947.3 9743.1 9877.3 9928.8 9939.0 9941.6 7706.7 8824.4 9035.7 9076.8 9096.1 12021.6 12264.8 12283.3 12511.4 12584.6 12269.7 12349.9 12357.3 12367.5 12400.4 10546.6 11608.1 12034.8 12042.9 12053.8 40616.8 40915.1 40958.4 41052.6 41206.2 3353.7 3358.6 3569.5 3598.3 3833.8 30961.8 31177.2 31578.1 31633.5 31862.8 2869.9 3106.7 3248.9 3267.0 3329.7 503878.0 507585.5 508750.0 511810.0 517375.0 60975.0 61210.0 61284.0 61380.4 61400.0 34567.5 65935.0 65991.0 65995.7 66000.0 15746.4 16576.8 16637.7 16652.4 16673.2 12722.4 12844.0 12848.8 12860.7 12881.3 16154.5 16560.8 19093.0 19094.8 19267.0 7609.2 7683.5 7742.8 7744.6 7756.4 770.3 916.6 966.4 1018.1 1038.0 29529.3 29939.3 30073.7 30742.5 30806.8 1038.5 1078.5 1083.4 1127.6 1131.8 14970.6 15354.5 15600.6 15953.8 16070.3 683226.0 688463.0 688926.7 689286.0 690209.0 50029.5 50835.7 59080.4 60063.7 60254.1 1318.9 1343.3 1362.4 1362.7 1369.9 30186.4 31057.8 31143.0 31902.7 32122.0 51558.5 51982.5 52020.2 52163.5 52179.2 650045.0 666033.0 666918.0 840708.0 841944.0 41008.8 41883.0 42122.0 42145.8 42407.6 46262.0 46777.3 46978.8 47323.8 47437.5 38158.0 38388.0 38741.6 39493.6 40146.2 31038.8 38083.0 38155.6 39266.8 39342.2 37178.3 37445.0 38115.0 38215.6 38250.6 32984.8 33002.7 33386.0 34151.3 34245.7 24904.9 24948.0 25065.4 25238.5 25346.4 11:33:29 INFO - PROCESS | 1916 | 11:33:29 INFO - PROCESS | 1916 | [#2] jquery.html Cycles:180 Average:33197.71 Median:12115.88 stddev:91186.69 (752.6%) stddev-sans-first:91409.12 11:33:29 INFO - PROCESS | 1916 | Values: 344.6 360.6 365.7 371.3 392.3 15301.5 15335.0 17062.5 20470.0 20987.4 17302.6 20845.3 20860.3 21125.7 21400.8 5374.3 5856.7 6153.4 6157.3 6164.9 5119.3 6094.7 6154.2 6156.4 6162.2 5878.4 6006.3 6052.9 6105.6 6119.8 6042.2 6092.7 6097.3 6099.8 6104.6 5208.4 5276.4 5482.9 5547.1 5571.0 12754.0 12795.4 12800.9 12992.3 13002.6 2097.1 2252.2 2341.6 2606.9 2795.4 9798.3 10086.3 10388.7 12579.2 14844.0 1821.9 1849.9 1850.8 1917.6 1943.6 117663.0 121922.4 123853.4 124024.0 130426.0 11129.4 11222.7 11253.7 11387.4 11570.1 14203.4 14220.9 14225.4 14230.3 14254.6 8197.5 8480.1 8515.8 8626.4 8718.2 7845.7 7905.9 8565.7 8582.8 8792.8 4278.5 4385.0 4386.6 4452.9 4470.1 1804.6 1843.4 1854.9 1858.7 1881.3 823.2 847.0 866.4 877.6 900.4 18978.2 20121.4 21806.7 21878.7 21979.6 837.5 921.3 936.4 944.3 947.5 10478.5 10969.9 11345.9 11347.4 11456.4 446481.0 457565.0 578183.0 595571.0 602913.0 105671.4 124119.4 134689.0 139789.0 140990.3 884.5 965.5 1001.6 1009.8 1011.4 18826.7 19131.5 19333.2 19432.9 19473.7 37524.2 52010.7 54067.7 54862.5 55714.0 82088.6 82890.0 83543.0 83550.0 83833.0 6942.9 7914.4 8076.4 8079.9 8204.9 10453.8 12881.4 13336.6 13407.0 13493.0 9766.8 11175.7 11208.4 11398.5 11736.5 11166.1 11266.7 11414.9 11596.6 11803.2 11487.0 11673.9 11730.4 11976.9 12019.0 7178.8 7352.2 7374.8 7480.7 7581.4 9750.7 10679.8 10684.3 11025.8 11720.6 11:33:29 INFO - PROCESS | 1916 | 11:33:29 INFO - PROCESS | 1916 | [#3] mootools.html Cycles:180 Average:5698.31 Median:5052.01 stddev:3227.67 (63.9%) stddev-sans-first:3220.03 11:33:29 INFO - PROCESS | 1916 | Values: 1329.7 1465.8 1499.6 1703.8 1879.5 7781.3 8569.0 8871.9 8877.3 9015.3 8775.4 8834.5 8866.2 8881.3 8883.8 2390.1 2420.0 2421.6 2424.6 2429.1 2116.2 2361.2 2367.8 2393.9 2412.5 2366.2 2388.0 2397.8 2404.9 2406.0 2326.5 2346.1 2349.4 2359.9 2385.2 2331.3 2367.7 2367.8 2371.7 2375.9 8647.3 8772.5 8828.6 8883.9 8913.7 5148.0 5356.7 5359.2 5364.8 5365.7 3043.5 3541.8 3673.9 3751.0 3766.4 976.2 1093.1 1098.2 1106.8 1115.3 11665.4 11728.9 11731.9 11805.9 11809.8 3843.7 4380.0 4615.4 4632.7 4644.8 9229.9 9232.8 9233.3 9236.1 9237.8 5254.8 5257.6 5260.8 5267.6 5290.0 4242.8 4319.0 4380.1 4550.3 4917.4 3114.7 3721.4 3793.0 3831.9 3838.6 1108.7 1116.2 1122.0 1131.8 1142.4 672.8 810.5 885.8 887.5 932.9 5220.7 5500.9 5764.7 6051.9 6164.4 872.1 906.6 923.8 942.6 966.6 3712.3 3962.7 4050.2 4074.6 4097.3 10657.3 10872.6 10975.6 10980.8 10995.3 10571.3 10805.4 10910.7 11032.7 11038.4 7777.8 8267.2 8407.8 8409.7 8436.6 5895.6 5936.7 6101.7 6143.3 6226.1 9628.1 9974.9 10086.8 10311.2 10320.5 9375.6 10001.7 10234.8 10335.4 10425.0 6935.6 7330.5 7597.8 7711.5 7725.0 8240.0 8298.4 8341.1 8412.5 8416.3 7554.8 7584.3 7600.2 7684.3 7689.8 7388.3 7408.5 7422.7 7501.4 7519.2 6725.9 6994.1 7019.5 7071.7 7109.0 5026.7 5044.5 5048.8 5055.2 5195.8 4402.4 4592.4 5143.7 5171.6 5183.9 11:33:29 INFO - PROCESS | 1916 | 11:33:29 INFO - PROCESS | 1916 | [#4] prototype.html Cycles:180 Average:4346.14 Median:4770.50 stddev:1355.83 (28.4%) stddev-sans-first:1325.19 11:33:29 INFO - PROCESS | 1916 | Values: 300.3 301.1 305.0 308.5 314.4 4333.9 4870.9 5135.0 5138.7 5168.7 4911.7 4948.5 5039.8 5145.3 5196.3 3929.1 3961.7 3979.4 3984.9 4024.7 3824.0 3997.5 4013.8 4026.5 4048.0 3123.6 3944.5 3966.6 3983.8 4028.5 3237.5 3614.2 3670.3 3950.7 3978.3 3261.1 3434.9 3472.2 3505.3 3512.0 4342.4 4639.7 4865.7 5019.2 5054.9 4033.8 4514.2 4556.9 4576.5 4577.6 5425.3 5442.8 5483.0 5504.9 5529.6 2976.8 3714.7 3826.7 3835.0 3851.5 4963.4 5542.9 5642.6 5776.6 5793.9 3215.6 3792.8 3796.4 3797.2 3839.4 3679.2 3890.5 3899.7 3906.3 3909.2 4001.9 4202.4 4251.0 4493.2 4536.2 5307.0 5481.3 5492.0 5521.9 5561.7 1183.8 1192.3 1217.7 1220.6 1315.0 818.6 824.4 855.8 864.2 886.6 4252.0 4614.1 5038.2 5049.5 5111.6 5307.8 5370.0 5545.8 5603.2 5635.6 4737.3 5008.2 5012.2 5030.4 5056.7 2134.7 2425.1 2450.8 2451.9 2457.5 4263.6 4701.8 4818.9 4834.5 4874.4 4184.3 4185.6 4404.4 4541.1 4719.8 4321.9 4942.6 5913.4 5916.6 5937.5 4756.7 5728.9 5731.4 5743.5 5757.0 4919.7 5176.5 5254.7 5631.6 5683.2 4914.7 5844.1 5920.6 5937.8 5952.0 3196.7 3813.7 3833.4 3841.3 3844.9 4604.9 4862.9 5743.7 5748.6 5798.8 4611.6 5384.6 5514.9 5540.9 5549.7 4585.7 4655.1 5506.0 5544.8 5602.2 5066.2 5380.7 5421.6 5432.7 5458.9 5037.7 5474.6 5526.7 5530.8 5533.3 4538.8 4602.7 4784.3 5155.6 5434.8 11:33:29 INFO - PROCESS | 1916 | 11:33:29 INFO - PROCESS | 1916 | [#5] yui.html Cycles:180 Average:25505.57 Median:13244.25 stddev:40960.06 (309.3%) stddev-sans-first:41032.47 11:33:29 INFO - PROCESS | 1916 | Values: 657.4 761.9 787.1 804.5 810.4 15207.0 15845.8 15876.8 15905.0 15976.5 12143.8 14096.0 14226.2 14334.8 14910.6 667.1 829.7 832.2 858.3 864.3 851.2 852.2 853.6 854.3 855.6 907.1 910.0 911.1 911.9 913.8 702.6 884.4 902.3 912.5 915.6 681.5 682.0 682.7 683.3 686.2 14925.6 14963.6 14967.6 14983.0 14985.5 14139.6 14262.6 14279.1 14305.6 14499.0 11615.8 12768.8 13250.9 13342.3 13347.1 4623.2 4647.3 4696.4 4832.9 4868.8 120378.0 123336.5 123560.4 123937.7 126304.4 11025.8 11279.4 11370.3 11412.9 11597.8 22953.7 22982.4 22998.2 23028.5 23045.7 7834.7 7912.2 7970.8 8027.5 8205.5 4186.4 4247.7 4400.1 4483.7 4558.7 3442.8 3496.9 3541.5 3542.0 3545.3 523.3 542.4 629.2 654.6 659.1 599.8 627.6 661.0 663.8 667.9 8700.9 9008.7 9262.5 9268.5 9293.9 542.9 621.1 634.4 655.4 662.4 3453.7 4207.0 4471.7 4490.7 4513.0 102750.0 110571.9 142350.2 167746.1 171161.0 125053.0 128331.0 130891.0 131433.7 139303.0 111838.4 112207.0 115446.8 121700.4 127575.7 8259.8 8719.7 8890.2 9001.4 9004.2 47783.5 66890.3 68985.7 69582.7 71516.6 80465.0 99038.5 100380.0 103580.5 104692.5 11708.7 12062.5 12113.2 12315.6 12846.0 13186.3 13718.9 13738.6 13811.9 13888.0 10329.3 11321.9 13730.4 14161.4 14573.1 13689.9 14026.0 14165.0 14285.4 14490.4 13446.6 13769.6 13793.2 13929.0 14217.5 9035.9 11262.3 11323.5 11596.4 11612.7 9533.4 11369.4 11566.8 11673.2 11682.6 11:33:29 INFO - PROCESS | 1916 | -------- Summary: end -------- 11:33:29 INFO - PROCESS | 1916 | 11:33:29 INFO - TEST-INFO | 1916: exit 0 11:33:29 INFO - TEST-OK | dromaeo_css | took 1118084ms 11:33:29 INFO - TEST-START | kraken 11:33:29 INFO - operating with platform_type : mac_ 11:33:29 INFO - Initialising browser for kraken test... 11:33:29 INFO - TEST-INFO | started process 1920 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/tmpsEiB0s/profile http://localhost:49426/getInfo.html) 11:33:32 INFO - PROCESS | 1920 | __metrics Screen width/height:1600/1200 11:33:32 INFO - PROCESS | 1920 | colorDepth:24 11:33:32 INFO - PROCESS | 1920 | Browser inner width/height: 1024/689 11:33:32 INFO - PROCESS | 1920 | __metrics 11:33:32 INFO - PROCESS | 1920 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 11:33:32 INFO - PROCESS | 1920 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:33:32 INFO - TEST-INFO | 1920: exit 0 11:33:32 INFO - Browser initialized. 11:33:32 INFO - Running cycle 1/1 for kraken test... 11:33:32 INFO - TEST-INFO | started process 1921 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/tmpsEiB0s/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:33:44 INFO - PROCESS | 1921 | RSS: Main: 233996288 11:33:44 INFO - PROCESS | 1921 | 11:33:47 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:49426/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49426/tests/kraken/driver.html?testName=audio-beat-detection) 11:33:48 INFO - PROCESS | 1921 | RSS: Main: 292737024 11:33:48 INFO - PROCESS | 1921 | 11:33:50 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:49426/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49426/tests/kraken/driver.html?testName=audio-dft) 11:33:51 INFO - PROCESS | 1921 | RSS: Main: 289181696 11:33:51 INFO - PROCESS | 1921 | 11:33:55 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:49426/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49426/tests/kraken/driver.html?testName=audio-fft) 11:33:55 INFO - PROCESS | 1921 | RSS: Main: 377843712 11:33:55 INFO - PROCESS | 1921 | 11:33:57 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:49426/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49426/tests/kraken/driver.html?testName=audio-oscillator) 11:33:58 INFO - PROCESS | 1921 | RSS: Main: 281616384 11:33:58 INFO - PROCESS | 1921 | 11:34:00 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:49426/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49426/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:34:01 INFO - PROCESS | 1921 | RSS: Main: 285569024 11:34:01 INFO - PROCESS | 1921 | 11:34:03 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:49426/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49426/tests/kraken/driver.html?testName=imaging-darkroom) 11:34:04 INFO - PROCESS | 1921 | RSS: Main: 324681728 11:34:04 INFO - PROCESS | 1921 | 11:34:07 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:49426/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49426/tests/kraken/driver.html?testName=imaging-desaturate) 11:34:07 INFO - PROCESS | 1921 | RSS: Main: 323506176 11:34:07 INFO - PROCESS | 1921 | 11:34:10 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:49426/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49426/tests/kraken/driver.html?testName=json-parse-financial) 11:34:11 INFO - PROCESS | 1921 | RSS: Main: 324431872 11:34:11 INFO - PROCESS | 1921 | 11:34:12 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:49426/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49426/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:34:13 INFO - PROCESS | 1921 | RSS: Main: 298504192 11:34:13 INFO - PROCESS | 1921 | 11:34:15 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:49426/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49426/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:34:15 INFO - PROCESS | 1921 | RSS: Main: 306192384 11:34:15 INFO - PROCESS | 1921 | 11:34:18 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:49426/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49426/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:34:18 INFO - PROCESS | 1921 | RSS: Main: 310169600 11:34:18 INFO - PROCESS | 1921 | 11:34:21 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:49426/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49426/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:34:22 INFO - PROCESS | 1921 | RSS: Main: 323698688 11:34:22 INFO - PROCESS | 1921 | 11:34:25 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:49426/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49426/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:34:25 INFO - PROCESS | 1921 | RSS: Main: 319983616 11:34:25 INFO - PROCESS | 1921 | 11:34:27 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:49426/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49426/tests/kraken/driver.html?testName=ai-astar) 11:34:27 INFO - PROCESS | 1921 | RSS: Main: 363454464 11:34:27 INFO - PROCESS | 1921 | 11:34:27 INFO - PROCESS | 1921 | __start_tp_report 11:34:27 INFO - PROCESS | 1921 | _x_x_mozilla_page_load 11:34:27 INFO - PROCESS | 1921 | _x_x_mozilla_page_load_details 11:34:27 INFO - PROCESS | 1921 | |i|pagename|runs| 11:34:27 INFO - PROCESS | 1921 | |0;ai-astar;112;111;115;113;111;111;110;110;111;122 11:34:27 INFO - PROCESS | 1921 | |1;audio-beat-detection;147;107;134;163;105;131;129;107;161;118 11:34:27 INFO - PROCESS | 1921 | |2;audio-dft;185;184;184;181;182;185;181;180;183;182 11:34:27 INFO - PROCESS | 1921 | |3;audio-fft;75;75;76;158;58;57;57;67;145;58 11:34:27 INFO - PROCESS | 1921 | |4;audio-oscillator;82;83;131;71;70;81;122;69;69;115 11:34:27 INFO - PROCESS | 1921 | |5;imaging-gaussian-blur;86;83;86;84;86;83;88;83;86;83 11:34:27 INFO - PROCESS | 1921 | |6;imaging-darkroom;93;93;93;92;93;92;93;92;92;92 11:34:27 INFO - PROCESS | 1921 | |7;imaging-desaturate;85;84;84;85;83;85;85;83;83;87 11:34:27 INFO - PROCESS | 1921 | |8;json-parse-financial;56;55;57;56;54;54;55;55;55;54 11:34:27 INFO - PROCESS | 1921 | |9;json-stringify-tinderbox;68;64;68;72;74;78;72;71;70;71 11:34:27 INFO - PROCESS | 1921 | |10;stanford-crypto-aes;90;87;88;88;84;85;88;89;86;85 11:34:27 INFO - PROCESS | 1921 | |11;stanford-crypto-ccm;140;138;155;151;149;135;152;143;189;147 11:34:27 INFO - PROCESS | 1921 | |12;stanford-crypto-pbkdf2;169;168;169;171;170;179;168;169;167;168 11:34:27 INFO - PROCESS | 1921 | |13;stanford-crypto-sha256-iterative;62;81;84;88;67;66;66;66;66;65 11:34:27 INFO - PROCESS | 1921 | __end_tp_report 11:34:27 INFO - PROCESS | 1921 | __start_cc_report 11:34:27 INFO - PROCESS | 1921 | _x_x_mozilla_cycle_collect,2051 11:34:27 INFO - PROCESS | 1921 | __end_cc_report 11:34:27 INFO - PROCESS | 1921 | __startTimestamp1472754867888__endTimestamp 11:34:27 INFO - PROCESS | 1921 | 11:34:27 INFO - PROCESS | 1921 | ------- Summary: start ------- 11:34:27 INFO - PROCESS | 1921 | Number of tests: 14 11:34:27 INFO - PROCESS | 1921 | 11:34:27 INFO - PROCESS | 1921 | [#0] ai-astar Cycles:10 Average:112.60 Median:111.50 stddev:3.63 (3.3%) stddev-sans-first:3.84 11:34:27 INFO - PROCESS | 1921 | Values: 112.0 111.0 115.0 113.0 111.0 111.0 110.0 110.0 111.0 122.0 11:34:27 INFO - PROCESS | 1921 | 11:34:27 INFO - PROCESS | 1921 | [#1] audio-beat-detection Cycles:10 Average:130.20 Median:132.50 stddev:21.56 (16.3%) stddev-sans-first:21.99 11:34:27 INFO - PROCESS | 1921 | Values: 147.0 107.0 134.0 163.0 105.0 131.0 129.0 107.0 161.0 118.0 11:34:27 INFO - PROCESS | 1921 | 11:34:27 INFO - PROCESS | 1921 | [#2] audio-dft Cycles:10 Average:182.70 Median:183.50 stddev:1.77 (1.0%) stddev-sans-first:1.67 11:34:27 INFO - PROCESS | 1921 | Values: 185.0 184.0 184.0 181.0 182.0 185.0 181.0 180.0 183.0 182.0 11:34:27 INFO - PROCESS | 1921 | 11:34:27 INFO - PROCESS | 1921 | [#3] audio-fft Cycles:10 Average:82.60 Median:62.50 stddev:37.27 (59.6%) stddev-sans-first:39.43 11:34:27 INFO - PROCESS | 1921 | Values: 75.0 75.0 76.0 158.0 58.0 57.0 57.0 67.0 145.0 58.0 11:34:27 INFO - PROCESS | 1921 | 11:34:27 INFO - PROCESS | 1921 | [#4] audio-oscillator Cycles:10 Average:89.30 Median:70.50 stddev:23.95 (34.0%) stddev-sans-first:25.26 11:34:27 INFO - PROCESS | 1921 | Values: 82.0 83.0 131.0 71.0 70.0 81.0 122.0 69.0 69.0 115.0 11:34:27 INFO - PROCESS | 1921 | 11:34:27 INFO - PROCESS | 1921 | [#5] imaging-gaussian-blur Cycles:10 Average:84.80 Median:86.00 stddev:1.81 (2.1%) stddev-sans-first:1.87 11:34:27 INFO - PROCESS | 1921 | Values: 86.0 83.0 86.0 84.0 86.0 83.0 88.0 83.0 86.0 83.0 11:34:27 INFO - PROCESS | 1921 | 11:34:27 INFO - PROCESS | 1921 | [#6] imaging-darkroom Cycles:10 Average:92.50 Median:93.00 stddev:0.53 (0.6%) stddev-sans-first:0.53 11:34:27 INFO - PROCESS | 1921 | Values: 93.0 93.0 93.0 92.0 93.0 92.0 93.0 92.0 92.0 92.0 11:34:27 INFO - PROCESS | 1921 | 11:34:27 INFO - PROCESS | 1921 | [#7] imaging-desaturate Cycles:10 Average:84.40 Median:85.00 stddev:1.26 (1.5%) stddev-sans-first:1.32 11:34:27 INFO - PROCESS | 1921 | Values: 85.0 84.0 84.0 85.0 83.0 85.0 85.0 83.0 83.0 87.0 11:34:27 INFO - PROCESS | 1921 | 11:34:27 INFO - PROCESS | 1921 | [#8] json-parse-financial Cycles:10 Average:55.10 Median:55.00 stddev:0.99 (1.8%) stddev-sans-first:1.00 11:34:27 INFO - PROCESS | 1921 | Values: 56.0 55.0 57.0 56.0 54.0 54.0 55.0 55.0 55.0 54.0 11:34:27 INFO - PROCESS | 1921 | 11:34:27 INFO - PROCESS | 1921 | [#9] json-stringify-tinderbox Cycles:10 Average:70.80 Median:71.50 stddev:3.77 (5.3%) stddev-sans-first:3.86 11:34:27 INFO - PROCESS | 1921 | Values: 68.0 64.0 68.0 72.0 74.0 78.0 72.0 71.0 70.0 71.0 11:34:27 INFO - PROCESS | 1921 | 11:34:27 INFO - PROCESS | 1921 | [#10] stanford-crypto-aes Cycles:10 Average:87.00 Median:88.00 stddev:1.94 (2.2%) stddev-sans-first:1.73 11:34:27 INFO - PROCESS | 1921 | Values: 90.0 87.0 88.0 88.0 84.0 85.0 88.0 89.0 86.0 85.0 11:34:27 INFO - PROCESS | 1921 | 11:34:27 INFO - PROCESS | 1921 | [#11] stanford-crypto-ccm Cycles:10 Average:149.90 Median:150.00 stddev:15.20 (10.1%) stddev-sans-first:15.69 11:34:27 INFO - PROCESS | 1921 | Values: 140.0 138.0 155.0 151.0 149.0 135.0 152.0 143.0 189.0 147.0 11:34:27 INFO - PROCESS | 1921 | 11:34:27 INFO - PROCESS | 1921 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.80 Median:169.00 stddev:3.43 (2.0%) stddev-sans-first:3.62 11:34:27 INFO - PROCESS | 1921 | Values: 169.0 168.0 169.0 171.0 170.0 179.0 168.0 169.0 167.0 168.0 11:34:27 INFO - PROCESS | 1921 | 11:34:27 INFO - PROCESS | 1921 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.10 Median:66.50 stddev:9.37 (14.1%) stddev-sans-first:9.35 11:34:27 INFO - PROCESS | 1921 | Values: 62.0 81.0 84.0 88.0 67.0 66.0 66.0 66.0 66.0 65.0 11:34:27 INFO - PROCESS | 1921 | -------- Summary: end -------- 11:34:27 INFO - PROCESS | 1921 | 11:34:28 INFO - TEST-INFO | 1921: exit 0 11:34:28 INFO - TEST-OK | kraken | took 58819ms 11:34:28 INFO - SUITE-END | took 1176s 11:34:29 INFO - Completed test suite (00:19:37) 11:34:29 INFO - javascript benchmark 11:34:29 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3271.9509207361893, 3280.1143691203383, 3318.1704573856523, 3357.3117510910265, 3357.714594259488, 5486.780528778854, 5569.0254205513975, 5585.134304182848, 5624.915626265604, 5627.352854421738, 5572.637778544392, 5581.665100093997, 5596.328440587125, 5608.186812912125, 5608.2428872102255, 5069.391672999236, 5084.644074914758, 5093.134167191568, 5164.04465173944, 5197.792088316482, 5114.846554603368, 5179.482051794828, 5195.792168313282, 5198.45416231296, 5200.713960732158, 5157.355330583677, 5161.251618515313, 5182.352205974254, 5196.324477817879, 5201.453847346031, 5215.791368345281, 5235.528802407802, 5237.869053273661, 5255.605829562798, 5290.841274761763, 5020.146575082244, 5043.974780126113, 5063.746812659389, 5151.7681704323395, 5177.223416487519, 5530.474604912527, 5565.026120428909, 5610.579206559483, 5622.156676498517, 5673.943260567383, 6144.907826382608, 6164.291106522717, 6170.228721409824, 6175.505959523227, 6182.319944806068, 6053.213082299273, 6119.380693981191, 6175.104609831549, 6206.007038873804, 6215.627062376272, 5723.456271654186, 5741.425857414225, 5741.942580574224, 5756.33802112754, 5805.7387417566315, 6226.159468471789, 6229.96885015572, 6243.125962365272, 6243.250809902841, 6250.718717657716, 5717.42825717425, 6058.333583305833, 6090.9390906090375, 6091.908621370683, 6107.297660769068, 6183.592230103847, 6243.906341404882, 6245.0, 6265.185525881698, 6271.843203919847, 6100.115483254903, 6161.8459538511, 6170.629762214281, 6182.0, 6198.287196972406, 5947.583669143119, 5986.790462333797, 5988.970055149696, 6177.62934223948, 6198.163247961557, 4821.831235906727, 4831.347768051268, 4838.516148385133, 4839.516048395203, 4839.685420447659, 4323.697341186087, 4324.221640104748, 4352.847650332224, 4363.672724545671, 4369.868903932887, 5624.3813180550105, 5633.352164501053, 5634.549236061105, 5660.943390566124, 5666.433356664301, 6119.785807496716, 6233.781817636361, 6243.37566243372, 6249.750009999549, 6253.937460625336, 4767.523247675274, 5453.590980676465, 5532.225488431623, 5606.21512988182, 5613.607047506635, 4994.375703037121, 5352.090144675337, 5358.356997160287, 5391.056565101092, 5413.621046526784, 6243.282022567281, 6270.968645156745, 6294.590851594723, 6296.622202667854, 6310.337414571496, 6078.908816367846, 6097.0710051834385, 6204.286507051746, 6211.3167551570195, 6233.8779019776875, 6150.892839288971, 6179.0, 6195.132681424514, 6277.5605707600025, 6283.905741413791, 6131.386861314011, 6131.417515336036, 6138.877222455437, 6144.56988010835, 6149.108379285068, 6000.189974353581, 6041.909371359346, 6174.197354343906, 6195.287541932736, 6219.0, 5856.0630299151935, 5856.502197313276, 5957.106434034756, 5965.403459654, 5994.520438364833, 5965.9105113422465, 5974.61165024272, 5976.013957696931, 5977.880442391041, 5988.341282459013, 5784.971075144766, 5809.390014048633, 5828.067509198508, 5856.853578660568, 5857.121431785266, 6031.336552979081, 6031.487323577545, 6084.0, 6106.2179897963915, 6117.265928088658, 5849.239598852122, 5860.326062502696, 5861.794837180678, 5889.263842019747, 5891.646501209942], "value": 5626.11554521712, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [467.93448917151596, 473.37987236719897, 513.3224144129749, 522.1228336394857, 548.7777450132697, 25039.624405633906, 39807.800960995155, 40771.0, 41733.79133104338, 41844.744657660245, 43408.91477713059, 43626.90932726683, 43842.342364864555, 43867.78066109673, 43947.3407898881, 9743.123118919279, 9877.259205559576, 9928.751781205492, 9939.006099390039, 9941.602335906555, 7706.6917323307, 8824.382293239476, 9035.683751068715, 9076.818463630723, 9096.135867092615, 12021.579244726436, 12264.81602775959, 12283.263004219776, 12511.374431278446, 12584.622461326175, 12269.730099931541, 12349.93825030869, 12357.25856448607, 12367.505299787998, 12400.379981000959, 10546.578136874516, 11608.129390295762, 12034.81947770784, 12042.879571204263, 12053.758924821455, 40616.796916015526, 40915.13594048388, 40958.38562421566, 41052.589474105174, 41206.1758764826, 3353.6814002669953, 3358.647342029101, 3569.5181150544604, 3598.2623562169692, 3833.8274777635074, 30961.845190774126, 31177.22056948572, 31578.052658420285, 31633.52549711756, 31862.840685796655, 2869.913902582926, 3106.7359274461696, 3248.886288979898, 3267.036224313833, 3329.6503867093847, 503878.0, 507585.46207269095, 508750.0, 511810.0, 517375.0, 60975.0, 61210.0, 61284.0, 61380.38619613836, 61400.0, 34567.4814877777, 65935.0, 65991.0, 65995.67002164958, 66000.0, 15746.448874289345, 16576.75134872978, 16637.667246655, 16652.417165399394, 16673.24970376336, 12722.363881805873, 12844.0, 12848.807267890988, 12860.678483037811, 12881.291528965809, 16154.515364539082, 16560.834391655928, 19093.0, 19094.809051909582, 19267.0, 7609.239076092457, 7683.500572462772, 7742.7677169685, 7744.574048427278, 7756.379489640815, 770.2951799103869, 916.5829547555846, 966.4442945306477, 1018.1040684198007, 1037.9792404151874, 29529.26176845553, 29939.251518712208, 30073.69926300753, 30742.538861917088, 30806.845965770477, 1038.4651904269308, 1078.4607696151925, 1083.3770581915273, 1127.5658871334595, 1131.8472006279046, 14970.550883473514, 15354.539363819104, 15600.609984750472, 15953.760693589606, 16070.276837542338, 683226.0, 688463.0, 688926.6627004193, 689286.0, 690209.0, 50029.49970500248, 50835.7458212714, 59080.40919590835, 60063.69968150131, 60254.0961885572, 1318.854925958144, 1343.2947702456288, 1362.3937347880099, 1362.7070179911366, 1369.9383527741275, 30186.396272074875, 31057.844710776302, 31143.0, 31902.662387650507, 32122.0, 51558.48441515537, 51982.48017519928, 52020.219696703825, 52163.47836521586, 52179.217311739594, 650045.0, 666033.0, 666918.0, 840708.0, 841944.0, 41008.79495602503, 41883.0, 42122.0, 42145.78927105345, 42407.575924240366, 46262.0, 46777.25781937505, 46978.76510617425, 47323.76338118287, 47437.52562474469, 38158.0, 38388.0, 38741.61258387493, 39493.60506395014, 40146.19707605773, 31038.844805775825, 38083.0, 38155.618443815205, 39266.80366598149, 39342.21315573615, 37178.25643487169, 37445.0, 38115.0, 38215.61784382121, 38250.617493824706, 32984.83507582447, 33002.66997329996, 33385.99842004647, 34151.316973659894, 34245.657543425245, 24904.875475622506, 24948.0020799166, 25065.373365666004, 25238.53554916906, 25346.366340841625], "value": 20793.432714284096, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [344.64501563389683, 360.5799243880879, 365.7275329879239, 371.2760117770347, 392.33695055356446, 15301.464448744297, 15335.0, 17062.488125356245, 20469.99999999998, 20987.370378888656, 17302.56743581411, 20845.270415535422, 20860.269890553835, 21125.683114753254, 21400.785992140074, 5374.301340825707, 5856.736446859891, 6153.446189842914, 6157.322694503601, 6164.938350616481, 5119.334486516747, 6094.725737341831, 6154.230721159855, 6156.445919867211, 6162.168107305523, 5878.382769809173, 6006.309274433431, 6052.909206361908, 6105.633661980273, 6119.816405507842, 6042.244719410074, 6092.66490343031, 6097.3292937776805, 6099.817005489843, 6104.603200791934, 5208.427073021964, 5276.393214780311, 5482.945170548283, 5547.11246200606, 5570.969370666439, 12754.0, 12795.424205910756, 12800.935995319964, 12992.285424301659, 13002.60992170227, 2097.119209931817, 2252.2004688335733, 2341.555104530151, 2606.908758193454, 2795.35706787438, 9798.314118011742, 10086.344387614856, 10388.688339349756, 12579.182353147107, 14844.0, 1821.9180136893872, 1849.861260405475, 1850.7964123946356, 1917.587718640499, 1943.6112777444425, 117663.0, 121922.39038804927, 123853.38073309576, 124024.0, 130426.0, 11129.443527823576, 11222.66332010041, 11253.71865703364, 11387.37369444672, 11570.132240082028, 14203.360848761831, 14220.928895355457, 14225.359858806378, 14230.28848557568, 14254.572362829133, 8197.508149511048, 8480.077822556645, 8515.829683406297, 8626.439281446686, 8718.215360617574, 7845.686172553148, 7905.881411778828, 8565.743027709179, 8582.785430364165, 8792.824143517222, 4278.465191851019, 4384.978075109604, 4386.605205531518, 4452.933206001912, 4470.105978804252, 1804.630050839601, 1843.3548258109554, 1854.8794328368613, 1858.7026075828073, 1881.294514557041, 823.2097186700835, 846.9540117954322, 866.441145461174, 877.6006916852816, 900.4147304252289, 18978.240870365305, 20121.39635810928, 21806.672899906513, 21878.671819922714, 21979.560408791735, 837.539353355652, 921.3366376209119, 936.3913456253354, 944.2917811641269, 947.5357075033185, 10478.528466219039, 10969.945150274198, 11345.943270283597, 11347.432628368548, 11456.427178641035, 446481.0, 457565.0, 578183.0, 595571.0, 602913.0, 105671.41492877612, 124119.3794031024, 134689.0, 139789.0, 140990.2950485241, 884.4604791077437, 965.546193289139, 1001.5943542865151, 1009.7879445316419, 1011.4335971855637, 18826.717599236297, 19131.521711957314, 19333.226670933003, 19432.902835485733, 19473.707894381878, 37524.249515010095, 52010.65952032092, 54067.72966135144, 54862.45137548733, 55714.0, 82088.58955705183, 82890.0, 83543.0, 83550.0, 83833.04056212209, 6942.8611427772175, 7914.44598878073, 8076.394270429624, 8079.919200808152, 8204.917950820654, 10453.843192352273, 12881.35593220354, 13336.599902002956, 13406.994475414187, 13493.0, 9766.75583110428, 11175.745107927783, 11208.383538905438, 11398.544058237578, 11736.530538778694, 11166.050885674484, 11266.66200014001, 11414.885851141382, 11596.641165139234, 11803.173777835469, 11487.0, 11673.883261167279, 11730.354830484064, 11976.940115299367, 12019.0, 7178.820529486805, 7352.191258961403, 7374.815629609303, 7480.738174164096, 7581.355584775135, 9750.658726944523, 10679.786404272027, 10684.305520141166, 11025.83461248066, 11720.589779357682], "value": 9343.813260606405, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1329.667583104224, 1465.846086160953, 1499.6325900154463, 1703.812580616133, 1879.5395128193586, 7781.2996830285265, 8568.957155214222, 8871.911280887198, 8877.334199934998, 9015.278777697786, 8775.429597076201, 8834.514101724419, 8866.202041816236, 8881.289496840254, 8883.777905552364, 2390.139549762089, 2419.9758002420012, 2421.6367544868235, 2424.599941009731, 2429.137656132074, 2116.195845578678, 2361.197192954395, 2367.7750613691674, 2393.85636861788, 2412.4571971306405, 2366.171839856045, 2387.9761202387926, 2397.8441401308946, 2404.939876503093, 2406.0496104038893, 2326.5230627721276, 2346.096752750195, 2349.4243910242017, 2359.8584084954787, 2385.153270588933, 2331.2773040357374, 2367.6685264063044, 2367.7513861044513, 2371.67982322386, 2375.8930848111877, 8647.264982476498, 8772.517511536864, 8828.558572071435, 8883.866741998876, 8913.688020919237, 5147.974260128713, 5356.6518176318805, 5359.169328754049, 5364.785408583652, 5365.678059316453, 3043.45217860785, 3541.8406171722336, 3673.8897833064784, 3751.0, 3766.3597188478025, 976.1507488484992, 1093.0763504838399, 1098.1653943003296, 1106.839508271304, 1115.252780636975, 11665.358405287705, 11728.88271117295, 11731.94134029333, 11805.881941180563, 11809.763804723943, 3843.6925045996813, 4380.0, 4615.423072115986, 4632.675712700078, 4644.790984405709, 9229.907700923039, 9232.769180770534, 9233.307501937381, 9236.07639236071, 9237.769055773659, 5254.7635356409055, 5257.631965762436, 5260.763265653055, 5267.552258058108, 5290.0, 4242.766647834337, 4319.049809042006, 4380.080183161508, 4550.317452382169, 4917.385326834145, 3114.672959339282, 3721.4417837324618, 3793.0, 3831.9079062467063, 3838.6353296436796, 1108.684025052856, 1116.2186469471403, 1122.0, 1131.7566723154562, 1142.4459137318395, 672.7981605518324, 810.5420437452821, 885.7608445719619, 887.454215657379, 932.9020452852488, 5220.686758794484, 5500.9273191728025, 5764.7117644117625, 6051.939480605226, 6164.445199932027, 872.1017352127319, 906.551257127726, 923.7782932096383, 942.5569982108398, 966.6230170233614, 3712.2946640138825, 3962.7424217425773, 4050.23045621331, 4074.5517993020744, 4097.282975479279, 10657.253992220625, 10872.565097396016, 10975.615853445252, 10980.835287470694, 10995.34027958325, 10571.312864663772, 10805.40570268627, 10910.672679819618, 11032.669019929255, 11038.448077596087, 7777.805554861174, 8267.173282671685, 8407.789805254917, 8409.74770756866, 8436.620352084172, 5895.587308888336, 5936.673482958478, 6101.694915254131, 6143.262808463013, 6226.097215903578, 9628.085331893459, 9974.850377244202, 10086.8486972694, 10311.22665800053, 10320.535575899252, 9375.578098985425, 10001.74995625086, 10234.846477302995, 10335.431551264752, 10425.0, 6935.618540980295, 7330.523515971445, 7597.772066838003, 7711.498752581064, 7725.0, 8240.0, 8298.377621678277, 8341.082480927214, 8412.453190542596, 8416.326693864354, 7554.848903021799, 7584.2794934481135, 7600.201978792147, 7684.269994350528, 7689.769306920802, 7388.298111679382, 7408.518446300973, 7422.740204092831, 7501.399888009056, 7519.172890982097, 6725.932740672531, 6994.090768200102, 7019.543729657556, 7071.681774320271, 7108.964455177898, 5026.748662566784, 5044.520770526794, 5048.79401579992, 5055.216441451581, 5195.792168313376, 4402.383666286659, 4592.380028696217, 5143.691378517301, 5171.637985341141, 5183.948160518346], "value": 4551.112598598406, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [300.33176182992844, 301.09670987038885, 304.95552731893287, 308.4802108447266, 314.41989529318414, 4333.913321733564, 4870.85387438377, 5134.9743251283735, 5138.7173705446185, 5168.68198609355, 4911.656184067117, 4948.480409556999, 5039.757307997942, 5145.279660847485, 5196.324477817888, 3929.1355901701722, 3961.6632586230135, 3979.4229836673667, 3984.880453586397, 4024.6579040781567, 3824.0, 3997.4603428537066, 4013.8193781279765, 4026.4966879140106, 4048.0, 3123.5627012218306, 3944.5266568011734, 3966.643002129808, 3983.780892050936, 4028.4964379452567, 3237.4981877808978, 3614.168980880262, 3670.284294562561, 3950.743201691895, 3978.303796835571, 3261.0650414627025, 3434.8969530914114, 3472.1840367513614, 3505.333986542574, 3511.964880351215, 4342.370356298319, 4639.744814035227, 4865.70805751656, 5019.247112933052, 5054.9494505054845, 4033.838646454138, 4514.164879497303, 4556.908861822745, 4576.519465456113, 4577.633789296849, 5425.267588875491, 5442.809501667462, 5483.0, 5504.862378440531, 5529.640573362719, 2976.7618590512707, 3714.7213958953184, 3826.7321287509885, 3834.961650383488, 3851.499305090348, 4963.379577552806, 5542.949108829718, 5642.605017648766, 5776.624519406268, 5793.928123297162, 3215.5980502437196, 3792.7724336539895, 3796.367561948389, 3797.18360552477, 3839.4432807242792, 3679.1537946272506, 3890.4747859038666, 3899.727019108635, 3906.3163946309282, 3909.2049184832413, 4001.8999525011527, 4202.432671589358, 4250.957490425056, 4493.236149854532, 4536.228841097021, 5307.044731948209, 5481.342238931354, 5491.972540137273, 5521.889562208733, 5561.665200351887, 1183.8461000069935, 1192.3025030357164, 1217.6529689038582, 1220.6277085488834, 1314.993425032888, 818.5988865456003, 824.4146655874276, 855.7860534866284, 864.222200019987, 886.609891647673, 4251.957480425218, 4614.093121333538, 5038.153443304521, 5049.495050494995, 5111.591072714174, 5307.840764777063, 5370.0333939890415, 5545.805896793673, 5603.2349809809775, 5635.5773317001385, 4737.313089601988, 5008.198688209942, 5012.223105418666, 5030.4466508684745, 5056.6713163644245, 2134.722486076831, 2425.108415435963, 2450.840695354796, 2451.8721388161644, 2457.4716435965997, 4263.637590804817, 4701.811927522861, 4818.903621927542, 4834.540718631724, 4874.439439464436, 4184.309588917795, 4185.560516145822, 4404.4494438195225, 4541.137183935041, 4719.787609557578, 4321.891952701208, 4942.55517003457, 5913.408659134052, 5916.5742045761035, 5937.495312898452, 4756.6908150970075, 5728.8567785804025, 5731.449504970248, 5743.4543718346695, 5757.0, 4919.720872573085, 5176.534111929945, 5254.737263136904, 5631.63394379364, 5683.204351390891, 4914.70511769295, 5844.12338149264, 5920.644761314335, 5937.762489500372, 5952.0, 3196.6803319667847, 3813.6949044075855, 3833.3674943633987, 3841.327767640596, 3844.9231015380096, 4604.907901842012, 4862.902741945212, 5743.712814359349, 5748.5688573358, 5798.826035218782, 4611.608013318905, 5384.569234461155, 5514.856531776849, 5540.861478463071, 5549.667019978814, 4585.656075794395, 4655.138799322136, 5506.0, 5544.7782088716, 5602.215689803512, 5066.240063990431, 5380.704061276668, 5421.647592906448, 5432.701201433959, 5458.9454105458435, 5037.672551284301, 5474.616776825584, 5526.696031718133, 5530.7511161997, 5533.335999680065, 4538.750368729751, 4602.7008244464005, 4784.30627558995, 5155.639105262596, 5434.782608695607], "value": 3916.3901564059975, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [657.3886285754248, 761.9276168763969, 787.1144961917843, 804.5020409780844, 810.3679130278383, 15207.0, 15845.762313565307, 15876.761848572252, 15905.045697258143, 15976.520704378872, 12143.817842732366, 14096.013279070443, 14226.217558034305, 14334.784978225334, 14910.552683419488, 667.126064855039, 829.709601639426, 832.2052439919893, 858.3262139220712, 864.3085531574715, 851.1658574596898, 852.2329903087209, 853.5732133933034, 854.3293514591047, 855.6021450025737, 907.0566610724871, 909.9808214799434, 911.138973669879, 911.9316051296146, 913.8263729891332, 702.6170736948376, 884.3941899798594, 902.3142411767104, 912.4707669551644, 915.555955361649, 681.4752640466813, 682.0383259603934, 682.6962001909152, 683.3166833166833, 686.1731613405834, 14925.626859328495, 14963.551093467213, 14967.625809354744, 14983.0, 14985.475508357264, 14139.646508837259, 14262.57212283633, 14279.143251404948, 14305.57083287503, 14499.0, 11615.780343064025, 12768.80846787299, 13250.86749132506, 13342.332883355793, 13347.132436391603, 4623.2371658676275, 4647.326137710047, 4696.365990591261, 4832.9275060874115, 4868.805247790084, 120378.0, 123336.533269335, 123560.38219808844, 123937.74715907696, 126304.36847815794, 11025.779484410266, 11279.379634120201, 11370.317780933005, 11412.942935285271, 11597.826032609517, 22953.655695164587, 22982.425439363815, 22998.19506317271, 23028.539429211323, 23045.654315185286, 7834.7257845976255, 7912.248336408032, 7970.840583188304, 8027.478213916193, 8205.466644668077, 4186.434831297796, 4247.660187185018, 4400.075984043387, 4483.708558943658, 4558.749268790249, 3442.845071971767, 3496.947545786815, 3541.5041894134843, 3541.964580354215, 3545.290941811648, 523.2778765303856, 542.3708498142137, 629.1789215074372, 654.6268626882727, 659.1069101367674, 599.8140576421323, 627.645380360095, 660.978787772892, 663.7743167323133, 667.9279755991661, 8700.869486957701, 9008.729738107868, 9262.536873156316, 9268.490233037113, 9293.907060929305, 542.8832800947815, 621.1366200980641, 634.4446871462941, 655.4428735574724, 662.413763819014, 3453.706434953057, 4206.978965105155, 4471.731696098244, 4490.685652004328, 4513.044040741767, 102750.0, 110571.89428105616, 142350.15299694156, 167746.09650372688, 171161.0, 125053.0, 128331.0, 130891.0, 131433.68566314405, 139302.98015728118, 111838.44080779869, 112207.0, 115446.84553154529, 121700.39149804371, 127575.6909170887, 8259.752207433787, 8719.65121395137, 8890.155435233642, 9001.44717800305, 9004.189622933967, 47783.52216477791, 66890.33109669035, 68985.65507172432, 69582.65208673924, 71516.64241678758, 80465.0, 99038.5048074755, 100380.0, 103580.48209758903, 104692.47653761682, 11708.70728231801, 12062.51749929993, 12113.212641178296, 12315.568955086528, 12846.03654725918, 13186.274754888573, 13718.862811371759, 13738.587842364745, 13811.930940345233, 13888.0, 10329.328593641389, 11321.886781132413, 13730.382132803848, 14161.433542658178, 14573.125612463286, 13689.86310136886, 14026.0, 14165.0, 14285.42858285657, 14490.420383184555, 13446.596602101952, 13769.586912392644, 13793.24137172465, 13929.0, 14217.50238741639, 9035.909640903506, 11262.267952583055, 11323.547058117912, 11596.36220007874, 11612.6516204514, 9533.427994320364, 11369.43152842338, 11566.768664626828, 11673.182877198515, 11682.649520514398], "value": 7875.47835590171, "unit": "score"}], "name": "dromaeo_css", "value": 7316.974763433804, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 111.0, 115.0, 113.0, 111.0, 111.0, 110.0, 110.0, 111.0, 122.0], "value": 112.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 107.0, 134.0, 163.0, 105.0, 131.0, 129.0, 107.0, 161.0, 118.0], "value": 130.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 184.0, 184.0, 181.0, 182.0, 185.0, 181.0, 180.0, 183.0, 182.0], "value": 182.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 76.0, 158.0, 58.0, 57.0, 57.0, 67.0, 145.0, 58.0], "value": 82.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 131.0, 71.0, 70.0, 81.0, 122.0, 69.0, 69.0, 115.0], "value": 89.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 83.0, 86.0, 84.0, 86.0, 83.0, 88.0, 83.0, 86.0, 83.0], "value": 84.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 93.0, 92.0, 93.0, 92.0, 93.0, 92.0, 92.0, 92.0], "value": 92.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 84.0, 85.0, 83.0, 85.0, 85.0, 83.0, 83.0, 87.0], "value": 84.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 57.0, 56.0, 54.0, 54.0, 55.0, 55.0, 55.0, 54.0], "value": 55.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 64.0, 68.0, 72.0, 74.0, 78.0, 72.0, 71.0, 70.0, 71.0], "value": 70.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 88.0, 88.0, 84.0, 85.0, 88.0, 89.0, 86.0, 85.0], "value": 87.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 138.0, 155.0, 151.0, 149.0, 135.0, 152.0, 143.0, 189.0, 147.0], "value": 149.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 168.0, 169.0, 171.0, 170.0, 179.0, 168.0, 169.0, 167.0, 168.0], "value": 169.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 81.0, 84.0, 88.0, 67.0, 66.0, 66.0, 66.0, 66.0, 65.0], "value": 71.1, "unit": "score"}], "name": "kraken", "value": 1462.8, "alertThreshold": 2.0}]} 11:34:29 INFO - Return code: 0 11:34:29 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:34:29 INFO - # TBPL SUCCESS # 11:34:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:34:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:34:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:34:29 INFO - 2016-09-01 11:34:29.225 screenresolution[1925:16435] starting screenresolution argv=screenresolution get 11:34:29 INFO - 2016-09-01 11:34:29.228 screenresolution[1925:16435] Display 0: 1600x1200x32@60 11:34:29 INFO - 2016-09-01 11:34:29.234 screenresolution[1926:16438] starting screenresolution argv=screenresolution list 11:34:29 INFO - Available Modes on Display 0 11:34:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:34:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:34:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:34:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:34:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:34:29 INFO - Intel Iris: 11:34:29 INFO - Chipset Model: Intel Iris 11:34:29 INFO - Type: GPU 11:34:29 INFO - Bus: Built-In 11:34:29 INFO - VRAM (Dynamic, Max): 1536 MB 11:34:29 INFO - Vendor: Intel (0x8086) 11:34:29 INFO - Device ID: 0x0a2e 11:34:29 INFO - Revision ID: 0x0009 11:34:29 INFO - Displays: 11:34:29 INFO - Display: 11:34:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:34:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:34:29 INFO - Display Serial Number: 1600x1200 60 11:34:29 INFO - Main Display: Yes 11:34:29 INFO - Mirror: Off 11:34:29 INFO - Online: Yes 11:34:29 INFO - Rotation: Supported 11:34:29 INFO - Return code: 0 11:34:29 INFO - Running post-action listener: _resource_record_post_action 11:34:29 INFO - [mozharness: 2016-09-01 18:34:29.478615Z] Finished run-tests step (success) 11:34:29 INFO - Running post-run listener: _resource_record_post_run 11:34:29 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 1085440; Write bytes: 706549248; Read time: 15; Write time: 2024 11:34:29 INFO - TinderboxPrint: CPU usage
53.4% 11:34:29 INFO - TinderboxPrint: I/O read bytes / time
1,085,440 / 15 11:34:29 INFO - TinderboxPrint: I/O write bytes / time
706,549,248 / 2,024 11:34:29 INFO - TinderboxPrint: CPU idle
2,259.0 (46.6%) 11:34:29 INFO - TinderboxPrint: CPU system
965.6 (19.9%) 11:34:29 INFO - TinderboxPrint: CPU user
1,625.1 (33.5%) 11:34:29 INFO - TinderboxPrint: Swap in / out
407,678,976 / 0 11:34:29 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 402803200; Write bytes: 404277760; Read time: 29708; Write time: 769 11:34:29 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 1085440; Write bytes: 294853632; Read time: 15; Write time: 1227 11:34:29 INFO - Running post-run listener: _upload_blobber_files 11:34:29 INFO - Blob upload gear active. 11:34:29 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:34:29 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 11:34:29 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:34:29 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:34:30 INFO - (blobuploader) - INFO - Open directory for files ... 11:34:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:34:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:34:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:34:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:34:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:34:31 INFO - (blobuploader) - INFO - Done attempting. 11:34:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:34:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:34:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:34:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:34:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:34:33 INFO - (blobuploader) - INFO - Done attempting. 11:34:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:34:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:34:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:34:38 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:34:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:34:38 INFO - (blobuploader) - INFO - Done attempting. 11:34:38 INFO - (blobuploader) - INFO - Iteration through files over. 11:34:38 INFO - Return code: 0 11:34:38 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:34:38 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:34:38 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3f85b976ae09574c638a86e5e2fb4800ef9d56ee97acb141589a9174c75a727252365644e3edae5176d3f2b1d852a2672567de127ee23f85f709ae5e61ce1308", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5b07c51ce5b1cb394e8ea1c2a741b0ad5c7ac28180b72b3dfd5339f024e6fb77f19e9cad46132cc282b87565b42b8b4b31544e412178598ed4a0ad618b64f6d2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cbbdb35fe8b4ae9ce3f070d7f97b3d47e4153565f50f0c434e1879f22bfe66d972f2e43600c40a6e1a044d95830c9e9d6ce9f5f9d33f492f81e007d4242a02ef"} 11:34:38 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:34:38 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:34:38 INFO - Contents: 11:34:38 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3f85b976ae09574c638a86e5e2fb4800ef9d56ee97acb141589a9174c75a727252365644e3edae5176d3f2b1d852a2672567de127ee23f85f709ae5e61ce1308", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5b07c51ce5b1cb394e8ea1c2a741b0ad5c7ac28180b72b3dfd5339f024e6fb77f19e9cad46132cc282b87565b42b8b4b31544e412178598ed4a0ad618b64f6d2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cbbdb35fe8b4ae9ce3f070d7f97b3d47e4153565f50f0c434e1879f22bfe66d972f2e43600c40a6e1a044d95830c9e9d6ce9f5f9d33f492f81e007d4242a02ef"} 11:34:38 INFO - Running post-run listener: copy_logs_to_upload_dir 11:34:38 INFO - Copying logs to upload dir... 11:34:38 INFO - mkdir: /builds/slave/test/build/upload/logs 11:34:38 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.045051 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-09-01 11:34:38.491879) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-01 11:34:38.495518) ========= 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.c87tQgaOMl/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.uffSaAg7Fw/Listeners TMPDIR=/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/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/Fx-Team/sha512/3f85b976ae09574c638a86e5e2fb4800ef9d56ee97acb141589a9174c75a727252365644e3edae5176d3f2b1d852a2672567de127ee23f85f709ae5e61ce1308", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5b07c51ce5b1cb394e8ea1c2a741b0ad5c7ac28180b72b3dfd5339f024e6fb77f19e9cad46132cc282b87565b42b8b4b31544e412178598ed4a0ad618b64f6d2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cbbdb35fe8b4ae9ce3f070d7f97b3d47e4153565f50f0c434e1879f22bfe66d972f2e43600c40a6e1a044d95830c9e9d6ce9f5f9d33f492f81e007d4242a02ef"} build_url:https://queue.taskcluster.net/v1/task/B6vVkrNFS9iI0m8-A_XEAg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011249 build_url: 'https://queue.taskcluster.net/v1/task/B6vVkrNFS9iI0m8-A_XEAg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3f85b976ae09574c638a86e5e2fb4800ef9d56ee97acb141589a9174c75a727252365644e3edae5176d3f2b1d852a2672567de127ee23f85f709ae5e61ce1308", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5b07c51ce5b1cb394e8ea1c2a741b0ad5c7ac28180b72b3dfd5339f024e6fb77f19e9cad46132cc282b87565b42b8b4b31544e412178598ed4a0ad618b64f6d2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cbbdb35fe8b4ae9ce3f070d7f97b3d47e4153565f50f0c434e1879f22bfe66d972f2e43600c40a6e1a044d95830c9e9d6ce9f5f9d33f492f81e007d4242a02ef"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-01 11:34:38.528783) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 11:34:38.529144) ========= 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.c87tQgaOMl/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.uffSaAg7Fw/Listeners TMPDIR=/var/folders/zy/bdsjk76j7tl5nrllnsr13pf000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005234 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 11:34:38.578025) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-01 11:34:38.578340) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-01 11:34:38.578716) ========= ========= Total master_lag: 0.18 =========