builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0005 starttime: 1470657906.92 results: success (0) buildid: 20160805131321 builduid: 3935528495d04780899dc77e8ecc8206 revision: f54fea6078ddf0023a4307a25264c6ece653e503 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-08 05:05:06.915295) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-08 05:05:06.915880) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-08 05:05:06.916155) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-08 05:05:07.276732) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 05:05:07.277002) ========= 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.rrfxmt4ccD/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.jHqzfvjhKk/Listeners TMPDIR=/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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-08-08 05:05:07-- 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% 19.0M=0.001s 2016-08-08 05:05:07 (19.0 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.480984 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 05:05:07.784981) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 05:05:07.785253) ========= 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.rrfxmt4ccD/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.jHqzfvjhKk/Listeners TMPDIR=/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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.032039 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 05:05:07.837925) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 05:05:07.838222) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev f54fea6078ddf0023a4307a25264c6ece653e503 --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 f54fea6078ddf0023a4307a25264c6ece653e503 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.rrfxmt4ccD/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.jHqzfvjhKk/Listeners TMPDIR=/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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-08-08 05:05:07,916 truncating revision to first 12 chars 2016-08-08 05:05:07,916 Setting DEBUG logging. 2016-08-08 05:05:07,916 attempt 1/10 2016-08-08 05:05:07,916 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/f54fea6078dd?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-08 05:05:08,600 unpacking tar archive at: fx-team-f54fea6078dd/testing/mozharness/ program finished with exit code 0 elapsedTime=0.956357 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 05:05:08.816803) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-08 05:05:08.817280) ========= script_repo_revision: f54fea6078ddf0023a4307a25264c6ece653e503 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-08 05:05:08.817933) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-08 05:05:08.818472) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-08 05:05:08.836752) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-08-08 05:05:08.837044) ========= /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.rrfxmt4ccD/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.jHqzfvjhKk/Listeners TMPDIR=/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 05:05:09 INFO - MultiFileLogger online at 20160808 05:05:09 in /builds/slave/test 05:05:09 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 05:05:09 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:05:09 INFO - {'append_to_log': False, 05:05:09 INFO - 'base_work_dir': '/builds/slave/test', 05:05:09 INFO - 'blob_upload_branch': 'Fx-Team', 05:05:09 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:05:09 INFO - 'branch': 'Fx-Team', 05:05:09 INFO - 'buildbot_json_path': 'buildprops.json', 05:05:09 INFO - 'config_files': ('talos/mac_config.py',), 05:05:09 INFO - 'default_actions': ('clobber', 05:05:09 INFO - 'read-buildbot-config', 05:05:09 INFO - 'download-and-extract', 05:05:09 INFO - 'populate-webroot', 05:05:09 INFO - 'create-virtualenv', 05:05:09 INFO - 'install', 05:05:09 INFO - 'run-tests'), 05:05:09 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:05:09 INFO - 'download_minidump_stackwalk': True, 05:05:09 INFO - 'download_symbols': 'ondemand', 05:05:09 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:05:09 INFO - 'tooltool.py': '/tools/tooltool.py', 05:05:09 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:05:09 INFO - '/tools/misc-python/virtualenv.py')}, 05:05:09 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:05:09 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:05:09 INFO - 'installer_path': 'installer.exe', 05:05:09 INFO - 'log_level': 'info', 05:05:09 INFO - 'log_name': 'talos', 05:05:09 INFO - 'log_to_console': True, 05:05:09 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:05:09 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:05:09 INFO - 'opt_config_files': (), 05:05:09 INFO - 'pip_index': False, 05:05:09 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:05:09 INFO - 'cmd': ('bash', 05:05:09 INFO - '-c', 05:05:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:05:09 INFO - 'enabled': True, 05:05:09 INFO - 'halt_on_failure': False, 05:05:09 INFO - 'name': 'check_screen_resolution'},), 05:05:09 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:05:09 INFO - 'cmd': ('bash', 05:05:09 INFO - '-c', 05:05:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:05:09 INFO - 'enabled': True, 05:05:09 INFO - 'halt_on_failure': False, 05:05:09 INFO - 'name': 'check_screen_resolution'},), 05:05:09 INFO - 'run_cmd_checks_enabled': True, 05:05:09 INFO - 'sps_profile': False, 05:05:09 INFO - 'sps_profile_interval': 0, 05:05:09 INFO - 'suite': 'dromaeojs', 05:05:09 INFO - 'system_bits': '32', 05:05:09 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:05:09 INFO - 'title': 't-yosemite-r7-0005', 05:05:09 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:05:09 INFO - 'use_talos_json': True, 05:05:09 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:05:09 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:05:09 INFO - 'work_dir': 'build'} 05:05:09 INFO - [mozharness: 2016-08-08 12:05:09.037968Z] Running clobber step. 05:05:09 INFO - Running pre-action listener: _resource_record_pre_action 05:05:09 INFO - Running main action method: clobber 05:05:09 INFO - rmtree: /builds/slave/test/build 05:05:09 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:05:11 INFO - Running post-action listener: _resource_record_post_action 05:05:11 INFO - [mozharness: 2016-08-08 12:05:11.531154Z] Finished clobber step (success) 05:05:11 INFO - [mozharness: 2016-08-08 12:05:11.531435Z] Running read-buildbot-config step. 05:05:11 INFO - Running pre-action listener: _resource_record_pre_action 05:05:11 INFO - Running main action method: read_buildbot_config 05:05:11 INFO - Using buildbot properties: 05:05:11 INFO - { 05:05:11 INFO - "project": "", 05:05:11 INFO - "product": "firefox", 05:05:11 INFO - "script_repo_revision": "production", 05:05:11 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 05:05:11 INFO - "repository": "", 05:05:11 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 05:05:11 INFO - "buildid": "20160805131321", 05:05:11 INFO - "pgo_build": "False", 05:05:11 INFO - "basedir": "/builds/slave/test", 05:05:11 INFO - "buildnumber": 909, 05:05:11 INFO - "slavename": "t-yosemite-r7-0005", 05:05:11 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 05:05:11 INFO - "platform": "yosemite_r7", 05:05:11 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 05:05:11 INFO - "branch": "fx-team", 05:05:11 INFO - "repo_path": "integration/fx-team", 05:05:11 INFO - "revision": "f54fea6078ddf0023a4307a25264c6ece653e503", 05:05:11 INFO - "stage_platform": "macosx64", 05:05:11 INFO - "builduid": "3935528495d04780899dc77e8ecc8206", 05:05:11 INFO - "slavebuilddir": "test" 05:05:11 INFO - } 05:05:11 INFO - Found installer url https://queue.taskcluster.net/v1/task/Xm0TwQN8QGKRoOIzRgX9RA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 05:05:11 INFO - Running post-action listener: _resource_record_post_action 05:05:11 INFO - [mozharness: 2016-08-08 12:05:11.537398Z] Finished read-buildbot-config step (success) 05:05:11 INFO - [mozharness: 2016-08-08 12:05:11.537562Z] Running download-and-extract step. 05:05:11 INFO - Running pre-action listener: _resource_record_pre_action 05:05:11 INFO - Running main action method: download_and_extract 05:05:11 INFO - mkdir: /builds/slave/test/build/tests 05:05:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:11 INFO - trying https://queue.taskcluster.net/v1/task/Xm0TwQN8QGKRoOIzRgX9RA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 05:05:11 INFO - Downloading https://queue.taskcluster.net/v1/task/Xm0TwQN8QGKRoOIzRgX9RA/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 05:05:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Xm0TwQN8QGKRoOIzRgX9RA/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 05:05:12 INFO - Downloaded 1199 bytes. 05:05:12 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 05:05:12 INFO - Using the following test package requirements: 05:05:12 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 05:05:12 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:05:12 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 05:05:12 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:05:12 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 05:05:12 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:05:12 INFO - u'jsshell-mac.zip'], 05:05:12 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:05:12 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 05:05:12 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 05:05:12 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:05:12 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 05:05:12 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:05:12 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 05:05:12 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:05:12 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 05:05:12 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:05:12 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 05:05:12 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:05:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:12 INFO - trying https://queue.taskcluster.net/v1/task/Xm0TwQN8QGKRoOIzRgX9RA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 05:05:12 INFO - Downloading https://queue.taskcluster.net/v1/task/Xm0TwQN8QGKRoOIzRgX9RA/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 05:05:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Xm0TwQN8QGKRoOIzRgX9RA/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 05:05:13 INFO - Downloaded 16320553 bytes. 05:05:13 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 05:05:20 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 05:05:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:20 INFO - trying https://queue.taskcluster.net/v1/task/Xm0TwQN8QGKRoOIzRgX9RA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 05:05:20 INFO - Downloading https://queue.taskcluster.net/v1/task/Xm0TwQN8QGKRoOIzRgX9RA/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 05:05:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Xm0TwQN8QGKRoOIzRgX9RA/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 05:05:21 INFO - Downloaded 16320553 bytes. 05:05:21 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 05:05:28 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')]} 05:05:28 INFO - trying https://queue.taskcluster.net/v1/task/Xm0TwQN8QGKRoOIzRgX9RA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 05:05:28 INFO - Downloading https://queue.taskcluster.net/v1/task/Xm0TwQN8QGKRoOIzRgX9RA/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 05:05:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Xm0TwQN8QGKRoOIzRgX9RA/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 05:05:28 INFO - Downloaded 14413723 bytes. 05:05:28 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 05:05:29 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')]} 05:05:29 INFO - trying https://queue.taskcluster.net/v1/task/Xm0TwQN8QGKRoOIzRgX9RA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 05:05:29 INFO - Downloading https://queue.taskcluster.net/v1/task/Xm0TwQN8QGKRoOIzRgX9RA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 05:05:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Xm0TwQN8QGKRoOIzRgX9RA/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 05:05:32 INFO - Downloaded 111902381 bytes. 05:05:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Xm0TwQN8QGKRoOIzRgX9RA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 05:05:32 INFO - mkdir: /builds/slave/test/properties 05:05:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:05:32 INFO - Writing to file /builds/slave/test/properties/build_url 05:05:32 INFO - Contents: 05:05:32 INFO - build_url:https://queue.taskcluster.net/v1/task/Xm0TwQN8QGKRoOIzRgX9RA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 05:05:32 INFO - Running post-action listener: _resource_record_post_action 05:05:32 INFO - Running post-action listener: set_extra_try_arguments 05:05:32 INFO - [mozharness: 2016-08-08 12:05:32.840774Z] Finished download-and-extract step (success) 05:05:32 INFO - [mozharness: 2016-08-08 12:05:32.840888Z] Running populate-webroot step. 05:05:32 INFO - Running pre-action listener: _resource_record_pre_action 05:05:32 INFO - Running main action method: populate_webroot 05:05:32 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:05:32 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:05:32 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:05:32 INFO - '--tppagecycles', 05:05:32 INFO - '7'], 05:05:32 INFO - 'tests': ['tsvgm']}}, 05:05:32 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:05:32 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:05:32 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:05:32 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:05:32 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:05:32 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:05:32 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:05:32 INFO - 'talos_options': ['--disable-e10s'], 05:05:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:05:32 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:05:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:05:32 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:05:32 INFO - 'talos_options': ['--disable-e10s'], 05:05:32 INFO - 'tests': ['damp', 'tps']}, 05:05:32 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:05:32 INFO - 'tests': ['damp', 'tps']}, 05:05:32 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:05:32 INFO - 'tests': ['dromaeo_dom']}, 05:05:32 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:05:32 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:05:32 INFO - 'tests': ['basic_compositor_video']}, 05:05:32 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:05:32 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:05:32 INFO - 'tests': ['a11yr', 05:05:32 INFO - 'ts_paint', 05:05:32 INFO - 'tpaint', 05:05:32 INFO - 'sessionrestore', 05:05:32 INFO - 'sessionrestore_no_auto_restore', 05:05:32 INFO - 'tabpaint']}, 05:05:32 INFO - 'other-e10s': {'tests': ['a11yr', 05:05:32 INFO - 'ts_paint', 05:05:32 INFO - 'tpaint', 05:05:32 INFO - 'sessionrestore', 05:05:32 INFO - 'sessionrestore_no_auto_restore', 05:05:32 INFO - 'tabpaint']}, 05:05:32 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:05:32 INFO - 'ts_paint', 05:05:32 INFO - 'tpaint', 05:05:32 INFO - 'sessionrestore', 05:05:32 INFO - 'sessionrestore_no_auto_restore', 05:05:32 INFO - 'tabpaint']}, 05:05:32 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:05:32 INFO - 'ts_paint', 05:05:32 INFO - 'tpaint', 05:05:32 INFO - 'sessionrestore', 05:05:32 INFO - 'sessionrestore_no_auto_restore', 05:05:32 INFO - 'tabpaint']}, 05:05:32 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:05:32 INFO - 'tests': ['a11yr', 05:05:32 INFO - 'ts_paint', 05:05:32 INFO - 'tpaint', 05:05:32 INFO - 'sessionrestore', 05:05:32 INFO - 'sessionrestore_no_auto_restore', 05:05:32 INFO - 'tabpaint']}, 05:05:32 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:05:32 INFO - 'tests': ['a11yr', 05:05:32 INFO - 'ts_paint', 05:05:32 INFO - 'tpaint', 05:05:32 INFO - 'sessionrestore', 05:05:32 INFO - 'sessionrestore_no_auto_restore', 05:05:32 INFO - 'tabpaint']}, 05:05:32 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:05:32 INFO - 'tests': ['tsvgx', 05:05:32 INFO - 'tsvgr_opacity', 05:05:32 INFO - 'tart', 05:05:32 INFO - 'tscrollx', 05:05:32 INFO - 'cart']}, 05:05:32 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:05:32 INFO - 'tsvgr_opacity', 05:05:32 INFO - 'tart', 05:05:32 INFO - 'tscrollx', 05:05:32 INFO - 'cart']}, 05:05:32 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:05:32 INFO - 'talos_options': ['--disable-e10s'], 05:05:32 INFO - 'tests': ['tp5o']}, 05:05:32 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:05:32 INFO - 'tests': ['tp5o']}, 05:05:32 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:05:32 INFO - 'talos_options': ['--disable-e10s', 05:05:32 INFO - '--xperf_path', 05:05:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:05:32 INFO - 'tests': ['tp5n']}, 05:05:32 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:05:32 INFO - 'talos_options': ['--xperf_path', 05:05:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:05:32 INFO - 'tests': ['tp5n']}}, 05:05:32 INFO - 'talos.zip': {'path': '', 05:05:32 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:05:32 INFO - Running post-action listener: _resource_record_post_action 05:05:32 INFO - [mozharness: 2016-08-08 12:05:32.859967Z] Finished populate-webroot step (success) 05:05:32 INFO - [mozharness: 2016-08-08 12:05:32.860099Z] Running create-virtualenv step. 05:05:32 INFO - Running pre-action listener: _resource_record_pre_action 05:05:32 INFO - Running main action method: create_virtualenv 05:05:32 INFO - Creating virtualenv /builds/slave/test/build/venv 05:05:32 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 05:05:32 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:05:32 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:05:32 INFO - Using real prefix '/tools/python27' 05:05:32 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:05:33 INFO - Installing distribute.............................................................................................................................................................................................done. 05:05:36 INFO - Installing pip.................done. 05:05:36 INFO - Return code: 0 05:05:36 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:05:36 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')]} 05:05:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:36 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')]} 05:05:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:36 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 0x105f83a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106018618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd048568e80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ee7d30>, '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 0x105c4cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105edfb20>, '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.rrfxmt4ccD/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.jHqzfvjhKk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 05:05:36 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 05:05:36 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 05:05:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rrfxmt4ccD/Render', 05:05:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:05:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:05:36 INFO - 'HOME': '/Users/cltbld', 05:05:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:05:36 INFO - 'LOGNAME': 'cltbld', 05:05:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:36 INFO - 'MOZ_NO_REMOTE': '1', 05:05:36 INFO - 'NO_EM_RESTART': '1', 05:05:36 INFO - 'PAGER': '/bin/cat', 05:05:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:05:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:36 INFO - 'PWD': '/builds/slave/test', 05:05:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:05:36 INFO - 'SHELL': '/bin/bash', 05:05:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jHqzfvjhKk/Listeners', 05:05:36 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 05:05:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:05:36 INFO - 'USER': 'cltbld', 05:05:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:05:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:05:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:36 INFO - 'XPC_FLAGS': '0x0', 05:05:36 INFO - 'XPC_SERVICE_NAME': '0', 05:05:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:05:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:36 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 05:05:36 INFO - Cleaning up... 05:05:36 INFO - Return code: 0 05:05:36 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:05:36 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')]} 05:05:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:36 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')]} 05:05:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:36 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 0x105f83a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106018618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd048568e80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ee7d30>, '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 0x105c4cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105edfb20>, '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.rrfxmt4ccD/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.jHqzfvjhKk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 05:05:36 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 05:05:36 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 05:05:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rrfxmt4ccD/Render', 05:05:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:05:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:05:36 INFO - 'HOME': '/Users/cltbld', 05:05:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:05:36 INFO - 'LOGNAME': 'cltbld', 05:05:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:36 INFO - 'MOZ_NO_REMOTE': '1', 05:05:36 INFO - 'NO_EM_RESTART': '1', 05:05:36 INFO - 'PAGER': '/bin/cat', 05:05:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:05:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:36 INFO - 'PWD': '/builds/slave/test', 05:05:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:05:36 INFO - 'SHELL': '/bin/bash', 05:05:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jHqzfvjhKk/Listeners', 05:05:36 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 05:05:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:05:36 INFO - 'USER': 'cltbld', 05:05:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:05:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:05:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:36 INFO - 'XPC_FLAGS': '0x0', 05:05:36 INFO - 'XPC_SERVICE_NAME': '0', 05:05:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:05:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:37 INFO - Downloading/unpacking psutil>=3.1.1 05:05:37 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 05:05:37 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 05:05:37 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 05:05:37 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 05:05:37 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 05:05:37 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 05:05:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:05:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:05:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:05:40 INFO - Installing collected packages: psutil 05:05:40 INFO - Running setup.py install for psutil 05:05:40 INFO - building 'psutil._psutil_osx' extension 05:05:40 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 05:05:41 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 05:05:41 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 05:05:41 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 05:05:41 INFO - building 'psutil._psutil_posix' extension 05:05:41 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 05:05:41 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:05:41 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:05:41 INFO - ^ 05:05:41 INFO - 1 warning generated. 05:05:41 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 05:05:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:05:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:05:41 INFO - Successfully installed psutil 05:05:41 INFO - Cleaning up... 05:05:41 INFO - Return code: 0 05:05:41 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:05:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:41 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 0x105f83a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106018618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd048568e80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ee7d30>, '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 0x105c4cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105edfb20>, '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.rrfxmt4ccD/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.jHqzfvjhKk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 05:05:41 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 05:05:41 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 05:05:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rrfxmt4ccD/Render', 05:05:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:05:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:05:41 INFO - 'HOME': '/Users/cltbld', 05:05:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:05:41 INFO - 'LOGNAME': 'cltbld', 05:05:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:41 INFO - 'MOZ_NO_REMOTE': '1', 05:05:41 INFO - 'NO_EM_RESTART': '1', 05:05:41 INFO - 'PAGER': '/bin/cat', 05:05:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:05:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:41 INFO - 'PWD': '/builds/slave/test', 05:05:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:05:41 INFO - 'SHELL': '/bin/bash', 05:05:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jHqzfvjhKk/Listeners', 05:05:41 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 05:05:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:05:41 INFO - 'USER': 'cltbld', 05:05:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:05:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:05:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:41 INFO - 'XPC_FLAGS': '0x0', 05:05:41 INFO - 'XPC_SERVICE_NAME': '0', 05:05:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:05:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:41 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:05:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:44 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:05:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:05:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:05:44 INFO - Installing collected packages: mozsystemmonitor 05:05:44 INFO - Running setup.py install for mozsystemmonitor 05:05:44 INFO - Successfully installed mozsystemmonitor 05:05:44 INFO - Cleaning up... 05:05:44 INFO - Return code: 0 05:05:44 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:05:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105f83a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106018618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd048568e80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ee7d30>, '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 0x105c4cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105edfb20>, '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.rrfxmt4ccD/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.jHqzfvjhKk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 05:05:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:05:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 05:05:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rrfxmt4ccD/Render', 05:05:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:05:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:05:44 INFO - 'HOME': '/Users/cltbld', 05:05:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:05:44 INFO - 'LOGNAME': 'cltbld', 05:05:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:44 INFO - 'MOZ_NO_REMOTE': '1', 05:05:44 INFO - 'NO_EM_RESTART': '1', 05:05:44 INFO - 'PAGER': '/bin/cat', 05:05:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:05:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:44 INFO - 'PWD': '/builds/slave/test', 05:05:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:05:44 INFO - 'SHELL': '/bin/bash', 05:05:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jHqzfvjhKk/Listeners', 05:05:44 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 05:05:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:05:44 INFO - 'USER': 'cltbld', 05:05:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:05:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:05:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:44 INFO - 'XPC_FLAGS': '0x0', 05:05:44 INFO - 'XPC_SERVICE_NAME': '0', 05:05:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:05:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:45 INFO - Downloading/unpacking jsonschema==2.5.1 05:05:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:47 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:05:47 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:05:47 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 05:05:47 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 05:05:47 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 05:05:47 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 05:05:47 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 05:05:47 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 05:05:47 INFO - Downloading functools32-3.2.3-2.tar.gz 05:05:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:05:47 INFO - warning: no files found matching '*.txt' 05:05:47 INFO - no previously-included directories found matching 'build' 05:05:47 INFO - no previously-included directories found matching 'dist' 05:05:47 INFO - no previously-included directories found matching '.git*' 05:05:47 INFO - Installing collected packages: jsonschema, functools32 05:05:47 INFO - Running setup.py install for functools32 05:05:48 INFO - warning: no files found matching '*.txt' 05:05:48 INFO - no previously-included directories found matching 'build' 05:05:48 INFO - no previously-included directories found matching 'dist' 05:05:48 INFO - no previously-included directories found matching '.git*' 05:05:48 INFO - Successfully installed jsonschema functools32 05:05:48 INFO - Cleaning up... 05:05:48 INFO - Return code: 0 05:05:48 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:05:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:48 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 0x105f83a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106018618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd048568e80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ee7d30>, '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 0x105c4cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105edfb20>, '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.rrfxmt4ccD/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.jHqzfvjhKk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 05:05:48 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 05:05:48 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 05:05:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rrfxmt4ccD/Render', 05:05:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:05:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:05:48 INFO - 'HOME': '/Users/cltbld', 05:05:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:05:48 INFO - 'LOGNAME': 'cltbld', 05:05:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:48 INFO - 'MOZ_NO_REMOTE': '1', 05:05:48 INFO - 'NO_EM_RESTART': '1', 05:05:48 INFO - 'PAGER': '/bin/cat', 05:05:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:05:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:48 INFO - 'PWD': '/builds/slave/test', 05:05:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:05:48 INFO - 'SHELL': '/bin/bash', 05:05:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jHqzfvjhKk/Listeners', 05:05:48 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 05:05:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:05:48 INFO - 'USER': 'cltbld', 05:05:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:05:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:05:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:48 INFO - 'XPC_FLAGS': '0x0', 05:05:48 INFO - 'XPC_SERVICE_NAME': '0', 05:05:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:05:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:48 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:05:48 INFO - Cleaning up... 05:05:48 INFO - Return code: 0 05:05:48 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:05:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:48 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 0x105f83a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106018618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd048568e80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ee7d30>, '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 0x105c4cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105edfb20>, '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.rrfxmt4ccD/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.jHqzfvjhKk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 05:05:48 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 05:05:48 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 05:05:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rrfxmt4ccD/Render', 05:05:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:05:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:05:48 INFO - 'HOME': '/Users/cltbld', 05:05:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:05:48 INFO - 'LOGNAME': 'cltbld', 05:05:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:48 INFO - 'MOZ_NO_REMOTE': '1', 05:05:48 INFO - 'NO_EM_RESTART': '1', 05:05:48 INFO - 'PAGER': '/bin/cat', 05:05:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:05:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:48 INFO - 'PWD': '/builds/slave/test', 05:05:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:05:48 INFO - 'SHELL': '/bin/bash', 05:05:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jHqzfvjhKk/Listeners', 05:05:48 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 05:05:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:05:48 INFO - 'USER': 'cltbld', 05:05:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:05:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:05:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:48 INFO - 'XPC_FLAGS': '0x0', 05:05:48 INFO - 'XPC_SERVICE_NAME': '0', 05:05:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:05:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:48 INFO - Downloading/unpacking blobuploader==1.2.4 05:05:48 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 05:05:48 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 05:05:48 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 05:05:48 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 05:05:48 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 05:05:48 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 05:05:51 INFO - Downloading blobuploader-1.2.4.tar.gz 05:05:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:05:51 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:05:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:05:51 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:05:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:52 INFO - Downloading docopt-0.6.1.tar.gz 05:05:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:05:52 INFO - Installing collected packages: blobuploader, requests, docopt 05:05:52 INFO - Running setup.py install for blobuploader 05:05:52 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:05:52 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:05:52 INFO - Running setup.py install for requests 05:05:53 INFO - Running setup.py install for docopt 05:05:53 INFO - Successfully installed blobuploader requests docopt 05:05:53 INFO - Cleaning up... 05:05:53 INFO - Return code: 0 05:05:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:05:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:53 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 0x105f83a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106018618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd048568e80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ee7d30>, '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 0x105c4cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105edfb20>, '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.rrfxmt4ccD/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.jHqzfvjhKk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 05:05:53 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 05:05:53 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 05:05:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rrfxmt4ccD/Render', 05:05:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:05:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:05:53 INFO - 'HOME': '/Users/cltbld', 05:05:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:05:53 INFO - 'LOGNAME': 'cltbld', 05:05:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:53 INFO - 'MOZ_NO_REMOTE': '1', 05:05:53 INFO - 'NO_EM_RESTART': '1', 05:05:53 INFO - 'PAGER': '/bin/cat', 05:05:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:05:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:53 INFO - 'PWD': '/builds/slave/test', 05:05:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:05:53 INFO - 'SHELL': '/bin/bash', 05:05:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jHqzfvjhKk/Listeners', 05:05:53 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 05:05:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:05:53 INFO - 'USER': 'cltbld', 05:05:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:05:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:05:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:53 INFO - 'XPC_FLAGS': '0x0', 05:05:53 INFO - 'XPC_SERVICE_NAME': '0', 05:05:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:05:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:05:53 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-Qbmmx6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:05:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:05:53 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-MMza9C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:05:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:05:53 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-R3Hzw1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:05:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:05:53 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-Vo642w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:05:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:05:53 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-33GCSV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:05:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:05:54 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-eSUutx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:05:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:05:54 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-mMTWZC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:05:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:05:54 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-VEKN0O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:05:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:05:54 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-Y1lqnH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:05:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:05:54 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-Exw2zQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:05:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:05:54 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-Al4r8G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:05:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:05:54 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-bDWyOJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:05:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:05:54 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-ZdNnnT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:05:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:05:54 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-pFT3SP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:05:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:05:54 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-R302CH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:05:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:05:55 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-Eo6zVl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:05:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:05:55 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-VSh40z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:05:55 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:05:55 INFO - Running setup.py install for manifestparser 05:05:55 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:05:55 INFO - Running setup.py install for mozcrash 05:05:55 INFO - Running setup.py install for mozdebug 05:05:55 INFO - Running setup.py install for mozdevice 05:05:55 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:05:55 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:05:55 INFO - Running setup.py install for mozfile 05:05:56 INFO - Running setup.py install for mozhttpd 05:05:56 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:05:56 INFO - Running setup.py install for mozinfo 05:05:56 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:05:56 INFO - Running setup.py install for mozInstall 05:05:56 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:05:56 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:05:56 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:05:56 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:05:56 INFO - Running setup.py install for mozleak 05:05:56 INFO - Running setup.py install for mozlog 05:05:56 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:05:56 INFO - Running setup.py install for moznetwork 05:05:56 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:05:56 INFO - Running setup.py install for mozprocess 05:05:57 INFO - Running setup.py install for mozprofile 05:05:57 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:05:57 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:05:57 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:05:57 INFO - Running setup.py install for mozrunner 05:05:57 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:05:57 INFO - Running setup.py install for mozscreenshot 05:05:57 INFO - Running setup.py install for moztest 05:05:57 INFO - Running setup.py install for mozversion 05:05:57 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:05:57 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:05:57 INFO - Cleaning up... 05:05:57 INFO - Return code: 0 05:05:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:05: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')]} 05:05:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05: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')]} 05:05:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:57 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 0x105f83a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106018618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd048568e80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ee7d30>, '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 0x105c4cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105edfb20>, '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.rrfxmt4ccD/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.jHqzfvjhKk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 05:05:57 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 05:05:57 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 05:05:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rrfxmt4ccD/Render', 05:05:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:05:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:05:57 INFO - 'HOME': '/Users/cltbld', 05:05:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:05:57 INFO - 'LOGNAME': 'cltbld', 05:05:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:57 INFO - 'MOZ_NO_REMOTE': '1', 05:05:57 INFO - 'NO_EM_RESTART': '1', 05:05:57 INFO - 'PAGER': '/bin/cat', 05:05:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:05:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:57 INFO - 'PWD': '/builds/slave/test', 05:05:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:05:57 INFO - 'SHELL': '/bin/bash', 05:05:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jHqzfvjhKk/Listeners', 05:05:57 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 05:05:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:05:57 INFO - 'USER': 'cltbld', 05:05:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:05:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:05:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:57 INFO - 'XPC_FLAGS': '0x0', 05:05:57 INFO - 'XPC_SERVICE_NAME': '0', 05:05:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:05:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:05:58 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-_4MyVJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:05:58 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)) 05:05:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:05:58 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-xBvWyF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:05:58 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)) 05:05:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:05:58 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-Js9NJF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:05:58 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)) 05:05:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:05:58 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-8zpbTe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:05:58 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)) 05:05:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:05:58 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-ohTCZm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:05:58 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)) 05:05:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:05:58 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-aZiw8P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:05:58 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)) 05:05:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:05:58 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-V1wEEB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:05:58 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)) 05:05:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:05:58 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-yC150W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:05:59 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)) 05:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:05:59 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-Hw8tFF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:05:59 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)) 05:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:05:59 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-d_UtRe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:05:59 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)) 05:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:05:59 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-3t9NaM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:05:59 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)) 05:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:05:59 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-AXxUTo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:05:59 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)) 05:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:05:59 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-aVRbjd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:05:59 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)) 05:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:05:59 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-FJavDr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:05:59 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)) 05:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:05:59 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-B8zcvw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:05:59 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)) 05:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:05:59 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-d9VmJr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:05:59 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)) 05:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:05:59 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-gGnUMN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:05:59 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)) 05:05:59 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)) 05:05:59 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)) 05:05:59 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)) 05:05:59 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)) 05:05:59 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)) 05:06:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:06:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:06:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:06:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:02 INFO - Downloading blessings-1.6.tar.gz 05:06:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:06:02 INFO - Installing collected packages: blessings 05:06:02 INFO - Running setup.py install for blessings 05:06:02 INFO - Successfully installed blessings 05:06:02 INFO - Cleaning up... 05:06:02 INFO - Return code: 0 05:06:02 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:06:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:06:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:06:03 INFO - Reading from file tmpfile_stdout 05:06:03 INFO - Current package versions: 05:06:03 INFO - blessings == 1.6 05:06:03 INFO - blobuploader == 1.2.4 05:06:03 INFO - docopt == 0.6.1 05:06:03 INFO - functools32 == 3.2.3-2 05:06:03 INFO - jsonschema == 2.5.1 05:06:03 INFO - manifestparser == 1.1 05:06:03 INFO - mozInstall == 1.12 05:06:03 INFO - mozcrash == 0.17 05:06:03 INFO - mozdebug == 0.1 05:06:03 INFO - mozdevice == 0.48 05:06:03 INFO - mozfile == 1.2 05:06:03 INFO - mozhttpd == 0.7 05:06:03 INFO - mozinfo == 0.9 05:06:03 INFO - mozleak == 0.1 05:06:03 INFO - mozlog == 3.3 05:06:03 INFO - moznetwork == 0.27 05:06:03 INFO - mozprocess == 0.23 05:06:03 INFO - mozprofile == 0.28 05:06:03 INFO - mozrunner == 6.12 05:06:03 INFO - mozscreenshot == 0.1 05:06:03 INFO - mozsystemmonitor == 0.3 05:06:03 INFO - moztest == 0.7 05:06:03 INFO - mozversion == 1.4 05:06:03 INFO - psutil == 3.1.1 05:06:03 INFO - requests == 1.2.3 05:06:03 INFO - wsgiref == 0.1.2 05:06:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:06:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:06:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:06:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:03 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 0x105f83a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106018618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd048568e80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ee7d30>, '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 0x105c4cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105edfb20>, '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.rrfxmt4ccD/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.jHqzfvjhKk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 05:06:03 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 05:06:03 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 05:06:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rrfxmt4ccD/Render', 05:06:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:06:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:06:03 INFO - 'HOME': '/Users/cltbld', 05:06:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:06:03 INFO - 'LOGNAME': 'cltbld', 05:06:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:03 INFO - 'MOZ_NO_REMOTE': '1', 05:06:03 INFO - 'NO_EM_RESTART': '1', 05:06:03 INFO - 'PAGER': '/bin/cat', 05:06:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:06:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:03 INFO - 'PWD': '/builds/slave/test', 05:06:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:06:03 INFO - 'SHELL': '/bin/bash', 05:06:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jHqzfvjhKk/Listeners', 05:06:03 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 05:06:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:06:03 INFO - 'USER': 'cltbld', 05:06:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:06:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:06:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:03 INFO - 'XPC_FLAGS': '0x0', 05:06:03 INFO - 'XPC_SERVICE_NAME': '0', 05:06:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:06:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:03 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)) 05:06:03 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)) 05:06:03 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)) 05:06:03 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)) 05:06:03 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)) 05:06:03 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)) 05:06:03 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)) 05:06:03 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)) 05:06:03 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)) 05:06:03 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)) 05:06:03 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)) 05:06:03 INFO - Cleaning up... 05:06:03 INFO - Return code: 0 05:06:03 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:06:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:06:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:06:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105f83a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106018618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd048568e80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ee7d30>, '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 0x105c4cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105edfb20>, '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.rrfxmt4ccD/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.jHqzfvjhKk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 05:06:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:06:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:06:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rrfxmt4ccD/Render', 05:06:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:06:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:06:03 INFO - 'HOME': '/Users/cltbld', 05:06:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:06:03 INFO - 'LOGNAME': 'cltbld', 05:06:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:03 INFO - 'MOZ_NO_REMOTE': '1', 05:06:03 INFO - 'NO_EM_RESTART': '1', 05:06:03 INFO - 'PAGER': '/bin/cat', 05:06:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:06:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:03 INFO - 'PWD': '/builds/slave/test', 05:06:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:06:03 INFO - 'SHELL': '/bin/bash', 05:06:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jHqzfvjhKk/Listeners', 05:06:03 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 05:06:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:06:03 INFO - 'USER': 'cltbld', 05:06:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:06:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:06:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:03 INFO - 'XPC_FLAGS': '0x0', 05:06:03 INFO - 'XPC_SERVICE_NAME': '0', 05:06:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:06:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:03 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:06:03 INFO - Cleaning up... 05:06:03 INFO - Return code: 0 05:06:03 INFO - Running post-action listener: _resource_record_post_action 05:06:03 INFO - Running post-action listener: _start_resource_monitoring 05:06:03 INFO - Starting resource monitoring. 05:06:03 INFO - [mozharness: 2016-08-08 12:06:03.598786Z] Finished create-virtualenv step (success) 05:06:03 INFO - [mozharness: 2016-08-08 12:06:03.599745Z] Running install step. 05:06:03 INFO - Running pre-action listener: _resource_record_pre_action 05:06:03 INFO - Running main action method: install 05:06:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:06:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:06:03 INFO - Reading from file tmpfile_stdout 05:06:03 INFO - Detecting whether we're running mozinstall >=1.0... 05:06:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:06:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:06:03 INFO - Reading from file tmpfile_stdout 05:06:03 INFO - Output received: 05:06:03 INFO - Usage: mozinstall [options] installer 05:06:03 INFO - Options: 05:06:03 INFO - -h, --help show this help message and exit 05:06:03 INFO - -d DEST, --destination=DEST 05:06:03 INFO - Directory to install application into. [default: 05:06:03 INFO - "/builds/slave/test"] 05:06:03 INFO - --app=APP Application being installed. [default: firefox] 05:06:03 INFO - mkdir: /builds/slave/test/build/application 05:06:03 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'] 05:06:03 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 05:06:38 INFO - Reading from file tmpfile_stdout 05:06:38 INFO - Output received: 05:06:38 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:06:38 INFO - Running post-action listener: _resource_record_post_action 05:06:38 INFO - [mozharness: 2016-08-08 12:06:38.580387Z] Finished install step (success) 05:06:38 INFO - [mozharness: 2016-08-08 12:06:38.580553Z] Running run-tests step. 05:06:38 INFO - Running pre-action listener: _resource_record_pre_action 05:06:38 INFO - Running main action method: run_tests 05:06:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:06:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:06:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:06:38 INFO - 2016-08-08 05:06:38.595 screenresolution[1641:7674] starting screenresolution argv=screenresolution get 05:06:38 INFO - 2016-08-08 05:06:38.597 screenresolution[1641:7674] Display 0: 1600x1200x32@60 05:06:38 INFO - 2016-08-08 05:06:38.603 screenresolution[1642:7677] starting screenresolution argv=screenresolution list 05:06:38 INFO - Available Modes on Display 0 05:06:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:06:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:06:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:06:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:06:38 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:06:38 INFO - Intel Iris: 05:06:38 INFO - Chipset Model: Intel Iris 05:06:38 INFO - Type: GPU 05:06:38 INFO - Bus: Built-In 05:06:38 INFO - VRAM (Dynamic, Max): 1536 MB 05:06:38 INFO - Vendor: Intel (0x8086) 05:06:38 INFO - Device ID: 0x0a2e 05:06:38 INFO - Revision ID: 0x0009 05:06:38 INFO - Displays: 05:06:38 INFO - Display: 05:06:38 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:06:38 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:06:38 INFO - Display Serial Number: 1600x1200 60 05:06:38 INFO - Main Display: Yes 05:06:38 INFO - Mirror: Off 05:06:38 INFO - Online: Yes 05:06:38 INFO - Rotation: Supported 05:06:38 INFO - Return code: 0 05:06:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:06:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:06:38 INFO - Python 2.7.3 05:06:38 INFO - Return code: 0 05:06:38 INFO - grabbing minidump binary from tooltool 05:06:38 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')]} 05:06:38 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 0x105ee7d30>, '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 0x105c4cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105edfb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:06:38 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 05:06:38 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 05:06:38 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 05:06:38 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:06:38 INFO - Return code: 0 05:06:38 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:06:38 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:06:38 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:06:38 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:06:38 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:06:38 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/Xm0TwQN8QGKRoOIzRgX9RA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0005', '--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 05:06:38 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/Xm0TwQN8QGKRoOIzRgX9RA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0005 --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 05:06:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rrfxmt4ccD/Render', 05:06:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:06:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:06:38 INFO - 'HOME': '/Users/cltbld', 05:06:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:06:38 INFO - 'LOGNAME': 'cltbld', 05:06:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:06:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:06:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:38 INFO - 'MOZ_NO_REMOTE': '1', 05:06:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:06:38 INFO - 'NO_EM_RESTART': '1', 05:06:38 INFO - 'PAGER': '/bin/cat', 05:06:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:06:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:38 INFO - 'PWD': '/builds/slave/test', 05:06:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:06:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:06:38 INFO - 'SHELL': '/bin/bash', 05:06:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jHqzfvjhKk/Listeners', 05:06:38 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 05:06:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:06:38 INFO - 'USER': 'cltbld', 05:06:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:06:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:06:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:38 INFO - 'XPC_FLAGS': '0x0', 05:06:38 INFO - 'XPC_SERVICE_NAME': '0', 05:06:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:06:38 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/Xm0TwQN8QGKRoOIzRgX9RA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0005', '--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 05:06:39 INFO - mozversion application_buildid: 20160805131321 05:06:39 INFO - mozversion application_changeset: f54fea6078ddf0023a4307a25264c6ece653e503 05:06:39 INFO - mozversion application_display_name: Nightly 05:06:39 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:06:39 INFO - mozversion application_name: Firefox 05:06:39 INFO - mozversion application_remotingname: firefox 05:06:39 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 05:06:39 INFO - mozversion application_vendor: Mozilla 05:06:39 INFO - mozversion application_version: 51.0a1 05:06:39 INFO - mozversion platform_buildid: 20160805131321 05:06:39 INFO - mozversion platform_changeset: f54fea6078ddf0023a4307a25264c6ece653e503 05:06:39 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 05:06:39 INFO - mozversion platform_version: 51.0a1 05:06:39 INFO - using testdate: 1470657999 05:06:39 INFO - actual date: 1470657999 05:06:39 INFO - starting webserver on 'localhost:49266' 05:06:39 INFO - SUITE-START | Running 2 tests 05:06:39 INFO - TEST-START | dromaeo_css 05:06:39 INFO - operating with platform_type : mac_ 05:06:39 INFO - Initialising browser for dromaeo_css test... 05:06:39 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/tmpLwTQHU/profile http://localhost:49266/getInfo.html) 05:06:41 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 05:06:41 INFO - PROCESS | 1652 | colorDepth:24 05:06:41 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 05:06:41 INFO - PROCESS | 1652 | __metrics 05:06:41 INFO - PROCESS | 1652 | 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" 05:06:41 INFO - PROCESS | 1652 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:06:42 INFO - TEST-INFO | 1652: exit 0 05:06:42 INFO - Browser initialized. 05:06:42 INFO - Running cycle 1/1 for dromaeo_css test... 05:06:42 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/tmpLwTQHU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:06:54 INFO - PROCESS | 1655 | RSS: Main: 243712000 05:06:54 INFO - PROCESS | 1655 | 05:07:45 INFO - PROCESS | 1655 | 1470658065295 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] 05:09:58 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49266/tests/dromaeo/cssquery-ext.html) 05:09:59 INFO - PROCESS | 1655 | RSS: Main: 376147968 05:09:59 INFO - PROCESS | 1655 | 05:13:03 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-ext.html (next: http://localhost:49266/tests/dromaeo/cssquery-jquery.html) 05:13:03 INFO - PROCESS | 1655 | RSS: Main: 396410880 05:13:03 INFO - PROCESS | 1655 | 05:16:05 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49266/tests/dromaeo/cssquery-mootools.html) 05:16:06 INFO - PROCESS | 1655 | RSS: Main: 359944192 05:16:06 INFO - PROCESS | 1655 | 05:19:08 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49266/tests/dromaeo/cssquery-prototype.html) 05:19:09 INFO - PROCESS | 1655 | RSS: Main: 354267136 05:19:09 INFO - PROCESS | 1655 | 05:22:13 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49266/tests/dromaeo/cssquery-yui.html) 05:22:13 INFO - PROCESS | 1655 | RSS: Main: 346570752 05:22:13 INFO - PROCESS | 1655 | 05:25:17 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-yui.html (next: http://localhost:49266/tests/dromaeo/cssquery-dojo.html) 05:25:17 INFO - PROCESS | 1655 | RSS: Main: 453062656 05:25:17 INFO - PROCESS | 1655 | 05:25:17 INFO - PROCESS | 1655 | __start_tp_report 05:25:17 INFO - PROCESS | 1655 | _x_x_mozilla_page_load 05:25:17 INFO - PROCESS | 1655 | _x_x_mozilla_page_load_details 05:25:17 INFO - PROCESS | 1655 | |i|pagename|runs| 05:25:17 INFO - PROCESS | 1655 | |0;dojo.html;3265.134739294087;3293.7859039162454;3309.98345008275;3312.420326442872;3350.296437748073;5480.6437581557175;5607.523360514356;5641.464060914212;5660.915086273709;5683.460071293226;5589.944100558993;5618.073017952043;5682.715864206793;5690.658560486363;5742.54059675225;5088.720120393377;5132.5124113209185;5143.614228932826;5188.584913206934;5222.138347172731;5219.399769026553;5230.267762513251;5244;5244.842654720345;5281.102212623844;5104.310918026056;5163.63854530183;5181.715005674687;5205.817796377147;5267.894642107175;5174.715390653512;5175.068487672217;5180.378354597435;5181.5854731621375;5184.963007398535;4727.2436410174205;5132.923006154873;5153.252778347156;5156.819511317123;5205.167173252299;5604.551635869161;5640.830775076754;5645.971770141164;5659.349174844904;5661.037623603997;6130.203073600448;6169.475594574466;6170.50635949123;6193.3496982816605;6216.129741836146;6039.969800151015;6171.25944886612;6219.751209951596;6232.781852635136;6233.657148856811;5817.0983497557945;5820.330661973885;5844.26946631671;5849.444302791228;5850.268716410449;6286.9056964145575;6296.622202667854;6314.873702525923;6326.557141000086;6335.239771227482;5936.1392598073035;6009.729562169713;6054.39456054391;6082.452579267887;6106.72519736604;6209.285932117864;6214.4407003369915;6216.595921265103;6217.285012223652;6226.346233645492;6152.600080994721;6156.969215153896;6189.690515474208;6191.9690401547705;6201.1938448002375;6096.078966295426;6100.450959413674;6131.386861313922;6136.202293701879;6161.199044124325;4922.606191504671;4945.975270123627;4954.504549545017;4958.801647934042;4973.378327709036;4382.05785756064;4391.209582275221;4405.118976204772;4428.512863585003;4448.377227188196;5475.561955043587;5497.39528651848;5550.8057217997175;5641.5204707600315;5649.067903795828;6197.070439434121;6252.843678907972;6255.6872156392;6271.843203919847;6274.466670333073;5620.409856965041;5620.803271885465;5622.803201887914;5664.745086471119;5672.007398705293;5284.286621306074;5348.197770334481;5350.170723537858;5359.249705041298;5416.133418653076;4660.836870709509;6314.747410103728;6323.36766323364;6330.430261276507;6337.017762246859;6230.470410015199;6239.220097487814;6239.844003899939;6241.750329986749;6242.032484964838;5010.799568017239;6240.781572644935;6297.4017468340435;6305.8108256752375;6336.968315158395;5974.671393073423;6026.698665066817;6029.457348838626;6039.912815693042;6122.877542449215;6112.730468808142;6133.916185115316;6181.505479561714;6184.907226391698;6189.907151392643;5817.738201781015;5854.590178687451;5981.222441082631;5995.130706047686;6030.216071910623;5915.852103697442;5974.074018527135;6065.090236464565;6069;6101.156499727147;5922.378150294157;5939.376365481562;5962.970185149046;5970.0746384310505;5977.163197152315;6050;6072.787452439144;6073.423024812636;6088.726007329769;6095.664738439428;5645.830625081254;5775.27809023872;5822.214001109796;5853.443922827325;5889.499392551681 05:25:17 INFO - PROCESS | 1655 | |1;ext.html;451.5619848746717;471.3848427801718;507.968823288724;524.1430261522412;532.0237364436259;30452.54321185181;39356.40965385522;39739;40396.99007524813;43834.12331753363;42807.571924280746;43159.78420107903;43206;43257;43345.56654433462;8754.47473151613;9560.996095409986;9732.51337433129;9736.464494452837;9766.316357854954;8770.166834150745;11692.824607630891;11921;11942.522299108025;12224.755504889898;11841.881581184163;11880.881191188064;11891.167618266725;11920.225185362968;11950.521979120867;11836;11921.105917056255;11935.880641193651;11936.224145430519;11937.582184623583;11694.649160525198;11736.23714458566;11772.705682357924;11799.764004719944;11854.585089521825;32478.837605812056;40306.395404068964;40316.59683403187;40420.595794041976;40502;3589.174489867319;3647.598764135943;3648.854045838163;3692.5015122958325;3926.823292951824;30780;31344.37311253762;32207.51688724671;32358.191045223823;32399;3263.1515805890403;3321.78408403453;3346.1300061983816;3357.496375543688;3378.8310584470923;370132;374755;375270;375430.1228493867;378027.99999999726;33183.834080829925;33597.66402335945;56283.71858140683;56438.15342769862;56660.43339566634;34587;34655.30689386198;34965.825170873984;35029.824850876095;35060.824695876356;14451.91610629207;14612.342444590018;14627;14627.341769620392;14630.853691463162;11266.662000139844;11477.71305717347;12380.195287306296;12392.256464612152;12479.5008199671;7549.924500755032;7694.807629809299;7719.266669666373;7778.805529861799;7791.532508049422;3247.3342964692183;3267.2485328374605;3280.2783387654677;3283.9343213135603;3306.3717893600137;969.8690676758688;1011.4740335025746;1030.1141018723893;1035.4874337202982;1038.2680210451613;28760.280992974924;30163.547546786816;30232.697673023427;30652;31106.911258105858;994.125169850527;1000.7798284377425;1052.3633201912885;1096.8282800055933;1148.3683973814368;13024.54414095502;15515.612109697347;15543.834212434112;15823.446179383667;16269;627747;631398;637491;638487;639462;57771;58318.7084064577;58419.123713144334;58595;58919.41080589225;1242.8011518157052;1349.426493740176;1358.5245164192652;1398.0912406935574;1407.183833376639;27411.725882740917;27780.44439111166;30600.846995764878;30745.92389266365;31774.36451271008;56943.71528142333;57785;57795.42204577901;57856.71071644615;57994;594388;603619;604883;609916;618459;42781.78609106935;43200.783996079816;44131.77934110309;44234.3364849534;44255.33616995812;47133.057338853716;48267;48571.27143092786;48681.5131848677;48885.26672099993;35301;36455.08862278464;36925.13966480447;37865.81067094647;37940;39748;39821.60178398179;40030.39954400628;40817.726350947494;41295.380569290886;38464.423033653955;38687.6131238684;39316.41025384564;39365.80317098396;39396.40905386479;29697;31469.370612588078;31978.52032219472;34818.129546761535;35846.46230306599;21386.893065535194;24674.50650987006;25617.871910640326;25733.3566660835;25800 05:25:17 INFO - PROCESS | 1655 | |2;jquery.html;356.08663777410953;369.30386221971577;377.7469095705878;380.68783597450096;404.2077528045032;15395.615109622251;15800.446984355536;19994;20587.794122058775;20651.896740516313;17027.40404085857;18943.905280473613;20518.589628207428;20935.581288374222;21040.684389734168;4499.70751901127;5076.086304465195;5239.476052394768;5240.371155461331;5247.685138891659;5025.748712564376;5104.285400043978;5107.387113546361;5109.080365534202;5118.72031992002;4880.585150262232;5099.235114732783;5103.744812759366;5106.897862042757;5114.002769459957;4491.303847903564;5163.3545806774155;5166.405863325728;5171.663841850268;5174.094533456649;4834.081524510332;5023.372078490189;5030;5030.874228144289;5033.723145227011;17158.14209289543;21457.67813482799;21700.565988680137;22086.337409877568;22245.888770556045;2253.019936327703;2332.93001209964;2337.135259953828;2510;2862.8834754445784;8557.529335886524;9511.571979260882;9999.900000999969;10542.736431589195;10549.05058544727;1814.872958892878;1825.3520000399933;1842.6406850664125;1856.053412759489;1882.4634979031048;125364;137669;137951;138100;139800;11480.655580332603;11505.654830355103;11600.825987610193;11672.358020308795;11870.28778273289;14728.779068313985;14736.557903262705;14745.262736863115;14757.893158013192;14764;7235.7467488638695;8505.489670619783;8576.313894888393;8633.050364459905;8643.740687779376;8584.484930904166;8595.957020214859;8763.824723505622;8774.692885748967;8790.077041910636;4470.463544374631;4541.364209010741;4543.818247270072;4570.885727856764;4631.374764406829;1884.6607610629944;1927.9903600481907;1928.7781905080815;1929.4211736479;1974.131382191831;814.1532805881935;818.6316157729045;857.3227150550995;875.2472873328932;918.9218916392048;19943.900280498503;21803.784860557767;22725.659115113285;22756.658650120262;22765.203217887294;696.979090627282;863.6027427383349;963.3449254506862;1012.6911292055994;1025.8512515685334;8923.776905577282;10391.324563903323;10601.946990265154;10852.240343175901;11347;616040;625770;626219;629605;630741;130735.03897441545;136790;143189;143276;143513;1010.9443980580992;1016.694991502561;1033.9741506462249;1035.6427032673716;1036.2849633752687;18833.058347082864;18961.336353227573;19213.847169169894;19224.615507690047;19265.5183620405;54844;54957.450425496834;56277.43722562722;56848.14727779169;57672.71163644155;78215.2178478208;83355;83638;83717.74423383776;84314.3137137266;7049.471289653194;7791.92208077912;7994.480358776661;8233.46482478637;8275.793105172419;9361.017093205115;12588.748225035632;12831.486740530274;13155.53955611549;13401.731965360834;11253;11467.770644587228;11624.709382265512;11785.705357366134;11853.288802671523;11244.66266012021;11506.597269095542;11763.235389699643;11792.287773812242;11795.233309834834;11610.593629222936;11662.416879155839;12115.878841211475;12141.696457588278;12167.695807604881;8006;8046.476978996347;8144.959275203586;8261.421700480909;8454.32365410778;10253;11332.150088743541;11641.53433862636;11675.416229188337;11684.824727629262 05:25:17 INFO - PROCESS | 1655 | |3;mootools.html;1325.6950901292696;1473.4842805018245;1509.501864384753;1691.4840973503076;1872.438268519444;7615.543067415959;8441.451305665136;8613.526256055915;8625.439346442477;8761.56192190391;8551.87172192416;8637.179467950551;8643.049264580892;8645.005824330205;8646.221840034395;2314.7569505201923;2340.8361414700976;2341.672165896776;2344.1678204237505;2344.589696803061;2304.135949019118;2326.1044497868356;2359.8584084954873;2361.9763802361927;2364.8462849914786;2324.32594547581;2346.4837735698206;2351.318117745852;2361.067378385537;2361.681173041643;2300.804431623314;2335.9532809343887;2336.4626135988647;2337.8947947342326;2344.3904584808074;2325;2325.5116425550655;2329.7087864017;2335.1243283768586;2340.3447034830274;8632.78418039554;8736.51949142797;8738.912610873873;8769.473831570127;8809.911900881038;5116.462771408986;5323.1749078892835;5328.5470734987575;5340.62615616907;5349;3560.359135355635;3564.48314994327;3618.963810361889;3636.563612366533;3638.8544458221904;1059.2267644619471;1067.546292825546;1079.3092420850662;1080.6595922284455;1093.2675107677796;11382.373969431677;11479.65561033162;11551.653450396501;11563.479643416067;11564.13269004828;4330.242207613655;4381.627561657231;4558.635309175258;4562.338460923148;4571.474280457724;9010.86483702745;9011.2790976723;9013.233875120557;9022;9040.683576074805;5206.843794686166;5208.244804503402;5212.635115541952;5212.8957420851375;5216.921746173811;4062.939055914164;4364.367166760802;4368.956310436918;4494.73031618104;4846.466888642247;3700.9074773130887;3754.7747135171976;3758.248350329945;3787.450819631138;3816.179521402856;1002.7042022603204;1145.1010956399186;1147.6671765188003;1160.5299853559322;1168.8714241433436;780.8477346917297;871.2333146830875;900.9459432434074;933.702203339599;946.7301818981557;5592.7762889484775;5811.738471768781;5859.150423188699;6067.969660151759;6104.389561043949;787.6849260295927;936.7377134402378;946.7349142240183;955.2119501411363;989.3569180032893;3892.2993861105274;4006.1587066715733;4021.7184797063924;4048.8621318477826;4142.08874047709;10088.192944564564;10729.141668666489;10892.891071089347;10899.237053406345;10948.945255273673;10120.898791012143;10701.78596428067;10815;10844.891551084545;10863;7792.961035194901;8208.794780130545;8245.752627421307;8264.090949995496;8284.171582841667;5729.4843464088435;5738.626989245686;5975.641461512323;6005.099235114768;6026.367231440811;10006.799864002534;10020;10030.64892728751;10042.347247428894;10066.29535932507;9246.95376523113;9663.951680241555;9886.604535818487;9892.901070989197;10229.028242316968;7013.368796808311;7325.413966882743;7388.1503627083575;7505.812354691177;7635.923640763744;8062.193780621891;8086.231807978233;8103.0681471631515;8110.310623596824;8194;7317.304856038668;7350.70597176107;7394.112706475257;7476.439267054884;7485.66314515859;5818.359980402237;7165.964170179116;7254.89117663246;7285.562866228043;7330.926690733025;6767.93232067673;6907.654617269217;6926.099607051051;6928.099347084847;6936.34104760047;4826.638002149783;4953.0589188054155;4972.8756781080765;4981.501849814989;5148.819791307286;4870.12337779189;4943.011397720542;5030.37120359955;5052.418971818288;5143.791209065792 05:25:17 INFO - PROCESS | 1655 | |4;prototype.html;292.7906546819024;297.45862530195046;308.39862268576275;309.5727895504204;313.07954613436493;4339.414179085822;5124.282600435938;5179.766910489027;5199.428062913076;5217.104506998614;4939.949920782871;5081.542661160494;5156.716380599066;5174.870628234296;5176.58587313015;3280.7539434542387;3828.25349056934;3956.3076461619244;3972.741771784825;4028.3554631259003;3094.9535756963664;4050.574689657588;4051.554329023805;4057.3508238681816;4067.884699956021;3918.74528155669;3936.409538569223;3948.624880636335;3962.187751510933;3972.423998520213;3475.739319551044;3725.9627403725885;3935.4096885467266;3958.366661334173;3964.603539646041;2962.2599095836267;3240.363616373716;3334.51649510822;3438.1232785639504;3445.7415693823064;4129.15352352767;4178.540360560336;4862.878428039292;5066.36670416198;5114.28400023997;3705.907352316187;3729.198222382201;4167.187398457302;4421.756803375813;4491.752953587551;4438.977805110953;5445.50990410865;5448.91826622601;5465.398806131322;5504.339479262474;3312.8840490582957;3449.844756985942;3617.240379520304;3778.3199024175638;3790.4503846942316;4906.215005599087;4999.150144475448;5656.745446454919;5685.971570142123;5719.771209151629;3304.9173770655443;3775.2260786538695;3786;3786.958586388765;3788.139954334515;3855.4216867469545;3860.2665493556133;3864.1112544115012;3872.3804191329814;3874.8837534874;3695.371786796251;3722.9813850930573;4278.593533614302;4462.3306504024;4494.662900282492;5471.699056551848;5485.4788795064405;5491.505764481216;5512.6968016759465;5517.61376703635;1018.8369860822381;1110.9222354435112;1256.6921104329315;1271.8092286156966;1305.797601371789;871.6774793326509;884.8363052835246;897.6409436225562;899.9460032398077;903.6475774447971;4428.7121337112985;5134.690653883235;5135.820246291362;5143.17709166539;5150.330457040561;4855.878603034952;4870.103916866493;5618.887622247532;5645.5483561314995;5667.036603777367;4853.883606770392;5085.516875896784;5092.821751238689;5095.592352611782;5107.642465027487;2243.05791567542;2288.302067869316;2469.962950555743;2482.7517248275026;2484.788792952619;4144.544100148981;4548.817307500074;4640.443146822403;4785.377900872933;4868.513148685174;3874.767513949172;4722.291656251589;4729.935764452971;4784.296872267158;4806.303086052503;4092.324766413568;4153.605407486284;4247.617714405718;4778.522147785194;4805.399325084364;3924.7448915820382;4250.193667691281;4647.907041859212;4653;4698.911946606474;4153.418521407066;4626.83806066786;4634.490206077397;4641.74672838325;4695.839913639627;4254.404383386266;4345.304751239786;4364.77786219859;4763.666543341933;4797.976010119927;2857.571364295289;3260.2501424672932;3272.5254838048827;3283.9507407389387;3308.6525914778604;4395.8021889014435;4616.361191776871;4641.194220738962;4653.603918824407;4689.373950587682;4014.598540145962;4227.93658095135;4534.886627834198;4550.271956486946;4589.954100458953;3697.741158118906;4003;4141.482314710661;4488.5960263576435;4547.522510136388;4948.4654860374185;5009.423916249678;5094.2867998479505;5182.740862956762;5245.239440281214;4984;4998.776034251052;5442.85700002988;5447.673139611636;5459.727013649381;5310.336207974004;5355.456158324764;5405.891882162414;5434.483724046209;5445.09421702401 05:25:17 INFO - PROCESS | 1655 | |5;yui.html;602.1944879858727;700.6391708270238;732.5238594913302;749.3480671815521;848.8514509960755;14665.413383464662;15499.767503487457;15913.283902224384;16097;16149;14683.486077987272;14718.558443246693;14860.628484287898;14866.702665946674;14908.701825963475;825.145973916996;845.6532821543168;849.558229720545;850.7490290364326;852.688768599463;674.2044387622603;694.7741983855242;821.9383546234027;844.2697067037;851.3870013590205;884.0319849764529;885.0927799006038;885.1104639836955;886.2998231397188;888.2716172738357;875.9737207883774;876.6975393489236;877.1097336203758;879.4371602174614;879.7316818370363;521.6035812782275;636.6657504809965;652.797632733854;653.0889409274037;657.4970147836908;14791.186484743335;14838.406463741438;14852.851471485255;14861.702765944621;14864.108153510715;13859.930700346535;14327.212003339811;14377.928110359382;14436.350364233635;14539.78190327146;12869.935650321782;12977;13088.476460941647;13091.47634094635;13101.213927164401;4509.503954564995;4589.839355622537;4700.341952126705;4764.6426518011285;4787.569118779292;80253.59873200655;111875;113156.86843131628;113325.43337283343;113467.43266283699;9317.953410233042;10967.50646220922;11300.830487542693;11610.477528511237;11625.476853541611;18398.632027359377;22080.447988800086;22270;22273.77726222717;22293.442663933532;7869.370450363957;7974.920250797534;8067.596620168968;8084.231997960184;8222.424430289822;4286.828526858891;4363.3620518521;4381.7370957742;4394.538573449806;4556.794944227518;3492.877749278763;3593.047842321787;3596.8741094062107;3597.2086141048926;3598.442241452579;652.3769799841141;664.315754772585;665.6272487407067;675.1021141881287;680.9182898052167;586.7652938824419;621.860081481663;626.9655168965751;629.3046183966742;641.7785863876956;8827.249683776823;9069.18377346042;9089.50007749567;9141.360104792733;9333.346665733334;548.2707998362168;601.9157317975487;611.7063809371526;617.9351168127372;628.5568674084759;4312.482502099705;4502.887427814266;4539.773011349419;4546.522615125365;4632.467266264355;106745;146599.2670036643;152198;152386;159260;114325.4283728576;141552;141699;143499;143675;70546.64726676437;120665.39667301608;121025;123169;125559;8605.35459840502;8634.481931084156;8648.135186481302;8676.175763302475;8802.397586535215;66431;67240;67989;68356.316436835;70160.64919675369;81436.18563814287;90754;101297.49351253196;103336.48331758293;104231;11672.88327116718;11753.941230294136;11846.703832403915;11898.762024759284;12217.045272756317;12213.755724885275;12217;12230.571929982409;12670.429830657828;12671.30307833078;11868.347240901488;11935.045196384443;11977.64067077989;12005.879941200476;12019;11752.529898804294;12069.27584344942;12097.697557561132;12387.566435175086;12546.184498007966;11550.075993920635;11667;11706.531738730355;11764;12415.317157556421;9549.474778887228;9919.603215871573;9985.900140998498;10187.541560629641;10245.692629221136;9137.328141142685;10059.380007242593;10067.554794997703;10077.272047459875;10078.082259909426 05:25:17 INFO - PROCESS | 1655 | __end_tp_report 05:25:17 INFO - PROCESS | 1655 | __start_cc_report 05:25:17 INFO - PROCESS | 1655 | _x_x_mozilla_cycle_collect,1409 05:25:17 INFO - PROCESS | 1655 | __end_cc_report 05:25:17 INFO - PROCESS | 1655 | __startTimestamp1470659117362__endTimestamp 05:25:17 INFO - PROCESS | 1655 | 05:25:17 INFO - PROCESS | 1655 | ------- Summary: start ------- 05:25:17 INFO - PROCESS | 1655 | Number of tests: 6 05:25:17 INFO - PROCESS | 1655 | 05:25:17 INFO - PROCESS | 1655 | [#0] dojo.html Cycles:165 Average:5683.44 Median:5854.59 stddev:640.74 (10.9%) stddev-sans-first:613.98 05:25:17 INFO - PROCESS | 1655 | Values: 3265.1 3293.8 3310.0 3312.4 3350.3 5480.6 5607.5 5641.5 5660.9 5683.5 5589.9 5618.1 5682.7 5690.7 5742.5 5088.7 5132.5 5143.6 5188.6 5222.1 5219.4 5230.3 5244.0 5244.8 5281.1 5104.3 5163.6 5181.7 5205.8 5267.9 5174.7 5175.1 5180.4 5181.6 5185.0 4727.2 5132.9 5153.3 5156.8 5205.2 5604.6 5640.8 5646.0 5659.3 5661.0 6130.2 6169.5 6170.5 6193.3 6216.1 6040.0 6171.3 6219.8 6232.8 6233.7 5817.1 5820.3 5844.3 5849.4 5850.3 6286.9 6296.6 6314.9 6326.6 6335.2 5936.1 6009.7 6054.4 6082.5 6106.7 6209.3 6214.4 6216.6 6217.3 6226.3 6152.6 6157.0 6189.7 6192.0 6201.2 6096.1 6100.5 6131.4 6136.2 6161.2 4922.6 4946.0 4954.5 4958.8 4973.4 4382.1 4391.2 4405.1 4428.5 4448.4 5475.6 5497.4 5550.8 5641.5 5649.1 6197.1 6252.8 6255.7 6271.8 6274.5 5620.4 5620.8 5622.8 5664.7 5672.0 5284.3 5348.2 5350.2 5359.2 5416.1 4660.8 6314.7 6323.4 6330.4 6337.0 6230.5 6239.2 6239.8 6241.8 6242.0 5010.8 6240.8 6297.4 6305.8 6337.0 5974.7 6026.7 6029.5 6039.9 6122.9 6112.7 6133.9 6181.5 6184.9 6189.9 5817.7 5854.6 5981.2 5995.1 6030.2 5915.9 5974.1 6065.1 6069.0 6101.2 5922.4 5939.4 5963.0 5970.1 5977.2 6050.0 6072.8 6073.4 6088.7 6095.7 5645.8 5775.3 5822.2 5853.4 5889.5 05:25:17 INFO - PROCESS | 1655 | 05:25:17 INFO - PROCESS | 1655 | [#1] ext.html Cycles:180 Average:67009.25 Median:35573.73 stddev:147542.36 (414.8%) stddev-sans-first:147871.62 05:25:17 INFO - PROCESS | 1655 | Values: 451.6 471.4 508.0 524.1 532.0 30452.5 39356.4 39739.0 40397.0 43834.1 42807.6 43159.8 43206.0 43257.0 43345.6 8754.5 9561.0 9732.5 9736.5 9766.3 8770.2 11692.8 11921.0 11942.5 12224.8 11841.9 11880.9 11891.2 11920.2 11950.5 11836.0 11921.1 11935.9 11936.2 11937.6 11694.6 11736.2 11772.7 11799.8 11854.6 32478.8 40306.4 40316.6 40420.6 40502.0 3589.2 3647.6 3648.9 3692.5 3926.8 30780.0 31344.4 32207.5 32358.2 32399.0 3263.2 3321.8 3346.1 3357.5 3378.8 370132.0 374755.0 375270.0 375430.1 378028.0 33183.8 33597.7 56283.7 56438.2 56660.4 34587.0 34655.3 34965.8 35029.8 35060.8 14451.9 14612.3 14627.0 14627.3 14630.9 11266.7 11477.7 12380.2 12392.3 12479.5 7549.9 7694.8 7719.3 7778.8 7791.5 3247.3 3267.2 3280.3 3283.9 3306.4 969.9 1011.5 1030.1 1035.5 1038.3 28760.3 30163.5 30232.7 30652.0 31106.9 994.1 1000.8 1052.4 1096.8 1148.4 13024.5 15515.6 15543.8 15823.4 16269.0 627747.0 631398.0 637491.0 638487.0 639462.0 57771.0 58318.7 58419.1 58595.0 58919.4 1242.8 1349.4 1358.5 1398.1 1407.2 27411.7 27780.4 30600.8 30745.9 31774.4 56943.7 57785.0 57795.4 57856.7 57994.0 594388.0 603619.0 604883.0 609916.0 618459.0 42781.8 43200.8 44131.8 44234.3 44255.3 47133.1 48267.0 48571.3 48681.5 48885.3 35301.0 36455.1 36925.1 37865.8 37940.0 39748.0 39821.6 40030.4 40817.7 41295.4 38464.4 38687.6 39316.4 39365.8 39396.4 29697.0 31469.4 31978.5 34818.1 35846.5 21386.9 24674.5 25617.9 25733.4 25800.0 05:25:17 INFO - PROCESS | 1655 | 05:25:17 INFO - PROCESS | 1655 | [#2] jquery.html Cycles:180 Average:36599.07 Median:22741.16 stddev:105031.26 (461.9%) stddev-sans-first:105290.64 05:25:17 INFO - PROCESS | 1655 | Values: 356.1 369.3 377.7 380.7 404.2 15395.6 15800.4 19994.0 20587.8 20651.9 17027.4 18943.9 20518.6 20935.6 21040.7 4499.7 5076.1 5239.5 5240.4 5247.7 5025.7 5104.3 5107.4 5109.1 5118.7 4880.6 5099.2 5103.7 5106.9 5114.0 4491.3 5163.4 5166.4 5171.7 5174.1 4834.1 5023.4 5030.0 5030.9 5033.7 17158.1 21457.7 21700.6 22086.3 22245.9 2253.0 2332.9 2337.1 2510.0 2862.9 8557.5 9511.6 9999.9 10542.7 10549.1 1814.9 1825.4 1842.6 1856.1 1882.5 125364.0 137669.0 137951.0 138100.0 139800.0 11480.7 11505.7 11600.8 11672.4 11870.3 14728.8 14736.6 14745.3 14757.9 14764.0 7235.7 8505.5 8576.3 8633.1 8643.7 8584.5 8596.0 8763.8 8774.7 8790.1 4470.5 4541.4 4543.8 4570.9 4631.4 1884.7 1928.0 1928.8 1929.4 1974.1 814.2 818.6 857.3 875.2 918.9 19943.9 21803.8 22725.7 22756.7 22765.2 697.0 863.6 963.3 1012.7 1025.9 8923.8 10391.3 10601.9 10852.2 11347.0 616040.0 625770.0 626219.0 629605.0 630741.0 130735.0 136790.0 143189.0 143276.0 143513.0 1010.9 1016.7 1034.0 1035.6 1036.3 18833.1 18961.3 19213.8 19224.6 19265.5 54844.0 54957.5 56277.4 56848.1 57672.7 78215.2 83355.0 83638.0 83717.7 84314.3 7049.5 7791.9 7994.5 8233.5 8275.8 9361.0 12588.7 12831.5 13155.5 13401.7 11253.0 11467.8 11624.7 11785.7 11853.3 11244.7 11506.6 11763.2 11792.3 11795.2 11610.6 11662.4 12115.9 12141.7 12167.7 8006.0 8046.5 8145.0 8261.4 8454.3 10253.0 11332.2 11641.5 11675.4 11684.8 05:25:17 INFO - PROCESS | 1655 | 05:25:17 INFO - PROCESS | 1655 | [#3] mootools.html Cycles:180 Average:5618.27 Median:4977.19 stddev:3144.51 (63.2%) stddev-sans-first:3136.78 05:25:17 INFO - PROCESS | 1655 | Values: 1325.7 1473.5 1509.5 1691.5 1872.4 7615.5 8441.5 8613.5 8625.4 8761.6 8551.9 8637.2 8643.0 8645.0 8646.2 2314.8 2340.8 2341.7 2344.2 2344.6 2304.1 2326.1 2359.9 2362.0 2364.8 2324.3 2346.5 2351.3 2361.1 2361.7 2300.8 2336.0 2336.5 2337.9 2344.4 2325.0 2325.5 2329.7 2335.1 2340.3 8632.8 8736.5 8738.9 8769.5 8809.9 5116.5 5323.2 5328.5 5340.6 5349.0 3560.4 3564.5 3619.0 3636.6 3638.9 1059.2 1067.5 1079.3 1080.7 1093.3 11382.4 11479.7 11551.7 11563.5 11564.1 4330.2 4381.6 4558.6 4562.3 4571.5 9010.9 9011.3 9013.2 9022.0 9040.7 5206.8 5208.2 5212.6 5212.9 5216.9 4062.9 4364.4 4369.0 4494.7 4846.5 3700.9 3754.8 3758.2 3787.5 3816.2 1002.7 1145.1 1147.7 1160.5 1168.9 780.8 871.2 900.9 933.7 946.7 5592.8 5811.7 5859.2 6068.0 6104.4 787.7 936.7 946.7 955.2 989.4 3892.3 4006.2 4021.7 4048.9 4142.1 10088.2 10729.1 10892.9 10899.2 10948.9 10120.9 10701.8 10815.0 10844.9 10863.0 7793.0 8208.8 8245.8 8264.1 8284.2 5729.5 5738.6 5975.6 6005.1 6026.4 10006.8 10020.0 10030.6 10042.3 10066.3 9247.0 9664.0 9886.6 9892.9 10229.0 7013.4 7325.4 7388.2 7505.8 7635.9 8062.2 8086.2 8103.1 8110.3 8194.0 7317.3 7350.7 7394.1 7476.4 7485.7 5818.4 7166.0 7254.9 7285.6 7330.9 6767.9 6907.7 6926.1 6928.1 6936.3 4826.6 4953.1 4972.9 4981.5 5148.8 4870.1 4943.0 5030.4 5052.4 5143.8 05:25:17 INFO - PROCESS | 1655 | 05:25:17 INFO - PROCESS | 1655 | [#4] prototype.html Cycles:180 Average:4122.00 Median:4490.17 stddev:1253.17 (27.9%) stddev-sans-first:1223.28 05:25:17 INFO - PROCESS | 1655 | Values: 292.8 297.5 308.4 309.6 313.1 4339.4 5124.3 5179.8 5199.4 5217.1 4939.9 5081.5 5156.7 5174.9 5176.6 3280.8 3828.3 3956.3 3972.7 4028.4 3095.0 4050.6 4051.6 4057.4 4067.9 3918.7 3936.4 3948.6 3962.2 3972.4 3475.7 3726.0 3935.4 3958.4 3964.6 2962.3 3240.4 3334.5 3438.1 3445.7 4129.2 4178.5 4862.9 5066.4 5114.3 3705.9 3729.2 4167.2 4421.8 4491.8 4439.0 5445.5 5448.9 5465.4 5504.3 3312.9 3449.8 3617.2 3778.3 3790.5 4906.2 4999.2 5656.7 5686.0 5719.8 3304.9 3775.2 3786.0 3787.0 3788.1 3855.4 3860.3 3864.1 3872.4 3874.9 3695.4 3723.0 4278.6 4462.3 4494.7 5471.7 5485.5 5491.5 5512.7 5517.6 1018.8 1110.9 1256.7 1271.8 1305.8 871.7 884.8 897.6 899.9 903.6 4428.7 5134.7 5135.8 5143.2 5150.3 4855.9 4870.1 5618.9 5645.5 5667.0 4853.9 5085.5 5092.8 5095.6 5107.6 2243.1 2288.3 2470.0 2482.8 2484.8 4144.5 4548.8 4640.4 4785.4 4868.5 3874.8 4722.3 4729.9 4784.3 4806.3 4092.3 4153.6 4247.6 4778.5 4805.4 3924.7 4250.2 4647.9 4653.0 4698.9 4153.4 4626.8 4634.5 4641.7 4695.8 4254.4 4345.3 4364.8 4763.7 4798.0 2857.6 3260.3 3272.5 3284.0 3308.7 4395.8 4616.4 4641.2 4653.6 4689.4 4014.6 4227.9 4534.9 4550.3 4590.0 3697.7 4003.0 4141.5 4488.6 4547.5 4948.5 5009.4 5094.3 5182.7 5245.2 4984.0 4998.8 5442.9 5447.7 5459.7 5310.3 5355.5 5405.9 5434.5 5445.1 05:25:17 INFO - PROCESS | 1655 | 05:25:17 INFO - PROCESS | 1655 | [#5] yui.html Cycles:180 Average:24944.36 Median:3942.64 stddev:40515.07 (1027.6%) stddev-sans-first:40587.50 05:25:17 INFO - PROCESS | 1655 | Values: 602.2 700.6 732.5 749.3 848.9 14665.4 15499.8 15913.3 16097.0 16149.0 14683.5 14718.6 14860.6 14866.7 14908.7 825.1 845.7 849.6 850.7 852.7 674.2 694.8 821.9 844.3 851.4 884.0 885.1 885.1 886.3 888.3 876.0 876.7 877.1 879.4 879.7 521.6 636.7 652.8 653.1 657.5 14791.2 14838.4 14852.9 14861.7 14864.1 13859.9 14327.2 14377.9 14436.4 14539.8 12869.9 12977.0 13088.5 13091.5 13101.2 4509.5 4589.8 4700.3 4764.6 4787.6 80253.6 111875.0 113156.9 113325.4 113467.4 9318.0 10967.5 11300.8 11610.5 11625.5 18398.6 22080.4 22270.0 22273.8 22293.4 7869.4 7974.9 8067.6 8084.2 8222.4 4286.8 4363.4 4381.7 4394.5 4556.8 3492.9 3593.0 3596.9 3597.2 3598.4 652.4 664.3 665.6 675.1 680.9 586.8 621.9 627.0 629.3 641.8 8827.2 9069.2 9089.5 9141.4 9333.3 548.3 601.9 611.7 617.9 628.6 4312.5 4502.9 4539.8 4546.5 4632.5 106745.0 146599.3 152198.0 152386.0 159260.0 114325.4 141552.0 141699.0 143499.0 143675.0 70546.6 120665.4 121025.0 123169.0 125559.0 8605.4 8634.5 8648.1 8676.2 8802.4 66431.0 67240.0 67989.0 68356.3 70160.6 81436.2 90754.0 101297.5 103336.5 104231.0 11672.9 11753.9 11846.7 11898.8 12217.0 12213.8 12217.0 12230.6 12670.4 12671.3 11868.3 11935.0 11977.6 12005.9 12019.0 11752.5 12069.3 12097.7 12387.6 12546.2 11550.1 11667.0 11706.5 11764.0 12415.3 9549.5 9919.6 9985.9 10187.5 10245.7 9137.3 10059.4 10067.6 10077.3 10078.1 05:25:17 INFO - PROCESS | 1655 | -------- Summary: end -------- 05:25:17 INFO - PROCESS | 1655 | 05:25:18 INFO - TEST-INFO | 1655: exit 0 05:25:18 INFO - TEST-OK | dromaeo_css | took 1118953ms 05:25:18 INFO - TEST-START | kraken 05:25:18 INFO - operating with platform_type : mac_ 05:25:18 INFO - Initialising browser for kraken test... 05:25:18 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/tmp7atoW4/profile http://localhost:49266/getInfo.html) 05:25:20 INFO - PROCESS | 1660 | __metrics Screen width/height:1600/1200 05:25:20 INFO - PROCESS | 1660 | colorDepth:24 05:25:20 INFO - PROCESS | 1660 | Browser inner width/height: 1024/689 05:25:20 INFO - PROCESS | 1660 | __metrics 05:25:20 INFO - PROCESS | 1660 | 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" 05:25:20 INFO - PROCESS | 1660 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:25:21 INFO - TEST-INFO | 1660: exit 0 05:25:21 INFO - Browser initialized. 05:25:21 INFO - Running cycle 1/1 for kraken test... 05:25:21 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/tmp7atoW4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:25:33 INFO - PROCESS | 1661 | RSS: Main: 243527680 05:25:33 INFO - PROCESS | 1661 | 05:25:35 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-beat-detection) 05:25:36 INFO - PROCESS | 1661 | RSS: Main: 293810176 05:25:36 INFO - PROCESS | 1661 | 05:25:39 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-dft) 05:25:39 INFO - PROCESS | 1661 | RSS: Main: 300863488 05:25:39 INFO - PROCESS | 1661 | 05:25:43 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-fft) 05:25:43 INFO - PROCESS | 1661 | RSS: Main: 395124736 05:25:43 INFO - PROCESS | 1661 | 05:25:45 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-oscillator) 05:25:46 INFO - PROCESS | 1661 | RSS: Main: 297250816 05:25:46 INFO - PROCESS | 1661 | 05:25:48 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:25:49 INFO - PROCESS | 1661 | RSS: Main: 302546944 05:25:49 INFO - PROCESS | 1661 | 05:25:51 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-darkroom) 05:25:52 INFO - PROCESS | 1661 | RSS: Main: 338817024 05:25:52 INFO - PROCESS | 1661 | 05:25:55 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-desaturate) 05:25:56 INFO - PROCESS | 1661 | RSS: Main: 341921792 05:25:56 INFO - PROCESS | 1661 | 05:25:59 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49266/tests/kraken/driver.html?testName=json-parse-financial) 05:25:59 INFO - PROCESS | 1661 | RSS: Main: 340885504 05:25:59 INFO - PROCESS | 1661 | 05:26:01 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49266/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:26:01 INFO - PROCESS | 1661 | RSS: Main: 312733696 05:26:01 INFO - PROCESS | 1661 | 05:26:03 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:26:04 INFO - PROCESS | 1661 | RSS: Main: 318238720 05:26:04 INFO - PROCESS | 1661 | 05:26:06 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:26:07 INFO - PROCESS | 1661 | RSS: Main: 315834368 05:26:07 INFO - PROCESS | 1661 | 05:26:10 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:26:10 INFO - PROCESS | 1661 | RSS: Main: 329035776 05:26:10 INFO - PROCESS | 1661 | 05:26:13 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:26:14 INFO - PROCESS | 1661 | RSS: Main: 330395648 05:26:14 INFO - PROCESS | 1661 | 05:26:16 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49266/tests/kraken/driver.html?testName=ai-astar) 05:26:16 INFO - PROCESS | 1661 | RSS: Main: 374394880 05:26:16 INFO - PROCESS | 1661 | 05:26:16 INFO - PROCESS | 1661 | __start_tp_report 05:26:16 INFO - PROCESS | 1661 | _x_x_mozilla_page_load 05:26:16 INFO - PROCESS | 1661 | _x_x_mozilla_page_load_details 05:26:16 INFO - PROCESS | 1661 | |i|pagename|runs| 05:26:16 INFO - PROCESS | 1661 | |0;ai-astar;99;109;111;112;111;113;111;110;111;110 05:26:16 INFO - PROCESS | 1661 | |1;audio-beat-detection;144;109;107;163;107;106;129;108;140;106 05:26:16 INFO - PROCESS | 1661 | |2;audio-dft;183;184;183;184;183;187;179;183;184;183 05:26:16 INFO - PROCESS | 1661 | |3;audio-fft;76;58;62;86;58;57;64;65;83;57 05:26:16 INFO - PROCESS | 1661 | |4;audio-oscillator;82;68;100;71;77;68;96;71;67;123 05:26:16 INFO - PROCESS | 1661 | |5;imaging-gaussian-blur;91;87;89;87;90;86;90;89;135;106 05:26:16 INFO - PROCESS | 1661 | |6;imaging-darkroom;94;93;94;94;95;93;93;113;148;150 05:26:16 INFO - PROCESS | 1661 | |7;imaging-desaturate;85;88;84;84;84;86;85;84;86;86 05:26:16 INFO - PROCESS | 1661 | |8;json-parse-financial;54;53;52;54;54;52;55;52;52;54 05:26:16 INFO - PROCESS | 1661 | |9;json-stringify-tinderbox;65;69;70;80;73;69;71;69;70;71 05:26:16 INFO - PROCESS | 1661 | |10;stanford-crypto-aes;83;83;82;89;102;100;103;78;81;76 05:26:16 INFO - PROCESS | 1661 | |11;stanford-crypto-ccm;143;150;148;152;154;145;127;137;142;141 05:26:16 INFO - PROCESS | 1661 | |12;stanford-crypto-pbkdf2;168;168;167;169;167;166;167;169;165;164 05:26:16 INFO - PROCESS | 1661 | |13;stanford-crypto-sha256-iterative;67;67;71;69;67;65;68;66;67;67 05:26:16 INFO - PROCESS | 1661 | __end_tp_report 05:26:16 INFO - PROCESS | 1661 | __start_cc_report 05:26:16 INFO - PROCESS | 1661 | _x_x_mozilla_cycle_collect,2093 05:26:16 INFO - PROCESS | 1661 | __end_cc_report 05:26:16 INFO - PROCESS | 1661 | __startTimestamp1470659176370__endTimestamp 05:26:16 INFO - PROCESS | 1661 | 05:26:16 INFO - PROCESS | 1661 | ------- Summary: start ------- 05:26:16 INFO - PROCESS | 1661 | Number of tests: 14 05:26:16 INFO - PROCESS | 1661 | 05:26:16 INFO - PROCESS | 1661 | [#0] ai-astar Cycles:10 Average:109.70 Median:111.00 stddev:3.92 (3.5%) stddev-sans-first:1.17 05:26:16 INFO - PROCESS | 1661 | Values: 99.0 109.0 111.0 112.0 111.0 113.0 111.0 110.0 111.0 110.0 05:26:16 INFO - PROCESS | 1661 | 05:26:16 INFO - PROCESS | 1661 | [#1] audio-beat-detection Cycles:10 Average:121.90 Median:119.00 stddev:20.72 (17.4%) stddev-sans-first:20.38 05:26:16 INFO - PROCESS | 1661 | Values: 144.0 109.0 107.0 163.0 107.0 106.0 129.0 108.0 140.0 106.0 05:26:16 INFO - PROCESS | 1661 | 05:26:16 INFO - PROCESS | 1661 | [#2] audio-dft Cycles:10 Average:183.30 Median:183.50 stddev:1.95 (1.1%) stddev-sans-first:2.06 05:26:16 INFO - PROCESS | 1661 | Values: 183.0 184.0 183.0 184.0 183.0 187.0 179.0 183.0 184.0 183.0 05:26:16 INFO - PROCESS | 1661 | 05:26:16 INFO - PROCESS | 1661 | [#3] audio-fft Cycles:10 Average:66.60 Median:64.50 stddev:11.04 (17.1%) stddev-sans-first:11.17 05:26:16 INFO - PROCESS | 1661 | Values: 76.0 58.0 62.0 86.0 58.0 57.0 64.0 65.0 83.0 57.0 05:26:16 INFO - PROCESS | 1661 | 05:26:16 INFO - PROCESS | 1661 | [#4] audio-oscillator Cycles:10 Average:82.30 Median:71.00 stddev:18.51 (26.1%) stddev-sans-first:19.63 05:26:16 INFO - PROCESS | 1661 | Values: 82.0 68.0 100.0 71.0 77.0 68.0 96.0 71.0 67.0 123.0 05:26:16 INFO - PROCESS | 1661 | 05:26:16 INFO - PROCESS | 1661 | [#5] imaging-gaussian-blur Cycles:10 Average:95.00 Median:89.00 stddev:15.16 (17.0%) stddev-sans-first:16.01 05:26:16 INFO - PROCESS | 1661 | Values: 91.0 87.0 89.0 87.0 90.0 86.0 90.0 89.0 135.0 106.0 05:26:16 INFO - PROCESS | 1661 | 05:26:16 INFO - PROCESS | 1661 | [#6] imaging-darkroom Cycles:10 Average:106.70 Median:93.50 stddev:23.10 (24.7%) stddev-sans-first:24.04 05:26:16 INFO - PROCESS | 1661 | Values: 94.0 93.0 94.0 94.0 95.0 93.0 93.0 113.0 148.0 150.0 05:26:16 INFO - PROCESS | 1661 | 05:26:16 INFO - PROCESS | 1661 | [#7] imaging-desaturate Cycles:10 Average:85.20 Median:85.50 stddev:1.32 (1.5%) stddev-sans-first:1.39 05:26:16 INFO - PROCESS | 1661 | Values: 85.0 88.0 84.0 84.0 84.0 86.0 85.0 84.0 86.0 86.0 05:26:16 INFO - PROCESS | 1661 | 05:26:16 INFO - PROCESS | 1661 | [#8] json-parse-financial Cycles:10 Average:53.20 Median:54.00 stddev:1.14 (2.1%) stddev-sans-first:1.17 05:26:16 INFO - PROCESS | 1661 | Values: 54.0 53.0 52.0 54.0 54.0 52.0 55.0 52.0 52.0 54.0 05:26:16 INFO - PROCESS | 1661 | 05:26:16 INFO - PROCESS | 1661 | [#9] json-stringify-tinderbox Cycles:10 Average:70.70 Median:70.50 stddev:3.86 (5.5%) stddev-sans-first:3.50 05:26:16 INFO - PROCESS | 1661 | Values: 65.0 69.0 70.0 80.0 73.0 69.0 71.0 69.0 70.0 71.0 05:26:16 INFO - PROCESS | 1661 | 05:26:16 INFO - PROCESS | 1661 | [#10] stanford-crypto-aes Cycles:10 Average:87.70 Median:81.50 stddev:10.24 (12.6%) stddev-sans-first:10.72 05:26:16 INFO - PROCESS | 1661 | Values: 83.0 83.0 82.0 89.0 102.0 100.0 103.0 78.0 81.0 76.0 05:26:16 INFO - PROCESS | 1661 | 05:26:16 INFO - PROCESS | 1661 | [#11] stanford-crypto-ccm Cycles:10 Average:143.90 Median:146.50 stddev:7.95 (5.4%) stddev-sans-first:8.43 05:26:16 INFO - PROCESS | 1661 | Values: 143.0 150.0 148.0 152.0 154.0 145.0 127.0 137.0 142.0 141.0 05:26:16 INFO - PROCESS | 1661 | 05:26:16 INFO - PROCESS | 1661 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.00 Median:167.50 stddev:1.63 (1.0%) stddev-sans-first:1.69 05:26:16 INFO - PROCESS | 1661 | Values: 168.0 168.0 167.0 169.0 167.0 166.0 167.0 169.0 165.0 164.0 05:26:16 INFO - PROCESS | 1661 | 05:26:16 INFO - PROCESS | 1661 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.40 Median:67.00 stddev:1.65 (2.5%) stddev-sans-first:1.74 05:26:16 INFO - PROCESS | 1661 | Values: 67.0 67.0 71.0 69.0 67.0 65.0 68.0 66.0 67.0 67.0 05:26:16 INFO - PROCESS | 1661 | -------- Summary: end -------- 05:26:16 INFO - PROCESS | 1661 | 05:26:17 INFO - TEST-INFO | 1661: exit 0 05:26:17 INFO - TEST-OK | kraken | took 59082ms 05:26:17 INFO - SUITE-END | took 1178s 05:26:17 INFO - Completed test suite (00:19:38) 05:26:17 INFO - javascript benchmark 05:26:17 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3265.134739294087, 3293.7859039162454, 3309.98345008275, 3312.420326442872, 3350.296437748073, 5480.6437581557175, 5607.523360514356, 5641.464060914212, 5660.915086273709, 5683.460071293226, 5589.944100558993, 5618.073017952043, 5682.715864206793, 5690.658560486363, 5742.54059675225, 5088.720120393377, 5132.5124113209185, 5143.614228932826, 5188.584913206934, 5222.138347172731, 5219.399769026553, 5230.267762513251, 5244.0, 5244.842654720345, 5281.102212623844, 5104.310918026056, 5163.63854530183, 5181.715005674687, 5205.817796377147, 5267.894642107175, 5174.715390653512, 5175.068487672217, 5180.378354597435, 5181.5854731621375, 5184.963007398535, 4727.2436410174205, 5132.923006154873, 5153.252778347156, 5156.819511317123, 5205.167173252299, 5604.551635869161, 5640.830775076754, 5645.971770141164, 5659.349174844904, 5661.037623603997, 6130.203073600448, 6169.475594574466, 6170.50635949123, 6193.3496982816605, 6216.129741836146, 6039.969800151015, 6171.25944886612, 6219.751209951596, 6232.781852635136, 6233.657148856811, 5817.0983497557945, 5820.330661973885, 5844.26946631671, 5849.444302791228, 5850.268716410449, 6286.9056964145575, 6296.622202667854, 6314.873702525923, 6326.557141000086, 6335.239771227482, 5936.1392598073035, 6009.729562169713, 6054.39456054391, 6082.452579267887, 6106.72519736604, 6209.285932117864, 6214.4407003369915, 6216.595921265103, 6217.285012223652, 6226.346233645492, 6152.600080994721, 6156.969215153896, 6189.690515474208, 6191.9690401547705, 6201.1938448002375, 6096.078966295426, 6100.450959413674, 6131.386861313922, 6136.202293701879, 6161.199044124325, 4922.606191504671, 4945.975270123627, 4954.504549545017, 4958.801647934042, 4973.378327709036, 4382.05785756064, 4391.209582275221, 4405.118976204772, 4428.512863585003, 4448.377227188196, 5475.561955043587, 5497.39528651848, 5550.8057217997175, 5641.5204707600315, 5649.067903795828, 6197.070439434121, 6252.843678907972, 6255.6872156392, 6271.843203919847, 6274.466670333073, 5620.409856965041, 5620.803271885465, 5622.803201887914, 5664.745086471119, 5672.007398705293, 5284.286621306074, 5348.197770334481, 5350.170723537858, 5359.249705041298, 5416.133418653076, 4660.836870709509, 6314.747410103728, 6323.36766323364, 6330.430261276507, 6337.017762246859, 6230.470410015199, 6239.220097487814, 6239.844003899939, 6241.750329986749, 6242.032484964838, 5010.799568017239, 6240.781572644935, 6297.4017468340435, 6305.8108256752375, 6336.968315158395, 5974.671393073423, 6026.698665066817, 6029.457348838626, 6039.912815693042, 6122.877542449215, 6112.730468808142, 6133.916185115316, 6181.505479561714, 6184.907226391698, 6189.907151392643, 5817.738201781015, 5854.590178687451, 5981.222441082631, 5995.130706047686, 6030.216071910623, 5915.852103697442, 5974.074018527135, 6065.090236464565, 6069.0, 6101.156499727147, 5922.378150294157, 5939.376365481562, 5962.970185149046, 5970.0746384310505, 5977.163197152315, 6050.0, 6072.787452439144, 6073.423024812636, 6088.726007329769, 6095.664738439428, 5645.830625081254, 5775.27809023872, 5822.214001109796, 5853.443922827325, 5889.499392551681], "value": 5642.936579702339, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [451.5619848746717, 471.3848427801718, 507.968823288724, 524.1430261522412, 532.0237364436259, 30452.54321185181, 39356.40965385522, 39739.0, 40396.99007524813, 43834.12331753363, 42807.571924280746, 43159.78420107903, 43206.0, 43257.0, 43345.56654433462, 8754.47473151613, 9560.996095409986, 9732.51337433129, 9736.464494452837, 9766.316357854954, 8770.166834150745, 11692.824607630891, 11921.0, 11942.522299108025, 12224.755504889898, 11841.881581184163, 11880.881191188064, 11891.167618266725, 11920.225185362968, 11950.521979120867, 11836.0, 11921.105917056255, 11935.880641193651, 11936.224145430519, 11937.582184623583, 11694.649160525198, 11736.23714458566, 11772.705682357924, 11799.764004719944, 11854.585089521825, 32478.837605812056, 40306.395404068964, 40316.59683403187, 40420.595794041976, 40502.0, 3589.174489867319, 3647.598764135943, 3648.854045838163, 3692.5015122958325, 3926.823292951824, 30780.0, 31344.37311253762, 32207.51688724671, 32358.191045223823, 32399.0, 3263.1515805890403, 3321.78408403453, 3346.1300061983816, 3357.496375543688, 3378.8310584470923, 370132.0, 374755.0, 375270.0, 375430.1228493867, 378027.99999999726, 33183.834080829925, 33597.66402335945, 56283.71858140683, 56438.15342769862, 56660.43339566634, 34587.0, 34655.30689386198, 34965.825170873984, 35029.824850876095, 35060.824695876356, 14451.91610629207, 14612.342444590018, 14627.0, 14627.341769620392, 14630.853691463162, 11266.662000139844, 11477.71305717347, 12380.195287306296, 12392.256464612152, 12479.5008199671, 7549.924500755032, 7694.807629809299, 7719.266669666373, 7778.805529861799, 7791.532508049422, 3247.3342964692183, 3267.2485328374605, 3280.2783387654677, 3283.9343213135603, 3306.3717893600137, 969.8690676758688, 1011.4740335025746, 1030.1141018723893, 1035.4874337202982, 1038.2680210451613, 28760.280992974924, 30163.547546786816, 30232.697673023427, 30652.0, 31106.911258105858, 994.125169850527, 1000.7798284377425, 1052.3633201912885, 1096.8282800055933, 1148.3683973814368, 13024.54414095502, 15515.612109697347, 15543.834212434112, 15823.446179383667, 16269.0, 627747.0, 631398.0, 637491.0, 638487.0, 639462.0, 57771.0, 58318.7084064577, 58419.123713144334, 58595.0, 58919.41080589225, 1242.8011518157052, 1349.426493740176, 1358.5245164192652, 1398.0912406935574, 1407.183833376639, 27411.725882740917, 27780.44439111166, 30600.846995764878, 30745.92389266365, 31774.36451271008, 56943.71528142333, 57785.0, 57795.42204577901, 57856.71071644615, 57994.0, 594388.0, 603619.0, 604883.0, 609916.0, 618459.0, 42781.78609106935, 43200.783996079816, 44131.77934110309, 44234.3364849534, 44255.33616995812, 47133.057338853716, 48267.0, 48571.27143092786, 48681.5131848677, 48885.26672099993, 35301.0, 36455.08862278464, 36925.13966480447, 37865.81067094647, 37940.0, 39748.0, 39821.60178398179, 40030.39954400628, 40817.726350947494, 41295.380569290886, 38464.423033653955, 38687.6131238684, 39316.41025384564, 39365.80317098396, 39396.40905386479, 29697.0, 31469.370612588078, 31978.52032219472, 34818.129546761535, 35846.46230306599, 21386.893065535194, 24674.50650987006, 25617.871910640326, 25733.3566660835, 25800.0], "value": 19202.99593793008, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [356.08663777410953, 369.30386221971577, 377.7469095705878, 380.68783597450096, 404.2077528045032, 15395.615109622251, 15800.446984355536, 19994.0, 20587.794122058775, 20651.896740516313, 17027.40404085857, 18943.905280473613, 20518.589628207428, 20935.581288374222, 21040.684389734168, 4499.70751901127, 5076.086304465195, 5239.476052394768, 5240.371155461331, 5247.685138891659, 5025.748712564376, 5104.285400043978, 5107.387113546361, 5109.080365534202, 5118.72031992002, 4880.585150262232, 5099.235114732783, 5103.744812759366, 5106.897862042757, 5114.002769459957, 4491.303847903564, 5163.3545806774155, 5166.405863325728, 5171.663841850268, 5174.094533456649, 4834.081524510332, 5023.372078490189, 5030.0, 5030.874228144289, 5033.723145227011, 17158.14209289543, 21457.67813482799, 21700.565988680137, 22086.337409877568, 22245.888770556045, 2253.019936327703, 2332.93001209964, 2337.135259953828, 2510.0, 2862.8834754445784, 8557.529335886524, 9511.571979260882, 9999.900000999969, 10542.736431589195, 10549.05058544727, 1814.872958892878, 1825.3520000399933, 1842.6406850664125, 1856.053412759489, 1882.4634979031048, 125364.0, 137669.0, 137951.0, 138100.0, 139800.0, 11480.655580332603, 11505.654830355103, 11600.825987610193, 11672.358020308795, 11870.28778273289, 14728.779068313985, 14736.557903262705, 14745.262736863115, 14757.893158013192, 14764.0, 7235.7467488638695, 8505.489670619783, 8576.313894888393, 8633.050364459905, 8643.740687779376, 8584.484930904166, 8595.957020214859, 8763.824723505622, 8774.692885748967, 8790.077041910636, 4470.463544374631, 4541.364209010741, 4543.818247270072, 4570.885727856764, 4631.374764406829, 1884.6607610629944, 1927.9903600481907, 1928.7781905080815, 1929.4211736479, 1974.131382191831, 814.1532805881935, 818.6316157729045, 857.3227150550995, 875.2472873328932, 918.9218916392048, 19943.900280498503, 21803.784860557767, 22725.659115113285, 22756.658650120262, 22765.203217887294, 696.979090627282, 863.6027427383349, 963.3449254506862, 1012.6911292055994, 1025.8512515685334, 8923.776905577282, 10391.324563903323, 10601.946990265154, 10852.240343175901, 11347.0, 616040.0, 625770.0, 626219.0, 629605.0, 630741.0, 130735.03897441545, 136790.0, 143189.0, 143276.0, 143513.0, 1010.9443980580992, 1016.694991502561, 1033.9741506462249, 1035.6427032673716, 1036.2849633752687, 18833.058347082864, 18961.336353227573, 19213.847169169894, 19224.615507690047, 19265.5183620405, 54844.0, 54957.450425496834, 56277.43722562722, 56848.14727779169, 57672.71163644155, 78215.2178478208, 83355.0, 83638.0, 83717.74423383776, 84314.3137137266, 7049.471289653194, 7791.92208077912, 7994.480358776661, 8233.46482478637, 8275.793105172419, 9361.017093205115, 12588.748225035632, 12831.486740530274, 13155.53955611549, 13401.731965360834, 11253.0, 11467.770644587228, 11624.709382265512, 11785.705357366134, 11853.288802671523, 11244.66266012021, 11506.597269095542, 11763.235389699643, 11792.287773812242, 11795.233309834834, 11610.593629222936, 11662.416879155839, 12115.878841211475, 12141.696457588278, 12167.695807604881, 8006.0, 8046.476978996347, 8144.959275203586, 8261.421700480909, 8454.32365410778, 10253.0, 11332.150088743541, 11641.53433862636, 11675.416229188337, 11684.824727629262], "value": 9445.232596437902, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1325.6950901292696, 1473.4842805018245, 1509.501864384753, 1691.4840973503076, 1872.438268519444, 7615.543067415959, 8441.451305665136, 8613.526256055915, 8625.439346442477, 8761.56192190391, 8551.87172192416, 8637.179467950551, 8643.049264580892, 8645.005824330205, 8646.221840034395, 2314.7569505201923, 2340.8361414700976, 2341.672165896776, 2344.1678204237505, 2344.589696803061, 2304.135949019118, 2326.1044497868356, 2359.8584084954873, 2361.9763802361927, 2364.8462849914786, 2324.32594547581, 2346.4837735698206, 2351.318117745852, 2361.067378385537, 2361.681173041643, 2300.804431623314, 2335.9532809343887, 2336.4626135988647, 2337.8947947342326, 2344.3904584808074, 2325.0, 2325.5116425550655, 2329.7087864017, 2335.1243283768586, 2340.3447034830274, 8632.78418039554, 8736.51949142797, 8738.912610873873, 8769.473831570127, 8809.911900881038, 5116.462771408986, 5323.1749078892835, 5328.5470734987575, 5340.62615616907, 5349.0, 3560.359135355635, 3564.48314994327, 3618.963810361889, 3636.563612366533, 3638.8544458221904, 1059.2267644619471, 1067.546292825546, 1079.3092420850662, 1080.6595922284455, 1093.2675107677796, 11382.373969431677, 11479.65561033162, 11551.653450396501, 11563.479643416067, 11564.13269004828, 4330.242207613655, 4381.627561657231, 4558.635309175258, 4562.338460923148, 4571.474280457724, 9010.86483702745, 9011.2790976723, 9013.233875120557, 9022.0, 9040.683576074805, 5206.843794686166, 5208.244804503402, 5212.635115541952, 5212.8957420851375, 5216.921746173811, 4062.939055914164, 4364.367166760802, 4368.956310436918, 4494.73031618104, 4846.466888642247, 3700.9074773130887, 3754.7747135171976, 3758.248350329945, 3787.450819631138, 3816.179521402856, 1002.7042022603204, 1145.1010956399186, 1147.6671765188003, 1160.5299853559322, 1168.8714241433436, 780.8477346917297, 871.2333146830875, 900.9459432434074, 933.702203339599, 946.7301818981557, 5592.7762889484775, 5811.738471768781, 5859.150423188699, 6067.969660151759, 6104.389561043949, 787.6849260295927, 936.7377134402378, 946.7349142240183, 955.2119501411363, 989.3569180032893, 3892.2993861105274, 4006.1587066715733, 4021.7184797063924, 4048.8621318477826, 4142.08874047709, 10088.192944564564, 10729.141668666489, 10892.891071089347, 10899.237053406345, 10948.945255273673, 10120.898791012143, 10701.78596428067, 10815.0, 10844.891551084545, 10863.0, 7792.961035194901, 8208.794780130545, 8245.752627421307, 8264.090949995496, 8284.171582841667, 5729.4843464088435, 5738.626989245686, 5975.641461512323, 6005.099235114768, 6026.367231440811, 10006.799864002534, 10020.0, 10030.64892728751, 10042.347247428894, 10066.29535932507, 9246.95376523113, 9663.951680241555, 9886.604535818487, 9892.901070989197, 10229.028242316968, 7013.368796808311, 7325.413966882743, 7388.1503627083575, 7505.812354691177, 7635.923640763744, 8062.193780621891, 8086.231807978233, 8103.0681471631515, 8110.310623596824, 8194.0, 7317.304856038668, 7350.70597176107, 7394.112706475257, 7476.439267054884, 7485.66314515859, 5818.359980402237, 7165.964170179116, 7254.89117663246, 7285.562866228043, 7330.926690733025, 6767.93232067673, 6907.654617269217, 6926.099607051051, 6928.099347084847, 6936.34104760047, 4826.638002149783, 4953.0589188054155, 4972.8756781080765, 4981.501849814989, 5148.819791307286, 4870.12337779189, 4943.011397720542, 5030.37120359955, 5052.418971818288, 5143.791209065792], "value": 4512.390892981481, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [292.7906546819024, 297.45862530195046, 308.39862268576275, 309.5727895504204, 313.07954613436493, 4339.414179085822, 5124.282600435938, 5179.766910489027, 5199.428062913076, 5217.104506998614, 4939.949920782871, 5081.542661160494, 5156.716380599066, 5174.870628234296, 5176.58587313015, 3280.7539434542387, 3828.25349056934, 3956.3076461619244, 3972.741771784825, 4028.3554631259003, 3094.9535756963664, 4050.574689657588, 4051.554329023805, 4057.3508238681816, 4067.884699956021, 3918.74528155669, 3936.409538569223, 3948.624880636335, 3962.187751510933, 3972.423998520213, 3475.739319551044, 3725.9627403725885, 3935.4096885467266, 3958.366661334173, 3964.603539646041, 2962.2599095836267, 3240.363616373716, 3334.51649510822, 3438.1232785639504, 3445.7415693823064, 4129.15352352767, 4178.540360560336, 4862.878428039292, 5066.36670416198, 5114.28400023997, 3705.907352316187, 3729.198222382201, 4167.187398457302, 4421.756803375813, 4491.752953587551, 4438.977805110953, 5445.50990410865, 5448.91826622601, 5465.398806131322, 5504.339479262474, 3312.8840490582957, 3449.844756985942, 3617.240379520304, 3778.3199024175638, 3790.4503846942316, 4906.215005599087, 4999.150144475448, 5656.745446454919, 5685.971570142123, 5719.771209151629, 3304.9173770655443, 3775.2260786538695, 3786.0, 3786.958586388765, 3788.139954334515, 3855.4216867469545, 3860.2665493556133, 3864.1112544115012, 3872.3804191329814, 3874.8837534874, 3695.371786796251, 3722.9813850930573, 4278.593533614302, 4462.3306504024, 4494.662900282492, 5471.699056551848, 5485.4788795064405, 5491.505764481216, 5512.6968016759465, 5517.61376703635, 1018.8369860822381, 1110.9222354435112, 1256.6921104329315, 1271.8092286156966, 1305.797601371789, 871.6774793326509, 884.8363052835246, 897.6409436225562, 899.9460032398077, 903.6475774447971, 4428.7121337112985, 5134.690653883235, 5135.820246291362, 5143.17709166539, 5150.330457040561, 4855.878603034952, 4870.103916866493, 5618.887622247532, 5645.5483561314995, 5667.036603777367, 4853.883606770392, 5085.516875896784, 5092.821751238689, 5095.592352611782, 5107.642465027487, 2243.05791567542, 2288.302067869316, 2469.962950555743, 2482.7517248275026, 2484.788792952619, 4144.544100148981, 4548.817307500074, 4640.443146822403, 4785.377900872933, 4868.513148685174, 3874.767513949172, 4722.291656251589, 4729.935764452971, 4784.296872267158, 4806.303086052503, 4092.324766413568, 4153.605407486284, 4247.617714405718, 4778.522147785194, 4805.399325084364, 3924.7448915820382, 4250.193667691281, 4647.907041859212, 4653.0, 4698.911946606474, 4153.418521407066, 4626.83806066786, 4634.490206077397, 4641.74672838325, 4695.839913639627, 4254.404383386266, 4345.304751239786, 4364.77786219859, 4763.666543341933, 4797.976010119927, 2857.571364295289, 3260.2501424672932, 3272.5254838048827, 3283.9507407389387, 3308.6525914778604, 4395.8021889014435, 4616.361191776871, 4641.194220738962, 4653.603918824407, 4689.373950587682, 4014.598540145962, 4227.93658095135, 4534.886627834198, 4550.271956486946, 4589.954100458953, 3697.741158118906, 4003.0, 4141.482314710661, 4488.5960263576435, 4547.522510136388, 4948.4654860374185, 5009.423916249678, 5094.2867998479505, 5182.740862956762, 5245.239440281214, 4984.0, 4998.776034251052, 5442.85700002988, 5447.673139611636, 5459.727013649381, 5310.336207974004, 5355.456158324764, 5405.891882162414, 5434.483724046209, 5445.09421702401], "value": 3736.2744010141987, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [602.1944879858727, 700.6391708270238, 732.5238594913302, 749.3480671815521, 848.8514509960755, 14665.413383464662, 15499.767503487457, 15913.283902224384, 16097.0, 16149.0, 14683.486077987272, 14718.558443246693, 14860.628484287898, 14866.702665946674, 14908.701825963475, 825.145973916996, 845.6532821543168, 849.558229720545, 850.7490290364326, 852.688768599463, 674.2044387622603, 694.7741983855242, 821.9383546234027, 844.2697067037, 851.3870013590205, 884.0319849764529, 885.0927799006038, 885.1104639836955, 886.2998231397188, 888.2716172738357, 875.9737207883774, 876.6975393489236, 877.1097336203758, 879.4371602174614, 879.7316818370363, 521.6035812782275, 636.6657504809965, 652.797632733854, 653.0889409274037, 657.4970147836908, 14791.186484743335, 14838.406463741438, 14852.851471485255, 14861.702765944621, 14864.108153510715, 13859.930700346535, 14327.212003339811, 14377.928110359382, 14436.350364233635, 14539.78190327146, 12869.935650321782, 12977.0, 13088.476460941647, 13091.47634094635, 13101.213927164401, 4509.503954564995, 4589.839355622537, 4700.341952126705, 4764.6426518011285, 4787.569118779292, 80253.59873200655, 111875.0, 113156.86843131628, 113325.43337283343, 113467.43266283699, 9317.953410233042, 10967.50646220922, 11300.830487542693, 11610.477528511237, 11625.476853541611, 18398.632027359377, 22080.447988800086, 22270.0, 22273.77726222717, 22293.442663933532, 7869.370450363957, 7974.920250797534, 8067.596620168968, 8084.231997960184, 8222.424430289822, 4286.828526858891, 4363.3620518521, 4381.7370957742, 4394.538573449806, 4556.794944227518, 3492.877749278763, 3593.047842321787, 3596.8741094062107, 3597.2086141048926, 3598.442241452579, 652.3769799841141, 664.315754772585, 665.6272487407067, 675.1021141881287, 680.9182898052167, 586.7652938824419, 621.860081481663, 626.9655168965751, 629.3046183966742, 641.7785863876956, 8827.249683776823, 9069.18377346042, 9089.50007749567, 9141.360104792733, 9333.346665733334, 548.2707998362168, 601.9157317975487, 611.7063809371526, 617.9351168127372, 628.5568674084759, 4312.482502099705, 4502.887427814266, 4539.773011349419, 4546.522615125365, 4632.467266264355, 106745.0, 146599.2670036643, 152198.0, 152386.0, 159260.0, 114325.4283728576, 141552.0, 141699.0, 143499.0, 143675.0, 70546.64726676437, 120665.39667301608, 121025.0, 123169.0, 125559.0, 8605.35459840502, 8634.481931084156, 8648.135186481302, 8676.175763302475, 8802.397586535215, 66431.0, 67240.0, 67989.0, 68356.316436835, 70160.64919675369, 81436.18563814287, 90754.0, 101297.49351253196, 103336.48331758293, 104231.0, 11672.88327116718, 11753.941230294136, 11846.703832403915, 11898.762024759284, 12217.045272756317, 12213.755724885275, 12217.0, 12230.571929982409, 12670.429830657828, 12671.30307833078, 11868.347240901488, 11935.045196384443, 11977.64067077989, 12005.879941200476, 12019.0, 11752.529898804294, 12069.27584344942, 12097.697557561132, 12387.566435175086, 12546.184498007966, 11550.075993920635, 11667.0, 11706.531738730355, 11764.0, 12415.317157556421, 9549.474778887228, 9919.603215871573, 9985.900140998498, 10187.541560629641, 10245.692629221136, 9137.328141142685, 10059.380007242593, 10067.554794997703, 10077.272047459875, 10078.082259909426], "value": 7669.445879253113, "unit": "score"}], "name": "dromaeo_css", "value": 7138.785288784719, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 109.0, 111.0, 112.0, 111.0, 113.0, 111.0, 110.0, 111.0, 110.0], "value": 109.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 109.0, 107.0, 163.0, 107.0, 106.0, 129.0, 108.0, 140.0, 106.0], "value": 121.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 184.0, 183.0, 184.0, 183.0, 187.0, 179.0, 183.0, 184.0, 183.0], "value": 183.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 58.0, 62.0, 86.0, 58.0, 57.0, 64.0, 65.0, 83.0, 57.0], "value": 66.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 68.0, 100.0, 71.0, 77.0, 68.0, 96.0, 71.0, 67.0, 123.0], "value": 82.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 87.0, 89.0, 87.0, 90.0, 86.0, 90.0, 89.0, 135.0, 106.0], "value": 95.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 94.0, 94.0, 95.0, 93.0, 93.0, 113.0, 148.0, 150.0], "value": 106.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 88.0, 84.0, 84.0, 84.0, 86.0, 85.0, 84.0, 86.0, 86.0], "value": 85.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 52.0, 54.0, 54.0, 52.0, 55.0, 52.0, 52.0, 54.0], "value": 53.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 69.0, 70.0, 80.0, 73.0, 69.0, 71.0, 69.0, 70.0, 71.0], "value": 70.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 82.0, 89.0, 102.0, 100.0, 103.0, 78.0, 81.0, 76.0], "value": 87.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 150.0, 148.0, 152.0, 154.0, 145.0, 127.0, 137.0, 142.0, 141.0], "value": 143.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 168.0, 167.0, 169.0, 167.0, 166.0, 167.0, 169.0, 165.0, 164.0], "value": 167.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 71.0, 69.0, 67.0, 65.0, 68.0, 66.0, 67.0, 67.0], "value": 67.4, "unit": "score"}], "name": "kraken", "value": 1440.6000000000004, "alertThreshold": 2.0}]} 05:26:17 INFO - Return code: 0 05:26:17 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:26:17 INFO - # TBPL SUCCESS # 05:26:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:26:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:26:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:26:17 INFO - 2016-08-08 05:26:17.689 screenresolution[1665:9740] starting screenresolution argv=screenresolution get 05:26:17 INFO - 2016-08-08 05:26:17.692 screenresolution[1665:9740] Display 0: 1600x1200x32@60 05:26:17 INFO - 2016-08-08 05:26:17.697 screenresolution[1666:9743] starting screenresolution argv=screenresolution list 05:26:17 INFO - Available Modes on Display 0 05:26:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:26:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:26:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:26:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:26:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:26:17 INFO - Intel Iris: 05:26:17 INFO - Chipset Model: Intel Iris 05:26:17 INFO - Type: GPU 05:26:17 INFO - Bus: Built-In 05:26:17 INFO - VRAM (Dynamic, Max): 1536 MB 05:26:17 INFO - Vendor: Intel (0x8086) 05:26:17 INFO - Device ID: 0x0a2e 05:26:17 INFO - Revision ID: 0x0009 05:26:17 INFO - Displays: 05:26:17 INFO - Display: 05:26:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:26:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:26:17 INFO - Display Serial Number: 1600x1200 60 05:26:17 INFO - Main Display: Yes 05:26:17 INFO - Mirror: Off 05:26:17 INFO - Online: Yes 05:26:17 INFO - Rotation: Supported 05:26:17 INFO - Return code: 0 05:26:17 INFO - Running post-action listener: _resource_record_post_action 05:26:17 INFO - [mozharness: 2016-08-08 12:26:17.932629Z] Finished run-tests step (success) 05:26:17 INFO - Running post-run listener: _resource_record_post_run 05:26:17 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 31411200; Write bytes: 706799104; Read time: 299; Write time: 1888 05:26:17 INFO - TinderboxPrint: CPU usage
53.5% 05:26:17 INFO - TinderboxPrint: I/O read bytes / time
31,411,200 / 299 05:26:17 INFO - TinderboxPrint: I/O write bytes / time
706,799,104 / 1,888 05:26:17 INFO - TinderboxPrint: CPU idle
2,256.7 (46.5%) 05:26:17 INFO - TinderboxPrint: CPU system
966.8 (19.9%) 05:26:17 INFO - TinderboxPrint: CPU user
1,630.1 (33.6%) 05:26:17 INFO - TinderboxPrint: Swap in / out
434,343,936 / 0 05:26:17 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 385905152; Write bytes: 381368320; Read time: 29486; Write time: 742 05:26:18 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 30477312; Write bytes: 295947776; Read time: 285; Write time: 1090 05:26:18 INFO - Running post-run listener: _upload_blobber_files 05:26:18 INFO - Blob upload gear active. 05:26:18 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:26:18 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:26:18 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'] 05:26:18 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 05:26:18 INFO - (blobuploader) - INFO - Open directory for files ... 05:26:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:26:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:26:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:26:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:26:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:26:19 INFO - (blobuploader) - INFO - Done attempting. 05:26:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:26:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:26:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:26:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:26:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:26:20 INFO - (blobuploader) - INFO - Done attempting. 05:26:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:26:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:26:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:26:23 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:26:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:26:23 INFO - (blobuploader) - INFO - Done attempting. 05:26:23 INFO - (blobuploader) - INFO - Iteration through files over. 05:26:23 INFO - Return code: 0 05:26:23 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:26:23 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:26:23 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f24c82c82d4e92262ff1d8f47f663938943b7d4589d21c1044a4f2482a69e3d68450b9fd2c016716cfcab969666f10a1bfe0237e6795198d7b0252ff12d74003", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4ffbe8dbe17c0f25f3f847a9f65a39e84e119b270039d7927c541e21494d53853ba156cfd10ff98a3248bae0bd5a637fd8fb206230187f71b882bdab0127da17", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a68f1ec2aa28133f2b1b3be356ed861cbbd75ba57a7bd16887097b96a5ac87b3809020c686322294afae89ee69b425e7fa9765d4efb4f7235d46fca76a4bed29"} 05:26:23 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:26:23 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:26:23 INFO - Contents: 05:26:23 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f24c82c82d4e92262ff1d8f47f663938943b7d4589d21c1044a4f2482a69e3d68450b9fd2c016716cfcab969666f10a1bfe0237e6795198d7b0252ff12d74003", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4ffbe8dbe17c0f25f3f847a9f65a39e84e119b270039d7927c541e21494d53853ba156cfd10ff98a3248bae0bd5a637fd8fb206230187f71b882bdab0127da17", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a68f1ec2aa28133f2b1b3be356ed861cbbd75ba57a7bd16887097b96a5ac87b3809020c686322294afae89ee69b425e7fa9765d4efb4f7235d46fca76a4bed29"} 05:26:23 INFO - Running post-run listener: copy_logs_to_upload_dir 05:26:23 INFO - Copying logs to upload dir... 05:26:23 INFO - mkdir: /builds/slave/test/build/upload/logs 05:26:23 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.773931 ========= master_lag: 0.26 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-08-08 05:26:23.875790) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-08 05:26:23.883679) ========= 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.rrfxmt4ccD/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.jHqzfvjhKk/Listeners TMPDIR=/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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/f24c82c82d4e92262ff1d8f47f663938943b7d4589d21c1044a4f2482a69e3d68450b9fd2c016716cfcab969666f10a1bfe0237e6795198d7b0252ff12d74003", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4ffbe8dbe17c0f25f3f847a9f65a39e84e119b270039d7927c541e21494d53853ba156cfd10ff98a3248bae0bd5a637fd8fb206230187f71b882bdab0127da17", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a68f1ec2aa28133f2b1b3be356ed861cbbd75ba57a7bd16887097b96a5ac87b3809020c686322294afae89ee69b425e7fa9765d4efb4f7235d46fca76a4bed29"} build_url:https://queue.taskcluster.net/v1/task/Xm0TwQN8QGKRoOIzRgX9RA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011077 build_url: 'https://queue.taskcluster.net/v1/task/Xm0TwQN8QGKRoOIzRgX9RA/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/f24c82c82d4e92262ff1d8f47f663938943b7d4589d21c1044a4f2482a69e3d68450b9fd2c016716cfcab969666f10a1bfe0237e6795198d7b0252ff12d74003", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4ffbe8dbe17c0f25f3f847a9f65a39e84e119b270039d7927c541e21494d53853ba156cfd10ff98a3248bae0bd5a637fd8fb206230187f71b882bdab0127da17", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a68f1ec2aa28133f2b1b3be356ed861cbbd75ba57a7bd16887097b96a5ac87b3809020c686322294afae89ee69b425e7fa9765d4efb4f7235d46fca76a4bed29"}' ========= master_lag: 0.63 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-08 05:26:24.521802) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 05:26:24.522229) ========= 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.rrfxmt4ccD/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.jHqzfvjhKk/Listeners TMPDIR=/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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.005182 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 05:26:24.572496) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-08 05:26:24.572876) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-08 05:26:24.573255) ========= ========= Total master_lag: 1.01 =========