builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0219 starttime: 1472686419.29 results: success (0) buildid: 20160831142219 builduid: 3100d6125bb643ad86ad127c4a4227aa revision: 215b219482a545aea1118d436eb77334bbb4be21 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-31 16:33:39.290603) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-31 16:33:39.291010) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-31 16:33:39.291285) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-31 16:33:39.316268) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 16:33:39.316542) ========= 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.T8GNZIvrwz/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.29uMhurPlE/Listeners TMPDIR=/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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-31 16:33:39-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.6M=0.001s 2016-08-31 16:33:39 (18.6 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.138417 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 16:33:39.477935) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 16:33:39.478250) ========= 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.T8GNZIvrwz/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.29uMhurPlE/Listeners TMPDIR=/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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.022650 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 16:33:39.526146) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-31 16:33:39.526503) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 215b219482a545aea1118d436eb77334bbb4be21 --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 215b219482a545aea1118d436eb77334bbb4be21 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.T8GNZIvrwz/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.29uMhurPlE/Listeners TMPDIR=/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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-31 16:33:39,603 truncating revision to first 12 chars 2016-08-31 16:33:39,603 Setting DEBUG logging. 2016-08-31 16:33:39,603 attempt 1/10 2016-08-31 16:33:39,603 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/215b219482a5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-31 16:33:41,183 unpacking tar archive at: fx-team-215b219482a5/testing/mozharness/ program finished with exit code 0 elapsedTime=1.825469 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-31 16:33:41.375636) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-31 16:33:41.375970) ========= script_repo_revision: 215b219482a545aea1118d436eb77334bbb4be21 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-31 16:33:41.376370) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-31 16:33:41.376686) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-31 16:33:41.395257) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-08-31 16:33:41.395652) ========= /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.T8GNZIvrwz/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.29uMhurPlE/Listeners TMPDIR=/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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 16:33:41 INFO - MultiFileLogger online at 20160831 16:33:41 in /builds/slave/test 16:33:41 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 16:33:41 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:33:41 INFO - {'append_to_log': False, 16:33:41 INFO - 'base_work_dir': '/builds/slave/test', 16:33:41 INFO - 'blob_upload_branch': 'Fx-Team', 16:33:41 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:33:41 INFO - 'branch': 'Fx-Team', 16:33:41 INFO - 'buildbot_json_path': 'buildprops.json', 16:33:41 INFO - 'config_files': ('talos/mac_config.py',), 16:33:41 INFO - 'default_actions': ('clobber', 16:33:41 INFO - 'read-buildbot-config', 16:33:41 INFO - 'download-and-extract', 16:33:41 INFO - 'populate-webroot', 16:33:41 INFO - 'create-virtualenv', 16:33:41 INFO - 'install', 16:33:41 INFO - 'run-tests'), 16:33:41 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:33:41 INFO - 'download_minidump_stackwalk': True, 16:33:41 INFO - 'download_symbols': 'ondemand', 16:33:41 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:33:41 INFO - 'tooltool.py': '/tools/tooltool.py', 16:33:41 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:33:41 INFO - '/tools/misc-python/virtualenv.py')}, 16:33:41 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:33:41 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:33:41 INFO - 'installer_path': 'installer.exe', 16:33:41 INFO - 'log_level': 'info', 16:33:41 INFO - 'log_name': 'talos', 16:33:41 INFO - 'log_to_console': True, 16:33:41 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:33:41 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:33:41 INFO - 'opt_config_files': (), 16:33:41 INFO - 'pip_index': False, 16:33:41 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:33:41 INFO - 'cmd': ('bash', 16:33:41 INFO - '-c', 16:33:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:33:41 INFO - 'enabled': True, 16:33:41 INFO - 'halt_on_failure': False, 16:33:41 INFO - 'name': 'check_screen_resolution'},), 16:33:41 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:33:41 INFO - 'cmd': ('bash', 16:33:41 INFO - '-c', 16:33:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:33:41 INFO - 'enabled': True, 16:33:41 INFO - 'halt_on_failure': False, 16:33:41 INFO - 'name': 'check_screen_resolution'},), 16:33:41 INFO - 'run_cmd_checks_enabled': True, 16:33:41 INFO - 'sps_profile': False, 16:33:41 INFO - 'sps_profile_interval': 0, 16:33:41 INFO - 'suite': 'dromaeojs', 16:33:41 INFO - 'system_bits': '32', 16:33:41 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:33:41 INFO - 'title': 't-yosemite-r7-0219', 16:33:41 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:33:41 INFO - 'use_talos_json': True, 16:33:41 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:33:41 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:33:41 INFO - 'work_dir': 'build'} 16:33:41 INFO - [mozharness: 2016-08-31 23:33:41.593848Z] Running clobber step. 16:33:41 INFO - Running pre-action listener: _resource_record_pre_action 16:33:41 INFO - Running main action method: clobber 16:33:41 INFO - rmtree: /builds/slave/test/build 16:33:41 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:33:43 INFO - Running post-action listener: _resource_record_post_action 16:33:43 INFO - [mozharness: 2016-08-31 23:33:43.711424Z] Finished clobber step (success) 16:33:43 INFO - [mozharness: 2016-08-31 23:33:43.711622Z] Running read-buildbot-config step. 16:33:43 INFO - Running pre-action listener: _resource_record_pre_action 16:33:43 INFO - Running main action method: read_buildbot_config 16:33:43 INFO - Using buildbot properties: 16:33:43 INFO - { 16:33:43 INFO - "project": "", 16:33:43 INFO - "product": "firefox", 16:33:43 INFO - "script_repo_revision": "production", 16:33:43 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 16:33:43 INFO - "repository": "", 16:33:43 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 16:33:43 INFO - "buildid": "20160831142219", 16:33:43 INFO - "pgo_build": "False", 16:33:43 INFO - "basedir": "/builds/slave/test", 16:33:43 INFO - "buildnumber": 133, 16:33:43 INFO - "slavename": "t-yosemite-r7-0219", 16:33:43 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 16:33:43 INFO - "platform": "yosemite_r7", 16:33:43 INFO - "scheduler": "tests-fx-team-macosx64-talos", 16:33:43 INFO - "branch": "fx-team", 16:33:43 INFO - "repo_path": "integration/fx-team", 16:33:43 INFO - "revision": "215b219482a545aea1118d436eb77334bbb4be21", 16:33:43 INFO - "stage_platform": "macosx64", 16:33:43 INFO - "builduid": "3100d6125bb643ad86ad127c4a4227aa", 16:33:43 INFO - "slavebuilddir": "test" 16:33:43 INFO - } 16:33:43 INFO - Found installer url https://queue.taskcluster.net/v1/task/StoTJFtnTYO0ihRskczJMw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 16:33:43 INFO - Running post-action listener: _resource_record_post_action 16:33:43 INFO - [mozharness: 2016-08-31 23:33:43.717867Z] Finished read-buildbot-config step (success) 16:33:43 INFO - [mozharness: 2016-08-31 23:33:43.718116Z] Running download-and-extract step. 16:33:43 INFO - Running pre-action listener: _resource_record_pre_action 16:33:43 INFO - Running main action method: download_and_extract 16:33:43 INFO - mkdir: /builds/slave/test/build/tests 16:33:43 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')]} 16:33:43 INFO - trying https://queue.taskcluster.net/v1/task/StoTJFtnTYO0ihRskczJMw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 16:33:43 INFO - Downloading https://queue.taskcluster.net/v1/task/StoTJFtnTYO0ihRskczJMw/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 16:33:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/StoTJFtnTYO0ihRskczJMw/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 16:33:44 INFO - Downloaded 1199 bytes. 16:33:44 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 16:33:44 INFO - Using the following test package requirements: 16:33:44 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 16:33:44 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:33:44 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 16:33:44 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:33:44 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 16:33:44 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:33:44 INFO - u'jsshell-mac.zip'], 16:33:44 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:33:44 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 16:33:44 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 16:33:44 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:33:44 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 16:33:44 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:33:44 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 16:33:44 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:33:44 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 16:33:44 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:33:44 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 16:33:44 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:33: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')]} 16:33:44 INFO - trying https://queue.taskcluster.net/v1/task/StoTJFtnTYO0ihRskczJMw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 16:33:44 INFO - Downloading https://queue.taskcluster.net/v1/task/StoTJFtnTYO0ihRskczJMw/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 16:33:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/StoTJFtnTYO0ihRskczJMw/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 16:33:46 INFO - Downloaded 16396920 bytes. 16:33:46 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 16:33:52 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 16:33:52 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')]} 16:33:52 INFO - trying https://queue.taskcluster.net/v1/task/StoTJFtnTYO0ihRskczJMw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 16:33:52 INFO - Downloading https://queue.taskcluster.net/v1/task/StoTJFtnTYO0ihRskczJMw/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 16:33:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/StoTJFtnTYO0ihRskczJMw/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 16:33:53 INFO - Downloaded 16396920 bytes. 16:33:53 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 16:34:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:34:00 INFO - trying https://queue.taskcluster.net/v1/task/StoTJFtnTYO0ihRskczJMw/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 16:34:00 INFO - Downloading https://queue.taskcluster.net/v1/task/StoTJFtnTYO0ihRskczJMw/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 16:34:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/StoTJFtnTYO0ihRskczJMw/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 16:34:01 INFO - Downloaded 14413998 bytes. 16:34:01 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 16:34:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:34:02 INFO - trying https://queue.taskcluster.net/v1/task/StoTJFtnTYO0ihRskczJMw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 16:34:02 INFO - Downloading https://queue.taskcluster.net/v1/task/StoTJFtnTYO0ihRskczJMw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 16:34:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/StoTJFtnTYO0ihRskczJMw/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 16:34:06 INFO - Downloaded 111572073 bytes. 16:34:06 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/StoTJFtnTYO0ihRskczJMw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 16:34:06 INFO - mkdir: /builds/slave/test/properties 16:34:06 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:34:06 INFO - Writing to file /builds/slave/test/properties/build_url 16:34:06 INFO - Contents: 16:34:06 INFO - build_url:https://queue.taskcluster.net/v1/task/StoTJFtnTYO0ihRskczJMw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 16:34:08 INFO - Running post-action listener: _resource_record_post_action 16:34:08 INFO - Running post-action listener: set_extra_try_arguments 16:34:08 INFO - [mozharness: 2016-08-31 23:34:08.063043Z] Finished download-and-extract step (success) 16:34:08 INFO - [mozharness: 2016-08-31 23:34:08.063187Z] Running populate-webroot step. 16:34:08 INFO - Running pre-action listener: _resource_record_pre_action 16:34:08 INFO - Running main action method: populate_webroot 16:34:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:34:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:34:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:34:08 INFO - '--tppagecycles', 16:34:08 INFO - '7'], 16:34:08 INFO - 'tests': ['tsvgm']}}, 16:34:08 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:34:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:34:08 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:34:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:34:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:34:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:34:08 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:34:08 INFO - 'talos_options': ['--disable-e10s'], 16:34:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:34:08 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:34:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:34:08 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:34:08 INFO - 'talos_options': ['--disable-e10s'], 16:34:08 INFO - 'tests': ['damp', 'tps']}, 16:34:08 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:34:08 INFO - 'tests': ['damp', 'tps']}, 16:34:08 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:34:08 INFO - 'tests': ['dromaeo_dom']}, 16:34:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:34:08 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:34:08 INFO - 'tests': ['basic_compositor_video']}, 16:34:08 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:34:08 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:34:08 INFO - 'tests': ['a11yr', 16:34:08 INFO - 'ts_paint', 16:34:08 INFO - 'tpaint', 16:34:08 INFO - 'sessionrestore', 16:34:08 INFO - 'sessionrestore_no_auto_restore', 16:34:08 INFO - 'tabpaint']}, 16:34:08 INFO - 'other-e10s': {'tests': ['a11yr', 16:34:08 INFO - 'ts_paint', 16:34:08 INFO - 'tpaint', 16:34:08 INFO - 'sessionrestore', 16:34:08 INFO - 'sessionrestore_no_auto_restore', 16:34:08 INFO - 'tabpaint']}, 16:34:08 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:34:08 INFO - 'ts_paint', 16:34:08 INFO - 'tpaint', 16:34:08 INFO - 'sessionrestore', 16:34:08 INFO - 'sessionrestore_no_auto_restore', 16:34:08 INFO - 'tabpaint']}, 16:34:08 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:34:08 INFO - 'ts_paint', 16:34:08 INFO - 'tpaint', 16:34:08 INFO - 'sessionrestore', 16:34:08 INFO - 'sessionrestore_no_auto_restore', 16:34:08 INFO - 'tabpaint']}, 16:34:08 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:34:08 INFO - 'tests': ['a11yr', 16:34:08 INFO - 'ts_paint', 16:34:08 INFO - 'tpaint', 16:34:08 INFO - 'sessionrestore', 16:34:08 INFO - 'sessionrestore_no_auto_restore', 16:34:08 INFO - 'tabpaint']}, 16:34:08 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:34:08 INFO - 'tests': ['a11yr', 16:34:08 INFO - 'ts_paint', 16:34:08 INFO - 'tpaint', 16:34:08 INFO - 'sessionrestore', 16:34:08 INFO - 'sessionrestore_no_auto_restore', 16:34:08 INFO - 'tabpaint']}, 16:34:08 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:34:08 INFO - 'tests': ['tsvgx', 16:34:08 INFO - 'tsvgr_opacity', 16:34:08 INFO - 'tart', 16:34:08 INFO - 'tscrollx', 16:34:08 INFO - 'cart']}, 16:34:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:34:08 INFO - 'tsvgr_opacity', 16:34:08 INFO - 'tart', 16:34:08 INFO - 'tscrollx', 16:34:08 INFO - 'cart']}, 16:34:08 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:34:08 INFO - 'talos_options': ['--disable-e10s'], 16:34:08 INFO - 'tests': ['tp5o']}, 16:34:08 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:34:08 INFO - 'tests': ['tp5o']}, 16:34:08 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:34:08 INFO - 'talos_options': ['--disable-e10s', 16:34:08 INFO - '--xperf_path', 16:34:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:34:08 INFO - 'tests': ['tp5n']}, 16:34:08 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:34:08 INFO - 'talos_options': ['--xperf_path', 16:34:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:34:08 INFO - 'tests': ['tp5n']}}, 16:34:08 INFO - 'talos.zip': {'path': '', 16:34:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:34:08 INFO - Running post-action listener: _resource_record_post_action 16:34:08 INFO - [mozharness: 2016-08-31 23:34:08.081895Z] Finished populate-webroot step (success) 16:34:08 INFO - [mozharness: 2016-08-31 23:34:08.082004Z] Running create-virtualenv step. 16:34:08 INFO - Running pre-action listener: _resource_record_pre_action 16:34:08 INFO - Running main action method: create_virtualenv 16:34:08 INFO - Creating virtualenv /builds/slave/test/build/venv 16:34:08 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 16:34:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:34:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:34:08 INFO - Using real prefix '/tools/python27' 16:34:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:34:08 INFO - Installing distribute.............................................................................................................................................................................................done. 16:34:11 INFO - Installing pip.................done. 16:34:11 INFO - Return code: 0 16:34:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:34: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')]} 16:34:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:34:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:34: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')]} 16:34:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:34:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:34:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108d8da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e22618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb212457360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108cf1d30>, '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 0x108a56db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108ce9b20>, '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.T8GNZIvrwz/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.29uMhurPlE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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 16:34:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 16:34:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:34:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.T8GNZIvrwz/Render', 16:34:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:34:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:34:11 INFO - 'HOME': '/Users/cltbld', 16:34:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:34:11 INFO - 'LOGNAME': 'cltbld', 16:34:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:34:11 INFO - 'MOZ_NO_REMOTE': '1', 16:34:11 INFO - 'NO_EM_RESTART': '1', 16:34:11 INFO - 'PAGER': '/bin/cat', 16:34:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:34:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:34:11 INFO - 'PWD': '/builds/slave/test', 16:34:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:34:11 INFO - 'SHELL': '/bin/bash', 16:34:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.29uMhurPlE/Listeners', 16:34:11 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 16:34:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:34:11 INFO - 'USER': 'cltbld', 16:34:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:34:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:34:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:34:11 INFO - 'XPC_FLAGS': '0x0', 16:34:11 INFO - 'XPC_SERVICE_NAME': '0', 16:34:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:34:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:34:12 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 16:34:12 INFO - Cleaning up... 16:34:12 INFO - Return code: 0 16:34:12 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:34: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')]} 16:34:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:34:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:34: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')]} 16:34:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:34:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:34:12 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 0x108d8da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e22618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb212457360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108cf1d30>, '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 0x108a56db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108ce9b20>, '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.T8GNZIvrwz/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.29uMhurPlE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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 16:34:12 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 16:34:12 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 16:34:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.T8GNZIvrwz/Render', 16:34:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:34:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:34:12 INFO - 'HOME': '/Users/cltbld', 16:34:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:34:12 INFO - 'LOGNAME': 'cltbld', 16:34:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:34:12 INFO - 'MOZ_NO_REMOTE': '1', 16:34:12 INFO - 'NO_EM_RESTART': '1', 16:34:12 INFO - 'PAGER': '/bin/cat', 16:34:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:34:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:34:12 INFO - 'PWD': '/builds/slave/test', 16:34:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:34:12 INFO - 'SHELL': '/bin/bash', 16:34:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.29uMhurPlE/Listeners', 16:34:12 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 16:34:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:34:12 INFO - 'USER': 'cltbld', 16:34:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:34:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:34:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:34:12 INFO - 'XPC_FLAGS': '0x0', 16:34:12 INFO - 'XPC_SERVICE_NAME': '0', 16:34:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:34:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:34:12 INFO - Downloading/unpacking psutil>=3.1.1 16:34:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:34:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:34:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:34:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:34:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:34:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:34:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:34:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:34:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:34:15 INFO - Installing collected packages: psutil 16:34:15 INFO - Running setup.py install for psutil 16:34:15 INFO - building 'psutil._psutil_osx' extension 16:34:15 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 16:34:16 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 16:34:16 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 16:34:16 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 16:34:16 INFO - building 'psutil._psutil_posix' extension 16:34:16 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 16:34:16 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:34:16 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:34:16 INFO - ^ 16:34:16 INFO - 1 warning generated. 16:34:16 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 16:34:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:34:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:34:16 INFO - Successfully installed psutil 16:34:16 INFO - Cleaning up... 16:34:16 INFO - Return code: 0 16:34:16 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:34:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:34:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:34:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:34:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:34:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:34:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:34:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108d8da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e22618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb212457360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108cf1d30>, '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 0x108a56db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108ce9b20>, '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.T8GNZIvrwz/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.29uMhurPlE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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 16:34:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:34:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 16:34:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.T8GNZIvrwz/Render', 16:34:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:34:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:34:16 INFO - 'HOME': '/Users/cltbld', 16:34:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:34:16 INFO - 'LOGNAME': 'cltbld', 16:34:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:34:16 INFO - 'MOZ_NO_REMOTE': '1', 16:34:16 INFO - 'NO_EM_RESTART': '1', 16:34:16 INFO - 'PAGER': '/bin/cat', 16:34:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:34:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:34:16 INFO - 'PWD': '/builds/slave/test', 16:34:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:34:16 INFO - 'SHELL': '/bin/bash', 16:34:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.29uMhurPlE/Listeners', 16:34:16 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 16:34:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:34:16 INFO - 'USER': 'cltbld', 16:34:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:34:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:34:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:34:16 INFO - 'XPC_FLAGS': '0x0', 16:34:16 INFO - 'XPC_SERVICE_NAME': '0', 16:34:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:34:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:34:17 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:34:17 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 16:34:17 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 16:34:17 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 16:34:17 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 16:34:17 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 16:34:17 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 16:34:19 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:34:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:34:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:34:19 INFO - Installing collected packages: mozsystemmonitor 16:34:19 INFO - Running setup.py install for mozsystemmonitor 16:34:19 INFO - Successfully installed mozsystemmonitor 16:34:19 INFO - Cleaning up... 16:34:19 INFO - Return code: 0 16:34:19 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:34:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:34:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:34:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:34:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:34:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:34:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:34:19 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 0x108d8da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e22618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb212457360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108cf1d30>, '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 0x108a56db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108ce9b20>, '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.T8GNZIvrwz/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.29uMhurPlE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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 16:34:19 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 16:34:19 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 16:34:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.T8GNZIvrwz/Render', 16:34:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:34:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:34:19 INFO - 'HOME': '/Users/cltbld', 16:34:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:34:19 INFO - 'LOGNAME': 'cltbld', 16:34:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:34:19 INFO - 'MOZ_NO_REMOTE': '1', 16:34:19 INFO - 'NO_EM_RESTART': '1', 16:34:19 INFO - 'PAGER': '/bin/cat', 16:34:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:34:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:34:19 INFO - 'PWD': '/builds/slave/test', 16:34:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:34:19 INFO - 'SHELL': '/bin/bash', 16:34:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.29uMhurPlE/Listeners', 16:34:19 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 16:34:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:34:19 INFO - 'USER': 'cltbld', 16:34:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:34:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:34:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:34:19 INFO - 'XPC_FLAGS': '0x0', 16:34:19 INFO - 'XPC_SERVICE_NAME': '0', 16:34:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:34:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:34:20 INFO - Downloading/unpacking jsonschema==2.5.1 16:34:20 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 16:34:20 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 16:34:20 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 16:34:20 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 16:34:20 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 16:34:20 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 16:34:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:34:22 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:34:22 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 16:34:22 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 16:34:22 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 16:34:22 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 16:34:22 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 16:34:22 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 16:34:22 INFO - Downloading functools32-3.2.3-2.tar.gz 16:34:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:34:23 INFO - warning: no files found matching '*.txt' 16:34:23 INFO - no previously-included directories found matching 'build' 16:34:23 INFO - no previously-included directories found matching 'dist' 16:34:23 INFO - no previously-included directories found matching '.git*' 16:34:23 INFO - Installing collected packages: jsonschema, functools32 16:34:23 INFO - Running setup.py install for functools32 16:34:23 INFO - warning: no files found matching '*.txt' 16:34:23 INFO - no previously-included directories found matching 'build' 16:34:23 INFO - no previously-included directories found matching 'dist' 16:34:23 INFO - no previously-included directories found matching '.git*' 16:34:23 INFO - Successfully installed jsonschema functools32 16:34:23 INFO - Cleaning up... 16:34:23 INFO - Return code: 0 16:34:23 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:34:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:34:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:34:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:34:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:34:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:34:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:34:23 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 0x108d8da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e22618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb212457360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108cf1d30>, '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 0x108a56db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108ce9b20>, '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.T8GNZIvrwz/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.29uMhurPlE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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 16:34:23 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 16:34:23 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 16:34:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.T8GNZIvrwz/Render', 16:34:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:34:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:34:23 INFO - 'HOME': '/Users/cltbld', 16:34:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:34:23 INFO - 'LOGNAME': 'cltbld', 16:34:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:34:23 INFO - 'MOZ_NO_REMOTE': '1', 16:34:23 INFO - 'NO_EM_RESTART': '1', 16:34:23 INFO - 'PAGER': '/bin/cat', 16:34:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:34:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:34:23 INFO - 'PWD': '/builds/slave/test', 16:34:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:34:23 INFO - 'SHELL': '/bin/bash', 16:34:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.29uMhurPlE/Listeners', 16:34:23 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 16:34:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:34:23 INFO - 'USER': 'cltbld', 16:34:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:34:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:34:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:34:23 INFO - 'XPC_FLAGS': '0x0', 16:34:23 INFO - 'XPC_SERVICE_NAME': '0', 16:34:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:34:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:34:23 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:34:23 INFO - Cleaning up... 16:34:23 INFO - Return code: 0 16:34:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:34:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:34:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:34:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:34:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:34:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:34:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:34:23 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 0x108d8da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e22618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb212457360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108cf1d30>, '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 0x108a56db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108ce9b20>, '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.T8GNZIvrwz/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.29uMhurPlE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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 16:34:23 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 16:34:23 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 16:34:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.T8GNZIvrwz/Render', 16:34:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:34:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:34:23 INFO - 'HOME': '/Users/cltbld', 16:34:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:34:23 INFO - 'LOGNAME': 'cltbld', 16:34:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:34:23 INFO - 'MOZ_NO_REMOTE': '1', 16:34:23 INFO - 'NO_EM_RESTART': '1', 16:34:23 INFO - 'PAGER': '/bin/cat', 16:34:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:34:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:34:23 INFO - 'PWD': '/builds/slave/test', 16:34:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:34:23 INFO - 'SHELL': '/bin/bash', 16:34:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.29uMhurPlE/Listeners', 16:34:23 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 16:34:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:34:23 INFO - 'USER': 'cltbld', 16:34:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:34:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:34:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:34:23 INFO - 'XPC_FLAGS': '0x0', 16:34:23 INFO - 'XPC_SERVICE_NAME': '0', 16:34:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:34:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:34:23 INFO - Downloading/unpacking blobuploader==1.2.4 16:34:23 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 16:34:23 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 16:34:23 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 16:34:23 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 16:34:23 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 16:34:23 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 16:34:26 INFO - Downloading blobuploader-1.2.4.tar.gz 16:34:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:34:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:34:26 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 16:34:26 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 16:34:26 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 16:34:26 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 16:34:26 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 16:34:26 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 16:34:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:34:26 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:34:26 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 16:34:26 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 16:34:26 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 16:34:26 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 16:34:26 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 16:34:26 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 16:34:27 INFO - Downloading docopt-0.6.1.tar.gz 16:34:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:34:27 INFO - Installing collected packages: blobuploader, requests, docopt 16:34:27 INFO - Running setup.py install for blobuploader 16:34:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:34:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:34:27 INFO - Running setup.py install for requests 16:34:28 INFO - Running setup.py install for docopt 16:34:28 INFO - Successfully installed blobuploader requests docopt 16:34:28 INFO - Cleaning up... 16:34:28 INFO - Return code: 0 16:34:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:34: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')]} 16:34:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:34:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:34: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')]} 16:34:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:34:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:34:28 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 0x108d8da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e22618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb212457360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108cf1d30>, '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 0x108a56db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108ce9b20>, '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.T8GNZIvrwz/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.29uMhurPlE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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 16:34:28 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 16:34:28 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 16:34:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.T8GNZIvrwz/Render', 16:34:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:34:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:34:28 INFO - 'HOME': '/Users/cltbld', 16:34:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:34:28 INFO - 'LOGNAME': 'cltbld', 16:34:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:34:28 INFO - 'MOZ_NO_REMOTE': '1', 16:34:28 INFO - 'NO_EM_RESTART': '1', 16:34:28 INFO - 'PAGER': '/bin/cat', 16:34:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:34:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:34:28 INFO - 'PWD': '/builds/slave/test', 16:34:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:34:28 INFO - 'SHELL': '/bin/bash', 16:34:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.29uMhurPlE/Listeners', 16:34:28 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 16:34:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:34:28 INFO - 'USER': 'cltbld', 16:34:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:34:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:34:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:34:28 INFO - 'XPC_FLAGS': '0x0', 16:34:28 INFO - 'XPC_SERVICE_NAME': '0', 16:34:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:34:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:34:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:34:28 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-aQfGdx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:34:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:34:28 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-2dPH4I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:34:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:34:28 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-cXJsTg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:34:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:34:29 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-qFMArh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:34:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:34:29 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-Rjo9BY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:34:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:34:29 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-RNNtym-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:34:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:34:29 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-iLYMhm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:34:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:34:29 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-8CVdRo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:34:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:34:29 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-IvKSRs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:34:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:34:29 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-D9UTUW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:34:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:34:29 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-0g1vKs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:34:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:34:29 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-d3qppo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:34:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:34:29 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-TUvO3m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:34:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:34:30 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-okcElc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:34:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:34:30 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-hQqfb3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:34:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:34:30 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-86xsnN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:34:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:34:30 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-eitXNT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:34:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:34:30 INFO - Running setup.py install for manifestparser 16:34:30 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:34:30 INFO - Running setup.py install for mozcrash 16:34:30 INFO - Running setup.py install for mozdebug 16:34:30 INFO - Running setup.py install for mozdevice 16:34:31 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:34:31 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:34:31 INFO - Running setup.py install for mozfile 16:34:31 INFO - Running setup.py install for mozhttpd 16:34:31 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:34:31 INFO - Running setup.py install for mozinfo 16:34:31 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:34:31 INFO - Running setup.py install for mozInstall 16:34:31 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:34:31 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:34:31 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:34:31 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:34:31 INFO - Running setup.py install for mozleak 16:34:31 INFO - Running setup.py install for mozlog 16:34:31 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:34:31 INFO - Running setup.py install for moznetwork 16:34:32 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:34:32 INFO - Running setup.py install for mozprocess 16:34:32 INFO - Running setup.py install for mozprofile 16:34:32 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:34:32 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:34:32 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:34:32 INFO - Running setup.py install for mozrunner 16:34:32 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:34:32 INFO - Running setup.py install for mozscreenshot 16:34:32 INFO - Running setup.py install for moztest 16:34:32 INFO - Running setup.py install for mozversion 16:34:32 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:34:33 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:34:33 INFO - Cleaning up... 16:34:33 INFO - Return code: 0 16:34:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:34:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:34:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:34:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:34:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:34:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:34:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:34:33 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 0x108d8da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e22618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb212457360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108cf1d30>, '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 0x108a56db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108ce9b20>, '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.T8GNZIvrwz/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.29uMhurPlE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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 16:34:33 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 16:34:33 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 16:34:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.T8GNZIvrwz/Render', 16:34:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:34:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:34:33 INFO - 'HOME': '/Users/cltbld', 16:34:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:34:33 INFO - 'LOGNAME': 'cltbld', 16:34:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:34:33 INFO - 'MOZ_NO_REMOTE': '1', 16:34:33 INFO - 'NO_EM_RESTART': '1', 16:34:33 INFO - 'PAGER': '/bin/cat', 16:34:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:34:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:34:33 INFO - 'PWD': '/builds/slave/test', 16:34:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:34:33 INFO - 'SHELL': '/bin/bash', 16:34:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.29uMhurPlE/Listeners', 16:34:33 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 16:34:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:34:33 INFO - 'USER': 'cltbld', 16:34:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:34:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:34:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:34:33 INFO - 'XPC_FLAGS': '0x0', 16:34:33 INFO - 'XPC_SERVICE_NAME': '0', 16:34:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:34:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:34:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:34:33 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-Y2Oo2V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:34:33 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)) 16:34:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:34:33 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-rGEQQo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:34:33 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)) 16:34:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:34:33 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-HcHhUU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:34:33 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)) 16:34:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:34:33 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-IZtlxU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:34:33 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)) 16:34:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:34:33 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-rCKQXN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:34:33 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)) 16:34:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:34:33 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-xN7pQ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:34:33 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)) 16:34:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:34:33 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-27R2kf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:34:34 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)) 16:34:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:34:34 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-QHpTIm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:34:34 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)) 16:34:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:34:34 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-BKsW7M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:34:34 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)) 16:34:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:34:34 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-kYsSwZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:34:34 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)) 16:34:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:34:34 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-SbPMwB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:34:34 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)) 16:34:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:34:34 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-blS75i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:34:34 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)) 16:34:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:34:34 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-dMnW4I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:34:34 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)) 16:34:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:34:34 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-3favIw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:34:34 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)) 16:34:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:34:34 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-Zmy8GR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:34:34 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)) 16:34:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:34:34 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-5Nv804-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:34:34 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)) 16:34:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:34:34 INFO - Running setup.py (path:/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/pip-xMXLWI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:34:35 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)) 16:34:35 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)) 16:34:35 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)) 16:34:35 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)) 16:34:35 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)) 16:34:35 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)) 16:34:35 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:34:35 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 16:34:35 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 16:34:35 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 16:34:35 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 16:34:35 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 16:34:35 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 16:34:37 INFO - Downloading blessings-1.6.tar.gz 16:34:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:34:38 INFO - Installing collected packages: blessings 16:34:38 INFO - Running setup.py install for blessings 16:34:38 INFO - Successfully installed blessings 16:34:38 INFO - Cleaning up... 16:34:38 INFO - Return code: 0 16:34:38 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:34:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:34:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:34:38 INFO - Reading from file tmpfile_stdout 16:34:38 INFO - Current package versions: 16:34:38 INFO - blessings == 1.6 16:34:38 INFO - blobuploader == 1.2.4 16:34:38 INFO - docopt == 0.6.1 16:34:38 INFO - functools32 == 3.2.3-2 16:34:38 INFO - jsonschema == 2.5.1 16:34:38 INFO - manifestparser == 1.1 16:34:38 INFO - mozInstall == 1.12 16:34:38 INFO - mozcrash == 0.17 16:34:38 INFO - mozdebug == 0.1 16:34:38 INFO - mozdevice == 0.48 16:34:38 INFO - mozfile == 1.2 16:34:38 INFO - mozhttpd == 0.7 16:34:38 INFO - mozinfo == 0.9 16:34:38 INFO - mozleak == 0.1 16:34:38 INFO - mozlog == 3.3 16:34:38 INFO - moznetwork == 0.27 16:34:38 INFO - mozprocess == 0.23 16:34:38 INFO - mozprofile == 0.28 16:34:38 INFO - mozrunner == 6.12 16:34:38 INFO - mozscreenshot == 0.1 16:34:38 INFO - mozsystemmonitor == 0.3 16:34:38 INFO - moztest == 0.7 16:34:38 INFO - mozversion == 1.4 16:34:38 INFO - psutil == 3.1.1 16:34:38 INFO - requests == 1.2.3 16:34:38 INFO - wsgiref == 0.1.2 16:34:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:34: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')]} 16:34:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:34:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:34: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')]} 16:34:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:34:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:34:38 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 0x108d8da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e22618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb212457360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108cf1d30>, '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 0x108a56db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108ce9b20>, '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.T8GNZIvrwz/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.29uMhurPlE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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 16:34:38 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 16:34:38 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 16:34:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.T8GNZIvrwz/Render', 16:34:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:34:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:34:38 INFO - 'HOME': '/Users/cltbld', 16:34:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:34:38 INFO - 'LOGNAME': 'cltbld', 16:34:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:34:38 INFO - 'MOZ_NO_REMOTE': '1', 16:34:38 INFO - 'NO_EM_RESTART': '1', 16:34:38 INFO - 'PAGER': '/bin/cat', 16:34:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:34:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:34:38 INFO - 'PWD': '/builds/slave/test', 16:34:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:34:38 INFO - 'SHELL': '/bin/bash', 16:34:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.29uMhurPlE/Listeners', 16:34:38 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 16:34:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:34:38 INFO - 'USER': 'cltbld', 16:34:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:34:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:34:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:34:38 INFO - 'XPC_FLAGS': '0x0', 16:34:38 INFO - 'XPC_SERVICE_NAME': '0', 16:34:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:34:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:34:39 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)) 16:34:39 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)) 16:34:39 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)) 16:34:39 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)) 16:34:39 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)) 16:34:39 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)) 16:34:39 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)) 16:34:39 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)) 16:34:39 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)) 16:34:39 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)) 16:34:39 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)) 16:34:39 INFO - Cleaning up... 16:34:39 INFO - Return code: 0 16:34:39 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:34:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:34:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:34:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:34:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:34:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:34:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:34:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108d8da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e22618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb212457360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108cf1d30>, '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 0x108a56db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108ce9b20>, '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.T8GNZIvrwz/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.29uMhurPlE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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 16:34:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:34:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:34:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.T8GNZIvrwz/Render', 16:34:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:34:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:34:39 INFO - 'HOME': '/Users/cltbld', 16:34:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:34:39 INFO - 'LOGNAME': 'cltbld', 16:34:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:34:39 INFO - 'MOZ_NO_REMOTE': '1', 16:34:39 INFO - 'NO_EM_RESTART': '1', 16:34:39 INFO - 'PAGER': '/bin/cat', 16:34:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:34:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:34:39 INFO - 'PWD': '/builds/slave/test', 16:34:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:34:39 INFO - 'SHELL': '/bin/bash', 16:34:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.29uMhurPlE/Listeners', 16:34:39 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 16:34:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:34:39 INFO - 'USER': 'cltbld', 16:34:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:34:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:34:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:34:39 INFO - 'XPC_FLAGS': '0x0', 16:34:39 INFO - 'XPC_SERVICE_NAME': '0', 16:34:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:34:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:34:39 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:34:39 INFO - Cleaning up... 16:34:39 INFO - Return code: 0 16:34:39 INFO - Running post-action listener: _resource_record_post_action 16:34:39 INFO - Running post-action listener: _start_resource_monitoring 16:34:39 INFO - Starting resource monitoring. 16:34:39 INFO - [mozharness: 2016-08-31 23:34:39.491512Z] Finished create-virtualenv step (success) 16:34:39 INFO - [mozharness: 2016-08-31 23:34:39.492464Z] Running install step. 16:34:39 INFO - Running pre-action listener: _resource_record_pre_action 16:34:39 INFO - Running main action method: install 16:34:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:34:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:34:39 INFO - Reading from file tmpfile_stdout 16:34:39 INFO - Detecting whether we're running mozinstall >=1.0... 16:34:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:34:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:34:39 INFO - Reading from file tmpfile_stdout 16:34:39 INFO - Output received: 16:34:39 INFO - Usage: mozinstall [options] installer 16:34:39 INFO - Options: 16:34:39 INFO - -h, --help show this help message and exit 16:34:39 INFO - -d DEST, --destination=DEST 16:34:39 INFO - Directory to install application into. [default: 16:34:39 INFO - "/builds/slave/test"] 16:34:39 INFO - --app=APP Application being installed. [default: firefox] 16:34:39 INFO - mkdir: /builds/slave/test/build/application 16:34:39 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'] 16:34:39 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 16:35:13 INFO - Reading from file tmpfile_stdout 16:35:13 INFO - Output received: 16:35:13 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:35:13 INFO - Running post-action listener: _resource_record_post_action 16:35:13 INFO - [mozharness: 2016-08-31 23:35:13.783015Z] Finished install step (success) 16:35:13 INFO - [mozharness: 2016-08-31 23:35:13.783149Z] Running run-tests step. 16:35:13 INFO - Running pre-action listener: _resource_record_pre_action 16:35:13 INFO - Running main action method: run_tests 16:35:13 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:35:13 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:35:13 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:35:13 INFO - 2016-08-31 16:35:13.797 screenresolution[1637:7469] starting screenresolution argv=screenresolution get 16:35:13 INFO - 2016-08-31 16:35:13.800 screenresolution[1637:7469] Display 0: 1600x1200x32@60 16:35:13 INFO - 2016-08-31 16:35:13.805 screenresolution[1638:7471] starting screenresolution argv=screenresolution list 16:35:13 INFO - Available Modes on Display 0 16:35:13 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:35:13 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:35:13 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:35:13 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:35:14 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:35:14 INFO - Intel Iris: 16:35:14 INFO - Chipset Model: Intel Iris 16:35:14 INFO - Type: GPU 16:35:14 INFO - Bus: Built-In 16:35:14 INFO - VRAM (Dynamic, Max): 1536 MB 16:35:14 INFO - Vendor: Intel (0x8086) 16:35:14 INFO - Device ID: 0x0a2e 16:35:14 INFO - Revision ID: 0x0009 16:35:14 INFO - Displays: 16:35:14 INFO - Display: 16:35:14 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:35:14 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:35:14 INFO - Display Serial Number: 1600x1200 60 16:35:14 INFO - Main Display: Yes 16:35:14 INFO - Mirror: Off 16:35:14 INFO - Online: Yes 16:35:14 INFO - Rotation: Supported 16:35:14 INFO - Return code: 0 16:35:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:35:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:35:14 INFO - Python 2.7.3 16:35:14 INFO - Return code: 0 16:35:14 INFO - grabbing minidump binary from tooltool 16:35:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:35:14 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 0x108cf1d30>, '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 0x108a56db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108ce9b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:35:14 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 16:35:14 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 16:35:14 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 16:35:14 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:35:14 INFO - Return code: 0 16:35:14 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:35:14 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:35:14 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:35:14 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:35:14 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:35:14 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/StoTJFtnTYO0ihRskczJMw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0219', '--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 16:35:14 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/StoTJFtnTYO0ihRskczJMw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0219 --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 16:35:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.T8GNZIvrwz/Render', 16:35:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:35:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:35:14 INFO - 'HOME': '/Users/cltbld', 16:35:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:35:14 INFO - 'LOGNAME': 'cltbld', 16:35:14 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:35:14 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:35:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:35:14 INFO - 'MOZ_NO_REMOTE': '1', 16:35:14 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:35:14 INFO - 'NO_EM_RESTART': '1', 16:35:14 INFO - 'PAGER': '/bin/cat', 16:35:14 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:35:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:35:14 INFO - 'PWD': '/builds/slave/test', 16:35:14 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:35:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:35:14 INFO - 'SHELL': '/bin/bash', 16:35:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.29uMhurPlE/Listeners', 16:35:14 INFO - 'TMPDIR': '/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/', 16:35:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:35:14 INFO - 'USER': 'cltbld', 16:35:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:35:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:35:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:35:14 INFO - 'XPC_FLAGS': '0x0', 16:35:14 INFO - 'XPC_SERVICE_NAME': '0', 16:35:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:35:14 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/StoTJFtnTYO0ihRskczJMw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0219', '--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 16:35:14 INFO - mozversion application_buildid: 20160831142219 16:35:14 INFO - mozversion application_changeset: 215b219482a545aea1118d436eb77334bbb4be21 16:35:14 INFO - mozversion application_display_name: Nightly 16:35:14 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:35:14 INFO - mozversion application_name: Firefox 16:35:14 INFO - mozversion application_remotingname: firefox 16:35:14 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 16:35:14 INFO - mozversion application_vendor: Mozilla 16:35:14 INFO - mozversion application_version: 51.0a1 16:35:14 INFO - mozversion platform_buildid: 20160831142219 16:35:14 INFO - mozversion platform_changeset: 215b219482a545aea1118d436eb77334bbb4be21 16:35:14 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 16:35:14 INFO - mozversion platform_version: 51.0a1 16:35:14 INFO - using testdate: 1472686514 16:35:14 INFO - actual date: 1472686514 16:35:14 INFO - starting webserver on 'localhost:49278' 16:35:14 INFO - SUITE-START | Running 2 tests 16:35:14 INFO - TEST-START | dromaeo_css 16:35:14 INFO - operating with platform_type : mac_ 16:35:14 INFO - Initialising browser for dromaeo_css test... 16:35:14 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/tmpK3Q3HH/profile http://localhost:49278/getInfo.html) 16:35:17 INFO - PROCESS | 1648 | __metrics Screen width/height:1600/1200 16:35:17 INFO - PROCESS | 1648 | colorDepth:24 16:35:17 INFO - PROCESS | 1648 | Browser inner width/height: 1024/689 16:35:17 INFO - PROCESS | 1648 | __metrics 16:35:17 INFO - PROCESS | 1648 | 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" 16:35:17 INFO - PROCESS | 1648 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:35:17 INFO - TEST-INFO | 1648: exit 0 16:35:17 INFO - Browser initialized. 16:35:17 INFO - Running cycle 1/1 for dromaeo_css test... 16:35:17 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/tmpK3Q3HH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:35:29 INFO - PROCESS | 1651 | RSS: Main: 234217472 16:35:29 INFO - PROCESS | 1651 | 16:36:21 INFO - PROCESS | 1651 | 1472686581088 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] 16:38:33 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49278/tests/dromaeo/cssquery-ext.html) 16:38:34 INFO - PROCESS | 1651 | RSS: Main: 370647040 16:38:34 INFO - PROCESS | 1651 | 16:41:37 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-ext.html (next: http://localhost:49278/tests/dromaeo/cssquery-jquery.html) 16:41:38 INFO - PROCESS | 1651 | RSS: Main: 382341120 16:41:38 INFO - PROCESS | 1651 | 16:44:40 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49278/tests/dromaeo/cssquery-mootools.html) 16:44:41 INFO - PROCESS | 1651 | RSS: Main: 345481216 16:44:41 INFO - PROCESS | 1651 | 16:47:43 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49278/tests/dromaeo/cssquery-prototype.html) 16:47:44 INFO - PROCESS | 1651 | RSS: Main: 346869760 16:47:44 INFO - PROCESS | 1651 | 16:50:47 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49278/tests/dromaeo/cssquery-yui.html) 16:50:48 INFO - PROCESS | 1651 | RSS: Main: 327659520 16:50:48 INFO - PROCESS | 1651 | 16:53:51 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-yui.html (next: http://localhost:49278/tests/dromaeo/cssquery-dojo.html) 16:53:51 INFO - PROCESS | 1651 | RSS: Main: 514174976 16:53:51 INFO - PROCESS | 1651 | 16:53:51 INFO - PROCESS | 1651 | __start_tp_report 16:53:51 INFO - PROCESS | 1651 | _x_x_mozilla_page_load 16:53:51 INFO - PROCESS | 1651 | _x_x_mozilla_page_load_details 16:53:51 INFO - PROCESS | 1651 | |i|pagename|runs| 16:53:51 INFO - PROCESS | 1651 | |0;dojo.html;3307.1401435626744;3319.883804066858;3364.293498365343;3364.9400438861758;3410.5736782902136;4569.908601827961;5647.548196144309;5672.914906276399;5698.515626171775;5733.885322293557;5683.715814209294;5734.971325143369;5742.339630942432;5748.19525266462;5760.308762948442;5180.870478238036;5216;5246.186841039645;5252.474752524736;5299.205119232108;5321.733913304339;5330.466953304677;5332.280142180814;5333.4399888011785;5336.9733151334385;5183.50756678115;5228.686278823283;5242.6330156889035;5270.657407268534;5317.308749862513;5325.680459172443;5339.492748188916;5340.359156901197;5344.43883392246;5363.436839131913;5126.077306084903;5129.974350128263;5187.507186817246;5261.421243663194;5281.603879709037;4885.438174609894;5660.632058916157;5720.8283751487525;5745.1669507921115;5772.769089236425;6281.528885333618;6281.654509002003;6289.685515724242;6303.8739225215695;6310.368963103699;6126.151269885583;6247.312795592481;6318.273398559132;6344.587601805868;6357.141785858942;5817.505512031483;5850.736716847752;5879.617824841372;5893.675847828325;5942.970285148546;6326.5255105867245;6328.17733694626;6347.5239357047485;6351.142595749607;6354;5959.791407300723;6160.10678451622;6168.383161683796;6234.719437625317;6242.43818056368;6206.286277078104;6237.4698150656795;6273.37266273369;6343.904841427382;6348.873022539523;6263.749450022039;6272.749090036439;6279.874402512016;6290.968545157245;6297.307296197415;6131.019036954066;6301.369863013662;6302.968485157637;6308.558400911984;6321.367863213642;4899.902001960012;4970.428400733889;5032.672876263031;5037.848864534069;5078.162103252922;4457.0194557197365;4467.329900514948;4475.351074094303;4475.641948644101;4475.753833539186;5727.541796656257;5742.310922689304;5752.108423194411;5759.884802304015;5764.855878603068;6110.771402862501;6194.5044396448175;6216.533759968021;6249.187605611332;6288.874222515524;5743.8564035899435;5754.165645981393;5761.164631128463;5770.538356931435;5795.217645617788;5432.103702889058;5475.507204351547;5483.040467918098;5484.588655850747;5488.56091512678;6241.126242326169;6254.9687251563455;6281.748730050746;6291.779787707408;6369.9681501592195;6285.905711414424;6309.432151106423;6343.841403964938;6350.7142178603;6360.332165122595;6369.49044076482;6375.713092910737;6397.0404439332615;6399.872002560016;6403.103565500926;5107.897842043212;6106.389361063858;6160.969195153995;6178.135061091541;6247.68761561911;6330.39861213184;6335.303116657256;6335.3981371769605;6336.524760642878;6342.809715708536;6171.537134714825;6181.876362472635;6202.255729312511;6217.067439884054;6231.376862313732;5786.537077033743;5939.821805345847;5990.281166260077;5999.880002400015;6080.270367555921;5921.703914804329;6000.639961602318;6002.129691194839;6007.729652165575;6041.607295525777;6134.539909506716;6144.907826382697;6157.6613286269685;6162.383761623802;6182.969085154546;5799.710014499174;5846.093855452412;5977.820665380045;5998.8500287491415;6056.606320589148 16:53:51 INFO - PROCESS | 1651 | |1;ext.html;522.9398619158796;528.5322489596708;534.345426852106;536.334944668611;556.2879514221793;29914.700852991464;37147.62852371475;40104.59895401045;41094.58905410945;41155.794221028926;41763.74708758735;42104.36843447351;42320.153596928045;42370.57629423705;42440;8631.95684021579;9577.90422095779;9773.413595184276;9784.36401633895;9790.461524616143;8689.652413903435;11734.471948762326;11975.880241197563;11982.880171198263;12198;11889.821652675217;11956.70108247292;11967.820482692767;11978.520859165623;12003.699907502294;12019.879801201963;12104.273743575326;12111.454984525717;12118.939405303005;12123.515059397658;11713.53145874164;11783.64649060521;11793.410329483577;11800.64598062051;11809.645710628694;39319.803400983095;39609.801950990346;39667.8016609918;39669.206615867806;39715;3551.6803487685975;3590.9461358079648;3950.8419663213435;4065.9593404065877;4150.667946564267;30373.088807335815;30389.848050759603;30650.00349088876;30733.385332293452;30763.769449222003;3107.5960125183806;3240.4815229563155;3243.254051568129;3290.276139249361;3332.350191712617;460917;471370;472039;473825.57046577317;475834.62082689366;56396.87206255852;56782.43217567771;56941.14588281179;56984;57028.42971570314;34993.12517187091;35754.821225893706;58115;58326;58460.70769646124;16022.919885400499;16478.2584783685;16481.423150189687;16514.917425412797;16661.333546658;11326.943365283121;11555.480003399867;12477.563285284972;12570.994320454342;12727.618171454871;18137.63724725498;18503.074846257903;18674.719879201824;18684.34604788826;18708.812911870707;7686.923130768732;7720.459567830198;7775.844483110419;7783.299503044753;7866.960665196638;894.6600291889041;951.5004622573205;1033.194108595297;1036.7667274863247;1050.894910508943;29541;29688.109356719335;29995.950141745372;30292.939747108747;30387;1051.4847724614883;1058.1006144776877;1068.038765111406;1072.485207100596;1175.629676651852;11793.528258869721;15476.922615386851;15569;15616.57147390138;15871.12708801004;638825;645372;649072;650115;654493;53316.2002569954;55829;56404;56416.153757692846;56547.86904261892;1036.4144258493927;1330.6340756291904;1362.5707902010836;1371.3897315694421;1397.3572156807982;30008.2005914359;30716.232094197825;30796.846015769777;31249;31557.36885262328;47002.059958801314;47466.52533474621;47563.52436475729;47681;47682.04635907193;518456;519878;519957;520311;521358.3932080467;25501.744982550677;26036.739632603432;26741.320268952466;26836;27591.72408275972;45720.8569785758;45837.08325833398;46030.76984615056;46481.76759116183;47058;35659.93020209398;37938.43092353562;38076.61923380731;39432.211355773296;39660.40509392304;32984.67015329816;37368;37766.43350349692;37921;38438.23123537569;37515.81242093772;37884.24231515409;38184.80907595444;38295;38336.04159896025;32003;32797.016089517354;33604;33757;33764.3247135051;24126.276211713677;24197.63703544413;24860.37849053788;25198.992040318182;25626.743732562434 16:53:51 INFO - PROCESS | 1651 | |2;jquery.html;358.56076306524494;380.3439067608375;383.48038407957233;392.9724919255651;414.7594395250755;15390.923045384772;15556.366581508933;21052.473688157766;21396.037178326973;21511.56976860462;19954.90022549885;20592.1763129475;20863.061162247697;21124.57750844982;21456.356309310708;6032.215811944452;6087;6131.688888000496;6198.938010619881;6277.435030847223;6058.697065146725;6173.9073913891325;6180.351063138373;6197.380261973789;6204.937950620504;4205.201011807762;6641.701123449432;6914.274001229874;6980.092587963558;7044.964775176142;6611.933880661228;6789.592624442549;6835.7949261522235;6900.378965893068;6946.965265173667;5919.467247947662;5920.052791553374;5923.437273459014;5929.673867937262;5943.3759455257;26719.866400667874;27892.302692432648;27988.720112798816;27989.720102799118;28280.717192828015;2267.183813827021;2414.985706003483;2845.8292502449917;3134.717875391226;3228.741700663941;16934.83065169345;17264.74102888458;17298.048607326593;17424.738628920575;18439.539011524685;1914.2438736699064;1997.2011195521761;2094.9266775662777;2131.488442773739;2190.912363505458;121970.53397791274;142946;153017.46982530254;155159;157972.21013894858;21452.92053622301;21524.569508610053;21540.353789386343;21727.111399935206;21888.169570325128;29126.563101553493;35574.822125889725;35590;35645;35663.64336356603;9216.677416290398;10786.921307869306;10991.835122473169;11124.944375278234;11146.554137834559;9101.81796364069;9120.8631870522;9124.908750912538;9347.298952578447;9349.158575728217;8821.514816685143;9150.771230719152;9689.951550242204;9783.559739811582;9874;3741.1769410729594;3767.981160094182;3772.7170462215445;3786.5645450772963;3875.6318149775725;706.6396137969673;781.9139894611588;803.9316658084128;807.7963833887466;954.3653470442079;21315.934203289773;21882.562348752937;21905.561888762455;21963;21974.230901918356;426.15195760436507;898.7303808857447;926.9907300926905;933.7712260496086;985.4235272365746;5673.801416950387;5909.881802363929;6082.908756368658;6213.720382582795;6250.000000000047;536873;608687;609792;612833;614245;82907.17092829115;123254.76745232612;133032.66967330396;141871.87192192127;146578;453.33359960857484;459.07496394764576;460.5417609478523;460.94238220222474;462.13811242033364;11261.831072534082;11346.716332091764;11407;11593.304401735923;11596.826047609462;56343.46454409479;56750.43249567451;57409;58070;58348.66605335909;83412;83862.58068709617;83879;83903.16096838954;84112.57943710242;7955.4828936118965;8025.839483210187;8324.876141721032;8336.208060234268;8557.529335886586;9554;13015.088943773844;13593.184408935496;13718.38267277955;13766.793498097337;11259;11345.943270283597;11827.112966527717;11856.585019524275;11861.229020113666;9657.889342725395;10832.67501974942;11069.398691307697;11212.04697600713;11422.31466112036;11359.375234361858;11613.535458581562;11729.530818767495;11808.645740627795;12047.939760301142;8274.710385136492;8393.790155246168;8449.915500844912;8521.488710677379;8633.940899620395;8594.269487093667;9658.565364558752;11054.336739795637;11443.771124577628;11578.710532236424 16:53:51 INFO - PROCESS | 1651 | |3;mootools.html;1463.853614638536;1545.6058705030216;1784.589544404787;1904.333483280851;1972.2899756087807;8537.871931921018;9089.727308180756;9094.226990705798;9094.408863423881;9094.90905090949;7914.327282181007;8147.26674599286;8630.180132887366;8885.955570222157;8891.199792018717;2396.2451827674313;2436.768506991833;2438.2807071913826;2438.719547252062;2445.7798798108165;2393.748656391071;2413.239829453725;2437.5612389769835;2439.4145405102736;2473.480569080495;2183.3449965010514;2335.2877372401495;2404.3989002749313;2426.7209270933804;2439.487707581401;2385.3917251101034;2406.5908795504806;2412.867292298923;2415.975840241593;2417.480241748015;2273.4089136824377;2371.8102551795814;2381.2380038387723;2400.183937461254;2401.3156250468705;7654.73208437702;8902.065283145306;9013.684521041732;9018.233450156744;9060.77348066297;5314.388845282765;5493.890122197574;5499.890002199973;5501.367342755594;5517.2551705520045;2952.852357382122;3657.4330978698345;3728.384816505273;3741.4761933329355;3743.3636281832155;960.5581432541039;1093.8031154392206;1108.894655007773;1112.6439539347411;1118.2954738514766;11713.53145874164;11720.413979301085;11827.994620457272;11828.585999489977;11851.051915846798;4415.712978656441;4518.299663552155;4682.157211701926;4700.341952126705;4705.106029854317;9303.953480232556;9322;9339.906600933904;9348.813023739624;9350.392224505385;5299.814506492331;5300.9734951325;5303.071962406563;5325.813596524103;5329.786808527616;3593.94609080864;4566.13243483737;4838.758062096881;4953.678010929278;5016.573591244785;3293.9953314239388;3363.72178572141;3374.342003309331;3688.4098544232793;3743;1166.9941650291864;1170.584442522892;1173.4074292482242;1183.071289038101;1184.7630473905253;819.2422009641058;919.3059240273551;933.272047802715;937.9484128373005;971.815355082532;5231.555317797954;5817.592768506164;5948.613340132878;6045.274567051982;6084.148219249309;433.86116442738654;441.44610969387577;451.24867096284316;455.6172814835554;469.3991690635991;3894.3768996960916;3964.266610677034;3988.222296652125;4092.0588264699286;4099.098198396348;9325.160735533835;10429.9478502607;10953.068989135852;10954.56181752721;10981.3960232188;10881.12950963921;10909.963553462409;10911.83632245517;10928.398938058324;10932.234743568033;7977;8284.627191776384;8368.288695460955;8383.245507904317;8412.74761757136;6001.7599296027665;6057.9701450754355;6114.0828875669;6114.327423983447;6232.626042437469;10207.7448063799;10266.486675666336;10312.535935883054;10314.793704126025;10342;8322.042965058854;10087.596496140366;10300.072993430627;10304.639337623148;10503.369797812156;7002.544834585736;7227.168875579166;7236.7467138652;7623.085229772463;7718.343940764873;7981.122076571689;8067.596620169085;8263.83472330538;8553.657853686032;8567.100454452193;7421.517601355895;7465.141508726349;7551.131619863786;7646.464747467624;7681.078270607562;7274.945132955798;7461.813454663677;7461.813454663677;7479.289467500579;7634.580098094658;6184.226971628546;7001;7165.247648996989;7166.355028047501;7175.569465832067;5023.522765337287;5098.719570423662;5117.385913690381;5172.7155006475;5233.528982391451;5085.796568137381;5126.589872810241;5137.152369858932;5219.739013049409;5327.334083239595 16:53:51 INFO - PROCESS | 1651 | |4;prototype.html;300.5626812987104;303.65686773945447;313.1919647309941;316.0833582610428;317.68549136355017;5504.64219825711;5511.58663100267;5547.972260138704;5550.944490555098;5552.472515111068;4589.793459294331;5025.949740502603;5177.767000484978;5481.616286859921;5510.154098377048;3441.500982357563;3520.489529018291;4145.958540414587;4181.770002649868;4224.817051225662;3475.77407468515;4133.925179453334;4171.624553790159;4176.937345939813;4211.5788421157795;3900.3369427197304;4097.405876147956;4137.8758637240935;4139.56534563871;4145.818441744103;3807.562130355017;4109.856155034559;4119.096037293716;4129.380592911058;4130.2978493656;3554.608993010767;3560.2345495718546;3578.2645416972746;3580.1407662160896;3580.8030558319283;5035.823746168867;5348.459481746178;5352.95617354616;5386.515213630752;5407.215618845771;3934.8622798201927;4687.406281864182;4696.3894693689945;4701.905961880778;4727.952720472786;5784.49603103192;5785.768569257225;5794.031088704209;5800.506956908668;5809.302883653989;3603.7837729736298;3906.277338692351;3925.9203718977224;3931.4470784040145;3931.9017024574327;5294;5964.463198312173;5973.8805223895715;5997.820065398045;6093.664848433335;3685.8389607273625;3721;3785.280796648628;3941.724079314478;3944.6449819516383;3507.6141624421293;3978.826246257361;4018.256622524784;4025.5169379674626;4031;3955.5055618047745;4452.799624016927;4578.381918440968;4673.299005149255;4685.961606701096;5240.716024574004;5404.405515393303;5589.804356847572;5722.224721010307;5738.426157384228;1211.4124649544963;1231.5098730535972;1281.6923938254938;1352.532502235086;1368.7262547490343;661.3452681844939;821.2116368286393;868.96524139035;909.272581934463;920.7606022434081;5396.271503347077;5406.540444062299;5410.97294513525;5431.402545719968;5431.83704488866;4833.854984350475;5347.572194224453;5481.862953426116;5849.502792262705;5879.617824841372;4492.707973981681;4858.392700912386;5047.596192304681;5348.411674715778;5371.898760754114;2723.509768241696;2841.8579071046365;3073.5236038413636;3094.777562862657;3111.735502482269;4676.719396836201;5287.920681189708;5297.258383826267;6065.5450841187085;6098.963176260047;4999.975000124976;5949.727649792683;5959.824663997696;5973.522118230531;5977.282726072899;5967.761289548456;5972.879627732093;5997.910031349533;6030.547708921848;6060.121282414113;5240.502152295526;5499.835004949858;5766.711664416846;5818.50542703858;5859.92354014146;5498.642588231752;5823.70881455934;5829.650220986754;5831.94168058314;5847.076461769116;4919.852404427873;5717.656940583578;6044.425779550936;6104.4505994460715;6105.328413874559;2984.910452686423;3940.034691500486;3952.7430717003303;3960.683145348308;3989.7207195496035;5956.255468066492;5982.850428739142;5991.610545314541;6010.459058684739;6011.579189456696;4917;5066.758644132211;5908.822735317947;5916.053431450947;5930.970345148419;4803.5916947058495;5720.513756330759;5738.770449181986;5765.4811067004175;5777.595568310178;5290;5385.649932754515;5681.488666019913;5710.114932185518;5751.539876809761;5221.477852214748;5647.519960803378;5735.340435849931;5833.79581714638;5835.753235004639;4543.54564543543;5174.404943431554;5441.891162176655;5640.730835561966;5642 16:53:51 INFO - PROCESS | 1651 | |5;yui.html;712.5051621795097;718.532953580173;766.421351879331;812.3135950122145;821.7945513621593;12895.226286422809;15897.602559936007;15925.920370398146;15945.362185512566;15982.360705571778;14383.280835958212;14490.5652830415;14493.420263189486;14519.419223231085;14530.273486325668;674.4402146218652;696.1854630082815;836.356005875474;839.4249938791947;864.3646919514183;849.8300339932007;858.6393714639835;858.6951632170583;859.1408591408591;859.6905114158898;912.5847739278612;913.1051569461931;914.2274777812771;914.3553794574842;917.5458148216642;710.3358359933479;884.6417201033591;901.2024358442793;907.4691305586207;914.6250037484599;676.2257215488288;677.9728810847561;678.6165816313817;680.6494655252549;681.366329313738;14635;14682.55952321432;14695.338709758087;14699;14701.632459188499;13608.183508989394;13841.861581384159;13934.512292069729;14094.647633809134;14360.425582976668;12563.309018004005;12614.68463288416;12652.936735316356;12775.87224127756;12792.168509046882;4382.057857560608;4461.308497182942;4482.753448560328;4496.977515112436;4665.113628410591;92619.53690231573;118816.99999999914;122525.38737306345;122542;125132;8568;10992.945035274772;11015.614453494089;11054.00513953748;11299;20802.751834889954;21155.15379384838;21156.259530916344;21160.576788464452;21241.150353985668;7674.6930122794465;7693.307602315706;7700.422468314899;7896.842063158705;7973.960130199312;4432.889177770581;4470.150671372428;4471.642268618502;4516;4629.444466664022;3481;3603.6936860367164;3603.891883243507;3609.3864043112208;3846.2692088503095;726.3462883404978;726.6148941061246;728.3153835394712;733.1678544851632;736.5323019882413;577.7313549199642;599.5233789137559;656.2190992718649;682.8395327098128;697.5117417807555;9397.389169703947;9416.482093484925;9416.764580885532;9432.151106400457;9440.197583205505;632.4466092169351;650.9902351464732;672.4586707700348;682.3014975770815;685.6605980039913;4336.9132617348105;4417.845375411909;4501.48232953208;4585.10590434862;4624.213883639722;157972.42027579807;160328.3967160337;160752;164902;169232;140413.2979335097;140608;140843;142221;143812;90632.64051039239;100943.49528252606;104654.95345046604;121679;124010;9271.733730750124;9685.806283874143;9746.411080463939;9767.609295628095;9857.753556161153;39228.41157382585;56543.71728141333;56760.716196418754;63112.68443657936;63213.68393158189;87434;90833;95862;97074;99705;10459.052808255594;12625.936870315589;12833.101682882481;12911;12983.285919274153;14487.855121449073;14505.492307769178;14817.703645926806;14821.25893705332;15177.924110379377;13531.391087400894;13551.52569660057;13753.724925501634;13776.173429594257;14030.298485075502;13474.19154850712;13512.391942362421;13995.51015714445;14019.698903795259;14205.644858878612;13239.536616218385;13257.469701211843;13607;14037.5086871959;14070.085444446078;11416.479935321811;11419.200655954004;11573.657815340845;11620.825687614524;11757.529698812294;10326.328788628714;10343.63797267092;11293.37886416255;11484.540618375171;11526.308421494738 16:53:51 INFO - PROCESS | 1651 | __end_tp_report 16:53:51 INFO - PROCESS | 1651 | __start_cc_report 16:53:51 INFO - PROCESS | 1651 | _x_x_mozilla_cycle_collect,1280 16:53:51 INFO - PROCESS | 1651 | __end_cc_report 16:53:51 INFO - PROCESS | 1651 | __startTimestamp1472687631788__endTimestamp 16:53:51 INFO - PROCESS | 1651 | 16:53:51 INFO - PROCESS | 1651 | ------- Summary: start ------- 16:53:51 INFO - PROCESS | 1651 | Number of tests: 6 16:53:51 INFO - PROCESS | 1651 | 16:53:51 INFO - PROCESS | 1651 | [#0] dojo.html Cycles:165 Average:5771.83 Median:5977.82 stddev:651.72 (10.9%) stddev-sans-first:624.38 16:53:51 INFO - PROCESS | 1651 | Values: 3307.1 3319.9 3364.3 3364.9 3410.6 4569.9 5647.5 5672.9 5698.5 5733.9 5683.7 5735.0 5742.3 5748.2 5760.3 5180.9 5216.0 5246.2 5252.5 5299.2 5321.7 5330.5 5332.3 5333.4 5337.0 5183.5 5228.7 5242.6 5270.7 5317.3 5325.7 5339.5 5340.4 5344.4 5363.4 5126.1 5130.0 5187.5 5261.4 5281.6 4885.4 5660.6 5720.8 5745.2 5772.8 6281.5 6281.7 6289.7 6303.9 6310.4 6126.2 6247.3 6318.3 6344.6 6357.1 5817.5 5850.7 5879.6 5893.7 5943.0 6326.5 6328.2 6347.5 6351.1 6354.0 5959.8 6160.1 6168.4 6234.7 6242.4 6206.3 6237.5 6273.4 6343.9 6348.9 6263.7 6272.7 6279.9 6291.0 6297.3 6131.0 6301.4 6303.0 6308.6 6321.4 4899.9 4970.4 5032.7 5037.8 5078.2 4457.0 4467.3 4475.4 4475.6 4475.8 5727.5 5742.3 5752.1 5759.9 5764.9 6110.8 6194.5 6216.5 6249.2 6288.9 5743.9 5754.2 5761.2 5770.5 5795.2 5432.1 5475.5 5483.0 5484.6 5488.6 6241.1 6255.0 6281.7 6291.8 6370.0 6285.9 6309.4 6343.8 6350.7 6360.3 6369.5 6375.7 6397.0 6399.9 6403.1 5107.9 6106.4 6161.0 6178.1 6247.7 6330.4 6335.3 6335.4 6336.5 6342.8 6171.5 6181.9 6202.3 6217.1 6231.4 5786.5 5939.8 5990.3 5999.9 6080.3 5921.7 6000.6 6002.1 6007.7 6041.6 6134.5 6144.9 6157.7 6162.4 6183.0 5799.7 5846.1 5977.8 5998.9 6056.6 16:53:51 INFO - PROCESS | 1651 | 16:53:51 INFO - PROCESS | 1651 | [#1] ext.html Cycles:180 Average:67774.35 Median:19605.81 stddev:147166.90 (750.6%) stddev-sans-first:147493.12 16:53:51 INFO - PROCESS | 1651 | Values: 522.9 528.5 534.3 536.3 556.3 29914.7 37147.6 40104.6 41094.6 41155.8 41763.7 42104.4 42320.2 42370.6 42440.0 8632.0 9577.9 9773.4 9784.4 9790.5 8689.7 11734.5 11975.9 11982.9 12198.0 11889.8 11956.7 11967.8 11978.5 12003.7 12019.9 12104.3 12111.5 12118.9 12123.5 11713.5 11783.6 11793.4 11800.6 11809.6 39319.8 39609.8 39667.8 39669.2 39715.0 3551.7 3590.9 3950.8 4066.0 4150.7 30373.1 30389.8 30650.0 30733.4 30763.8 3107.6 3240.5 3243.3 3290.3 3332.4 460917.0 471370.0 472039.0 473825.6 475834.6 56396.9 56782.4 56941.1 56984.0 57028.4 34993.1 35754.8 58115.0 58326.0 58460.7 16022.9 16478.3 16481.4 16514.9 16661.3 11326.9 11555.5 12477.6 12571.0 12727.6 18137.6 18503.1 18674.7 18684.3 18708.8 7686.9 7720.5 7775.8 7783.3 7867.0 894.7 951.5 1033.2 1036.8 1050.9 29541.0 29688.1 29996.0 30292.9 30387.0 1051.5 1058.1 1068.0 1072.5 1175.6 11793.5 15476.9 15569.0 15616.6 15871.1 638825.0 645372.0 649072.0 650115.0 654493.0 53316.2 55829.0 56404.0 56416.2 56547.9 1036.4 1330.6 1362.6 1371.4 1397.4 30008.2 30716.2 30796.8 31249.0 31557.4 47002.1 47466.5 47563.5 47681.0 47682.0 518456.0 519878.0 519957.0 520311.0 521358.4 25501.7 26036.7 26741.3 26836.0 27591.7 45720.9 45837.1 46030.8 46481.8 47058.0 35659.9 37938.4 38076.6 39432.2 39660.4 32984.7 37368.0 37766.4 37921.0 38438.2 37515.8 37884.2 38184.8 38295.0 38336.0 32003.0 32797.0 33604.0 33757.0 33764.3 24126.3 24197.6 24860.4 25199.0 25626.7 16:53:51 INFO - PROCESS | 1651 | 16:53:51 INFO - PROCESS | 1651 | [#2] jquery.html Cycles:180 Average:37100.68 Median:2129.56 stddev:100387.26 (4714.0%) stddev-sans-first:100630.97 16:53:51 INFO - PROCESS | 1651 | Values: 358.6 380.3 383.5 393.0 414.8 15390.9 15556.4 21052.5 21396.0 21511.6 19954.9 20592.2 20863.1 21124.6 21456.4 6032.2 6087.0 6131.7 6198.9 6277.4 6058.7 6173.9 6180.4 6197.4 6204.9 4205.2 6641.7 6914.3 6980.1 7045.0 6611.9 6789.6 6835.8 6900.4 6947.0 5919.5 5920.1 5923.4 5929.7 5943.4 26719.9 27892.3 27988.7 27989.7 28280.7 2267.2 2415.0 2845.8 3134.7 3228.7 16934.8 17264.7 17298.0 17424.7 18439.5 1914.2 1997.2 2094.9 2131.5 2190.9 121970.5 142946.0 153017.5 155159.0 157972.2 21452.9 21524.6 21540.4 21727.1 21888.2 29126.6 35574.8 35590.0 35645.0 35663.6 9216.7 10786.9 10991.8 11124.9 11146.6 9101.8 9120.9 9124.9 9347.3 9349.2 8821.5 9150.8 9690.0 9783.6 9874.0 3741.2 3768.0 3772.7 3786.6 3875.6 706.6 781.9 803.9 807.8 954.4 21315.9 21882.6 21905.6 21963.0 21974.2 426.2 898.7 927.0 933.8 985.4 5673.8 5909.9 6082.9 6213.7 6250.0 536873.0 608687.0 609792.0 612833.0 614245.0 82907.2 123254.8 133032.7 141871.9 146578.0 453.3 459.1 460.5 460.9 462.1 11261.8 11346.7 11407.0 11593.3 11596.8 56343.5 56750.4 57409.0 58070.0 58348.7 83412.0 83862.6 83879.0 83903.2 84112.6 7955.5 8025.8 8324.9 8336.2 8557.5 9554.0 13015.1 13593.2 13718.4 13766.8 11259.0 11345.9 11827.1 11856.6 11861.2 9657.9 10832.7 11069.4 11212.0 11422.3 11359.4 11613.5 11729.5 11808.6 12047.9 8274.7 8393.8 8449.9 8521.5 8633.9 8594.3 9658.6 11054.3 11443.8 11578.7 16:53:51 INFO - PROCESS | 1651 | 16:53:51 INFO - PROCESS | 1651 | [#3] mootools.html Cycles:180 Average:5707.67 Median:4985.13 stddev:3224.03 (64.7%) stddev-sans-first:3217.30 16:53:51 INFO - PROCESS | 1651 | Values: 1463.9 1545.6 1784.6 1904.3 1972.3 8537.9 9089.7 9094.2 9094.4 9094.9 7914.3 8147.3 8630.2 8886.0 8891.2 2396.2 2436.8 2438.3 2438.7 2445.8 2393.7 2413.2 2437.6 2439.4 2473.5 2183.3 2335.3 2404.4 2426.7 2439.5 2385.4 2406.6 2412.9 2416.0 2417.5 2273.4 2371.8 2381.2 2400.2 2401.3 7654.7 8902.1 9013.7 9018.2 9060.8 5314.4 5493.9 5499.9 5501.4 5517.3 2952.9 3657.4 3728.4 3741.5 3743.4 960.6 1093.8 1108.9 1112.6 1118.3 11713.5 11720.4 11828.0 11828.6 11851.1 4415.7 4518.3 4682.2 4700.3 4705.1 9304.0 9322.0 9339.9 9348.8 9350.4 5299.8 5301.0 5303.1 5325.8 5329.8 3593.9 4566.1 4838.8 4953.7 5016.6 3294.0 3363.7 3374.3 3688.4 3743.0 1167.0 1170.6 1173.4 1183.1 1184.8 819.2 919.3 933.3 937.9 971.8 5231.6 5817.6 5948.6 6045.3 6084.1 433.9 441.4 451.2 455.6 469.4 3894.4 3964.3 3988.2 4092.1 4099.1 9325.2 10429.9 10953.1 10954.6 10981.4 10881.1 10910.0 10911.8 10928.4 10932.2 7977.0 8284.6 8368.3 8383.2 8412.7 6001.8 6058.0 6114.1 6114.3 6232.6 10207.7 10266.5 10312.5 10314.8 10342.0 8322.0 10087.6 10300.1 10304.6 10503.4 7002.5 7227.2 7236.7 7623.1 7718.3 7981.1 8067.6 8263.8 8553.7 8567.1 7421.5 7465.1 7551.1 7646.5 7681.1 7274.9 7461.8 7461.8 7479.3 7634.6 6184.2 7001.0 7165.2 7166.4 7175.6 5023.5 5098.7 5117.4 5172.7 5233.5 5085.8 5126.6 5137.2 5219.7 5327.3 16:53:51 INFO - PROCESS | 1651 | 16:53:51 INFO - PROCESS | 1651 | [#4] prototype.html Cycles:180 Average:4627.58 Median:5292.00 stddev:1446.00 (27.3%) stddev-sans-first:1413.12 16:53:51 INFO - PROCESS | 1651 | Values: 300.6 303.7 313.2 316.1 317.7 5504.6 5511.6 5548.0 5550.9 5552.5 4589.8 5025.9 5177.8 5481.6 5510.2 3441.5 3520.5 4146.0 4181.8 4224.8 3475.8 4133.9 4171.6 4176.9 4211.6 3900.3 4097.4 4137.9 4139.6 4145.8 3807.6 4109.9 4119.1 4129.4 4130.3 3554.6 3560.2 3578.3 3580.1 3580.8 5035.8 5348.5 5353.0 5386.5 5407.2 3934.9 4687.4 4696.4 4701.9 4728.0 5784.5 5785.8 5794.0 5800.5 5809.3 3603.8 3906.3 3925.9 3931.4 3931.9 5294.0 5964.5 5973.9 5997.8 6093.7 3685.8 3721.0 3785.3 3941.7 3944.6 3507.6 3978.8 4018.3 4025.5 4031.0 3955.5 4452.8 4578.4 4673.3 4686.0 5240.7 5404.4 5589.8 5722.2 5738.4 1211.4 1231.5 1281.7 1352.5 1368.7 661.3 821.2 869.0 909.3 920.8 5396.3 5406.5 5411.0 5431.4 5431.8 4833.9 5347.6 5481.9 5849.5 5879.6 4492.7 4858.4 5047.6 5348.4 5371.9 2723.5 2841.9 3073.5 3094.8 3111.7 4676.7 5287.9 5297.3 6065.5 6099.0 5000.0 5949.7 5959.8 5973.5 5977.3 5967.8 5972.9 5997.9 6030.5 6060.1 5240.5 5499.8 5766.7 5818.5 5859.9 5498.6 5823.7 5829.7 5831.9 5847.1 4919.9 5717.7 6044.4 6104.5 6105.3 2984.9 3940.0 3952.7 3960.7 3989.7 5956.3 5982.9 5991.6 6010.5 6011.6 4917.0 5066.8 5908.8 5916.1 5931.0 4803.6 5720.5 5738.8 5765.5 5777.6 5290.0 5385.6 5681.5 5710.1 5751.5 5221.5 5647.5 5735.3 5833.8 5835.8 4543.5 5174.4 5441.9 5640.7 5642.0 16:53:51 INFO - PROCESS | 1651 | 16:53:51 INFO - PROCESS | 1651 | [#5] yui.html Cycles:180 Average:25652.81 Median:3727.83 stddev:42141.05 (1130.4%) stddev-sans-first:42217.67 16:53:51 INFO - PROCESS | 1651 | Values: 712.5 718.5 766.4 812.3 821.8 12895.2 15897.6 15925.9 15945.4 15982.4 14383.3 14490.6 14493.4 14519.4 14530.3 674.4 696.2 836.4 839.4 864.4 849.8 858.6 858.7 859.1 859.7 912.6 913.1 914.2 914.4 917.5 710.3 884.6 901.2 907.5 914.6 676.2 678.0 678.6 680.6 681.4 14635.0 14682.6 14695.3 14699.0 14701.6 13608.2 13841.9 13934.5 14094.6 14360.4 12563.3 12614.7 12652.9 12775.9 12792.2 4382.1 4461.3 4482.8 4497.0 4665.1 92619.5 118817.0 122525.4 122542.0 125132.0 8568.0 10992.9 11015.6 11054.0 11299.0 20802.8 21155.2 21156.3 21160.6 21241.2 7674.7 7693.3 7700.4 7896.8 7974.0 4432.9 4470.2 4471.6 4516.0 4629.4 3481.0 3603.7 3603.9 3609.4 3846.3 726.3 726.6 728.3 733.2 736.5 577.7 599.5 656.2 682.8 697.5 9397.4 9416.5 9416.8 9432.2 9440.2 632.4 651.0 672.5 682.3 685.7 4336.9 4417.8 4501.5 4585.1 4624.2 157972.4 160328.4 160752.0 164902.0 169232.0 140413.3 140608.0 140843.0 142221.0 143812.0 90632.6 100943.5 104655.0 121679.0 124010.0 9271.7 9685.8 9746.4 9767.6 9857.8 39228.4 56543.7 56760.7 63112.7 63213.7 87434.0 90833.0 95862.0 97074.0 99705.0 10459.1 12625.9 12833.1 12911.0 12983.3 14487.9 14505.5 14817.7 14821.3 15177.9 13531.4 13551.5 13753.7 13776.2 14030.3 13474.2 13512.4 13995.5 14019.7 14205.6 13239.5 13257.5 13607.0 14037.5 14070.1 11416.5 11419.2 11573.7 11620.8 11757.5 10326.3 10343.6 11293.4 11484.5 11526.3 16:53:51 INFO - PROCESS | 1651 | -------- Summary: end -------- 16:53:51 INFO - PROCESS | 1651 | 16:53:52 INFO - TEST-INFO | 1651: exit 0 16:53:52 INFO - TEST-OK | dromaeo_css | took 1118011ms 16:53:52 INFO - TEST-START | kraken 16:53:52 INFO - operating with platform_type : mac_ 16:53:52 INFO - Initialising browser for kraken test... 16:53:52 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/tmpOAalDf/profile http://localhost:49278/getInfo.html) 16:53:54 INFO - PROCESS | 1661 | __metrics Screen width/height:1600/1200 16:53:54 INFO - PROCESS | 1661 | colorDepth:24 16:53:54 INFO - PROCESS | 1661 | Browser inner width/height: 1024/689 16:53:54 INFO - PROCESS | 1661 | __metrics 16:53:54 INFO - PROCESS | 1661 | 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" 16:53:54 INFO - PROCESS | 1661 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:53:55 INFO - TEST-INFO | 1661: exit 0 16:53:55 INFO - Browser initialized. 16:53:55 INFO - Running cycle 1/1 for kraken test... 16:53:55 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/T/tmpOAalDf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:54:07 INFO - PROCESS | 1662 | RSS: Main: 234643456 16:54:07 INFO - PROCESS | 1662 | 16:54:10 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection) 16:54:11 INFO - PROCESS | 1662 | RSS: Main: 292167680 16:54:11 INFO - PROCESS | 1662 | 16:54:13 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-dft) 16:54:14 INFO - PROCESS | 1662 | RSS: Main: 288886784 16:54:14 INFO - PROCESS | 1662 | 16:54:17 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-fft) 16:54:18 INFO - PROCESS | 1662 | RSS: Main: 379453440 16:54:18 INFO - PROCESS | 1662 | 16:54:20 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator) 16:54:20 INFO - PROCESS | 1662 | RSS: Main: 281759744 16:54:20 INFO - PROCESS | 1662 | 16:54:23 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:54:23 INFO - PROCESS | 1662 | RSS: Main: 284688384 16:54:23 INFO - PROCESS | 1662 | 16:54:26 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom) 16:54:27 INFO - PROCESS | 1662 | RSS: Main: 324145152 16:54:27 INFO - PROCESS | 1662 | 16:54:29 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate) 16:54:30 INFO - PROCESS | 1662 | RSS: Main: 324689920 16:54:30 INFO - PROCESS | 1662 | 16:54:33 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial) 16:54:33 INFO - PROCESS | 1662 | RSS: Main: 323362816 16:54:33 INFO - PROCESS | 1662 | 16:54:35 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:54:36 INFO - PROCESS | 1662 | RSS: Main: 298131456 16:54:36 INFO - PROCESS | 1662 | 16:54:38 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:54:38 INFO - PROCESS | 1662 | RSS: Main: 301940736 16:54:38 INFO - PROCESS | 1662 | 16:54:40 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:54:41 INFO - PROCESS | 1662 | RSS: Main: 302039040 16:54:41 INFO - PROCESS | 1662 | 16:54:44 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:54:45 INFO - PROCESS | 1662 | RSS: Main: 321642496 16:54:45 INFO - PROCESS | 1662 | 16:54:48 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:54:48 INFO - PROCESS | 1662 | RSS: Main: 317829120 16:54:48 INFO - PROCESS | 1662 | 16:54:50 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49278/tests/kraken/driver.html?testName=ai-astar) 16:54:50 INFO - PROCESS | 1662 | RSS: Main: 361361408 16:54:50 INFO - PROCESS | 1662 | 16:54:50 INFO - PROCESS | 1662 | __start_tp_report 16:54:50 INFO - PROCESS | 1662 | _x_x_mozilla_page_load 16:54:50 INFO - PROCESS | 1662 | _x_x_mozilla_page_load_details 16:54:50 INFO - PROCESS | 1662 | |i|pagename|runs| 16:54:50 INFO - PROCESS | 1662 | |0;ai-astar;114;113;132;112;114;113;115;112;112;114 16:54:50 INFO - PROCESS | 1662 | |1;audio-beat-detection;150;109;132;164;108;134;130;112;195;135 16:54:50 INFO - PROCESS | 1662 | |2;audio-dft;185;183;183;181;183;183;182;183;183;184 16:54:50 INFO - PROCESS | 1662 | |3;audio-fft;78;73;74;152;58;56;56;66;142;57 16:54:50 INFO - PROCESS | 1662 | |4;audio-oscillator;82;82;129;71;77;89;142;75;70;117 16:54:50 INFO - PROCESS | 1662 | |5;imaging-gaussian-blur;87;83;87;83;86;86;89;85;87;83 16:54:50 INFO - PROCESS | 1662 | |6;imaging-darkroom;93;92;93;92;92;93;94;93;93;98 16:54:50 INFO - PROCESS | 1662 | |7;imaging-desaturate;87;86;87;86;129;137;137;86;86;86 16:54:50 INFO - PROCESS | 1662 | |8;json-parse-financial;51;53;56;56;55;53;54;52;53;54 16:54:50 INFO - PROCESS | 1662 | |9;json-stringify-tinderbox;65;65;86;111;107;78;70;70;71;70 16:54:50 INFO - PROCESS | 1662 | |10;stanford-crypto-aes;85;84;97;105;81;87;83;88;85;86 16:54:50 INFO - PROCESS | 1662 | |11;stanford-crypto-ccm;145;140;129;150;148;154;138;138;177;139 16:54:50 INFO - PROCESS | 1662 | |12;stanford-crypto-pbkdf2;170;167;171;170;167;169;168;172;167;167 16:54:50 INFO - PROCESS | 1662 | |13;stanford-crypto-sha256-iterative;69;68;69;67;67;67;67;67;67;67 16:54:50 INFO - PROCESS | 1662 | __end_tp_report 16:54:50 INFO - PROCESS | 1662 | __start_cc_report 16:54:50 INFO - PROCESS | 1662 | _x_x_mozilla_cycle_collect,1988 16:54:50 INFO - PROCESS | 1662 | __end_cc_report 16:54:50 INFO - PROCESS | 1662 | __startTimestamp1472687690654__endTimestamp 16:54:50 INFO - PROCESS | 1662 | 16:54:50 INFO - PROCESS | 1662 | ------- Summary: start ------- 16:54:50 INFO - PROCESS | 1662 | Number of tests: 14 16:54:50 INFO - PROCESS | 1662 | 16:54:50 INFO - PROCESS | 1662 | [#0] ai-astar Cycles:10 Average:115.10 Median:114.00 stddev:6.03 (5.3%) stddev-sans-first:6.38 16:54:50 INFO - PROCESS | 1662 | Values: 114.0 113.0 132.0 112.0 114.0 113.0 115.0 112.0 112.0 114.0 16:54:50 INFO - PROCESS | 1662 | 16:54:50 INFO - PROCESS | 1662 | [#1] audio-beat-detection Cycles:10 Average:136.90 Median:134.50 stddev:27.08 (20.1%) stddev-sans-first:28.30 16:54:50 INFO - PROCESS | 1662 | Values: 150.0 109.0 132.0 164.0 108.0 134.0 130.0 112.0 195.0 135.0 16:54:50 INFO - PROCESS | 1662 | 16:54:50 INFO - PROCESS | 1662 | [#2] audio-dft Cycles:10 Average:183.00 Median:183.00 stddev:1.05 (0.6%) stddev-sans-first:0.83 16:54:50 INFO - PROCESS | 1662 | Values: 185.0 183.0 183.0 181.0 183.0 183.0 182.0 183.0 183.0 184.0 16:54:50 INFO - PROCESS | 1662 | 16:54:50 INFO - PROCESS | 1662 | [#3] audio-fft Cycles:10 Average:81.20 Median:62.00 stddev:35.69 (57.6%) stddev-sans-first:37.84 16:54:50 INFO - PROCESS | 1662 | Values: 78.0 73.0 74.0 152.0 58.0 56.0 56.0 66.0 142.0 57.0 16:54:50 INFO - PROCESS | 1662 | 16:54:50 INFO - PROCESS | 1662 | [#4] audio-oscillator Cycles:10 Average:93.40 Median:76.00 stddev:26.08 (34.3%) stddev-sans-first:27.34 16:54:50 INFO - PROCESS | 1662 | Values: 82.0 82.0 129.0 71.0 77.0 89.0 142.0 75.0 70.0 117.0 16:54:50 INFO - PROCESS | 1662 | 16:54:50 INFO - PROCESS | 1662 | [#5] imaging-gaussian-blur Cycles:10 Average:85.60 Median:86.50 stddev:2.07 (2.4%) stddev-sans-first:2.13 16:54:50 INFO - PROCESS | 1662 | Values: 87.0 83.0 87.0 83.0 86.0 86.0 89.0 85.0 87.0 83.0 16:54:50 INFO - PROCESS | 1662 | 16:54:50 INFO - PROCESS | 1662 | [#6] imaging-darkroom Cycles:10 Average:93.30 Median:93.00 stddev:1.77 (1.9%) stddev-sans-first:1.87 16:54:50 INFO - PROCESS | 1662 | Values: 93.0 92.0 93.0 92.0 92.0 93.0 94.0 93.0 93.0 98.0 16:54:50 INFO - PROCESS | 1662 | 16:54:50 INFO - PROCESS | 1662 | [#7] imaging-desaturate Cycles:10 Average:100.70 Median:86.00 stddev:23.31 (27.1%) stddev-sans-first:24.20 16:54:50 INFO - PROCESS | 1662 | Values: 87.0 86.0 87.0 86.0 129.0 137.0 137.0 86.0 86.0 86.0 16:54:50 INFO - PROCESS | 1662 | 16:54:50 INFO - PROCESS | 1662 | [#8] json-parse-financial Cycles:10 Average:53.70 Median:54.00 stddev:1.64 (3.0%) stddev-sans-first:1.41 16:54:50 INFO - PROCESS | 1662 | Values: 51.0 53.0 56.0 56.0 55.0 53.0 54.0 52.0 53.0 54.0 16:54:50 INFO - PROCESS | 1662 | 16:54:50 INFO - PROCESS | 1662 | [#9] json-stringify-tinderbox Cycles:10 Average:79.30 Median:70.00 stddev:16.85 (24.1%) stddev-sans-first:17.06 16:54:50 INFO - PROCESS | 1662 | Values: 65.0 65.0 86.0 111.0 107.0 78.0 70.0 70.0 71.0 70.0 16:54:50 INFO - PROCESS | 1662 | 16:54:50 INFO - PROCESS | 1662 | [#10] stanford-crypto-aes Cycles:10 Average:88.10 Median:85.50 stddev:7.32 (8.6%) stddev-sans-first:7.68 16:54:50 INFO - PROCESS | 1662 | Values: 85.0 84.0 97.0 105.0 81.0 87.0 83.0 88.0 85.0 86.0 16:54:50 INFO - PROCESS | 1662 | 16:54:50 INFO - PROCESS | 1662 | [#11] stanford-crypto-ccm Cycles:10 Average:145.80 Median:146.50 stddev:13.11 (9.0%) stddev-sans-first:13.91 16:54:50 INFO - PROCESS | 1662 | Values: 145.0 140.0 129.0 150.0 148.0 154.0 138.0 138.0 177.0 139.0 16:54:50 INFO - PROCESS | 1662 | 16:54:50 INFO - PROCESS | 1662 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.80 Median:169.50 stddev:1.87 (1.1%) stddev-sans-first:1.94 16:54:50 INFO - PROCESS | 1662 | Values: 170.0 167.0 171.0 170.0 167.0 169.0 168.0 172.0 167.0 167.0 16:54:50 INFO - PROCESS | 1662 | 16:54:50 INFO - PROCESS | 1662 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.50 Median:67.00 stddev:0.85 (1.3%) stddev-sans-first:0.71 16:54:50 INFO - PROCESS | 1662 | Values: 69.0 68.0 69.0 67.0 67.0 67.0 67.0 67.0 67.0 67.0 16:54:50 INFO - PROCESS | 1662 | -------- Summary: end -------- 16:54:50 INFO - PROCESS | 1662 | 16:54:51 INFO - TEST-INFO | 1662: exit 0 16:54:51 INFO - TEST-OK | kraken | took 59097ms 16:54:51 INFO - SUITE-END | took 1177s 16:54:51 INFO - Completed test suite (00:19:37) 16:54:51 INFO - javascript benchmark 16:54:51 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3307.1401435626744, 3319.883804066858, 3364.293498365343, 3364.9400438861758, 3410.5736782902136, 4569.908601827961, 5647.548196144309, 5672.914906276399, 5698.515626171775, 5733.885322293557, 5683.715814209294, 5734.971325143369, 5742.339630942432, 5748.19525266462, 5760.308762948442, 5180.870478238036, 5216.0, 5246.186841039645, 5252.474752524736, 5299.205119232108, 5321.733913304339, 5330.466953304677, 5332.280142180814, 5333.4399888011785, 5336.9733151334385, 5183.50756678115, 5228.686278823283, 5242.6330156889035, 5270.657407268534, 5317.308749862513, 5325.680459172443, 5339.492748188916, 5340.359156901197, 5344.43883392246, 5363.436839131913, 5126.077306084903, 5129.974350128263, 5187.507186817246, 5261.421243663194, 5281.603879709037, 4885.438174609894, 5660.632058916157, 5720.8283751487525, 5745.1669507921115, 5772.769089236425, 6281.528885333618, 6281.654509002003, 6289.685515724242, 6303.8739225215695, 6310.368963103699, 6126.151269885583, 6247.312795592481, 6318.273398559132, 6344.587601805868, 6357.141785858942, 5817.505512031483, 5850.736716847752, 5879.617824841372, 5893.675847828325, 5942.970285148546, 6326.5255105867245, 6328.17733694626, 6347.5239357047485, 6351.142595749607, 6354.0, 5959.791407300723, 6160.10678451622, 6168.383161683796, 6234.719437625317, 6242.43818056368, 6206.286277078104, 6237.4698150656795, 6273.37266273369, 6343.904841427382, 6348.873022539523, 6263.749450022039, 6272.749090036439, 6279.874402512016, 6290.968545157245, 6297.307296197415, 6131.019036954066, 6301.369863013662, 6302.968485157637, 6308.558400911984, 6321.367863213642, 4899.902001960012, 4970.428400733889, 5032.672876263031, 5037.848864534069, 5078.162103252922, 4457.0194557197365, 4467.329900514948, 4475.351074094303, 4475.641948644101, 4475.753833539186, 5727.541796656257, 5742.310922689304, 5752.108423194411, 5759.884802304015, 5764.855878603068, 6110.771402862501, 6194.5044396448175, 6216.533759968021, 6249.187605611332, 6288.874222515524, 5743.8564035899435, 5754.165645981393, 5761.164631128463, 5770.538356931435, 5795.217645617788, 5432.103702889058, 5475.507204351547, 5483.040467918098, 5484.588655850747, 5488.56091512678, 6241.126242326169, 6254.9687251563455, 6281.748730050746, 6291.779787707408, 6369.9681501592195, 6285.905711414424, 6309.432151106423, 6343.841403964938, 6350.7142178603, 6360.332165122595, 6369.49044076482, 6375.713092910737, 6397.0404439332615, 6399.872002560016, 6403.103565500926, 5107.897842043212, 6106.389361063858, 6160.969195153995, 6178.135061091541, 6247.68761561911, 6330.39861213184, 6335.303116657256, 6335.3981371769605, 6336.524760642878, 6342.809715708536, 6171.537134714825, 6181.876362472635, 6202.255729312511, 6217.067439884054, 6231.376862313732, 5786.537077033743, 5939.821805345847, 5990.281166260077, 5999.880002400015, 6080.270367555921, 5921.703914804329, 6000.639961602318, 6002.129691194839, 6007.729652165575, 6041.607295525777, 6134.539909506716, 6144.907826382697, 6157.6613286269685, 6162.383761623802, 6182.969085154546, 5799.710014499174, 5846.093855452412, 5977.820665380045, 5998.8500287491415, 6056.606320589148], "value": 5729.924062951624, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [522.9398619158796, 528.5322489596708, 534.345426852106, 536.334944668611, 556.2879514221793, 29914.700852991464, 37147.62852371475, 40104.59895401045, 41094.58905410945, 41155.794221028926, 41763.74708758735, 42104.36843447351, 42320.153596928045, 42370.57629423705, 42440.0, 8631.95684021579, 9577.90422095779, 9773.413595184276, 9784.36401633895, 9790.461524616143, 8689.652413903435, 11734.471948762326, 11975.880241197563, 11982.880171198263, 12198.0, 11889.821652675217, 11956.70108247292, 11967.820482692767, 11978.520859165623, 12003.699907502294, 12019.879801201963, 12104.273743575326, 12111.454984525717, 12118.939405303005, 12123.515059397658, 11713.53145874164, 11783.64649060521, 11793.410329483577, 11800.64598062051, 11809.645710628694, 39319.803400983095, 39609.801950990346, 39667.8016609918, 39669.206615867806, 39715.0, 3551.6803487685975, 3590.9461358079648, 3950.8419663213435, 4065.9593404065877, 4150.667946564267, 30373.088807335815, 30389.848050759603, 30650.00349088876, 30733.385332293452, 30763.769449222003, 3107.5960125183806, 3240.4815229563155, 3243.254051568129, 3290.276139249361, 3332.350191712617, 460917.0, 471370.0, 472039.0, 473825.57046577317, 475834.62082689366, 56396.87206255852, 56782.43217567771, 56941.14588281179, 56984.0, 57028.42971570314, 34993.12517187091, 35754.821225893706, 58115.0, 58326.0, 58460.70769646124, 16022.919885400499, 16478.2584783685, 16481.423150189687, 16514.917425412797, 16661.333546658, 11326.943365283121, 11555.480003399867, 12477.563285284972, 12570.994320454342, 12727.618171454871, 18137.63724725498, 18503.074846257903, 18674.719879201824, 18684.34604788826, 18708.812911870707, 7686.923130768732, 7720.459567830198, 7775.844483110419, 7783.299503044753, 7866.960665196638, 894.6600291889041, 951.5004622573205, 1033.194108595297, 1036.7667274863247, 1050.894910508943, 29541.0, 29688.109356719335, 29995.950141745372, 30292.939747108747, 30387.0, 1051.4847724614883, 1058.1006144776877, 1068.038765111406, 1072.485207100596, 1175.629676651852, 11793.528258869721, 15476.922615386851, 15569.0, 15616.57147390138, 15871.12708801004, 638825.0, 645372.0, 649072.0, 650115.0, 654493.0, 53316.2002569954, 55829.0, 56404.0, 56416.153757692846, 56547.86904261892, 1036.4144258493927, 1330.6340756291904, 1362.5707902010836, 1371.3897315694421, 1397.3572156807982, 30008.2005914359, 30716.232094197825, 30796.846015769777, 31249.0, 31557.36885262328, 47002.059958801314, 47466.52533474621, 47563.52436475729, 47681.0, 47682.04635907193, 518456.0, 519878.0, 519957.0, 520311.0, 521358.3932080467, 25501.744982550677, 26036.739632603432, 26741.320268952466, 26836.0, 27591.72408275972, 45720.8569785758, 45837.08325833398, 46030.76984615056, 46481.76759116183, 47058.0, 35659.93020209398, 37938.43092353562, 38076.61923380731, 39432.211355773296, 39660.40509392304, 32984.67015329816, 37368.0, 37766.43350349692, 37921.0, 38438.23123537569, 37515.81242093772, 37884.24231515409, 38184.80907595444, 38295.0, 38336.04159896025, 32003.0, 32797.016089517354, 33604.0, 33757.0, 33764.3247135051, 24126.276211713677, 24197.63703544413, 24860.37849053788, 25198.992040318182, 25626.743732562434], "value": 20110.654052724913, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [358.56076306524494, 380.3439067608375, 383.48038407957233, 392.9724919255651, 414.7594395250755, 15390.923045384772, 15556.366581508933, 21052.473688157766, 21396.037178326973, 21511.56976860462, 19954.90022549885, 20592.1763129475, 20863.061162247697, 21124.57750844982, 21456.356309310708, 6032.215811944452, 6087.0, 6131.688888000496, 6198.938010619881, 6277.435030847223, 6058.697065146725, 6173.9073913891325, 6180.351063138373, 6197.380261973789, 6204.937950620504, 4205.201011807762, 6641.701123449432, 6914.274001229874, 6980.092587963558, 7044.964775176142, 6611.933880661228, 6789.592624442549, 6835.7949261522235, 6900.378965893068, 6946.965265173667, 5919.467247947662, 5920.052791553374, 5923.437273459014, 5929.673867937262, 5943.3759455257, 26719.866400667874, 27892.302692432648, 27988.720112798816, 27989.720102799118, 28280.717192828015, 2267.183813827021, 2414.985706003483, 2845.8292502449917, 3134.717875391226, 3228.741700663941, 16934.83065169345, 17264.74102888458, 17298.048607326593, 17424.738628920575, 18439.539011524685, 1914.2438736699064, 1997.2011195521761, 2094.9266775662777, 2131.488442773739, 2190.912363505458, 121970.53397791274, 142946.0, 153017.46982530254, 155159.0, 157972.21013894858, 21452.92053622301, 21524.569508610053, 21540.353789386343, 21727.111399935206, 21888.169570325128, 29126.563101553493, 35574.822125889725, 35590.0, 35645.0, 35663.64336356603, 9216.677416290398, 10786.921307869306, 10991.835122473169, 11124.944375278234, 11146.554137834559, 9101.81796364069, 9120.8631870522, 9124.908750912538, 9347.298952578447, 9349.158575728217, 8821.514816685143, 9150.771230719152, 9689.951550242204, 9783.559739811582, 9874.0, 3741.1769410729594, 3767.981160094182, 3772.7170462215445, 3786.5645450772963, 3875.6318149775725, 706.6396137969673, 781.9139894611588, 803.9316658084128, 807.7963833887466, 954.3653470442079, 21315.934203289773, 21882.562348752937, 21905.561888762455, 21963.0, 21974.230901918356, 426.15195760436507, 898.7303808857447, 926.9907300926905, 933.7712260496086, 985.4235272365746, 5673.801416950387, 5909.881802363929, 6082.908756368658, 6213.720382582795, 6250.000000000047, 536873.0, 608687.0, 609792.0, 612833.0, 614245.0, 82907.17092829115, 123254.76745232612, 133032.66967330396, 141871.87192192127, 146578.0, 453.33359960857484, 459.07496394764576, 460.5417609478523, 460.94238220222474, 462.13811242033364, 11261.831072534082, 11346.716332091764, 11407.0, 11593.304401735923, 11596.826047609462, 56343.46454409479, 56750.43249567451, 57409.0, 58070.0, 58348.66605335909, 83412.0, 83862.58068709617, 83879.0, 83903.16096838954, 84112.57943710242, 7955.4828936118965, 8025.839483210187, 8324.876141721032, 8336.208060234268, 8557.529335886586, 9554.0, 13015.088943773844, 13593.184408935496, 13718.38267277955, 13766.793498097337, 11259.0, 11345.943270283597, 11827.112966527717, 11856.585019524275, 11861.229020113666, 9657.889342725395, 10832.67501974942, 11069.398691307697, 11212.04697600713, 11422.31466112036, 11359.375234361858, 11613.535458581562, 11729.530818767495, 11808.645740627795, 12047.939760301142, 8274.710385136492, 8393.790155246168, 8449.915500844912, 8521.488710677379, 8633.940899620395, 8594.269487093667, 9658.565364558752, 11054.336739795637, 11443.771124577628, 11578.710532236424], "value": 10367.030970154814, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1463.853614638536, 1545.6058705030216, 1784.589544404787, 1904.333483280851, 1972.2899756087807, 8537.871931921018, 9089.727308180756, 9094.226990705798, 9094.408863423881, 9094.90905090949, 7914.327282181007, 8147.26674599286, 8630.180132887366, 8885.955570222157, 8891.199792018717, 2396.2451827674313, 2436.768506991833, 2438.2807071913826, 2438.719547252062, 2445.7798798108165, 2393.748656391071, 2413.239829453725, 2437.5612389769835, 2439.4145405102736, 2473.480569080495, 2183.3449965010514, 2335.2877372401495, 2404.3989002749313, 2426.7209270933804, 2439.487707581401, 2385.3917251101034, 2406.5908795504806, 2412.867292298923, 2415.975840241593, 2417.480241748015, 2273.4089136824377, 2371.8102551795814, 2381.2380038387723, 2400.183937461254, 2401.3156250468705, 7654.73208437702, 8902.065283145306, 9013.684521041732, 9018.233450156744, 9060.77348066297, 5314.388845282765, 5493.890122197574, 5499.890002199973, 5501.367342755594, 5517.2551705520045, 2952.852357382122, 3657.4330978698345, 3728.384816505273, 3741.4761933329355, 3743.3636281832155, 960.5581432541039, 1093.8031154392206, 1108.894655007773, 1112.6439539347411, 1118.2954738514766, 11713.53145874164, 11720.413979301085, 11827.994620457272, 11828.585999489977, 11851.051915846798, 4415.712978656441, 4518.299663552155, 4682.157211701926, 4700.341952126705, 4705.106029854317, 9303.953480232556, 9322.0, 9339.906600933904, 9348.813023739624, 9350.392224505385, 5299.814506492331, 5300.9734951325, 5303.071962406563, 5325.813596524103, 5329.786808527616, 3593.94609080864, 4566.13243483737, 4838.758062096881, 4953.678010929278, 5016.573591244785, 3293.9953314239388, 3363.72178572141, 3374.342003309331, 3688.4098544232793, 3743.0, 1166.9941650291864, 1170.584442522892, 1173.4074292482242, 1183.071289038101, 1184.7630473905253, 819.2422009641058, 919.3059240273551, 933.272047802715, 937.9484128373005, 971.815355082532, 5231.555317797954, 5817.592768506164, 5948.613340132878, 6045.274567051982, 6084.148219249309, 433.86116442738654, 441.44610969387577, 451.24867096284316, 455.6172814835554, 469.3991690635991, 3894.3768996960916, 3964.266610677034, 3988.222296652125, 4092.0588264699286, 4099.098198396348, 9325.160735533835, 10429.9478502607, 10953.068989135852, 10954.56181752721, 10981.3960232188, 10881.12950963921, 10909.963553462409, 10911.83632245517, 10928.398938058324, 10932.234743568033, 7977.0, 8284.627191776384, 8368.288695460955, 8383.245507904317, 8412.74761757136, 6001.7599296027665, 6057.9701450754355, 6114.0828875669, 6114.327423983447, 6232.626042437469, 10207.7448063799, 10266.486675666336, 10312.535935883054, 10314.793704126025, 10342.0, 8322.042965058854, 10087.596496140366, 10300.072993430627, 10304.639337623148, 10503.369797812156, 7002.544834585736, 7227.168875579166, 7236.7467138652, 7623.085229772463, 7718.343940764873, 7981.122076571689, 8067.596620169085, 8263.83472330538, 8553.657853686032, 8567.100454452193, 7421.517601355895, 7465.141508726349, 7551.131619863786, 7646.464747467624, 7681.078270607562, 7274.945132955798, 7461.813454663677, 7461.813454663677, 7479.289467500579, 7634.580098094658, 6184.226971628546, 7001.0, 7165.247648996989, 7166.355028047501, 7175.569465832067, 5023.522765337287, 5098.719570423662, 5117.385913690381, 5172.7155006475, 5233.528982391451, 5085.796568137381, 5126.589872810241, 5137.152369858932, 5219.739013049409, 5327.334083239595], "value": 4509.961804978612, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [300.5626812987104, 303.65686773945447, 313.1919647309941, 316.0833582610428, 317.68549136355017, 5504.64219825711, 5511.58663100267, 5547.972260138704, 5550.944490555098, 5552.472515111068, 4589.793459294331, 5025.949740502603, 5177.767000484978, 5481.616286859921, 5510.154098377048, 3441.500982357563, 3520.489529018291, 4145.958540414587, 4181.770002649868, 4224.817051225662, 3475.77407468515, 4133.925179453334, 4171.624553790159, 4176.937345939813, 4211.5788421157795, 3900.3369427197304, 4097.405876147956, 4137.8758637240935, 4139.56534563871, 4145.818441744103, 3807.562130355017, 4109.856155034559, 4119.096037293716, 4129.380592911058, 4130.2978493656, 3554.608993010767, 3560.2345495718546, 3578.2645416972746, 3580.1407662160896, 3580.8030558319283, 5035.823746168867, 5348.459481746178, 5352.95617354616, 5386.515213630752, 5407.215618845771, 3934.8622798201927, 4687.406281864182, 4696.3894693689945, 4701.905961880778, 4727.952720472786, 5784.49603103192, 5785.768569257225, 5794.031088704209, 5800.506956908668, 5809.302883653989, 3603.7837729736298, 3906.277338692351, 3925.9203718977224, 3931.4470784040145, 3931.9017024574327, 5294.0, 5964.463198312173, 5973.8805223895715, 5997.820065398045, 6093.664848433335, 3685.8389607273625, 3721.0, 3785.280796648628, 3941.724079314478, 3944.6449819516383, 3507.6141624421293, 3978.826246257361, 4018.256622524784, 4025.5169379674626, 4031.0, 3955.5055618047745, 4452.799624016927, 4578.381918440968, 4673.299005149255, 4685.961606701096, 5240.716024574004, 5404.405515393303, 5589.804356847572, 5722.224721010307, 5738.426157384228, 1211.4124649544963, 1231.5098730535972, 1281.6923938254938, 1352.532502235086, 1368.7262547490343, 661.3452681844939, 821.2116368286393, 868.96524139035, 909.272581934463, 920.7606022434081, 5396.271503347077, 5406.540444062299, 5410.97294513525, 5431.402545719968, 5431.83704488866, 4833.854984350475, 5347.572194224453, 5481.862953426116, 5849.502792262705, 5879.617824841372, 4492.707973981681, 4858.392700912386, 5047.596192304681, 5348.411674715778, 5371.898760754114, 2723.509768241696, 2841.8579071046365, 3073.5236038413636, 3094.777562862657, 3111.735502482269, 4676.719396836201, 5287.920681189708, 5297.258383826267, 6065.5450841187085, 6098.963176260047, 4999.975000124976, 5949.727649792683, 5959.824663997696, 5973.522118230531, 5977.282726072899, 5967.761289548456, 5972.879627732093, 5997.910031349533, 6030.547708921848, 6060.121282414113, 5240.502152295526, 5499.835004949858, 5766.711664416846, 5818.50542703858, 5859.92354014146, 5498.642588231752, 5823.70881455934, 5829.650220986754, 5831.94168058314, 5847.076461769116, 4919.852404427873, 5717.656940583578, 6044.425779550936, 6104.4505994460715, 6105.328413874559, 2984.910452686423, 3940.034691500486, 3952.7430717003303, 3960.683145348308, 3989.7207195496035, 5956.255468066492, 5982.850428739142, 5991.610545314541, 6010.459058684739, 6011.579189456696, 4917.0, 5066.758644132211, 5908.822735317947, 5916.053431450947, 5930.970345148419, 4803.5916947058495, 5720.513756330759, 5738.770449181986, 5765.4811067004175, 5777.595568310178, 5290.0, 5385.649932754515, 5681.488666019913, 5710.114932185518, 5751.539876809761, 5221.477852214748, 5647.519960803378, 5735.340435849931, 5833.79581714638, 5835.753235004639, 4543.54564543543, 5174.404943431554, 5441.891162176655, 5640.730835561966, 5642.0], "value": 4158.443022128653, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [712.5051621795097, 718.532953580173, 766.421351879331, 812.3135950122145, 821.7945513621593, 12895.226286422809, 15897.602559936007, 15925.920370398146, 15945.362185512566, 15982.360705571778, 14383.280835958212, 14490.5652830415, 14493.420263189486, 14519.419223231085, 14530.273486325668, 674.4402146218652, 696.1854630082815, 836.356005875474, 839.4249938791947, 864.3646919514183, 849.8300339932007, 858.6393714639835, 858.6951632170583, 859.1408591408591, 859.6905114158898, 912.5847739278612, 913.1051569461931, 914.2274777812771, 914.3553794574842, 917.5458148216642, 710.3358359933479, 884.6417201033591, 901.2024358442793, 907.4691305586207, 914.6250037484599, 676.2257215488288, 677.9728810847561, 678.6165816313817, 680.6494655252549, 681.366329313738, 14635.0, 14682.55952321432, 14695.338709758087, 14699.0, 14701.632459188499, 13608.183508989394, 13841.861581384159, 13934.512292069729, 14094.647633809134, 14360.425582976668, 12563.309018004005, 12614.68463288416, 12652.936735316356, 12775.87224127756, 12792.168509046882, 4382.057857560608, 4461.308497182942, 4482.753448560328, 4496.977515112436, 4665.113628410591, 92619.53690231573, 118816.99999999914, 122525.38737306345, 122542.0, 125132.0, 8568.0, 10992.945035274772, 11015.614453494089, 11054.00513953748, 11299.0, 20802.751834889954, 21155.15379384838, 21156.259530916344, 21160.576788464452, 21241.150353985668, 7674.6930122794465, 7693.307602315706, 7700.422468314899, 7896.842063158705, 7973.960130199312, 4432.889177770581, 4470.150671372428, 4471.642268618502, 4516.0, 4629.444466664022, 3481.0, 3603.6936860367164, 3603.891883243507, 3609.3864043112208, 3846.2692088503095, 726.3462883404978, 726.6148941061246, 728.3153835394712, 733.1678544851632, 736.5323019882413, 577.7313549199642, 599.5233789137559, 656.2190992718649, 682.8395327098128, 697.5117417807555, 9397.389169703947, 9416.482093484925, 9416.764580885532, 9432.151106400457, 9440.197583205505, 632.4466092169351, 650.9902351464732, 672.4586707700348, 682.3014975770815, 685.6605980039913, 4336.9132617348105, 4417.845375411909, 4501.48232953208, 4585.10590434862, 4624.213883639722, 157972.42027579807, 160328.3967160337, 160752.0, 164902.0, 169232.0, 140413.2979335097, 140608.0, 140843.0, 142221.0, 143812.0, 90632.64051039239, 100943.49528252606, 104654.95345046604, 121679.0, 124010.0, 9271.733730750124, 9685.806283874143, 9746.411080463939, 9767.609295628095, 9857.753556161153, 39228.41157382585, 56543.71728141333, 56760.716196418754, 63112.68443657936, 63213.68393158189, 87434.0, 90833.0, 95862.0, 97074.0, 99705.0, 10459.052808255594, 12625.936870315589, 12833.101682882481, 12911.0, 12983.285919274153, 14487.855121449073, 14505.492307769178, 14817.703645926806, 14821.25893705332, 15177.924110379377, 13531.391087400894, 13551.52569660057, 13753.724925501634, 13776.173429594257, 14030.298485075502, 13474.19154850712, 13512.391942362421, 13995.51015714445, 14019.698903795259, 14205.644858878612, 13239.536616218385, 13257.469701211843, 13607.0, 14037.5086871959, 14070.085444446078, 11416.479935321811, 11419.200655954004, 11573.657815340845, 11620.825687614524, 11757.529698812294, 10326.328788628714, 10343.63797267092, 11293.37886416255, 11484.540618375171, 11526.308421494738], "value": 7933.579330636883, "unit": "score"}], "name": "dromaeo_css", "value": 7498.508947605353, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 132.0, 112.0, 114.0, 113.0, 115.0, 112.0, 112.0, 114.0], "value": 115.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 109.0, 132.0, 164.0, 108.0, 134.0, 130.0, 112.0, 195.0, 135.0], "value": 136.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 183.0, 183.0, 181.0, 183.0, 183.0, 182.0, 183.0, 183.0, 184.0], "value": 183.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 73.0, 74.0, 152.0, 58.0, 56.0, 56.0, 66.0, 142.0, 57.0], "value": 81.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 129.0, 71.0, 77.0, 89.0, 142.0, 75.0, 70.0, 117.0], "value": 93.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 83.0, 87.0, 83.0, 86.0, 86.0, 89.0, 85.0, 87.0, 83.0], "value": 85.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 92.0, 93.0, 92.0, 92.0, 93.0, 94.0, 93.0, 93.0, 98.0], "value": 93.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 87.0, 86.0, 129.0, 137.0, 137.0, 86.0, 86.0, 86.0], "value": 100.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 53.0, 56.0, 56.0, 55.0, 53.0, 54.0, 52.0, 53.0, 54.0], "value": 53.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 86.0, 111.0, 107.0, 78.0, 70.0, 70.0, 71.0, 70.0], "value": 79.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 97.0, 105.0, 81.0, 87.0, 83.0, 88.0, 85.0, 86.0], "value": 88.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 140.0, 129.0, 150.0, 148.0, 154.0, 138.0, 138.0, 177.0, 139.0], "value": 145.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 167.0, 171.0, 170.0, 167.0, 169.0, 168.0, 172.0, 167.0, 167.0], "value": 168.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 68.0, 69.0, 67.0, 67.0, 67.0, 67.0, 67.0, 67.0, 67.0], "value": 67.5, "unit": "score"}], "name": "kraken", "value": 1492.3999999999999, "alertThreshold": 2.0}]} 16:54:51 INFO - Return code: 0 16:54:51 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:54:51 INFO - # TBPL SUCCESS # 16:54:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:54:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:54:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:54:51 INFO - 2016-08-31 16:54:51.972 screenresolution[1666:9791] starting screenresolution argv=screenresolution get 16:54:51 INFO - 2016-08-31 16:54:51.975 screenresolution[1666:9791] Display 0: 1600x1200x32@60 16:54:51 INFO - 2016-08-31 16:54:51.981 screenresolution[1667:9795] starting screenresolution argv=screenresolution list 16:54:51 INFO - Available Modes on Display 0 16:54:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:54:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:54:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:54:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:54:52 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:54:52 INFO - Intel Iris: 16:54:52 INFO - Chipset Model: Intel Iris 16:54:52 INFO - Type: GPU 16:54:52 INFO - Bus: Built-In 16:54:52 INFO - VRAM (Dynamic, Max): 1536 MB 16:54:52 INFO - Vendor: Intel (0x8086) 16:54:52 INFO - Device ID: 0x0a2e 16:54:52 INFO - Revision ID: 0x0009 16:54:52 INFO - Displays: 16:54:52 INFO - Display: 16:54:52 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:54:52 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:54:52 INFO - Display Serial Number: 1600x1200 60 16:54:52 INFO - Main Display: Yes 16:54:52 INFO - Mirror: Off 16:54:52 INFO - Online: Yes 16:54:52 INFO - Rotation: Supported 16:54:52 INFO - Return code: 0 16:54:52 INFO - Running post-action listener: _resource_record_post_action 16:54:52 INFO - [mozharness: 2016-08-31 23:54:52.216503Z] Finished run-tests step (success) 16:54:52 INFO - Running post-run listener: _resource_record_post_run 16:54:52 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 37176320; Write bytes: 705942016; Read time: 252; Write time: 2014 16:54:52 INFO - TinderboxPrint: CPU usage
53.6% 16:54:52 INFO - TinderboxPrint: I/O read bytes / time
37,176,320 / 252 16:54:52 INFO - TinderboxPrint: I/O write bytes / time
705,942,016 / 2,014 16:54:52 INFO - TinderboxPrint: CPU idle
2,251.0 (46.4%) 16:54:52 INFO - TinderboxPrint: CPU system
969.8 (20.0%) 16:54:52 INFO - TinderboxPrint: CPU user
1,628.9 (33.6%) 16:54:52 INFO - TinderboxPrint: Swap in / out
457,613,312 / 0 16:54:52 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 403040768; Write bytes: 397952512; Read time: 29750; Write time: 871 16:54:52 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 34448384; Write bytes: 295189504; Read time: 229; Write time: 1106 16:54:52 INFO - Running post-run listener: _upload_blobber_files 16:54:52 INFO - Blob upload gear active. 16:54:52 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:54:52 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 16:54:52 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'] 16:54:52 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 16:54:53 INFO - (blobuploader) - INFO - Open directory for files ... 16:54:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:54:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:54:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:54:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:54:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:54:54 INFO - (blobuploader) - INFO - Done attempting. 16:54:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:54:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:54:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:54:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:54:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:54:55 INFO - (blobuploader) - INFO - Done attempting. 16:54:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:54:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:54:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:54:57 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:54:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:54:57 INFO - (blobuploader) - INFO - Done attempting. 16:54:57 INFO - (blobuploader) - INFO - Iteration through files over. 16:54:57 INFO - Return code: 0 16:54:57 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:54:57 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:54:57 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a5e01ab91c78b6fe49b282ae8d0626a557fae3dc86d44b102effc458b9b50af4d3a67659a84711eb3c50a08bb6a963fb3c3e7be3729d4aa549801fc62e865f36", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/372c3bb38b94e5b7ba3462ead314c4192de65b91a832947a0f713dc561122a5b533ec3ee09e5563e06d04e47e298d5af635cee07f80b8cbaaf8c1a6e00897585", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/52c353782a353f47f38d260d0180290a61538a0c351f4e9c9ba08d904af5b469c098edd052949f22fe0b95ac6a058ba51d5890ae97a6391ab64dccde638d80e5"} 16:54:57 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:54:57 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:54:57 INFO - Contents: 16:54:57 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a5e01ab91c78b6fe49b282ae8d0626a557fae3dc86d44b102effc458b9b50af4d3a67659a84711eb3c50a08bb6a963fb3c3e7be3729d4aa549801fc62e865f36", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/372c3bb38b94e5b7ba3462ead314c4192de65b91a832947a0f713dc561122a5b533ec3ee09e5563e06d04e47e298d5af635cee07f80b8cbaaf8c1a6e00897585", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/52c353782a353f47f38d260d0180290a61538a0c351f4e9c9ba08d904af5b469c098edd052949f22fe0b95ac6a058ba51d5890ae97a6391ab64dccde638d80e5"} 16:54:57 INFO - Running post-run listener: copy_logs_to_upload_dir 16:54:57 INFO - Copying logs to upload dir... 16:54:57 INFO - mkdir: /builds/slave/test/build/upload/logs 16:54:57 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.343926 ========= master_lag: 0.26 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-08-31 16:54:57.997817) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-31 16:54:58.001780) ========= 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.T8GNZIvrwz/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.29uMhurPlE/Listeners TMPDIR=/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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/a5e01ab91c78b6fe49b282ae8d0626a557fae3dc86d44b102effc458b9b50af4d3a67659a84711eb3c50a08bb6a963fb3c3e7be3729d4aa549801fc62e865f36", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/372c3bb38b94e5b7ba3462ead314c4192de65b91a832947a0f713dc561122a5b533ec3ee09e5563e06d04e47e298d5af635cee07f80b8cbaaf8c1a6e00897585", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/52c353782a353f47f38d260d0180290a61538a0c351f4e9c9ba08d904af5b469c098edd052949f22fe0b95ac6a058ba51d5890ae97a6391ab64dccde638d80e5"} build_url:https://queue.taskcluster.net/v1/task/StoTJFtnTYO0ihRskczJMw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011282 build_url: 'https://queue.taskcluster.net/v1/task/StoTJFtnTYO0ihRskczJMw/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/a5e01ab91c78b6fe49b282ae8d0626a557fae3dc86d44b102effc458b9b50af4d3a67659a84711eb3c50a08bb6a963fb3c3e7be3729d4aa549801fc62e865f36", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/372c3bb38b94e5b7ba3462ead314c4192de65b91a832947a0f713dc561122a5b533ec3ee09e5563e06d04e47e298d5af635cee07f80b8cbaaf8c1a6e00897585", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/52c353782a353f47f38d260d0180290a61538a0c351f4e9c9ba08d904af5b469c098edd052949f22fe0b95ac6a058ba51d5890ae97a6391ab64dccde638d80e5"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-31 16:54:58.035270) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 16:54:58.035602) ========= 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.T8GNZIvrwz/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.29uMhurPlE/Listeners TMPDIR=/var/folders/hs/v2yc1m995s13wd7f6m2b870c00000w/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.005096 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 16:54:58.083326) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-31 16:54:58.083645) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-31 16:54:58.083953) ========= ========= Total master_lag: 0.39 =========