builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0107 starttime: 1464042436.64 results: success (0) buildid: 20160523133154 builduid: 6807398504784a7580e041da1215d670 revision: a132c5d3fcac17034d38b3191a611440943c5425 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-23 15:27:16.639698) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-23 15:27:16.640133) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-23 15:27:16.640424) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-23 15:27:16.853915) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 15:27:16.854179) ========= 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.bnZue09OIo/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.bc8xLnhXkc/Listeners TMPDIR=/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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-05-23 15:27:16-- 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% 136M=0s 2016-05-23 15:27:17 (136 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.462528 ========= master_lag: 0.10 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 15:27:17.421107) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 15:27:17.421412) ========= 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.bnZue09OIo/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.bc8xLnhXkc/Listeners TMPDIR=/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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.020484 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 15:27:17.472054) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-23 15:27:17.474407) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev a132c5d3fcac17034d38b3191a611440943c5425 --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 a132c5d3fcac17034d38b3191a611440943c5425 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.bnZue09OIo/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.bc8xLnhXkc/Listeners TMPDIR=/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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-05-23 15:27:17,549 truncating revision to first 12 chars 2016-05-23 15:27:17,549 Setting DEBUG logging. 2016-05-23 15:27:17,549 attempt 1/10 2016-05-23 15:27:17,550 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/a132c5d3fcac?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-23 15:27:19,710 unpacking tar archive at: fx-team-a132c5d3fcac/testing/mozharness/ program finished with exit code 0 elapsedTime=2.409449 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-23 15:27:19.906330) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-23 15:27:19.907018) ========= script_repo_revision: a132c5d3fcac17034d38b3191a611440943c5425 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-23 15:27:19.907411) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-23 15:27:19.907681) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-23 15:27:19.922722) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-05-23 15:27:19.923296) ========= /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.bnZue09OIo/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.bc8xLnhXkc/Listeners TMPDIR=/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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 15:27:20 INFO - MultiFileLogger online at 20160523 15:27:20 in /builds/slave/test 15:27:20 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 15:27:20 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:27:20 INFO - {'append_to_log': False, 15:27:20 INFO - 'base_work_dir': '/builds/slave/test', 15:27:20 INFO - 'blob_upload_branch': 'Fx-Team', 15:27:20 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:27:20 INFO - 'branch': 'Fx-Team', 15:27:20 INFO - 'buildbot_json_path': 'buildprops.json', 15:27:20 INFO - 'config_files': ('talos/mac_config.py',), 15:27:20 INFO - 'default_actions': ('clobber', 15:27:20 INFO - 'read-buildbot-config', 15:27:20 INFO - 'download-and-extract', 15:27:20 INFO - 'populate-webroot', 15:27:20 INFO - 'create-virtualenv', 15:27:20 INFO - 'install', 15:27:20 INFO - 'run-tests'), 15:27:20 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:27:20 INFO - 'download_minidump_stackwalk': True, 15:27:20 INFO - 'download_symbols': 'ondemand', 15:27:20 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:27:20 INFO - 'tooltool.py': '/tools/tooltool.py', 15:27:20 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:27:20 INFO - '/tools/misc-python/virtualenv.py')}, 15:27:20 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:27:20 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:27:20 INFO - 'installer_path': 'installer.exe', 15:27:20 INFO - 'log_level': 'info', 15:27:20 INFO - 'log_name': 'talos', 15:27:20 INFO - 'log_to_console': True, 15:27:20 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:27:20 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:27:20 INFO - 'opt_config_files': (), 15:27:20 INFO - 'pip_index': False, 15:27:20 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:27:20 INFO - 'cmd': ('bash', 15:27:20 INFO - '-c', 15:27:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:27:20 INFO - 'enabled': True, 15:27:20 INFO - 'halt_on_failure': False, 15:27:20 INFO - 'name': 'check_screen_resolution'},), 15:27:20 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:27:20 INFO - 'cmd': ('bash', 15:27:20 INFO - '-c', 15:27:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:27:20 INFO - 'enabled': True, 15:27:20 INFO - 'halt_on_failure': False, 15:27:20 INFO - 'name': 'check_screen_resolution'},), 15:27:20 INFO - 'run_cmd_checks_enabled': True, 15:27:20 INFO - 'sps_profile': False, 15:27:20 INFO - 'sps_profile_interval': 0, 15:27:20 INFO - 'suite': 'dromaeojs', 15:27:20 INFO - 'system_bits': '32', 15:27:20 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:27:20 INFO - 'title': 't-yosemite-r7-0107', 15:27:20 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:27:20 INFO - 'use_talos_json': True, 15:27:20 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:27:20 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:27:20 INFO - 'work_dir': 'build'} 15:27:20 INFO - ##### 15:27:20 INFO - ##### Running clobber step. 15:27:20 INFO - ##### 15:27:20 INFO - Running pre-action listener: _resource_record_pre_action 15:27:20 INFO - Running main action method: clobber 15:27:20 INFO - rmtree: /builds/slave/test/build 15:27:20 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:27:20 INFO - Running post-action listener: _resource_record_post_action 15:27:20 INFO - ##### 15:27:20 INFO - ##### Finished clobber step (success) 15:27:20 INFO - ##### 15:27:20 INFO - ##### 15:27:20 INFO - ##### Running read-buildbot-config step. 15:27:20 INFO - ##### 15:27:20 INFO - Running pre-action listener: _resource_record_pre_action 15:27:20 INFO - Running main action method: read_buildbot_config 15:27:20 INFO - Using buildbot properties: 15:27:20 INFO - { 15:27:20 INFO - "project": "", 15:27:20 INFO - "product": "firefox", 15:27:20 INFO - "script_repo_revision": "production", 15:27:20 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 15:27:20 INFO - "repository": "", 15:27:20 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 15:27:20 INFO - "buildid": "20160523133154", 15:27:20 INFO - "pgo_build": "False", 15:27:20 INFO - "basedir": "/builds/slave/test", 15:27:20 INFO - "buildnumber": 786, 15:27:20 INFO - "slavename": "t-yosemite-r7-0107", 15:27:20 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 15:27:20 INFO - "platform": "yosemite_r7", 15:27:20 INFO - "scheduler": "tests-fx-team-macosx64-talos", 15:27:20 INFO - "branch": "fx-team", 15:27:20 INFO - "repo_path": "integration/fx-team", 15:27:20 INFO - "revision": "a132c5d3fcac17034d38b3191a611440943c5425", 15:27:20 INFO - "stage_platform": "macosx64", 15:27:20 INFO - "builduid": "6807398504784a7580e041da1215d670", 15:27:20 INFO - "slavebuilddir": "test" 15:27:20 INFO - } 15:27:20 INFO - Found installer url https://queue.taskcluster.net/v1/task/WZ8Qli3uQeyyJoDQEdcbVg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 15:27:20 INFO - Running post-action listener: _resource_record_post_action 15:27:20 INFO - ##### 15:27:20 INFO - ##### Finished read-buildbot-config step (success) 15:27:20 INFO - ##### 15:27:20 INFO - ##### 15:27:20 INFO - ##### Running download-and-extract step. 15:27:20 INFO - ##### 15:27:20 INFO - Running pre-action listener: _resource_record_pre_action 15:27:20 INFO - Running main action method: download_and_extract 15:27:20 INFO - mkdir: /builds/slave/test/build/tests 15:27:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:27:20 INFO - https://queue.taskcluster.net/v1/task/WZ8Qli3uQeyyJoDQEdcbVg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 15:27:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WZ8Qli3uQeyyJoDQEdcbVg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 15:27:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WZ8Qli3uQeyyJoDQEdcbVg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 15:27:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WZ8Qli3uQeyyJoDQEdcbVg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 15:27:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WZ8Qli3uQeyyJoDQEdcbVg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 15:27:23 INFO - Downloaded 1199 bytes. 15:27:23 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 15:27:23 INFO - Using the following test package requirements: 15:27:23 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 15:27:23 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:27:23 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 15:27:23 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:27:23 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 15:27:23 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:27:23 INFO - u'jsshell-mac.zip'], 15:27:23 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:27:23 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 15:27:23 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 15:27:23 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:27:23 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 15:27:23 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:27:23 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 15:27:23 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:27:23 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 15:27:23 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:27:23 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 15:27:23 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 15:27: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:27:23 INFO - https://queue.taskcluster.net/v1/task/WZ8Qli3uQeyyJoDQEdcbVg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 15:27:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WZ8Qli3uQeyyJoDQEdcbVg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 15:27:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WZ8Qli3uQeyyJoDQEdcbVg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 15:27:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WZ8Qli3uQeyyJoDQEdcbVg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 15:27:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WZ8Qli3uQeyyJoDQEdcbVg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:27:25 INFO - Downloaded 18630559 bytes. 15:27:25 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:27:25 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 15:27:28 INFO - Return code: 0 15:27:28 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 15:27: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:27:28 INFO - https://queue.taskcluster.net/v1/task/WZ8Qli3uQeyyJoDQEdcbVg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 15:27:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WZ8Qli3uQeyyJoDQEdcbVg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 15:27:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WZ8Qli3uQeyyJoDQEdcbVg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 15:27:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WZ8Qli3uQeyyJoDQEdcbVg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 15:27:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WZ8Qli3uQeyyJoDQEdcbVg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:27:30 INFO - Downloaded 18630559 bytes. 15:27:30 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:27:30 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 15:27:34 INFO - Return code: 0 15:27:34 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:27:34 INFO - https://queue.taskcluster.net/v1/task/WZ8Qli3uQeyyJoDQEdcbVg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 15:27:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WZ8Qli3uQeyyJoDQEdcbVg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 15:27:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WZ8Qli3uQeyyJoDQEdcbVg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 15:27:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WZ8Qli3uQeyyJoDQEdcbVg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 15:27:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WZ8Qli3uQeyyJoDQEdcbVg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 15:27:35 INFO - Downloaded 10840359 bytes. 15:27:35 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:27:35 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 15:27:35 INFO - Return code: 0 15:27:35 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:27:35 INFO - https://queue.taskcluster.net/v1/task/WZ8Qli3uQeyyJoDQEdcbVg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 15:27:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WZ8Qli3uQeyyJoDQEdcbVg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 15:27:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WZ8Qli3uQeyyJoDQEdcbVg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 15:27:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WZ8Qli3uQeyyJoDQEdcbVg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 15:27:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WZ8Qli3uQeyyJoDQEdcbVg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 15:27:38 INFO - Downloaded 110173312 bytes. 15:27:38 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WZ8Qli3uQeyyJoDQEdcbVg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 15:27:38 INFO - mkdir: /builds/slave/test/properties 15:27:38 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:27:38 INFO - Writing to file /builds/slave/test/properties/build_url 15:27:38 INFO - Contents: 15:27:38 INFO - build_url:https://queue.taskcluster.net/v1/task/WZ8Qli3uQeyyJoDQEdcbVg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 15:27:39 INFO - Running post-action listener: _resource_record_post_action 15:27:39 INFO - Running post-action listener: set_extra_try_arguments 15:27:39 INFO - ##### 15:27:39 INFO - ##### Finished download-and-extract step (success) 15:27:39 INFO - ##### 15:27:39 INFO - ##### 15:27:39 INFO - ##### Running populate-webroot step. 15:27:39 INFO - ##### 15:27:39 INFO - Running pre-action listener: _resource_record_pre_action 15:27:39 INFO - Running main action method: populate_webroot 15:27:39 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:27:39 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:27:39 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:27:39 INFO - '--tppagecycles', 15:27:39 INFO - '7'], 15:27:39 INFO - 'tests': ['tsvgm']}}, 15:27:39 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:27:39 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:27:39 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:27:39 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:27:39 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:27:39 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:27:39 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:27:39 INFO - 'talos_options': ['--disable-e10s'], 15:27:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:27:39 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:27:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:27:39 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:27:39 INFO - 'talos_options': ['--disable-e10s'], 15:27:39 INFO - 'tests': ['damp', 'tps']}, 15:27:39 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:27:39 INFO - 'tests': ['damp', 'tps']}, 15:27:39 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:27:39 INFO - 'tests': ['dromaeo_dom']}, 15:27:39 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:27:39 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:27:39 INFO - 'tests': ['a11yr', 15:27:39 INFO - 'ts_paint', 15:27:39 INFO - 'tpaint', 15:27:39 INFO - 'sessionrestore', 15:27:39 INFO - 'sessionrestore_no_auto_restore', 15:27:39 INFO - 'tabpaint']}, 15:27:39 INFO - 'other-e10s': {'tests': ['a11yr', 15:27:39 INFO - 'ts_paint', 15:27:39 INFO - 'tpaint', 15:27:39 INFO - 'sessionrestore', 15:27:39 INFO - 'sessionrestore_no_auto_restore', 15:27:39 INFO - 'tabpaint']}, 15:27:39 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:27:39 INFO - 'ts_paint', 15:27:39 INFO - 'tpaint', 15:27:39 INFO - 'sessionrestore', 15:27:39 INFO - 'sessionrestore_no_auto_restore', 15:27:39 INFO - 'tabpaint']}, 15:27:39 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:27:39 INFO - 'ts_paint', 15:27:39 INFO - 'tpaint', 15:27:39 INFO - 'sessionrestore', 15:27:39 INFO - 'sessionrestore_no_auto_restore', 15:27:39 INFO - 'tabpaint']}, 15:27:39 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:27:39 INFO - 'tests': ['a11yr', 15:27:39 INFO - 'ts_paint', 15:27:39 INFO - 'tpaint', 15:27:39 INFO - 'sessionrestore', 15:27:39 INFO - 'sessionrestore_no_auto_restore', 15:27:39 INFO - 'tabpaint']}, 15:27:39 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:27:39 INFO - 'tests': ['a11yr', 15:27:39 INFO - 'ts_paint', 15:27:39 INFO - 'tpaint', 15:27:39 INFO - 'sessionrestore', 15:27:39 INFO - 'sessionrestore_no_auto_restore', 15:27:39 INFO - 'tabpaint']}, 15:27:39 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:27:39 INFO - 'tests': ['tsvgx', 15:27:39 INFO - 'tsvgr_opacity', 15:27:39 INFO - 'tart', 15:27:39 INFO - 'tscrollx', 15:27:39 INFO - 'cart']}, 15:27:39 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:27:39 INFO - 'tsvgr_opacity', 15:27:39 INFO - 'tart', 15:27:39 INFO - 'tscrollx', 15:27:39 INFO - 'cart']}, 15:27:39 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:27:39 INFO - 'talos_options': ['--disable-e10s'], 15:27:39 INFO - 'tests': ['tp5o']}, 15:27:39 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:27:39 INFO - 'tests': ['tp5o']}, 15:27:39 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:27:39 INFO - 'talos_options': ['--disable-e10s', 15:27:39 INFO - '--xperf_path', 15:27:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:27:39 INFO - 'tests': ['tp5n']}, 15:27:39 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:27:39 INFO - 'talos_options': ['--xperf_path', 15:27:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:27:39 INFO - 'tests': ['tp5n']}}, 15:27:39 INFO - 'talos.zip': {'path': '', 15:27:39 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:27:39 INFO - Running post-action listener: _resource_record_post_action 15:27:39 INFO - ##### 15:27:39 INFO - ##### Finished populate-webroot step (success) 15:27:39 INFO - ##### 15:27:39 INFO - ##### 15:27:39 INFO - ##### Running create-virtualenv step. 15:27:39 INFO - ##### 15:27:39 INFO - Running pre-action listener: _resource_record_pre_action 15:27:39 INFO - Running main action method: create_virtualenv 15:27:39 INFO - Creating virtualenv /builds/slave/test/build/venv 15:27:39 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 15:27:39 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:27:40 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:27:40 INFO - Using real prefix '/tools/python27' 15:27:40 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:27:40 INFO - Installing distribute.............................................................................................................................................................................................done. 15:27:43 INFO - Installing pip.................done. 15:27:43 INFO - Return code: 0 15:27:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:27: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:27:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:27:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:27: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:27:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:27:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:27:43 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 0x1026a6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026ac570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faee8da50f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10254ad30>, '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 0x10236edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102541b20>, '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.bnZue09OIo/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.bc8xLnhXkc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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 15:27:43 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 15:27:43 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 15:27:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bnZue09OIo/Render', 15:27:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:27:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:27:43 INFO - 'HOME': '/Users/cltbld', 15:27:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:27:43 INFO - 'LOGNAME': 'cltbld', 15:27:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:27:43 INFO - 'MOZ_NO_REMOTE': '1', 15:27:43 INFO - 'NO_EM_RESTART': '1', 15:27:43 INFO - 'PAGER': '/bin/cat', 15:27:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:27:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:27:43 INFO - 'PWD': '/builds/slave/test', 15:27:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:27:43 INFO - 'SHELL': '/bin/bash', 15:27:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bc8xLnhXkc/Listeners', 15:27:43 INFO - 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/', 15:27:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:27:43 INFO - 'USER': 'cltbld', 15:27:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:27:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:27:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:27:43 INFO - 'XPC_FLAGS': '0x0', 15:27:43 INFO - 'XPC_SERVICE_NAME': '0', 15:27:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:27:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:27:43 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 15:27:43 INFO - Cleaning up... 15:27:43 INFO - Return code: 0 15:27:43 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:27: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:27:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:27:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:27: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:27:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:27:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:27:43 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 0x1026a6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026ac570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faee8da50f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10254ad30>, '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 0x10236edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102541b20>, '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.bnZue09OIo/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.bc8xLnhXkc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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 15:27:43 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 15:27:43 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 15:27:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bnZue09OIo/Render', 15:27:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:27:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:27:43 INFO - 'HOME': '/Users/cltbld', 15:27:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:27:43 INFO - 'LOGNAME': 'cltbld', 15:27:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:27:43 INFO - 'MOZ_NO_REMOTE': '1', 15:27:43 INFO - 'NO_EM_RESTART': '1', 15:27:43 INFO - 'PAGER': '/bin/cat', 15:27:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:27:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:27:43 INFO - 'PWD': '/builds/slave/test', 15:27:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:27:43 INFO - 'SHELL': '/bin/bash', 15:27:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bc8xLnhXkc/Listeners', 15:27:43 INFO - 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/', 15:27:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:27:43 INFO - 'USER': 'cltbld', 15:27:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:27:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:27:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:27:43 INFO - 'XPC_FLAGS': '0x0', 15:27:43 INFO - 'XPC_SERVICE_NAME': '0', 15:27:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:27:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:27:44 INFO - Downloading/unpacking psutil>=3.1.1 15:27:44 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 15:27:44 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 15:27:44 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 15:27:44 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 15:27:44 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 15:27:44 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 15:27:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:27:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:27:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:27:47 INFO - Installing collected packages: psutil 15:27:47 INFO - Running setup.py install for psutil 15:27:47 INFO - building 'psutil._psutil_osx' extension 15:27:47 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 15:27:47 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 15:27:47 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 15:27:47 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 15:27:47 INFO - building 'psutil._psutil_posix' extension 15:27:47 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 15:27:47 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:27:47 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:27:47 INFO - ^ 15:27:47 INFO - 1 warning generated. 15:27:47 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 15:27:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:27:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:27:47 INFO - Successfully installed psutil 15:27:47 INFO - Cleaning up... 15:27:48 INFO - Return code: 0 15:27:48 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:27:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:27:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:27:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:27:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:27:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:27:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:27:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1026a6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026ac570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faee8da50f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10254ad30>, '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 0x10236edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102541b20>, '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.bnZue09OIo/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.bc8xLnhXkc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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 15:27:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:27:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:27:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bnZue09OIo/Render', 15:27:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:27:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:27:48 INFO - 'HOME': '/Users/cltbld', 15:27:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:27:48 INFO - 'LOGNAME': 'cltbld', 15:27:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:27:48 INFO - 'MOZ_NO_REMOTE': '1', 15:27:48 INFO - 'NO_EM_RESTART': '1', 15:27:48 INFO - 'PAGER': '/bin/cat', 15:27:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:27:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:27:48 INFO - 'PWD': '/builds/slave/test', 15:27:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:27:48 INFO - 'SHELL': '/bin/bash', 15:27:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bc8xLnhXkc/Listeners', 15:27:48 INFO - 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/', 15:27:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:27:48 INFO - 'USER': 'cltbld', 15:27:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:27:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:27:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:27:48 INFO - 'XPC_FLAGS': '0x0', 15:27:48 INFO - 'XPC_SERVICE_NAME': '0', 15:27:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:27:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:27:48 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:27:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:27:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:27:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:27:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:27:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:27:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:27:50 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:27:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:27:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:27:50 INFO - Installing collected packages: mozsystemmonitor 15:27:50 INFO - Running setup.py install for mozsystemmonitor 15:27:50 INFO - Successfully installed mozsystemmonitor 15:27:50 INFO - Cleaning up... 15:27:50 INFO - Return code: 0 15:27:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:27:50 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:27:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:27:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:27:50 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:27:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:27:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:27:50 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 0x1026a6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026ac570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faee8da50f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10254ad30>, '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 0x10236edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102541b20>, '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.bnZue09OIo/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.bc8xLnhXkc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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 15:27:50 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 15:27:50 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 15:27:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bnZue09OIo/Render', 15:27:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:27:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:27:50 INFO - 'HOME': '/Users/cltbld', 15:27:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:27:50 INFO - 'LOGNAME': 'cltbld', 15:27:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:27:50 INFO - 'MOZ_NO_REMOTE': '1', 15:27:50 INFO - 'NO_EM_RESTART': '1', 15:27:50 INFO - 'PAGER': '/bin/cat', 15:27:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:27:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:27:51 INFO - 'PWD': '/builds/slave/test', 15:27:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:27:51 INFO - 'SHELL': '/bin/bash', 15:27:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bc8xLnhXkc/Listeners', 15:27:51 INFO - 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/', 15:27:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:27:51 INFO - 'USER': 'cltbld', 15:27:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:27:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:27:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:27:51 INFO - 'XPC_FLAGS': '0x0', 15:27:51 INFO - 'XPC_SERVICE_NAME': '0', 15:27:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:27:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:27:51 INFO - Downloading/unpacking blobuploader==1.2.4 15:27:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:27:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:27:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:27:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:27:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:27:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:27:53 INFO - Downloading blobuploader-1.2.4.tar.gz 15:27:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:27:53 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:27:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:27:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:27:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:27:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:27:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:27:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:27:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:27:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:27:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:27:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:27:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:27:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:27:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:27:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:27:54 INFO - Downloading docopt-0.6.1.tar.gz 15:27:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:27:55 INFO - Installing collected packages: blobuploader, requests, docopt 15:27:55 INFO - Running setup.py install for blobuploader 15:27:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:27:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:27:55 INFO - Running setup.py install for requests 15:27:55 INFO - Running setup.py install for docopt 15:27:55 INFO - Successfully installed blobuploader requests docopt 15:27:55 INFO - Cleaning up... 15:27:55 INFO - Return code: 0 15:27:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:27:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:27:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:27:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:27:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:27:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:27:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:27:55 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 0x1026a6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026ac570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faee8da50f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10254ad30>, '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 0x10236edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102541b20>, '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.bnZue09OIo/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.bc8xLnhXkc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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 15:27:55 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 15:27:55 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 15:27:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bnZue09OIo/Render', 15:27:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:27:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:27:55 INFO - 'HOME': '/Users/cltbld', 15:27:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:27:55 INFO - 'LOGNAME': 'cltbld', 15:27:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:27:55 INFO - 'MOZ_NO_REMOTE': '1', 15:27:55 INFO - 'NO_EM_RESTART': '1', 15:27:55 INFO - 'PAGER': '/bin/cat', 15:27:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:27:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:27:55 INFO - 'PWD': '/builds/slave/test', 15:27:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:27:55 INFO - 'SHELL': '/bin/bash', 15:27:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bc8xLnhXkc/Listeners', 15:27:55 INFO - 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/', 15:27:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:27:55 INFO - 'USER': 'cltbld', 15:27:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:27:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:27:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:27:55 INFO - 'XPC_FLAGS': '0x0', 15:27:55 INFO - 'XPC_SERVICE_NAME': '0', 15:27:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:27:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:27:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:27:56 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-LB7J1M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:27:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:27:56 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-FRpjKt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:27:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:27:56 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-cYsTiq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:27:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:27:56 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-rKBDJG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:27:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:27:56 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-W8jj30-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:27:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:27:56 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-3T6hDq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:27:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:27:56 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-rmBO1C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:27:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:27:56 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-EEQB3O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:27:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:27:57 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-BAFkBi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:27:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:27:57 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-BpKtpK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:27:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:27:57 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-u9_nHP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:27:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:27:57 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-OolTPO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:27:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:27:57 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-YVYPpk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:27:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:27:57 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-Yb6OQr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:27:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:27:57 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-XylDrT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:27:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:27:57 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-3xvJLL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:27:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:27:57 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-hEWhKc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:27:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:27:58 INFO - Running setup.py install for manifestparser 15:27:58 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:27:58 INFO - Running setup.py install for mozcrash 15:27:58 INFO - Running setup.py install for mozdebug 15:27:58 INFO - Running setup.py install for mozdevice 15:27:58 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:27:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:27:58 INFO - Running setup.py install for mozfile 15:27:58 INFO - Running setup.py install for mozhttpd 15:27:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:27:58 INFO - Running setup.py install for mozinfo 15:27:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:27:59 INFO - Running setup.py install for mozInstall 15:27:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:27:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:27:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:27:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:27:59 INFO - Running setup.py install for mozleak 15:27:59 INFO - Running setup.py install for mozlog 15:27:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:27:59 INFO - Running setup.py install for moznetwork 15:27:59 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:27:59 INFO - Running setup.py install for mozprocess 15:27:59 INFO - Running setup.py install for mozprofile 15:27:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:27:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:27:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:27:59 INFO - Running setup.py install for mozrunner 15:28:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:28:00 INFO - Running setup.py install for mozscreenshot 15:28:00 INFO - Running setup.py install for moztest 15:28:00 INFO - Running setup.py install for mozversion 15:28:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:28:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:28:00 INFO - Cleaning up... 15:28:00 INFO - Return code: 0 15:28:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:28: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:28:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:28:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:28: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:28:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:28:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:28:00 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 0x1026a6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026ac570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faee8da50f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10254ad30>, '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 0x10236edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102541b20>, '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.bnZue09OIo/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.bc8xLnhXkc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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 15:28:00 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 15:28:00 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 15:28:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bnZue09OIo/Render', 15:28:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:28:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:28:00 INFO - 'HOME': '/Users/cltbld', 15:28:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:28:00 INFO - 'LOGNAME': 'cltbld', 15:28:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:28:00 INFO - 'MOZ_NO_REMOTE': '1', 15:28:00 INFO - 'NO_EM_RESTART': '1', 15:28:00 INFO - 'PAGER': '/bin/cat', 15:28:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:28:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:28:00 INFO - 'PWD': '/builds/slave/test', 15:28:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:28:00 INFO - 'SHELL': '/bin/bash', 15:28:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bc8xLnhXkc/Listeners', 15:28:00 INFO - 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/', 15:28:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:28:00 INFO - 'USER': 'cltbld', 15:28:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:28:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:28:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:28:00 INFO - 'XPC_FLAGS': '0x0', 15:28:00 INFO - 'XPC_SERVICE_NAME': '0', 15:28:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:28:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:28:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:28:00 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-gNXGjb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:28:00 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)) 15:28:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:28:00 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-HWyeM2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:28:01 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)) 15:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:28:01 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-2ZLbVE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:28:01 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)) 15:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:28:01 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-G5svYD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:28:01 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)) 15:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:28:01 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-aXdyPK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:28:01 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)) 15:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:28:01 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-k_vwf3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:28:01 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)) 15:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:28:01 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-TlKgT6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:28:01 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)) 15:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:28:01 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-t9eEYk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:28:01 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)) 15:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:28:01 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-6StMSL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:28:01 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)) 15:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:28:01 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-fXeYHw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:28:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 15:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:28:01 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-kWXmav-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:28:02 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)) 15:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:28:02 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-vGfNvt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:28:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 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)) 15:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:28:02 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-wSVA_R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:28:02 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)) 15:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:28:02 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-nykdY5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:28:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 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)) 15:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:28:02 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-6YPiDw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:28:02 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)) 15:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:28:02 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-NkZxH7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:28:02 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)) 15:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:28:02 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-jjFoY0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:28:02 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)) 15:28:02 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)) 15:28:02 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)) 15:28:02 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)) 15:28:02 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)) 15:28:02 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)) 15:28:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:28:02 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 15:28:02 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 15:28:02 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 15:28:02 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 15:28:02 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 15:28:02 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 15:28:05 INFO - Downloading blessings-1.6.tar.gz 15:28:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:28:05 INFO - Installing collected packages: blessings 15:28:05 INFO - Running setup.py install for blessings 15:28:05 INFO - Successfully installed blessings 15:28:05 INFO - Cleaning up... 15:28:05 INFO - Return code: 0 15:28:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:28:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:28:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:28:05 INFO - Reading from file tmpfile_stdout 15:28:05 INFO - Current package versions: 15:28:05 INFO - blessings == 1.6 15:28:05 INFO - blobuploader == 1.2.4 15:28:05 INFO - docopt == 0.6.1 15:28:05 INFO - manifestparser == 1.1 15:28:05 INFO - mozInstall == 1.12 15:28:05 INFO - mozcrash == 0.17 15:28:05 INFO - mozdebug == 0.1 15:28:05 INFO - mozdevice == 0.48 15:28:05 INFO - mozfile == 1.2 15:28:05 INFO - mozhttpd == 0.7 15:28:05 INFO - mozinfo == 0.9 15:28:05 INFO - mozleak == 0.1 15:28:05 INFO - mozlog == 3.2 15:28:05 INFO - moznetwork == 0.27 15:28:05 INFO - mozprocess == 0.22 15:28:05 INFO - mozprofile == 0.28 15:28:05 INFO - mozrunner == 6.11 15:28:05 INFO - mozscreenshot == 0.1 15:28:05 INFO - mozsystemmonitor == 0.3 15:28:05 INFO - moztest == 0.7 15:28:05 INFO - mozversion == 1.4 15:28:05 INFO - psutil == 3.1.1 15:28:05 INFO - requests == 1.2.3 15:28:05 INFO - wsgiref == 0.1.2 15:28:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:28:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:28:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:28:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:28:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:28:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:28:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:28:05 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 0x1026a6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026ac570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faee8da50f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10254ad30>, '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 0x10236edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102541b20>, '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.bnZue09OIo/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.bc8xLnhXkc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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 15:28:05 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 15:28:05 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 15:28:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bnZue09OIo/Render', 15:28:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:28:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:28:05 INFO - 'HOME': '/Users/cltbld', 15:28:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:28:05 INFO - 'LOGNAME': 'cltbld', 15:28:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:28:05 INFO - 'MOZ_NO_REMOTE': '1', 15:28:05 INFO - 'NO_EM_RESTART': '1', 15:28:05 INFO - 'PAGER': '/bin/cat', 15:28:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:28:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:28:05 INFO - 'PWD': '/builds/slave/test', 15:28:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:28:05 INFO - 'SHELL': '/bin/bash', 15:28:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bc8xLnhXkc/Listeners', 15:28:05 INFO - 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/', 15:28:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:28:05 INFO - 'USER': 'cltbld', 15:28:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:28:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:28:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:28:05 INFO - 'XPC_FLAGS': '0x0', 15:28:05 INFO - 'XPC_SERVICE_NAME': '0', 15:28:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:28:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:28:06 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)) 15:28:06 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)) 15:28:06 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)) 15:28:06 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)) 15:28:06 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)) 15:28:06 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)) 15:28:06 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)) 15:28:06 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)) 15:28:06 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)) 15:28:06 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)) 15:28:06 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)) 15:28:06 INFO - Cleaning up... 15:28:06 INFO - Return code: 0 15:28:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:28:06 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:28:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:28:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:28:06 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:28:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:28:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:28:06 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 0x1026a6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026ac570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faee8da50f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10254ad30>, '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 0x10236edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102541b20>, '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.bnZue09OIo/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.bc8xLnhXkc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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 15:28:06 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 15:28:06 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 15:28:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bnZue09OIo/Render', 15:28:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:28:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:28:06 INFO - 'HOME': '/Users/cltbld', 15:28:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:28:06 INFO - 'LOGNAME': 'cltbld', 15:28:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:28:06 INFO - 'MOZ_NO_REMOTE': '1', 15:28:06 INFO - 'NO_EM_RESTART': '1', 15:28:06 INFO - 'PAGER': '/bin/cat', 15:28:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:28:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:28:06 INFO - 'PWD': '/builds/slave/test', 15:28:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:28:06 INFO - 'SHELL': '/bin/bash', 15:28:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bc8xLnhXkc/Listeners', 15:28:06 INFO - 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/', 15:28:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:28:06 INFO - 'USER': 'cltbld', 15:28:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:28:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:28:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:28:06 INFO - 'XPC_FLAGS': '0x0', 15:28:06 INFO - 'XPC_SERVICE_NAME': '0', 15:28:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:28:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:28:06 INFO - Downloading/unpacking jsonschema 15:28:06 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 15:28:06 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 15:28:06 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 15:28:06 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 15:28:06 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 15:28:06 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 15:28:08 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:28:08 INFO - Downloading/unpacking functools32 (from jsonschema) 15:28:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:28:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:28:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:28:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:28:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:28:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:28:09 INFO - Downloading functools32-3.2.3-2.tar.gz 15:28:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:28:09 INFO - warning: no files found matching '*.txt' 15:28:09 INFO - no previously-included directories found matching 'build' 15:28:09 INFO - no previously-included directories found matching 'dist' 15:28:09 INFO - no previously-included directories found matching '.git*' 15:28:09 INFO - Installing collected packages: jsonschema, functools32 15:28:09 INFO - Running setup.py install for functools32 15:28:09 INFO - warning: no files found matching '*.txt' 15:28:09 INFO - no previously-included directories found matching 'build' 15:28:09 INFO - no previously-included directories found matching 'dist' 15:28:09 INFO - no previously-included directories found matching '.git*' 15:28:09 INFO - Successfully installed jsonschema functools32 15:28:09 INFO - Cleaning up... 15:28:09 INFO - Return code: 0 15:28:09 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 15:28:09 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:28:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:28:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:28:09 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:28:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:28:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:28:09 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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1026a6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026ac570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faee8da50f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10254ad30>, '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 0x10236edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102541b20>, '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.bnZue09OIo/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.bc8xLnhXkc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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 15:28:09 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', 'flake8'] in /builds/slave/test/build 15:28:09 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 flake8 15:28:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bnZue09OIo/Render', 15:28:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:28:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:28:09 INFO - 'HOME': '/Users/cltbld', 15:28:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:28:09 INFO - 'LOGNAME': 'cltbld', 15:28:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:28:09 INFO - 'MOZ_NO_REMOTE': '1', 15:28:09 INFO - 'NO_EM_RESTART': '1', 15:28:09 INFO - 'PAGER': '/bin/cat', 15:28:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:28:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:28:09 INFO - 'PWD': '/builds/slave/test', 15:28:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:28:09 INFO - 'SHELL': '/bin/bash', 15:28:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bc8xLnhXkc/Listeners', 15:28:09 INFO - 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/', 15:28:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:28:09 INFO - 'USER': 'cltbld', 15:28:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:28:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:28:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:28:09 INFO - 'XPC_FLAGS': '0x0', 15:28:09 INFO - 'XPC_SERVICE_NAME': '0', 15:28:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:28:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:28:09 INFO - Downloading/unpacking flake8 15:28:09 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 15:28:09 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 15:28:09 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 15:28:09 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 15:28:09 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 15:28:09 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 15:28:12 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 15:28:12 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 15:28: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 15:28: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 15:28: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 15:28: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 15:28: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 15:28: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 15:28:12 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 15:28:12 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 15:28: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 15:28: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 15:28: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 15:28: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 15:28: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 15:28: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 15:28:13 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 15:28:13 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 15:28:13 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 15:28:13 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 15:28:13 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 15:28:13 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 15:28:13 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 15:28:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 15:28:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:28:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:28:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:28:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:28:13 INFO - no previously-included directories found matching 'docs/_build' 15:28:13 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 15:28:13 INFO - Running setup.py install for pep8 15:28:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:28:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:28:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:28:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:28:13 INFO - no previously-included directories found matching 'docs/_build' 15:28:13 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 15:28:13 INFO - Successfully installed flake8 mccabe pyflakes pep8 15:28:13 INFO - Cleaning up... 15:28:13 INFO - Return code: 0 15:28:13 INFO - Running post-action listener: _resource_record_post_action 15:28:13 INFO - Running post-action listener: _start_resource_monitoring 15:28:13 INFO - Starting resource monitoring. 15:28:13 INFO - ##### 15:28:13 INFO - ##### Finished create-virtualenv step (success) 15:28:13 INFO - ##### 15:28:13 INFO - ##### 15:28:13 INFO - ##### Running install step. 15:28:13 INFO - ##### 15:28:13 INFO - Running pre-action listener: _resource_record_pre_action 15:28:13 INFO - Running main action method: install 15:28:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:28:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:28:14 INFO - Reading from file tmpfile_stdout 15:28:14 INFO - Detecting whether we're running mozinstall >=1.0... 15:28:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:28:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:28:14 INFO - Reading from file tmpfile_stdout 15:28:14 INFO - Output received: 15:28:14 INFO - Usage: mozinstall [options] installer 15:28:14 INFO - Options: 15:28:14 INFO - -h, --help show this help message and exit 15:28:14 INFO - -d DEST, --destination=DEST 15:28:14 INFO - Directory to install application into. [default: 15:28:14 INFO - "/builds/slave/test"] 15:28:14 INFO - --app=APP Application being installed. [default: firefox] 15:28:14 INFO - mkdir: /builds/slave/test/build/application 15:28:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 15:28:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 15:28:48 INFO - Reading from file tmpfile_stdout 15:28:48 INFO - Output received: 15:28:48 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 15:28:48 INFO - Running post-action listener: _resource_record_post_action 15:28:48 INFO - ##### 15:28:48 INFO - ##### Finished install step (success) 15:28:48 INFO - ##### 15:28:48 INFO - ##### 15:28:48 INFO - ##### Running run-tests step. 15:28:48 INFO - ##### 15:28:48 INFO - Running pre-action listener: _resource_record_pre_action 15:28:48 INFO - Running main action method: run_tests 15:28:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:28:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:28:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:28:48 INFO - 2016-05-23 15:28:48.035 screenresolution[2358:21924] starting screenresolution argv=screenresolution get 15:28:48 INFO - 2016-05-23 15:28:48.038 screenresolution[2358:21924] Display 0: 1600x1200x32@60 15:28:48 INFO - 2016-05-23 15:28:48.044 screenresolution[2359:21926] starting screenresolution argv=screenresolution list 15:28:48 INFO - Available Modes on Display 0 15:28:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:28:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:28:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:28:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:28:48 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:28:48 INFO - Intel Iris: 15:28:48 INFO - Chipset Model: Intel Iris 15:28:48 INFO - Type: GPU 15:28:48 INFO - Bus: Built-In 15:28:48 INFO - VRAM (Dynamic, Max): 1536 MB 15:28:48 INFO - Vendor: Intel (0x8086) 15:28:48 INFO - Device ID: 0x0a2e 15:28:48 INFO - Revision ID: 0x0009 15:28:48 INFO - Displays: 15:28:48 INFO - Display: 15:28:48 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:28:48 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:28:48 INFO - Display Serial Number: 1600x1200 60 15:28:48 INFO - Main Display: Yes 15:28:48 INFO - Mirror: Off 15:28:48 INFO - Online: Yes 15:28:48 INFO - Rotation: Supported 15:28:48 INFO - Return code: 0 15:28:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:28:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:28:48 INFO - Python 2.7.3 15:28:48 INFO - Return code: 0 15:28:48 INFO - grabbing minidump binary from tooltool 15:28:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:28:48 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 0x10254ad30>, '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 0x10236edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102541b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:28:48 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 15:28:48 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 15:28:48 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 15:28:48 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:28:48 INFO - Return code: 0 15:28:48 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:28:48 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:28:48 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:28:48 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:28:48 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:28:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 15:28:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 15:28:49 INFO - Return code: 0 15:28:49 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/WZ8Qli3uQeyyJoDQEdcbVg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0107', '--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 15:28:49 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/WZ8Qli3uQeyyJoDQEdcbVg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0107 --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 15:28:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bnZue09OIo/Render', 15:28:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:28:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:28:49 INFO - 'HOME': '/Users/cltbld', 15:28:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:28:49 INFO - 'LOGNAME': 'cltbld', 15:28:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:28:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:28:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:28:49 INFO - 'MOZ_NO_REMOTE': '1', 15:28:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:28:49 INFO - 'NO_EM_RESTART': '1', 15:28:49 INFO - 'PAGER': '/bin/cat', 15:28:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:28:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:28:49 INFO - 'PWD': '/builds/slave/test', 15:28:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:28:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:28:49 INFO - 'SHELL': '/bin/bash', 15:28:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bc8xLnhXkc/Listeners', 15:28:49 INFO - 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/', 15:28:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:28:49 INFO - 'USER': 'cltbld', 15:28:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:28:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:28:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:28:49 INFO - 'XPC_FLAGS': '0x0', 15:28:49 INFO - 'XPC_SERVICE_NAME': '0', 15:28:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:28:49 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/WZ8Qli3uQeyyJoDQEdcbVg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0107', '--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 15:28:49 INFO - mozversion application_buildid: 20160523133154 15:28:49 INFO - mozversion application_changeset: a132c5d3fcac17034d38b3191a611440943c5425 15:28:49 INFO - mozversion application_display_name: Nightly 15:28:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:28:49 INFO - mozversion application_name: Firefox 15:28:49 INFO - mozversion application_remotingname: firefox 15:28:49 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:28:49 INFO - mozversion application_vendor: Mozilla 15:28:49 INFO - mozversion application_version: 49.0a1 15:28:49 INFO - mozversion platform_buildid: 20160523133154 15:28:49 INFO - mozversion platform_changeset: a132c5d3fcac17034d38b3191a611440943c5425 15:28:49 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:28:49 INFO - mozversion platform_version: 49.0a1 15:28:49 INFO - using testdate: 1464042529 15:28:49 INFO - actual date: 1464042529 15:28:49 INFO - starting webserver on 'localhost:49462' 15:28:49 INFO - SUITE-START | Running 2 tests 15:28:49 INFO - TEST-START | dromaeo_css 15:28:49 INFO - operating with platform_type : mac_ 15:28:49 INFO - Initialising browser for dromaeo_css test... 15:28:49 INFO - TEST-INFO | started process 2377 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/tmpTUH0F8/profile http://localhost:49462/getInfo.html) 15:28:51 INFO - PROCESS | 2377 | __metrics Screen width/height:1600/1200 15:28:51 INFO - PROCESS | 2377 | colorDepth:24 15:28:51 INFO - PROCESS | 2377 | Browser inner width/height: 1024/689 15:28:51 INFO - PROCESS | 2377 | __metrics 15:28:52 INFO - PROCESS | 2377 | 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" 15:28:52 ERROR - PROCESS | 2377 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 15:28:52 INFO - TEST-INFO | 2377: exit 0 15:28:52 INFO - Browser initialized. 15:28:52 INFO - Running cycle 1/1 for dromaeo_css test... 15:28:52 INFO - TEST-INFO | started process 2381 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/tmpTUH0F8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:29:04 INFO - PROCESS | 2381 | RSS: Main: 238907392 15:29:04 INFO - PROCESS | 2381 | 15:29:56 INFO - PROCESS | 2381 | 1464042596200 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] 15:32:06 INFO - PROCESS | 2381 | Cycle 1(1): loaded http://localhost:49462/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49462/tests/dromaeo/cssquery-ext.html) 15:32:08 INFO - PROCESS | 2381 | RSS: Main: 372129792 15:32:08 INFO - PROCESS | 2381 | 15:35:11 INFO - PROCESS | 2381 | Cycle 1(1): loaded http://localhost:49462/tests/dromaeo/cssquery-ext.html (next: http://localhost:49462/tests/dromaeo/cssquery-jquery.html) 15:35:11 INFO - PROCESS | 2381 | RSS: Main: 370360320 15:35:11 INFO - PROCESS | 2381 | 15:38:13 INFO - PROCESS | 2381 | Cycle 1(1): loaded http://localhost:49462/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49462/tests/dromaeo/cssquery-mootools.html) 15:38:14 INFO - PROCESS | 2381 | RSS: Main: 368914432 15:38:14 INFO - PROCESS | 2381 | 15:41:16 INFO - PROCESS | 2381 | Cycle 1(1): loaded http://localhost:49462/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49462/tests/dromaeo/cssquery-prototype.html) 15:41:17 INFO - PROCESS | 2381 | RSS: Main: 369483776 15:41:17 INFO - PROCESS | 2381 | 15:44:20 INFO - PROCESS | 2381 | Cycle 1(1): loaded http://localhost:49462/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49462/tests/dromaeo/cssquery-yui.html) 15:44:21 INFO - PROCESS | 2381 | RSS: Main: 336941056 15:44:21 INFO - PROCESS | 2381 | 15:47:24 INFO - PROCESS | 2381 | Cycle 1(1): loaded http://localhost:49462/tests/dromaeo/cssquery-yui.html (next: http://localhost:49462/tests/dromaeo/cssquery-dojo.html) 15:47:24 INFO - PROCESS | 2381 | RSS: Main: 381952000 15:47:24 INFO - PROCESS | 2381 | 15:47:24 INFO - PROCESS | 2381 | __start_tp_report 15:47:24 INFO - PROCESS | 2381 | _x_x_mozilla_page_load 15:47:24 INFO - PROCESS | 2381 | _x_x_mozilla_page_load_details 15:47:24 INFO - PROCESS | 2381 | |i|pagename|runs| 15:47:24 INFO - PROCESS | 2381 | |0;dojo.html;3439.2261741108255;3441.896743097708;3458.723302135829;3497.8600855965765;3521.9647803521957;4672.64955128365;5681.318241810978;5695.487406133447;5792.333881603611;5807.506361959233;4409.581089796464;5653.095504719256;5757.510611598009;5763.510101641355;5818.709064546777;5111.565516931066;5155.71643559604;5204.973975130138;5226.189940559219;5339.519443250107;5326.866828329284;5356.678599284036;5379.623426360156;5382.1119515280125;5407.405185429599;5163.561097306752;5223.268742376052;5252.264682944391;5252.527272545489;5301.443348448434;4263;5230.895382092375;5264.499872512105;5276.81531146408;5325.893482130375;4315.8489452869;5157.252198431206;5207.505286997729;5224.582033437354;5306.5754739621125;5609.158626206061;5673.149027645845;5712.485876271114;5716.79991200306;5723.284589426322;6141.662208578527;6143.2662465203;6220.968895155541;6225.501959843202;6284.874302513924;6263.686815659199;6293.622382657055;6336.96831515844;6376.171097757308;6402.93597064028;5154.92267615986;5645.717714114277;5864.506583371936;5881.264841894763;5918.496927761145;6239.188905442264;6324.525660575475;6332.081848131995;6333;6334.239891212992;6162.815115546541;6176.969115154396;6184.443400094013;6198.473129783929;6199.752009919643;5579.804706835242;6116.701002199504;6171.413715697002;6302.149209856702;6324.873502529924;6200.937990620036;6247.312795592481;6262.405071518198;6263.718132683949;6272.937270627326;6182.752689892444;6212.689365531705;6255.749770009149;6286.968565157236;6303.149074874833;4871.14754917888;4927.753612319441;4930.063287975273;4977.352944117241;5021.949780502148;3799.2021675447913;4470.396496472999;4494.640428765691;4511.300748384006;4523.864284071483;5440.102383106822;5449.972750136224;5486.1222204447895;5494.230807686927;5532.197831314517;5984.461398474158;5992.910106348409;5996.940030599638;6008.729607167688;6010.248718910137;5434.538064264502;5449.209864569615;5474.507294343449;5503.752331145108;5620.606557540933;4678.883027924329;5314.840554783363;5365.812196573102;5380.300560927133;5404.000259951843;6250.968745156245;6290.4653104485715;6297.779577714758;6301.117843501914;6360.586561873463;6328.873422531616;6329.968350158219;6335.081413195153;6351.396617321347;6359.07793369968;6347.873042539031;6349.841253968688;6364.07720880479;6365.077063825626;6367.490600752021;6042.154098426312;6098.129681996294;6133.570650054453;6146.385361463817;6168.568200225941;5327.413984461784;6295.150154729236;6303.621782693053;6332.050192471166;6335.3981371769605;4689.296605509065;6034.487068599218;6113.388661133851;6118.143459915705;6119.785807496716;5927.9703601481715;5977.760889564369;6004.519638428827;6070.605410648293;6074.696265186634;5851.882962340815;5901.91147132802;5904.3210030847;5938.049912014057;5996.640201587918;6088.670442354268;6099.864778873631;6114.480269176992;6124.371389695924;6144.877102457836;5874.236349274567;5884.293884733859;5969.403059693996;5973.49225315853;5978.880422391615 15:47:24 INFO - PROCESS | 2381 | |1;ext.html;465.23236659511804;512.6027328820164;513.4121430961549;515.8658748725333;543.6928135603387;29418;30404.847975760116;40932.59067409325;41949.16101677972;41989.79005104978;43569.56430435694;43856.34215486762;44091.55908440915;44120.779396103055;44130;9415.105564971334;9622.37454565451;9631.373960692568;9641.325107242496;9654.806903861918;8503.702370417037;8740.038595754462;11421.885781142208;11657.591984280552;11671.41642917855;11535.192536522447;11604.477798499045;11608.883911160907;11617.302961822275;11680.240784349033;8519.914800851973;11504.597339093092;11558.306501609888;11582.826257606059;11643.534258629645;11246.044086252678;11598.014168795662;11615.767684646344;11794.35131067791;11805;39834.800825995975;40271.59728402708;40276;40329.596704033174;40335.7983210085;3085.5217441296636;3203.2792621660174;3246.3345014272018;3365.730741540671;3503.1242189452637;28147;28400;28438.857805711046;28603.57094643582;28723.712762872314;2659.1224895784344;2696.0294294054124;2701.1896431070795;2741.917742467729;2791.567307067408;523732;525593;525961.3701931542;527610;527711;59652.70173649104;59893.70053149707;59947.400525994184;59978.10032849511;59989.700051499465;31159.53260701091;31703.207419814687;32367.676323236465;32383;63111;16795.664086718196;16836.158192090348;17010;17025;17042;11721.062315014779;11897.88102118985;12905;13023;13045.869541304466;16874.662506749795;19145.425637230906;19188.368988635844;20065;20089.698654520194;6574.375434333826;7381.37258333037;7667.808304792313;7716.7684969451;7839.216078392115;814.5764202614607;983.149575617086;1017.5573625472942;1100.2023532938715;1108.0858291909208;24128.41835918769;27191.86404067967;28880.632371643103;28894.133176004754;29235;1016.6492560066765;1093.6554985179803;1142.257532603814;1147.701597584634;1155.5724381978553;14764.630884227765;14860.405583776745;14920.104793712411;15069.773953390708;15109.54671359861;680363.5981820059;682211;686668;688106;693488;58378.8324233513;59176;59593;59726;59746.40253597495;1284.4220100954421;1392.617030316671;1420.0272813123045;1455.345094707386;1469.632591852037;29476.263093422836;29799.851000744857;30032.549511756904;30200.94296699693;30229.50136649311;48624.027519448704;49206;49232.50767492422;49380;49664.503354965986;472428;473041;473974;474932;476823;22419.887900560392;24860.254192374257;25545.23364299074;25793.6130958032;26588.936442542083;44614.10771784481;46417.76791116023;46586;46690.766546167055;50006;36674.44988325231;37840;38012.429813553375;38569.421458678706;39012.80493597514;32153.517697235027;36957.44563831491;37254.8137259312;37414.62585374111;37772.81113594415;35893.82053089718;37140;37603;37843.432348514245;37968.77265432469;31479.52780708337;32861.014169573995;32907.8354608235;33001.339973200884;33037.66962330442;19201;22622.32431040993;24148.154814581405;24387.75612243926;24901.875490623155 15:47:24 INFO - PROCESS | 2381 | |2;jquery.html;382.6517868739448;384.90185002824285;386.06572095528816;396.1304935666214;401.21161916833415;18985.525361865944;20347.389578312675;20895.059722312515;20932.476688082807;21013.47466313343;11769.882301176985;11820.822687659671;12297.569585064524;12374.443150058247;19881.90059049706;5292.444293349201;5501.972490137544;5540.279763630722;5546.168074788774;5550.47270509301;3952.2885880541635;4113.526944401402;5846.415358464141;6180.660063696495;6182.196314479112;6171.506279497629;6172.876542469148;6194.163787888645;6199.287081985562;6554.180727409074;6591.274959754423;6745.224299205605;6767.357101075415;6772.966135169342;6801.421879140279;3789.981050094732;5550.27846379972;5894.233749612566;5896.321922978869;5922.733476993546;11197.328160310326;13286.069975101747;13371.331433428388;13485.258310792902;13498.392572334269;2006.5988150085611;2147.6349020666526;2192.0464597899963;2194.8902554872193;2205.4045407739823;9788.412695238309;9870.161036311922;10087.646932357406;10145.746356341151;10157.746056348651;1728.0582082764797;1742.198588649223;1743.7907451105948;1782;1822.271091563372;107541;107809;108854;109475.45262273717;111562.88437115688;9688.9031109688;11119.555217791198;11499.367534785668;11603.245789023686;11627.360495172847;14736.631584210481;14857.851421485862;14886.404543818126;14897.925510372379;14900.0314979526;8492.70275540353;8617.913820861711;8707.998580163236;8816.735497935073;8843.380963332505;7858.567778772108;8017.637001709607;8652.322762825774;8681.869771953427;8790.956045219733;4508.098380323949;4589.816407343736;4606.953930460652;4639.582437580634;4668.253079507263;1770.1326350088364;1783.3397001010633;1816.582186097205;1935.719320698519;1947.9318223862097;388.5065966222881;388.59003751042354;388.599742265467;841.848467275884;888.9866652000226;10384.740381490523;11881.940590296994;11949.402529873472;12001;12031.518739250507;424.898024474124;430.3781036402431;438.3162266863639;445.1941985007147;447.61952340510305;6005.39946005396;6033.758649653964;6055.848603784941;6152.907706384408;6177.1969643945995;500077;513075;513403;615576;619545;126472;135695;136919;137856.62143378437;138599.30700346435;995;1054.3305001324215;1058.4125810175426;1075.3118004477142;1076.687760549432;17245;18067.819321806615;18375.356862509747;18455.815441845945;18715.532111697317;49533;50397.74801125971;51266.48733512617;51884.44346669606;52751.73624131855;74659;75903.24096758962;76131;77000;79630;7455.179930207782;7860.371170306476;7889.447738658238;7909.169537198742;8003;9228.723138305862;10625.840612390666;12055.095867809774;12089.335086570323;12227;6349.555531112777;9636.988116247692;10021.448820314952;10194;10533.473326333806;9653.1794797443;9869.753256168653;10323.380597164194;10419.687409377731;10795.136389089012;9981.750456238653;10344.896551034393;10639.255252132585;10665.093467055387;10988.395638239974;6710.932890671031;7639.961800191186;7890.723824666109;7933.563653999086;8227.177282271725;10027.849582256418;10372.533236004247;10478.266521343432;10586.153107751208;10680.786384272427 15:47:24 INFO - PROCESS | 2381 | |3;mootools.html;1409.1967578480271;1558.75839244917;1591.7134915715171;1815.9636807263855;1952.9511762205952;7044.5421047631935;7745.573993430359;8583.141685831422;8594.054653988056;8594.796728458005;8174.877376839352;8486.405951583392;8503.78740531487;8599.613017414216;8613.267872230852;2087.09211493001;2252.977470225301;2318.397216723651;2331.370529956919;2370.596998510249;2349.4361353275176;2350.9647355289685;2360.3745007573007;2361.53949979754;2365.7989070928993;2303.8041766449874;2314.687517185184;2340.0639744102323;2349.8707571083582;2352.9882350588223;2052.4971382011354;2268.6029944759766;2312.075169932024;2318.455163036685;2322.790948814598;2313.9884300578556;2344.2967109866972;2361.3624321433117;2366.9526609467885;2375.394274460019;8356.665733370657;8364.330853531703;8398.706045288447;8405.41162118652;8411.032731235926;5132.461091585367;5215.530602245778;5295.205719142121;5309.4071778466305;5315.282436871012;3152.2592190835367;3239.5302681111107;3319.850606722703;3344.0970937846646;3344.2642618623863;1022.9590816367337;1024.1499555369026;1026.194437366671;1027.203916964351;1040.5681642118511;11225.943870280678;11363.03414209785;11399.316041037564;11402.258853174517;11421;4613.446386433582;4707.152712511713;4763.190257656207;4797.512771040989;4821;8296.377771667028;9904;9952.054554817281;9953.80092398148;9963.601455941825;5306.310179676618;5307.469253074739;5320.095583750772;5325.520703136736;5334.866628334323;4332.610065094157;4497.033137875373;4707.670463067621;4843.709377437365;4844.636652251095;3470.8958731238104;3900.414937759302;3956.2592459317807;4004.7196696231567;4038.434619153321;1269.156011252525;1288.2721262486853;1290.8580056193812;1300.2718477652545;1307.5488956310057;746.6490749347798;854.5171977832629;889.6130183370254;904.4799240436776;955.1022039283051;5436.864078398072;5504.256925315031;5781.103928891029;5814.883702326015;5927.703614819242;930.4463844012802;985.1921424431897;1011.8464949957055;1021.3157184686278;1054.9029009829844;3613.5483064616924;3750.4374343848094;3769.0577355661085;3821.4458903458844;3884.708646851441;10262.076413122855;10350.585976560853;10440.791184176425;10560.419176945341;10564.418956957441;10382.42896640677;10414;10431.739206519898;10508.106810921005;10557.419341936115;7936.56348900816;8077;8130.674773009132;8209.13804050578;8221.013478382753;5528.723563821713;5605.635633683797;5625;5698.6580805151825;5772.220750198838;9494.104298189119;9844.2124630028;9848.06443387877;9953.900460995586;9960.053794889474;9907.256955728493;10009.749756256151;10072.899271007196;10084.294099412971;10108.595656173964;6444.387783160592;6617.735290588323;6765.729370825309;6841.144856892888;7011.754588589375;8114.715984940499;8206.958965205136;8295.792605184919;8309.00291964968;8323.625436855476;7166.570005799668;7300.890486642811;7330;7492;7494.775156745306;7121.359077683033;7129.928700713134;7131.536450130725;7154.320339567733;7175.103112110986;6803.863922721617;6890.483213759088;6899.310068993061;6902.413294869993;6927.376536111774;4725.36207611968;4767.213409787346;4781.306710527024;4872.756362181806;4964.180910149785;4737.644676649196;4817.156997525349;4818;4905;5009.799608015638 15:47:24 INFO - PROCESS | 2381 | |4;prototype.html;306.97390721788645;310.2151556561898;315.3094722557599;317.2750265643005;322.1141859885315;4464.97767511162;4527.071950250199;5167.888903885664;5262.289590905227;5377.489138531833;5276.208568714695;5309.814156504523;5316.548093412064;5328.360596728398;5338.866528336794;3891.591382904797;3898.8050597470155;4054.8175332110127;4055.371417430303;4095.75425474471;3254.6419893811662;3383.8646454141804;4018.116014476825;4065.715399922007;4124.1957818225455;4074.5314288856716;4081.5918408159096;4102.871710279682;4106.240345536085;4116.814743336557;3845.5193100862393;3950.6641935435377;3972.245273398045;4067.217487736404;4078.8572399966156;3007.45865744166;3106.9125805967956;3291.901242962715;3540.309639620275;3555.306715190539;4237.343211802145;4294.119705460374;4637.258038713823;4732.929006064912;5106.744662766847;3760.7555508891833;4422.5356337584735;4558.58972692456;4598.723631802246;4696.53034696531;4530.003399252159;5068.530126263379;5493.835184944458;5515.117581187031;5568.443155684399;3382.915427114317;3539.1152211947015;3846.807659617008;3930.3514920038447;3980.5422376426504;5102.6428150029515;5209.4009188943755;5774.105013722879;5921.7363498281165;5981.05615674903;3700.685441737429;3764.3036038333;3792.544894612609;3992.640662340403;4025.9194816103513;4030.415589739471;4053.918921621551;4083.387491876242;4089.795510224477;4098.4467096941535;4184.832606695759;4550.563200879183;4623.283391074388;4628;4714.504976977368;4562.338460923148;5606.271184746039;5642.210090587321;5676.687782171937;5694.889496548216;1207.6112470658636;1298.9090763646454;1348.184348469162;1389.5622878793147;1408.7041721238654;738.7920749574484;765.8162041110205;820.1962077164412;856.8971723393232;866.8049688820091;4184.979075104605;4429.8703830523655;4464.888377790581;4924.901501969941;5290.338707661543;5097.949020509747;5389.946100539023;5686.9715651421475;5727.198192253088;5785.479306862402;4800.3279540864305;4807.326974223612;4854.199057155601;5066.923996140061;5241.266222728821;2888.321244507539;2902.954936222958;3025.7579393648457;3029.636443626779;3082.2756652186717;5730.255066841367;5806.738696758572;5982.756466325128;5988.461038506555;6076.511254742616;5259.658122222043;5500.6347270674;5973.253343332083;6018.937217378252;6021.397860213943;4626.583607475276;5264.10510213257;5501.9174712379345;5760.654360738369;6014.72933717975;5429.53848922846;5728.596493859031;5795.391483894299;5819.806939577461;5848.5028772553105;5089.8727531812;5652.349979752381;5692.857678558069;5695.658260504383;5703.401142879938;4690.87419019426;5066.871913796141;5976;5993.250843644544;6245.406686364788;3079;3138.59198304219;3276.5249038889706;3293.407186706416;3319.302946381287;4339.891502712458;4808.677613656133;4823.95176048235;4894.853154405374;4906.362172917497;4140.875773726793;4150.613397296506;4416.343871048357;4609.354690343288;4701.788419521062;4077.8164982575263;4489.955100449084;4617.907641847211;4651.720896746206;4696.882577935579;4711.575958163782;4741.312509686145;4782.210935195653;5415.918761218663;5491.890162196813;4925.532074452921;5450.345958485007;5495.258140151029;5678.949394362056;5736.451158187349;4619.006913513476;5187.974060129675;5284.339457567804;5452.345718513804;5489.780408783604 15:47:24 INFO - PROCESS | 2381 | |5;yui.html;720.1789959446231;745.8284594543259;746.6901235987066;755.4296506137865;757.5492581913761;14148.646283842909;14356.856431435683;14805.18571478571;14822.925885370572;14848.475759120949;14040.368183431745;14093.365798539066;14110.506132285373;14142.929285353559;14156.858431415683;689.1626673591585;712.5154894671621;822.8847961285425;874.8906386701647;897.8922529296461;855.1448551448551;861.67687117331;880.0143838900441;883.0286684646901;888.2849306308419;933.77589378549;936.1060187520901;937.5874615169304;939.0937745076001;940.0928104379273;720.5892641194521;904.0642934562696;931.902150274225;934.4113208678489;936.9063093690577;664.7772996046331;679.9970044184837;680.3706571421465;680.4964326398479;680.877442060429;14618.195999220039;14626.70746585073;14646.707065858729;14650.267486625733;14653.926730366387;11661.300321980709;11767.867540572446;11866.406679666068;12043.458044388024;12138.211016283913;10480.528376223088;10601.73495662607;10698.197635177392;10785.78428431427;11135.336399657537;3934.311495488278;3943.98028009861;3948.545917219528;4082;4085.9387109193385;74474;75978;76486.61756691236;77394.61302693507;77550.24497550256;9156.633734650539;9218.44689318643;9293.674721384854;9565.665201717906;9623.278254130968;20923.790762092183;20995.950202489814;20999.16003359879;21022.2642207522;21048.789512104988;7196;7296.6716497757725;7304.123505179413;7307.744228952068;7407.111146662327;3966.8413263469715;4010;4012.6789856811383;4018.1963607278662;4024.7987600619854;3309.321589074234;3333.683300086488;3415.351083294166;3419.555457790521;3422.178677117524;510.67572091722025;570.0406463765258;593.092568370394;610.6610830988844;650.9869802603921;457.7688267424996;475.75023112866023;567.8523583868224;586.4106572894214;593.5637306579661;7868.606569671608;7989.480683755653;8077.151899050633;8180.713675021465;8307.709230176915;571.2516603249757;582.9417058294136;593.5696619950587;604.7943699142313;609.2262826210688;3441.6558344165383;3727.7949712765517;3773.981025123256;3813.2373525294497;3876.5541962674088;74786;75344.24655753482;77227.45545089067;91904.08095919089;93500;69647.30352696408;69782;70583;72059;73071;65411.01883471752;66024;66453.13151778793;66740.99888501676;67019.66490167518;8119.228673276029;8122.634481448231;8125.065617454068;8135.064467586303;8202.548859812767;38861.61138388693;42353.94115147146;42474.575254248295;42889.78555107205;43061;56881.43118568927;57264.713676431355;57405.56486087881;58848.70575647095;60611.69694151678;11435.542578297107;11441.427928603704;11491.138164637852;11566.94216528912;11679.766404671689;11381.829272560753;11784;11808.586699465477;11918.582849600569;12808.423620936894;11132.220744547803;11196.160287978597;11329.660110196708;11624.883751162719;11646.883531164918;11349.4892729829;11455.885441145481;11607.477663505331;11654.76690466169;11776.764464710486;10670.039696427359;11056;11075.833862491907;11237.943810280896;11542.884571154182;9416.246700263826;9485.715428537154;9537.380070295409;9651.034896510293;9793.363431376938;9414.65779648215;9560.474173920502;9619.807603848023;9632.42205467646;9654.56554455037 15:47:24 INFO - PROCESS | 2381 | __end_tp_report 15:47:24 INFO - PROCESS | 2381 | __start_cc_report 15:47:24 INFO - PROCESS | 2381 | _x_x_mozilla_cycle_collect,1397 15:47:24 INFO - PROCESS | 2381 | __end_cc_report 15:47:24 INFO - PROCESS | 2381 | __startTimestamp1464043644496__endTimestamp 15:47:24 INFO - PROCESS | 2381 | 15:47:24 INFO - PROCESS | 2381 | ------- Summary: start ------- 15:47:24 INFO - PROCESS | 2381 | Number of tests: 6 15:47:24 INFO - PROCESS | 2381 | 15:47:24 INFO - PROCESS | 2381 | [#0] dojo.html Cycles:165 Average:5695.97 Median:5918.50 stddev:670.96 (11.3%) stddev-sans-first:649.24 15:47:24 INFO - PROCESS | 2381 | Values: 3439.2 3441.9 3458.7 3497.9 3522.0 4672.6 5681.3 5695.5 5792.3 5807.5 4409.6 5653.1 5757.5 5763.5 5818.7 5111.6 5155.7 5205.0 5226.2 5339.5 5326.9 5356.7 5379.6 5382.1 5407.4 5163.6 5223.3 5252.3 5252.5 5301.4 4263.0 5230.9 5264.5 5276.8 5325.9 4315.8 5157.3 5207.5 5224.6 5306.6 5609.2 5673.1 5712.5 5716.8 5723.3 6141.7 6143.3 6221.0 6225.5 6284.9 6263.7 6293.6 6337.0 6376.2 6402.9 5154.9 5645.7 5864.5 5881.3 5918.5 6239.2 6324.5 6332.1 6333.0 6334.2 6162.8 6177.0 6184.4 6198.5 6199.8 5579.8 6116.7 6171.4 6302.1 6324.9 6200.9 6247.3 6262.4 6263.7 6272.9 6182.8 6212.7 6255.7 6287.0 6303.1 4871.1 4927.8 4930.1 4977.4 5021.9 3799.2 4470.4 4494.6 4511.3 4523.9 5440.1 5450.0 5486.1 5494.2 5532.2 5984.5 5992.9 5996.9 6008.7 6010.2 5434.5 5449.2 5474.5 5503.8 5620.6 4678.9 5314.8 5365.8 5380.3 5404.0 6251.0 6290.5 6297.8 6301.1 6360.6 6328.9 6330.0 6335.1 6351.4 6359.1 6347.9 6349.8 6364.1 6365.1 6367.5 6042.2 6098.1 6133.6 6146.4 6168.6 5327.4 6295.2 6303.6 6332.1 6335.4 4689.3 6034.5 6113.4 6118.1 6119.8 5928.0 5977.8 6004.5 6070.6 6074.7 5851.9 5901.9 5904.3 5938.0 5996.6 6088.7 6099.9 6114.5 6124.4 6144.9 5874.2 5884.3 5969.4 5973.5 5978.9 15:47:24 INFO - PROCESS | 2381 | 15:47:24 INFO - PROCESS | 2381 | [#1] ext.html Cycles:180 Average:68740.11 Median:18053.67 stddev:152384.21 (844.1%) stddev-sans-first:152725.47 15:47:24 INFO - PROCESS | 2381 | Values: 465.2 512.6 513.4 515.9 543.7 29418.0 30404.8 40932.6 41949.2 41989.8 43569.6 43856.3 44091.6 44120.8 44130.0 9415.1 9622.4 9631.4 9641.3 9654.8 8503.7 8740.0 11421.9 11657.6 11671.4 11535.2 11604.5 11608.9 11617.3 11680.2 8519.9 11504.6 11558.3 11582.8 11643.5 11246.0 11598.0 11615.8 11794.4 11805.0 39834.8 40271.6 40276.0 40329.6 40335.8 3085.5 3203.3 3246.3 3365.7 3503.1 28147.0 28400.0 28438.9 28603.6 28723.7 2659.1 2696.0 2701.2 2741.9 2791.6 523732.0 525593.0 525961.4 527610.0 527711.0 59652.7 59893.7 59947.4 59978.1 59989.7 31159.5 31703.2 32367.7 32383.0 63111.0 16795.7 16836.2 17010.0 17025.0 17042.0 11721.1 11897.9 12905.0 13023.0 13045.9 16874.7 19145.4 19188.4 20065.0 20089.7 6574.4 7381.4 7667.8 7716.8 7839.2 814.6 983.1 1017.6 1100.2 1108.1 24128.4 27191.9 28880.6 28894.1 29235.0 1016.6 1093.7 1142.3 1147.7 1155.6 14764.6 14860.4 14920.1 15069.8 15109.5 680363.6 682211.0 686668.0 688106.0 693488.0 58378.8 59176.0 59593.0 59726.0 59746.4 1284.4 1392.6 1420.0 1455.3 1469.6 29476.3 29799.9 30032.5 30200.9 30229.5 48624.0 49206.0 49232.5 49380.0 49664.5 472428.0 473041.0 473974.0 474932.0 476823.0 22419.9 24860.3 25545.2 25793.6 26588.9 44614.1 46417.8 46586.0 46690.8 50006.0 36674.4 37840.0 38012.4 38569.4 39012.8 32153.5 36957.4 37254.8 37414.6 37772.8 35893.8 37140.0 37603.0 37843.4 37968.8 31479.5 32861.0 32907.8 33001.3 33037.7 19201.0 22622.3 24148.2 24387.8 24901.9 15:47:24 INFO - PROCESS | 2381 | 15:47:24 INFO - PROCESS | 2381 | [#2] jquery.html Cycles:180 Average:32487.87 Median:388.59 stddev:93257.70 (23998.7%) stddev-sans-first:93488.16 15:47:24 INFO - PROCESS | 2381 | Values: 382.7 384.9 386.1 396.1 401.2 18985.5 20347.4 20895.1 20932.5 21013.5 11769.9 11820.8 12297.6 12374.4 19881.9 5292.4 5502.0 5540.3 5546.2 5550.5 3952.3 4113.5 5846.4 6180.7 6182.2 6171.5 6172.9 6194.2 6199.3 6554.2 6591.3 6745.2 6767.4 6773.0 6801.4 3790.0 5550.3 5894.2 5896.3 5922.7 11197.3 13286.1 13371.3 13485.3 13498.4 2006.6 2147.6 2192.0 2194.9 2205.4 9788.4 9870.2 10087.6 10145.7 10157.7 1728.1 1742.2 1743.8 1782.0 1822.3 107541.0 107809.0 108854.0 109475.5 111562.9 9688.9 11119.6 11499.4 11603.2 11627.4 14736.6 14857.9 14886.4 14897.9 14900.0 8492.7 8617.9 8708.0 8816.7 8843.4 7858.6 8017.6 8652.3 8681.9 8791.0 4508.1 4589.8 4607.0 4639.6 4668.3 1770.1 1783.3 1816.6 1935.7 1947.9 388.5 388.6 388.6 841.8 889.0 10384.7 11881.9 11949.4 12001.0 12031.5 424.9 430.4 438.3 445.2 447.6 6005.4 6033.8 6055.8 6152.9 6177.2 500077.0 513075.0 513403.0 615576.0 619545.0 126472.0 135695.0 136919.0 137856.6 138599.3 995.0 1054.3 1058.4 1075.3 1076.7 17245.0 18067.8 18375.4 18455.8 18715.5 49533.0 50397.7 51266.5 51884.4 52751.7 74659.0 75903.2 76131.0 77000.0 79630.0 7455.2 7860.4 7889.4 7909.2 8003.0 9228.7 10625.8 12055.1 12089.3 12227.0 6349.6 9637.0 10021.4 10194.0 10533.5 9653.2 9869.8 10323.4 10419.7 10795.1 9981.8 10344.9 10639.3 10665.1 10988.4 6710.9 7640.0 7890.7 7933.6 8227.2 10027.8 10372.5 10478.3 10586.2 10680.8 15:47:24 INFO - PROCESS | 2381 | 15:47:24 INFO - PROCESS | 2381 | [#3] mootools.html Cycles:180 Average:5569.76 Median:4832.35 stddev:3110.14 (64.4%) stddev-sans-first:3103.15 15:47:24 INFO - PROCESS | 2381 | Values: 1409.2 1558.8 1591.7 1816.0 1953.0 7044.5 7745.6 8583.1 8594.1 8594.8 8174.9 8486.4 8503.8 8599.6 8613.3 2087.1 2253.0 2318.4 2331.4 2370.6 2349.4 2351.0 2360.4 2361.5 2365.8 2303.8 2314.7 2340.1 2349.9 2353.0 2052.5 2268.6 2312.1 2318.5 2322.8 2314.0 2344.3 2361.4 2367.0 2375.4 8356.7 8364.3 8398.7 8405.4 8411.0 5132.5 5215.5 5295.2 5309.4 5315.3 3152.3 3239.5 3319.9 3344.1 3344.3 1023.0 1024.1 1026.2 1027.2 1040.6 11225.9 11363.0 11399.3 11402.3 11421.0 4613.4 4707.2 4763.2 4797.5 4821.0 8296.4 9904.0 9952.1 9953.8 9963.6 5306.3 5307.5 5320.1 5325.5 5334.9 4332.6 4497.0 4707.7 4843.7 4844.6 3470.9 3900.4 3956.3 4004.7 4038.4 1269.2 1288.3 1290.9 1300.3 1307.5 746.6 854.5 889.6 904.5 955.1 5436.9 5504.3 5781.1 5814.9 5927.7 930.4 985.2 1011.8 1021.3 1054.9 3613.5 3750.4 3769.1 3821.4 3884.7 10262.1 10350.6 10440.8 10560.4 10564.4 10382.4 10414.0 10431.7 10508.1 10557.4 7936.6 8077.0 8130.7 8209.1 8221.0 5528.7 5605.6 5625.0 5698.7 5772.2 9494.1 9844.2 9848.1 9953.9 9960.1 9907.3 10009.7 10072.9 10084.3 10108.6 6444.4 6617.7 6765.7 6841.1 7011.8 8114.7 8207.0 8295.8 8309.0 8323.6 7166.6 7300.9 7330.0 7492.0 7494.8 7121.4 7129.9 7131.5 7154.3 7175.1 6803.9 6890.5 6899.3 6902.4 6927.4 4725.4 4767.2 4781.3 4872.8 4964.2 4737.6 4817.2 4818.0 4905.0 5009.8 15:47:24 INFO - PROCESS | 2381 | 15:47:24 INFO - PROCESS | 2381 | [#4] prototype.html Cycles:180 Average:4390.68 Median:4696.71 stddev:1358.49 (28.9%) stddev-sans-first:1327.27 15:47:24 INFO - PROCESS | 2381 | Values: 307.0 310.2 315.3 317.3 322.1 4465.0 4527.1 5167.9 5262.3 5377.5 5276.2 5309.8 5316.5 5328.4 5338.9 3891.6 3898.8 4054.8 4055.4 4095.8 3254.6 3383.9 4018.1 4065.7 4124.2 4074.5 4081.6 4102.9 4106.2 4116.8 3845.5 3950.7 3972.2 4067.2 4078.9 3007.5 3106.9 3291.9 3540.3 3555.3 4237.3 4294.1 4637.3 4732.9 5106.7 3760.8 4422.5 4558.6 4598.7 4696.5 4530.0 5068.5 5493.8 5515.1 5568.4 3382.9 3539.1 3846.8 3930.4 3980.5 5102.6 5209.4 5774.1 5921.7 5981.1 3700.7 3764.3 3792.5 3992.6 4025.9 4030.4 4053.9 4083.4 4089.8 4098.4 4184.8 4550.6 4623.3 4628.0 4714.5 4562.3 5606.3 5642.2 5676.7 5694.9 1207.6 1298.9 1348.2 1389.6 1408.7 738.8 765.8 820.2 856.9 866.8 4185.0 4429.9 4464.9 4924.9 5290.3 5097.9 5389.9 5687.0 5727.2 5785.5 4800.3 4807.3 4854.2 5066.9 5241.3 2888.3 2903.0 3025.8 3029.6 3082.3 5730.3 5806.7 5982.8 5988.5 6076.5 5259.7 5500.6 5973.3 6018.9 6021.4 4626.6 5264.1 5501.9 5760.7 6014.7 5429.5 5728.6 5795.4 5819.8 5848.5 5089.9 5652.3 5692.9 5695.7 5703.4 4690.9 5066.9 5976.0 5993.3 6245.4 3079.0 3138.6 3276.5 3293.4 3319.3 4339.9 4808.7 4824.0 4894.9 4906.4 4140.9 4150.6 4416.3 4609.4 4701.8 4077.8 4490.0 4617.9 4651.7 4696.9 4711.6 4741.3 4782.2 5415.9 5491.9 4925.5 5450.3 5495.3 5678.9 5736.5 4619.0 5188.0 5284.3 5452.3 5489.8 15:47:24 INFO - PROCESS | 2381 | 15:47:24 INFO - PROCESS | 2381 | [#5] yui.html Cycles:180 Average:17075.82 Median:29717.56 stddev:23233.45 (78.2%) stddev-sans-first:23266.17 15:47:24 INFO - PROCESS | 2381 | Values: 720.2 745.8 746.7 755.4 757.5 14148.6 14356.9 14805.2 14822.9 14848.5 14040.4 14093.4 14110.5 14142.9 14156.9 689.2 712.5 822.9 874.9 897.9 855.1 861.7 880.0 883.0 888.3 933.8 936.1 937.6 939.1 940.1 720.6 904.1 931.9 934.4 936.9 664.8 680.0 680.4 680.5 680.9 14618.2 14626.7 14646.7 14650.3 14653.9 11661.3 11767.9 11866.4 12043.5 12138.2 10480.5 10601.7 10698.2 10785.8 11135.3 3934.3 3944.0 3948.5 4082.0 4085.9 74474.0 75978.0 76486.6 77394.6 77550.2 9156.6 9218.4 9293.7 9565.7 9623.3 20923.8 20996.0 20999.2 21022.3 21048.8 7196.0 7296.7 7304.1 7307.7 7407.1 3966.8 4010.0 4012.7 4018.2 4024.8 3309.3 3333.7 3415.4 3419.6 3422.2 510.7 570.0 593.1 610.7 651.0 457.8 475.8 567.9 586.4 593.6 7868.6 7989.5 8077.2 8180.7 8307.7 571.3 582.9 593.6 604.8 609.2 3441.7 3727.8 3774.0 3813.2 3876.6 74786.0 75344.2 77227.5 91904.1 93500.0 69647.3 69782.0 70583.0 72059.0 73071.0 65411.0 66024.0 66453.1 66741.0 67019.7 8119.2 8122.6 8125.1 8135.1 8202.5 38861.6 42353.9 42474.6 42889.8 43061.0 56881.4 57264.7 57405.6 58848.7 60611.7 11435.5 11441.4 11491.1 11566.9 11679.8 11381.8 11784.0 11808.6 11918.6 12808.4 11132.2 11196.2 11329.7 11624.9 11646.9 11349.5 11455.9 11607.5 11654.8 11776.8 10670.0 11056.0 11075.8 11237.9 11542.9 9416.2 9485.7 9537.4 9651.0 9793.4 9414.7 9560.5 9619.8 9632.4 9654.6 15:47:24 INFO - PROCESS | 2381 | -------- Summary: end -------- 15:47:24 INFO - PROCESS | 2381 | 15:47:25 INFO - TEST-INFO | 2381: exit 0 15:47:25 INFO - TEST-OK | dromaeo_css | took 1115396ms 15:47:25 INFO - TEST-START | kraken 15:47:25 INFO - operating with platform_type : mac_ 15:47:25 INFO - Initialising browser for kraken test... 15:47:25 INFO - TEST-INFO | started process 2385 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/tmpz5NGgs/profile http://localhost:49462/getInfo.html) 15:47:27 INFO - PROCESS | 2385 | __metrics Screen width/height:1600/1200 15:47:27 INFO - PROCESS | 2385 | colorDepth:24 15:47:27 INFO - PROCESS | 2385 | Browser inner width/height: 1024/689 15:47:27 INFO - PROCESS | 2385 | __metrics 15:47:27 INFO - PROCESS | 2385 | 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" 15:47:27 ERROR - PROCESS | 2385 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 15:47:28 INFO - TEST-INFO | 2385: exit 0 15:47:28 INFO - Browser initialized. 15:47:28 INFO - Running cycle 1/1 for kraken test... 15:47:28 INFO - TEST-INFO | started process 2387 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/tmpz5NGgs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:47:40 INFO - PROCESS | 2387 | RSS: Main: 238972928 15:47:40 INFO - PROCESS | 2387 | 15:47:42 INFO - PROCESS | 2387 | Cycle 1(1): loaded http://localhost:49462/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49462/tests/kraken/driver.html?testName=audio-beat-detection) 15:47:43 INFO - PROCESS | 2387 | RSS: Main: 287539200 15:47:43 INFO - PROCESS | 2387 | 15:47:46 INFO - PROCESS | 2387 | Cycle 1(1): loaded http://localhost:49462/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49462/tests/kraken/driver.html?testName=audio-dft) 15:47:47 INFO - PROCESS | 2387 | RSS: Main: 296251392 15:47:47 INFO - PROCESS | 2387 | 15:47:50 INFO - PROCESS | 2387 | Cycle 1(1): loaded http://localhost:49462/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49462/tests/kraken/driver.html?testName=audio-fft) 15:47:51 INFO - PROCESS | 2387 | RSS: Main: 387174400 15:47:51 INFO - PROCESS | 2387 | 15:47:53 INFO - PROCESS | 2387 | Cycle 1(1): loaded http://localhost:49462/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49462/tests/kraken/driver.html?testName=audio-oscillator) 15:47:54 INFO - PROCESS | 2387 | RSS: Main: 286597120 15:47:54 INFO - PROCESS | 2387 | 15:47:56 INFO - PROCESS | 2387 | Cycle 1(1): loaded http://localhost:49462/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49462/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:47:57 INFO - PROCESS | 2387 | RSS: Main: 290574336 15:47:57 INFO - PROCESS | 2387 | 15:48:00 INFO - PROCESS | 2387 | Cycle 1(1): loaded http://localhost:49462/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49462/tests/kraken/driver.html?testName=imaging-darkroom) 15:48:00 INFO - PROCESS | 2387 | RSS: Main: 330043392 15:48:00 INFO - PROCESS | 2387 | 15:48:03 INFO - PROCESS | 2387 | Cycle 1(1): loaded http://localhost:49462/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49462/tests/kraken/driver.html?testName=imaging-desaturate) 15:48:04 INFO - PROCESS | 2387 | RSS: Main: 331833344 15:48:04 INFO - PROCESS | 2387 | 15:48:07 INFO - PROCESS | 2387 | Cycle 1(1): loaded http://localhost:49462/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49462/tests/kraken/driver.html?testName=json-parse-financial) 15:48:08 INFO - PROCESS | 2387 | RSS: Main: 332554240 15:48:08 INFO - PROCESS | 2387 | 15:48:09 INFO - PROCESS | 2387 | Cycle 1(1): loaded http://localhost:49462/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49462/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:48:10 INFO - PROCESS | 2387 | RSS: Main: 316030976 15:48:10 INFO - PROCESS | 2387 | 15:48:12 INFO - PROCESS | 2387 | Cycle 1(1): loaded http://localhost:49462/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49462/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:48:13 INFO - PROCESS | 2387 | RSS: Main: 323391488 15:48:13 INFO - PROCESS | 2387 | 15:48:15 INFO - PROCESS | 2387 | Cycle 1(1): loaded http://localhost:49462/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49462/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:48:16 INFO - PROCESS | 2387 | RSS: Main: 325586944 15:48:16 INFO - PROCESS | 2387 | 15:48:19 INFO - PROCESS | 2387 | Cycle 1(1): loaded http://localhost:49462/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49462/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:48:19 INFO - PROCESS | 2387 | RSS: Main: 327073792 15:48:19 INFO - PROCESS | 2387 | 15:48:22 INFO - PROCESS | 2387 | Cycle 1(1): loaded http://localhost:49462/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49462/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:48:23 INFO - PROCESS | 2387 | RSS: Main: 322719744 15:48:23 INFO - PROCESS | 2387 | 15:48:25 INFO - PROCESS | 2387 | Cycle 1(1): loaded http://localhost:49462/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49462/tests/kraken/driver.html?testName=ai-astar) 15:48:25 INFO - PROCESS | 2387 | RSS: Main: 369807360 15:48:25 INFO - PROCESS | 2387 | 15:48:25 INFO - PROCESS | 2387 | __start_tp_report 15:48:25 INFO - PROCESS | 2387 | _x_x_mozilla_page_load 15:48:25 INFO - PROCESS | 2387 | _x_x_mozilla_page_load_details 15:48:25 INFO - PROCESS | 2387 | |i|pagename|runs| 15:48:25 INFO - PROCESS | 2387 | |0;ai-astar;114;107;113;112;113;113;113;110;112;112 15:48:25 INFO - PROCESS | 2387 | |1;audio-beat-detection;160;121;119;203;117;122;166;139;165;116 15:48:25 INFO - PROCESS | 2387 | |2;audio-dft;185;182;186;185;197;258;184;184;185;184 15:48:25 INFO - PROCESS | 2387 | |3;audio-fft;80;57;60;119;58;56;64;67;94;79 15:48:25 INFO - PROCESS | 2387 | |4;audio-oscillator;96;77;139;78;86;100;137;81;77;104 15:48:25 INFO - PROCESS | 2387 | |5;imaging-gaussian-blur;88;85;88;86;89;85;88;85;88;85 15:48:25 INFO - PROCESS | 2387 | |6;imaging-darkroom;93;94;101;94;94;93;94;93;94;94 15:48:25 INFO - PROCESS | 2387 | |7;imaging-desaturate;103;131;83;84;86;84;84;86;84;83 15:48:25 INFO - PROCESS | 2387 | |8;json-parse-financial;56;56;59;56;57;57;57;120;56;58 15:48:25 INFO - PROCESS | 2387 | |9;json-stringify-tinderbox;67;78;77;73;74;71;71;71;71;72 15:48:25 INFO - PROCESS | 2387 | |10;stanford-crypto-aes;84;85;80;86;85;85;79;84;83;86 15:48:25 INFO - PROCESS | 2387 | |11;stanford-crypto-ccm;136;170;152;125;147;127;143;144;256;159 15:48:25 INFO - PROCESS | 2387 | |12;stanford-crypto-pbkdf2;166;165;169;163;165;162;163;164;160;165 15:48:25 INFO - PROCESS | 2387 | |13;stanford-crypto-sha256-iterative;67;67;67;67;67;73;84;83;84;72 15:48:25 INFO - PROCESS | 2387 | __end_tp_report 15:48:25 INFO - PROCESS | 2387 | __start_cc_report 15:48:25 INFO - PROCESS | 2387 | _x_x_mozilla_cycle_collect,2288 15:48:25 INFO - PROCESS | 2387 | __end_cc_report 15:48:25 INFO - PROCESS | 2387 | __startTimestamp1464043705549__endTimestamp 15:48:25 INFO - PROCESS | 2387 | 15:48:25 INFO - PROCESS | 2387 | ------- Summary: start ------- 15:48:25 INFO - PROCESS | 2387 | Number of tests: 14 15:48:25 INFO - PROCESS | 2387 | 15:48:25 INFO - PROCESS | 2387 | [#0] ai-astar Cycles:10 Average:111.90 Median:113.00 stddev:2.02 (1.8%) stddev-sans-first:2.00 15:48:25 INFO - PROCESS | 2387 | Values: 114.0 107.0 113.0 112.0 113.0 113.0 113.0 110.0 112.0 112.0 15:48:25 INFO - PROCESS | 2387 | 15:48:25 INFO - PROCESS | 2387 | [#1] audio-beat-detection Cycles:10 Average:142.80 Median:149.50 stddev:29.48 (19.7%) stddev-sans-first:30.61 15:48:25 INFO - PROCESS | 2387 | Values: 160.0 121.0 119.0 203.0 117.0 122.0 166.0 139.0 165.0 116.0 15:48:25 INFO - PROCESS | 2387 | 15:48:25 INFO - PROCESS | 2387 | [#2] audio-dft Cycles:10 Average:193.00 Median:185.00 stddev:23.20 (12.5%) stddev-sans-first:24.43 15:48:25 INFO - PROCESS | 2387 | Values: 185.0 182.0 186.0 185.0 197.0 258.0 184.0 184.0 185.0 184.0 15:48:25 INFO - PROCESS | 2387 | 15:48:25 INFO - PROCESS | 2387 | [#3] audio-fft Cycles:10 Average:73.40 Median:65.50 stddev:20.27 (30.9%) stddev-sans-first:21.35 15:48:25 INFO - PROCESS | 2387 | Values: 80.0 57.0 60.0 119.0 58.0 56.0 64.0 67.0 94.0 79.0 15:48:25 INFO - PROCESS | 2387 | 15:48:25 INFO - PROCESS | 2387 | [#4] audio-oscillator Cycles:10 Average:97.50 Median:77.50 stddev:23.47 (30.3%) stddev-sans-first:24.89 15:48:25 INFO - PROCESS | 2387 | Values: 96.0 77.0 139.0 78.0 86.0 100.0 137.0 81.0 77.0 104.0 15:48:25 INFO - PROCESS | 2387 | 15:48:25 INFO - PROCESS | 2387 | [#5] imaging-gaussian-blur Cycles:10 Average:86.70 Median:88.00 stddev:1.64 (1.9%) stddev-sans-first:1.67 15:48:25 INFO - PROCESS | 2387 | Values: 88.0 85.0 88.0 86.0 89.0 85.0 88.0 85.0 88.0 85.0 15:48:25 INFO - PROCESS | 2387 | 15:48:25 INFO - PROCESS | 2387 | [#6] imaging-darkroom Cycles:10 Average:94.40 Median:94.00 stddev:2.37 (2.5%) stddev-sans-first:2.46 15:48:25 INFO - PROCESS | 2387 | Values: 93.0 94.0 101.0 94.0 94.0 93.0 94.0 93.0 94.0 94.0 15:48:25 INFO - PROCESS | 2387 | 15:48:25 INFO - PROCESS | 2387 | [#7] imaging-desaturate Cycles:10 Average:90.80 Median:84.00 stddev:15.34 (18.3%) stddev-sans-first:15.62 15:48:25 INFO - PROCESS | 2387 | Values: 103.0 131.0 83.0 84.0 86.0 84.0 84.0 86.0 84.0 83.0 15:48:25 INFO - PROCESS | 2387 | 15:48:25 INFO - PROCESS | 2387 | [#8] json-parse-financial Cycles:10 Average:63.20 Median:57.00 stddev:19.98 (35.1%) stddev-sans-first:21.02 15:48:25 INFO - PROCESS | 2387 | Values: 56.0 56.0 59.0 56.0 57.0 57.0 57.0 120.0 56.0 58.0 15:48:25 INFO - PROCESS | 2387 | 15:48:25 INFO - PROCESS | 2387 | [#9] json-stringify-tinderbox Cycles:10 Average:72.50 Median:72.50 stddev:3.21 (4.4%) stddev-sans-first:2.71 15:48:25 INFO - PROCESS | 2387 | Values: 67.0 78.0 77.0 73.0 74.0 71.0 71.0 71.0 71.0 72.0 15:48:25 INFO - PROCESS | 2387 | 15:48:25 INFO - PROCESS | 2387 | [#10] stanford-crypto-aes Cycles:10 Average:83.70 Median:85.00 stddev:2.41 (2.8%) stddev-sans-first:2.55 15:48:25 INFO - PROCESS | 2387 | Values: 84.0 85.0 80.0 86.0 85.0 85.0 79.0 84.0 83.0 86.0 15:48:25 INFO - PROCESS | 2387 | 15:48:25 INFO - PROCESS | 2387 | [#11] stanford-crypto-ccm Cycles:10 Average:155.90 Median:149.50 stddev:37.74 (25.2%) stddev-sans-first:39.33 15:48:25 INFO - PROCESS | 2387 | Values: 136.0 170.0 152.0 125.0 147.0 127.0 143.0 144.0 256.0 159.0 15:48:25 INFO - PROCESS | 2387 | 15:48:25 INFO - PROCESS | 2387 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.20 Median:165.00 stddev:2.44 (1.5%) stddev-sans-first:2.50 15:48:25 INFO - PROCESS | 2387 | Values: 166.0 165.0 169.0 163.0 165.0 162.0 163.0 164.0 160.0 165.0 15:48:25 INFO - PROCESS | 2387 | 15:48:25 INFO - PROCESS | 2387 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.10 Median:72.50 stddev:7.62 (10.5%) stddev-sans-first:7.76 15:48:25 INFO - PROCESS | 2387 | Values: 67.0 67.0 67.0 67.0 67.0 73.0 84.0 83.0 84.0 72.0 15:48:25 INFO - PROCESS | 2387 | -------- Summary: end -------- 15:48:25 INFO - PROCESS | 2387 | 15:48:26 INFO - TEST-INFO | 2387: exit 0 15:48:26 INFO - TEST-OK | kraken | took 61257ms 15:48:26 INFO - SUITE-END | took 1176s 15:48:26 INFO - Completed test suite (00:19:36) 15:48:26 INFO - javascript benchmark 15:48:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3439.2261741108255, 3441.896743097708, 3458.723302135829, 3497.8600855965765, 3521.9647803521957, 4672.64955128365, 5681.318241810978, 5695.487406133447, 5792.333881603611, 5807.506361959233, 4409.581089796464, 5653.095504719256, 5757.510611598009, 5763.510101641355, 5818.709064546777, 5111.565516931066, 5155.71643559604, 5204.973975130138, 5226.189940559219, 5339.519443250107, 5326.866828329284, 5356.678599284036, 5379.623426360156, 5382.1119515280125, 5407.405185429599, 5163.561097306752, 5223.268742376052, 5252.264682944391, 5252.527272545489, 5301.443348448434, 4263.0, 5230.895382092375, 5264.499872512105, 5276.81531146408, 5325.893482130375, 4315.8489452869, 5157.252198431206, 5207.505286997729, 5224.582033437354, 5306.5754739621125, 5609.158626206061, 5673.149027645845, 5712.485876271114, 5716.79991200306, 5723.284589426322, 6141.662208578527, 6143.2662465203, 6220.968895155541, 6225.501959843202, 6284.874302513924, 6263.686815659199, 6293.622382657055, 6336.96831515844, 6376.171097757308, 6402.93597064028, 5154.92267615986, 5645.717714114277, 5864.506583371936, 5881.264841894763, 5918.496927761145, 6239.188905442264, 6324.525660575475, 6332.081848131995, 6333.0, 6334.239891212992, 6162.815115546541, 6176.969115154396, 6184.443400094013, 6198.473129783929, 6199.752009919643, 5579.804706835242, 6116.701002199504, 6171.413715697002, 6302.149209856702, 6324.873502529924, 6200.937990620036, 6247.312795592481, 6262.405071518198, 6263.718132683949, 6272.937270627326, 6182.752689892444, 6212.689365531705, 6255.749770009149, 6286.968565157236, 6303.149074874833, 4871.14754917888, 4927.753612319441, 4930.063287975273, 4977.352944117241, 5021.949780502148, 3799.2021675447913, 4470.396496472999, 4494.640428765691, 4511.300748384006, 4523.864284071483, 5440.102383106822, 5449.972750136224, 5486.1222204447895, 5494.230807686927, 5532.197831314517, 5984.461398474158, 5992.910106348409, 5996.940030599638, 6008.729607167688, 6010.248718910137, 5434.538064264502, 5449.209864569615, 5474.507294343449, 5503.752331145108, 5620.606557540933, 4678.883027924329, 5314.840554783363, 5365.812196573102, 5380.300560927133, 5404.000259951843, 6250.968745156245, 6290.4653104485715, 6297.779577714758, 6301.117843501914, 6360.586561873463, 6328.873422531616, 6329.968350158219, 6335.081413195153, 6351.396617321347, 6359.07793369968, 6347.873042539031, 6349.841253968688, 6364.07720880479, 6365.077063825626, 6367.490600752021, 6042.154098426312, 6098.129681996294, 6133.570650054453, 6146.385361463817, 6168.568200225941, 5327.413984461784, 6295.150154729236, 6303.621782693053, 6332.050192471166, 6335.3981371769605, 4689.296605509065, 6034.487068599218, 6113.388661133851, 6118.143459915705, 6119.785807496716, 5927.9703601481715, 5977.760889564369, 6004.519638428827, 6070.605410648293, 6074.696265186634, 5851.882962340815, 5901.91147132802, 5904.3210030847, 5938.049912014057, 5996.640201587918, 6088.670442354268, 6099.864778873631, 6114.480269176992, 6124.371389695924, 6144.877102457836, 5874.236349274567, 5884.293884733859, 5969.403059693996, 5973.49225315853, 5978.880422391615], "value": 5655.4995784565735, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [465.23236659511804, 512.6027328820164, 513.4121430961549, 515.8658748725333, 543.6928135603387, 29418.0, 30404.847975760116, 40932.59067409325, 41949.16101677972, 41989.79005104978, 43569.56430435694, 43856.34215486762, 44091.55908440915, 44120.779396103055, 44130.0, 9415.105564971334, 9622.37454565451, 9631.373960692568, 9641.325107242496, 9654.806903861918, 8503.702370417037, 8740.038595754462, 11421.885781142208, 11657.591984280552, 11671.41642917855, 11535.192536522447, 11604.477798499045, 11608.883911160907, 11617.302961822275, 11680.240784349033, 8519.914800851973, 11504.597339093092, 11558.306501609888, 11582.826257606059, 11643.534258629645, 11246.044086252678, 11598.014168795662, 11615.767684646344, 11794.35131067791, 11805.0, 39834.800825995975, 40271.59728402708, 40276.0, 40329.596704033174, 40335.7983210085, 3085.5217441296636, 3203.2792621660174, 3246.3345014272018, 3365.730741540671, 3503.1242189452637, 28147.0, 28400.0, 28438.857805711046, 28603.57094643582, 28723.712762872314, 2659.1224895784344, 2696.0294294054124, 2701.1896431070795, 2741.917742467729, 2791.567307067408, 523732.0, 525593.0, 525961.3701931542, 527610.0, 527711.0, 59652.70173649104, 59893.70053149707, 59947.400525994184, 59978.10032849511, 59989.700051499465, 31159.53260701091, 31703.207419814687, 32367.676323236465, 32383.0, 63111.0, 16795.664086718196, 16836.158192090348, 17010.0, 17025.0, 17042.0, 11721.062315014779, 11897.88102118985, 12905.0, 13023.0, 13045.869541304466, 16874.662506749795, 19145.425637230906, 19188.368988635844, 20065.0, 20089.698654520194, 6574.375434333826, 7381.37258333037, 7667.808304792313, 7716.7684969451, 7839.216078392115, 814.5764202614607, 983.149575617086, 1017.5573625472942, 1100.2023532938715, 1108.0858291909208, 24128.41835918769, 27191.86404067967, 28880.632371643103, 28894.133176004754, 29235.0, 1016.6492560066765, 1093.6554985179803, 1142.257532603814, 1147.701597584634, 1155.5724381978553, 14764.630884227765, 14860.405583776745, 14920.104793712411, 15069.773953390708, 15109.54671359861, 680363.5981820059, 682211.0, 686668.0, 688106.0, 693488.0, 58378.8324233513, 59176.0, 59593.0, 59726.0, 59746.40253597495, 1284.4220100954421, 1392.617030316671, 1420.0272813123045, 1455.345094707386, 1469.632591852037, 29476.263093422836, 29799.851000744857, 30032.549511756904, 30200.94296699693, 30229.50136649311, 48624.027519448704, 49206.0, 49232.50767492422, 49380.0, 49664.503354965986, 472428.0, 473041.0, 473974.0, 474932.0, 476823.0, 22419.887900560392, 24860.254192374257, 25545.23364299074, 25793.6130958032, 26588.936442542083, 44614.10771784481, 46417.76791116023, 46586.0, 46690.766546167055, 50006.0, 36674.44988325231, 37840.0, 38012.429813553375, 38569.421458678706, 39012.80493597514, 32153.517697235027, 36957.44563831491, 37254.8137259312, 37414.62585374111, 37772.81113594415, 35893.82053089718, 37140.0, 37603.0, 37843.432348514245, 37968.77265432469, 31479.52780708337, 32861.014169573995, 32907.8354608235, 33001.339973200884, 33037.66962330442, 19201.0, 22622.32431040993, 24148.154814581405, 24387.75612243926, 24901.875490623155], "value": 19673.23988845227, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [382.6517868739448, 384.90185002824285, 386.06572095528816, 396.1304935666214, 401.21161916833415, 18985.525361865944, 20347.389578312675, 20895.059722312515, 20932.476688082807, 21013.47466313343, 11769.882301176985, 11820.822687659671, 12297.569585064524, 12374.443150058247, 19881.90059049706, 5292.444293349201, 5501.972490137544, 5540.279763630722, 5546.168074788774, 5550.47270509301, 3952.2885880541635, 4113.526944401402, 5846.415358464141, 6180.660063696495, 6182.196314479112, 6171.506279497629, 6172.876542469148, 6194.163787888645, 6199.287081985562, 6554.180727409074, 6591.274959754423, 6745.224299205605, 6767.357101075415, 6772.966135169342, 6801.421879140279, 3789.981050094732, 5550.27846379972, 5894.233749612566, 5896.321922978869, 5922.733476993546, 11197.328160310326, 13286.069975101747, 13371.331433428388, 13485.258310792902, 13498.392572334269, 2006.5988150085611, 2147.6349020666526, 2192.0464597899963, 2194.8902554872193, 2205.4045407739823, 9788.412695238309, 9870.161036311922, 10087.646932357406, 10145.746356341151, 10157.746056348651, 1728.0582082764797, 1742.198588649223, 1743.7907451105948, 1782.0, 1822.271091563372, 107541.0, 107809.0, 108854.0, 109475.45262273717, 111562.88437115688, 9688.9031109688, 11119.555217791198, 11499.367534785668, 11603.245789023686, 11627.360495172847, 14736.631584210481, 14857.851421485862, 14886.404543818126, 14897.925510372379, 14900.0314979526, 8492.70275540353, 8617.913820861711, 8707.998580163236, 8816.735497935073, 8843.380963332505, 7858.567778772108, 8017.637001709607, 8652.322762825774, 8681.869771953427, 8790.956045219733, 4508.098380323949, 4589.816407343736, 4606.953930460652, 4639.582437580634, 4668.253079507263, 1770.1326350088364, 1783.3397001010633, 1816.582186097205, 1935.719320698519, 1947.9318223862097, 388.5065966222881, 388.59003751042354, 388.599742265467, 841.848467275884, 888.9866652000226, 10384.740381490523, 11881.940590296994, 11949.402529873472, 12001.0, 12031.518739250507, 424.898024474124, 430.3781036402431, 438.3162266863639, 445.1941985007147, 447.61952340510305, 6005.39946005396, 6033.758649653964, 6055.848603784941, 6152.907706384408, 6177.1969643945995, 500077.0, 513075.0, 513403.0, 615576.0, 619545.0, 126472.0, 135695.0, 136919.0, 137856.62143378437, 138599.30700346435, 995.0, 1054.3305001324215, 1058.4125810175426, 1075.3118004477142, 1076.687760549432, 17245.0, 18067.819321806615, 18375.356862509747, 18455.815441845945, 18715.532111697317, 49533.0, 50397.74801125971, 51266.48733512617, 51884.44346669606, 52751.73624131855, 74659.0, 75903.24096758962, 76131.0, 77000.0, 79630.0, 7455.179930207782, 7860.371170306476, 7889.447738658238, 7909.169537198742, 8003.0, 9228.723138305862, 10625.840612390666, 12055.095867809774, 12089.335086570323, 12227.0, 6349.555531112777, 9636.988116247692, 10021.448820314952, 10194.0, 10533.473326333806, 9653.1794797443, 9869.753256168653, 10323.380597164194, 10419.687409377731, 10795.136389089012, 9981.750456238653, 10344.896551034393, 10639.255252132585, 10665.093467055387, 10988.395638239974, 6710.932890671031, 7639.961800191186, 7890.723824666109, 7933.563653999086, 8227.177282271725, 10027.849582256418, 10372.533236004247, 10478.266521343432, 10586.153107751208, 10680.786384272427], "value": 8495.383374174402, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1409.1967578480271, 1558.75839244917, 1591.7134915715171, 1815.9636807263855, 1952.9511762205952, 7044.5421047631935, 7745.573993430359, 8583.141685831422, 8594.054653988056, 8594.796728458005, 8174.877376839352, 8486.405951583392, 8503.78740531487, 8599.613017414216, 8613.267872230852, 2087.09211493001, 2252.977470225301, 2318.397216723651, 2331.370529956919, 2370.596998510249, 2349.4361353275176, 2350.9647355289685, 2360.3745007573007, 2361.53949979754, 2365.7989070928993, 2303.8041766449874, 2314.687517185184, 2340.0639744102323, 2349.8707571083582, 2352.9882350588223, 2052.4971382011354, 2268.6029944759766, 2312.075169932024, 2318.455163036685, 2322.790948814598, 2313.9884300578556, 2344.2967109866972, 2361.3624321433117, 2366.9526609467885, 2375.394274460019, 8356.665733370657, 8364.330853531703, 8398.706045288447, 8405.41162118652, 8411.032731235926, 5132.461091585367, 5215.530602245778, 5295.205719142121, 5309.4071778466305, 5315.282436871012, 3152.2592190835367, 3239.5302681111107, 3319.850606722703, 3344.0970937846646, 3344.2642618623863, 1022.9590816367337, 1024.1499555369026, 1026.194437366671, 1027.203916964351, 1040.5681642118511, 11225.943870280678, 11363.03414209785, 11399.316041037564, 11402.258853174517, 11421.0, 4613.446386433582, 4707.152712511713, 4763.190257656207, 4797.512771040989, 4821.0, 8296.377771667028, 9904.0, 9952.054554817281, 9953.80092398148, 9963.601455941825, 5306.310179676618, 5307.469253074739, 5320.095583750772, 5325.520703136736, 5334.866628334323, 4332.610065094157, 4497.033137875373, 4707.670463067621, 4843.709377437365, 4844.636652251095, 3470.8958731238104, 3900.414937759302, 3956.2592459317807, 4004.7196696231567, 4038.434619153321, 1269.156011252525, 1288.2721262486853, 1290.8580056193812, 1300.2718477652545, 1307.5488956310057, 746.6490749347798, 854.5171977832629, 889.6130183370254, 904.4799240436776, 955.1022039283051, 5436.864078398072, 5504.256925315031, 5781.103928891029, 5814.883702326015, 5927.703614819242, 930.4463844012802, 985.1921424431897, 1011.8464949957055, 1021.3157184686278, 1054.9029009829844, 3613.5483064616924, 3750.4374343848094, 3769.0577355661085, 3821.4458903458844, 3884.708646851441, 10262.076413122855, 10350.585976560853, 10440.791184176425, 10560.419176945341, 10564.418956957441, 10382.42896640677, 10414.0, 10431.739206519898, 10508.106810921005, 10557.419341936115, 7936.56348900816, 8077.0, 8130.674773009132, 8209.13804050578, 8221.013478382753, 5528.723563821713, 5605.635633683797, 5625.0, 5698.6580805151825, 5772.220750198838, 9494.104298189119, 9844.2124630028, 9848.06443387877, 9953.900460995586, 9960.053794889474, 9907.256955728493, 10009.749756256151, 10072.899271007196, 10084.294099412971, 10108.595656173964, 6444.387783160592, 6617.735290588323, 6765.729370825309, 6841.144856892888, 7011.754588589375, 8114.715984940499, 8206.958965205136, 8295.792605184919, 8309.00291964968, 8323.625436855476, 7166.570005799668, 7300.890486642811, 7330.0, 7492.0, 7494.775156745306, 7121.359077683033, 7129.928700713134, 7131.536450130725, 7154.320339567733, 7175.103112110986, 6803.863922721617, 6890.483213759088, 6899.310068993061, 6902.413294869993, 6927.376536111774, 4725.36207611968, 4767.213409787346, 4781.306710527024, 4872.756362181806, 4964.180910149785, 4737.644676649196, 4817.156997525349, 4818.0, 4905.0, 5009.799608015638], "value": 4494.53405803313, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [306.97390721788645, 310.2151556561898, 315.3094722557599, 317.2750265643005, 322.1141859885315, 4464.97767511162, 4527.071950250199, 5167.888903885664, 5262.289590905227, 5377.489138531833, 5276.208568714695, 5309.814156504523, 5316.548093412064, 5328.360596728398, 5338.866528336794, 3891.591382904797, 3898.8050597470155, 4054.8175332110127, 4055.371417430303, 4095.75425474471, 3254.6419893811662, 3383.8646454141804, 4018.116014476825, 4065.715399922007, 4124.1957818225455, 4074.5314288856716, 4081.5918408159096, 4102.871710279682, 4106.240345536085, 4116.814743336557, 3845.5193100862393, 3950.6641935435377, 3972.245273398045, 4067.217487736404, 4078.8572399966156, 3007.45865744166, 3106.9125805967956, 3291.901242962715, 3540.309639620275, 3555.306715190539, 4237.343211802145, 4294.119705460374, 4637.258038713823, 4732.929006064912, 5106.744662766847, 3760.7555508891833, 4422.5356337584735, 4558.58972692456, 4598.723631802246, 4696.53034696531, 4530.003399252159, 5068.530126263379, 5493.835184944458, 5515.117581187031, 5568.443155684399, 3382.915427114317, 3539.1152211947015, 3846.807659617008, 3930.3514920038447, 3980.5422376426504, 5102.6428150029515, 5209.4009188943755, 5774.105013722879, 5921.7363498281165, 5981.05615674903, 3700.685441737429, 3764.3036038333, 3792.544894612609, 3992.640662340403, 4025.9194816103513, 4030.415589739471, 4053.918921621551, 4083.387491876242, 4089.795510224477, 4098.4467096941535, 4184.832606695759, 4550.563200879183, 4623.283391074388, 4628.0, 4714.504976977368, 4562.338460923148, 5606.271184746039, 5642.210090587321, 5676.687782171937, 5694.889496548216, 1207.6112470658636, 1298.9090763646454, 1348.184348469162, 1389.5622878793147, 1408.7041721238654, 738.7920749574484, 765.8162041110205, 820.1962077164412, 856.8971723393232, 866.8049688820091, 4184.979075104605, 4429.8703830523655, 4464.888377790581, 4924.901501969941, 5290.338707661543, 5097.949020509747, 5389.946100539023, 5686.9715651421475, 5727.198192253088, 5785.479306862402, 4800.3279540864305, 4807.326974223612, 4854.199057155601, 5066.923996140061, 5241.266222728821, 2888.321244507539, 2902.954936222958, 3025.7579393648457, 3029.636443626779, 3082.2756652186717, 5730.255066841367, 5806.738696758572, 5982.756466325128, 5988.461038506555, 6076.511254742616, 5259.658122222043, 5500.6347270674, 5973.253343332083, 6018.937217378252, 6021.397860213943, 4626.583607475276, 5264.10510213257, 5501.9174712379345, 5760.654360738369, 6014.72933717975, 5429.53848922846, 5728.596493859031, 5795.391483894299, 5819.806939577461, 5848.5028772553105, 5089.8727531812, 5652.349979752381, 5692.857678558069, 5695.658260504383, 5703.401142879938, 4690.87419019426, 5066.871913796141, 5976.0, 5993.250843644544, 6245.406686364788, 3079.0, 3138.59198304219, 3276.5249038889706, 3293.407186706416, 3319.302946381287, 4339.891502712458, 4808.677613656133, 4823.95176048235, 4894.853154405374, 4906.362172917497, 4140.875773726793, 4150.613397296506, 4416.343871048357, 4609.354690343288, 4701.788419521062, 4077.8164982575263, 4489.955100449084, 4617.907641847211, 4651.720896746206, 4696.882577935579, 4711.575958163782, 4741.312509686145, 4782.210935195653, 5415.918761218663, 5491.890162196813, 4925.532074452921, 5450.345958485007, 5495.258140151029, 5678.949394362056, 5736.451158187349, 4619.006913513476, 5187.974060129675, 5284.339457567804, 5452.345718513804, 5489.780408783604], "value": 3964.292754864152, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [720.1789959446231, 745.8284594543259, 746.6901235987066, 755.4296506137865, 757.5492581913761, 14148.646283842909, 14356.856431435683, 14805.18571478571, 14822.925885370572, 14848.475759120949, 14040.368183431745, 14093.365798539066, 14110.506132285373, 14142.929285353559, 14156.858431415683, 689.1626673591585, 712.5154894671621, 822.8847961285425, 874.8906386701647, 897.8922529296461, 855.1448551448551, 861.67687117331, 880.0143838900441, 883.0286684646901, 888.2849306308419, 933.77589378549, 936.1060187520901, 937.5874615169304, 939.0937745076001, 940.0928104379273, 720.5892641194521, 904.0642934562696, 931.902150274225, 934.4113208678489, 936.9063093690577, 664.7772996046331, 679.9970044184837, 680.3706571421465, 680.4964326398479, 680.877442060429, 14618.195999220039, 14626.70746585073, 14646.707065858729, 14650.267486625733, 14653.926730366387, 11661.300321980709, 11767.867540572446, 11866.406679666068, 12043.458044388024, 12138.211016283913, 10480.528376223088, 10601.73495662607, 10698.197635177392, 10785.78428431427, 11135.336399657537, 3934.311495488278, 3943.98028009861, 3948.545917219528, 4082.0, 4085.9387109193385, 74474.0, 75978.0, 76486.61756691236, 77394.61302693507, 77550.24497550256, 9156.633734650539, 9218.44689318643, 9293.674721384854, 9565.665201717906, 9623.278254130968, 20923.790762092183, 20995.950202489814, 20999.16003359879, 21022.2642207522, 21048.789512104988, 7196.0, 7296.6716497757725, 7304.123505179413, 7307.744228952068, 7407.111146662327, 3966.8413263469715, 4010.0, 4012.6789856811383, 4018.1963607278662, 4024.7987600619854, 3309.321589074234, 3333.683300086488, 3415.351083294166, 3419.555457790521, 3422.178677117524, 510.67572091722025, 570.0406463765258, 593.092568370394, 610.6610830988844, 650.9869802603921, 457.7688267424996, 475.75023112866023, 567.8523583868224, 586.4106572894214, 593.5637306579661, 7868.606569671608, 7989.480683755653, 8077.151899050633, 8180.713675021465, 8307.709230176915, 571.2516603249757, 582.9417058294136, 593.5696619950587, 604.7943699142313, 609.2262826210688, 3441.6558344165383, 3727.7949712765517, 3773.981025123256, 3813.2373525294497, 3876.5541962674088, 74786.0, 75344.24655753482, 77227.45545089067, 91904.08095919089, 93500.0, 69647.30352696408, 69782.0, 70583.0, 72059.0, 73071.0, 65411.01883471752, 66024.0, 66453.13151778793, 66740.99888501676, 67019.66490167518, 8119.228673276029, 8122.634481448231, 8125.065617454068, 8135.064467586303, 8202.548859812767, 38861.61138388693, 42353.94115147146, 42474.575254248295, 42889.78555107205, 43061.0, 56881.43118568927, 57264.713676431355, 57405.56486087881, 58848.70575647095, 60611.69694151678, 11435.542578297107, 11441.427928603704, 11491.138164637852, 11566.94216528912, 11679.766404671689, 11381.829272560753, 11784.0, 11808.586699465477, 11918.582849600569, 12808.423620936894, 11132.220744547803, 11196.160287978597, 11329.660110196708, 11624.883751162719, 11646.883531164918, 11349.4892729829, 11455.885441145481, 11607.477663505331, 11654.76690466169, 11776.764464710486, 10670.039696427359, 11056.0, 11075.833862491907, 11237.943810280896, 11542.884571154182, 9416.246700263826, 9485.715428537154, 9537.380070295409, 9651.034896510293, 9793.363431376938, 9414.65779648215, 9560.474173920502, 9619.807603848023, 9632.42205467646, 9654.56554455037], "value": 6698.237789087439, "unit": "score"}], "name": "dromaeo_css", "value": 6951.275291332763, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 107.0, 113.0, 112.0, 113.0, 113.0, 113.0, 110.0, 112.0, 112.0], "value": 111.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 121.0, 119.0, 203.0, 117.0, 122.0, 166.0, 139.0, 165.0, 116.0], "value": 142.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 182.0, 186.0, 185.0, 197.0, 258.0, 184.0, 184.0, 185.0, 184.0], "value": 193.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 57.0, 60.0, 119.0, 58.0, 56.0, 64.0, 67.0, 94.0, 79.0], "value": 73.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 77.0, 139.0, 78.0, 86.0, 100.0, 137.0, 81.0, 77.0, 104.0], "value": 97.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 88.0, 86.0, 89.0, 85.0, 88.0, 85.0, 88.0, 85.0], "value": 86.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 101.0, 94.0, 94.0, 93.0, 94.0, 93.0, 94.0, 94.0], "value": 94.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 131.0, 83.0, 84.0, 86.0, 84.0, 84.0, 86.0, 84.0, 83.0], "value": 90.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 59.0, 56.0, 57.0, 57.0, 57.0, 120.0, 56.0, 58.0], "value": 63.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 78.0, 77.0, 73.0, 74.0, 71.0, 71.0, 71.0, 71.0, 72.0], "value": 72.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 80.0, 86.0, 85.0, 85.0, 79.0, 84.0, 83.0, 86.0], "value": 83.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 170.0, 152.0, 125.0, 147.0, 127.0, 143.0, 144.0, 256.0, 159.0], "value": 155.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 165.0, 169.0, 163.0, 165.0, 162.0, 163.0, 164.0, 160.0, 165.0], "value": 164.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 67.0, 67.0, 67.0, 73.0, 84.0, 83.0, 84.0, 72.0], "value": 73.1, "unit": "score"}], "name": "kraken", "value": 1503.1000000000001, "alertThreshold": 2.0}]} 15:48:26 INFO - Return code: 0 15:48:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 15:48:26 ERROR - # TBPL SUCCESS # 15:48:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:48:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:48:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:48:26 INFO - 2016-05-23 15:48:26.863 screenresolution[2391:24258] starting screenresolution argv=screenresolution get 15:48:26 INFO - 2016-05-23 15:48:26.866 screenresolution[2391:24258] Display 0: 1600x1200x32@60 15:48:26 INFO - 2016-05-23 15:48:26.872 screenresolution[2392:24261] starting screenresolution argv=screenresolution list 15:48:26 INFO - Available Modes on Display 0 15:48:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:48:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:48:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:48:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:48:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:48:27 INFO - Intel Iris: 15:48:27 INFO - Chipset Model: Intel Iris 15:48:27 INFO - Type: GPU 15:48:27 INFO - Bus: Built-In 15:48:27 INFO - VRAM (Dynamic, Max): 1536 MB 15:48:27 INFO - Vendor: Intel (0x8086) 15:48:27 INFO - Device ID: 0x0a2e 15:48:27 INFO - Revision ID: 0x0009 15:48:27 INFO - Displays: 15:48:27 INFO - Display: 15:48:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:48:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:48:27 INFO - Display Serial Number: 1600x1200 60 15:48:27 INFO - Main Display: Yes 15:48:27 INFO - Mirror: Off 15:48:27 INFO - Online: Yes 15:48:27 INFO - Rotation: Supported 15:48:27 INFO - Return code: 0 15:48:27 INFO - Running post-action listener: _resource_record_post_action 15:48:27 INFO - ##### 15:48:27 INFO - ##### Finished run-tests step (success) 15:48:27 INFO - ##### 15:48:27 INFO - Running post-run listener: _resource_record_post_run 15:48:27 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 1744384; Write bytes: 694976000; Read time: 28; Write time: 1965 15:48:27 INFO - TinderboxPrint: CPU usage
53.9% 15:48:27 INFO - TinderboxPrint: I/O read bytes / time
1,744,384 / 28 15:48:27 INFO - TinderboxPrint: I/O write bytes / time
694,976,000 / 1,965 15:48:27 INFO - TinderboxPrint: CPU idle
2,234.6 (46.1%) 15:48:27 INFO - TinderboxPrint: CPU system
975.8 (20.1%) 15:48:27 INFO - TinderboxPrint: CPU user
1,639.3 (33.8%) 15:48:27 INFO - TinderboxPrint: Swap in / out
399,007,744 / 0 15:48:27 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 397017600; Write bytes: 398841344; Read time: 29840; Write time: 752 15:48:27 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 1719808; Write bytes: 295286784; Read time: 27; Write time: 1210 15:48:27 INFO - Running post-run listener: _upload_blobber_files 15:48:27 INFO - Blob upload gear active. 15:48:27 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:48:27 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 15:48:27 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'] 15:48:27 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 15:48:27 INFO - (blobuploader) - INFO - Open directory for files ... 15:48:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:48:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:48:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:48:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:48:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:48:29 INFO - (blobuploader) - INFO - Done attempting. 15:48:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:48:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:48:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:48:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:48:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:48:31 INFO - (blobuploader) - INFO - Done attempting. 15:48:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:48:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:48:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:48:33 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:48:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:48:33 INFO - (blobuploader) - INFO - Done attempting. 15:48:33 INFO - (blobuploader) - INFO - Iteration through files over. 15:48:33 INFO - Return code: 0 15:48:33 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:48:33 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:48:33 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/97e61c684c082c34178a999ded93e5cc4366caed271f686141fc403ab06a6e1d647ba89d1fe70deefe2b78f69a61379b6c5b2e3f7b3b294043019a241dd6ff81", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bd4ad58b8ae4a09b86f3d1088496df53eaedfd0f8e05212998f791d83ae05292c0a02d4e0b04deea910a9e23d661c6f4fb7e3c48709331f90e92469dc83354f8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a01b277eb8dbda8e9942e612202bdde5a3a940f8b90ea5e3f5a42d5c2a59b39a8c39b071e3096a28a9b5b2ab882d4ab8bb13649f1a6a1c08e3faea93c3282496"} 15:48:33 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:48:33 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:48:33 INFO - Contents: 15:48:33 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/97e61c684c082c34178a999ded93e5cc4366caed271f686141fc403ab06a6e1d647ba89d1fe70deefe2b78f69a61379b6c5b2e3f7b3b294043019a241dd6ff81", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bd4ad58b8ae4a09b86f3d1088496df53eaedfd0f8e05212998f791d83ae05292c0a02d4e0b04deea910a9e23d661c6f4fb7e3c48709331f90e92469dc83354f8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a01b277eb8dbda8e9942e612202bdde5a3a940f8b90ea5e3f5a42d5c2a59b39a8c39b071e3096a28a9b5b2ab882d4ab8bb13649f1a6a1c08e3faea93c3282496"} 15:48:33 INFO - Running post-run listener: copy_logs_to_upload_dir 15:48:33 INFO - Copying logs to upload dir... 15:48:33 INFO - mkdir: /builds/slave/test/build/upload/logs 15:48:33 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.985862 ========= master_lag: 0.34 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-05-23 15:48:34.245977) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-05-23 15:48:34.250342) ========= 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.bnZue09OIo/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.bc8xLnhXkc/Listeners TMPDIR=/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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/97e61c684c082c34178a999ded93e5cc4366caed271f686141fc403ab06a6e1d647ba89d1fe70deefe2b78f69a61379b6c5b2e3f7b3b294043019a241dd6ff81", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bd4ad58b8ae4a09b86f3d1088496df53eaedfd0f8e05212998f791d83ae05292c0a02d4e0b04deea910a9e23d661c6f4fb7e3c48709331f90e92469dc83354f8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a01b277eb8dbda8e9942e612202bdde5a3a940f8b90ea5e3f5a42d5c2a59b39a8c39b071e3096a28a9b5b2ab882d4ab8bb13649f1a6a1c08e3faea93c3282496"} build_url:https://queue.taskcluster.net/v1/task/WZ8Qli3uQeyyJoDQEdcbVg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010925 build_url: 'https://queue.taskcluster.net/v1/task/WZ8Qli3uQeyyJoDQEdcbVg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/97e61c684c082c34178a999ded93e5cc4366caed271f686141fc403ab06a6e1d647ba89d1fe70deefe2b78f69a61379b6c5b2e3f7b3b294043019a241dd6ff81", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bd4ad58b8ae4a09b86f3d1088496df53eaedfd0f8e05212998f791d83ae05292c0a02d4e0b04deea910a9e23d661c6f4fb7e3c48709331f90e92469dc83354f8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a01b277eb8dbda8e9942e612202bdde5a3a940f8b90ea5e3f5a42d5c2a59b39a8c39b071e3096a28a9b5b2ab882d4ab8bb13649f1a6a1c08e3faea93c3282496"}' ========= master_lag: 1.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-05-23 15:48:35.285123) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 15:48:35.289491) ========= 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.bnZue09OIo/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.bc8xLnhXkc/Listeners TMPDIR=/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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.005152 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 15:48:35.317001) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-23 15:48:35.317333) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-23 15:48:35.317707) ========= ========= Total master_lag: 1.54 =========